Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Planes.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.001776/0.001776, allocations: 109.8 kB / 19.17 MB, free: 3.891 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.002084/0.002084, allocations: 208.9 kB / 22.13 MB, free: 0.9375 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.481/1.481, allocations: 230.2 MB / 255.2 MB, free: 15.08 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.2389/0.2389, allocations: 46.88 MB / 358.1 MB, free: 10.53 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.Visualizers.Planes,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|surfaceWireframe.nu|surfaceWireframe.nv|surfaceSolid.nu|surfaceSolid.nv",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Planes")
translateModel(ModelicaTest.MultiBody.Visualizers.Planes,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5002,variableFilter="time|surfaceWireframe.nu|surfaceWireframe.nv|surfaceSolid.nu|surfaceSolid.nv",fileNamePrefix="ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Planes") [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.00229/0.00229, allocations: 79.88 kB / 0.4923 GB, free: 8.113 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1252/0.1275, allocations: 65.99 MB / 0.5568 GB, free: 5.992 MB / 414.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Visualizers.Planes): time 0.01373/0.1413, allocations: 11.56 MB / 0.5681 GB, free: 10.37 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.006796/0.1481, allocations: 3.073 MB / 0.5711 GB, free: 7.285 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001617/0.1497, allocations: 59.88 kB / 0.5711 GB, free: 7.227 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003332/0.153, allocations: 1.592 MB / 0.5727 GB, free: 5.629 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003884/0.1569, allocations: 1.603 MB / 0.5742 GB, free: 4.02 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.000401/0.1573, allocations: 111.9 kB / 0.5744 GB, free: 3.91 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004623/0.1619, allocations: 3.306 MB / 0.5776 GB, free: 0.6016 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002546/0.1645, allocations: 1.914 MB / 0.5795 GB, free: 14.65 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001721/0.1662, allocations: 1.144 MB / 0.5806 GB, free: 13.5 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003816/0.17, allocations: 2.591 MB / 0.5831 GB, free: 10.91 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0006933/0.1707, allocations: 228 kB / 0.5833 GB, free: 10.68 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001607/0.1723, allocations: 0.7333 MB / 0.584 GB, free: 9.949 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001637/0.1739, allocations: 1.534 MB / 0.5855 GB, free: 8.41 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002246/0.1762, allocations: 1.812 MB / 0.5873 GB, free: 6.586 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.007166/0.1834, allocations: 5.943 MB / 0.5931 GB, free: 0.6172 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 5.14e-06/0.1834, allocations: 0 / 0.5931 GB, free: 0.6172 MB / 446.1 MB
Notification: Performance of FrontEnd: time 1.012e-06/0.1834, allocations: 7.938 kB / 0.5931 GB, free: 0.6094 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0001624/0.1835, allocations: 0 / 0.5931 GB, free: 0.6094 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: 1485
 * Number of variables: 1485
