Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.JointUPS2.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.001713/0.001713, allocations: 109.4 kB / 19.8 MB, free: 2.5 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.001953/0.001953, allocations: 218.5 kB / 23 MB, free: 5.582 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.45/1.45, allocations: 230.2 MB / 256.2 MB, free: 8.531 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.2355/0.2355, allocations: 46.9 MB / 359.4 MB, free: 4.871 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.JointUPS2,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_JointUPS2",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.Joints.JointUPS2,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_JointUPS2",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.002312/0.002312, allocations: 83.86 kB / 0.4941 GB, free: 8.98 MB / 366.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3487/0.351, allocations: 65.98 MB / 0.5586 GB, free: 5.141 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Joints.JointUPS2): time 0.01537/0.3664, allocations: 12.02 MB / 0.5703 GB, free: 9.062 MB / 414.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01007/0.3765, allocations: 4.427 MB / 0.5746 GB, free: 4.625 MB / 414.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002043/0.3785, allocations: 79.75 kB / 0.5747 GB, free: 4.547 MB / 414.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.005925/0.3844, allocations: 2.791 MB / 0.5774 GB, free: 1.746 MB / 414.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006348/0.3908, allocations: 2.054 MB / 0.5795 GB, free: 15.68 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.00278/0.3936, allocations: 1.006 MB / 0.5804 GB, free: 14.68 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.008921/0.4025, allocations: 5.38 MB / 0.5857 GB, free: 9.285 MB / 430.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.004647/0.4071, allocations: 2.616 MB / 0.5882 GB, free: 6.621 MB / 430.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003651/0.4108, allocations: 1.764 MB / 0.59 GB, free: 4.852 MB / 430.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.004602/0.4154, allocations: 2.629 MB / 0.5925 GB, free: 2.215 MB / 430.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.001371/0.4167, allocations: 368 kB / 0.5929 GB, free: 1.855 MB / 430.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.003583/0.4203, allocations: 1.455 MB / 0.5943 GB, free: 408 kB / 430.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.003224/0.4236, allocations: 2.24 MB / 0.5965 GB, free: 14.15 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.004724/0.4283, allocations: 2.559 MB / 0.599 GB, free: 11.57 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.01232/0.4406, allocations: 8.221 MB / 0.607 GB, free: 3.324 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 6.352e-06/0.4406, allocations: 0 / 0.607 GB, free: 3.324 MB / 446.1 MB
Notification: Performance of FrontEnd: time 1.823e-06/0.4406, allocations: 0 / 0.607 GB, free: 3.324 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0002564/0.4409, allocations: 7.938 kB / 0.607 GB, free: 3.316 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: 1695
 * Number of variables: 1695
