Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.3_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.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.FourbarVariants.JointUSR,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|j2.s|j2.v",fileNamePrefix="ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR")
translateModel(ModelicaTest.MultiBody.FourbarVariants.JointUSR,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|j2.s|j2.v",fileNamePrefix="ModelicaTest_3.2.3_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.001376/0.001376, allocations: 106.1 kB / 15.98 MB, free: 6.359 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.001807/0.001807, allocations: 193.5 kB / 16.91 MB, free: 6.191 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.767/1.767, allocations: 205.1 MB / 222.8 MB, free: 4.723 MB / 174.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo): time 0.3962/0.3962, allocations: 43.5 MB / 313.6 MB, free: 8.484 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: Performance of FrontEnd - loaded program: time 0.001437/0.001437, allocations: 68.41 kB / 382.2 MB, free: 8.641 MB / 318.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.154/0.1555, allocations: 57.79 MB / 440 MB, free: 14.76 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.FourbarVariants.JointUSR): time 0.3197/0.4752, allocations: 63.15 MB / 0.4914 GB, free: 10.46 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.03608/0.5114, allocations: 13.1 MB / 0.5042 GB, free: 1.703 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01702/0.5284, allocations: 273.5 kB / 0.5044 GB, free: 1.66 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.009298/0.5378, allocations: 4.412 MB / 0.5087 GB, free: 15.66 MB / 398.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01099/0.5489, allocations: 2.824 MB / 0.5115 GB, free: 14.4 MB / 398.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00492/0.5538, allocations: 1.419 MB / 0.5129 GB, free: 13.81 MB / 398.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01759/0.5715, allocations: 8.717 MB / 0.5214 GB, free: 10.78 MB / 398.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01196/0.5835, allocations: 6.803 MB / 0.528 GB, free: 7.152 MB / 398.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.008143/0.5917, allocations: 3.076 MB / 0.531 GB, free: 4.066 MB / 398.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.008164/0.5999, allocations: 4.713 MB / 0.5356 GB, free: 15.34 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.003022/0.6029, allocations: 0.6602 MB / 0.5363 GB, free: 14.68 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.006697/0.6096, allocations: 1.853 MB / 0.5381 GB, free: 12.82 MB / 414.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.0069/0.6166, allocations: 3.788 MB / 0.5418 GB, free: 9.027 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.01178/0.6284, allocations: 4.265 MB / 0.546 GB, free: 4.738 MB / 414.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.02176/0.6503, allocations: 13.22 MB / 0.5589 GB, free: 7.473 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 7.644e-06/0.6503, allocations: 3.938 kB / 0.5589 GB, free: 7.469 MB / 430.1 MB
Notification: Performance of FrontEnd: time 1.754e-06/0.6503, allocations: 0 / 0.5589 GB, free: 7.469 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 0.0005549/0.6509, allocations: 0 / 0.5589 GB, free: 7.469 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: 2782
 * Number of variables: 2782
