Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.Distance.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo", uses=false)
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.Distance,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Sensors_Distance",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(ModelicaTest.MultiBody.Sensors.Distance,fileNamePrefix="ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Sensors_Distance",fmuType="me",version="2.0",platforms={"static"})
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices trunk/package.mo): time 0.001558/0.001558, allocations: 118.2 kB / 17.78 MB, free: 5.258 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex trunk/package.mo): time 0.001294/0.001294, allocations: 205.2 kB / 18.74 MB, free: 4.312 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica trunk/package.mo): time 1.424/1.424, allocations: 227.4 MB / 247 MB, free: 10.41 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaTest 4.1.0-beta.om/package.mo): time 0.2177/0.2177, allocations: 46.12 MB / 344.4 MB, free: 11.45 MB / 286.1 MB
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.002343/0.002343, allocations: 79.86 kB / 480.9 MB, free: 4.535 MB / 350.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3128/0.3152, allocations: 62.44 MB / 0.5306 GB, free: 13.39 MB / 398.1 MB
Notification: Performance of NFInst.instantiate(ModelicaTest.MultiBody.Sensors.Distance): time 0.01082/0.326, allocations: 8.927 MB / 0.5393 GB, free: 6.395 MB / 398.1 MB
Notification: Performance of NFInst.instExpressions: time 0.007285/0.3334, allocations: 3.542 MB / 0.5428 GB, free: 3.648 MB / 398.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001248/0.3346, allocations: 47.72 kB / 0.5428 GB, free: 3.641 MB / 398.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.003316/0.338, allocations: 1.667 MB / 0.5444 GB, free: 2.512 MB / 398.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.00403/0.3421, allocations: 1.536 MB / 0.5459 GB, free: 1.449 MB / 398.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001319/0.3434, allocations: 0.5525 MB / 0.5465 GB, free: 1.09 MB / 398.1 MB
Notification: Performance of NFFlatten.flatten: time 0.005175/0.3486, allocations: 3.576 MB / 0.55 GB, free: 13.87 MB / 414.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002692/0.3513, allocations: 2.197 MB / 0.5521 GB, free: 11.62 MB / 414.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.001599/0.3529, allocations: 1.09 MB / 0.5532 GB, free: 10.52 MB / 414.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002108/0.355, allocations: 1.534 MB / 0.5547 GB, free: 8.984 MB / 414.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0004881/0.3555, allocations: 220 kB / 0.5549 GB, free: 8.77 MB / 414.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002022/0.3576, allocations: 1.1 MB / 0.556 GB, free: 7.668 MB / 414.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.001428/0.359, allocations: 1.399 MB / 0.5573 GB, free: 6.266 MB / 414.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.001892/0.3609, allocations: 1.677 MB / 0.559 GB, free: 4.578 MB / 414.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.006611/0.3675, allocations: 5.58 MB / 0.5644 GB, free: 14.98 MB / 430.1 MB
Notification: Performance of FrontEnd - DAE generated: time 4.698e-06/0.3676, allocations: 0 / 0.5644 GB, free: 14.98 MB / 430.1 MB
Notification: Performance of FrontEnd: time 3.857e-06/0.3676, allocations: 0 / 0.5644 GB, free: 14.98 MB / 430.1 MB
Notification: Performance of Transformations before backend: time 0.0001027/0.3677, allocations: 3.938 kB / 0.5644 GB, free: 14.97 MB / 430.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1141
 * Number of variables: 1141
