Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3.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.001318/0.001318, allocations: 114.5 kB / 21.18 MB, free: 0.7422 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.00145/0.00145, allocations: 212.1 kB / 24.48 MB, free: 4.191 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.416/1.416, allocations: 230.2 MB / 257.8 MB, free: 8.488 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo): time 0.2271/0.2271, allocations: 46.89 MB / 361.1 MB, free: 4.621 MB / 286.1 MB
"
[Timeout remaining time 180]
Using package ModelicaTest with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/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.Joints.FreeMotionScalarInit3,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|body.w_a.1.|body.w_a.2.|body.w_a.3.|freeMotionScalarInit.angle_1|freeMotionScalarInit.angle_2|freeMotionScalarInit.angle_3|freeMotionScalarInit.r_rel_a_1|freeMotionScalarInit.r_rel_a_2|freeMotionScalarInit.r_rel_a_3|freeMotionScalarInit.v_rel_a_1|freeMotionScalarInit.v_rel_a_2|freeMotionScalarInit.v_rel_a_3",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3")
translateModel(ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|body.w_a.1.|body.w_a.2.|body.w_a.3.|freeMotionScalarInit.angle_1|freeMotionScalarInit.angle_2|freeMotionScalarInit.angle_3|freeMotionScalarInit.r_rel_a_1|freeMotionScalarInit.r_rel_a_2|freeMotionScalarInit.r_rel_a_3|freeMotionScalarInit.v_rel_a_1|freeMotionScalarInit.v_rel_a_2|freeMotionScalarInit.v_rel_a_3",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3") [Timeout 660]
"Warning: Requested package Modelica 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.002421/0.002421, allocations: 75.88 kB / 0.4968 GB, free: 7.805 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3608/0.3632, allocations: 65.99 MB / 0.5612 GB, free: 5.008 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3): time 0.01769/0.3809, allocations: 14.06 MB / 0.5749 GB, free: 6.887 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01074/0.3917, allocations: 4.466 MB / 0.5793 GB, free: 2.406 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002869/0.3945, allocations: 79.75 kB / 0.5794 GB, free: 2.328 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.005398/0.3999, allocations: 2.386 MB / 0.5817 GB, free: 15.93 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.005767/0.4057, allocations: 1.946 MB / 0.5836 GB, free: 13.98 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.0019/0.4076, allocations: 0.6002 MB / 0.5842 GB, free: 13.38 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.006405/0.414, allocations: 4.149 MB / 0.5882 GB, free: 9.215 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.004664/0.4187, allocations: 2.708 MB / 0.5909 GB, free: 6.457 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002594/0.4213, allocations: 1.585 MB / 0.5924 GB, free: 4.867 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.00329/0.4246, allocations: 2.348 MB / 0.5947 GB, free: 2.512 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0007608/0.4253, allocations: 348 kB / 0.5951 GB, free: 2.172 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.00247/0.4278, allocations: 1.252 MB / 0.5963 GB, free: 0.918 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.00201/0.4298, allocations: 2.037 MB / 0.5983 GB, free: 14.88 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002559/0.4324, allocations: 2.376 MB / 0.6006 GB, free: 12.48 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.009766/0.4421, allocations: 7.839 MB / 0.6083 GB, free: 4.613 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.081e-06/0.4421, allocations: 0 / 0.6083 GB, free: 4.613 MB / 446.1 MB
Notification: Performance of FrontEnd: time 1.643e-06/0.4421, allocations: 3.938 kB / 0.6083 GB, free: 4.609 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0002116/0.4423, allocations: 0 / 0.6083 GB, free: 4.609 MB / 446.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1811
 * Number of variables: 1811
