Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.RevoluteInitialization.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.001674/0.001674, allocations: 121.2 kB / 18.5 MB, free: 4.578 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.001864/0.001864, allocations: 206.6 kB / 21.45 MB, free: 1.629 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.523/1.523, allocations: 230.4 MB / 254.7 MB, free: 15.04 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.2407/0.2407, allocations: 46.92 MB / 357.7 MB, free: 10.23 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.RevoluteInitialization,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_RevoluteInitialization",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.Joints.RevoluteInitialization,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_RevoluteInitialization",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.002439/0.002439, allocations: 83.86 kB / 0.4917 GB, free: 7.754 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.135/0.1374, allocations: 66.02 MB / 0.5562 GB, free: 5.602 MB / 414.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.RevoluteInitialization): time 0.01289/0.1503, allocations: 10.3 MB / 0.5662 GB, free: 11.24 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008707/0.159, allocations: 3.822 MB / 0.5699 GB, free: 7.406 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002048/0.1611, allocations: 67.62 kB / 0.57 GB, free: 7.34 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.004449/0.1655, allocations: 1.958 MB / 0.5719 GB, free: 5.375 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.005045/0.1706, allocations: 1.68 MB / 0.5736 GB, free: 3.691 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001721/0.1723, allocations: 0.6043 MB / 0.5742 GB, free: 3.086 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.005455/0.1777, allocations: 3.372 MB / 0.5774 GB, free: 15.71 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.00346/0.1812, allocations: 2.193 MB / 0.5796 GB, free: 13.46 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002515/0.1837, allocations: 1.257 MB / 0.5808 GB, free: 12.2 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.003036/0.1868, allocations: 1.814 MB / 0.5826 GB, free: 10.38 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0008253/0.1876, allocations: 256 kB / 0.5828 GB, free: 10.13 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002452/0.19, allocations: 1.053 MB / 0.5839 GB, free: 9.074 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.002185/0.1922, allocations: 1.593 MB / 0.5854 GB, free: 7.477 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.003044/0.1953, allocations: 1.817 MB / 0.5872 GB, free: 5.648 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.3277/0.523, allocations: 5.811 MB / 0.5929 GB, free: 13.58 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 9.808e-06/0.523, allocations: 0.6562 kB / 0.5929 GB, free: 13.58 MB / 446.1 MB
Notification: Performance of FrontEnd: time 2.144e-06/0.523, allocations: 0 / 0.5929 GB, free: 13.58 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0002556/0.5233, allocations: 0 / 0.5929 GB, free: 13.58 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: 1275
 * Number of variables: 1275
