Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.1_ModelicaTest.MultiBody.FourbarVariants.JointUSR.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.1+maint.om/package.mo", uses=false)
Using package ModelicaTest with version 3.2.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.1+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.FourbarVariants.JointUSR,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ModelicaTest_3.2.1_ModelicaTest.MultiBody.FourbarVariants.JointUSR")
translateModel(ModelicaTest.MultiBody.FourbarVariants.JointUSR,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ModelicaTest_3.2.1_ModelicaTest.MultiBody.FourbarVariants.JointUSR")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001536/0.001536, allocations: 111.3 kB / 15.23 MB, free: 0.6406 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.002067/0.002067, allocations: 191.4 kB / 16.14 MB, free: 6.703 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.69/1.69, allocations: 205.1 MB / 222 MB, free: 4.781 MB / 174.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.1+maint.om/package.mo): time 0.3482/0.3482, allocations: 37.98 MB / 307.3 MB, free: 10.04 MB / 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: ModelicaTest requested package Modelica of version 3.2.1. Modelica 3.2.3 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.001336/0.001336, allocations: 63.91 kB / 374.5 MB, free: 15.65 MB / 318.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1429/0.1442, allocations: 56.48 MB / 431 MB, free: 7.078 MB / 366.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.FourbarVariants.JointUSR): time 0.3381/0.4824, allocations: 63.15 MB / 494.2 MB, free: 16.88 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.03769/0.5202, allocations: 13.23 MB / 0.4955 GB, free: 8.074 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01818/0.5384, allocations: 274.7 kB / 0.4958 GB, free: 8.031 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.009249/0.5478, allocations: 4.562 MB / 0.5002 GB, free: 5.934 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.00974/0.5576, allocations: 2.858 MB / 0.503 GB, free: 4.672 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004341/0.5619, allocations: 1.382 MB / 0.5044 GB, free: 4.105 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01981/0.5818, allocations: 12.02 MB / 0.5161 GB, free: 0.6914 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01369/0.5955, allocations: 6.301 MB / 0.5223 GB, free: 13.72 MB / 398.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0105/0.6061, allocations: 2.655 MB / 0.5248 GB, free: 12.32 MB / 398.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.009892/0.616, allocations: 4.503 MB / 0.5292 GB, free: 8.637 MB / 398.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.003302/0.6194, allocations: 460 kB / 0.5297 GB, free: 8.188 MB / 398.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.006435/0.6258, allocations: 1.709 MB / 0.5314 GB, free: 6.477 MB / 398.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.006066/0.6319, allocations: 3.573 MB / 0.5348 GB, free: 2.895 MB / 398.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.009491/0.6415, allocations: 4.015 MB / 0.5388 GB, free: 14.85 MB / 414.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01855/0.6601, allocations: 12.97 MB / 0.5514 GB, free: 1.848 MB / 414.1 MB
Notification: Performance of FrontEnd - DAE generated: time 7.804e-06/0.6601, allocations: 8 kB / 0.5514 GB, free: 1.84 MB / 414.1 MB
Notification: Performance of FrontEnd: time 1.713e-06/0.6601, allocations: 0 / 0.5514 GB, free: 1.84 MB / 414.1 MB
Notification: Performance of Transformations before backend: time 0.0005917/0.6607, allocations: 0 / 0.5514 GB, free: 1.84 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: 2782
 * Number of variables: 2782
Notification: Performance of Generate backend data structure: time 0.02971/0.6905, allocations: 9.045 MB / 0.5603 GB, free: 8.707 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.617e-05/0.6905, allocations: 12.02 kB / 0.5603 GB, free: 8.695 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01797/0.7085, allocations: 4.1 MB / 0.5643 GB, free: 4.578 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01478/0.7234, allocations: 5.299 MB / 0.5695 GB, free: 15.2 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005317/0.7239, allocations: 438.9 kB / 0.5699 GB, free: 14.77 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003469/0.7274, allocations: 0.597 MB / 0.5705 GB, free: 14.17 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04735/0.7748, allocations: 20.63 MB / 0.5906 GB, free: 8.398 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.000499/0.7753, allocations: 75.31 kB / 0.5907 GB, free: 8.324 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.003123/0.7785, allocations: 275.7 kB / 0.5909 GB, free: 8.055 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.00676/0.7853, allocations: 3.714 MB / 0.5946 GB, free: 4.34 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04206/0.8274, allocations: 18.2 MB / 0.6123 GB, free: 2.125 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.5929/1.42, allocations: 74.63 MB / 0.6852 GB, free: 91.22 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.0191/1.439, allocations: 7.45 MB / 0.6925 GB, free: 88.2 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.007418/1.447, allocations: 4.108 MB / 0.6965 GB, free: 86.56 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.03743/1.484, allocations: 18.22 MB / 0.7143 GB, free: 77.18 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.046e-05/1.484, allocations: 67.94 kB / 0.7144 GB, free: 77.11 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=335): time 6.342e-06/1.485, allocations: 7.938 kB / 0.7144 GB, free: 77.1 MB / 494.1 MB
Notification: Performance of matching and sorting (n=419): time 0.111/1.595, allocations: 35.09 MB / 0.7486 GB, free: 53.19 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.534e-05/1.596, allocations: 196.9 kB / 0.7488 GB, free: 52.96 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008786/1.604, allocations: 6.518 MB / 0.7552 GB, free: 46.44 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0009704/1.605, allocations: 85.7 kB / 0.7553 GB, free: 46.35 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.002656/1.608, allocations: 5.363 MB / 0.7605 GB, free: 40.99 MB / 494.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.00193/1.61, allocations: 1.543 MB / 0.762 GB, free: 39.43 MB / 494.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002835/1.613, allocations: 2.138 MB / 0.7641 GB, free: 37.28 MB / 494.1 MB
Notification: Performance of setup shared object (initialization): time 5.899e-05/1.613, allocations: 305.1 kB / 0.7644 GB, free: 36.98 MB / 494.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005682/1.619, allocations: 4.331 MB / 0.7686 GB, free: 32.64 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.008399/1.627, allocations: 10.28 MB / 0.7787 GB, free: 19.47 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01572/1.643, allocations: 15.67 MB / 0.794 GB, free: 0.8828 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001821/1.643, allocations: 63.66 kB / 0.794 GB, free: 0.8242 MB / 494.1 MB
Notification: Performance of matching and sorting (n=1417) (initialization): time 0.03991/1.683, allocations: 16.36 MB / 0.81 GB, free: 436 kB / 0.4982 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001559/1.683, allocations: 95.98 kB / 0.8101 GB, free: 340 kB / 0.4982 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002826/1.683, allocations: 208 kB / 0.8103 GB, free: 132 kB / 0.4982 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008465/1.692, allocations: 2.366 MB / 0.8126 GB, free: 13.76 MB / 0.5138 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.009053/1.701, allocations: 2.468 MB / 0.815 GB, free: 11.29 MB / 0.5138 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.3133/2.014, allocations: 11.89 MB / 0.8266 GB, free: 153.7 MB / 0.5138 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01695/2.031, allocations: 0.5865 MB / 0.8272 GB, free: 153.7 MB / 0.5138 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.00336/2.035, allocations: 0.5999 MB / 0.8278 GB, free: 153.7 MB / 0.5138 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 943
 * Number of states: 0 ()
 * Number of discrete variables: 49 (jointUSR.revolute.positiveBranch,Body3.sphereColor[3],Body3.sphereColor[2],Body3.sphereColor[1],Body2.sphereColor[3],Body2.sphereColor[2],Body2.sphereColor[1],Body1.sphereColor[3],Body1.sphereColor[2],Body1.sphereColor[1],jointUSR.rod1Color[3],jointUSR.rod1Color[2],jointUSR.rod1Color[1],jointUSR.cylinderColor[3],jointUSR.cylinderColor[2],jointUSR.cylinderColor[1],jointUSR.sphereColor[3],jointUSR.sphereColor[2],jointUSR.sphereColor[1],jointUSR.revoluteColor[3],jointUSR.revoluteColor[2],jointUSR.revoluteColor[1],fixedFrame.color_x[3],fixedFrame.color_x[2],fixedFrame.color_x[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 (1316):
 * Single equations (assignments): 1309
 * 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,52,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.01309/2.048, allocations: 3.78 MB / 0.8315 GB, free: 153.5 MB / 0.5138 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002568/2.051, allocations: 0.5912 MB / 0.8321 GB, free: 153.5 MB / 0.5138 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.03422/2.085, allocations: 8.33 MB / 0.8402 GB, free: 151.3 MB / 0.5138 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0002055/2.085, allocations: 58.94 kB / 0.8403 GB, free: 151.3 MB / 0.5138 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.000119/2.085, allocations: 12.12 kB / 0.8403 GB, free: 151.3 MB / 0.5138 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001683/2.085, allocations: 23.77 kB / 0.8403 GB, free: 151.3 MB / 0.5138 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.07035/2.156, allocations: 18.96 MB / 0.8588 GB, free: 144.1 MB / 0.5138 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 0.0001145/2.156, allocations: 12.42 kB / 0.8588 GB, free: 144.1 MB / 0.5138 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.005158/2.161, allocations: 0.6253 MB / 0.8594 GB, free: 144.1 MB / 0.5138 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.007943/2.169, allocations: 1.806 MB / 0.8612 GB, free: 143.8 MB / 0.5138 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001016/2.17, allocations: 77.44 kB / 0.8613 GB, free: 143.7 MB / 0.5138 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02157/2.192, allocations: 10 MB / 0.871 GB, free: 139.1 MB / 0.5138 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.001e-05/2.192, allocations: 17.84 kB / 0.871 GB, free: 139.1 MB / 0.5138 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.03056/2.222, allocations: 12.47 MB / 0.8832 GB, free: 134.2 MB / 0.5138 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.004721/2.227, allocations: 1.166 MB / 0.8844 GB, free: 133.6 MB / 0.5138 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002297/2.23, allocations: 76.64 kB / 0.8844 GB, free: 133.5 MB / 0.5138 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.004702/2.234, allocations: 209.2 kB / 0.8846 GB, free: 133.5 MB / 0.5138 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001289/2.236, allocations: 263.3 kB / 0.8849 GB, free: 133.4 MB / 0.5138 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0006815/2.236, allocations: 112.4 kB / 0.885 GB, free: 133.4 MB / 0.5138 GB
Notification: Performance of sorting global known variables: time 0.009431/2.246, allocations: 4.052 MB / 0.889 GB, free: 132.1 MB / 0.5138 GB
Notification: Performance of sort global known variables: time 6.71e-07/2.246, allocations: 0 / 0.889 GB, free: 132.1 MB / 0.5138 GB
Notification: Performance of remove unused functions: time 0.0125/2.258, allocations: 1.965 MB / 0.8909 GB, free: 131.9 MB / 0.5138 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 55
 * 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 (331):
 * Single equations (assignments): 324
 * 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,42,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.001669/2.26, allocations: 276.5 kB / 0.8911 GB, free: 131.7 MB / 0.5138 GB
Notification: Performance of simCode: created initialization part: time 0.01829/2.278, allocations: 10.12 MB / 0.901 GB, free: 125.4 MB / 0.5138 GB
Notification: Performance of simCode: created event and clocks part: time 1.835e-05/2.278, allocations: 0 / 0.901 GB, free: 125.4 MB / 0.5138 GB
Notification: Performance of simCode: created simulation system equations: time 0.006087/2.284, allocations: 3.393 MB / 0.9043 GB, free: 122.4 MB / 0.5138 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00931/2.294, allocations: 0.7643 MB / 0.9051 GB, free: 121.8 MB / 0.5138 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.03822/2.332, allocations: 21.74 MB / 0.9263 GB, free: 100.8 MB / 0.5138 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.01242/2.345, allocations: 5.605 MB / 0.9318 GB, free: 95.18 MB / 0.5138 GB
Notification: Performance of simCode: alias equations: time 0.01025/2.355, allocations: 3.322 MB / 0.935 GB, free: 91.85 MB / 0.5138 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002431/2.357, allocations: 415.4 kB / 0.9354 GB, free: 91.44 MB / 0.5138 GB
Notification: Performance of SimCode: time 9.92e-07/2.357, allocations: 4 kB / 0.9354 GB, free: 91.44 MB / 0.5138 GB
Notification: Performance of Templates: time 0.1219/2.479, allocations: 72.18 MB / 1.006 GB, free: 19.37 MB / 0.5138 GB
make -j1 -f ModelicaTest_3.2.1_ModelicaTest.MultiBody.FourbarVariants.JointUSR.makefile
(rm -f ModelicaTest_3.2.1_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe ; mkfifo ModelicaTest_3.2.1_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe ; head -c 1048576 < ModelicaTest_3.2.1_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe >> ../files/ModelicaTest_3.2.1_ModelicaTest.MultiBody.FourbarVariants.JointUSR.sim & ./ModelicaTest_3.2.1_ModelicaTest.MultiBody.FourbarVariants.JointUSR  -abortSlowSimulation -alarm=480 -lv LOG_STATS > ModelicaTest_3.2.1_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe 2>&1)