Notification: Performance of Generate backend data structure: time 0.01308/0.4554, allocations: 5.47 MB / 0.6136 GB, free: 15.06 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.391e-05/0.4555, allocations: 12.03 kB / 0.6136 GB, free: 15.05 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01085/0.4663, allocations: 3.195 MB / 0.6167 GB, free: 11.84 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005845/0.4722, allocations: 2.271 MB / 0.6189 GB, free: 9.527 MB / 462.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002328/0.4724, allocations: 266.9 kB / 0.6192 GB, free: 9.266 MB / 462.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0008332/0.4732, allocations: 347.7 kB / 0.6195 GB, free: 8.926 MB / 462.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02224/0.4955, allocations: 12.17 MB / 0.6314 GB, free: 12.28 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001573/0.4956, allocations: 27.77 kB / 0.6314 GB, free: 12.25 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001152/0.4968, allocations: 159.8 kB / 0.6316 GB, free: 12.1 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004611/0.5014, allocations: 2.641 MB / 0.6342 GB, free: 9.453 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02421/0.5256, allocations: 11.21 MB / 0.6451 GB, free: 14.22 MB / 494.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.07043/0.596, allocations: 38.95 MB / 0.6832 GB, free: 6.398 MB / 0.5137 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01113/0.6072, allocations: 4.809 MB / 0.6879 GB, free: 1.359 MB / 0.5137 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003584/0.6107, allocations: 2.21 MB / 0.69 GB, free: 15.14 MB / 0.5294 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.01342/0.6242, allocations: 6.841 MB / 0.6967 GB, free: 7.914 MB / 0.5294 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.729e-05/0.6242, allocations: 52.56 kB / 0.6968 GB, free: 7.855 MB / 0.5294 GB
Notification: Performance of pre-optimization done (n=226): time 7.064e-06/0.6242, allocations: 0 / 0.6968 GB, free: 7.855 MB / 0.5294 GB
Notification: Performance of matching and sorting (n=235): time 0.03958/0.6638, allocations: 16.25 MB / 0.7126 GB, free: 7.461 MB / 0.545 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.017e-05/0.6639, allocations: 77.34 kB / 0.7127 GB, free: 7.375 MB / 0.545 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00478/0.6687, allocations: 3.129 MB / 0.7157 GB, free: 4.23 MB / 0.545 GB
Notification: Performance of collectPreVariables (initialization): time 0.000351/0.669, allocations: 57.69 kB / 0.7158 GB, free: 4.168 MB / 0.545 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002735/0.6717, allocations: 3.317 MB / 0.719 GB, free: 0.8203 MB / 0.545 GB
Notification: Performance of collectInitialBindings (initialization): time 0.002024/0.6738, allocations: 0.9263 MB / 0.7199 GB, free: 15.88 MB / 0.5606 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002423/0.6762, allocations: 1.219 MB / 0.7211 GB, free: 14.66 MB / 0.5606 GB
Notification: Performance of setup shared object (initialization): time 0.0001863/0.6764, allocations: 301.1 kB / 0.7214 GB, free: 14.36 MB / 0.5606 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004351/0.6807, allocations: 2.378 MB / 0.7237 GB, free: 11.98 MB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.007043/0.6878, allocations: 5.701 MB / 0.7293 GB, free: 4.629 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.2895/0.9772, allocations: 8.762 MB / 0.7379 GB, free: 146.1 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 6.87e-05/0.9773, allocations: 31.5 kB / 0.7379 GB, free: 146.1 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=795) (initialization): time 0.01316/0.9905, allocations: 8.847 MB / 0.7465 GB, free: 143.2 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 6.367e-05/0.9905, allocations: 60.62 kB / 0.7466 GB, free: 143.2 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.000127/0.9907, allocations: 115.8 kB / 0.7467 GB, free: 143.2 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003023/0.9937, allocations: 1.126 MB / 0.7478 GB, free: 143 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003877/0.9975, allocations: 1.357 MB / 0.7491 GB, free: 142.9 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007477/1.005, allocations: 6.952 MB / 0.7559 GB, free: 138.9 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004089/1.009, allocations: 320.5 kB / 0.7562 GB, free: 138.8 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005375/1.01, allocations: 346.3 kB / 0.7566 GB, free: 138.7 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 527
 * Number of states: 0 ()
 * Number of discrete variables: 32 (freeMotionScalarInit.arrow.arrow.twoHeadedArrow,freeMotionScalarInit.arrow.headAtOrigin,spring.color[3],spring.color[2],spring.color[1],spring.massColor[3],spring.massColor[2],spring.massColor[1],frame_a1.color_x[3],frame_a1.color_x[2],frame_a1.color_x[1],frame_a.color_x[3],frame_a.color_x[2],frame_a.color_x[1],body.sphereColor[3],body.sphereColor[2],body.sphereColor[1],freeMotionScalarInit.arrowColor[3],freeMotionScalarInit.arrowColor[2],freeMotionScalarInit.arrowColor[1],world.groundColor[3],world.groundColor[2],world.groundColor[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1],world.axisColor_x[3],world.axisColor_x[2],world.axisColor_x[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (734):
 * Single equations (assignments): 726
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(3,3,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(4,5)}
Notification: Performance of prepare postOptimizeDAE: time 0.003018/1.013, allocations: 2.333 MB / 0.7588 GB, free: 137.4 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004105/1.013, allocations: 316.3 kB / 0.7591 GB, free: 137.3 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.005857/1.019, allocations: 4.389 MB / 0.7634 GB, free: 133.6 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.065e-05/1.019, allocations: 10.64 kB / 0.7634 GB, free: 133.6 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.831e-06/1.019, allocations: 4 kB / 0.7634 GB, free: 133.6 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0006243/1.02, allocations: 342.2 kB / 0.7638 GB, free: 133.4 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003576/1.02, allocations: 36 kB / 0.7638 GB, free: 133.4 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0011/1.021, allocations: 135.6 kB / 0.7639 GB, free: 133.3 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000225/1.021, allocations: 111.9 kB / 0.764 GB, free: 133.3 MB / 0.5606 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.00543/1.027, allocations: 4.129 MB / 0.7681 GB, free: 129.3 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.005771/1.032, allocations: 6.233 MB / 0.7742 GB, free: 123.1 MB / 0.5606 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001485/1.034, allocations: 1.585 MB / 0.7757 GB, free: 121.5 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.002151/1.036, allocations: 1.891 MB / 0.7775 GB, free: 119.6 MB / 0.5606 GB
Notification: Performance of Backend: time 5.11e-07/1.036, allocations: 0 / 0.7775 GB, free: 119.6 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.007112/1.043, allocations: 4.421 MB / 0.7819 GB, free: 115.3 MB / 0.5606 GB
Notification: Performance of SimCode: time 0.01333/1.057, allocations: 10.49 MB / 0.7921 GB, free: 104.8 MB / 0.5606 GB
Notification: Performance of Templates: time 0.071/1.128, allocations: 50.28 MB / 0.8412 GB, free: 54.68 MB / 0.5606 GB
"
[Timeout remaining time 659]
make -j1 -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3.makefile [Timeout 660]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3.sim & ./ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3.pipe 2>&1) [Timeout 480]
diffSimulationResults("ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/ModelicaTest/MultiBody/Joints/FreeMotionScalarInit3/FreeMotionScalarInit3.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/OpenModelicaLibraryTesting/files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.FreeMotionScalarInit3.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: 6.991022346075624]