Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.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.001881/0.001881, allocations: 122.1 kB / 18.45 MB, free: 4.594 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.002468/0.002468, allocations: 207.3 kB / 21.41 MB, free: 1.645 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.567/1.567, allocations: 227.4 MB / 251.7 MB, free: 52 kB / 190.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.2475/0.2475, allocations: 46.15 MB / 351.1 MB, free: 14.3 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: "" <> buildModelFMU(ModelicaTest.MultiBody.Joints.Spherical,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_Spherical",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.Joints.Spherical,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_Spherical",fmuType="me",version="2.0",platforms={"static"}) [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.002303/0.002303, allocations: 82.44 kB / 489.7 MB, free: 28.08 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1409/0.1432, allocations: 62.44 MB / 0.5392 GB, free: 4.77 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.Spherical): time 0.01441/0.1577, allocations: 11.76 MB / 0.5507 GB, free: 8.945 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008708/0.1664, allocations: 4.058 MB / 0.5546 GB, free: 4.871 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001444/0.1679, allocations: 63.81 kB / 0.5547 GB, free: 4.809 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.002517/0.1705, allocations: 1.179 MB / 0.5558 GB, free: 3.625 MB / 414.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003905/0.1744, allocations: 1.72 MB / 0.5575 GB, free: 1.902 MB / 414.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001386/0.1758, allocations: 0.5535 MB / 0.558 GB, free: 1.348 MB / 414.1 MB
Notification: Performance of NFFlatten.flatten: time 0.005517/0.1814, allocations: 4.046 MB / 0.562 GB, free: 13.29 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002639/0.184, allocations: 1.776 MB / 0.5637 GB, free: 11.49 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002064/0.1861, allocations: 1.152 MB / 0.5649 GB, free: 10.34 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002627/0.1888, allocations: 1.67 MB / 0.5665 GB, free: 8.66 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0006878/0.1895, allocations: 216 kB / 0.5667 GB, free: 8.449 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002783/0.1923, allocations: 1.283 MB / 0.5679 GB, free: 7.164 MB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001916/0.1942, allocations: 1.57 MB / 0.5695 GB, free: 5.59 MB / 430.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.002429/0.1967, allocations: 1.836 MB / 0.5713 GB, free: 3.742 MB / 430.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.008723/0.2054, allocations: 6.212 MB / 0.5773 GB, free: 13.5 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.282e-06/0.2054, allocations: 0 / 0.5773 GB, free: 13.5 MB / 446.1 MB
Notification: Performance of FrontEnd: time 2.054e-06/0.2055, allocations: 4 kB / 0.5773 GB, free: 13.5 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0001907/0.2057, allocations: 0 / 0.5773 GB, free: 13.5 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: 1482
 * Number of variables: 1482
