Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001902/0.001902, allocations: 106.5 kB / 21.2 MB, free: 0.6406 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.002298/0.002298, allocations: 212.6 kB / 24.49 MB, free: 3.934 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.443/1.443, allocations: 230.6 MB / 258.3 MB, free: 7.742 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+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/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_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_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.001581/0.001581, allocations: 55.92 kB / 379 MB, free: 2.449 MB / 302.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2042/0.2058, allocations: 53.37 MB / 432.3 MB, free: 6.703 MB / 334.1 MB
Notification: Performance of NFInst.instantiate(Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint): time 0.02036/0.2262, allocations: 16.69 MB / 449 MB, free: 5.945 MB / 350.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01228/0.2384, allocations: 6.015 MB / 455 MB, free: 15.91 MB / 366.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002216/0.2407, allocations: 175.1 kB / 455.2 MB, free: 15.74 MB / 366.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.01137/0.252, allocations: 6.001 MB / 461.2 MB, free: 9.723 MB / 366.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006687/0.2587, allocations: 2.684 MB / 463.9 MB, free: 7.027 MB / 366.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.005015/0.2637, allocations: 2.502 MB / 466.4 MB, free: 4.52 MB / 366.1 MB
Notification: Performance of NFFlatten.flatten: time 0.008655/0.2724, allocations: 6.855 MB / 473.3 MB, free: 13.65 MB / 382.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.007481/0.2799, allocations: 5.979 MB / 479.2 MB, free: 7.566 MB / 382.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.004469/0.2843, allocations: 2.902 MB / 482.1 MB, free: 4.656 MB / 382.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.005939/0.2903, allocations: 3.953 MB / 486.1 MB, free: 0.6914 MB / 382.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001419/0.2917, allocations: 0.6602 MB / 486.8 MB, free: 32 kB / 382.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003543/0.2952, allocations: 1.865 MB / 488.6 MB, free: 14.16 MB / 398.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.00343/0.2987, allocations: 3.499 MB / 492.1 MB, free: 10.66 MB / 398.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.003606/0.3023, allocations: 2.933 MB / 495.1 MB, free: 7.707 MB / 398.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01386/0.3161, allocations: 12.48 MB / 0.4956 GB, free: 11.18 MB / 414.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.37e-06/0.3161, allocations: 0 / 0.4956 GB, free: 11.18 MB / 414.1 MB
Notification: Performance of FrontEnd: time 1.483e-06/0.3161, allocations: 3.938 kB / 0.4956 GB, free: 11.18 MB / 414.1 MB
Notification: Performance of Transformations before backend: time 0.0002763/0.3164, allocations: 0 / 0.4956 GB, free: 11.18 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.01703/0.3334, allocations: 8.255 MB / 0.5037 GB, free: 2.844 MB / 414.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.549e-05/0.3335, allocations: 8.031 kB / 0.5037 GB, free: 2.836 MB / 414.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.009275/0.3428, allocations: 2.648 MB / 0.5063 GB, free: 184 kB / 414.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01014/0.3529, allocations: 4.106 MB / 0.5103 GB, free: 11.99 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003947/0.3533, allocations: 455.3 kB / 0.5107 GB, free: 11.54 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001885/0.3552, allocations: 0.6637 MB / 0.5114 GB, free: 10.88 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.03287/0.3881, allocations: 17.36 MB / 0.5283 GB, free: 8.594 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001966/0.3883, allocations: 67.47 kB / 0.5284 GB, free: 8.527 MB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00072/0.389, allocations: 267.8 kB / 0.5287 GB, free: 8.266 MB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004206/0.3932, allocations: 2.784 MB / 0.5314 GB, free: 5.477 MB / 446.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.3538/0.747, allocations: 14.34 MB / 0.5454 GB, free: 90.84 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.05619/0.8032, allocations: 59.16 MB / 0.6032 GB, free: 35.93 MB / 446.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.02023/0.8234, allocations: 7.025 MB / 0.61 GB, free: 28.89 MB / 446.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003205/0.8266, allocations: 3.236 MB / 0.6132 GB, free: 25.64 MB / 446.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.01701/0.8436, allocations: 11.97 MB / 0.6249 GB, free: 13.88 MB / 446.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.916e-05/0.8436, allocations: 56.56 kB / 0.6249 GB, free: 13.81 MB / 446.1 MB
Notification: Performance of pre-optimization done (n=247): time 7.203e-06/0.8436, allocations: 4 kB / 0.6249 GB, free: 13.81 MB / 446.1 MB
Notification: Performance of matching and sorting (n=293): time 0.197/1.041, allocations: 53.82 MB / 0.6775 GB, free: 8.27 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001089/1.041, allocations: 162.5 kB / 0.6776 GB, free: 8.078 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.007599/1.048, allocations: 5.399 MB / 0.6829 GB, free: 2.801 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0005923/1.049, allocations: 65.7 kB / 0.683 GB, free: 2.73 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.004167/1.053, allocations: 4.986 MB / 0.6879 GB, free: 13.73 MB / 0.4982 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001434/1.055, allocations: 1.167 MB / 0.689 GB, free: 12.56 MB / 0.4982 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002886/1.057, allocations: 1.766 MB / 0.6907 GB, free: 10.78 MB / 0.4982 GB
Notification: Performance of setup shared object (initialization): time 0.0001274/1.058, allocations: 304.9 kB / 0.691 GB, free: 10.48 MB / 0.4982 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.006383/1.064, allocations: 3.999 MB / 0.6949 GB, free: 6.473 MB / 0.4982 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01147/1.075, allocations: 9.095 MB / 0.7038 GB, free: 10.86 MB / 0.5138 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01912/1.095, allocations: 14.04 MB / 0.7175 GB, free: 10.3 MB / 0.5294 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001505/1.095, allocations: 52 kB / 0.7176 GB, free: 10.25 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=1137) (initialization): time 0.07836/1.173, allocations: 23.83 MB / 0.7408 GB, free: 2.379 MB / 0.545 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001159/1.173, allocations: 84 kB / 0.7409 GB, free: 2.297 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001884/1.173, allocations: 176 kB / 0.7411 GB, free: 2.125 MB / 0.545 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.2001/1.374, allocations: 5.375 MB / 0.7463 GB, free: 181.3 MB / 0.545 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.006476/1.38, allocations: 1.987 MB / 0.7483 GB, free: 181.3 MB / 0.545 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04031/1.42, allocations: 34.85 MB / 0.7823 GB, free: 163.6 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006749/1.427, allocations: 0.5963 MB / 0.7829 GB, free: 163.2 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007919/1.428, allocations: 0.5038 MB / 0.7834 GB, free: 162.7 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.003779/1.432, allocations: 3.224 MB / 0.7865 GB, free: 159.9 MB / 0.545 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008781/1.432, allocations: 497.5 kB / 0.787 GB, free: 159.7 MB / 0.545 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0556/1.488, allocations: 17.18 MB / 0.8038 GB, free: 145.6 MB / 0.545 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.399e-05/1.488, allocations: 11.95 kB / 0.8038 GB, free: 145.6 MB / 0.545 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.965e-06/1.488, allocations: 0 / 0.8038 GB, free: 145.6 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001009/1.489, allocations: 447.7 kB / 0.8042 GB, free: 145.4 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0008839/1.49, allocations: 54.44 kB / 0.8043 GB, free: 145.4 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002846/1.493, allocations: 295.5 kB / 0.8045 GB, free: 145.1 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001083/1.494, allocations: 240.3 kB / 0.8048 GB, free: 144.8 MB / 0.545 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.01489/1.509, allocations: 9.522 MB / 0.8141 GB, free: 135.4 MB / 0.545 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.01001/1.519, allocations: 9.716 MB / 0.8236 GB, free: 125.9 MB / 0.545 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002883/1.522, allocations: 3.047 MB / 0.8265 GB, free: 122.9 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.003293/1.525, allocations: 3.106 MB / 0.8296 GB, free: 119.8 MB / 0.545 GB
Notification: Performance of Backend: time 4.01e-07/1.525, allocations: 3.938 kB / 0.8296 GB, free: 119.8 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.01556/1.541, allocations: 8.281 MB / 0.8377 GB, free: 111.6 MB / 0.545 GB
Notification: Performance of SimCode: time 0.01733/1.558, allocations: 15.18 MB / 0.8525 GB, free: 96.46 MB / 0.545 GB
Notification: Performance of Templates: time 0.3034/1.861, allocations: 102.7 MB / 0.9527 GB, free: 184.3 MB / 0.545 GB
"
[Timeout remaining time 658]
make -j1 -f Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.makefile [Timeout 660]
(rm -f Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.pipe ; mkfifo Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.pipe ; head -c 1048576 < Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.pipe >> ../files/Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.sim & ./Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint.pipe 2>&1) [Timeout 480]
diffSimulationResults("Modelica_4.1.0_Modelica.Mechanics.MultiBody.Examples.Constraints.PrismaticConstraint_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/Modelica/Mechanics/MultiBody/Examples/Constraints/PrismaticConstraint/PrismaticConstraint.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Modelica_4.1.0_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.46095222900476]