Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo", uses=false)
Using package ModelicaTest with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(ModelicaTest.MultiBody.Joints.JointUSP2,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|revolute.phi|revolute.w",fileNamePrefix="ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2")
translateModel(ModelicaTest.MultiBody.Joints.JointUSP2,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|revolute.phi|revolute.w",fileNamePrefix="ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.00134/0.00134, allocations: 105.7 kB / 16.4 MB, free: 6.043 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001289/0.001289, allocations: 189 kB / 17.34 MB, free: 5.609 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.918/1.918, allocations: 205.1 MB / 223.2 MB, free: 12.23 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo): time 0.2465/0.2465, allocations: 43.53 MB / 314.1 MB, free: 440 kB / 254.1 MB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.001391/0.001391, allocations: 77.45 kB / 441.8 MB, free: 5.621 MB / 318.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1625/0.1639, allocations: 57.79 MB / 499.6 MB, free: 11.76 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.JointUSP2): time 0.01529/0.1793, allocations: 13.3 MB / 0.5009 GB, free: 14.39 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01195/0.1912, allocations: 4.837 MB / 0.5056 GB, free: 9.539 MB / 398.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003335/0.1946, allocations: 79.81 kB / 0.5057 GB, free: 9.461 MB / 398.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.00863/0.2033, allocations: 3.987 MB / 0.5096 GB, free: 5.461 MB / 398.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.007932/0.2113, allocations: 2.202 MB / 0.5117 GB, free: 3.25 MB / 398.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003346/0.2147, allocations: 1.181 MB / 0.5129 GB, free: 2.066 MB / 398.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01119/0.2259, allocations: 5.576 MB / 0.5183 GB, free: 12.47 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.007092/0.233, allocations: 5.19 MB / 0.5234 GB, free: 7.215 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004894/0.238, allocations: 2.193 MB / 0.5255 GB, free: 5.016 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.005863/0.2439, allocations: 2.972 MB / 0.5284 GB, free: 2.035 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.002127/0.246, allocations: 0.5 MB / 0.5289 GB, free: 1.535 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.3511/0.5972, allocations: 1.638 MB / 0.5305 GB, free: 12.97 MB / 414.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.005618/0.6028, allocations: 2.693 MB / 0.5332 GB, free: 12.97 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.009928/0.6128, allocations: 3.027 MB / 0.5361 GB, free: 12.03 MB / 414.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.02293/0.6358, allocations: 9.951 MB / 0.5458 GB, free: 10.73 MB / 414.1 MB
Notification: Performance of FrontEnd - DAE generated: time 8.256e-06/0.6358, allocations: 0 / 0.5458 GB, free: 10.73 MB / 414.1 MB
Notification: Performance of FrontEnd: time 2.535e-06/0.6359, allocations: 1.344 kB / 0.5458 GB, free: 10.73 MB / 414.1 MB
Notification: Performance of Transformations before backend: time 0.0005513/0.6364, allocations: 0 / 0.5458 GB, free: 10.73 MB / 414.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1932
 * Number of variables: 1932
