Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_10_Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable.conf.json loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.002054/0.002054, allocations: 118.7 kB / 17.13 MB, free: 5.758 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001409/0.001409, allocations: 191.7 kB / 20.08 MB, free: 2.848 MB / 14.72 MB " [Timeout remaining time 180] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.465/1.465, allocations: 225.6 MB / 248.5 MB, free: 3.59 MB / 190.1 MB " [Timeout remaining time 178] loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo", uses=false) [Timeout 180] "Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo): time 2.644/2.644, allocations: 443.5 MB / 0.7293 GB, free: 14.47 MB / 0.5762 GB " [Timeout remaining time 177] Using package Buildings with version 10.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo) Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo) Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo) Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo) Running command: translateModel(Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|valTab.m_flow|valLin.m_flow",fileNamePrefix="Buildings_10_Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable") translateModel(Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|valTab.m_flow|valLin.m_flow",fileNamePrefix="Buildings_10_Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable") [Timeout 300] "Notification: Performance of FrontEnd - loaded program: time 0.001932/0.001932, allocations: 63.91 kB / 1.057 GB, free: 1.969 MB / 0.795 GB Notification: Performance of FrontEnd - Absyn->SCode: time 0.3401/0.3421, allocations: 160.7 MB / 1.214 GB, free: 0.9375 MB / 0.9512 GB Notification: Performance of NFInst.instantiate(Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable): time 0.02002/0.3621, allocations: 15.66 MB / 1.229 GB, free: 1.207 MB / 0.9669 GB Notification: Performance of NFInst.instExpressions: time 0.007364/0.3694, allocations: 4.342 MB / 1.234 GB, free: 12.85 MB / 0.9825 GB Notification: Performance of NFInst.updateImplicitVariability: time 0.0006908/0.3701, allocations: 15.88 kB / 1.234 GB, free: 12.83 MB / 0.9825 GB Notification: Performance of NFTyping.typeComponents: time 0.001078/0.3712, allocations: 262.4 kB / 1.234 GB, free: 12.57 MB / 0.9825 GB Notification: Performance of NFTyping.typeBindings: time 0.001774/0.373, allocations: 0.6374 MB / 1.234 GB, free: 11.93 MB / 0.9825 GB Notification: Performance of NFTyping.typeClassSections: time 0.002116/0.3751, allocations: 0.7161 MB / 1.235 GB, free: 11.23 MB / 0.9825 GB Notification: Performance of NFFlatten.flatten: time 0.001589/0.3767, allocations: 1.029 MB / 1.236 GB, free: 10.19 MB / 0.9825 GB Notification: Performance of NFFlatten.resolveConnections: time 0.0005313/0.3772, allocations: 287.8 kB / 1.236 GB, free: 9.902 MB / 0.9825 GB Notification: Performance of NFEvalConstants.evaluate: time 0.0008535/0.3781, allocations: 434.4 kB / 1.237 GB, free: 9.477 MB / 0.9825 GB Notification: Performance of NFSimplifyModel.simplify: time 0.0005447/0.3786, allocations: 342.4 kB / 1.237 GB, free: 9.141 MB / 0.9825 GB Notification: Performance of NFPackage.collectConstants: time 8.133e-05/0.3787, allocations: 28 kB / 1.237 GB, free: 9.113 MB / 0.9825 GB Notification: Performance of NFFlatten.collectFunctions: time 0.001393/0.3801, allocations: 0.6317 MB / 1.238 GB, free: 8.48 MB / 0.9825 GB Notification: Performance of NFScalarize.scalarize: time 0.0001433/0.3802, allocations: 111.5 kB / 1.238 GB, free: 8.371 MB / 0.9825 GB Notification: Performance of NFVerifyModel.verify: time 0.0002486/0.3805, allocations: 230.6 kB / 1.238 GB, free: 8.145 MB / 0.9825 GB Notification: Performance of NFConvertDAE.convert: time 0.001253/0.3817, allocations: 0.7627 MB / 1.239 GB, free: 7.379 MB / 0.9825 GB Notification: Performance of FrontEnd - DAE generated: time 5.11e-06/0.3817, allocations: 0 / 1.239 GB, free: 7.379 MB / 0.9825 GB Notification: Performance of FrontEnd: time 1.473e-06/0.3817, allocations: 4 kB / 1.239 GB, free: 7.375 MB / 0.9825 GB Notification: Performance of Transformations before backend: time 8.757e-06/0.3818, allocations: 0 / 1.239 GB, free: 7.375 MB / 0.9825 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 51 * Number of variables: 51 Notification: Performance of Generate backend data structure: time 0.001366/0.3831, allocations: 0.8515 MB / 1.24 GB, free: 6.473 MB / 0.9825 GB Notification: Performance of prepare preOptimizeDAE: time 4.292e-05/0.3832, allocations: 8.031 kB / 1.24 GB, free: 6.465 MB / 0.9825 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.000123/0.3833, allocations: 60.56 kB / 1.24 GB, free: 6.406 MB / 0.9825 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.0006934/0.384, allocations: 425.9 kB / 1.24 GB, free: 5.953 MB / 0.9825 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 2.805e-05/0.384, allocations: 20.44 kB / 1.24 GB, free: 5.934 MB / 0.9825 GB Notification: Performance of preOpt expandDerOperator (simulation): time 5.26e-05/0.3841, allocations: 31.98 kB / 1.24 GB, free: 5.902 MB / 0.9825 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.0004451/0.3845, allocations: 211.2 kB / 1.24 GB, free: 5.688 MB / 0.9825 GB Notification: Performance of preOpt findStateOrder (simulation): time 1.237e-05/0.3845, allocations: 0 / 1.24 GB, free: 5.688 MB / 0.9825 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 2.717e-05/0.3845, allocations: 12 kB / 1.24 GB, free: 5.676 MB / 0.9825 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 9.698e-06/0.3846, allocations: 4 kB / 1.24 GB, free: 5.672 MB / 0.9825 GB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0003862/0.3849, allocations: 165.6 kB / 1.241 GB, free: 5.512 MB / 0.9825 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.001721/0.3867, allocations: 0.946 MB / 1.242 GB, free: 4.484 MB / 0.9825 GB Notification: Performance of preOpt comSubExp (simulation): time 0.00031/0.387, allocations: 126.6 kB / 1.242 GB, free: 4.352 MB / 0.9825 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0001699/0.3871, allocations: 68.81 kB / 1.242 GB, free: 4.281 MB / 0.9825 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0003213/0.3875, allocations: 336.4 kB / 1.242 GB, free: 3.879 MB / 0.9825 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.963e-05/0.3875, allocations: 38.5 kB / 1.242 GB, free: 3.832 MB / 0.9825 GB Notification: Performance of pre-optimization done (n=14): time 1.292e-06/0.3875, allocations: 0 / 1.242 GB, free: 3.832 MB / 0.9825 GB Notification: Performance of matching and sorting (n=14): time 0.0006701/0.3882, allocations: 241.3 kB / 1.242 GB, free: 3.59 MB / 0.9825 GB Notification: Performance of inlineWhenForInitialization (initialization): time 5.144e-05/0.3882, allocations: 63.33 kB / 1.242 GB, free: 3.516 MB / 0.9825 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0005629/0.3888, allocations: 470 kB / 1.243 GB, free: 3.047 MB / 0.9825 GB Notification: Performance of collectPreVariables (initialization): time 3.622e-05/0.3888, allocations: 29.7 kB / 1.243 GB, free: 3.012 MB / 0.9825 GB Notification: Performance of collectInitialEqns (initialization): time 0.0002056/0.389, allocations: 232.8 kB / 1.243 GB, free: 2.777 MB / 0.9825 GB Notification: Performance of collectInitialBindings (initialization): time 4.62e-05/0.3891, allocations: 51.58 kB / 1.243 GB, free: 2.727 MB / 0.9825 GB Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001197/0.3892, allocations: 61.64 kB / 1.243 GB, free: 2.66 MB / 0.9825 GB Notification: Performance of setup shared object (initialization): time 0.0001786/0.3894, allocations: 358.7 kB / 1.244 GB, free: 2.301 MB / 0.9825 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001963/0.3896, allocations: 68.81 kB / 1.244 GB, free: 2.23 MB / 0.9825 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.000297/0.3899, allocations: 161.2 kB / 1.244 GB, free: 2.047 MB / 0.9825 GB Notification: Performance of analyzeInitialSystem (initialization): time 0.0003694/0.3902, allocations: 164.4 kB / 1.244 GB, free: 1.879 MB / 0.9825 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.134e-06/0.3902, allocations: 3.938 kB / 1.244 GB, free: 1.875 MB / 0.9825 GB Notification: Performance of matching and sorting (n=24) (initialization): time 0.0005668/0.3908, allocations: 255.1 kB / 1.244 GB, free: 1.621 MB / 0.9825 GB Notification: Performance of prepare postOptimizeDAE: time 1.771e-05/0.3908, allocations: 4 kB / 1.244 GB, free: 1.617 MB / 0.9825 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.564e-06/0.3908, allocations: 3.984 kB / 1.244 GB, free: 1.613 MB / 0.9825 GB Notification: Performance of postOpt tearingSystem (initialization): time 1.854e-05/0.3908, allocations: 8 kB / 1.244 GB, free: 1.605 MB / 0.9825 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0002355/0.3911, allocations: 43.97 kB / 1.244 GB, free: 1.562 MB / 0.9825 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.025e-05/0.3911, allocations: 7.984 kB / 1.244 GB, free: 1.555 MB / 0.9825 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0003028/0.3914, allocations: 27.97 kB / 1.244 GB, free: 1.527 MB / 0.9825 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 3.651e-05/0.3914, allocations: 15.92 kB / 1.244 GB, free: 1.512 MB / 0.9825 GB Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0003101/0.3917, allocations: 192.1 kB / 1.244 GB, free: 1.32 MB / 0.9825 GB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0002696/0.392, allocations: 163.2 kB / 1.245 GB, free: 1.129 MB / 0.9825 GB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0003595/0.3924, allocations: 183.8 kB / 1.245 GB, free: 0.9375 MB / 0.9825 GB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.072e-06/0.3924, allocations: 0 / 1.245 GB, free: 0.9375 MB / 0.9825 GB Notification: Performance of matching and sorting (n=24) (initialization_lambda0): time 0.0005799/0.3929, allocations: 249.2 kB / 1.245 GB, free: 0.6875 MB / 0.9825 GB Notification: Performance of prepare postOptimizeDAE: time 8.546e-06/0.393, allocations: 8 kB / 1.245 GB, free: 0.6797 MB / 0.9825 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.094e-06/0.393, allocations: 0 / 1.245 GB, free: 0.6797 MB / 0.9825 GB Notification: Performance of postOpt tearingSystem (initialization): time 5.551e-06/0.393, allocations: 4 kB / 1.245 GB, free: 0.6758 MB / 0.9825 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.000207/0.3932, allocations: 48.03 kB / 1.245 GB, free: 0.6289 MB / 0.9825 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 5.09e-06/0.3932, allocations: 7.938 kB / 1.245 GB, free: 0.6211 MB / 0.9825 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0003314/0.3935, allocations: 35.86 kB / 1.245 GB, free: 0.5859 MB / 0.9825 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 2.209e-05/0.3935, allocations: 8 kB / 1.245 GB, free: 0.5781 MB / 0.9825 GB Notification: Model statistics after passing the back-end for initialization: * Number of independent subsystems: 3 * 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 initialization (24): * Single equations (assignments): 24 * Array equations: 0 * 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.000143/0.3937, allocations: 196.1 kB / 1.245 GB, free: 388 kB / 0.9825 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 8.496e-05/0.3938, allocations: 43.91 kB / 1.245 GB, free: 344 kB / 0.9825 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0006173/0.3944, allocations: 261 kB / 1.246 GB, free: 80 kB / 0.9825 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.528e-06/0.3944, allocations: 4 kB / 1.246 GB, free: 76 kB / 0.9825 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 4.499e-06/0.3944, allocations: 0 / 1.246 GB, free: 76 kB / 0.9825 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 6.862e-06/0.3944, allocations: 3.984 kB / 1.246 GB, free: 72 kB / 0.9825 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001132/0.3955, allocations: 0.7045 MB / 1.246 GB, free: 15.3 MB / 0.9981 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.398e-06/0.3955, allocations: 0 / 1.246 GB, free: 15.3 MB / 0.9981 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0001281/0.3957, allocations: 35.92 kB / 1.246 GB, free: 15.26 MB / 0.9981 GB Notification: Performance of postOpt tearingSystem (simulation): time 5.209e-06/0.3957, allocations: 4 kB / 1.246 GB, free: 15.26 MB / 0.9981 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 1.296e-05/0.3957, allocations: 0 / 1.246 GB, free: 15.26 MB / 0.9981 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 6.091e-06/0.3957, allocations: 7.938 kB / 1.246 GB, free: 15.25 MB / 0.9981 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.633e-06/0.3957, allocations: 0 / 1.246 GB, free: 15.25 MB / 0.9981 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0005363/0.3962, allocations: 348.3 kB / 1.247 GB, free: 14.88 MB / 0.9981 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0001521/0.3964, allocations: 132.8 kB / 1.247 GB, free: 14.73 MB / 0.9981 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 5.302e-05/0.3964, allocations: 11.98 kB / 1.247 GB, free: 14.72 MB / 0.9981 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0002135/0.3966, allocations: 15.92 kB / 1.247 GB, free: 14.71 MB / 0.9981 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 5.107e-05/0.3967, allocations: 15.98 kB / 1.247 GB, free: 14.69 MB / 0.9981 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 1.636e-05/0.3967, allocations: 8 kB / 1.247 GB, free: 14.68 MB / 0.9981 GB Notification: Performance of sorting global known variables: time 0.0003977/0.3971, allocations: 338.9 kB / 1.247 GB, free: 14.36 MB / 0.9981 GB Notification: Performance of sort global known variables: time 7e-08/0.3971, allocations: 0 / 1.247 GB, free: 14.36 MB / 0.9981 GB Notification: Performance of remove unused functions: time 0.0006966/0.3978, allocations: 199.6 kB / 1.247 GB, free: 14.16 MB / 0.9981 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 5 * 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 (16): * Single equations (assignments): 14 * Array equations: 0 * Algorithm blocks: 0 * Record equations: 2 * 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.001641/0.3994, allocations: 480.1 kB / 1.248 GB, free: 13.83 MB / 0.9981 GB Notification: Performance of simCode: created initialization part: time 0.0007218/0.4002, allocations: 265.4 kB / 1.248 GB, free: 13.57 MB / 0.9981 GB Notification: Performance of simCode: created event and clocks part: time 2.484e-06/0.4002, allocations: 0 / 1.248 GB, free: 13.57 MB / 0.9981 GB Notification: Performance of simCode: created simulation system equations: time 0.0001989/0.4004, allocations: 83.83 kB / 1.248 GB, free: 13.49 MB / 0.9981 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00101/0.4014, allocations: 195.9 kB / 1.248 GB, free: 13.3 MB / 0.9981 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.001442/0.4028, allocations: 0.7488 MB / 1.249 GB, free: 12.5 MB / 0.9981 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0005366/0.4034, allocations: 0.823 MB / 1.25 GB, free: 11.65 MB / 0.9981 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001032/0.4035, allocations: 43.42 kB / 1.25 GB, free: 11.61 MB / 0.9981 GB Notification: Performance of SimCode: time 4.31e-07/0.4035, allocations: 0 / 1.25 GB, free: 11.61 MB / 0.9981 GB Notification: Performance of Templates: time 0.01225/0.4157, allocations: 7.398 MB / 1.257 GB, free: 4.281 MB / 0.9981 GB " [Timeout remaining time 300] make -j1 -f Buildings_10_Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable.makefile [Timeout 300] (rm -f Buildings_10_Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable.pipe ; mkfifo Buildings_10_Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable.pipe ; head -c 1048576 < Buildings_10_Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable.pipe >> ../files/Buildings_10_Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable.sim & ./Buildings_10_Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable -abortSlowSimulation -alarm=400 -emit_protected -lv LOG_STATS > Buildings_10_Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable.pipe 2>&1) [Timeout 400] diffSimulationResults("Buildings_10_Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_10.0.x/Buildings_Fluid_Actuators_Valves_Examples_TwoWayValvesTable.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork_2/OpenModelicaLibraryTesting/files/Buildings_10_Buildings.Fluid.Actuators.Valves.Examples.TwoWayValvesTable.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300] "" [Timeout remaining time 300] Reference file matches [Calling sys.exit(0), Time elapsed: 12.9574003291782]