Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.Joints.SphericalSpherical2.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo", uses=false)
Using package ModelicaTest with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo)
Using package ModelicaServices with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package Complex with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo)
Running command: "" <> buildModelFMU(ModelicaTest.MultiBody.Joints.SphericalSpherical2,fileNamePrefix="ModelicaTest_3_2_3_cpp_ModelicaTest_MultiBody_Joints_SphericalSpherical2",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.Joints.SphericalSpherical2,fileNamePrefix="ModelicaTest_3_2_3_cpp_ModelicaTest_MultiBody_Joints_SphericalSpherical2",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 3.2.3+maint.om/package.mo): time 0.00165/0.00165, allocations: 186 kB / 16.41 MB, free: 5.727 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 2.106/2.106, allocations: 205.4 MB / 222.5 MB, free: 9.141 MB / 186.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 3.2.3+maint.om/package.mo): time 0.001257/0.001258, allocations: 97.48 kB / 270.9 MB, free: 8.395 MB / 218.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 3.2.3+maint.om/package.mo): time 0.5341/0.5341, allocations: 43.55 MB / 362.7 MB, free: 51.94 MB / 298.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004933/0.0004934, allocations: 16 kB / 431.5 MB, free: 5.859 MB / 314.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1329/0.1334, allocations: 58.84 MB / 490.3 MB, free: 10.93 MB / 378.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 0.5416/0.6751, allocations: 117.4 MB / 0.5935 GB, free: 1.023 MB / 410.7 MB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0002637/0.6754, allocations: 83.95 kB / 0.5936 GB, free: 0.9414 MB / 410.7 MB
Notification: Performance of FrontEnd - DAE generated: time 1.311/1.987, allocations: 230.3 MB / 0.8185 GB, free: 31.07 MB / 490.7 MB
Notification: Performance of FrontEnd: time 3.396e-06/1.987, allocations: 0 / 0.8185 GB, free: 31.07 MB / 490.7 MB
Notification: Performance of Transformations before backend: time 0.001452/1.988, allocations: 276 kB / 0.8187 GB, free: 30.8 MB / 490.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 4612
 * Number of variables: 4612
