Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.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.001271/0.001271, allocations: 114.3 kB / 21.18 MB, free: 0.6211 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.001363/0.001363, allocations: 210.3 kB / 24.48 MB, free: 4.031 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.481/1.481, allocations: 230.2 MB / 257.8 MB, free: 8.516 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.2207/0.2207, allocations: 46.89 MB / 361.1 MB, free: 4.66 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.Joints.Spherical,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|body.Q.1.|body.Q.2.|body.Q.3.|body.Q.4.|body.w_a.1.|body.w_a.2.|body.w_a.3.",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical")
translateModel(ModelicaTest.MultiBody.Joints.Spherical,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|body.Q.1.|body.Q.2.|body.Q.3.|body.Q.4.|body.w_a.1.|body.w_a.2.|body.w_a.3.",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical") [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.002312/0.002312, allocations: 83.86 kB / 0.4968 GB, free: 7.852 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3044/0.3067, allocations: 65.99 MB / 0.5612 GB, free: 5.043 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.Spherical): time 0.01324/0.3199, allocations: 11.83 MB / 0.5728 GB, free: 9.156 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.00799/0.3279, allocations: 4.101 MB / 0.5768 GB, free: 5.039 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001261/0.3292, allocations: 63.88 kB / 0.5768 GB, free: 4.977 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002381/0.3316, allocations: 1.202 MB / 0.578 GB, free: 3.77 MB / 414.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003755/0.3353, allocations: 1.767 MB / 0.5797 GB, free: 1.996 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001208/0.3365, allocations: 0.5574 MB / 0.5803 GB, free: 1.438 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004858/0.3414, allocations: 4.035 MB / 0.5842 GB, free: 13.39 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.00193/0.3433, allocations: 1.466 MB / 0.5857 GB, free: 11.91 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001786/0.3451, allocations: 1.152 MB / 0.5868 GB, free: 10.75 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002785/0.3479, allocations: 1.857 MB / 0.5886 GB, free: 8.887 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0005357/0.3484, allocations: 216 kB / 0.5888 GB, free: 8.676 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002441/0.3509, allocations: 1.33 MB / 0.5901 GB, free: 7.344 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.00184/0.3527, allocations: 1.608 MB / 0.5917 GB, free: 5.73 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002087/0.3548, allocations: 1.832 MB / 0.5935 GB, free: 3.887 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.007435/0.3622, allocations: 6.197 MB / 0.5995 GB, free: 13.66 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 7.454e-06/0.3622, allocations: 8 kB / 0.5995 GB, free: 13.66 MB / 446.1 MB
Notification: Performance of FrontEnd: time 1.783e-06/0.3622, allocations: 0 / 0.5995 GB, free: 13.66 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0001163/0.3624, allocations: 0 / 0.5995 GB, free: 13.66 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: 1482
 * Number of variables: 1482