Notification: Performance of Generate backend data structure: time 0.009204/0.5325, allocations: 4.118 MB / 0.5969 GB, free: 11.8 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.253e-05/0.5325, allocations: 9.125 kB / 0.5969 GB, free: 11.8 MB / 446.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0009671/0.5335, allocations: 0.6109 MB / 0.5975 GB, free: 11.74 MB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006951/0.5404, allocations: 1.8 MB / 0.5993 GB, free: 11.48 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004739/0.5452, allocations: 2.172 MB / 0.6014 GB, free: 10.95 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001684/0.5453, allocations: 189.2 kB / 0.6016 GB, free: 10.95 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0006459/0.546, allocations: 261.1 kB / 0.6018 GB, free: 10.94 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01533/0.5613, allocations: 8.45 MB / 0.6101 GB, free: 9.062 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001202/0.5614, allocations: 38.8 kB / 0.6101 GB, free: 9.059 MB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003853/0.5618, allocations: 123.4 kB / 0.6102 GB, free: 9.008 MB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003049/0.5649, allocations: 1.7 MB / 0.6119 GB, free: 8.734 MB / 446.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01369/0.5786, allocations: 7.109 MB / 0.6188 GB, free: 6.719 MB / 446.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.03997/0.6185, allocations: 34.09 MB / 0.6521 GB, free: 10.61 MB / 478.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.007897/0.6264, allocations: 3.184 MB / 0.6552 GB, free: 7.281 MB / 478.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002069/0.6285, allocations: 1.361 MB / 0.6565 GB, free: 5.91 MB / 478.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.007172/0.6357, allocations: 4.457 MB / 0.6609 GB, free: 1.266 MB / 478.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.478e-05/0.6357, allocations: 43.12 kB / 0.6609 GB, free: 1.215 MB / 478.1 MB
Notification: Performance of pre-optimization done (n=121): time 4.298e-06/0.6357, allocations: 0 / 0.6609 GB, free: 1.215 MB / 478.1 MB
Notification: Performance of matching and sorting (n=129): time 0.03037/0.6661, allocations: 11.74 MB / 0.6724 GB, free: 5.391 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001149/0.6662, allocations: 166.9 kB / 0.6726 GB, free: 5.191 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005229/0.6714, allocations: 3.733 MB / 0.6762 GB, free: 1.469 MB / 494.1 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002565/0.6717, allocations: 45.45 kB / 0.6762 GB, free: 1.422 MB / 494.1 MB
Notification: Performance of collectInitialEqns (initialization): time 0.002251/0.6739, allocations: 2.862 MB / 0.679 GB, free: 14.59 MB / 0.4981 GB
Notification: Performance of collectInitialBindings (initialization): time 0.000741/0.6747, allocations: 0.5414 MB / 0.6796 GB, free: 14.05 MB / 0.4981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001595/0.6763, allocations: 1.089 MB / 0.6806 GB, free: 12.96 MB / 0.4981 GB
Notification: Performance of setup shared object (initialization): time 0.0001284/0.6764, allocations: 309 kB / 0.6809 GB, free: 12.66 MB / 0.4981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002688/0.6791, allocations: 1.701 MB / 0.6826 GB, free: 10.95 MB / 0.4981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005564/0.6847, allocations: 5.121 MB / 0.6876 GB, free: 4.156 MB / 0.4981 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.009491/0.6941, allocations: 7.406 MB / 0.6948 GB, free: 11.55 MB / 0.5137 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001083/0.6943, allocations: 40 kB / 0.6949 GB, free: 11.51 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=692) (initialization): time 0.01303/0.7073, allocations: 6.729 MB / 0.7014 GB, free: 4.902 MB / 0.5137 GB
Notification: Performance of prepare postOptimizeDAE: time 9.397e-05/0.7074, allocations: 60 kB / 0.7015 GB, free: 4.844 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001494/0.7075, allocations: 123.9 kB / 0.7016 GB, free: 4.723 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0007124/0.7082, allocations: 317.3 kB / 0.7019 GB, free: 4.41 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004073/0.7123, allocations: 1.24 MB / 0.7031 GB, free: 3.168 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002885/0.7152, allocations: 2.5 MB / 0.7056 GB, free: 0.5273 MB / 0.5137 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003872/0.7191, allocations: 291.6 kB / 0.7058 GB, free: 248 kB / 0.5137 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0008343/0.7199, allocations: 339.5 kB / 0.7062 GB, free: 15.91 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 555
 * Number of states: 0 ()
 * Number of discrete variables: 33 (spring.color[3],spring.color[2],spring.color[1],spring.massColor[3],spring.massColor[2],spring.massColor[1],fixed.color[3],fixed.color[2],fixed.color[1],body.color[3],body.color[2],body.color[1],body.body.sphereColor[3],body.body.sphereColor[2],body.body.sphereColor[1],body.frameTranslation.color[3],body.frameTranslation.color[2],body.frameTranslation.color[1],rev.cylinderColor[3],rev.cylinderColor[2],rev.cylinderColor[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 (664):
 * Single equations (assignments): 660
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(1,4,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.003038/0.7229, allocations: 1.791 MB / 0.7079 GB, free: 14.11 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0006611/0.7236, allocations: 325.8 kB / 0.7082 GB, free: 13.79 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.005087/0.7287, allocations: 2.363 MB / 0.7105 GB, free: 11.41 MB / 0.5294 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.519e-05/0.7287, allocations: 16 kB / 0.7105 GB, free: 11.39 MB / 0.5294 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.048e-05/0.7287, allocations: 4 kB / 0.7105 GB, free: 11.39 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.616e-05/0.7288, allocations: 3.984 kB / 0.7106 GB, free: 11.38 MB / 0.5294 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01172/0.7405, allocations: 5.897 MB / 0.7163 GB, free: 5.445 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.546e-06/0.7405, allocations: 4 kB / 0.7163 GB, free: 5.441 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.000829/0.7413, allocations: 175.7 kB / 0.7165 GB, free: 5.27 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.00222/0.7435, allocations: 0.7208 MB / 0.7172 GB, free: 4.547 MB / 0.5294 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 9.666e-05/0.7436, allocations: 20 kB / 0.7172 GB, free: 4.527 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.006327/0.75, allocations: 4.825 MB / 0.7219 GB, free: 15.58 MB / 0.545 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.194e-06/0.75, allocations: 0 / 0.7219 GB, free: 15.58 MB / 0.545 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.007213/0.7572, allocations: 4.346 MB / 0.7262 GB, free: 11.21 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0009673/0.7581, allocations: 382.8 kB / 0.7265 GB, free: 10.82 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003411/0.7585, allocations: 16 kB / 0.7265 GB, free: 10.8 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00113/0.7596, allocations: 67.91 kB / 0.7266 GB, free: 10.73 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002207/0.7598, allocations: 84.5 kB / 0.7267 GB, free: 10.65 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 9.226e-05/0.7599, allocations: 30.89 kB / 0.7267 GB, free: 10.62 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.003691/0.7636, allocations: 2.327 MB / 0.729 GB, free: 8.312 MB / 0.545 GB
Notification: Performance of sort global known variables: time 7e-08/0.7636, allocations: 0 / 0.729 GB, free: 8.312 MB / 0.545 GB
Notification: Performance of remove unused functions: time 0.004605/0.7682, allocations: 0.8014 MB / 0.7298 GB, free: 7.512 MB / 0.545 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 6
 * Number of states: 2 (rev.phi,rev.w)
 * 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 (96):
 * Single equations (assignments): 92
 * Array equations: 3
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(1,11,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.007862/0.7761, allocations: 4.971 MB / 0.7346 GB, free: 2.5 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.006665/0.7827, allocations: 3.897 MB / 0.7384 GB, free: 14.62 MB / 0.5606 GB
Notification: Performance of simCode: created event and clocks part: time 6.442e-06/0.7828, allocations: 0 / 0.7384 GB, free: 14.62 MB / 0.5606 GB
Notification: Performance of simCode: created simulation system equations: time 0.001619/0.7844, allocations: 1.036 MB / 0.7394 GB, free: 13.58 MB / 0.5606 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00239/0.7868, allocations: 400.7 kB / 0.7398 GB, free: 13.2 MB / 0.5606 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.0576/0.8444, allocations: 37.72 MB / 0.7767 GB, free: 2.305 MB / 0.5919 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003261/0.8476, allocations: 2.793 MB / 0.7794 GB, free: 15.48 MB / 0.6075 GB
Notification: Performance of simCode: alias equations: time 0.003725/0.8513, allocations: 1.652 MB / 0.781 GB, free: 13.83 MB / 0.6075 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.002311/0.8537, allocations: 1.526 MB / 0.7825 GB, free: 12.3 MB / 0.6075 GB
Notification: Performance of SimCode: time 9.31e-07/0.8537, allocations: 0 / 0.7825 GB, free: 12.3 MB / 0.6075 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1164/0.97, allocations: 58 MB / 0.8391 GB, free: 2.684 MB / 0.6544 GB
Notification: Performance of buildModelFMU: Generate platform static: time 23.38/24.35, allocations: 7.094 kB / 0.8391 GB, free: 2.68 MB / 0.6544 GB
"
[Timeout remaining time 636]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.RevoluteInitialization.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.RevoluteInitialization.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.RevoluteInitialization.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.RevoluteInitialization.sim & python3 -m fmpy simulate  --output-file ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.RevoluteInitialization_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_RevoluteInitialization.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.RevoluteInitialization.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 27.724396415054798]
Failed to read output from testmodel.py, exit status != 0:
['time', 'rev.phi', 'rev.w']
24.471283907070756 24.701959741 24.175748028
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f7b73a397e0>