Notification: Performance of Generate backend data structure: time 0.02887/0.6653, allocations: 6.534 MB / 0.5522 GB, free: 8.297 MB / 414.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.601e-05/0.6654, allocations: 14.23 kB / 0.5522 GB, free: 8.293 MB / 414.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02066/0.686, allocations: 2.72 MB / 0.5549 GB, free: 7.926 MB / 414.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01581/0.7019, allocations: 3.796 MB / 0.5586 GB, free: 6.691 MB / 414.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005744/0.7025, allocations: 345.1 kB / 0.5589 GB, free: 6.555 MB / 414.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003707/0.7062, allocations: 434.4 kB / 0.5593 GB, free: 6.445 MB / 414.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0375/0.7437, allocations: 13.85 MB / 0.5729 GB, free: 14.52 MB / 430.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002305/0.744, allocations: 57.55 kB / 0.5729 GB, free: 14.51 MB / 430.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00138/0.7454, allocations: 198.5 kB / 0.5731 GB, free: 14.41 MB / 430.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003958/0.7494, allocations: 2.408 MB / 0.5755 GB, free: 13.5 MB / 430.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03033/0.7798, allocations: 11.76 MB / 0.5869 GB, free: 3.168 MB / 430.1 MB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.08442/0.8642, allocations: 51.55 MB / 0.6373 GB, free: 14.85 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01319/0.8775, allocations: 4.733 MB / 0.6419 GB, free: 9.973 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004366/0.8819, allocations: 2.959 MB / 0.6448 GB, free: 7.008 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.02071/0.9026, allocations: 12.86 MB / 0.6573 GB, free: 9.664 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.9e-05/0.9027, allocations: 59.16 kB / 0.6574 GB, free: 9.602 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=197): time 4.168e-06/0.9027, allocations: 0 / 0.6574 GB, free: 9.602 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=248): time 0.1733/1.076, allocations: 43.45 MB / 0.6998 GB, free: 14.09 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001534/1.076, allocations: 238.1 kB / 0.7001 GB, free: 13.82 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01328/1.09, allocations: 4.794 MB / 0.7047 GB, free: 9.016 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 0.001054/1.091, allocations: 64.83 kB / 0.7048 GB, free: 8.949 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.004736/1.095, allocations: 3.833 MB / 0.7085 GB, free: 5.113 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002365/1.098, allocations: 0.9398 MB / 0.7095 GB, free: 4.18 MB / 0.545 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.007019/1.105, allocations: 1.506 MB / 0.7109 GB, free: 2.668 MB / 0.545 GB
Notification: Performance of setup shared object (initialization): time 0.0001323/1.105, allocations: 305.1 kB / 0.7112 GB, free: 2.367 MB / 0.545 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.007224/1.112, allocations: 3.446 MB / 0.7146 GB, free: 14.92 MB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01453/1.127, allocations: 7.704 MB / 0.7221 GB, free: 5.195 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02138/1.148, allocations: 11.87 MB / 0.7337 GB, free: 7.844 MB / 0.5762 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001724/1.148, allocations: 47.94 kB / 0.7338 GB, free: 7.797 MB / 0.5762 GB
Notification: Performance of matching and sorting (n=967) (initialization): time 0.04306/1.192, allocations: 14.48 MB / 0.7479 GB, free: 9.453 MB / 0.5919 GB
Notification: Performance of prepare postOptimizeDAE: time 0.000204/1.192, allocations: 73 kB / 0.748 GB, free: 9.383 MB / 0.5919 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002965/1.192, allocations: 148 kB / 0.7481 GB, free: 9.238 MB / 0.5919 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01482/1.207, allocations: 4.013 MB / 0.752 GB, free: 5.215 MB / 0.5919 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00737/1.214, allocations: 1.638 MB / 0.7536 GB, free: 3.574 MB / 0.5919 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.4264/1.641, allocations: 19.85 MB / 0.773 GB, free: 199.5 MB / 0.5919 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01032/1.651, allocations: 459.2 kB / 0.7734 GB, free: 199.4 MB / 0.5919 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002732/1.654, allocations: 436.5 kB / 0.7739 GB, free: 199.3 MB / 0.5919 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 679
 * Number of states: 0 ()
 * Number of discrete variables: 37 (jointUSP.prismatic.positiveBranch,bodyBox.color[3],bodyBox.color[2],bodyBox.color[1],bodyBox.body.sphereColor[3],bodyBox.body.sphereColor[2],bodyBox.body.sphereColor[1],revolute.cylinderColor[3],revolute.cylinderColor[2],revolute.cylinderColor[1],fixedTranslation.color[3],fixedTranslation.color[2],fixedTranslation.color[1],body.sphereColor[3],body.sphereColor[2],body.sphereColor[1],jointUSP.rod1Color[3],jointUSP.rod1Color[2],jointUSP.rod1Color[1],jointUSP.cylinderColor[3],jointUSP.cylinderColor[2],jointUSP.cylinderColor[1],jointUSP.sphereColor[3],jointUSP.sphereColor[2],jointUSP.sphereColor[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 (884):
 * Single equations (assignments): 880
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(2,58,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.009874/1.664, allocations: 2.661 MB / 0.7765 GB, free: 198.3 MB / 0.5919 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002002/1.666, allocations: 429.7 kB / 0.7769 GB, free: 198.3 MB / 0.5919 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02148/1.687, allocations: 8.64 MB / 0.7853 GB, free: 195.6 MB / 0.5919 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.397e-05/1.687, allocations: 31.22 kB / 0.7853 GB, free: 195.6 MB / 0.5919 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.892e-05/1.688, allocations: 15.62 kB / 0.7853 GB, free: 195.6 MB / 0.5919 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.437e-05/1.688, allocations: 13.03 kB / 0.7854 GB, free: 195.6 MB / 0.5919 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.04742/1.735, allocations: 17.84 MB / 0.8028 GB, free: 187 MB / 0.5919 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.447e-05/1.735, allocations: 0 / 0.8028 GB, free: 187 MB / 0.5919 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002454/1.738, allocations: 401.4 kB / 0.8032 GB, free: 186.6 MB / 0.5919 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01275/1.75, allocations: 4.068 MB / 0.8071 GB, free: 182.6 MB / 0.5919 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0008705/1.751, allocations: 45.62 kB / 0.8072 GB, free: 182.5 MB / 0.5919 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02446/1.776, allocations: 18.21 MB / 0.825 GB, free: 164.3 MB / 0.5919 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.278e-06/1.776, allocations: 0 / 0.825 GB, free: 164.3 MB / 0.5919 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02441/1.8, allocations: 13.23 MB / 0.8379 GB, free: 151.3 MB / 0.5919 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003952/1.804, allocations: 0.9637 MB / 0.8388 GB, free: 150.5 MB / 0.5919 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001675/1.806, allocations: 36 kB / 0.8389 GB, free: 150.4 MB / 0.5919 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003688/1.81, allocations: 223.6 kB / 0.8391 GB, free: 150.2 MB / 0.5919 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008065/1.811, allocations: 149.6 kB / 0.8392 GB, free: 150.1 MB / 0.5919 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002543/1.811, allocations: 69.55 kB / 0.8393 GB, free: 150 MB / 0.5919 GB
Notification: Performance of sorting global known variables: time 0.006872/1.818, allocations: 2.993 MB / 0.8422 GB, free: 147 MB / 0.5919 GB
Notification: Performance of sort global known variables: time 6.91e-07/1.818, allocations: 0 / 0.8422 GB, free: 147 MB / 0.5919 GB
Notification: Performance of remove unused functions: time 0.01194/1.83, allocations: 1.489 MB / 0.8437 GB, free: 145.5 MB / 0.5919 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 5
 * Number of states: 2 (revolute.phi,revolute.w)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (173):
 * Single equations (assignments): 169
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(2,52,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.001024/1.831, allocations: 229.4 kB / 0.8439 GB, free: 145.3 MB / 0.5919 GB
Notification: Performance of simCode: created initialization part: time 0.01382/1.845, allocations: 8.271 MB / 0.852 GB, free: 137.1 MB / 0.5919 GB
Notification: Performance of simCode: created event and clocks part: time 1.746e-05/1.845, allocations: 12.05 kB / 0.852 GB, free: 137.1 MB / 0.5919 GB
Notification: Performance of simCode: created simulation system equations: time 0.004853/1.85, allocations: 3.107 MB / 0.855 GB, free: 133.9 MB / 0.5919 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00514/1.855, allocations: 0.5335 MB / 0.8555 GB, free: 133.4 MB / 0.5919 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02477/1.88, allocations: 12.89 MB / 0.8681 GB, free: 120.5 MB / 0.5919 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00518/1.885, allocations: 4.113 MB / 0.8721 GB, free: 116.4 MB / 0.5919 GB
Notification: Performance of simCode: alias equations: time 0.004798/1.89, allocations: 2.19 MB / 0.8743 GB, free: 114.2 MB / 0.5919 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0009899/1.891, allocations: 283.4 kB / 0.8745 GB, free: 113.9 MB / 0.5919 GB
Notification: Performance of SimCode: time 7.72e-07/1.891, allocations: 0.9844 kB / 0.8745 GB, free: 113.9 MB / 0.5919 GB
Notification: Performance of Templates: time 0.1051/1.996, allocations: 61.55 MB / 0.9346 GB, free: 52.55 MB / 0.5919 GB
make -j1 -f ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.makefile
(rm -f ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.pipe ; mkfifo ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.pipe ; head -c 1048576 < ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.pipe >> ../files/ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.sim & ./ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.pipe 2>&1)
diffSimulationResults("ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v3.2.3+build.4/ModelicaTest/MultiBody/Joints/JointUSP2/JointUSP2.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ModelicaTest_3.2.3_ModelicaTest.MultiBody.Joints.JointUSP2.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches