Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Modelica_3.2.3_cpp_Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+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/ModelicaServices 3.2.3+maint.om/package.mo", uses=false)
Using package ModelicaServices with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 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 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Running command: translateModel(Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|revolute1.phi|revolute1.w|revolute2.phi|revolute2.w",fileNamePrefix="Modelica_3.2.3_cpp_Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses")
translateModel(Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|revolute1.phi|revolute1.w|revolute2.phi|revolute2.w",fileNamePrefix="Modelica_3.2.3_cpp_Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.001412/0.001412, allocations: 174.3 kB / 16.41 MB, free: 5.684 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.771/1.771, allocations: 205.4 MB / 222.5 MB, free: 9.113 MB / 186.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.0007156/0.0007156, allocations: 99.72 kB / 270.9 MB, free: 8.352 MB / 218.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0002854/0.0002854, allocations: 12 kB / 327 MB, free: 196 kB / 266.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1925/0.1928, allocations: 47.58 MB / 374.6 MB, free: 45.39 MB / 298.7 MB
Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses): time 0.04432/0.2372, allocations: 66.42 MB / 441 MB, free: 14.27 MB / 330.7 MB
Notification: Performance of NFInst.instExpressions: time 0.02825/0.2655, allocations: 14 MB / 455 MB, free: 228 kB / 330.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.009832/0.2753, allocations: 323.2 kB / 455.3 MB, free: 15.9 MB / 346.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.008889/0.2843, allocations: 3.417 MB / 458.7 MB, free: 12.47 MB / 346.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.02468/0.309, allocations: 10.22 MB / 468.9 MB, free: 2.219 MB / 346.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004574/0.3136, allocations: 1.291 MB / 470.2 MB, free: 0.9258 MB / 346.7 MB
Notification: Performance of NFFlatten.flatten: time 0.2118/0.5254, allocations: 37.32 MB / 0.4956 GB, free: 31.34 MB / 378.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.008898/0.5343, allocations: 6.384 MB / 0.5019 GB, free: 30.8 MB / 378.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004427/0.5388, allocations: 2.256 MB / 0.5041 GB, free: 30.8 MB / 378.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.005616/0.5444, allocations: 3.693 MB / 0.5077 GB, free: 30.79 MB / 378.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0009703/0.5454, allocations: 250.4 kB / 0.5079 GB, free: 30.79 MB / 378.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002752/0.5481, allocations: 1.193 MB / 0.5091 GB, free: 30.79 MB / 378.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.002503/0.5507, allocations: 3.003 MB / 0.512 GB, free: 30.79 MB / 378.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.001292/0.552, allocations: 1.312 MB / 0.5133 GB, free: 30.75 MB / 378.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.006622/0.5586, allocations: 8.539 MB / 0.5216 GB, free: 28.06 MB / 378.7 MB
Notification: Performance of FrontEnd - DAE generated: time 5.831e-06/0.5586, allocations: 0 / 0.5216 GB, free: 28.06 MB / 378.7 MB
Notification: Performance of FrontEnd: time 2.234e-06/0.5586, allocations: 0 / 0.5216 GB, free: 28.06 MB / 378.7 MB
Notification: Performance of Transformations before backend: time 0.0003112/0.559, allocations: 0 / 0.5216 GB, free: 28.06 MB / 378.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2303
 * Number of variables: 2303
Notification: Performance of Generate backend data structure: time 0.01285/0.5718, allocations: 6.918 MB / 0.5284 GB, free: 24.03 MB / 378.7 MB
Notification: Performance of prepare preOptimizeDAE: time 3.876e-05/0.5719, allocations: 13 kB / 0.5284 GB, free: 24.03 MB / 378.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02599/0.5979, allocations: 3.766 MB / 0.5321 GB, free: 22.1 MB / 378.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01278/0.6107, allocations: 5.385 MB / 0.5373 GB, free: 18.08 MB / 378.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002269/0.6109, allocations: 372.6 kB / 0.5377 GB, free: 17.88 MB / 378.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001305/0.6122, allocations: 0.5137 MB / 0.5382 GB, free: 17.54 MB / 378.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02803/0.6403, allocations: 16.26 MB / 0.5541 GB, free: 4.676 MB / 378.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0002093/0.6406, allocations: 85.61 kB / 0.5542 GB, free: 4.625 MB / 378.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0006928/0.6413, allocations: 235.1 kB / 0.5544 GB, free: 4.559 MB / 378.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004008/0.6453, allocations: 2.927 MB / 0.5572 GB, free: 2.148 MB / 378.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.03087/0.6762, allocations: 13.72 MB / 0.5706 GB, free: 8.473 MB / 394.7 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.104/0.7802, allocations: 64.8 MB / 0.6339 GB, free: 15.02 MB / 458.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.02472/0.805, allocations: 7.972 MB / 0.6417 GB, free: 6.918 MB / 458.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.006702/0.8118, allocations: 4.33 MB / 0.6459 GB, free: 2.566 MB / 458.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.2508/1.063, allocations: 19.36 MB / 0.6649 GB, free: 188.4 MB / 474.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 9.412e-05/1.063, allocations: 63.62 kB / 0.6649 GB, free: 188.4 MB / 474.7 MB
Notification: Performance of pre-optimization done (n=290): time 6.692e-06/1.063, allocations: 0.625 kB / 0.6649 GB, free: 188.4 MB / 474.7 MB
Notification: Performance of matching and sorting (n=372): time 0.1646/1.227, allocations: 50.55 MB / 0.7143 GB, free: 161.1 MB / 474.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001064/1.228, allocations: 265.8 kB / 0.7145 GB, free: 160.8 MB / 474.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008419/1.236, allocations: 6.064 MB / 0.7205 GB, free: 154.7 MB / 474.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.001039/1.237, allocations: 81.7 kB / 0.7205 GB, free: 154.6 MB / 474.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.00242/1.239, allocations: 4.681 MB / 0.7251 GB, free: 149.9 MB / 474.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001575/1.241, allocations: 1.372 MB / 0.7264 GB, free: 148.6 MB / 474.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0004552/1.241, allocations: 196 kB / 0.7266 GB, free: 148.4 MB / 474.7 MB
Notification: Performance of setup shared object (initialization): time 0.0003825/1.242, allocations: 0.7073 MB / 0.7273 GB, free: 147.7 MB / 474.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.007926/1.25, allocations: 4.184 MB / 0.7314 GB, free: 143.5 MB / 474.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01119/1.261, allocations: 9.417 MB / 0.7406 GB, free: 131.5 MB / 474.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01081/1.272, allocations: 6.078 MB / 0.7465 GB, free: 125.4 MB / 474.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001317/1.272, allocations: 52 kB / 0.7466 GB, free: 125.4 MB / 474.7 MB
Notification: Performance of matching and sorting (n=1256) (initialization): time 0.04309/1.315, allocations: 17.5 MB / 0.7637 GB, free: 107.8 MB / 474.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001032/1.315, allocations: 91.94 kB / 0.7638 GB, free: 107.7 MB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.000206/1.316, allocations: 180 kB / 0.7639 GB, free: 107.6 MB / 474.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01373/1.329, allocations: 4.603 MB / 0.7684 GB, free: 103 MB / 474.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007549/1.337, allocations: 2.133 MB / 0.7705 GB, free: 100.8 MB / 474.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03023/1.367, allocations: 23.72 MB / 0.7937 GB, free: 76.84 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01014/1.377, allocations: 0.5344 MB / 0.7942 GB, free: 76.3 MB / 474.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001762/1.379, allocations: 0.5362 MB / 0.7947 GB, free: 75.77 MB / 474.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 825
 * Number of states: 0 ()
 * Number of discrete variables: 66 (lineForceWithTwoMasses.massColor[3],lineForceWithTwoMasses.massColor[2],lineForceWithTwoMasses.massColor[1],lineForceWithTwoMasses.color_b[3],lineForceWithTwoMasses.color_b[2],lineForceWithTwoMasses.color_b[1],lineForceWithTwoMasses.color_a[3],lineForceWithTwoMasses.color_a[2],lineForceWithTwoMasses.color_a[1],rod4.color[3],rod4.color[2],rod4.color[1],rod3.color[3],rod3.color[2],rod3.color[1],bodyBox2.color[3],bodyBox2.color[2],bodyBox2.color[1],bodyBox2.body.sphereColor[3],bodyBox2.body.sphereColor[2],bodyBox2.body.sphereColor[1],revolute2.cylinderColor[3],revolute2.cylinderColor[2],revolute2.cylinderColor[1],rod2.color[3],rod2.color[2],rod2.color[1],body2.sphereColor[3],body2.sphereColor[2],body2.sphereColor[1],body1.sphereColor[3],body1.sphereColor[2],body1.sphereColor[1],jointUPS.cylinderColor[3],jointUPS.cylinderColor[2],jointUPS.cylinderColor[1],jointUPS.sphereColor[3],jointUPS.sphereColor[2],jointUPS.sphereColor[1],jointUPS.axisColor[3],jointUPS.axisColor[2],jointUPS.axisColor[1],rod1.color[3],rod1.color[2],rod1.color[1],bodyBox1.color[3],bodyBox1.color[2],bodyBox1.color[1],bodyBox1.body.sphereColor[3],bodyBox1.body.sphereColor[2],bodyBox1.body.sphereColor[1],revolute1.cylinderColor[3],revolute1.cylinderColor[2],revolute1.cylinderColor[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 (1136):
 * Single equations (assignments): 1131
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(2,100.0%) 44,(2,100.0%) 50}
 * Non-linear torn systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.006906/1.386, allocations: 3.129 MB / 0.7978 GB, free: 72.62 MB / 474.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001249/1.387, allocations: 0.567 MB / 0.7983 GB, free: 72.05 MB / 474.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.02087/1.408, allocations: 10.72 MB / 0.8088 GB, free: 61.27 MB / 474.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 5.873e-05/1.408, allocations: 44 kB / 0.8089 GB, free: 61.23 MB / 474.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 2.939e-05/1.408, allocations: 16 kB / 0.8089 GB, free: 61.21 MB / 474.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.892e-05/1.408, allocations: 23.88 kB / 0.8089 GB, free: 61.19 MB / 474.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.03926/1.448, allocations: 20.53 MB / 0.8289 GB, free: 40.54 MB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.871e-05/1.448, allocations: 7.984 kB / 0.8289 GB, free: 40.53 MB / 474.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003054/1.451, allocations: 0.5382 MB / 0.8295 GB, free: 39.99 MB / 474.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.01352/1.464, allocations: 4.219 MB / 0.8336 GB, free: 35.76 MB / 474.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0008872/1.465, allocations: 67.89 kB / 0.8337 GB, free: 35.7 MB / 474.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.2979/1.763, allocations: 21.52 MB / 0.8547 GB, free: 167.6 MB / 474.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.017e-06/1.763, allocations: 1.438 kB / 0.8547 GB, free: 167.6 MB / 474.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02911/1.792, allocations: 15.64 MB / 0.8699 GB, free: 162.4 MB / 474.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.003359/1.796, allocations: 1.187 MB / 0.8711 GB, free: 162.1 MB / 474.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0008005/1.797, allocations: 62.38 kB / 0.8712 GB, free: 162.1 MB / 474.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002812/1.799, allocations: 216.6 kB / 0.8714 GB, free: 162 MB / 474.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0007939/1.8, allocations: 226 kB / 0.8716 GB, free: 162 MB / 474.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002667/1.801, allocations: 99.38 kB / 0.8717 GB, free: 162 MB / 474.7 MB
Notification: Performance of sorting global known variables: time 0.005956/1.806, allocations: 3.776 MB / 0.8754 GB, free: 160.7 MB / 474.7 MB
Notification: Performance of sort global known variables: time 2.21e-07/1.806, allocations: 0 / 0.8754 GB, free: 160.7 MB / 474.7 MB
Notification: Performance of remove unused functions: time 0.00897/1.815, allocations: 1.937 MB / 0.8773 GB, free: 160.5 MB / 474.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 4
 * Number of states: 4 (revolute1.phi,revolute1.w,revolute2.phi,revolute2.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 (272):
 * Single equations (assignments): 267
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(2,100.0%) 43,(1,100.0%) 36}
 * Non-linear torn systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.0006824/1.816, allocations: 223.8 kB / 0.8775 GB, free: 160.4 MB / 474.7 MB
Notification: Performance of simCode: created initialization part: time 0.01473/1.831, allocations: 10.45 MB / 0.8877 GB, free: 155.3 MB / 474.7 MB
Notification: Performance of simCode: created event and clocks part: time 6.062e-06/1.831, allocations: 1.625 kB / 0.8877 GB, free: 155.3 MB / 474.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.005231/1.836, allocations: 4.112 MB / 0.8917 GB, free: 151.7 MB / 474.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00485/1.841, allocations: 0.7499 MB / 0.8924 GB, free: 151.1 MB / 474.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02933/1.87, allocations: 19.56 MB / 0.9115 GB, free: 133.2 MB / 474.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.004891/1.875, allocations: 4.842 MB / 0.9163 GB, free: 128.3 MB / 474.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.00226/1.878, allocations: 451.3 kB / 0.9167 GB, free: 127.9 MB / 474.7 MB
Notification: Performance of SimCode: time 2.354e-06/1.878, allocations: 2.516 kB / 0.9167 GB, free: 127.9 MB / 474.7 MB
Notification: Performance of Templates: time 0.4524/2.33, allocations: 152.9 MB / 1.066 GB, free: 140.8 MB / 474.7 MB
make -j1 -f Modelica_3.2.3_cpp_Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses.makefile
(rm -f Modelica_3.2.3_cpp_Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses.pipe ; mkfifo Modelica_3.2.3_cpp_Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses.pipe ; head -c 1048576 < Modelica_3.2.3_cpp_Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses.pipe >> ../files/Modelica_3.2.3_cpp_Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses.sim & ./Modelica_3.2.3_cpp_Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses  --alarm=480   -emit_protected > Modelica_3.2.3_cpp_Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses.pipe 2>&1)
diffSimulationResults("Modelica_3.2.3_cpp_Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v3.2.3+build.4/Modelica/Mechanics/MultiBody/Examples/Elementary/LineForceWithTwoMasses/LineForceWithTwoMasses.csv","../files/Modelica_3.2.3_cpp_Modelica.Mechanics.MultiBody.Examples.Elementary.LineForceWithTwoMasses.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches