Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.AbsoluteSensor2.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.00133/0.00133, allocations: 116.4 kB / 18.5 MB, free: 4.574 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.001368/0.001368, allocations: 208.9 kB / 21.46 MB, free: 1.617 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.452/1.452, allocations: 230.2 MB / 254.5 MB, free: 15.25 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.2246/0.2246, allocations: 46.88 MB / 357.4 MB, free: 10.52 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.Sensors.AbsoluteSensor2,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Sensors_AbsoluteSensor2",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.Sensors.AbsoluteSensor2,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Sensors_AbsoluteSensor2",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.002229/0.002229, allocations: 75.88 kB / 0.4912 GB, free: 8.562 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1251/0.1273, allocations: 65.98 MB / 0.5556 GB, free: 6.453 MB / 414.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Sensors.AbsoluteSensor2): time 0.01448/0.1418, allocations: 11.98 MB / 0.5673 GB, free: 10.41 MB / 430.1 MB
Notification: Performance of NFInst.instExpressions: time 0.008597/0.1504, allocations: 4.097 MB / 0.5713 GB, free: 6.297 MB / 430.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001677/0.1521, allocations: 79.81 kB / 0.5714 GB, free: 6.219 MB / 430.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003371/0.1554, allocations: 1.682 MB / 0.5731 GB, free: 4.531 MB / 430.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.003726/0.1592, allocations: 1.557 MB / 0.5746 GB, free: 2.969 MB / 430.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.002383/0.1616, allocations: 1.184 MB / 0.5757 GB, free: 1.781 MB / 430.1 MB
Notification: Performance of NFFlatten.flatten: time 0.00493/0.1665, allocations: 3.517 MB / 0.5792 GB, free: 14.26 MB / 446.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002728/0.1692, allocations: 2.059 MB / 0.5812 GB, free: 12.15 MB / 446.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001716/0.1709, allocations: 1.156 MB / 0.5823 GB, free: 10.99 MB / 446.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.00227/0.1732, allocations: 1.732 MB / 0.584 GB, free: 9.25 MB / 446.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0005124/0.1737, allocations: 236 kB / 0.5842 GB, free: 9.02 MB / 446.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001314/0.175, allocations: 0.7294 MB / 0.5849 GB, free: 8.289 MB / 446.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001534/0.1766, allocations: 1.601 MB / 0.5865 GB, free: 6.684 MB / 446.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001964/0.1785, allocations: 1.867 MB / 0.5883 GB, free: 4.805 MB / 446.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.2678/0.4463, allocations: 6.14 MB / 0.5943 GB, free: 12.67 MB / 446.1 MB
Notification: Performance of FrontEnd - DAE generated: time 8.236e-06/0.4464, allocations: 1.156 kB / 0.5943 GB, free: 12.67 MB / 446.1 MB
Notification: Performance of FrontEnd: time 2.154e-06/0.4464, allocations: 0 / 0.5943 GB, free: 12.67 MB / 446.1 MB
Notification: Performance of Transformations before backend: time 0.0002393/0.4466, allocations: 0 / 0.5943 GB, free: 12.67 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: 1539
 * Number of variables: 1539