Notification: Performance of Generate backend data structure: time 0.1118/2.1, allocations: 14.54 MB / 0.8329 GB, free: 16.14 MB / 490.7 MB
Notification: Performance of prepare preOptimizeDAE: time 6.205e-05/2.1, allocations: 12.03 kB / 0.8329 GB, free: 16.13 MB / 490.7 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0172/2.117, allocations: 2.811 MB / 0.8357 GB, free: 13.3 MB / 490.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.115/2.232, allocations: 10.92 MB / 0.8464 GB, free: 2.352 MB / 490.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.07097/2.303, allocations: 15.6 MB / 0.8616 GB, free: 2.66 MB / 0.4949 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.002067/2.306, allocations: 1.091 MB / 0.8627 GB, free: 1.566 MB / 0.4949 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.00995/2.316, allocations: 1.449 MB / 0.8641 GB, free: 120 kB / 0.4949 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.9074/3.223, allocations: 32.32 MB / 0.8956 GB, free: 44.74 MB / 0.4949 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.001417/3.224, allocations: 147.2 kB / 0.8958 GB, free: 44.74 MB / 0.4949 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.00789/3.232, allocations: 0.6619 MB / 0.8964 GB, free: 44.74 MB / 0.4949 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.005796/3.238, allocations: 2.338 MB / 0.8987 GB, free: 44.72 MB / 0.4949 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.09264/3.331, allocations: 25.99 MB / 0.9241 GB, free: 44.62 MB / 0.4949 GB
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.2262/3.557, allocations: 130.8 MB / 1.052 GB, free: 348 kB / 0.5418 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.06664/3.624, allocations: 12.5 MB / 1.064 GB, free: 8.762 MB / 0.5574 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.007451/3.631, allocations: 4.841 MB / 1.069 GB, free: 3.562 MB / 0.5574 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0001392/3.632, allocations: 81.45 kB / 1.069 GB, free: 3.473 MB / 0.5574 GB
Notification: Performance of pre-optimization done (n=435): time 8.697e-06/3.632, allocations: 0 / 1.069 GB, free: 3.473 MB / 0.5574 GB
Notification: Performance of matching and sorting (n=569): time 1.654/5.286, allocations: 136.8 MB / 1.202 GB, free: 96.79 MB / 0.5575 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.05462/5.34, allocations: 25.88 MB / 1.228 GB, free: 72.7 MB / 0.5575 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.02288/5.363, allocations: 8.473 MB / 1.236 GB, free: 64.49 MB / 0.5575 GB
Notification: Performance of collectPreVariables (initialization): time 0.001829/5.365, allocations: 114.6 kB / 1.236 GB, free: 64.38 MB / 0.5575 GB
Notification: Performance of collectInitialEqns (initialization): time 0.00756/5.373, allocations: 8.581 MB / 1.244 GB, free: 55.84 MB / 0.5575 GB
Notification: Performance of collectInitialBindings (initialization): time 0.004456/5.377, allocations: 2.187 MB / 1.247 GB, free: 53.67 MB / 0.5575 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.003811/5.381, allocations: 318.7 kB / 1.247 GB, free: 53.36 MB / 0.5575 GB
Notification: Performance of setup shared object (initialization): time 0.00213/5.383, allocations: 0.9967 MB / 1.248 GB, free: 52.35 MB / 0.5575 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.02339/5.407, allocations: 9.409 MB / 1.257 GB, free: 42.93 MB / 0.5575 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.03001/5.437, allocations: 18.64 MB / 1.275 GB, free: 19.89 MB / 0.5575 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.4727/5.909, allocations: 12.86 MB / 1.288 GB, free: 92.16 MB / 0.5575 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0003101/5.91, allocations: 93.22 kB / 1.288 GB, free: 92.16 MB / 0.5575 GB
Notification: Performance of matching and sorting (n=2104) (initialization): time 0.1343/6.044, allocations: 36.44 MB / 1.324 GB, free: 90.59 MB / 0.5575 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0003341/6.045, allocations: 143.8 kB / 1.324 GB, free: 90.59 MB / 0.5575 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.09878/6.143, allocations: 23.48 MB / 1.347 GB, free: 90.05 MB / 0.5575 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.06964/6.213, allocations: 10.94 MB / 1.357 GB, free: 88.93 MB / 0.5575 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02472/6.238, allocations: 3.859 MB / 1.361 GB, free: 88.5 MB / 0.5575 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.1053/6.343, allocations: 52.91 MB / 1.413 GB, free: 60.79 MB / 0.5575 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.02975/6.373, allocations: 1.803 MB / 1.414 GB, free: 59.89 MB / 0.5575 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.004597/6.378, allocations: 0.9805 MB / 1.415 GB, free: 58.97 MB / 0.5575 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 1478
 * Number of states: 0 ()
 * Number of discrete variables: 78 (freeMotionScalarInit1.arrowColor[3],freeMotionScalarInit1.arrowColor[2],freeMotionScalarInit1.arrowColor[1],freeMotionScalarInit.arrowColor[3],freeMotionScalarInit.arrowColor[2],freeMotionScalarInit.arrowColor[1],rs3.arrowColor[3],rs3.arrowColor[2],rs3.arrowColor[1],rs2.arrowColor[3],rs2.arrowColor[2],rs2.arrowColor[1],bodyBox2.color[3],bodyBox2.color[2],bodyBox2.color[1],bodyBox2.body.sphereColor[3],bodyBox2.body.sphereColor[2],bodyBox2.body.sphereColor[1],sphericalSpherical1.massColor[3],sphericalSpherical1.massColor[2],sphericalSpherical1.massColor[1],sphericalSpherical1.sphereColor[3],sphericalSpherical1.sphereColor[2],sphericalSpherical1.sphereColor[1],sphericalSpherical1.rodColor[3],sphericalSpherical1.rodColor[2],sphericalSpherical1.rodColor[1],body.sphereColor[3],body.sphereColor[2],body.sphereColor[1],bodyBox1.color[3],bodyBox1.color[2],bodyBox1.color[1],bodyBox1.body.sphereColor[3],bodyBox1.body.sphereColor[2],bodyBox1.body.sphereColor[1],spherical1.sphereColor[3],spherical1.sphereColor[2],spherical1.sphereColor[1],bodyBox.color[3],bodyBox.color[2],bodyBox.color[1],bodyBox.body.sphereColor[3],bodyBox.body.sphereColor[2],bodyBox.body.sphereColor[1],rs1.arrowColor[3],rs1.arrowColor[2],rs1.arrowColor[1],fixedTranslation1.color[3],fixedTranslation1.color[2],fixedTranslation1.color[1],fixedTranslation.color[3],fixedTranslation.color[2],fixedTranslation.color[1],spherical.sphereColor[3],spherical.sphereColor[2],spherical.sphereColor[1],sphericalSpherical.massColor[3],sphericalSpherical.massColor[2],sphericalSpherical.massColor[1],sphericalSpherical.sphereColor[3],sphericalSpherical.sphereColor[2],sphericalSpherical.sphereColor[1],sphericalSpherical.rodColor[3],sphericalSpherical.rodColor[2],sphericalSpherical.rodColor[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 (1928):
 * Single equations (assignments): 1899
 * Array equations: 15
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 10
 * Torn equation systems: 4
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 4 {(4,100.0%),(4,100.0%),(4,100.0%),(4,100.0%)}
 * Non-linear Jacobian: 6 {1,1,4,4,4,4}
 * Without analytic Jacobian: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 4 {(8,82.8%) 32,(1,100.0%) 3,(4,93.8%) 20,(7,63.3%) 23}
 * Non-linear torn systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.019/6.397, allocations: 6.203 MB / 1.421 GB, free: 53.6 MB / 0.5575 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.00373/6.4, allocations: 0.8803 MB / 1.422 GB, free: 53.2 MB / 0.5575 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.1265/6.527, allocations: 29.05 MB / 1.451 GB, free: 32.29 MB / 0.5575 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.1176/6.645, allocations: 27.3 MB / 1.477 GB, free: 5.906 MB / 0.5575 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0001272/6.645, allocations: 28 kB / 1.477 GB, free: 5.879 MB / 0.5575 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0001601/6.645, allocations: 27.89 kB / 1.477 GB, free: 5.852 MB / 0.5575 GB
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 postOpt removeSimpleEquations (simulation): time 0.6799/7.325, allocations: 63.44 MB / 1.539 GB, free: 96.51 MB / 0.5732 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.247e-05/7.325, allocations: 3.906 kB / 1.539 GB, free: 96.51 MB / 0.5732 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.01039/7.335, allocations: 1.016 MB / 1.54 GB, free: 96.51 MB / 0.5732 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.09891/7.434, allocations: 15.69 MB / 1.556 GB, free: 96.41 MB / 0.5732 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.002118/7.437, allocations: 179 kB / 1.556 GB, free: 96.41 MB / 0.5732 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.1459/7.583, allocations: 67.66 MB / 1.622 GB, free: 70.55 MB / 0.5732 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 0.05595/7.639, allocations: 25.7 MB / 1.647 GB, free: 54.93 MB / 0.5732 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.1432/7.782, allocations: 41.35 MB / 1.687 GB, free: 17.91 MB / 0.5732 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.003638/7.785, allocations: 0.9443 MB / 1.688 GB, free: 17.43 MB / 0.5732 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.004324/7.79, allocations: 143.2 kB / 1.688 GB, free: 17.29 MB / 0.5732 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.016/7.806, allocations: 0.8965 MB / 1.689 GB, free: 16.4 MB / 0.5732 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.003746/7.81, allocations: 449.3 kB / 1.69 GB, free: 15.96 MB / 0.5732 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.001337/7.811, allocations: 184.5 kB / 1.69 GB, free: 15.79 MB / 0.5732 GB
Notification: Performance of sorting global known variables: time 0.01585/7.827, allocations: 5.882 MB / 1.696 GB, free: 9.957 MB / 0.5732 GB
Notification: Performance of sort global known variables: time 2.044e-06/7.827, allocations: 5.938 kB / 1.696 GB, free: 9.953 MB / 0.5732 GB
Notification: Performance of remove unused functions: time 0.02486/7.852, allocations: 4.438 MB / 1.7 GB, free: 5.527 MB / 0.5732 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 20
 * Number of states: 32 ($STATESET6.x[2],$STATESET6.x[1],$STATESET5.x[5],$STATESET5.x[4],$STATESET5.x[3],$STATESET5.x[2],$STATESET5.x[1],$STATESET4.x[6],$STATESET4.x[5],$STATESET4.x[4],$STATESET4.x[3],$STATESET4.x[2],$STATESET4.x[1],$STATESET3.x[3],$STATESET3.x[2],$STATESET3.x[1],$STATESET2.x[2],$STATESET2.x[1],$STATESET1.x[5],$STATESET1.x[4],$STATESET1.x[3],$STATESET1.x[2],$STATESET1.x[1],bodyBox.body.w_a[1],bodyBox.body.w_a[2],bodyBox.body.w_a[3],bodyBox1.body.w_a[1],bodyBox1.body.w_a[2],bodyBox1.body.w_a[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 (412):
 * Single equations (assignments): 397
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 6
 * Torn equation systems: 6
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 3 {(4,100.0%),(4,100.0%),(4,100.0%)}
 * Non-linear Jacobian: 3 {3,8,4}
 * Without analytic Jacobian: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 5 {(4,93.8%) 17,(3,100.0%) 3,(8,82.8%) 29,(7,93.9%) 12,(7,63.3%) 20}
 * Non-linear torn systems: 1 {7 12}
Notification: Performance of Backend phase and start with SimCode phase: time 0.694/8.546, allocations: 42.64 MB / 1.742 GB, free: 95.41 MB / 0.5732 GB
Notification: Performance of simCode: created initialization part: time 0.06524/8.611, allocations: 23.96 MB / 1.765 GB, free: 93.85 MB / 0.5732 GB
Notification: Performance of simCode: created event and clocks part: time 1.141e-05/8.611, allocations: 7.156 kB / 1.765 GB, free: 93.85 MB / 0.5732 GB
Notification: Performance of simCode: created simulation system equations: time 0.03516/8.646, allocations: 11.76 MB / 1.777 GB, free: 92.33 MB / 0.5732 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01634/8.663, allocations: 1.348 MB / 1.778 GB, free: 92.3 MB / 0.5732 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.6864/9.349, allocations: 297.3 MB / 2.068 GB, free: 15.93 MB / 0.6357 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0302/9.38, allocations: 9.453 MB / 2.077 GB, free: 14.89 MB / 0.6357 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.01919/9.399, allocations: 6.56 MB / 2.084 GB, free: 14.89 MB / 0.6357 GB
Notification: Performance of SimCode: time 9.72e-07/9.399, allocations: 0 / 2.084 GB, free: 14.89 MB / 0.6357 GB
Notification: Performance of buildModelFMU: Generate C++ for platform static: time 11.51/20.9, allocations: 0.9281 GB / 3.012 GB, free: 61.84 MB / 0.6357 GB
(rm -f ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.Joints.SphericalSpherical2.pipe ; mkfifo ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.Joints.SphericalSpherical2.pipe ; head -c 1048576 < ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.Joints.SphericalSpherical2.pipe >> ../files/ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.Joints.SphericalSpherical2.sim & /home/hudson/saved_omc/OMSimulator/install/linux/bin/OMSimulator -r=ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.Joints.SphericalSpherical2_res.mat --tempDir=temp_ModelicaTest_3_2_3_cpp_ModelicaTest_MultiBody_Joints_SphericalSpherical2_fmu --startTime=0 --stopTime=2 --timeout=50 --tolerance=1e-06 ModelicaTest_3_2_3_cpp_ModelicaTest_MultiBody_Joints_SphericalSpherical2.fmu > ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.Joints.SphericalSpherical2.pipe 2>&1)
TODO: How the !@#!# did the simulation report success but simulation result ModelicaTest_3.2.3_cpp_ModelicaTest.MultiBody.Joints.SphericalSpherical2_res.mat does not exist to compare? outputFormat=mat