Running: ./testmodel.py --libraries=/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.2_ModelicaTest.MultiBody.InitializationConversion.Bodies.conf.json
Using package ModelicaTest with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.2+maint.om/package.mo)
Using package ModelicaServices with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo)
Using package Modelica with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo)
Using package Complex with version 3.2.2 (/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo)
Running command: "" <> buildModelFMU(ModelicaTest.MultiBody.InitializationConversion.Bodies,fileNamePrefix="ModelicaTest_3_2_2_ModelicaTest_MultiBody_InitializationConversion_Bodies",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.2+maint.om/package.mo): time 0.0136/0.0136, allocations: 173.6 kB / 15.12 MB, free: 5.93 MB / 13.93 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.2+maint.om/package.mo): time 1.973/1.973, allocations: 194.5 MB / 210.3 MB, free: 8.582 MB / 170.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.2+maint.om/package.mo): time 0.002073/0.002073, allocations: 120.7 kB / 255.6 MB, free: 11.2 MB / 218.7 MB
Notification: Performance of loadFile(/var/lib/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.2+maint.om/package.mo): time 0.2729/0.2729, allocations: 40.01 MB / 340.8 MB, free: 11.52 MB / 250.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0006399/0.0006403, allocations: 15.28 kB / 406.3 MB, free: 52.03 MB / 298.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1014/0.1021, allocations: 54.87 MB / 461.1 MB, free: 3.32 MB / 298.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 0.5107/0.6128, allocations: 95.38 MB / 0.5435 GB, free: 21.79 MB / 378.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0002817/0.6131, allocations: 76.62 kB / 0.5435 GB, free: 21.79 MB / 378.7 MB
Notification: Performance of FrontEnd - DAE generated: time 0.4552/1.068, allocations: 102.4 MB / 0.6435 GB, free: 100 kB / 410.7 MB
Notification: Performance of FrontEnd: time 3.717e-06/1.068, allocations: 0 / 0.6435 GB, free: 100 kB / 410.7 MB
Notification: Performance of Transformations before backend: time 0.0005718/1.069, allocations: 84 kB / 0.6436 GB, free: 16 kB / 410.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1495
 * Number of variables: 1495