Notification: Performance of Generate backend data structure: time 0.007907/0.3703, allocations: 4.186 MB / 0.6036 GB, free: 9.395 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.423e-05/0.3703, allocations: 8.031 kB / 0.6036 GB, free: 9.387 MB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02055/0.3909, allocations: 6.163 MB / 0.6096 GB, free: 3.211 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.006511/0.3974, allocations: 2.182 MB / 0.6118 GB, free: 0.9844 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.000208/0.3976, allocations: 180.8 kB / 0.6119 GB, free: 0.8047 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001391/0.399, allocations: 311.6 kB / 0.6122 GB, free: 0.5 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01819/0.4172, allocations: 10.71 MB / 0.6227 GB, free: 5.32 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 7.813e-05/0.4172, allocations: 27.7 kB / 0.6227 GB, free: 5.293 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003633/0.4176, allocations: 115.8 kB / 0.6228 GB, free: 5.18 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004004/0.4216, allocations: 2.463 MB / 0.6252 GB, free: 2.711 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01939/0.441, allocations: 10.16 MB / 0.6352 GB, free: 8.543 MB / 478.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.05523/0.4962, allocations: 35.56 MB / 0.6699 GB, free: 4.117 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01183/0.5081, allocations: 5.391 MB / 0.6751 GB, free: 14.49 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003332/0.5114, allocations: 2.185 MB / 0.6773 GB, free: 12.3 MB / 0.5137 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001985/0.5134, allocations: 1.877 MB / 0.6791 GB, free: 10.09 MB / 0.5137 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.119e-05/0.5134, allocations: 56.5 kB / 0.6792 GB, free: 10.03 MB / 0.5137 GB
Notification: Performance of pre-optimization done (n=224): time 5.38e-06/0.5134, allocations: 3.938 kB / 0.6792 GB, free: 10.03 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=225): time 0.03567/0.5491, allocations: 14.62 MB / 0.6934 GB, free: 11.3 MB / 0.5294 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.26e-05/0.5491, allocations: 69.41 kB / 0.6935 GB, free: 11.22 MB / 0.5294 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003832/0.553, allocations: 2.791 MB / 0.6962 GB, free: 8.414 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002682/0.5533, allocations: 57.7 kB / 0.6963 GB, free: 8.352 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002052/0.5553, allocations: 2.795 MB / 0.699 GB, free: 5.527 MB / 0.5294 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0008606/0.5562, allocations: 0.8642 MB / 0.6999 GB, free: 4.656 MB / 0.5294 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001575/0.5577, allocations: 1.1 MB / 0.7009 GB, free: 3.547 MB / 0.5294 GB
Notification: Performance of setup shared object (initialization): time 0.0001277/0.5579, allocations: 301.1 kB / 0.7012 GB, free: 3.25 MB / 0.5294 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003657/0.5615, allocations: 2.533 MB / 0.7037 GB, free: 0.7109 MB / 0.5294 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006024/0.5675, allocations: 5.537 MB / 0.7091 GB, free: 9.703 MB / 0.545 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0109/0.5784, allocations: 8.773 MB / 0.7177 GB, free: 15.46 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.209e-05/0.5785, allocations: 28 kB / 0.7177 GB, free: 15.43 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=724) (initialization): time 0.3048/0.8833, allocations: 16.93 MB / 0.7342 GB, free: 149.6 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001234/0.8834, allocations: 52.28 kB / 0.7343 GB, free: 149.6 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.04248/0.9259, allocations: 10.39 MB / 0.7444 GB, free: 149 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.04603/0.9719, allocations: 8.646 MB / 0.7529 GB, free: 145.9 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004178/0.9761, allocations: 1.304 MB / 0.7542 GB, free: 145.6 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02507/1.001, allocations: 17.81 MB / 0.7715 GB, free: 127.7 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004374/1.006, allocations: 363.7 kB / 0.7719 GB, free: 127.3 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008128/1.006, allocations: 314.7 kB / 0.7722 GB, free: 127 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 476
 * Number of states: 0 ()
 * Number of discrete variables: 24 (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],spherical.sphereColor[3],spherical.sphereColor[2],spherical.sphereColor[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 (656):
 * Single equations (assignments): 647
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 1 system
   {(4,100.0%)}
 * Non-linear Jacobian (size): 0 systems
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(3,3,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(4,9)}
Notification: Performance of prepare postOptimizeDAE: time 0.002917/1.009, allocations: 2.016 MB / 0.7742 GB, free: 125.2 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005224/1.01, allocations: 286.5 kB / 0.7744 GB, free: 125.1 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006439/1.016, allocations: 4.81 MB / 0.7791 GB, free: 120.2 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.878e-05/1.016, allocations: 15.47 kB / 0.7791 GB, free: 120.2 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.861e-06/1.016, allocations: 7.266 kB / 0.7792 GB, free: 120.2 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005652/1.017, allocations: 317.6 kB / 0.7795 GB, free: 120.1 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003468/1.017, allocations: 39.98 kB / 0.7795 GB, free: 120 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00109/1.018, allocations: 155.8 kB / 0.7796 GB, free: 119.9 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002291/1.019, allocations: 113.7 kB / 0.7798 GB, free: 119.8 MB / 0.5606 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.00623/1.025, allocations: 4.375 MB / 0.784 GB, free: 115.4 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.005764/1.031, allocations: 6.299 MB / 0.7902 GB, free: 109.3 MB / 0.5606 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001488/1.032, allocations: 1.628 MB / 0.7918 GB, free: 107.7 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.001917/1.034, allocations: 1.708 MB / 0.7934 GB, free: 105.9 MB / 0.5606 GB
Notification: Performance of Backend: time 2.81e-07/1.034, allocations: 0 / 0.7934 GB, free: 105.9 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.008248/1.042, allocations: 4.688 MB / 0.798 GB, free: 101.3 MB / 0.5606 GB
Notification: Performance of SimCode: time 0.01053/1.053, allocations: 8.604 MB / 0.8064 GB, free: 92.68 MB / 0.5606 GB
Notification: Performance of Templates: time 0.07485/1.128, allocations: 58.68 MB / 0.8637 GB, free: 34.08 MB / 0.5606 GB
"
[Timeout remaining time 659]
make -j1 -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.makefile [Timeout 660]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.sim & ./ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.pipe 2>&1) [Timeout 480]
diffSimulationResults("ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/ModelicaTest/MultiBody/Joints/Spherical/Spherical.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/OpenModelicaLibraryTesting/files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.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.103237377014011]