Notification: Performance of Generate backend data structure: time 0.00896/0.4556, allocations: 4.092 MB / 0.5983 GB, free: 10.85 MB / 446.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.901e-05/0.4556, allocations: 9.5 kB / 0.5983 GB, free: 10.85 MB / 446.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001385/0.457, allocations: 0.7182 MB / 0.599 GB, free: 10.77 MB / 446.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01057/0.4676, allocations: 2.993 MB / 0.6019 GB, free: 10.48 MB / 446.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005445/0.473, allocations: 1.815 MB / 0.6037 GB, free: 9.992 MB / 446.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002154/0.4732, allocations: 196.4 kB / 0.6039 GB, free: 9.984 MB / 446.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000916/0.4741, allocations: 251.7 kB / 0.6041 GB, free: 9.98 MB / 446.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01624/0.4904, allocations: 10.04 MB / 0.614 GB, free: 7.113 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 8.278e-05/0.4905, allocations: 29.94 kB / 0.614 GB, free: 7.113 MB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004425/0.4909, allocations: 121.7 kB / 0.6141 GB, free: 7.074 MB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.003658/0.4946, allocations: 2.319 MB / 0.6164 GB, free: 6.695 MB / 446.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01382/0.5084, allocations: 8.919 MB / 0.6251 GB, free: 4.098 MB / 446.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.0508/0.5592, allocations: 37.61 MB / 0.6618 GB, free: 260 kB / 478.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.005889/0.5651, allocations: 3.25 MB / 0.665 GB, free: 12.74 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001764/0.5668, allocations: 1.129 MB / 0.6661 GB, free: 11.6 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.009946/0.5768, allocations: 5.178 MB / 0.6711 GB, free: 6.324 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.471e-05/0.5768, allocations: 47.12 kB / 0.6712 GB, free: 6.27 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=150): time 4.829e-06/0.5768, allocations: 0 / 0.6712 GB, free: 6.27 MB / 494.1 MB
Notification: Performance of matching and sorting (n=150): time 0.007392/0.5842, allocations: 3.349 MB / 0.6744 GB, free: 2.934 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001144/0.5843, allocations: 173.8 kB / 0.6746 GB, free: 2.73 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.006134/0.5905, allocations: 3.837 MB / 0.6784 GB, free: 14.9 MB / 0.4981 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002447/0.5907, allocations: 41.7 kB / 0.6784 GB, free: 14.86 MB / 0.4981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002752/0.5935, allocations: 3.316 MB / 0.6816 GB, free: 11.57 MB / 0.4981 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0008666/0.5943, allocations: 0.6334 MB / 0.6823 GB, free: 10.95 MB / 0.4981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001781/0.5961, allocations: 1.311 MB / 0.6835 GB, free: 9.629 MB / 0.4981 GB
Notification: Performance of setup shared object (initialization): time 0.0001333/0.5962, allocations: 301.1 kB / 0.6838 GB, free: 9.332 MB / 0.4981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003067/0.5993, allocations: 1.961 MB / 0.6857 GB, free: 7.367 MB / 0.4981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.006848/0.6062, allocations: 6.105 MB / 0.6917 GB, free: 15.26 MB / 0.5137 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0121/0.6183, allocations: 8.799 MB / 0.7003 GB, free: 4.992 MB / 0.5137 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001417/0.6184, allocations: 40 kB / 0.7003 GB, free: 4.953 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=849) (initialization): time 0.01507/0.6335, allocations: 7.717 MB / 0.7079 GB, free: 13.5 MB / 0.5294 GB
Notification: Performance of prepare postOptimizeDAE: time 9.677e-05/0.6336, allocations: 73.03 kB / 0.7079 GB, free: 13.43 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001777/0.6337, allocations: 151.9 kB / 0.7081 GB, free: 13.28 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0002069/0.634, allocations: 197.7 kB / 0.7083 GB, free: 13.09 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004348/0.6383, allocations: 1.454 MB / 0.7097 GB, free: 11.63 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0002552/0.6386, allocations: 286.9 kB / 0.71 GB, free: 11.35 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004091/0.6426, allocations: 0.9375 MB / 0.7109 GB, free: 10.41 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005787/0.6432, allocations: 400 kB / 0.7113 GB, free: 10.02 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 673
 * Number of states: 0 ()
 * Number of discrete variables: 23 (freeMotion.arrow.twoHeadedArrow,freeMotion.arrow.headAtOrigin,freeMotion.arrowColor[3],freeMotion.arrowColor[2],freeMotion.arrowColor[1],frame_a.color_x[3],frame_a.color_x[2],frame_a.color_x[1],fixedRotation.color[3],fixedRotation.color[2],fixedRotation.color[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 (791):
 * Single equations (assignments): 783
 * Array equations: 8
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.003421/0.6466, allocations: 2.193 MB / 0.7134 GB, free: 7.816 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005479/0.6472, allocations: 327.4 kB / 0.7137 GB, free: 7.496 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0007768/0.648, allocations: 411.1 kB / 0.7141 GB, free: 7.086 MB / 0.5294 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 6.231e-05/0.648, allocations: 42.64 kB / 0.7141 GB, free: 7.047 MB / 0.5294 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.346e-05/0.648, allocations: 4.562 kB / 0.7142 GB, free: 7.043 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.025e-05/0.6481, allocations: 3.984 kB / 0.7142 GB, free: 7.039 MB / 0.5294 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.009466/0.6575, allocations: 5.462 MB / 0.7195 GB, free: 1.477 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.347e-05/0.6575, allocations: 4 kB / 0.7195 GB, free: 1.473 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0009517/0.6585, allocations: 212 kB / 0.7197 GB, free: 1.266 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.266e-05/0.6585, allocations: 11.94 kB / 0.7197 GB, free: 1.254 MB / 0.5294 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 9.236e-05/0.6586, allocations: 38.59 kB / 0.7197 GB, free: 1.219 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 1.124e-05/0.6586, allocations: 8.594 kB / 0.7198 GB, free: 1.211 MB / 0.5294 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 8.936e-06/0.6586, allocations: 11.94 kB / 0.7198 GB, free: 1.199 MB / 0.5294 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.003215/0.6618, allocations: 2.548 MB / 0.7223 GB, free: 14.57 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0007239/0.6626, allocations: 386.5 kB / 0.7226 GB, free: 14.17 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003077/0.6629, allocations: 38.59 kB / 0.7227 GB, free: 14.13 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0009286/0.6638, allocations: 65.56 kB / 0.7227 GB, free: 14.07 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001667/0.664, allocations: 76.34 kB / 0.7228 GB, free: 14 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 9.781e-05/0.6641, allocations: 46.66 kB / 0.7228 GB, free: 13.95 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.003477/0.6675, allocations: 2.338 MB / 0.7251 GB, free: 11.63 MB / 0.545 GB
Notification: Performance of sort global known variables: time 1.1e-07/0.6675, allocations: 0 / 0.7251 GB, free: 11.63 MB / 0.545 GB
Notification: Performance of remove unused functions: time 0.004477/0.672, allocations: 1.067 MB / 0.7262 GB, free: 10.56 MB / 0.545 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 25
 * Number of states: 0 ()
 * 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 (108):
 * Single equations (assignments): 102
 * Array equations: 6
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.003805/0.6758, allocations: 3.189 MB / 0.7293 GB, free: 7.277 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.006953/0.6828, allocations: 4.27 MB / 0.7334 GB, free: 3.055 MB / 0.545 GB
Notification: Performance of simCode: created event and clocks part: time 5.861e-06/0.6828, allocations: 0 / 0.7334 GB, free: 3.055 MB / 0.545 GB
Notification: Performance of simCode: created simulation system equations: time 0.001259/0.684, allocations: 0.8708 MB / 0.7343 GB, free: 2.18 MB / 0.545 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.001957/0.686, allocations: 345.4 kB / 0.7346 GB, free: 1.852 MB / 0.545 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.06042/0.7464, allocations: 41.8 MB / 0.7755 GB, free: 1.543 MB / 0.5919 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.004877/0.7513, allocations: 3.158 MB / 0.7785 GB, free: 14.36 MB / 0.6075 GB
Notification: Performance of simCode: alias equations: time 0.005358/0.7566, allocations: 2.232 MB / 0.7807 GB, free: 12.12 MB / 0.6075 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.00271/0.7594, allocations: 1.659 MB / 0.7823 GB, free: 10.46 MB / 0.6075 GB
Notification: Performance of SimCode: time 1.263e-06/0.7594, allocations: 0 / 0.7823 GB, free: 10.46 MB / 0.6075 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.1066/0.8659, allocations: 51.28 MB / 0.8324 GB, free: 7.441 MB / 0.6544 GB
Notification: Performance of buildModelFMU: Generate platform static: time 19.37/20.23, allocations: 4 kB / 0.8324 GB, free: 7.438 MB / 0.6544 GB
"
[Timeout remaining time 640]
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.AbsoluteSensor2.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.AbsoluteSensor2.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.AbsoluteSensor2.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.AbsoluteSensor2.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.AbsoluteSensor2_res.mat --tempDir=temp_ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Sensors_AbsoluteSensor2_fmu --startTime=0 --stopTime=1.1 --stepSize=0.000219912 --timeout=50 --tolerance=1e-06 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Sensors_AbsoluteSensor2.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.AbsoluteSensor2.pipe 2>&1) [Timeout 52.5]
[Calling os._exit(0), Time elapsed: 23.540735348127782]
Failed to read output from testmodel.py, exit status != 0:
['time', 'freeMotion.r[3]', 'freeMotion.r[2]', 'freeMotion.r[1]']
20.346730794757605 20.577169308 20.127938504
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fee1c2f6020>