Notification: Performance of Generate backend data structure: time 0.0726/1.142, allocations: 6.887 MB / 0.6503 GB, free: 9.051 MB / 426.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.663e-05/1.142, allocations: 12.03 kB / 0.6503 GB, free: 9.039 MB / 426.7 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.004675/1.146, allocations: 0.7894 MB / 0.6511 GB, free: 8.238 MB / 426.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.06007/1.207, allocations: 7.349 MB / 0.6583 GB, free: 0.8711 MB / 426.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.07425/1.281, allocations: 18.3 MB / 0.6761 GB, free: 14.47 MB / 458.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0005707/1.281, allocations: 316 kB / 0.6764 GB, free: 14.16 MB / 458.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.002886/1.284, allocations: 439.6 kB / 0.6769 GB, free: 13.73 MB / 458.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0347/1.319, allocations: 10.6 MB / 0.6872 GB, free: 2.02 MB / 458.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0004063/1.32, allocations: 79.3 kB / 0.6873 GB, free: 1.941 MB / 458.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.001672/1.321, allocations: 203.9 kB / 0.6875 GB, free: 1.742 MB / 458.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.002926/1.324, allocations: 1.046 MB / 0.6885 GB, free: 0.6953 MB / 458.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02978/1.354, allocations: 9.257 MB / 0.6976 GB, free: 7.418 MB / 474.7 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.4496/1.804, allocations: 51.21 MB / 0.7476 GB, free: 74.93 MB / 490.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01478/1.819, allocations: 3.846 MB / 0.7513 GB, free: 74.17 MB / 490.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003145/1.819, allocations: 29.7 kB / 0.7513 GB, free: 74.17 MB / 490.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.611e-05/1.819, allocations: 60.8 kB / 0.7514 GB, free: 74.13 MB / 490.7 MB
Notification: Performance of pre-optimization done (n=188): time 6.612e-06/1.819, allocations: 0 / 0.7514 GB, free: 74.13 MB / 490.7 MB
Notification: Performance of matching and sorting (n=216): time 0.0918/1.911, allocations: 22.7 MB / 0.7736 GB, free: 66.71 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.004968/1.916, allocations: 6.225 MB / 0.7797 GB, free: 60.87 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.01216/1.928, allocations: 4.536 MB / 0.7841 GB, free: 60.48 MB / 490.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0007156/1.929, allocations: 57.06 kB / 0.7841 GB, free: 60.45 MB / 490.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.003605/1.932, allocations: 3.501 MB / 0.7876 GB, free: 58.98 MB / 490.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001743/1.934, allocations: 0.9105 MB / 0.7884 GB, free: 58.47 MB / 490.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001519/1.936, allocations: 142 kB / 0.7886 GB, free: 58.47 MB / 490.7 MB
Notification: Performance of setup shared object (initialization): time 0.0006391/1.936, allocations: 0.6078 MB / 0.7892 GB, free: 58.16 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.008335/1.945, allocations: 3.126 MB / 0.7922 GB, free: 58.14 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.01127/1.956, allocations: 7.431 MB / 0.7995 GB, free: 52.59 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01104/1.967, allocations: 4.663 MB / 0.804 GB, free: 52.56 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001798/1.967, allocations: 44.81 kB / 0.8041 GB, free: 52.56 MB / 490.7 MB
Notification: Performance of matching and sorting (n=946) (initialization): time 0.1027/2.07, allocations: 15.8 MB / 0.8195 GB, free: 49.07 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 0.0001934/2.07, allocations: 67.34 kB / 0.8196 GB, free: 49.07 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.07927/2.15, allocations: 9.003 MB / 0.8284 GB, free: 47.29 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.07042/2.22, allocations: 6.736 MB / 0.8349 GB, free: 46.4 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.01033/2.231, allocations: 1.69 MB / 0.8366 GB, free: 44.96 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03829/2.269, allocations: 16.55 MB / 0.8528 GB, free: 30.04 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.01248/2.281, allocations: 0.7856 MB / 0.8535 GB, free: 29.49 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001917/2.283, allocations: 457.3 kB / 0.854 GB, free: 29.07 MB / 490.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 694
 * Number of states: 0 ()
 * Number of discrete variables: 36 (body3.color[3],body3.color[2],body3.color[1],body3.body.sphereColor[3],body3.body.sphereColor[2],body3.body.sphereColor[1],body2.color[3],body2.color[2],body2.color[1],body2.body.sphereColor[3],body2.body.sphereColor[2],body2.body.sphereColor[1],body1.color[3],body1.color[2],body1.color[1],body1.body.sphereColor[3],body1.body.sphereColor[2],body1.body.sphereColor[1],body1.frameTranslation.color[3],body1.frameTranslation.color[2],body1.frameTranslation.color[1],pointMass.sphereColor[3],pointMass.sphereColor[2],pointMass.sphereColor[1],body.sphereColor[3],body.sphereColor[2],body.sphereColor[1],world.gravityArrowColor[3],world.gravityArrowColor[2],world.gravityArrowColor[1],world.axisColor_x[3],world.axisColor_x[2],world.axisColor_x[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 (870):
 * Single equations (assignments): 845
 * Array equations: 11
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 12
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 8 {(3,100.0%),(4,100.0%),(4,100.0%),(3,100.0%),(4,100.0%),(3,100.0%),(4,100.0%),(3,100.0%)}
 * Non-linear Jacobian: 4 {4,4,4,4}
 * Without analytic Jacobian: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(2,100.0%) 1,(2,100.0%) 1}
 * Non-linear torn systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.006657/2.29, allocations: 2.332 MB / 0.8562 GB, free: 27.02 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001287/2.291, allocations: 461.9 kB / 0.8567 GB, free: 26.57 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.00205/2.293, allocations: 0.6135 MB / 0.8573 GB, free: 26.02 MB / 490.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.02675/2.32, allocations: 6.006 MB / 0.8631 GB, free: 21.59 MB / 490.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.279e-05/2.32, allocations: 8 kB / 0.8631 GB, free: 21.59 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.389e-05/2.32, allocations: 17.59 kB / 0.8632 GB, free: 21.58 MB / 490.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.03367/2.354, allocations: 10.44 MB / 0.8734 GB, free: 12.6 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.047e-05/2.354, allocations: 4 kB / 0.8734 GB, free: 12.59 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.004272/2.358, allocations: 443.3 kB / 0.8738 GB, free: 12.16 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.02384/2.382, allocations: 3.596 MB / 0.8773 GB, free: 8.52 MB / 490.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0007794/2.383, allocations: 91.91 kB / 0.8774 GB, free: 8.43 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02322/2.406, allocations: 13.9 MB / 0.891 GB, free: 9.754 MB / 0.4948 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 0.006542/2.413, allocations: 5.92 MB / 0.8967 GB, free: 3.273 MB / 0.4948 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.05298/2.466, allocations: 10.7 MB / 0.9072 GB, free: 8.398 MB / 0.5105 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002732/2.469, allocations: 0.5342 MB / 0.9077 GB, free: 7.84 MB / 0.5105 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002402/2.471, allocations: 67.97 kB / 0.9078 GB, free: 7.773 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.009028/2.48, allocations: 344.8 kB / 0.9081 GB, free: 7.438 MB / 0.5105 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001578/2.482, allocations: 183.6 kB / 0.9083 GB, free: 7.258 MB / 0.5105 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0005768/2.483, allocations: 75.88 kB / 0.9084 GB, free: 7.188 MB / 0.5105 GB
Notification: Performance of sorting global known variables: time 0.01374/2.496, allocations: 3.218 MB / 0.9115 GB, free: 3.984 MB / 0.5105 GB
Notification: Performance of sort global known variables: time 9.02e-07/2.496, allocations: 0 / 0.9115 GB, free: 3.984 MB / 0.5105 GB
Notification: Performance of remove unused functions: time 0.01193/2.508, allocations: 1.637 MB / 0.9131 GB, free: 2.355 MB / 0.5105 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 14
 * Number of states: 54 ($STATESET4.x[3],$STATESET4.x[2],$STATESET4.x[1],body3.v_0[1],body3.v_0[2],body3.v_0[3],body3.body.frame_a.r_0[1],body3.body.frame_a.r_0[2],body3.body.frame_a.r_0[3],body3.body.w_a[1],body3.body.w_a[2],body3.body.w_a[3],$STATESET3.x[3],$STATESET3.x[2],$STATESET3.x[1],body2.v_0[1],body2.v_0[2],body2.v_0[3],body2.body.frame_a.r_0[1],body2.body.frame_a.r_0[2],body2.body.frame_a.r_0[3],body2.body.w_a[1],body2.body.w_a[2],body2.body.w_a[3],$STATESET2.x[3],$STATESET2.x[2],$STATESET2.x[1],body1.v_0[1],body1.v_0[2],body1.v_0[3],body1.body.frame_a.r_0[1],body1.body.frame_a.r_0[2],body1.body.frame_a.r_0[3],body1.body.w_a[1],body1.body.w_a[2],body1.body.w_a[3],pointMass.r_0[1],pointMass.r_0[2],pointMass.r_0[3],pointMass.v_0[1],pointMass.v_0[2],pointMass.v_0[3],$STATESET1.x[3],$STATESET1.x[2],$STATESET1.x[1],body.frame_a.r_0[1],body.frame_a.r_0[2],body.frame_a.r_0[3],body.v_0[1],body.v_0[2],body.v_0[3],body.w_a[1],body.w_a[2],body.w_a[3])
 * 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 (156):
 * Single equations (assignments): 139
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 12
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 8 {(3,100.0%),(4,100.0%),(3,100.0%),(4,100.0%),(3,100.0%),(4,100.0%),(3,100.0%),(4,100.0%)}
 * Non-linear Jacobian: 4 {4,4,4,4}
 * Without analytic Jacobian: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 2 {(2,100.0%) 1,(2,100.0%) 1}
 * Non-linear torn systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.3844/2.893, allocations: 12.03 MB / 0.9248 GB, free: 128 MB / 0.5105 GB
Notification: Performance of simCode: created initialization part: time 0.02734/2.92, allocations: 9.293 MB / 0.9339 GB, free: 126.4 MB / 0.5105 GB
Notification: Performance of simCode: created event and clocks part: time 1.062e-05/2.92, allocations: 0 / 0.9339 GB, free: 126.4 MB / 0.5105 GB
Notification: Performance of simCode: created simulation system equations: time 0.01459/2.935, allocations: 4.665 MB / 0.9385 GB, free: 124.9 MB / 0.5105 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.006159/2.941, allocations: 0.6263 MB / 0.9391 GB, free: 124.9 MB / 0.5105 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.204/3.145, allocations: 160.3 MB / 1.096 GB, free: 26.39 MB / 0.5417 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.008871/3.154, allocations: 3.788 MB / 1.099 GB, free: 25.04 MB / 0.5417 GB
Notification: Performance of simCode: alias equations: time 0.01068/3.165, allocations: 2.267 MB / 1.101 GB, free: 24.86 MB / 0.5417 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.005879/3.171, allocations: 2.279 MB / 1.104 GB, free: 23.03 MB / 0.5417 GB
Notification: Performance of SimCode: time 1.533e-06/3.171, allocations: 3.719 kB / 1.104 GB, free: 23.03 MB / 0.5417 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.6816/3.852, allocations: 118.1 MB / 1.219 GB, free: 141.9 MB / 0.5417 GB
Notification: Performance of buildModelFMU: configured platform static using cached values: time 0.0002561/3.853, allocations: 224.8 kB / 1.219 GB, free: 141.6 MB / 0.5417 GB
Notification: Performance of buildModelFMU: Generate platform static: time 6.774/10.63, allocations: 0 / 1.219 GB, free: 141.6 MB / 0.5417 GB
TODO: How the !@#!# did the simulation report success but simulation result ModelicaTest_3.2.2_ModelicaTest.MultiBody.InitializationConversion.Bodies_res.mat does not exist to compare? outputFormat=mat