Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.2_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.2+maint.om/package.mo", uses=false)
Using package ModelicaTest with version 3.2.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.2+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.2_ModelicaTest.MultiBody.FourbarVariants.JointUSR")
translateModel(ModelicaTest.MultiBody.FourbarVariants.JointUSR,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="ModelicaTest_3.2.2_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.001226/0.001226, allocations: 99.55 kB / 15.05 MB, free: 1.105 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.00149/0.00149, allocations: 177.3 kB / 15.94 MB, free: 228 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.522/1.522, allocations: 205.4 MB / 222.1 MB, free: 5.652 MB / 174.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.2+maint.om/package.mo): time 0.3478/0.3478, allocations: 39.99 MB / 310.4 MB, free: 10 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.2. 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.0006308/0.0006309, allocations: 16 kB / 378.8 MB, free: 12.64 MB / 318.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1368/0.1375, allocations: 57.95 MB / 436.8 MB, free: 2.59 MB / 366.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.FourbarVariants.JointUSR): time 0.2799/0.4174, allocations: 54.05 MB / 490.8 MB, free: 20.13 MB / 382.1 MB
Notification: Performance of NFInst.instExpressions: time 0.0283/0.4457, allocations: 13.09 MB / 0.4921 GB, free: 11.24 MB / 382.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0121/0.4579, allocations: 268.9 kB / 0.4923 GB, free: 11.2 MB / 382.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.008654/0.4665, allocations: 2.689 MB / 0.495 GB, free: 10.31 MB / 382.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.02946/0.496, allocations: 7.798 MB / 0.5026 GB, free: 6.609 MB / 382.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00563/0.5017, allocations: 1.259 MB / 0.5038 GB, free: 6.148 MB / 382.1 MB
Notification: Performance of NFFlatten.flatten: time 0.03019/0.5319, allocations: 17.3 MB / 0.5207 GB, free: 488 kB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.01259/0.5445, allocations: 7.802 MB / 0.5283 GB, free: 9.969 MB / 398.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.005594/0.5502, allocations: 2.036 MB / 0.5303 GB, free: 7.926 MB / 398.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.005335/0.5556, allocations: 2.874 MB / 0.5331 GB, free: 5.043 MB / 398.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001961/0.5576, allocations: 292 kB / 0.5334 GB, free: 4.758 MB / 398.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.004063/0.5616, allocations: 1.288 MB / 0.5347 GB, free: 3.469 MB / 398.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.004139/0.5658, allocations: 3.043 MB / 0.5376 GB, free: 428 kB / 398.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002983/0.5688, allocations: 1.52 MB / 0.5391 GB, free: 14.88 MB / 414.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01262/0.5814, allocations: 10.58 MB / 0.5494 GB, free: 4.281 MB / 414.1 MB
Notification: Performance of FrontEnd - DAE generated: time 3.857e-06/0.5815, allocations: 0 / 0.5494 GB, free: 4.281 MB / 414.1 MB
Notification: Performance of FrontEnd: time 1.793e-06/0.5815, allocations: 0 / 0.5494 GB, free: 4.281 MB / 414.1 MB
Notification: Performance of Transformations before backend: time 0.0005459/0.5821, allocations: 0 / 0.5494 GB, free: 4.281 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.01939/0.6015, allocations: 7.834 MB / 0.5571 GB, free: 12.36 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 3.909e-05/0.6015, allocations: 8.031 kB / 0.5571 GB, free: 12.36 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.03521/0.6367, allocations: 7.416 MB / 0.5643 GB, free: 4.918 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01417/0.6509, allocations: 6.038 MB / 0.5702 GB, free: 14.8 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003899/0.6514, allocations: 430.9 kB / 0.5707 GB, free: 14.38 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002548/0.6539, allocations: 0.6049 MB / 0.5712 GB, free: 13.77 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04117/0.6951, allocations: 20.84 MB / 0.5916 GB, free: 7.793 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002781/0.6954, allocations: 75.3 kB / 0.5917 GB, free: 7.719 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001507/0.697, allocations: 279.7 kB / 0.5919 GB, free: 7.445 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.00566/0.7026, allocations: 3.71 MB / 0.5956 GB, free: 3.73 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03821/0.7408, allocations: 18.37 MB / 0.6135 GB, free: 1.352 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.4937/1.235, allocations: 74.55 MB / 0.6863 GB, free: 86.41 MB / 494.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01885/1.253, allocations: 7.453 MB / 0.6936 GB, free: 83.43 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0371/1.291, allocations: 18.17 MB / 0.7113 GB, free: 74.08 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.547e-05/1.291, allocations: 65.69 kB / 0.7114 GB, free: 74.02 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=332): time 6.843e-06/1.291, allocations: 4 kB / 0.7114 GB, free: 74.02 MB / 494.1 MB
Notification: Performance of matching and sorting (n=413): time 0.1172/1.408, allocations: 35.73 MB / 0.7463 GB, free: 51.8 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.308e-05/1.408, allocations: 200.8 kB / 0.7465 GB, free: 51.57 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01251/1.421, allocations: 6.95 MB / 0.7533 GB, free: 44.62 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.001033/1.422, allocations: 91.97 kB / 0.7533 GB, free: 44.53 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.003601/1.425, allocations: 5.435 MB / 0.7586 GB, free: 39.09 MB / 494.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.002091/1.428, allocations: 1.51 MB / 0.7601 GB, free: 37.59 MB / 494.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001313/1.429, allocations: 219 kB / 0.7603 GB, free: 37.39 MB / 494.1 MB
Notification: Performance of setup shared object (initialization): time 0.0008519/1.43, allocations: 0.7563 MB / 0.7611 GB, free: 36.62 MB / 494.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.00694/1.437, allocations: 4.132 MB / 0.7651 GB, free: 32.49 MB / 494.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01015/1.447, allocations: 9.987 MB / 0.7749 GB, free: 19.62 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01015/1.457, allocations: 6.296 MB / 0.781 GB, free: 13.31 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0002422/1.457, allocations: 60 kB / 0.7811 GB, free: 13.25 MB / 494.1 MB
Notification: Performance of matching and sorting (n=1377) (initialization): time 0.03474/1.492, allocations: 15.84 MB / 0.7965 GB, free: 13.38 MB / 0.4982 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003799/1.492, allocations: 96.25 kB / 0.7966 GB, free: 13.29 MB / 0.4982 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0004345/1.493, allocations: 208 kB / 0.7968 GB, free: 13.08 MB / 0.4982 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.00846/1.501, allocations: 2.338 MB / 0.7991 GB, free: 10.75 MB / 0.4982 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00886/1.51, allocations: 2.41 MB / 0.8015 GB, free: 8.332 MB / 0.4982 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.02039/1.531, allocations: 12.09 MB / 0.8133 GB, free: 12.11 MB / 0.5138 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.008634/1.539, allocations: 0.5832 MB / 0.8138 GB, free: 11.53 MB / 0.5138 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.002187/1.542, allocations: 0.5879 MB / 0.8144 GB, free: 10.94 MB / 0.5138 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 937
 * 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 (1276):
 * Single equations (assignments): 1269
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(2,100.0%) 52}
 * Non-linear torn systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.009122/1.551, allocations: 3.764 MB / 0.8181 GB, free: 7.164 MB / 0.5138 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001786/1.553, allocations: 0.5939 MB / 0.8187 GB, free: 6.562 MB / 0.5138 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.2223/1.775, allocations: 8.15 MB / 0.8266 GB, free: 152 MB / 0.5138 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.356e-05/1.775, allocations: 63.92 kB / 0.8267 GB, free: 152 MB / 0.5138 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.111e-05/1.775, allocations: 11.31 kB / 0.8267 GB, free: 152 MB / 0.5138 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 8.027e-05/1.775, allocations: 18.98 kB / 0.8267 GB, free: 152 MB / 0.5138 GB
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 postOpt removeSimpleEquations (simulation): time 0.04048/1.816, allocations: 18.76 MB / 0.845 GB, free: 147.7 MB / 0.5138 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.954e-05/1.816, allocations: 16.89 kB / 0.845 GB, free: 147.7 MB / 0.5138 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002811/1.819, allocations: 0.613 MB / 0.8456 GB, free: 147.6 MB / 0.5138 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.006034/1.825, allocations: 1.748 MB / 0.8474 GB, free: 147.3 MB / 0.5138 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0005678/1.825, allocations: 85.73 kB / 0.8474 GB, free: 147.3 MB / 0.5138 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01536/1.841, allocations: 10.16 MB / 0.8574 GB, free: 142.6 MB / 0.5138 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.879e-05/1.841, allocations: 17.19 kB / 0.8574 GB, free: 142.6 MB / 0.5138 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02381/1.864, allocations: 14.07 MB / 0.8711 GB, free: 135.5 MB / 0.5138 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003814/1.868, allocations: 1.167 MB / 0.8723 GB, free: 135.2 MB / 0.5138 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001272/1.87, allocations: 77.61 kB / 0.8723 GB, free: 135.1 MB / 0.5138 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002831/1.872, allocations: 206.7 kB / 0.8725 GB, free: 135.1 MB / 0.5138 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006643/1.873, allocations: 252.7 kB / 0.8728 GB, free: 135 MB / 0.5138 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002747/1.873, allocations: 112.6 kB / 0.8729 GB, free: 135 MB / 0.5138 GB
Notification: Performance of sorting global known variables: time 0.009234/1.883, allocations: 4.403 MB / 0.8772 GB, free: 133.6 MB / 0.5138 GB
Notification: Performance of sort global known variables: time 6.11e-07/1.883, allocations: 0 / 0.8772 GB, free: 133.6 MB / 0.5138 GB
Notification: Performance of remove unused functions: time 0.009853/1.893, allocations: 1.936 MB / 0.8791 GB, free: 133.4 MB / 0.5138 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 52
 * 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 (325):
 * Single equations (assignments): 318
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 1 {(2,100.0%) 42}
 * Non-linear torn systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.001271/1.894, allocations: 283.7 kB / 0.8793 GB, free: 133.2 MB / 0.5138 GB
