Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_trunk_ModelicaTest.MultiBody.Forces.Spring.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo", uses=false)
Using package ModelicaTest with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo)
Using package ModelicaServices with version trunk (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 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)
Running command: translateModel(ModelicaTest.MultiBody.Forces.Spring,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|prismatic.s|prismatic.v|prismatic1.s|prismatic1.v",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.MultiBody.Forces.Spring")
translateModel(ModelicaTest.MultiBody.Forces.Spring,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|prismatic.s|prismatic.v|prismatic1.s|prismatic1.v",fileNamePrefix="ModelicaTest_trunk_ModelicaTest.MultiBody.Forces.Spring")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001583/0.001583, allocations: 207.3 kB / 17.99 MB, free: 3.773 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.389/1.389, allocations: 226.7 MB / 245.5 MB, free: 6.984 MB / 202.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001122/0.001122, allocations: 105.3 kB / 296.7 MB, free: 6.484 MB / 234.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest trunk/package.mo): time 0.4045/0.4045, allocations: 44.84 MB / 392.7 MB, free: 34.77 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.001183/0.001183, allocations: 63.89 kB / 465.5 MB, free: 12.1 MB / 346.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3967/0.3979, allocations: 62.01 MB / 0.5151 GB, free: 37.94 MB / 378.7 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Forces.Spring): time 0.0346/0.4326, allocations: 42.86 MB / 0.557 GB, free: 7.258 MB / 378.7 MB
Notification: Performance of NFInst.instExpressions: time 0.02459/0.4572, allocations: 9.271 MB / 0.5661 GB, free: 1.082 MB / 378.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01291/0.4702, allocations: 181.3 kB / 0.5662 GB, free: 1.055 MB / 378.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.005689/0.4759, allocations: 2.455 MB / 0.5686 GB, free: 15.92 MB / 394.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.007419/0.4834, allocations: 1.734 MB / 0.5703 GB, free: 15.17 MB / 394.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002586/0.486, allocations: 0.7221 MB / 0.571 GB, free: 14.7 MB / 394.7 MB
Notification: Performance of NFFlatten.flatten: time 0.009298/0.4953, allocations: 6.26 MB / 0.5771 GB, free: 9.969 MB / 394.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.004559/0.4999, allocations: 3.299 MB / 0.5804 GB, free: 6.629 MB / 394.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.00277/0.5027, allocations: 1.39 MB / 0.5817 GB, free: 5.234 MB / 394.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003251/0.506, allocations: 2.375 MB / 0.584 GB, free: 2.852 MB / 394.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0006815/0.5067, allocations: 243.9 kB / 0.5843 GB, free: 2.613 MB / 394.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.00217/0.5088, allocations: 0.9128 MB / 0.5852 GB, free: 1.699 MB / 394.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.001953/0.5108, allocations: 1.87 MB / 0.587 GB, free: 15.82 MB / 410.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.002618/0.5134, allocations: 2.182 MB / 0.5891 GB, free: 13.63 MB / 410.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.007791/0.5212, allocations: 6.545 MB / 0.5955 GB, free: 7.062 MB / 410.7 MB
Notification: Performance of FrontEnd - DAE generated: time 6.071e-06/0.5213, allocations: 4 kB / 0.5955 GB, free: 7.059 MB / 410.7 MB
Notification: Performance of FrontEnd: time 1.172e-06/0.5213, allocations: 0 / 0.5955 GB, free: 7.059 MB / 410.7 MB
Notification: Performance of Transformations before backend: time 0.0002434/0.5215, allocations: 0 / 0.5955 GB, free: 7.059 MB / 410.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1535
 * Number of variables: 1535
Notification: Performance of Generate backend data structure: time 0.01127/0.5328, allocations: 4.819 MB / 0.6002 GB, free: 2.168 MB / 410.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.76e-05/0.5329, allocations: 11.97 kB / 0.6002 GB, free: 2.156 MB / 410.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.007383/0.5403, allocations: 1.945 MB / 0.6021 GB, free: 208 kB / 410.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.006131/0.5464, allocations: 2.282 MB / 0.6044 GB, free: 13.86 MB / 426.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002467/0.5467, allocations: 262.4 kB / 0.6046 GB, free: 13.6 MB / 426.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0008585/0.5476, allocations: 331.7 kB / 0.6049 GB, free: 13.28 MB / 426.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01898/0.5666, allocations: 10.2 MB / 0.6149 GB, free: 2.293 MB / 426.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001279/0.5667, allocations: 43.59 kB / 0.6149 GB, free: 2.25 MB / 426.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004591/0.5672, allocations: 163.8 kB / 0.6151 GB, free: 2.09 MB / 426.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.002906/0.5701, allocations: 1.904 MB / 0.6169 GB, free: 188 kB / 426.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01866/0.5888, allocations: 8.146 MB / 0.6249 GB, free: 8.012 MB / 442.7 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.06052/0.6494, allocations: 39.43 MB / 0.6634 GB, free: 15.4 MB / 490.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.007747/0.6572, allocations: 3.268 MB / 0.6666 GB, free: 11.97 MB / 490.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001732/0.6589, allocations: 1.053 MB / 0.6676 GB, free: 10.9 MB / 490.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.009508/0.6685, allocations: 5.917 MB / 0.6734 GB, free: 4.613 MB / 490.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.98e-05/0.6685, allocations: 55.5 kB / 0.6735 GB, free: 4.551 MB / 490.7 MB
Notification: Performance of pre-optimization done (n=148): time 4.148e-06/0.6685, allocations: 0 / 0.6735 GB, free: 4.551 MB / 490.7 MB
Notification: Performance of matching and sorting (n=160): time 0.3258/0.9943, allocations: 9.432 MB / 0.6827 GB, free: 130.2 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.335e-05/0.9945, allocations: 144.8 kB / 0.6828 GB, free: 130.1 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005242/0.9997, allocations: 4.054 MB / 0.6868 GB, free: 128.6 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001951/0.9999, allocations: 47.52 kB / 0.6868 GB, free: 128.5 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001566/1.001, allocations: 3.179 MB / 0.6899 GB, free: 126.1 MB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0004816/1.002, allocations: 0.6333 MB / 0.6905 GB, free: 125.6 MB / 490.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001179/1.003, allocations: 1.212 MB / 0.6917 GB, free: 125 MB / 490.7 MB
Notification: Performance of setup shared object (initialization): time 4.962e-05/1.003, allocations: 307.7 kB / 0.692 GB, free: 124.7 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002115/1.005, allocations: 1.652 MB / 0.6936 GB, free: 124.2 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003733/1.009, allocations: 5.517 MB / 0.699 GB, free: 118.5 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.007426/1.017, allocations: 7.783 MB / 0.7066 GB, free: 111.4 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001502/1.017, allocations: 43.5 kB / 0.7067 GB, free: 111.4 MB / 490.7 MB
Notification: Performance of matching and sorting (n=799) (initialization): time 0.01334/1.03, allocations: 6.9 MB / 0.7134 GB, free: 108.8 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001112/1.03, allocations: 66.56 kB / 0.7135 GB, free: 108.8 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002507/1.03, allocations: 139.2 kB / 0.7136 GB, free: 108.8 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0002771/1.031, allocations: 184.7 kB / 0.7138 GB, free: 108.8 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004822/1.036, allocations: 1.434 MB / 0.7152 GB, free: 108.6 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0003317/1.036, allocations: 269.1 kB / 0.7154 GB, free: 108.5 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005013/1.041, allocations: 299.4 kB / 0.7157 GB, free: 108.4 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008634/1.042, allocations: 376.4 kB / 0.7161 GB, free: 108.4 MB / 490.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 625
 * Number of states: 0 ()
 * Number of discrete variables: 39 (prismatic1.boxColor[3],prismatic1.boxColor[2],prismatic1.boxColor[1],prismatic.boxColor[3],prismatic.boxColor[2],prismatic.boxColor[1],body1.sphereColor[3],body1.sphereColor[2],body1.sphereColor[1],body.sphereColor[3],body.sphereColor[2],body.sphereColor[1],spring1.color[3],spring1.color[2],spring1.color[1],spring1.massColor[3],spring1.massColor[2],spring1.massColor[1],spring.color[3],spring.color[2],spring.color[1],spring.massColor[3],spring.massColor[2],spring.massColor[1],fixedTranslation.color[3],fixedTranslation.color[2],fixedTranslation.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])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (775):
 * Single equations (assignments): 772
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.004198/1.046, allocations: 2.037 MB / 0.7181 GB, free: 107.4 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008765/1.047, allocations: 350.9 kB / 0.7184 GB, free: 107.2 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002565/1.049, allocations: 1.333 MB / 0.7197 GB, free: 106.6 MB / 490.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.588e-05/1.05, allocations: 19.44 kB / 0.7197 GB, free: 106.6 MB / 490.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.37e-05/1.05, allocations: 7.969 kB / 0.7197 GB, free: 106.6 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.66e-05/1.05, allocations: 5.531 kB / 0.7197 GB, free: 106.6 MB / 490.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01045/1.06, allocations: 5.831 MB / 0.7254 GB, free: 104 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.673e-05/1.06, allocations: 17 kB / 0.7254 GB, free: 104 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0009346/1.061, allocations: 282 kB / 0.7257 GB, free: 104 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 2.686e-05/1.061, allocations: 27.78 kB / 0.7257 GB, free: 104 MB / 490.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 7.787e-05/1.061, allocations: 52.3 kB / 0.7258 GB, free: 103.9 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 2.978e-05/1.061, allocations: 36.86 kB / 0.7258 GB, free: 103.9 MB / 490.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.253e-05/1.061, allocations: 30.75 kB / 0.7258 GB, free: 103.9 MB / 490.7 MB
Error: The model contains alias variables with conflicting fixed start values. It is necessary to resolve the conflicts, because otherwise the initial system is impossible to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01061/1.072, allocations: 8.059 MB / 0.7337 GB, free: 98.45 MB / 490.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006448/1.073, allocations: 415.3 kB / 0.7341 GB, free: 98.03 MB / 490.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.000277/1.073, allocations: 47.94 kB / 0.7342 GB, free: 97.99 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001024/1.074, allocations: 63.11 kB / 0.7342 GB, free: 97.94 MB / 490.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000178/1.074, allocations: 149.4 kB / 0.7344 GB, free: 97.82 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.289e-05/1.074, allocations: 71.8 kB / 0.7344 GB, free: 97.75 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.003167/1.077, allocations: 2.506 MB / 0.7369 GB, free: 95.66 MB / 490.7 MB
Notification: Performance of sort global known variables: time 2.4e-07/1.077, allocations: 0.75 kB / 0.7369 GB, free: 95.66 MB / 490.7 MB
Notification: Performance of remove unused functions: time 0.006582/1.084, allocations: 1.143 MB / 0.738 GB, free: 94.53 MB / 490.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 78
 * Number of states: 4 (prismatic.s,prismatic.v,prismatic1.s,prismatic1.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 (145):
 * Single equations (assignments): 142
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.0004978/1.084, allocations: 203.6 kB / 0.7382 GB, free: 94.33 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.005774/1.09, allocations: 3.776 MB / 0.7419 GB, free: 91 MB / 490.7 MB
Notification: Performance of simCode: created event and clocks part: time 1.314e-05/1.09, allocations: 12.31 kB / 0.7419 GB, free: 90.99 MB / 490.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.0008702/1.091, allocations: 0.7492 MB / 0.7426 GB, free: 90.32 MB / 490.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002877/1.094, allocations: 449.2 kB / 0.743 GB, free: 89.93 MB / 490.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01497/1.109, allocations: 11.75 MB / 0.7545 GB, free: 78.67 MB / 490.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002823/1.112, allocations: 3.224 MB / 0.7577 GB, free: 75.42 MB / 490.7 MB
Notification: Performance of simCode: alias equations: time 0.004135/1.116, allocations: 1.82 MB / 0.7594 GB, free: 73.59 MB / 490.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0006235/1.117, allocations: 243.4 kB / 0.7597 GB, free: 73.36 MB / 490.7 MB
Notification: Performance of SimCode: time 8.11e-07/1.117, allocations: 0 / 0.7597 GB, free: 73.36 MB / 490.7 MB
Notification: Performance of Templates: time 0.04805/1.165, allocations: 29.21 MB / 0.7882 GB, free: 44.32 MB / 490.7 MB
make -j1 -f ModelicaTest_trunk_ModelicaTest.MultiBody.Forces.Spring.makefile
(rm -f ModelicaTest_trunk_ModelicaTest.MultiBody.Forces.Spring.pipe ; mkfifo ModelicaTest_trunk_ModelicaTest.MultiBody.Forces.Spring.pipe ; head -c 1048576 < ModelicaTest_trunk_ModelicaTest.MultiBody.Forces.Spring.pipe >> ../files/ModelicaTest_trunk_ModelicaTest.MultiBody.Forces.Spring.sim & ./ModelicaTest_trunk_ModelicaTest.MultiBody.Forces.Spring  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_trunk_ModelicaTest.MultiBody.Forces.Spring.pipe 2>&1)
diffSimulationResults("ModelicaTest_trunk_ModelicaTest.MultiBody.Forces.Spring_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork@2/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.0.0/ModelicaTest/MultiBody/Forces/Spring/Spring.csv","../files/ModelicaTest_trunk_ModelicaTest.MultiBody.Forces.Spring.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001)
Reference file matches