Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr AixLib_AixLib.Fluid.Interfaces.Examples.StaticTwoPortConservationEquation.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/package.mo): time 0.06734/0.06734, allocations: 9.129 MB / 28.99 MB, free: 0.8086 MB / 18.57 MB [/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/EmbeddedTargets/STM32F4/Functions/HAL.mo:5:3-11:14:writable] Warning: External function annotation should occur on the external-clause, not on the function. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/OperatingSystem/DynamicArray.mo:6:3-94:18:writable] Warning: External function annotation should occur on the external-clause, not on the function. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1041:3-1062:27:writable] Warning: External function annotation should occur on the external-clause, not on the function. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/Incubate/Utilities/Functions.mo:1064:3-1092:25:writable] Warning: External function annotation should occur on the external-clause, not on the function. " [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.0008769/0.0008769, allocations: 87.28 kB / 35.33 MB, free: 1.074 MB / 24.77 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.001058/0.001058, allocations: 163.6 kB / 38.73 MB, free: 2.508 MB / 24.77 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 0.9775/0.9775, allocations: 177.1 MB / 219.2 MB, free: 10.09 MB / 202.7 MB " [Timeout remaining time 179] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/SDF 0.4.5/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/SDF 0.4.5/package.mo): time 0.004226/0.004226, allocations: 0.8261 MB / 276.5 MB, free: 0.7227 MB / 250.7 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 3.0.1/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 3.0.1/package.mo): time 1.576/1.576, allocations: 274.9 MB / 0.5419 GB, free: 0.625 MB / 0.4949 GB " [Timeout remaining time 178] Using package AixLib with version 3.0.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/AixLib 3.0.1/package.mo) Using package SDF with version 0.4.5 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/SDF 0.4.5/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 Modelica_DeviceDrivers with version 2.2.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica_DeviceDrivers 2.2.0/package.mo) Running command: translateModel(AixLib.Fluid.Interfaces.Examples.StaticTwoPortConservationEquation,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="AixLib_AixLib.Fluid.Interfaces.Examples.StaticTwoPortConservationEquation") translateModel(AixLib.Fluid.Interfaces.Examples.StaticTwoPortConservationEquation,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="AixLib_AixLib.Fluid.Interfaces.Examples.StaticTwoPortConservationEquation") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.543e-06/1.543e-06, allocations: 0 / 0.6378 GB, free: 14.13 MB / 0.6043 GB Notification: Performance of FrontEnd - Absyn->SCode: time 2.365e-05/2.52e-05, allocations: 6.25 kB / 0.6379 GB, free: 14.12 MB / 0.6043 GB Notification: Performance of NFInst.instantiate(AixLib.Fluid.Interfaces.Examples.StaticTwoPortConservationEquation): time 0.4301/0.4302, allocations: 152.9 MB / 0.7871 GB, free: 2.562 MB / 0.6824 GB Notification: Performance of NFInst.instExpressions: time 0.007456/0.4376, allocations: 4.924 MB / 0.792 GB, free: 13.65 MB / 0.698 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.001063/0.4387, allocations: 67.38 kB / 0.792 GB, free: 13.59 MB / 0.698 GB Notification: Performance of NFTyping.typeComponents: time 0.0008604/0.4395, allocations: 424.8 kB / 0.7924 GB, free: 13.17 MB / 0.698 GB Notification: Performance of NFTyping.typeBindings: time 0.002144/0.4417, allocations: 1.263 MB / 0.7937 GB, free: 11.9 MB / 0.698 GB Notification: Performance of NFTyping.typeClassSections: time 0.002382/0.4441, allocations: 1.517 MB / 0.7951 GB, free: 10.4 MB / 0.698 GB Notification: Performance of NFFlatten.flatten: time 0.002971/0.447, allocations: 2.938 MB / 0.798 GB, free: 7.465 MB / 0.698 GB Notification: Performance of NFFlatten.resolveConnections: time 0.0009647/0.448, allocations: 0.7457 MB / 0.7987 GB, free: 6.719 MB / 0.698 GB Notification: Performance of NFEvalConstants.evaluate: time 0.00213/0.4501, allocations: 1.519 MB / 0.8002 GB, free: 5.258 MB / 0.698 GB Notification: Performance of NFSimplifyModel.simplify: time 0.0007438/0.4509, allocations: 0.6386 MB / 0.8008 GB, free: 4.617 MB / 0.698 GB Notification: Performance of NFPackage.collectConstants: time 0.0001386/0.451, allocations: 96 kB / 0.8009 GB, free: 4.523 MB / 0.698 GB Notification: Performance of NFFlatten.collectFunctions: time 0.0009356/0.452, allocations: 0.7172 MB / 0.8016 GB, free: 3.805 MB / 0.698 GB Notification: Performance of NFScalarize.scalarize: time 0.0002532/0.4522, allocations: 390.7 kB / 0.802 GB, free: 3.422 MB / 0.698 GB Notification: Performance of NFVerifyModel.verify: time 0.0003291/0.4525, allocations: 391 kB / 0.8024 GB, free: 3.039 MB / 0.698 GB Notification: Performance of NFConvertDAE.convert: time 0.001398/0.4539, allocations: 1.371 MB / 0.8037 GB, free: 1.664 MB / 0.698 GB Notification: Performance of FrontEnd - DAE generated: time 3.757e-06/0.4539, allocations: 0 / 0.8037 GB, free: 1.664 MB / 0.698 GB Notification: Performance of FrontEnd: time 1.092e-06/0.4539, allocations: 0 / 0.8037 GB, free: 1.664 MB / 0.698 GB Notification: Performance of Transformations before backend: time 1.672e-05/0.454, allocations: 4 kB / 0.8037 GB, free: 1.66 MB / 0.698 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 170 * Number of variables: 170 Notification: Performance of Generate backend data structure: time 0.001748/0.4557, allocations: 1.446 MB / 0.8051 GB, free: 192 kB / 0.698 GB Notification: Performance of prepare preOptimizeDAE: time 3.219e-05/0.4557, allocations: 19.97 kB / 0.8052 GB, free: 172 kB / 0.698 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001072/0.4568, allocations: 464.2 kB / 0.8056 GB, free: 15.71 MB / 0.7137 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.0008346/0.4576, allocations: 0.7178 MB / 0.8063 GB, free: 14.97 MB / 0.7137 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 4.236e-05/0.4577, allocations: 53.31 kB / 0.8063 GB, free: 14.92 MB / 0.7137 GB Notification: Performance of preOpt expandDerOperator (simulation): time 7.85e-05/0.4578, allocations: 68 kB / 0.8064 GB, free: 14.85 MB / 0.7137 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.0008893/0.4587, allocations: 0.7987 MB / 0.8072 GB, free: 13.99 MB / 0.7137 GB Notification: Performance of preOpt findStateOrder (simulation): time 1.376e-05/0.4587, allocations: 0 / 0.8072 GB, free: 13.99 MB / 0.7137 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 5.188e-05/0.4587, allocations: 31.98 kB / 0.8072 GB, free: 13.96 MB / 0.7137 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.916e-05/0.4587, allocations: 29.3 kB / 0.8073 GB, free: 13.93 MB / 0.7137 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0004361/0.4592, allocations: 317.5 kB / 0.8076 GB, free: 13.61 MB / 0.7137 GB Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts. Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.002363/0.4615, allocations: 1.932 MB / 0.8094 GB, free: 11.64 MB / 0.7137 GB Notification: Performance of preOpt comSubExp (simulation): time 0.000482/0.462, allocations: 345.7 kB / 0.8098 GB, free: 11.29 MB / 0.7137 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0001489/0.4622, allocations: 147.4 kB / 0.8099 GB, free: 11.14 MB / 0.7137 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0001115/0.4623, allocations: 40.47 kB / 0.81 GB, free: 11.1 MB / 0.7137 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.0003288/0.4626, allocations: 415.6 kB / 0.8103 GB, free: 10.64 MB / 0.7137 GB Notification: Performance of pre-optimization done (n=57): time 1.543e-06/0.4626, allocations: 0 / 0.8103 GB, free: 10.64 MB / 0.7137 GB Notification: Performance of matching and sorting (n=57): time 0.000696/0.4633, allocations: 0.494 MB / 0.8108 GB, free: 10.12 MB / 0.7137 GB Notification: Performance of inlineWhenForInitialization (initialization): time 5.084e-05/0.4634, allocations: 140.9 kB / 0.811 GB, free: 9.957 MB / 0.7137 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0005233/0.4639, allocations: 0.6911 MB / 0.8116 GB, free: 9.254 MB / 0.7137 GB Notification: Performance of collectPreVariables (initialization): time 4.739e-05/0.4639, allocations: 33.7 kB / 0.8117 GB, free: 9.215 MB / 0.7137 GB Notification: Performance of collectInitialEqns (initialization): time 0.0004047/0.4643, allocations: 350.4 kB / 0.812 GB, free: 8.867 MB / 0.7137 GB Notification: Performance of collectInitialBindings (initialization): time 0.0001005/0.4644, allocations: 198.2 kB / 0.8122 GB, free: 8.672 MB / 0.7137 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001585/0.4646, allocations: 134.2 kB / 0.8123 GB, free: 8.535 MB / 0.7137 GB Notification: Performance of setup shared object (initialization): time 7.687e-05/0.4647, allocations: 325.9 kB / 0.8126 GB, free: 8.211 MB / 0.7137 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001503/0.4648, allocations: 105.7 kB / 0.8127 GB, free: 8.102 MB / 0.7137 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0002852/0.4651, allocations: 0.5284 MB / 0.8132 GB, free: 7.449 MB / 0.7137 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.0003368/0.4654, allocations: 375.4 kB / 0.8136 GB, free: 7.02 MB / 0.7137 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.156e-06/0.4655, allocations: 0 / 0.8136 GB, free: 7.02 MB / 0.7137 GB Notification: Performance of matching and sorting (n=72) (initialization): time 0.000467/0.4659, allocations: 419 kB / 0.814 GB, free: 6.59 MB / 0.7137 GB Notification: Performance of prepare postOptimizeDAE: time 3.123e-05/0.4659, allocations: 41.69 kB / 0.814 GB, free: 6.543 MB / 0.7137 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.811e-06/0.466, allocations: 0 / 0.814 GB, free: 6.543 MB / 0.7137 GB Notification: Performance of postOpt tearingSystem (initialization): time 1.676e-05/0.466, allocations: 11.94 kB / 0.8141 GB, free: 6.531 MB / 0.7137 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0003544/0.4663, allocations: 151.8 kB / 0.8142 GB, free: 6.383 MB / 0.7137 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 8.035e-06/0.4663, allocations: 13.86 kB / 0.8142 GB, free: 6.371 MB / 0.7137 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0003924/0.4667, allocations: 139.5 kB / 0.8143 GB, free: 6.234 MB / 0.7137 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.573e-05/0.4668, allocations: 27.97 kB / 0.8144 GB, free: 6.207 MB / 0.7137 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 14 * Number of states: 0 () * Number of discrete variables: 7 (QSen_flow.nextEventScaled,QSen_flow.nextEvent,$PRE.QSen_flow.nextEvent,QSen_flow.last,QSen_flow.b,QSen_flow.a,$whenCondition1) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (67): * Single equations (assignments): 66 * Array equations: 0 * Algorithm blocks: 1 * 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.0001909/0.467, allocations: 191.5 kB / 0.8146 GB, free: 6.004 MB / 0.7137 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 8.643e-05/0.4671, allocations: 79.92 kB / 0.8146 GB, free: 5.926 MB / 0.7137 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0004914/0.4675, allocations: 451.1 kB / 0.8151 GB, free: 5.473 MB / 0.7137 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.087e-06/0.4676, allocations: 11.92 kB / 0.8151 GB, free: 5.461 MB / 0.7137 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.873e-06/0.4676, allocations: 0 / 0.8151 GB, free: 5.461 MB / 0.7137 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.809e-06/0.4676, allocations: 4 kB / 0.8151 GB, free: 5.457 MB / 0.7137 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001554/0.4691, allocations: 1.656 MB / 0.8167 GB, free: 3.633 MB / 0.7137 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.111e-06/0.4691, allocations: 8 kB / 0.8167 GB, free: 3.625 MB / 0.7137 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0002371/0.4694, allocations: 112.7 kB / 0.8168 GB, free: 3.516 MB / 0.7137 GB Notification: Performance of postOpt tearingSystem (simulation): time 8.386e-06/0.4694, allocations: 8 kB / 0.8168 GB, free: 3.508 MB / 0.7137 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.911e-05/0.4694, allocations: 20.38 kB / 0.8168 GB, free: 3.488 MB / 0.7137 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 9.127e-06/0.4694, allocations: 23.92 kB / 0.8169 GB, free: 3.465 MB / 0.7137 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.372e-06/0.4694, allocations: 11.94 kB / 0.8169 GB, free: 3.453 MB / 0.7137 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0005807/0.47, allocations: 0.9146 MB / 0.8178 GB, free: 2.383 MB / 0.7137 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0002139/0.4702, allocations: 168.7 kB / 0.8179 GB, free: 2.219 MB / 0.7137 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 7.412e-05/0.4703, allocations: 24.48 kB / 0.818 GB, free: 2.195 MB / 0.7137 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.000348/0.4706, allocations: 67.89 kB / 0.818 GB, free: 2.129 MB / 0.7137 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 6.482e-05/0.4707, allocations: 63.73 kB / 0.8181 GB, free: 2.066 MB / 0.7137 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 4.214e-05/0.4707, allocations: 34.53 kB / 0.8181 GB, free: 2.035 MB / 0.7137 GB Notification: Performance of sorting global known variables: time 0.0002373/0.471, allocations: 324.5 kB / 0.8184 GB, free: 1.723 MB / 0.7137 GB Notification: Performance of sort global known variables: time 8e-08/0.471, allocations: 0 / 0.8184 GB, free: 1.723 MB / 0.7137 GB Notification: Performance of remove unused functions: time 0.0008424/0.4718, allocations: 295.4 kB / 0.8187 GB, free: 1.434 MB / 0.7137 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 32 * Number of states: 0 () * Number of discrete variables: 6 ($whenCondition1,QSen_flow.a,QSen_flow.b,QSen_flow.last,QSen_flow.nextEvent,QSen_flow.nextEventScaled) * Number of discrete states: 6 (QSen_flow.nextEvent,QSen_flow.last,QSen_flow.nextEventScaled,QSen_flow.b,QSen_flow.a,$whenCondition1) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (56): * Single equations (assignments): 51 * Array equations: 0 * Algorithm blocks: 1 * Record equations: 4 * 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.0002897/0.4721, allocations: 199.9 kB / 0.8189 GB, free: 1.227 MB / 0.7137 GB Notification: Performance of simCode: created initialization part: time 0.0004498/0.4726, allocations: 435.2 kB / 0.8193 GB, free: 0.7812 MB / 0.7137 GB Notification: Performance of simCode: created event and clocks part: time 1.041e-05/0.4726, allocations: 12 kB / 0.8193 GB, free: 0.7695 MB / 0.7137 GB Notification: Performance of simCode: created simulation system equations: time 0.0002245/0.4728, allocations: 266.6 kB / 0.8196 GB, free: 500 kB / 0.7137 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.0009835/0.4738, allocations: 413.5 kB / 0.82 GB, free: 140 kB / 0.7137 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.001924/0.4757, allocations: 1.752 MB / 0.8217 GB, free: 14.24 MB / 0.7293 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0002108/0.4759, allocations: 212.4 kB / 0.8219 GB, free: 14.01 MB / 0.7293 GB Notification: Performance of simCode: alias equations: time 0.0005696/0.4765, allocations: 155.8 kB / 0.822 GB, free: 13.86 MB / 0.7293 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0008506/0.4773, allocations: 339 kB / 0.8224 GB, free: 13.53 MB / 0.7293 GB Notification: Performance of SimCode: time 8.02e-07/0.4773, allocations: 0 / 0.8224 GB, free: 13.53 MB / 0.7293 GB Notification: Performance of Templates: time 0.02419/0.5015, allocations: 17.99 MB / 0.8399 GB, free: 12.08 MB / 0.7449 GB " [Timeout remaining time 659] make -j1 -f AixLib_AixLib.Fluid.Interfaces.Examples.StaticTwoPortConservationEquation.makefile [Timeout 660] (rm -f AixLib_AixLib.Fluid.Interfaces.Examples.StaticTwoPortConservationEquation.pipe ; mkfifo AixLib_AixLib.Fluid.Interfaces.Examples.StaticTwoPortConservationEquation.pipe ; head -c 1048576 < AixLib_AixLib.Fluid.Interfaces.Examples.StaticTwoPortConservationEquation.pipe >> ../files/AixLib_AixLib.Fluid.Interfaces.Examples.StaticTwoPortConservationEquation.sim & ./AixLib_AixLib.Fluid.Interfaces.Examples.StaticTwoPortConservationEquation -abortSlowSimulation -alarm=240 -lv LOG_STATS > AixLib_AixLib.Fluid.Interfaces.Examples.StaticTwoPortConservationEquation.pipe 2>&1) [Timeout 240] [Calling sys.exit(0), Time elapsed: 5.300214586313814]