Notification: Performance of Generate backend data structure: time 0.009396/0.2151, allocations: 4.209 MB / 0.5815 GB, free: 9.215 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.813e-05/0.2151, allocations: 12.03 kB / 0.5815 GB, free: 9.203 MB / 446.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001351/0.2165, allocations: 0.6876 MB / 0.5821 GB, free: 8.504 MB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0208/0.2373, allocations: 6.183 MB / 0.5882 GB, free: 2.309 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.006201/0.2435, allocations: 2.619 MB / 0.5907 GB, free: 15.64 MB / 462.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001909/0.2438, allocations: 192.4 kB / 0.5909 GB, free: 15.45 MB / 462.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001047/0.2448, allocations: 303.7 kB / 0.5912 GB, free: 15.16 MB / 462.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02078/0.2656, allocations: 10.73 MB / 0.6017 GB, free: 3.969 MB / 462.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 9.161e-05/0.2658, allocations: 27.73 kB / 0.6017 GB, free: 3.941 MB / 462.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003974/0.2662, allocations: 115.8 kB / 0.6018 GB, free: 3.828 MB / 462.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004296/0.2705, allocations: 2.467 MB / 0.6042 GB, free: 1.355 MB / 462.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.2854/0.5559, allocations: 10.15 MB / 0.6141 GB, free: 52.68 MB / 462.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.05291/0.6089, allocations: 35.57 MB / 0.6489 GB, free: 29.84 MB / 462.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.01043/0.6194, allocations: 5.589 MB / 0.6543 GB, free: 24.12 MB / 462.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002651/0.6221, allocations: 2.342 MB / 0.6566 GB, free: 21.78 MB / 462.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.001311/0.6234, allocations: 1.874 MB / 0.6585 GB, free: 19.58 MB / 462.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.411e-05/0.6235, allocations: 48.56 kB / 0.6585 GB, free: 19.52 MB / 462.1 MB
Notification: Performance of pre-optimization done (n=224): time 5.871e-06/0.6235, allocations: 0 / 0.6585 GB, free: 19.52 MB / 462.1 MB
Notification: Performance of matching and sorting (n=231): time 0.03057/0.654, allocations: 15.03 MB / 0.6732 GB, free: 4.594 MB / 462.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0009385/0.655, allocations: 1.619 MB / 0.6748 GB, free: 2.824 MB / 462.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00306/0.6581, allocations: 2.809 MB / 0.6775 GB, free: 68 kB / 462.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0003332/0.6585, allocations: 49.7 kB / 0.6775 GB, free: 12 kB / 462.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.002298/0.6608, allocations: 2.792 MB / 0.6803 GB, free: 13.29 MB / 478.1 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001233/0.662, allocations: 0.8917 MB / 0.6811 GB, free: 12.41 MB / 478.1 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002343/0.6644, allocations: 1.104 MB / 0.6822 GB, free: 11.3 MB / 478.1 MB
Notification: Performance of setup shared object (initialization): time 0.0001404/0.6645, allocations: 313 kB / 0.6825 GB, free: 11 MB / 478.1 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.004386/0.6689, allocations: 2.627 MB / 0.6851 GB, free: 8.363 MB / 478.1 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.007175/0.6761, allocations: 5.629 MB / 0.6906 GB, free: 1.262 MB / 478.1 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01252/0.6887, allocations: 8.937 MB / 0.6993 GB, free: 6.852 MB / 494.1 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001067/0.6888, allocations: 32 kB / 0.6993 GB, free: 6.82 MB / 494.1 MB
Notification: Performance of matching and sorting (n=725) (initialization): time 0.06617/0.755, allocations: 17.13 MB / 0.7161 GB, free: 5.664 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001264/0.7552, allocations: 48 kB / 0.7161 GB, free: 5.617 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.04548/0.8007, allocations: 10.64 MB / 0.7265 GB, free: 10.96 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.04842/0.8491, allocations: 8.639 MB / 0.7349 GB, free: 2.293 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.005002/0.8542, allocations: 1.302 MB / 0.7362 GB, free: 0.9883 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.03283/0.8871, allocations: 17.81 MB / 0.7536 GB, free: 14.91 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004435/0.8915, allocations: 419.5 kB / 0.754 GB, free: 14.5 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001177/0.8927, allocations: 316 kB / 0.7543 GB, free: 14.19 MB / 0.545 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 476
 * Number of states: 0 ()
 * Number of discrete variables: 24 (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],spherical.sphereColor[3],spherical.sphereColor[2],spherical.sphereColor[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 (653):
 * Single equations (assignments): 642
 * Array equations: 8
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 2
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 1 system
   {(4,100.0%)}
 * Non-linear Jacobian (size): 0 systems
 * Without analytic Jacobian (size): 0 systems
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,9)}
Notification: Performance of prepare postOptimizeDAE: time 0.004426/0.8972, allocations: 2.031 MB / 0.7563 GB, free: 12.15 MB / 0.545 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006566/0.8978, allocations: 286.1 kB / 0.7566 GB, free: 11.87 MB / 0.545 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.009818/0.9077, allocations: 5.109 MB / 0.7616 GB, free: 6.734 MB / 0.545 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.008069/0.9158, allocations: 4.409 MB / 0.7659 GB, free: 2.301 MB / 0.545 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.729e-05/0.9158, allocations: 4 kB / 0.7659 GB, free: 2.297 MB / 0.545 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.902e-05/0.9158, allocations: 11.91 kB / 0.7659 GB, free: 2.285 MB / 0.545 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.02408/0.9399, allocations: 12.91 MB / 0.7785 GB, free: 5.219 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.17e-05/0.94, allocations: 8 kB / 0.7785 GB, free: 5.211 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.002146/0.9421, allocations: 455.2 kB / 0.7789 GB, free: 4.766 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.00308/0.9452, allocations: 0.9429 MB / 0.7798 GB, free: 3.812 MB / 0.5606 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002908/0.9455, allocations: 53.92 kB / 0.7799 GB, free: 3.762 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.007781/0.9533, allocations: 5.842 MB / 0.7856 GB, free: 13.64 MB / 0.5762 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 0.001353/0.9547, allocations: 1.549 MB / 0.7871 GB, free: 11.94 MB / 0.5762 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.01303/0.9678, allocations: 7.753 MB / 0.7947 GB, free: 4.062 MB / 0.5762 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.000754/0.9685, allocations: 347.2 kB / 0.795 GB, free: 3.703 MB / 0.5762 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0006747/0.9692, allocations: 43.98 kB / 0.7951 GB, free: 3.66 MB / 0.5762 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001832/0.9711, allocations: 195.7 kB / 0.7952 GB, free: 3.469 MB / 0.5762 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004897/0.9716, allocations: 142.9 kB / 0.7954 GB, free: 3.332 MB / 0.5762 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0002429/0.9718, allocations: 71.92 kB / 0.7955 GB, free: 3.262 MB / 0.5762 GB
Notification: Performance of sorting global known variables: time 0.003241/0.9751, allocations: 1.725 MB / 0.7971 GB, free: 1.531 MB / 0.5762 GB
Notification: Performance of sort global known variables: time 1.7e-07/0.9751, allocations: 4 kB / 0.7971 GB, free: 1.527 MB / 0.5762 GB
Notification: Performance of remove unused functions: time 0.004683/0.9798, allocations: 1.076 MB / 0.7982 GB, free: 464 kB / 0.5762 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 33
 * Number of states: 6 ($STATESET1.x[3],$STATESET1.x[2],$STATESET1.x[1],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 (170):
 * Single equations (assignments): 161
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 2
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 1 system
   {(4,100.0%)}
 * Non-linear Jacobian (size): 1 system
   {4}
 * Without analytic Jacobian (size): 0 systems
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): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.01384/0.9936, allocations: 8.41 MB / 0.8064 GB, free: 7.91 MB / 0.5919 GB
Notification: Performance of simCode: created initialization part: time 0.01237/1.006, allocations: 6.565 MB / 0.8128 GB, free: 1.316 MB / 0.5919 GB
Notification: Performance of simCode: created event and clocks part: time 1.138e-05/1.006, allocations: 0 / 0.8128 GB, free: 1.316 MB / 0.5919 GB
Notification: Performance of simCode: created simulation system equations: time 0.004258/1.01, allocations: 2.529 MB / 0.8153 GB, free: 14.74 MB / 0.6075 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002611/1.013, allocations: 373.5 kB / 0.8156 GB, free: 14.38 MB / 0.6075 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.4354/1.448, allocations: 56.78 MB / 0.8711 GB, free: 205.9 MB / 0.6231 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.004736/1.453, allocations: 3.163 MB / 0.8742 GB, free: 204.1 MB / 0.6231 GB
Notification: Performance of simCode: alias equations: time 0.004162/1.457, allocations: 2.057 MB / 0.8762 GB, free: 203.2 MB / 0.6231 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002087/1.46, allocations: 1.661 MB / 0.8778 GB, free: 201.7 MB / 0.6231 GB
Notification: Performance of SimCode: time 1.292e-06/1.46, allocations: 0 / 0.8778 GB, free: 201.7 MB / 0.6231 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1048/1.564, allocations: 70.81 MB / 0.947 GB, free: 135.6 MB / 0.6231 GB
Notification: Performance of buildModelFMU: Generate platform static: time 20.97/22.53, allocations: 3.875 kB / 0.947 GB, free: 135.6 MB / 0.6231 GB
"
[Timeout remaining time 637]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.sim & python3 -m fmpy simulate  --output-file ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical_res.csv --start-time 0 --stop-time 1.1 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.000219912 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_Spherical.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.Spherical.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 25.970167096005753]
Failed to read output from testmodel.py, exit status != 0:
['time', 'body.Q[1]', 'body.Q[2]', 'body.Q[3]', 'body.Q[4]', 'body.w_a[1]', 'body.w_a[2]', 'body.w_a[3]']
22.66436367901042 22.676895263 22.468726578
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f3f2f7a6020>