Notification: Performance of simCode: created initialization part: time 0.01487/1.909, allocations: 9.771 MB / 0.8889 GB, free: 129.7 MB / 0.5138 GB
Notification: Performance of simCode: created event and clocks part: time 1.265e-05/1.909, allocations: 5.938 kB / 0.8889 GB, free: 129.7 MB / 0.5138 GB
Notification: Performance of simCode: created simulation system equations: time 0.004799/1.914, allocations: 3.296 MB / 0.8921 GB, free: 128.4 MB / 0.5138 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00453/1.918, allocations: 0.7346 MB / 0.8928 GB, free: 128 MB / 0.5138 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.0318/1.95, allocations: 20.68 MB / 0.913 GB, free: 110.6 MB / 0.5138 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.007015/1.957, allocations: 5.76 MB / 0.9186 GB, free: 104.8 MB / 0.5138 GB
Notification: Performance of simCode: alias equations: time 0.007839/1.965, allocations: 3.326 MB / 0.9219 GB, free: 101.4 MB / 0.5138 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.00151/1.966, allocations: 417.4 kB / 0.9223 GB, free: 101 MB / 0.5138 GB
Notification: Performance of SimCode: time 8.31e-07/1.966, allocations: 3.938 kB / 0.9223 GB, free: 101 MB / 0.5138 GB
Notification: Performance of Templates: time 0.1028/2.069, allocations: 69.74 MB / 0.9904 GB, free: 31.4 MB / 0.5138 GB
make -j1 -f ModelicaTest_3.2.2_ModelicaTest.MultiBody.FourbarVariants.JointUSR.makefile
Environment - environmentSimulation:
(rm -f ModelicaTest_3.2.2_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe ; mkfifo ModelicaTest_3.2.2_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe ; head -c 1048576 < ModelicaTest_3.2.2_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe >> ../files/ModelicaTest_3.2.2_ModelicaTest.MultiBody.FourbarVariants.JointUSR.sim & ./ModelicaTest_3.2.2_ModelicaTest.MultiBody.FourbarVariants.JointUSR  -abortSlowSimulation -alarm=480 -lv LOG_STATS > ModelicaTest_3.2.2_ModelicaTest.MultiBody.FourbarVariants.JointUSR.pipe 2>&1)
Environment - environmentSimulation: