Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo", uses=false)
Using package ModelicaTest with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/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.FourbarVariants.JointSSR,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|j2.s|j2.v",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR")
translateModel(ModelicaTest.MultiBody.FourbarVariants.JointSSR,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|j2.s|j2.v",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001315/0.001315, allocations: 93.22 kB / 17.35 MB, free: 6.344 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001516/0.001516, allocations: 203.6 kB / 18.28 MB, free: 5.477 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.507/1.507, allocations: 227.8 MB / 246.8 MB, free: 11.38 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo): time 0.2537/0.2537, allocations: 46.18 MB / 345.2 MB, free: 11.39 MB / 286.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0002644/0.0002644, allocations: 8 kB / 419.5 MB, free: 19.74 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1276/0.1279, allocations: 63.56 MB / 483.1 MB, free: 4.164 MB / 350.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.FourbarVariants.JointSSR): time 0.3323/0.4602, allocations: 56.3 MB / 0.5267 GB, free: 9.828 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.03636/0.4967, allocations: 12.13 MB / 0.5386 GB, free: 5.496 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01534/0.512, allocations: 249.1 kB / 0.5388 GB, free: 5.457 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003248/0.5153, allocations: 0.6786 MB / 0.5395 GB, free: 5.207 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.009783/0.5251, allocations: 2.571 MB / 0.542 GB, free: 4.363 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00348/0.5286, allocations: 1.154 MB / 0.5431 GB, free: 3.688 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01879/0.5474, allocations: 10.84 MB / 0.5537 GB, free: 14.21 MB / 398.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01378/0.5612, allocations: 7.822 MB / 0.5613 GB, free: 6.551 MB / 398.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.006517/0.5678, allocations: 1.976 MB / 0.5633 GB, free: 4.566 MB / 398.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.00777/0.5756, allocations: 3.516 MB / 0.5667 GB, free: 1.039 MB / 398.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.002404/0.5781, allocations: 268 kB / 0.567 GB, free: 0.7773 MB / 398.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.005414/0.5835, allocations: 1.494 MB / 0.5684 GB, free: 15.28 MB / 414.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.004966/0.5885, allocations: 2.921 MB / 0.5713 GB, free: 12.35 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.006992/0.5955, allocations: 3.343 MB / 0.5745 GB, free: 8.98 MB / 414.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01719/0.6127, allocations: 11.4 MB / 0.5857 GB, free: 13.54 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.532e-06/0.6128, allocations: 0 / 0.5857 GB, free: 13.54 MB / 430.1 MB
Notification: Performance of FrontEnd: time 3.126e-06/0.6128, allocations: 0 / 0.5857 GB, free: 13.54 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 0.0005628/0.6134, allocations: 0 / 0.5857 GB, free: 13.54 MB / 430.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2564
 * Number of variables: 2564
Notification: Performance of Generate backend data structure: time 0.0184/0.6318, allocations: 6.401 MB / 0.5919 GB, free: 7.059 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.288e-05/0.6319, allocations: 8.875 kB / 0.5919 GB, free: 7.051 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01863/0.6505, allocations: 4.043 MB / 0.5959 GB, free: 2.992 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01156/0.6621, allocations: 4.17 MB / 0.6 GB, free: 14.76 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004453/0.6626, allocations: 402 kB / 0.6003 GB, free: 14.37 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002736/0.6653, allocations: 0.5502 MB / 0.6009 GB, free: 13.82 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04303/0.7084, allocations: 18.93 MB / 0.6194 GB, free: 9.832 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.000438/0.7089, allocations: 79.3 kB / 0.6194 GB, free: 9.754 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002888/0.7118, allocations: 243.8 kB / 0.6197 GB, free: 9.516 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.006473/0.7183, allocations: 3.436 MB / 0.623 GB, free: 6.074 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04206/0.7604, allocations: 16.69 MB / 0.6393 GB, free: 5.371 MB / 478.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.1133/0.8738, allocations: 66.83 MB / 0.7046 GB, free: 0.6094 MB / 0.5294 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.3094/1.183, allocations: 6.279 MB / 0.7107 GB, free: 174.8 MB / 0.5294 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004753/1.188, allocations: 2.836 MB / 0.7135 GB, free: 174.1 MB / 0.5294 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.02263/1.211, allocations: 13.92 MB / 0.7271 GB, free: 166.8 MB / 0.5294 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.844e-05/1.211, allocations: 60.06 kB / 0.7271 GB, free: 166.7 MB / 0.5294 GB
Notification: Performance of pre-optimization done (n=284): time 6.041e-06/1.211, allocations: 0 / 0.7271 GB, free: 166.7 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=345): time 0.07818/1.289, allocations: 25.04 MB / 0.7516 GB, free: 155.4 MB / 0.5294 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 5.943e-05/1.289, allocations: 184.3 kB / 0.7518 GB, free: 155.2 MB / 0.5294 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.006843/1.296, allocations: 5.868 MB / 0.7575 GB, free: 149.3 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005377/1.296, allocations: 73.7 kB / 0.7576 GB, free: 149.2 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002319/1.299, allocations: 4.954 MB / 0.7624 GB, free: 144.3 MB / 0.5294 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001279/1.3, allocations: 1.287 MB / 0.7637 GB, free: 143 MB / 0.5294 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0009322/1.301, allocations: 0.8177 MB / 0.7645 GB, free: 142.2 MB / 0.5294 GB
Notification: Performance of setup shared object (initialization): time 8e-05/1.301, allocations: 301.1 kB / 0.7647 GB, free: 141.9 MB / 0.5294 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005948/1.307, allocations: 3.365 MB / 0.768 GB, free: 138.5 MB / 0.5294 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.008002/1.315, allocations: 8.88 MB / 0.7767 GB, free: 126.9 MB / 0.5294 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01284/1.328, allocations: 13.19 MB / 0.7896 GB, free: 111 MB / 0.5294 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001771/1.328, allocations: 56 kB / 0.7896 GB, free: 111 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=1265) (initialization): time 0.02498/1.353, allocations: 13 MB / 0.8023 GB, free: 97.92 MB / 0.5294 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001066/1.353, allocations: 88 kB / 0.8024 GB, free: 97.83 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001838/1.354, allocations: 192 kB / 0.8026 GB, free: 97.64 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.004009/1.358, allocations: 1.318 MB / 0.8039 GB, free: 96.32 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006954/1.365, allocations: 2.207 MB / 0.806 GB, free: 94.11 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01014/1.375, allocations: 7.309 MB / 0.8132 GB, free: 86.68 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006523/1.381, allocations: 0.511 MB / 0.8137 GB, free: 86.17 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001178/1.382, allocations: 0.5556 MB / 0.8142 GB, free: 85.62 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 880
 * Number of states: 0 ()
 * Number of discrete variables: 46 (jointSSR.revolute.positiveBranch,Body2.sphereColor[3],Body2.sphereColor[2],Body2.sphereColor[1],Body1.sphereColor[3],Body1.sphereColor[2],Body1.sphereColor[1],FixedFrame1.color_x[3],FixedFrame1.color_x[2],FixedFrame1.color_x[1],jointSSR.rod1Color[3],jointSSR.rod1Color[2],jointSSR.rod1Color[1],jointSSR.rod1.massColor[3],jointSSR.rod1.massColor[2],jointSSR.rod1.massColor[1],jointSSR.sphereColor[3],jointSSR.sphereColor[2],jointSSR.sphereColor[1],jointSSR.revoluteColor[3],jointSSR.revoluteColor[2],jointSSR.revoluteColor[1],b3.color[3],b3.color[2],b3.color[1],b2.color[3],b2.color[2],b2.color[1],b2.body.sphereColor[3],b2.body.sphereColor[2],b2.body.sphereColor[1],j2.boxColor[3],j2.boxColor[2],j2.boxColor[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 (1179):
 * Single equations (assignments): 1172
 * Array equations: 6
 * 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,37,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.005127/1.388, allocations: 3.449 MB / 0.8176 GB, free: 82.16 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008039/1.388, allocations: 0.5244 MB / 0.8181 GB, free: 81.62 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01441/1.403, allocations: 5.408 MB / 0.8234 GB, free: 76.16 MB / 0.5294 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0001086/1.403, allocations: 40.62 kB / 0.8234 GB, free: 76.12 MB / 0.5294 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.88e-05/1.403, allocations: 4 kB / 0.8234 GB, free: 76.12 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 8.733e-05/1.403, allocations: 19.94 kB / 0.8234 GB, free: 76.1 MB / 0.5294 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.03961/1.443, allocations: 14.01 MB / 0.8371 GB, free: 61.86 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.533e-05/1.443, allocations: 8 kB / 0.8371 GB, free: 61.85 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003453/1.446, allocations: 0.4999 MB / 0.8376 GB, free: 61.35 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.003994/1.45, allocations: 0.8164 MB / 0.8384 GB, free: 60.53 MB / 0.5294 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0004815/1.451, allocations: 68.84 kB / 0.8385 GB, free: 60.46 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.007975/1.459, allocations: 5.332 MB / 0.8437 GB, free: 55 MB / 0.5294 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 9.117e-06/1.459, allocations: 15.94 kB / 0.8437 GB, free: 54.98 MB / 0.5294 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01284/1.472, allocations: 8.305 MB / 0.8518 GB, free: 46.39 MB / 0.5294 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001507/1.473, allocations: 0.5722 MB / 0.8524 GB, free: 45.79 MB / 0.5294 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0006285/1.474, allocations: 60 kB / 0.8524 GB, free: 45.73 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002095/1.476, allocations: 144.8 kB / 0.8526 GB, free: 45.59 MB / 0.5294 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004926/1.477, allocations: 218.5 kB / 0.8528 GB, free: 45.38 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002123/1.477, allocations: 105.2 kB / 0.8529 GB, free: 45.28 MB / 0.5294 GB
Notification: Performance of sorting global known variables: time 0.005561/1.482, allocations: 3.646 MB / 0.8564 GB, free: 41.66 MB / 0.5294 GB
Notification: Performance of sort global known variables: time 5.81e-07/1.482, allocations: 0 / 0.8564 GB, free: 41.66 MB / 0.5294 GB
Notification: Performance of remove unused functions: time 0.008802/1.491, allocations: 1.619 MB / 0.858 GB, free: 40.05 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 51
 * Number of states: 2 (j2.s,j2.v)
 * 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 (269):
 * Single equations (assignments): 262
 * Array equations: 6
 * 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,30,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.001256/1.492, allocations: 267.9 kB / 0.8583 GB, free: 39.8 MB / 0.5294 GB
Notification: Performance of simCode: created initialization part: time 0.01305/1.506, allocations: 7.893 MB / 0.866 GB, free: 31.92 MB / 0.5294 GB
Notification: Performance of simCode: created event and clocks part: time 1.895e-05/1.506, allocations: 1.531 kB / 0.866 GB, free: 31.92 MB / 0.5294 GB
Notification: Performance of simCode: created simulation system equations: time 0.003661/1.509, allocations: 2.392 MB / 0.8683 GB, free: 29.51 MB / 0.5294 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01402/1.523, allocations: 0.6672 MB / 0.869 GB, free: 28.85 MB / 0.5294 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.03105/1.554, allocations: 18.91 MB / 0.8874 GB, free: 9.746 MB / 0.5294 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.007535/1.562, allocations: 5.16 MB / 0.8925 GB, free: 4.543 MB / 0.5294 GB
Notification: Performance of simCode: alias equations: time 0.008337/1.57, allocations: 2.959 MB / 0.8954 GB, free: 1.574 MB / 0.5294 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001312/1.572, allocations: 383.4 kB / 0.8957 GB, free: 1.203 MB / 0.5294 GB
Notification: Performance of SimCode: time 7.11e-07/1.572, allocations: 0 / 0.8957 GB, free: 1.203 MB / 0.5294 GB
Notification: Performance of Templates: time 0.3784/1.95, allocations: 57.6 MB / 0.952 GB, free: 151.2 MB / 0.545 GB
make -j1 -f ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.makefile
(rm -f ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.pipe ; mkfifo ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.pipe ; head -c 1048576 < ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.pipe >> ../files/ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.sim & ./ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.pipe 2>&1)
diffSimulationResults("ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.0.0/ModelicaTest/MultiBody/FourbarVariants/JointSSR/JointSSR.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches