Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr ClaRa_ClaRa.Basics.Functions.TestCases.TestLogMean.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo): time 0.01042/0.01042, allocations: 1.771 MB / 18.83 MB, free: 4.332 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001253/0.001253, allocations: 99.7 kB / 22.07 MB, free: 1.09 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.001551/0.001551, allocations: 208.2 kB / 25.44 MB, free: 2.797 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.494/1.494, allocations: 230.6 MB / 259.3 MB, free: 13.1 MB / 206.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/package.mo): time 1.082/1.082, allocations: 130.6 MB / 446.3 MB, free: 14.59 MB / 350.1 MB " [Timeout remaining time 179] Using package ClaRa with version 1.9.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ClaRa 1.9.0/package.mo) Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo) Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo) Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo) Using package SMArtInt with version 0.3.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SMArtInt main/package.mo) Running command: "" <> buildModelFMU(ClaRa.Basics.Functions.TestCases.TestLogMean,fileNamePrefix="ClaRa_ClaRa_Basics_Functions_TestCases_TestLogMean",fmuType="cs",version="2.0",platforms={"static"}) "" <> buildModelFMU(ClaRa.Basics.Functions.TestCases.TestLogMean,fileNamePrefix="ClaRa_ClaRa_Basics_Functions_TestCases_TestLogMean",fmuType="cs",version="2.0",platforms={"static"}) [Timeout 800] "Notification: SMArtInt requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed. Notification: ClaRa requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed. Notification: Automatically loaded package TILMedia 1.9.0 ClaRa due to uses annotation from ClaRa. Notification: TILMedia requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed. Warning: Requested package SMArtInt of version 0.5.1, but this package was already loaded with version 0.3.1. There are no conversion annotations for this version but 0.5.1 is newer than 0.3.1. There is a possibility that 0.3.1 remains backwards compatible, but it is not loaded so OpenModelica cannot verify this. Notification: Performance of FrontEnd - loaded program: time 0.3722/0.3722, allocations: 46.14 MB / 0.6552 GB, free: 30.35 MB / 0.4982 GB Notification: Performance of FrontEnd - Absyn->SCode: time 0.1622/0.5344, allocations: 89.06 MB / 0.7421 GB, free: 2.633 MB / 0.545 GB Notification: Performance of NFInst.instantiate(ClaRa.Basics.Functions.TestCases.TestLogMean): time 0.003451/0.5379, allocations: 2.283 MB / 0.7444 GB, free: 344 kB / 0.545 GB Notification: Performance of NFInst.instExpressions: time 0.001741/0.5396, allocations: 0.9812 MB / 0.7453 GB, free: 15.35 MB / 0.5607 GB Notification: Performance of NFInst.updateImplicitVariability: time 7.587e-05/0.5397, allocations: 4 kB / 0.7453 GB, free: 15.35 MB / 0.5607 GB Notification: Performance of NFTyping.typeComponents: time 0.0001487/0.5398, allocations: 67.77 kB / 0.7454 GB, free: 15.28 MB / 0.5607 GB Notification: Performance of NFTyping.typeBindings: time 0.0004848/0.5403, allocations: 171.3 kB / 0.7455 GB, free: 15.11 MB / 0.5607 GB Notification: Performance of NFTyping.typeClassSections: time 0.0005297/0.5409, allocations: 199.2 kB / 0.7457 GB, free: 14.92 MB / 0.5607 GB Notification: Performance of NFFlatten.flatten: time 0.0002894/0.5411, allocations: 227.5 kB / 0.746 GB, free: 14.7 MB / 0.5607 GB Notification: Performance of NFFlatten.resolveConnections: time 5.538e-05/0.5412, allocations: 17.55 kB / 0.746 GB, free: 14.68 MB / 0.5607 GB Notification: Performance of NFEvalConstants.evaluate: time 0.000281/0.5415, allocations: 139.5 kB / 0.7461 GB, free: 14.54 MB / 0.5607 GB Notification: Performance of NFSimplifyModel.simplify: time 0.000252/0.5417, allocations: 175.5 kB / 0.7463 GB, free: 14.37 MB / 0.5607 GB Notification: Performance of NFPackage.collectConstants: time 3.585e-05/0.5418, allocations: 12 kB / 0.7463 GB, free: 14.36 MB / 0.5607 GB Notification: Performance of NFFlatten.collectFunctions: time 0.0005125/0.5423, allocations: 335.5 kB / 0.7466 GB, free: 14.03 MB / 0.5607 GB Notification: Performance of NFScalarize.scalarize: time 8.732e-05/0.5424, allocations: 55.8 kB / 0.7467 GB, free: 13.98 MB / 0.5607 GB Notification: Performance of NFVerifyModel.verify: time 0.0001059/0.5425, allocations: 91.39 kB / 0.7467 GB, free: 13.89 MB / 0.5607 GB Notification: Performance of NFConvertDAE.convert: time 0.0004621/0.5429, allocations: 350.8 kB / 0.7471 GB, free: 13.54 MB / 0.5607 GB Notification: Performance of FrontEnd - DAE generated: time 3.687e-06/0.5429, allocations: 4 kB / 0.7471 GB, free: 13.54 MB / 0.5607 GB Notification: Performance of FrontEnd: time 1.243e-06/0.5429, allocations: 0 / 0.7471 GB, free: 13.54 MB / 0.5607 GB Notification: Performance of Transformations before backend: time 5.36e-06/0.5429, allocations: 0 / 0.7471 GB, free: 13.54 MB / 0.5607 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 31 * Number of variables: 31 Notification: Performance of Generate backend data structure: time 0.001494/0.5444, allocations: 0.673 MB / 0.7477 GB, free: 12.8 MB / 0.5607 GB Notification: Performance of prepare preOptimizeDAE: time 3.722e-05/0.5445, allocations: 8.031 kB / 0.7477 GB, free: 12.8 MB / 0.5607 GB Notification: Performance of preOpt introduceOutputRealDerivatives (simulation): time 1.848e-05/0.5445, allocations: 4.188 kB / 0.7477 GB, free: 12.79 MB / 0.5607 GB Notification: Performance of preOpt introduceOutputAliases (simulation): time 5.382e-05/0.5445, allocations: 50.61 kB / 0.7478 GB, free: 12.73 MB / 0.5607 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 7.982e-05/0.5446, allocations: 24.8 kB / 0.7478 GB, free: 12.71 MB / 0.5607 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.0003425/0.545, allocations: 240 kB / 0.748 GB, free: 12.44 MB / 0.5607 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 1.709e-05/0.545, allocations: 8.375 kB / 0.7481 GB, free: 12.43 MB / 0.5607 GB Notification: Performance of preOpt expandDerOperator (simulation): time 4.324e-05/0.545, allocations: 19.88 kB / 0.7481 GB, free: 12.41 MB / 0.5607 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.0008971/0.5459, allocations: 308 kB / 0.7484 GB, free: 12.09 MB / 0.5607 GB Notification: Performance of preOpt findStateOrder (simulation): time 9.568e-06/0.5459, allocations: 0 / 0.7484 GB, free: 12.09 MB / 0.5607 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 2.769e-05/0.546, allocations: 4 kB / 0.7484 GB, free: 12.09 MB / 0.5607 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.013e-06/0.546, allocations: 4 kB / 0.7484 GB, free: 12.09 MB / 0.5607 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001337/0.5473, allocations: 391.5 kB / 0.7488 GB, free: 11.7 MB / 0.5607 GB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.00112/0.5484, allocations: 0.674 MB / 0.7494 GB, free: 10.97 MB / 0.5607 GB Notification: Performance of preOpt comSubExp (simulation): time 0.0009204/0.5493, allocations: 276 kB / 0.7497 GB, free: 10.67 MB / 0.5607 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0006861/0.55, allocations: 198.7 kB / 0.7499 GB, free: 10.47 MB / 0.5607 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0002541/0.5503, allocations: 109 kB / 0.75 GB, free: 10.34 MB / 0.5607 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0008612/0.5511, allocations: 345.1 kB / 0.7503 GB, free: 9.973 MB / 0.5607 GB Notification: Performance of pre-optimization done (n=32): time 1.593e-06/0.5512, allocations: 0 / 0.7503 GB, free: 9.973 MB / 0.5607 GB Notification: Performance of matching and sorting (n=32): time 0.002368/0.5535, allocations: 0.7294 MB / 0.751 GB, free: 9.227 MB / 0.5607 GB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001082/0.5536, allocations: 166.5 kB / 0.7512 GB, free: 9.027 MB / 0.5607 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0002238/0.5539, allocations: 227.5 kB / 0.7514 GB, free: 8.797 MB / 0.5607 GB Notification: Performance of collectPreVariables (initialization): time 4.115e-05/0.5539, allocations: 33.7 kB / 0.7514 GB, free: 8.758 MB / 0.5607 GB Notification: Performance of collectInitialEqns (initialization): time 9.147e-05/0.554, allocations: 116.6 kB / 0.7515 GB, free: 8.637 MB / 0.5607 GB Notification: Performance of collectInitialBindings (initialization): time 9.458e-05/0.5541, allocations: 99.2 kB / 0.7516 GB, free: 8.539 MB / 0.5607 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001215/0.5542, allocations: 61.67 kB / 0.7517 GB, free: 8.473 MB / 0.5607 GB Notification: Performance of setup shared object (initialization): time 0.0001562/0.5544, allocations: 334.8 kB / 0.752 GB, free: 8.137 MB / 0.5607 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.000641/0.555, allocations: 167.9 kB / 0.7522 GB, free: 7.973 MB / 0.5607 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0006612/0.5557, allocations: 197.4 kB / 0.7523 GB, free: 7.77 MB / 0.5607 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.001319/0.557, allocations: 472.6 kB / 0.7528 GB, free: 7.273 MB / 0.5607 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.438e-06/0.557, allocations: 3.938 kB / 0.7528 GB, free: 7.27 MB / 0.5607 GB Notification: Performance of matching and sorting (n=35) (initialization): time 0.001604/0.5586, allocations: 0.5669 MB / 0.7534 GB, free: 6.684 MB / 0.5607 GB Notification: Performance of prepare postOptimizeDAE: time 7.276e-05/0.5587, allocations: 75.38 kB / 0.7534 GB, free: 6.598 MB / 0.5607 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.961e-06/0.5587, allocations: 0 / 0.7534 GB, free: 6.598 MB / 0.5607 GB Notification: Performance of postOpt tearingSystem (initialization): time 1.731e-05/0.5587, allocations: 4 kB / 0.7534 GB, free: 6.594 MB / 0.5607 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.000437/0.5591, allocations: 63.92 kB / 0.7535 GB, free: 6.531 MB / 0.5607 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 9.177e-06/0.5591, allocations: 7.938 kB / 0.7535 GB, free: 6.523 MB / 0.5607 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0003515/0.5595, allocations: 43.94 kB / 0.7535 GB, free: 6.48 MB / 0.5607 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.28e-05/0.5595, allocations: 19.98 kB / 0.7536 GB, free: 6.461 MB / 0.5607 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 4 * Number of states: 0 () * Number of discrete variables: 14 (rampT_out2.nextEventScaled,rampT_out2.nextEvent,$PRE.rampT_out2.nextEvent,rampT_out2.last,rampT_out2.b,rampT_out2.a,rampT_in.nextEventScaled,rampT_in.nextEvent,$PRE.rampT_in.nextEvent,rampT_in.last,rampT_in.b,rampT_in.a,$whenCondition2,$whenCondition1) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (25): * Single equations (assignments): 23 * Array equations: 0 * Algorithm blocks: 2 * 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.0002295/0.5598, allocations: 221.1 kB / 0.7538 GB, free: 6.223 MB / 0.5607 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 6.652e-05/0.5598, allocations: 27.86 kB / 0.7538 GB, free: 6.195 MB / 0.5607 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001567/0.5614, allocations: 0.6404 MB / 0.7544 GB, free: 5.539 MB / 0.5607 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.505e-06/0.5614, allocations: 4 kB / 0.7544 GB, free: 5.535 MB / 0.5607 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 4.548e-06/0.5614, allocations: 4 kB / 0.7544 GB, free: 5.531 MB / 0.5607 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 7.203e-06/0.5614, allocations: 7.922 kB / 0.7544 GB, free: 5.523 MB / 0.5607 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0003866/0.5618, allocations: 341 kB / 0.7548 GB, free: 5.141 MB / 0.5607 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.112e-06/0.5618, allocations: 4 kB / 0.7548 GB, free: 5.137 MB / 0.5607 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0004439/0.5622, allocations: 75.86 kB / 0.7548 GB, free: 5.062 MB / 0.5607 GB Notification: Performance of postOpt tearingSystem (simulation): time 3.196e-06/0.5622, allocations: 0 / 0.7548 GB, free: 5.062 MB / 0.5607 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 4.187e-05/0.5623, allocations: 19.97 kB / 0.7549 GB, free: 5.043 MB / 0.5607 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 4.438e-06/0.5623, allocations: 4 kB / 0.7549 GB, free: 5.039 MB / 0.5607 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.363e-06/0.5623, allocations: 4 kB / 0.7549 GB, free: 5.035 MB / 0.5607 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001714/0.564, allocations: 0.6774 MB / 0.7555 GB, free: 4.32 MB / 0.5607 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0001664/0.5642, allocations: 148.7 kB / 0.7557 GB, free: 4.156 MB / 0.5607 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 6.359e-05/0.5642, allocations: 12 kB / 0.7557 GB, free: 4.145 MB / 0.5607 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0003408/0.5646, allocations: 27.97 kB / 0.7557 GB, free: 4.117 MB / 0.5607 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 9.492e-05/0.5647, allocations: 36.12 kB / 0.7557 GB, free: 4.082 MB / 0.5607 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.91e-05/0.5647, allocations: 27.91 kB / 0.7558 GB, free: 4.055 MB / 0.5607 GB Notification: Performance of sorting global known variables: time 0.0001479/0.5649, allocations: 156.6 kB / 0.7559 GB, free: 3.902 MB / 0.5607 GB Notification: Performance of sort global known variables: time 9e-08/0.5649, allocations: 0 / 0.7559 GB, free: 3.902 MB / 0.5607 GB Notification: Performance of remove unused functions: time 0.0004127/0.5653, allocations: 103.9 kB / 0.756 GB, free: 3.801 MB / 0.5607 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 3 * Number of states: 0 () * Number of discrete variables: 14 ($cse7,$cse8,$whenCondition1,$whenCondition2,rampT_in.a,rampT_in.b,rampT_in.last,rampT_in.nextEvent,rampT_in.nextEventScaled,rampT_out2.a,rampT_out2.b,rampT_out2.last,rampT_out2.nextEvent,rampT_out2.nextEventScaled) * Number of discrete states: 12 (rampT_out2.nextEvent,rampT_out2.last,rampT_out2.nextEventScaled,rampT_out2.b,rampT_out2.a,$whenCondition1,rampT_in.nextEvent,rampT_in.last,rampT_in.nextEventScaled,rampT_in.b,rampT_in.a,$whenCondition2) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (30): * Single equations (assignments): 28 * Array equations: 0 * Algorithm blocks: 2 * 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.002231/0.5675, allocations: 1.278 MB / 0.7573 GB, free: 2.473 MB / 0.5607 GB Notification: Performance of simCode: created initialization part: time 0.001563/0.5691, allocations: 0.5309 MB / 0.7578 GB, free: 1.918 MB / 0.5607 GB Notification: Performance of simCode: created event and clocks part: time 4.108e-06/0.5691, allocations: 4.188 kB / 0.7578 GB, free: 1.914 MB / 0.5607 GB Notification: Performance of simCode: created simulation system equations: time 0.0007926/0.5699, allocations: 343 kB / 0.7581 GB, free: 1.555 MB / 0.5607 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0003036/0.5702, allocations: 43.81 kB / 0.7582 GB, free: 1.512 MB / 0.5607 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.006342/0.5765, allocations: 3.228 MB / 0.7613 GB, free: 14.15 MB / 0.5763 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0003874/0.5769, allocations: 0.7562 MB / 0.762 GB, free: 13.36 MB / 0.5763 GB Notification: Performance of simCode: alias equations: time 0.0001772/0.5771, allocations: 41.53 kB / 0.7621 GB, free: 13.32 MB / 0.5763 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001792/0.5773, allocations: 83.38 kB / 0.7622 GB, free: 13.24 MB / 0.5763 GB Notification: Performance of SimCode: time 5.51e-07/0.5773, allocations: 0 / 0.7622 GB, free: 13.24 MB / 0.5763 GB Notification: Performance of buildModelFMU: Generate the FMI files: time 0.02376/0.601, allocations: 6.66 MB / 0.7687 GB, free: 6.664 MB / 0.5763 GB Notification: Performance of buildModelFMU: Generate platform static: time 8.645/9.246, allocations: 11.92 kB / 0.7687 GB, free: 6.652 MB / 0.5763 GB " [Timeout remaining time 791] (rm -f ClaRa_ClaRa.Basics.Functions.TestCases.TestLogMean.pipe ; mkfifo ClaRa_ClaRa.Basics.Functions.TestCases.TestLogMean.pipe ; head -c 1048576 < ClaRa_ClaRa.Basics.Functions.TestCases.TestLogMean.pipe >> ../files/ClaRa_ClaRa.Basics.Functions.TestCases.TestLogMean.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=ClaRa_ClaRa.Basics.Functions.TestCases.TestLogMean_res.mat --tempDir=temp_ClaRa_ClaRa_Basics_Functions_TestCases_TestLogMean_fmu --startTime=0 --stopTime=20 --stepSize=0.00399361 --timeout=800 --tolerance=1e-06 ClaRa_ClaRa_Basics_Functions_TestCases_TestLogMean.fmu > ClaRa_ClaRa.Basics.Functions.TestCases.TestLogMean.pipe 2>&1) [Timeout 840.0] [Calling os._exit(0), Time elapsed: 12.982178979029413] Failed to read output from testmodel.py, exit status != 0: 9.376772116986103 9.400273193 8.85304012 Calling exit ...