Notification: Performance of Generate backend data structure: time 0.01691/0.4578, allocations: 6.016 MB / 0.6129 GB, free: 13.22 MB / 462.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.408e-05/0.4578, allocations: 8.031 kB / 0.6129 GB, free: 13.21 MB / 462.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.002728/0.4606, allocations: 0.7985 MB / 0.6137 GB, free: 12.4 MB / 462.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.08723/0.5478, allocations: 7.25 MB / 0.6208 GB, free: 5.141 MB / 462.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.008629/0.5564, allocations: 3.613 MB / 0.6243 GB, free: 1.469 MB / 462.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002743/0.5567, allocations: 258.8 kB / 0.6245 GB, free: 1.215 MB / 462.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001185/0.5579, allocations: 351.7 kB / 0.6249 GB, free: 0.8711 MB / 462.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02349/0.5814, allocations: 12.28 MB / 0.6369 GB, free: 3.777 MB / 478.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001508/0.5815, allocations: 47.64 kB / 0.6369 GB, free: 3.73 MB / 478.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0005032/0.582, allocations: 167.8 kB / 0.6371 GB, free: 3.566 MB / 478.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.004127/0.5861, allocations: 2.381 MB / 0.6394 GB, free: 1.18 MB / 478.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.02252/0.6087, allocations: 10.7 MB / 0.6498 GB, free: 6.465 MB / 494.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.06913/0.6778, allocations: 48.65 MB / 0.6974 GB, free: 4.402 MB / 0.5294 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.01592/0.6937, allocations: 5.646 MB / 0.7029 GB, free: 14.61 MB / 0.545 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004319/0.698, allocations: 2.826 MB / 0.7056 GB, free: 11.77 MB / 0.545 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.02071/0.7187, allocations: 13.02 MB / 0.7183 GB, free: 14.42 MB / 0.5606 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 6.203e-05/0.7188, allocations: 55.81 kB / 0.7184 GB, free: 14.36 MB / 0.5606 GB
Notification: Performance of pre-optimization done (n=193): time 4.769e-06/0.7188, allocations: 0 / 0.7184 GB, free: 14.36 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=254): time 0.4145/1.133, allocations: 38.91 MB / 0.7564 GB, free: 147.6 MB / 0.5606 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 7.67e-05/1.133, allocations: 217.8 kB / 0.7566 GB, free: 147.4 MB / 0.5606 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004842/1.138, allocations: 4.385 MB / 0.7609 GB, free: 143 MB / 0.5606 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005694/1.139, allocations: 65.7 kB / 0.7609 GB, free: 143 MB / 0.5606 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001734/1.141, allocations: 3.562 MB / 0.7644 GB, free: 139.8 MB / 0.5606 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001029/1.142, allocations: 0.9938 MB / 0.7654 GB, free: 138.8 MB / 0.5606 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00165/1.143, allocations: 1.377 MB / 0.7667 GB, free: 137.4 MB / 0.5606 GB
Notification: Performance of setup shared object (initialization): time 4.262e-05/1.143, allocations: 305.1 kB / 0.767 GB, free: 137.1 MB / 0.5606 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003444/1.147, allocations: 3.113 MB / 0.7701 GB, free: 134 MB / 0.5606 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.004691/1.151, allocations: 7.047 MB / 0.777 GB, free: 125 MB / 0.5606 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.009711/1.161, allocations: 10.96 MB / 0.7877 GB, free: 112.2 MB / 0.5606 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.098e-05/1.161, allocations: 40 kB / 0.7877 GB, free: 112.1 MB / 0.5606 GB
Notification: Performance of matching and sorting (n=886) (initialization): time 0.02241/1.184, allocations: 12.65 MB / 0.8 GB, free: 99.46 MB / 0.5606 GB
Notification: Performance of prepare postOptimizeDAE: time 7.785e-05/1.184, allocations: 64 kB / 0.8001 GB, free: 99.4 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001223/1.184, allocations: 140 kB / 0.8002 GB, free: 99.27 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.008157/1.192, allocations: 3.236 MB / 0.8034 GB, free: 96.02 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00475/1.197, allocations: 1.539 MB / 0.8049 GB, free: 94.48 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.019/1.216, allocations: 16.6 MB / 0.8211 GB, free: 77.75 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.005948/1.222, allocations: 444 kB / 0.8215 GB, free: 77.32 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001114/1.223, allocations: 400 kB / 0.8219 GB, free: 76.93 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 626
 * Number of states: 0 ()
 * Number of discrete variables: 36 (universal.cylinderColor[3],universal.cylinderColor[2],universal.cylinderColor[1],bodyBox.color[3],bodyBox.color[2],bodyBox.color[1],bodyBox.body.sphereColor[3],bodyBox.body.sphereColor[2],bodyBox.body.sphereColor[1],fixedTranslation.color[3],fixedTranslation.color[2],fixedTranslation.color[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.groundColor[3],world.groundColor[2],world.groundColor[1],world.gravitySphereColor[3],world.gravitySphereColor[2],world.gravitySphereColor[1],jointUPS.cylinderColor[3],jointUPS.cylinderColor[2],jointUPS.cylinderColor[1],jointUPS.sphereColor[3],jointUPS.sphereColor[2],jointUPS.sphereColor[1],jointUPS.axisColor[3],jointUPS.axisColor[2],jointUPS.axisColor[1])
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (808):
 * Single equations (assignments): 804
 * 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
   {(8,47,54.7%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.003613/1.226, allocations: 2.349 MB / 0.8242 GB, free: 74.57 MB / 0.5606 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0009055/1.227, allocations: 429.2 kB / 0.8246 GB, free: 74.14 MB / 0.5606 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01279/1.24, allocations: 7.483 MB / 0.8319 GB, free: 66.64 MB / 0.5606 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 4.841e-05/1.24, allocations: 28 kB / 0.832 GB, free: 66.61 MB / 0.5606 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.785e-05/1.24, allocations: 12 kB / 0.832 GB, free: 66.6 MB / 0.5606 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.268e-05/1.24, allocations: 14.48 kB / 0.832 GB, free: 66.59 MB / 0.5606 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0231/1.263, allocations: 15.01 MB / 0.8466 GB, free: 51.54 MB / 0.5606 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 3.581e-05/1.263, allocations: 0 / 0.8466 GB, free: 51.54 MB / 0.5606 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001984/1.265, allocations: 375.5 kB / 0.847 GB, free: 51.17 MB / 0.5606 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.007597/1.273, allocations: 2.931 MB / 0.8499 GB, free: 48.23 MB / 0.5606 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0004831/1.273, allocations: 40 kB / 0.8499 GB, free: 48.2 MB / 0.5606 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.02434/1.298, allocations: 14.82 MB / 0.8644 GB, free: 33.23 MB / 0.5606 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.819e-06/1.298, allocations: 3.938 kB / 0.8644 GB, free: 33.23 MB / 0.5606 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02592/1.324, allocations: 11.49 MB / 0.8756 GB, free: 21.67 MB / 0.5606 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001292/1.325, allocations: 458.2 kB / 0.876 GB, free: 21.2 MB / 0.5606 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001373/1.326, allocations: 51.92 kB / 0.8761 GB, free: 21.15 MB / 0.5606 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003487/1.33, allocations: 187.7 kB / 0.8763 GB, free: 20.96 MB / 0.5606 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0009268/1.331, allocations: 149.7 kB / 0.8764 GB, free: 20.82 MB / 0.5606 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0004235/1.331, allocations: 64 kB / 0.8765 GB, free: 20.75 MB / 0.5606 GB
Notification: Performance of sorting global known variables: time 0.006399/1.338, allocations: 2.746 MB / 0.8792 GB, free: 18 MB / 0.5606 GB
Notification: Performance of sort global known variables: time 1.2e-07/1.338, allocations: 0 / 0.8792 GB, free: 18 MB / 0.5606 GB
Notification: Performance of remove unused functions: time 0.008752/1.346, allocations: 1.293 MB / 0.8804 GB, free: 16.71 MB / 0.5606 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 4
 * Number of states: 4 (universal.phi_a,universal.phi_b,universal.w_a,universal.w_b)
 * 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 (189):
 * Single equations (assignments): 185
 * 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
   {(6,40,61.1%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.02123/1.368, allocations: 12.14 MB / 0.8923 GB, free: 4.5 MB / 0.5606 GB
Notification: Performance of simCode: created initialization part: time 0.01215/1.38, allocations: 7.525 MB / 0.8996 GB, free: 12.98 MB / 0.5762 GB
Notification: Performance of simCode: created event and clocks part: time 7.243e-06/1.38, allocations: 3.938 kB / 0.8996 GB, free: 12.98 MB / 0.5762 GB
Notification: Performance of simCode: created simulation system equations: time 0.005748/1.385, allocations: 2.882 MB / 0.9024 GB, free: 10.09 MB / 0.5762 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004055/1.39, allocations: 0.5079 MB / 0.9029 GB, free: 9.578 MB / 0.5762 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1194/1.509, allocations: 60.76 MB / 0.9623 GB, free: 6.531 MB / 0.6387 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00689/1.516, allocations: 3.662 MB / 0.9658 GB, free: 2.836 MB / 0.6387 GB
Notification: Performance of simCode: alias equations: time 0.005466/1.521, allocations: 2.176 MB / 0.968 GB, free: 0.6523 MB / 0.6387 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.003696/1.525, allocations: 2.225 MB / 0.9701 GB, free: 14.46 MB / 0.6544 GB
Notification: Performance of SimCode: time 8.91e-07/1.525, allocations: 0 / 0.9701 GB, free: 14.46 MB / 0.6544 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.3891/1.914, allocations: 95.77 MB / 1.064 GB, free: 166 MB / 0.6544 GB
Notification: Performance of buildModelFMU: Generate platform static: time 28.21/30.12, allocations: 12.06 kB / 1.064 GB, free: 165.9 MB / 0.6544 GB
"
[Timeout remaining time 630]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.JointUPS2.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.JointUPS2.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.JointUPS2.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.JointUPS2.sim & python3 -m fmpy simulate  --output-file ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.JointUPS2_res.csv --start-time 0 --stop-time 4 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.00079968 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Joints_JointUPS2.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Joints.JointUPS2.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 32.95337323201238]
Failed to read output from testmodel.py, exit status != 0:
['time', 'universal.phi_a', 'universal.phi_b', 'universal.w_a', 'universal.w_b']
30.25959836700349 30.267885632 29.824805801
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f45cd830a90>