Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.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.001788/0.001788, allocations: 110.5 kB / 21.18 MB, free: 0.7617 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.002119/0.002119, allocations: 215.8 kB / 24.48 MB, free: 4.051 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/package.mo): time 1.436/1.436, allocations: 230.4 MB / 258.1 MB, free: 8.133 MB / 206.1 MB
"
[Timeout remaining time 178]
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0-beta.om/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(Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|bodyOfConstraint.r_0.1.|bodyOfConstraint.r_0.2.|bodyOfConstraint.r_0.3.|bodyOfConstraint.v_0.1.|bodyOfConstraint.v_0.2.|bodyOfConstraint.v_0.3.|jointPrismatic_x.s|jointPrismatic_x.v|jointPrismatic_y.s|jointPrismatic_y.v",fileNamePrefix="Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint")
translateModel(Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|bodyOfConstraint.r_0.1.|bodyOfConstraint.r_0.2.|bodyOfConstraint.r_0.3.|bodyOfConstraint.v_0.1.|bodyOfConstraint.v_0.2.|bodyOfConstraint.v_0.3.|jointPrismatic_x.s|jointPrismatic_x.v|jointPrismatic_y.s|jointPrismatic_y.v",fileNamePrefix="Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint") [Timeout 660]
"Warning: Requested package Modelica of version trunk, but this package was already loaded with version 4.1.0. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Warning: Requested package Complex of version 4.1.0, but this package was already loaded with version trunk. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Warning: Requested package ModelicaServices of version 4.1.0, but this package was already loaded with version trunk. OpenModelica cannot reason about compatibility between the two packages since they are not semantic versions.
Notification: Performance of FrontEnd - loaded program: time 0.001955/0.001955, allocations: 59.84 kB / 378.7 MB, free: 2.891 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2105/0.2124, allocations: 53.32 MB / 432.1 MB, free: 7.383 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint): time 0.02272/0.2352, allocations: 19.39 MB / 451.5 MB, free: 3.91 MB / 350.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01208/0.2472, allocations: 6.303 MB / 457.8 MB, free: 13.59 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002332/0.2496, allocations: 151.5 kB / 457.9 MB, free: 13.45 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01149/0.2611, allocations: 6.265 MB / 464.2 MB, free: 7.16 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.005885/0.2669, allocations: 2.673 MB / 466.8 MB, free: 4.477 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004912/0.2719, allocations: 2.572 MB / 469.4 MB, free: 1.898 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.008491/0.2803, allocations: 6.873 MB / 476.3 MB, free: 11.01 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.006486/0.2868, allocations: 5.644 MB / 481.9 MB, free: 5.266 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003622/0.2905, allocations: 2.863 MB / 484.8 MB, free: 2.395 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.005473/0.2959, allocations: 3.945 MB / 488.7 MB, free: 14.44 MB / 398.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001098/0.297, allocations: 0.6679 MB / 489.4 MB, free: 13.77 MB / 398.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0035/0.3005, allocations: 2.005 MB / 491.4 MB, free: 11.76 MB / 398.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.003433/0.304, allocations: 3.499 MB / 494.9 MB, free: 8.254 MB / 398.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.003983/0.3079, allocations: 3.873 MB / 498.8 MB, free: 4.359 MB / 398.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01329/0.3212, allocations: 12.43 MB / 0.4992 GB, free: 7.883 MB / 414.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.037e-06/0.3212, allocations: 0 / 0.4992 GB, free: 7.883 MB / 414.1 MB
Notification: Performance of FrontEnd: time 1.303e-06/0.3212, allocations: 0 / 0.4992 GB, free: 7.883 MB / 414.1 MB
Notification: Performance of Transformations before backend: time 0.0001528/0.3214, allocations: 0 / 0.4992 GB, free: 7.883 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: 2429
 * Number of variables: 2429
Notification: Performance of Generate backend data structure: time 0.0148/0.3362, allocations: 8.327 MB / 0.5074 GB, free: 15.46 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.461e-05/0.3362, allocations: 11.97 kB / 0.5074 GB, free: 15.45 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00898/0.3452, allocations: 2.656 MB / 0.51 GB, free: 12.79 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.008721/0.3539, allocations: 4.163 MB / 0.514 GB, free: 8.543 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003467/0.3543, allocations: 447.4 kB / 0.5145 GB, free: 8.105 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001366/0.3557, allocations: 0.6792 MB / 0.5151 GB, free: 7.426 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03213/0.3878, allocations: 17.31 MB / 0.532 GB, free: 5.207 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001737/0.388, allocations: 63.45 kB / 0.5321 GB, free: 5.145 MB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007112/0.3887, allocations: 271.8 kB / 0.5323 GB, free: 4.879 MB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004373/0.393, allocations: 2.776 MB / 0.5351 GB, free: 2.102 MB / 446.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.35/0.7431, allocations: 14.37 MB / 0.5491 GB, free: 89.88 MB / 446.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.05602/0.7991, allocations: 63.99 MB / 0.6116 GB, free: 28.35 MB / 446.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.02095/0.82, allocations: 7.48 MB / 0.6189 GB, free: 20.73 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002984/0.823, allocations: 3.232 MB / 0.622 GB, free: 17.5 MB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.01691/0.8399, allocations: 14.73 MB / 0.6364 GB, free: 2.441 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.799e-05/0.8399, allocations: 64.5 kB / 0.6365 GB, free: 2.371 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=247): time 4.809e-06/0.8399, allocations: 0 / 0.6365 GB, free: 2.371 MB / 446.1 MB
Notification: Performance of matching and sorting (n=293): time 0.2058/1.046, allocations: 54.4 MB / 0.6896 GB, free: 12.11 MB / 0.4982 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001048/1.046, allocations: 162.5 kB / 0.6898 GB, free: 11.92 MB / 0.4982 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.008208/1.054, allocations: 5.671 MB / 0.6953 GB, free: 6.355 MB / 0.4982 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005367/1.055, allocations: 73.69 kB / 0.6954 GB, free: 6.277 MB / 0.4982 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003815/1.058, allocations: 4.964 MB / 0.7002 GB, free: 1.289 MB / 0.4982 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001343/1.06, allocations: 1.163 MB / 0.7014 GB, free: 120 kB / 0.4982 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0026/1.062, allocations: 1.766 MB / 0.7031 GB, free: 14.34 MB / 0.5138 GB
Notification: Performance of setup shared object (initialization): time 0.0001261/1.063, allocations: 301.1 kB / 0.7034 GB, free: 14.04 MB / 0.5138 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006279/1.069, allocations: 4.003 MB / 0.7073 GB, free: 10.03 MB / 0.5138 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01025/1.079, allocations: 9.091 MB / 0.7162 GB, free: 14.41 MB / 0.5294 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01742/1.096, allocations: 14.04 MB / 0.7299 GB, free: 13.87 MB / 0.545 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.562e-05/1.097, allocations: 52 kB / 0.7299 GB, free: 13.82 MB / 0.545 GB
Notification: Performance of matching and sorting (n=1137) (initialization): time 0.2613/1.358, allocations: 24.01 MB / 0.7534 GB, free: 177.2 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 8.213e-05/1.358, allocations: 81.5 kB / 0.7534 GB, free: 177.2 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001214/1.358, allocations: 179.3 kB / 0.7536 GB, free: 177.2 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.01497/1.373, allocations: 5.377 MB / 0.7589 GB, free: 177 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006162/1.379, allocations: 1.985 MB / 0.7608 GB, free: 176.9 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04133/1.421, allocations: 36.06 MB / 0.796 GB, free: 153.4 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006742/1.427, allocations: 0.5948 MB / 0.7966 GB, free: 152.9 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005805/1.428, allocations: 0.5039 MB / 0.7971 GB, free: 152.3 MB / 0.545 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 817
 * Number of states: 0 ()
 * Number of discrete variables: 67 (freeMotionScalarInit.arrow.arrow.twoHeadedArrow,freeMotionScalarInit.arrow.headAtOrigin,sensorConstraintRelative.arrow.twoHeadedArrow,sensorConstraintRelative.arrow.headAtOrigin,freeMotionScalarInit.arrowColor[3],freeMotionScalarInit.arrowColor[2],freeMotionScalarInit.arrowColor[1],fixedTranslation.color[3],fixedTranslation.color[2],fixedTranslation.color[1],fixedRotation.color[3],fixedRotation.color[2],fixedRotation.color[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],springOfConstraint.color[3],springOfConstraint.color[2],springOfConstraint.color[1],springOfConstraint.massColor[3],springOfConstraint.massColor[2],springOfConstraint.massColor[1],springOfJoint.color[3],springOfJoint.color[2],springOfJoint.color[1],springOfJoint.massColor[3],springOfJoint.massColor[2],springOfJoint.massColor[1],bodyOfConstraint.color[3],bodyOfConstraint.color[2],bodyOfConstraint.color[1],bodyOfConstraint.body.sphereColor[3],bodyOfConstraint.body.sphereColor[2],bodyOfConstraint.body.sphereColor[1],bodyOfConstraint.frameTranslation.color[3],bodyOfConstraint.frameTranslation.color[2],bodyOfConstraint.frameTranslation.color[1],bodyOfJoint.color[3],bodyOfJoint.color[2],bodyOfJoint.color[1],bodyOfJoint.body.sphereColor[3],bodyOfJoint.body.sphereColor[2],bodyOfJoint.body.sphereColor[1],bodyOfJoint.frameTranslation.color[3],bodyOfJoint.frameTranslation.color[2],bodyOfJoint.frameTranslation.color[1],sensorConstraintRelative.arrowColor[3],sensorConstraintRelative.arrowColor[2],sensorConstraintRelative.arrowColor[1],constraint.sphereColor[3],constraint.sphereColor[2],constraint.sphereColor[1],jointPrismatic_y.boxColor[3],jointPrismatic_y.boxColor[2],jointPrismatic_y.boxColor[1],jointPrismatic_x.boxColor[3],jointPrismatic_x.boxColor[2],jointPrismatic_x.boxColor[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1044):
 * Single equations (assignments): 1031
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 7
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 6 systems
   {(5,4,60.0%), (1,3,100.0%), (1,3,100.0%), (4,9,100.0%), (4,9,100.0%), (2,6,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(4,9)}
Notification: Performance of prepare postOptimizeDAE: time 0.003707/1.432, allocations: 3.222 MB / 0.8002 GB, free: 149.3 MB / 0.545 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0007087/1.432, allocations: 0.5365 MB / 0.8008 GB, free: 149.1 MB / 0.545 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.05893/1.491, allocations: 17.36 MB / 0.8177 GB, free: 131.6 MB / 0.545 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.096e-05/1.491, allocations: 15.94 kB / 0.8177 GB, free: 131.6 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.103e-06/1.491, allocations: 0 / 0.8177 GB, free: 131.6 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.00117/1.492, allocations: 0.5593 MB / 0.8183 GB, free: 131.4 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0009504/1.493, allocations: 51.94 kB / 0.8183 GB, free: 131.3 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003118/1.497, allocations: 299.1 kB / 0.8186 GB, free: 131 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001008/1.498, allocations: 225.8 kB / 0.8188 GB, free: 130.8 MB / 0.545 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01595/1.513, allocations: 9.559 MB / 0.8282 GB, free: 121.3 MB / 0.545 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01032/1.524, allocations: 9.751 MB / 0.8377 GB, free: 111.8 MB / 0.545 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.003084/1.527, allocations: 3.049 MB / 0.8407 GB, free: 108.7 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.003896/1.531, allocations: 3.503 MB / 0.8441 GB, free: 105.2 MB / 0.545 GB
Notification: Performance of Backend: time 2.91e-07/1.531, allocations: 0 / 0.8441 GB, free: 105.2 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.01666/1.547, allocations: 8.277 MB / 0.8522 GB, free: 96.97 MB / 0.545 GB
Notification: Performance of SimCode: time 0.01964/1.567, allocations: 15.35 MB / 0.8672 GB, free: 81.63 MB / 0.545 GB
Notification: Performance of Templates: time 0.3066/1.874, allocations: 98.15 MB / 0.963 GB, free: 181.4 MB / 0.545 GB
"
[Timeout remaining time 658]
make -j1 -f Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.makefile [Timeout 660]
(rm -f Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.pipe ; mkfifo Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.pipe ; head -c 1048576 < Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.pipe >> ../files/Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.sim & ./Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.pipe 2>&1) [Timeout 480]
diffSimulationResults("Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/Modelica/Mechanics/MultiBody/Examples/Constraints/PrismaticConstraint/PrismaticConstraint.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/OpenModelicaLibraryTesting/files/Modelica_4.1.0-beta.om_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.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.308453001081944]