Notification: Performance of Generate backend data structure: time 0.009025/0.3767, allocations: 3.885 MB / 0.5682 GB, free: 11.01 MB / 430.1 MB
Notification: Performance of prepare preOptimizeDAE: time 4.122e-05/0.3768, allocations: 8.031 kB / 0.5682 GB, free: 11 MB / 430.1 MB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0008089/0.3776, allocations: 0.5423 MB / 0.5688 GB, free: 10.45 MB / 430.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.09456/0.4722, allocations: 6.939 MB / 0.5755 GB, free: 3.5 MB / 430.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00544/0.4776, allocations: 2.405 MB / 0.5779 GB, free: 1.043 MB / 430.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001446/0.4778, allocations: 160.5 kB / 0.578 GB, free: 0.8828 MB / 430.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0006547/0.4785, allocations: 219.8 kB / 0.5782 GB, free: 0.668 MB / 430.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01185/0.4903, allocations: 7.359 MB / 0.5854 GB, free: 8.684 MB / 446.1 MB
Notification: Performance of preOpt findStateOrder (simulation): time 9.422e-05/0.4905, allocations: 39.7 kB / 0.5855 GB, free: 8.645 MB / 446.1 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003411/0.4908, allocations: 103.9 kB / 0.5856 GB, free: 8.543 MB / 446.1 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.002653/0.4935, allocations: 1.543 MB / 0.5871 GB, free: 7 MB / 446.1 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01183/0.5053, allocations: 6.174 MB / 0.5931 GB, free: 0.8125 MB / 446.1 MB
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.03639/0.5418, allocations: 29.92 MB / 0.6223 GB, free: 1.969 MB / 478.1 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.004659/0.5465, allocations: 2.179 MB / 0.6244 GB, free: 15.64 MB / 494.1 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001471/0.548, allocations: 1.077 MB / 0.6255 GB, free: 14.56 MB / 494.1 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003921/0.5484, allocations: 315.6 kB / 0.6258 GB, free: 14.2 MB / 494.1 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.335e-05/0.5484, allocations: 54.98 kB / 0.6259 GB, free: 14.13 MB / 494.1 MB
Notification: Performance of pre-optimization done (n=108): time 3.307e-06/0.5484, allocations: 0 / 0.6259 GB, free: 14.13 MB / 494.1 MB
Notification: Performance of matching and sorting (n=132): time 0.03438/0.5828, allocations: 11.05 MB / 0.6366 GB, free: 2.969 MB / 494.1 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001346/0.583, allocations: 188.6 kB / 0.6368 GB, free: 2.742 MB / 494.1 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005614/0.5886, allocations: 3.622 MB / 0.6404 GB, free: 15.12 MB / 0.4981 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002498/0.5889, allocations: 41.7 kB / 0.6404 GB, free: 15.07 MB / 0.4981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002112/0.591, allocations: 2.671 MB / 0.643 GB, free: 12.38 MB / 0.4981 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0006743/0.5917, allocations: 0.5506 MB / 0.6435 GB, free: 11.82 MB / 0.4981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001444/0.5932, allocations: 1.06 MB / 0.6446 GB, free: 10.75 MB / 0.4981 GB
Notification: Performance of setup shared object (initialization): time 0.000128/0.5933, allocations: 301.1 kB / 0.6449 GB, free: 10.45 MB / 0.4981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002531/0.5958, allocations: 1.626 MB / 0.6465 GB, free: 8.82 MB / 0.4981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005262/0.6011, allocations: 4.965 MB / 0.6513 GB, free: 2.168 MB / 0.4981 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.008926/0.6101, allocations: 7.17 MB / 0.6583 GB, free: 9.34 MB / 0.5137 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 8.04e-05/0.6102, allocations: 32 kB / 0.6583 GB, free: 9.309 MB / 0.5137 GB
Notification: Performance of matching and sorting (n=675) (initialization): time 0.01166/0.6218, allocations: 6.575 MB / 0.6648 GB, free: 2.727 MB / 0.5137 GB
Notification: Performance of prepare postOptimizeDAE: time 8.396e-05/0.622, allocations: 56 kB / 0.6648 GB, free: 2.672 MB / 0.5137 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0001361/0.6221, allocations: 120.2 kB / 0.6649 GB, free: 2.555 MB / 0.5137 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0009566/0.6231, allocations: 400.5 kB / 0.6653 GB, free: 2.16 MB / 0.5137 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003605/0.6267, allocations: 1.189 MB / 0.6665 GB, free: 0.9688 MB / 0.5137 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004052/0.6308, allocations: 3.318 MB / 0.6697 GB, free: 13.51 MB / 0.5294 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003438/0.6342, allocations: 283.7 kB / 0.67 GB, free: 13.23 MB / 0.5294 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0005647/0.6348, allocations: 324 kB / 0.6703 GB, free: 12.92 MB / 0.5294 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 539
 * Number of states: 0 ()
 * Number of discrete variables: 29 (Distance1.arrow.twoHeadedArrow,Distance1.arrow.headAtOrigin,BodyBox1.color[3],BodyBox1.color[2],BodyBox1.color[1],BodyBox1.body.sphereColor[3],BodyBox1.body.sphereColor[2],BodyBox1.body.sphereColor[1],Distance1.arrowColor[3],Distance1.arrowColor[2],Distance1.arrowColor[1],fixed.color[3],fixed.color[2],fixed.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 (640):
 * Single equations (assignments): 636
 * 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 prepare postOptimizeDAE: time 0.002577/0.6374, allocations: 1.704 MB / 0.672 GB, free: 11.2 MB / 0.5294 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005644/0.638, allocations: 313.9 kB / 0.6723 GB, free: 10.89 MB / 0.5294 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003203/0.6412, allocations: 1.717 MB / 0.6739 GB, free: 9.164 MB / 0.5294 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.114e-05/0.6412, allocations: 16 kB / 0.6739 GB, free: 9.148 MB / 0.5294 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.027e-05/0.6412, allocations: 3.938 kB / 0.674 GB, free: 9.145 MB / 0.5294 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.297e-05/0.6413, allocations: 16.06 kB / 0.674 GB, free: 9.129 MB / 0.5294 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.009281/0.6506, allocations: 5.243 MB / 0.6791 GB, free: 3.797 MB / 0.5294 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.539e-05/0.6506, allocations: 8 kB / 0.6791 GB, free: 3.789 MB / 0.5294 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0009886/0.6516, allocations: 187.7 kB / 0.6793 GB, free: 3.605 MB / 0.5294 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0007098/0.6523, allocations: 236.6 kB / 0.6795 GB, free: 3.371 MB / 0.5294 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 9.682e-05/0.6524, allocations: 27.97 kB / 0.6795 GB, free: 3.344 MB / 0.5294 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.003237/0.6557, allocations: 2.68 MB / 0.6821 GB, free: 0.5156 MB / 0.5294 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.604e-06/0.6557, allocations: 7.969 kB / 0.6822 GB, free: 0.5078 MB / 0.5294 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.00574/0.6614, allocations: 3.744 MB / 0.6858 GB, free: 12.73 MB / 0.545 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0008718/0.6624, allocations: 362.9 kB / 0.6862 GB, free: 12.36 MB / 0.545 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003337/0.6627, allocations: 20 kB / 0.6862 GB, free: 12.34 MB / 0.545 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00111/0.6638, allocations: 63.92 kB / 0.6862 GB, free: 12.28 MB / 0.545 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000212/0.664, allocations: 68.5 kB / 0.6863 GB, free: 12.21 MB / 0.545 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001048/0.6642, allocations: 48.03 kB / 0.6863 GB, free: 12.16 MB / 0.545 GB
Notification: Performance of sorting global known variables: time 0.003626/0.6678, allocations: 2.257 MB / 0.6886 GB, free: 9.918 MB / 0.545 GB
Notification: Performance of sort global known variables: time 2.31e-07/0.6678, allocations: 0 / 0.6886 GB, free: 9.918 MB / 0.545 GB
Notification: Performance of remove unused functions: time 0.003902/0.6717, allocations: 0.7616 MB / 0.6893 GB, free: 9.156 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 (102):
 * Single equations (assignments): 98
 * 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,8,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.006006/0.6777, allocations: 4.37 MB / 0.6936 GB, free: 4.75 MB / 0.545 GB
Notification: Performance of simCode: created initialization part: time 0.006838/0.6846, allocations: 3.936 MB / 0.6974 GB, free: 0.8242 MB / 0.545 GB
Notification: Performance of simCode: created event and clocks part: time 6.662e-06/0.6846, allocations: 0 / 0.6974 GB, free: 0.8242 MB / 0.545 GB
Notification: Performance of simCode: created simulation system equations: time 0.001622/0.6863, allocations: 1.072 MB / 0.6985 GB, free: 15.74 MB / 0.5606 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00219/0.6885, allocations: 367 kB / 0.6988 GB, free: 15.38 MB / 0.5606 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.2747/0.9632, allocations: 36.54 MB / 0.7345 GB, free: 172.3 MB / 0.5762 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.002525/0.9657, allocations: 2.643 MB / 0.7371 GB, free: 171.2 MB / 0.5762 GB
Notification: Performance of simCode: alias equations: time 0.00342/0.9692, allocations: 1.697 MB / 0.7387 GB, free: 171.1 MB / 0.5762 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001645/0.9708, allocations: 1.408 MB / 0.7401 GB, free: 170 MB / 0.5762 GB
Notification: Performance of SimCode: time 9.92e-07/0.9709, allocations: 3.844 kB / 0.7401 GB, free: 170 MB / 0.5762 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.08683/1.058, allocations: 53.33 MB / 0.7922 GB, free: 121.4 MB / 0.5762 GB
Notification: Performance of buildModelFMU: Generate platform static: time 22.98/24.03, allocations: 15.17 kB / 0.7922 GB, free: 121.4 MB / 0.5762 GB
(rm -f ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.Distance.pipe ; mkfifo ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.Distance.pipe ; head -c 1048576 < ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.Distance.pipe >> ../files/ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.Distance.sim & python3 -m fmpy simulate  --output-file ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.Distance_res.csv --start-time 0 --stop-time 5 --timeout 50 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 0.002 ModelicaTest_4_1_0-beta_om_ModelicaTest_MultiBody_Sensors_Distance.fmu > ModelicaTest_4.1.0-beta.om_ModelicaTest.MultiBody.Sensors.Distance.pipe 2>&1)