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) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001747/0.001747, allocations: 85.42 kB / 20.68 MB, free: 1.035 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.002598/0.002598, allocations: 158.9 kB / 23.95 MB, free: 4.168 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.525/1.525, allocations: 177 MB / 204.1 MB, free: 9.141 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo): time 0.2178/0.2178, allocations: 34.26 MB / 294.8 MB, free: 14.51 MB / 286.1 MB
"
[Timeout remaining time 180]
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=5002,variableFilter="time|j2.s|j2.v",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR")
translateModel(ModelicaTest.MultiBody.FourbarVariants.JointSSR,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|j2.s|j2.v",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.503e-06/1.503e-06, allocations: 0 / 442.6 MB, free: 5.297 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 3.454e-05/3.604e-05, allocations: 4.953 kB / 442.6 MB, free: 5.293 MB / 366.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.FourbarVariants.JointSSR): time 0.2496/0.2497, allocations: 14.15 MB / 456.8 MB, free: 11.52 MB / 366.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01311/0.2628, allocations: 5.109 MB / 461.9 MB, free: 8.258 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004709/0.2675, allocations: 117.7 kB / 462 MB, free: 8.223 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.009707/0.2772, allocations: 4.265 MB / 466.3 MB, free: 6.426 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.01109/0.2883, allocations: 2.891 MB / 469.2 MB, free: 5.051 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004214/0.2925, allocations: 1.395 MB / 470.6 MB, free: 4.375 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.01549/0.308, allocations: 8.793 MB / 479.4 MB, free: 15.64 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0102/0.3182, allocations: 5.49 MB / 484.8 MB, free: 11.66 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.01121/0.3294, allocations: 4.14 MB / 489 MB, free: 7.508 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.008593/0.338, allocations: 4.232 MB / 493.2 MB, free: 3.266 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.002762/0.3408, allocations: 0.6055 MB / 493.8 MB, free: 2.66 MB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.005833/0.3466, allocations: 1.744 MB / 495.6 MB, free: 0.9141 MB / 382.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.006104/0.3527, allocations: 3.522 MB / 499.1 MB, free: 13.38 MB / 398.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.007214/0.3599, allocations: 2.212 MB / 0.4896 GB, free: 11.16 MB / 398.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.02312/0.383, allocations: 12.52 MB / 0.5018 GB, free: 14.61 MB / 414.1 MB
Notification: Performance of FrontEnd - DAE generated: time 8.476e-06/0.383, allocations: 0 / 0.5018 GB, free: 14.61 MB / 414.1 MB
Notification: Performance of FrontEnd: time 2.555e-06/0.3831, allocations: 0 / 0.5018 GB, free: 14.61 MB / 414.1 MB
Notification: Performance of Transformations before backend: time 0.000563/0.3836, allocations: 4 kB / 0.5018 GB, free: 14.6 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: 2564
 * Number of variables: 2564
Notification: Performance of Generate backend data structure: time 0.03024/0.4138, allocations: 8.067 MB / 0.5097 GB, free: 6.453 MB / 414.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.098e-05/0.4139, allocations: 8.031 kB / 0.5097 GB, free: 6.445 MB / 414.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01977/0.4337, allocations: 4.263 MB / 0.5138 GB, free: 2.172 MB / 414.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01279/0.4465, allocations: 4.17 MB / 0.5179 GB, free: 13.95 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005625/0.447, allocations: 390.5 kB / 0.5183 GB, free: 13.56 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.003048/0.4501, allocations: 0.5619 MB / 0.5188 GB, free: 13 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.04868/0.4987, allocations: 18.97 MB / 0.5373 GB, free: 8.969 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0003729/0.4991, allocations: 75.39 kB / 0.5374 GB, free: 8.895 MB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.002322/0.5014, allocations: 251.7 kB / 0.5377 GB, free: 8.648 MB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.006791/0.5082, allocations: 3.42 MB / 0.541 GB, free: 5.219 MB / 446.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.04442/0.5526, allocations: 16.65 MB / 0.5573 GB, free: 4.562 MB / 462.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.1163/0.669, allocations: 62.44 MB / 0.6182 GB, free: 4.379 MB / 0.5137 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01872/0.6877, allocations: 5.343 MB / 0.6235 GB, free: 15 MB / 0.5294 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.005108/0.6928, allocations: 2.651 MB / 0.626 GB, free: 12.34 MB / 0.5294 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.02928/0.7221, allocations: 10.88 MB / 0.6367 GB, free: 1.43 MB / 0.5294 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 9.32e-05/0.7222, allocations: 68.55 kB / 0.6367 GB, free: 1.355 MB / 0.5294 GB
Notification: Performance of pre-optimization done (n=284): time 7.023e-06/0.7222, allocations: 0 / 0.6367 GB, free: 1.355 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=345): time 0.4953/1.218, allocations: 26.04 MB / 0.6622 GB, free: 160.7 MB / 0.5294 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001001/1.218, allocations: 192 kB / 0.6623 GB, free: 160.5 MB / 0.5294 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008095/1.226, allocations: 5.842 MB / 0.668 GB, free: 158.6 MB / 0.5294 GB
Notification: Performance of collectPreVariables (initialization): time 0.0006167/1.226, allocations: 73.45 kB / 0.6681 GB, free: 158.5 MB / 0.5294 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002684/1.229, allocations: 5.137 MB / 0.6731 GB, free: 154.8 MB / 0.5294 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001364/1.23, allocations: 1.329 MB / 0.6744 GB, free: 153.8 MB / 0.5294 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002281/1.233, allocations: 1.89 MB / 0.6763 GB, free: 152.9 MB / 0.5294 GB
Notification: Performance of setup shared object (initialization): time 6.034e-05/1.233, allocations: 305.1 kB / 0.6766 GB, free: 152.6 MB / 0.5294 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004571/1.237, allocations: 3.384 MB / 0.6799 GB, free: 151.5 MB / 0.5294 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006629/1.244, allocations: 8.916 MB / 0.6886 GB, free: 141.4 MB / 0.5294 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0128/1.257, allocations: 13.22 MB / 0.7015 GB, free: 125.9 MB / 0.5294 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0002572/1.257, allocations: 56 kB / 0.7015 GB, free: 125.9 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=1265) (initialization): time 0.02891/1.286, allocations: 13.01 MB / 0.7142 GB, free: 112.8 MB / 0.5294 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003081/1.286, allocations: 91.94 kB / 0.7143 GB, free: 112.8 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0004311/1.287, allocations: 193.9 kB / 0.7145 GB, free: 112.6 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.004316/1.291, allocations: 1.318 MB / 0.7158 GB, free: 111.2 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007412/1.298, allocations: 2.226 MB / 0.718 GB, free: 109 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.01154/1.31, allocations: 7.608 MB / 0.7254 GB, free: 101.3 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.009225/1.319, allocations: 0.6242 MB / 0.726 GB, free: 100.7 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001923/1.321, allocations: 0.5582 MB / 0.7266 GB, free: 100.1 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.007547/1.329, allocations: 3.448 MB / 0.7299 GB, free: 96.67 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00193/1.331, allocations: 0.5006 MB / 0.7304 GB, free: 96.17 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01248/1.343, allocations: 5.344 MB / 0.7356 GB, free: 90.77 MB / 0.5294 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.147e-05/1.343, allocations: 40.36 kB / 0.7357 GB, free: 90.73 MB / 0.5294 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.041e-05/1.343, allocations: 8 kB / 0.7357 GB, free: 90.72 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.717e-05/1.343, allocations: 11.95 kB / 0.7357 GB, free: 90.71 MB / 0.5294 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0319/1.375, allocations: 11.7 MB / 0.7471 GB, free: 78.88 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.507e-05/1.375, allocations: 12.88 kB / 0.7471 GB, free: 78.87 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002409/1.378, allocations: 0.4953 MB / 0.7476 GB, free: 78.37 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.003354/1.381, allocations: 0.8163 MB / 0.7484 GB, free: 77.56 MB / 0.5294 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0005456/1.381, allocations: 76.75 kB / 0.7485 GB, free: 77.49 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.008595/1.39, allocations: 5.584 MB / 0.7539 GB, free: 71.8 MB / 0.5294 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.279e-05/1.39, allocations: 19.88 kB / 0.754 GB, free: 71.79 MB / 0.5294 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01401/1.404, allocations: 8.238 MB / 0.762 GB, free: 63.26 MB / 0.5294 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001938/1.406, allocations: 0.4965 MB / 0.7625 GB, free: 62.76 MB / 0.5294 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001631/1.408, allocations: 67.94 kB / 0.7626 GB, free: 62.69 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003476/1.411, allocations: 197.8 kB / 0.7627 GB, free: 62.5 MB / 0.5294 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0006946/1.412, allocations: 237.4 kB / 0.763 GB, free: 62.27 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0003495/1.412, allocations: 102.6 kB / 0.7631 GB, free: 62.17 MB / 0.5294 GB
Notification: Performance of sorting global known variables: time 0.006241/1.418, allocations: 3.405 MB / 0.7664 GB, free: 58.78 MB / 0.5294 GB
Notification: Performance of sort global known variables: time 9.1e-08/1.418, allocations: 0 / 0.7664 GB, free: 58.78 MB / 0.5294 GB
Notification: Performance of remove unused functions: time 0.01047/1.429, allocations: 1.746 MB / 0.7681 GB, free: 57.03 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.001289/1.43, allocations: 291.3 kB / 0.7684 GB, free: 56.75 MB / 0.5294 GB
Notification: Performance of simCode: created initialization part: time 0.01367/1.444, allocations: 7.607 MB / 0.7758 GB, free: 49.15 MB / 0.5294 GB
Notification: Performance of simCode: created event and clocks part: time 2.059e-05/1.444, allocations: 0 / 0.7758 GB, free: 49.15 MB / 0.5294 GB
Notification: Performance of simCode: created simulation system equations: time 0.004248/1.448, allocations: 2.215 MB / 0.778 GB, free: 46.93 MB / 0.5294 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.006794/1.455, allocations: 0.5889 MB / 0.7785 GB, free: 46.34 MB / 0.5294 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02853/1.483, allocations: 15.46 MB / 0.7936 GB, free: 30.7 MB / 0.5294 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.008807/1.492, allocations: 4.684 MB / 0.7982 GB, free: 25.96 MB / 0.5294 GB
Notification: Performance of simCode: alias equations: time 0.007254/1.499, allocations: 2.916 MB / 0.8011 GB, free: 23.04 MB / 0.5294 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001854/1.501, allocations: 372.4 kB / 0.8014 GB, free: 22.68 MB / 0.5294 GB
Notification: Performance of SimCode: time 1.603e-06/1.501, allocations: 0 / 0.8014 GB, free: 22.68 MB / 0.5294 GB
Notification: Performance of Templates: time 0.3098/1.811, allocations: 50.64 MB / 0.8509 GB, free: 176 MB / 0.5607 GB
"
[Timeout remaining time 658]
make -j1 -f ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR.makefile [Timeout 660]
(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) [Timeout 480]
diffSimulationResults("ModelicaTest_trunk_ModelicaTest.MultiBody.FourbarVariants.JointSSR_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.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) [Timeout 660]
""
[Timeout remaining time 660]
Reference file matches
[Calling sys.exit(0), Time elapsed: 8.840448137372732]