Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForcesAndTorques3.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.001525/0.001525, 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.001649/0.001649, 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.41/1.41, allocations: 230.2 MB / 257.8 MB, free: 8.512 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.2188/0.2188, allocations: 46.89 MB / 361.1 MB, free: 4.645 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.FrameForcesAndTorques3,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.FrameForcesAndTorques3")
translateModel(ModelicaTest.MultiBody.Forces.FrameForcesAndTorques3,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.FrameForcesAndTorques3") [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.002273/0.002273, allocations: 79.81 kB / 0.4968 GB, free: 7.82 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3052/0.3075, allocations: 65.99 MB / 0.5612 GB, free: 5.027 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Forces.FrameForcesAndTorques3): time 0.01624/0.3238, allocations: 13.75 MB / 0.5746 GB, free: 7.223 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01126/0.335, allocations: 4.77 MB / 0.5793 GB, free: 2.434 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002422/0.3374, allocations: 95.69 kB / 0.5794 GB, free: 2.34 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.008647/0.3461, allocations: 4.529 MB / 0.5838 GB, free: 13.8 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006164/0.3523, allocations: 2.261 MB / 0.586 GB, free: 11.53 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002922/0.3552, allocations: 1.154 MB / 0.5871 GB, free: 10.37 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01012/0.3653, allocations: 6.35 MB / 0.5933 GB, free: 4.008 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.005765/0.3711, allocations: 4.022 MB / 0.5973 GB, free: 15.92 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003531/0.3746, allocations: 2.251 MB / 0.5995 GB, free: 13.67 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.00442/0.379, allocations: 3.154 MB / 0.6026 GB, free: 10.5 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0009958/0.38, allocations: 496 kB / 0.603 GB, free: 10.02 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002752/0.3828, allocations: 1.537 MB / 0.6045 GB, free: 8.48 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002818/0.3856, allocations: 2.766 MB / 0.6072 GB, free: 5.707 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.003422/0.389, allocations: 3.061 MB / 0.6102 GB, free: 2.629 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01233/0.4013, allocations: 9.751 MB / 0.6197 GB, free: 8.836 MB / 462.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.422e-06/0.4013, allocations: 3.938 kB / 0.6197 GB, free: 8.832 MB / 462.1 MB
Notification: Performance of FrontEnd: time 1.352e-06/0.4013, allocations: 4 kB / 0.6197 GB, free: 8.828 MB / 462.1 MB
Notification: Performance of Transformations before backend: time 0.0002998/0.4016, allocations: 0 / 0.6197 GB, free: 8.828 MB / 462.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1985
 * Number of variables: 1985
Notification: Performance of Generate backend data structure: time 0.01581/0.4174, allocations: 6.85 MB / 0.6264 GB, free: 1.891 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.123e-05/0.4175, allocations: 8.031 kB / 0.6264 GB, free: 1.883 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0115/0.429, allocations: 2.614 MB / 0.629 GB, free: 15.26 MB / 478.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.009741/0.4387, allocations: 3.899 MB / 0.6328 GB, free: 11.3 MB / 478.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002831/0.439, allocations: 337 kB / 0.6331 GB, free: 10.97 MB / 478.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00117/0.4402, allocations: 447.9 kB / 0.6336 GB, free: 10.54 MB / 478.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02598/0.4662, allocations: 13.99 MB / 0.6472 GB, free: 11.72 MB / 494.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001743/0.4663, allocations: 63.53 kB / 0.6473 GB, free: 11.66 MB / 494.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0006105/0.4669, allocations: 211.8 kB / 0.6475 GB, free: 11.45 MB / 494.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004687/0.4716, allocations: 2.535 MB / 0.65 GB, free: 8.91 MB / 494.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02481/0.4964, allocations: 12.13 MB / 0.6618 GB, free: 12.76 MB / 0.4981 GB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.06919/0.5656, allocations: 51.76 MB / 0.7123 GB, free: 7.672 MB / 0.545 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01638/0.582, allocations: 6.01 MB / 0.7182 GB, free: 1.512 MB / 0.545 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004775/0.5868, allocations: 3.546 MB / 0.7217 GB, free: 13.96 MB / 0.5606 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.02218/0.609, allocations: 13.24 MB / 0.7346 GB, free: 0.5469 MB / 0.5606 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.772e-05/0.609, allocations: 60.5 kB / 0.7347 GB, free: 492 kB / 0.5606 GB
Notification: Performance of pre-optimization done (n=228): time 5.34e-06/0.609, allocations: 4 kB / 0.7347 GB, free: 488 kB / 0.5606 GB
Notification: Performance of matching and sorting (n=270): time 0.3071/0.9161, allocations: 29.71 MB / 0.7637 GB, free: 141.3 MB / 0.5606 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.866e-05/0.9162, allocations: 210.7 kB / 0.7639 GB, free: 141.1 MB / 0.5606 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004674/0.9208, allocations: 4.966 MB / 0.7687 GB, free: 136.2 MB / 0.5606 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003293/0.9212, allocations: 69.7 kB / 0.7688 GB, free: 136.1 MB / 0.5606 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001396/0.9226, allocations: 3.944 MB / 0.7727 GB, free: 132.6 MB / 0.5606 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0006665/0.9232, allocations: 1.053 MB / 0.7737 GB, free: 131.5 MB / 0.5606 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001502/0.9247, allocations: 1.527 MB / 0.7752 GB, free: 130 MB / 0.5606 GB
Notification: Performance of setup shared object (initialization): time 3.909e-05/0.9248, allocations: 301.1 kB / 0.7755 GB, free: 129.7 MB / 0.5606 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003365/0.9281, allocations: 3.294 MB / 0.7787 GB, free: 126.4 MB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004504/0.9326, allocations: 7.338 MB / 0.7858 GB, free: 117.1 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.009001/0.9416, allocations: 11.4 MB / 0.797 GB, free: 103.8 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.422e-05/0.9417, allocations: 43.94 kB / 0.797 GB, free: 103.7 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=960) (initialization): time 0.01866/0.9604, allocations: 12.49 MB / 0.8092 GB, free: 91.21 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 7.521e-05/0.9604, allocations: 64 kB / 0.8093 GB, free: 91.14 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001136/0.9605, allocations: 147.9 kB / 0.8094 GB, free: 91 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.00497/0.9655, allocations: 2.237 MB / 0.8116 GB, free: 88.75 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005836/0.9714, allocations: 1.692 MB / 0.8133 GB, free: 87.06 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01457/0.9859, allocations: 14.39 MB / 0.8273 GB, free: 72.4 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005304/0.9912, allocations: 451.5 kB / 0.8277 GB, free: 71.96 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005977/0.9918, allocations: 431.9 kB / 0.8282 GB, free: 71.54 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 642
 * Number of states: 0 ()
 * Number of discrete variables: 59 (frameTorque2.torqueArrow.twoHeadedArrow,frameTorque2.torqueArrow.headAtOrigin,frameTorque2.forceArrow.twoHeadedArrow,frameTorque2.forceArrow.headAtOrigin,frameTorque1.torqueArrow.twoHeadedArrow,frameTorque1.torqueArrow.headAtOrigin,frameTorque1.forceArrow.twoHeadedArrow,frameTorque1.forceArrow.headAtOrigin,fixedTranslation.color[3],fixedTranslation.color[2],fixedTranslation.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],frameTorque2.torqueColor[3],frameTorque2.torqueColor[2],frameTorque2.torqueColor[1],frameTorque2.forceColor[3],frameTorque2.forceColor[2],frameTorque2.forceColor[1],frameTorque1.torqueColor[3],frameTorque1.torqueColor[2],frameTorque1.torqueColor[1],frameTorque1.forceColor[3],frameTorque1.forceColor[2],frameTorque1.forceColor[1],body2.color[3],body2.color[2],body2.color[1],body2.body.sphereColor[3],body2.body.sphereColor[2],body2.body.sphereColor[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 (888):
 * Single equations (assignments): 883
 * Array equations: 3
 * 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
   {(2,23,100.0%), (2,23,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.003019/0.9948, allocations: 2.684 MB / 0.8308 GB, free: 68.84 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006466/0.9955, allocations: 472.9 kB / 0.8312 GB, free: 68.38 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.009965/1.005, allocations: 6.969 MB / 0.838 GB, free: 61.38 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.097e-05/1.005, allocations: 19.41 kB / 0.8381 GB, free: 61.36 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.672e-06/1.005, allocations: 4 kB / 0.8381 GB, free: 61.36 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0008158/1.006, allocations: 0.5096 MB / 0.8386 GB, free: 60.83 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0005566/1.007, allocations: 51.66 kB / 0.8386 GB, free: 60.78 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002072/1.009, allocations: 207.7 kB / 0.8388 GB, free: 60.58 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00037/1.009, allocations: 181.7 kB / 0.839 GB, free: 60.4 MB / 0.5606 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01113/1.02, allocations: 7.393 MB / 0.8462 GB, free: 53.04 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.008629/1.029, allocations: 9.164 MB / 0.8551 GB, free: 43.82 MB / 0.5606 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002224/1.031, allocations: 2.319 MB / 0.8574 GB, free: 41.5 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.003418/1.035, allocations: 3.114 MB / 0.8605 GB, free: 38.39 MB / 0.5606 GB
Notification: Performance of Backend: time 3.71e-07/1.035, allocations: 0 / 0.8605 GB, free: 38.39 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.009383/1.044, allocations: 5.73 MB / 0.866 GB, free: 32.68 MB / 0.5606 GB
Notification: Performance of SimCode: time 0.0164/1.06, allocations: 12.76 MB / 0.8785 GB, free: 19.87 MB / 0.5606 GB
Notification: Performance of Templates: time 0.1223/1.183, allocations: 73.81 MB / 0.9506 GB, free: 10.35 MB / 0.6231 GB
"
[Timeout remaining time 659]
make -j1 -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForcesAndTorques3.makefile [Timeout 660]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForcesAndTorques3.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForcesAndTorques3.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForcesAndTorques3.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForcesAndTorques3.sim & ./ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForcesAndTorques3  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForcesAndTorques3.pipe 2>&1) [Timeout 480]
diffSimulationResults("ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForcesAndTorques3_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/ModelicaTest/MultiBody/Forces/FrameForcesAndTorques3/FrameForcesAndTorques3.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/OpenModelicaLibraryTesting/files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Forces.FrameForcesAndTorques3.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.383906987030059]