Notification: Performance of Generate backend data structure: time 0.02668/0.6776, allocations: 9.03 MB / 0.5677 GB, free: 14.35 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.285e-05/0.6777, allocations: 15.89 kB / 0.5677 GB, free: 14.34 MB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01782/0.6955, allocations: 4.104 MB / 0.5717 GB, free: 10.21 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01395/0.7095, allocations: 5.295 MB / 0.5769 GB, free: 4.836 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.00048/0.71, allocations: 434.9 kB / 0.5773 GB, free: 4.41 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003529/0.7135, allocations: 0.6011 MB / 0.5779 GB, free: 3.809 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04544/0.759, allocations: 20.62 MB / 0.598 GB, free: 14.05 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002545/0.7593, allocations: 79.3 kB / 0.5981 GB, free: 13.97 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001015/0.7603, allocations: 271.7 kB / 0.5984 GB, free: 13.7 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.00532/0.7657, allocations: 3.722 MB / 0.602 GB, free: 9.98 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03929/0.805, allocations: 18.2 MB / 0.6198 GB, free: 7.766 MB / 494.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.602/1.407, allocations: 74.63 MB / 0.6926 GB, free: 85.88 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.02139/1.428, allocations: 7.453 MB / 0.6999 GB, free: 82.86 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.008088/1.437, allocations: 4.107 MB / 0.7039 GB, free: 81.21 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.03218/1.469, allocations: 18.22 MB / 0.7217 GB, free: 71.73 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.46e-05/1.469, allocations: 66.53 kB / 0.7218 GB, free: 71.66 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=335): time 7.274e-06/1.469, allocations: 0 / 0.7218 GB, free: 71.66 MB / 494.1 MB
Notification: Performance of matching and sorting (n=419): time 0.112/1.581, allocations: 35.12 MB / 0.7561 GB, free: 39.36 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 8.74e-05/1.581, allocations: 192.9 kB / 0.7563 GB, free: 39.13 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01244/1.594, allocations: 6.51 MB / 0.7626 GB, free: 32.62 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.001697/1.595, allocations: 85.7 kB / 0.7627 GB, free: 32.53 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.004011/1.599, allocations: 5.368 MB / 0.7679 GB, free: 27.09 MB / 494.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.002407/1.602, allocations: 1.546 MB / 0.7695 GB, free: 25.52 MB / 494.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.003311/1.605, allocations: 2.139 MB / 0.7715 GB, free: 23.38 MB / 494.1 MB
Notification: Performance of setup shared object (initialization): time 7.172e-05/1.605, allocations: 301.1 kB / 0.7718 GB, free: 23.08 MB / 494.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00558/1.611, allocations: 4.327 MB / 0.7761 GB, free: 18.75 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.009058/1.62, allocations: 10.28 MB / 0.7861 GB, free: 5.57 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02071/1.641, allocations: 15.68 MB / 0.8014 GB, free: 2.969 MB / 0.4982 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.00036/1.641, allocations: 60 kB / 0.8015 GB, free: 2.91 MB / 0.4982 GB
Notification: Performance of matching and sorting (n=1417) (initialization): time 0.04196/1.683, allocations: 16.35 MB / 0.8174 GB, free: 2.52 MB / 0.5138 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001704/1.683, allocations: 92.19 kB / 0.8175 GB, free: 2.43 MB / 0.5138 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0003106/1.683, allocations: 212 kB / 0.8177 GB, free: 2.223 MB / 0.5138 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.3577/2.041, allocations: 2.369 MB / 0.82 GB, free: 153 MB / 0.5138 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.009239/2.051, allocations: 2.467 MB / 0.8224 GB, free: 153 MB / 0.5138 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01846/2.069, allocations: 12.75 MB / 0.8349 GB, free: 149.5 MB / 0.5138 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01017/2.079, allocations: 0.5918 MB / 0.8355 GB, free: 149.3 MB / 0.5138 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002461/2.082, allocations: 0.596 MB / 0.8361 GB, free: 149.2 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.008333/2.09, allocations: 3.781 MB / 0.8397 GB, free: 147.8 MB / 0.5138 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001594/2.092, allocations: 0.59 MB / 0.8403 GB, free: 147.8 MB / 0.5138 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0183/2.11, allocations: 8.325 MB / 0.8485 GB, free: 144.8 MB / 0.5138 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.000145/2.11, allocations: 62.73 kB / 0.8485 GB, free: 144.8 MB / 0.5138 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.227e-05/2.11, allocations: 11.06 kB / 0.8485 GB, free: 144.8 MB / 0.5138 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001412/2.11, allocations: 22.19 kB / 0.8485 GB, free: 144.8 MB / 0.5138 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.05181/2.162, allocations: 18.96 MB / 0.8671 GB, free: 137.4 MB / 0.5138 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.296e-05/2.162, allocations: 11.75 kB / 0.8671 GB, free: 137.4 MB / 0.5138 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003431/2.166, allocations: 0.629 MB / 0.8677 GB, free: 137.4 MB / 0.5138 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.006681/2.172, allocations: 1.817 MB / 0.8695 GB, free: 137 MB / 0.5138 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001051/2.174, allocations: 79.58 kB / 0.8695 GB, free: 137 MB / 0.5138 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01597/2.19, allocations: 10.83 MB / 0.8801 GB, free: 131.9 MB / 0.5138 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.362e-05/2.19, allocations: 23.38 kB / 0.8801 GB, free: 131.9 MB / 0.5138 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02094/2.211, allocations: 12.47 MB / 0.8923 GB, free: 123.9 MB / 0.5138 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003589/2.214, allocations: 1.174 MB / 0.8935 GB, free: 122.7 MB / 0.5138 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001079/2.215, allocations: 79.98 kB / 0.8935 GB, free: 122.6 MB / 0.5138 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002714/2.218, allocations: 199.7 kB / 0.8937 GB, free: 122.4 MB / 0.5138 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0008012/2.219, allocations: 268.9 kB / 0.894 GB, free: 122.2 MB / 0.5138 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002598/2.219, allocations: 112 kB / 0.8941 GB, free: 122 MB / 0.5138 GB
Notification: Performance of sorting global known variables: time 0.006109/2.225, allocations: 4.056 MB / 0.8981 GB, free: 118 MB / 0.5138 GB
Notification: Performance of sort global known variables: time 1.5e-07/2.225, allocations: 0 / 0.8981 GB, free: 118 MB / 0.5138 GB
Notification: Performance of remove unused functions: time 0.01294/2.238, allocations: 1.957 MB / 0.9 GB, free: 116.1 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.001646/2.24, allocations: 288.5 kB / 0.9002 GB, free: 115.8 MB / 0.5138 GB
Notification: Performance of simCode: created initialization part: time 0.02607/2.266, allocations: 15.89 MB / 0.9158 GB, free: 100 MB / 0.5138 GB
Notification: Performance of simCode: created event and clocks part: time 2.045e-05/2.266, allocations: 0 / 0.9158 GB, free: 100 MB / 0.5138 GB
Notification: Performance of simCode: created simulation system equations: time 0.005468/2.272, allocations: 3.399 MB / 0.9191 GB, free: 96.59 MB / 0.5138 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.005572/2.277, allocations: 0.8017 MB / 0.9199 GB, free: 95.85 MB / 0.5138 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.03035/2.307, allocations: 21.74 MB / 0.9411 GB, free: 73.97 MB / 0.5138 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.006606/2.314, allocations: 5.601 MB / 0.9466 GB, free: 68.34 MB / 0.5138 GB
Notification: Performance of simCode: alias equations: time 0.009257/2.323, allocations: 3.316 MB / 0.9498 GB, free: 65.02 MB / 0.5138 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001385/2.325, allocations: 419.4 kB / 0.9502 GB, free: 64.61 MB / 0.5138 GB
Notification: Performance of SimCode: time 9.72e-07/2.325, allocations: 4 kB / 0.9502 GB, free: 64.6 MB / 0.5138 GB
Notification: Performance of Templates: time 0.4061/2.731, allocations: 74.96 MB / 1.023 GB, free: 140.9 MB / 0.5138 GB
make -j1 -f ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR.makefile
(rm -f ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe ; mkfifo ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe ; head -c 1048576 < ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe >> ../files/ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR.sim & ./ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_3.2.3_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe 2>&1)