Notification: Performance of Generate backend data structure: time 0.2622/0.4457, allocations: 4.092 MB / 0.5971 GB, free: 34.08 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.801e-05/0.4458, allocations: 9.531 kB / 0.5971 GB, free: 34.08 MB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0111/0.4569, allocations: 2.992 MB / 0.6 GB, free: 33.79 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004498/0.4614, allocations: 1.841 MB / 0.6018 GB, free: 33.03 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002346/0.4616, allocations: 186.3 kB / 0.602 GB, free: 32.98 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000895/0.4625, allocations: 239.7 kB / 0.6022 GB, free: 32.94 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01982/0.4823, allocations: 9.752 MB / 0.6118 GB, free: 29.05 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001133/0.4824, allocations: 22.28 kB / 0.6118 GB, free: 29.04 MB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0007804/0.4832, allocations: 115.6 kB / 0.6119 GB, free: 28.96 MB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003805/0.487, allocations: 2.365 MB / 0.6142 GB, free: 28.24 MB / 446.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01599/0.503, allocations: 8.955 MB / 0.623 GB, free: 22.31 MB / 446.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.04409/0.5471, allocations: 29.57 MB / 0.6518 GB, free: 8.051 MB / 462.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.007298/0.5544, allocations: 3.36 MB / 0.6551 GB, free: 4.453 MB / 462.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001879/0.5563, allocations: 1.022 MB / 0.6561 GB, free: 3.418 MB / 462.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.01229/0.5686, allocations: 6.47 MB / 0.6624 GB, free: 12.53 MB / 478.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.773e-05/0.5686, allocations: 47.22 kB / 0.6625 GB, free: 12.48 MB / 478.1 MB
Notification: Performance of pre-optimization done (n=135): time 5.761e-06/0.5686, allocations: 0 / 0.6625 GB, free: 12.48 MB / 478.1 MB
Notification: Performance of matching and sorting (n=135): time 0.008774/0.5774, allocations: 3.143 MB / 0.6655 GB, free: 9.328 MB / 478.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 9.651e-05/0.5775, allocations: 111.5 kB / 0.6657 GB, free: 9.195 MB / 478.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005585/0.5831, allocations: 2.738 MB / 0.6683 GB, free: 6.438 MB / 478.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0001675/0.5832, allocations: 45.69 kB / 0.6684 GB, free: 6.387 MB / 478.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.002622/0.5859, allocations: 2.845 MB / 0.6711 GB, free: 3.512 MB / 478.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.0008138/0.5867, allocations: 0.5427 MB / 0.6717 GB, free: 2.965 MB / 478.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001823/0.5885, allocations: 0.9369 MB / 0.6726 GB, free: 2.016 MB / 478.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001515/0.5886, allocations: 304.3 kB / 0.6729 GB, free: 1.719 MB / 478.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002507/0.5911, allocations: 1.406 MB / 0.6743 GB, free: 312 kB / 478.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005222/0.5964, allocations: 4.225 MB / 0.6784 GB, free: 10.66 MB / 494.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.008452/0.6048, allocations: 6.155 MB / 0.6844 GB, free: 3.105 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001209/0.6049, allocations: 28 kB / 0.6844 GB, free: 3.078 MB / 494.1 MB
Notification: Performance of matching and sorting (n=609) (initialization): time 0.01167/0.6166, allocations: 5.463 MB / 0.6898 GB, free: 13.61 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001175/0.6167, allocations: 48 kB / 0.6898 GB, free: 13.56 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001702/0.6169, allocations: 104.2 kB / 0.6899 GB, free: 13.46 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.000173/0.6171, allocations: 132.7 kB / 0.69 GB, free: 13.33 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003411/0.6205, allocations: 0.9903 MB / 0.691 GB, free: 12.34 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0002017/0.6207, allocations: 199.3 kB / 0.6912 GB, free: 12.14 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003621/0.6243, allocations: 0.632 MB / 0.6918 GB, free: 11.51 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0006857/0.625, allocations: 288 kB / 0.6921 GB, free: 11.23 MB / 0.4981 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 455
 * Number of states: 0 ()
 * Number of discrete variables: 21 (fixedFrame.color_x[3],fixedFrame.color_x[2],fixedFrame.color_x[1],fixedFrameOfWireframe.color_x[3],fixedFrameOfWireframe.color_x[2],fixedFrameOfWireframe.color_x[1],fixedRotation.color[3],fixedRotation.color[2],fixedRotation.color[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 (551):
 * Single equations (assignments): 543
 * Array equations: 8
 * 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.004141/0.6291, allocations: 1.977 MB / 0.694 GB, free: 9.234 MB / 0.4981 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006237/0.6298, allocations: 238.4 kB / 0.6942 GB, free: 9 MB / 0.4981 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0008098/0.6306, allocations: 292.7 kB / 0.6945 GB, free: 8.707 MB / 0.4981 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.956e-05/0.6306, allocations: 19.94 kB / 0.6945 GB, free: 8.688 MB / 0.4981 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.293e-05/0.6306, allocations: 4 kB / 0.6945 GB, free: 8.684 MB / 0.4981 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.499e-05/0.6306, allocations: 11.97 kB / 0.6945 GB, free: 8.672 MB / 0.4981 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01355/0.6442, allocations: 6.188 MB / 0.7006 GB, free: 2.32 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.805e-05/0.6442, allocations: 8 kB / 0.7006 GB, free: 2.312 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0007325/0.6449, allocations: 164 kB / 0.7008 GB, free: 2.152 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (simulation): time 3.478e-05/0.645, allocations: 15.98 kB / 0.7008 GB, free: 2.137 MB / 0.4981 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001027/0.6451, allocations: 32 kB / 0.7008 GB, free: 2.105 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 2.208e-05/0.6451, allocations: 15.94 kB / 0.7008 GB, free: 2.09 MB / 0.4981 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.095e-05/0.6451, allocations: 15.88 kB / 0.7008 GB, free: 2.074 MB / 0.4981 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.003164/0.6483, allocations: 2.015 MB / 0.7028 GB, free: 15.93 MB / 0.5137 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007751/0.6491, allocations: 319.1 kB / 0.7031 GB, free: 15.61 MB / 0.5137 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004841/0.6495, allocations: 27.98 kB / 0.7031 GB, free: 15.58 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.000941/0.6505, allocations: 47.95 kB / 0.7032 GB, free: 15.53 MB / 0.5137 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001608/0.6506, allocations: 84.23 kB / 0.7033 GB, free: 15.45 MB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001113/0.6508, allocations: 51.98 kB / 0.7033 GB, free: 15.4 MB / 0.5137 GB
Notification: Performance of sorting global known variables: time 0.003361/0.6541, allocations: 1.67 MB / 0.7049 GB, free: 13.72 MB / 0.5137 GB
Notification: Performance of sort global known variables: time 9e-08/0.6541, allocations: 0 / 0.7049 GB, free: 13.72 MB / 0.5137 GB
Notification: Performance of remove unused functions: time 0.004621/0.6587, allocations: 1.028 MB / 0.7059 GB, free: 12.69 MB / 0.5137 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 34
 * Number of states: 0 ()
 * 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 (93):
 * Single equations (assignments): 87
 * Array equations: 6
 * 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.0004866/0.6592, allocations: 154.9 kB / 0.7061 GB, free: 12.53 MB / 0.5137 GB
Notification: Performance of simCode: created initialization part: time 0.005939/0.6652, allocations: 3.138 MB / 0.7091 GB, free: 9.414 MB / 0.5137 GB
Notification: Performance of simCode: created event and clocks part: time 1.023e-05/0.6652, allocations: 0 / 0.7091 GB, free: 9.414 MB / 0.5137 GB
Notification: Performance of simCode: created simulation system equations: time 0.001177/0.6663, allocations: 0.752 MB / 0.7099 GB, free: 8.668 MB / 0.5137 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002277/0.6686, allocations: 305.8 kB / 0.7102 GB, free: 8.379 MB / 0.5137 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01809/0.6867, allocations: 8.244 MB / 0.7182 GB, free: 15.98 MB / 0.5294 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.005129/0.6918, allocations: 3.099 MB / 0.7213 GB, free: 12.86 MB / 0.5294 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0007221/0.6926, allocations: 211.4 kB / 0.7215 GB, free: 12.65 MB / 0.5294 GB
Notification: Performance of SimCode: time 1.282e-06/0.6926, allocations: 0 / 0.7215 GB, free: 12.65 MB / 0.5294 GB
Notification: Performance of Templates: time 0.08025/0.7728, allocations: 44.99 MB / 0.7654 GB, free: 15.79 MB / 0.5762 GB
"
[Timeout remaining time 659]
make -j1 -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Planes.makefile [Timeout 660]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Planes.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Planes.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Planes.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Planes.sim & ./ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Planes  -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Planes.pipe 2>&1) [Timeout 480]
diffSimulationResults("ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Planes_res.mat","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/Reference-modelica.org/ReferenceResults/MAP-LIB_ReferenceResults/v4.1.0/ModelicaTest/MultiBody/Visualizers/Planes/Planes.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/OpenModelicaLibraryTesting/files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Visualizers.Planes.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: 11.944558571092784]