基本
文件
流程
慢流程
错误
SQL
慢sql
重复sql
调试
- 请求信息 : 2025-12-24 19:02:09 HTTP/1.1 GET : ly20240122.test3.shoppaas.co/blog/a/lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall.html
- 运行时间 : 0.150009s [ 吞吐率:6.67req/s ] 内存消耗:2,440.47kb 文件加载:249
- 查询信息 : [耗时:0.036776s/0.150009 24.52% ] 24 queries ,5 writes
- 缓存信息 : 8 reads,2 writes
- 配置加载 : 83
- 会话信息 : SESSION_ID=c5n89fccor83d6o637j6sv6ri5
- [ BEHAVIOR ] Run Closure @app_init [ RunTime:0.000016s ]
- [ CACHE ] INIT app\common\library\cache\driver\Redis
- [ BEHAVIOR ] Run \addons\aws\Aws @app_init [ RunTime:0.000413s ]
- [ BEHAVIOR ] Run \addons\listener\Listener @app_init [ RunTime:0.000063s ]
- [ BEHAVIOR ] Run Closure @app_init [ RunTime:0.000076s ]
- [ BEHAVIOR ] Run app\common\behavior\Common @app_dispatch [ RunTime:0.000078s ]
- [ ROUTE ] array (
'type' => 'module',
'module' =>
array (
0 => 'index',
1 => 'blog',
2 => 'view',
),
'convert' => false,
)
- [ HEADER ] array (
'x-forwarded-proto' => 'https',
'x-forwarded-host' => 'ly20240122.test3.shoppaas.co',
'x-forwarded-for' => '216.73.216.152',
'accept-encoding' => 'gzip, br, zstd, deflate',
'accept' => '*/*',
'user-agent' => 'Mozilla/5.0 AppleWebKit/537.36 (KHTML, like Gecko; compatible; ClaudeBot/1.0; +claudebot@anthropic.com)',
'host' => 'ly20240122.test3.shoppaas.co',
'content-length' => '',
'content-type' => '',
)
- [ PARAM ] array (
'path' => 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall',
)
- [ LANG ] /wwwroot/public/../application/index/lang/en-us.php
- [ BEHAVIOR ] Run app\common\behavior\Common @module_init [ RunTime:0.004547s ]
- [ BEHAVIOR ] Run \addons\aws\Aws @module_init [ RunTime:0.000034s ]
- [ DB ] INIT mysql
- [ BEHAVIOR ] Run \addons\aws\Aws @upload_config_init [ RunTime:0.000030s ]
- [ BEHAVIOR ] Run \addons\nkeditor\Nkeditor @config_init [ RunTime:0.000096s ]
- [ SESSION ] INIT array (
'id' => '',
'var_session_id' => '',
'session_name' => 'shoppaas_1705904057568:',
'type' => 'redis',
'host' => 'master.redis-test8.evt7pw.use1.cache.amazonaws.com',
'port' => '6379',
'password' => '',
'select' => 6,
'auto_start' => true,
)
- [ RUN ] app\index\controller\Blog->view[ /wwwroot/application/index/controller/Blog.php ]
- [ VIEW ] /wwwroot/template/customv1/template/blog_view.html [ array (
0 => 'controller_name',
1 => 'action_name',
2 => 'lang_count',
3 => 'lang_array',
4 => 'theme_key',
5 => 'theme_id',
6 => 'theme_name',
7 => 'hook_rute',
8 => 'theme_arr',
9 => 'template_name',
10 => 'payment_icons',
11 => 'payment',
12 => 'theme',
13 => 'theme_diy_mode',
14 => 'theme_config',
15 => 'icon_image',
16 => 'head_code',
17 => 'end_code',
18 => 'controllername',
19 => 'actionname',
20 => 'user',
21 => 'language',
22 => 'lang',
23 => 'is_rtl_lang',
24 => 'site',
25 => 'config',
26 => 'currency_switch',
27 => 'currency_list',
28 => 'currency_site',
29 => 'currency',
30 => 'cart',
31 => 'subscribing',
32 => 'login',
33 => 'policies',
34 => 'is_mobile',
35 => 'not_show_login',
36 => 'customer_login_status',
37 => 'config_site_version',
38 => 'blog_tag_list',
39 => 'pageList2',
40 => 'archives',
41 => 'productList',
42 => 'prev',
43 => 'next',
44 => 'seotitle',
45 => 'seokeywords',
46 => 'seodescription',
47 => 'page_type',
) ]
- [ BEHAVIOR ] Run addons\pixel\Hook @pixels [ RunTime:0.001426s ]
- [ BEHAVIOR ] Run addons\facebookpixel\Hook @pixels [ RunTime:0.001865s ]
- [ BEHAVIOR ] Run addons\snapchatpixel\Hook @pixels [ RunTime:0.001740s ]
- [ BEHAVIOR ] Run addons\googlepixel\Hook @pixels [ RunTime:0.001676s ]
- [ BEHAVIOR ] Run addons\tiktokpixel\Hook @pixels [ RunTime:0.008351s ]
- [ BEHAVIOR ] Run addons\mediago\Hook @pixels [ RunTime:0.001751s ]
- [ BEHAVIOR ] Run addons\taboolapixel\Hook @pixels [ RunTime:0.001721s ]
- [ BEHAVIOR ] Run addons\klaviyopixel\Hook @pixels [ RunTime:0.001189s ]
- [ BEHAVIOR ] Run addons\marketingnotify\Hook @global_footer [ RunTime:0.000051s ]
- [ BEHAVIOR ] Run addons\marketingcookie\Hook @global_footer [ RunTime:0.001925s ]
- [ BEHAVIOR ] Run addons\marketingpopup\Hook @global_footer [ RunTime:0.000050s ]
- 1/20 . [ BEHAVIOR ] Run addons\tiktokpixel\Hook @pixels [ RunTime:0.008351s ] [0.008351s/0.027098s=30.82%]
- 2/20 . [ BEHAVIOR ] Run app\common\behavior\Common @module_init [ RunTime:0.004547s ] [0.004547s/0.027098s=16.78%]
- 3/20 . [ BEHAVIOR ] Run addons\marketingcookie\Hook @global_footer [ RunTime:0.001925s ] [0.001925s/0.027098s=7.10%]
- 4/20 . [ BEHAVIOR ] Run addons\facebookpixel\Hook @pixels [ RunTime:0.001865s ] [0.001865s/0.027098s=6.88%]
- 5/20 . [ BEHAVIOR ] Run addons\mediago\Hook @pixels [ RunTime:0.001751s ] [0.001751s/0.027098s=6.46%]
- 6/20 . [ BEHAVIOR ] Run addons\snapchatpixel\Hook @pixels [ RunTime:0.001740s ] [0.001740s/0.027098s=6.42%]
- 7/20 . [ BEHAVIOR ] Run addons\taboolapixel\Hook @pixels [ RunTime:0.001721s ] [0.001721s/0.027098s=6.35%]
- 8/20 . [ BEHAVIOR ] Run addons\googlepixel\Hook @pixels [ RunTime:0.001676s ] [0.001676s/0.027098s=6.18%]
- 9/20 . [ BEHAVIOR ] Run addons\pixel\Hook @pixels [ RunTime:0.001426s ] [0.001426s/0.027098s=5.26%]
- 10/20 . [ BEHAVIOR ] Run addons\klaviyopixel\Hook @pixels [ RunTime:0.001189s ] [0.001189s/0.027098s=4.39%]
- 11/20 . [ BEHAVIOR ] Run \addons\aws\Aws @app_init [ RunTime:0.000413s ] [0.000413s/0.027098s=1.52%]
- 12/20 . [ BEHAVIOR ] Run \addons\nkeditor\Nkeditor @config_init [ RunTime:0.000096s ] [0.000096s/0.027098s=0.35%]
- 13/20 . [ BEHAVIOR ] Run app\common\behavior\Common @app_dispatch [ RunTime:0.000078s ] [0.000078s/0.027098s=0.29%]
- 14/20 . [ BEHAVIOR ] Run Closure @app_init [ RunTime:0.000076s ] [0.000076s/0.027098s=0.28%]
- 15/20 . [ BEHAVIOR ] Run \addons\listener\Listener @app_init [ RunTime:0.000063s ] [0.000063s/0.027098s=0.23%]
- 16/20 . [ BEHAVIOR ] Run addons\marketingnotify\Hook @global_footer [ RunTime:0.000051s ] [0.000051s/0.027098s=0.19%]
- 17/20 . [ BEHAVIOR ] Run addons\marketingpopup\Hook @global_footer [ RunTime:0.000050s ] [0.000050s/0.027098s=0.18%]
- 18/20 . [ BEHAVIOR ] Run \addons\aws\Aws @module_init [ RunTime:0.000034s ] [0.000034s/0.027098s=0.13%]
- 19/20 . [ BEHAVIOR ] Run \addons\aws\Aws @upload_config_init [ RunTime:0.000030s ] [0.000030s/0.027098s=0.11%]
- 20/20 . [ BEHAVIOR ] Run Closure @app_init [ RunTime:0.000016s ] [0.000016s/0.027098s=0.06%]
- [ DB ] CONNECT:[ UseTime:0.002601s ] mysql:host=database-test2.cb81pdkxodkj.us-east-1.rds.amazonaws.com:3306;port=3306;dbname=shoppaas_1705904057568;charset=utf8mb4
- [ SQL ] SELECT * FROM `payment` WHERE `status` = '1' ORDER BY `weigh` DESC,`id` ASC [ RunTime:0.001220s ]
- [ SQL ] SELECT `value` FROM `config` WHERE `name` = 'channel_google' LIMIT 1 [ RunTime:0.000985s ]
- [ SQL ] SELECT `value` FROM `config` WHERE `name` = 'lang_auto_detect' LIMIT 1 [ RunTime:0.000869s ]
- [ SQL ] set time_zone = '+08:00' [ RunTime:0.000826s ]
- [ SQL ] SELECT `value` FROM `config` WHERE ( name = 'multiCurrencySwitch' ) LIMIT 1 [ RunTime:0.001038s ]
- [ SQL ] SELECT `value` FROM `config` WHERE ( name = 'byIpSwitch' ) LIMIT 1 [ RunTime:0.001297s ]
- [ SQL ] UPDATE `cart` SET `deletetime`=1766574130 WHERE ( NOT EXISTS ( SELECT * FROM `product` WHERE ( product.id=cart.product_id ) ) AND `user_id` = 0 AND `session_id` = 'c5n89fccor83d6o637j6sv6ri5' AND `createtime` > 1766314930 ) AND `cart`.`deletetime` IS NULL [ RunTime:0.001132s ]
- [ SQL ] SELECT @@session.sql_mode [ RunTime:0.000736s ]
- [ SQL ] set session sql_mode='STRICT_TRANS_TABLES,ERROR_FOR_DIVISION_BY_ZERO,NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION' [ RunTime:0.000640s ]
- [ SQL ] SELECT * FROM `cart` LEFT JOIN ( SELECT type as activity_type,id as activity_id,`product_ids` FROM `activity` WHERE ( `type` IN ('seckill') ) AND `activity`.`deletetime` IS NULL ) `act` ON find_in_set(cart.product_id, product_ids) WHERE ( `user_id` = 0 AND `session_id` = 'c5n89fccor83d6o637j6sv6ri5' AND `store_id` = 0 ) AND `cart`.`deletetime` IS NULL GROUP BY `id` ORDER BY `createtime` DESC,`id` ASC [ RunTime:0.001700s ]
- [ SQL ] set session sql_mode='STRICT_TRANS_TABLES,ERROR_FOR_DIVISION_BY_ZERO,NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION' [ RunTime:0.000770s ]
- [ SQL ] SELECT `value` FROM `config` WHERE `name` = 'customer_login_config' LIMIT 1 [ RunTime:0.000916s ]
- [ SQL ] SELECT COUNT(*) AS tp_count FROM `app_center` WHERE `name` = 'access_control' AND `is_installed` = 1 LIMIT 1 [ RunTime:0.001065s ]
- [ SQL ] SELECT `id`,`name`,`nums` FROM `blog_tag` WHERE `status` = 'normal' ORDER BY `id` DESC [ RunTime:0.000842s ]
- [ SQL ] SELECT COUNT(*) AS tp_count FROM `blog_archives` WHERE ( `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL LIMIT 1 [ RunTime:0.000824s ]
- [ SQL ] SELECT * FROM `blog_archives` WHERE ( `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL ORDER BY `id` DESC LIMIT 0,10 [ RunTime:0.000914s ]
- [ SQL ] SELECT * FROM `admin` WHERE `id` IN (1) [ RunTime:0.000859s ]
- [ SQL ] SELECT * FROM `blog_archives` WHERE ( `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' AND `status` = 'normal' AND `deletetime` IS NULL OR ( `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' OR `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' ) ) AND `blog_archives`.`deletetime` IS NULL LIMIT 1 [ RunTime:0.000877s ]
- [ SQL ] UPDATE `blog_archives` SET `views`=`views`+1 WHERE `id` = 101 [ RunTime:0.004915s ]
- [ SQL ] SELECT * FROM `blog_archives` WHERE ( `id` < 101 AND `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL ORDER BY `id` DESC LIMIT 1 [ RunTime:0.002186s ]
- [ SQL ] SELECT * FROM `blog_archives` WHERE ( `id` > 101 AND `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL ORDER BY `id` ASC LIMIT 1 [ RunTime:0.001192s ]
- [ SQL ] SELECT COUNT(*) AS tp_count FROM `themes_lang` WHERE ( lang_name='en' AND is_enable=1 AND transl_way=2 AND deletetime IS NULL ) LIMIT 1 [ RunTime:0.000890s ]
- [ SQL ] SELECT * FROM `blog_archives` WHERE ( `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' AND `status` = 'normal' AND `deletetime` IS NULL OR ( `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' OR `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' ) ) AND `blog_archives`.`deletetime` IS NULL LIMIT 1 [ RunTime:0.001191s ]
- [ SQL ] SELECT `value` FROM `config` WHERE `name` = 'klaviyo_config' LIMIT 1 [ RunTime:0.001060s ]
- [ SQL ] SELECT COUNT(*) AS tp_count FROM `themes_lang` WHERE ( lang_name='en' AND is_enable=1 AND transl_way=2 AND deletetime IS NULL ) LIMIT 1 [ RunTime:0.001060s ]
- [ SQL ] SELECT * FROM `themes_menu_item` WHERE `themes_menu_item`.`deletetime` IS NULL [ RunTime:0.000850s ]
- [ SQL ] SELECT `nickname` FROM `admin` WHERE `id` = 1 LIMIT 1 [ RunTime:0.001144s ]
- [ SQL ] SELECT * FROM `blog_archives` WHERE ( `id` < 101 AND `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL ORDER BY `id` DESC LIMIT 1 [ RunTime:0.001062s ]
- [ SQL ] SELECT * FROM `blog_archives` WHERE ( `id` > 101 AND `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL ORDER BY `id` ASC LIMIT 1 [ RunTime:0.001115s ]
- 1/30 . UPDATE `blog_archives` SET `views`=`views`+1 WHERE `id` = 101 [0.004915s/0.036776s=13.36%]
- 2/30 . CONNECT:[ UseTime:0.002601s ] mysql:host=database-test2.cb81pdkxodkj.us-east-1.rds.amazonaws.com:3306;port=3306;dbname=shoppaas_1705904057568;charset=utf8mb4 [0.002601s/0.036776s=7.07%]
- 3/30 . SELECT * FROM `blog_archives` WHERE ( `id` < 101 AND `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL ORDER BY `id` DESC LIMIT 1 [0.002186s/0.036776s=5.94%]
- 4/30 . SELECT * FROM `cart` LEFT JOIN ( SELECT type as activity_type,id as activity_id,`product_ids` FROM `activity` WHERE ( `type` IN ('seckill') ) AND `activity`.`deletetime` IS NULL ) `act` ON find_in_set(cart.product_id, product_ids) WHERE ( `user_id` = 0 AND `session_id` = 'c5n89fccor83d6o637j6sv6ri5' AND `store_id` = 0 ) AND `cart`.`deletetime` IS NULL GROUP BY `id` ORDER BY `createtime` DESC,`id` ASC [0.001700s/0.036776s=4.62%]
- 5/30 . SELECT `value` FROM `config` WHERE ( name = 'byIpSwitch' ) LIMIT 1 [0.001297s/0.036776s=3.53%]
- 6/30 . SELECT * FROM `payment` WHERE `status` = '1' ORDER BY `weigh` DESC,`id` ASC [0.001220s/0.036776s=3.32%]
- 7/30 . SELECT * FROM `blog_archives` WHERE ( `id` > 101 AND `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL ORDER BY `id` ASC LIMIT 1 [0.001192s/0.036776s=3.24%]
- 8/30 . SELECT * FROM `blog_archives` WHERE ( `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' AND `status` = 'normal' AND `deletetime` IS NULL OR ( `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' OR `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' ) ) AND `blog_archives`.`deletetime` IS NULL LIMIT 1 [0.001191s/0.036776s=3.24%]
- 9/30 . SELECT `nickname` FROM `admin` WHERE `id` = 1 LIMIT 1 [0.001144s/0.036776s=3.11%]
- 10/30 . UPDATE `cart` SET `deletetime`=1766574130 WHERE ( NOT EXISTS ( SELECT * FROM `product` WHERE ( product.id=cart.product_id ) ) AND `user_id` = 0 AND `session_id` = 'c5n89fccor83d6o637j6sv6ri5' AND `createtime` > 1766314930 ) AND `cart`.`deletetime` IS NULL [0.001132s/0.036776s=3.08%]
- 11/30 . SELECT * FROM `blog_archives` WHERE ( `id` > 101 AND `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL ORDER BY `id` ASC LIMIT 1 [0.001115s/0.036776s=3.03%]
- 12/30 . SELECT COUNT(*) AS tp_count FROM `app_center` WHERE `name` = 'access_control' AND `is_installed` = 1 LIMIT 1 [0.001065s/0.036776s=2.90%]
- 13/30 . SELECT * FROM `blog_archives` WHERE ( `id` < 101 AND `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL ORDER BY `id` DESC LIMIT 1 [0.001062s/0.036776s=2.89%]
- 14/30 . SELECT COUNT(*) AS tp_count FROM `themes_lang` WHERE ( lang_name='en' AND is_enable=1 AND transl_way=2 AND deletetime IS NULL ) LIMIT 1 [0.001060s/0.036776s=2.88%]
- 15/30 . SELECT `value` FROM `config` WHERE `name` = 'klaviyo_config' LIMIT 1 [0.001060s/0.036776s=2.88%]
- 16/30 . SELECT `value` FROM `config` WHERE ( name = 'multiCurrencySwitch' ) LIMIT 1 [0.001038s/0.036776s=2.82%]
- 17/30 . SELECT `value` FROM `config` WHERE `name` = 'channel_google' LIMIT 1 [0.000985s/0.036776s=2.68%]
- 18/30 . SELECT `value` FROM `config` WHERE `name` = 'customer_login_config' LIMIT 1 [0.000916s/0.036776s=2.49%]
- 19/30 . SELECT * FROM `blog_archives` WHERE ( `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL ORDER BY `id` DESC LIMIT 0,10 [0.000914s/0.036776s=2.49%]
- 20/30 . SELECT COUNT(*) AS tp_count FROM `themes_lang` WHERE ( lang_name='en' AND is_enable=1 AND transl_way=2 AND deletetime IS NULL ) LIMIT 1 [0.000890s/0.036776s=2.42%]
- 21/30 . SELECT * FROM `blog_archives` WHERE ( `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' AND `status` = 'normal' AND `deletetime` IS NULL OR ( `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' OR `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' ) ) AND `blog_archives`.`deletetime` IS NULL LIMIT 1 [0.000877s/0.036776s=2.38%]
- 22/30 . SELECT `value` FROM `config` WHERE `name` = 'lang_auto_detect' LIMIT 1 [0.000869s/0.036776s=2.36%]
- 23/30 . SELECT * FROM `admin` WHERE `id` IN (1) [0.000859s/0.036776s=2.34%]
- 24/30 . SELECT * FROM `themes_menu_item` WHERE `themes_menu_item`.`deletetime` IS NULL [0.000850s/0.036776s=2.31%]
- 25/30 . SELECT `id`,`name`,`nums` FROM `blog_tag` WHERE `status` = 'normal' ORDER BY `id` DESC [0.000842s/0.036776s=2.29%]
- 26/30 . set time_zone = '+08:00' [0.000826s/0.036776s=2.25%]
- 27/30 . SELECT COUNT(*) AS tp_count FROM `blog_archives` WHERE ( `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL LIMIT 1 [0.000824s/0.036776s=2.24%]
- 28/30 . set session sql_mode='STRICT_TRANS_TABLES,ERROR_FOR_DIVISION_BY_ZERO,NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION' [0.000770s/0.036776s=2.09%]
- 29/30 . SELECT @@session.sql_mode [0.000736s/0.036776s=2.00%]
- 30/30 . set session sql_mode='STRICT_TRANS_TABLES,ERROR_FOR_DIVISION_BY_ZERO,NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION' [0.000640s/0.036776s=1.74%]
- 1/5. SELECT COUNT(*) AS tp_count FROM `themes_lang` WHERE ( lang_name='en' AND is_enable=1 AND transl_way=2 AND deletetime IS NULL ) LIMIT 1 [2 times, 0.00195s/0.036776s=5.30%]
- 2/5. SELECT * FROM `blog_archives` WHERE ( `id` > 101 AND `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL ORDER BY `id` ASC LIMIT 1 [2 times, 0.002307s/0.036776s=6.27%]
- 3/5. SELECT * FROM `blog_archives` WHERE ( `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' AND `status` = 'normal' AND `deletetime` IS NULL OR ( `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' OR `path` = 'lushan-scenic-area-temporarily-closed-due-to-heavy-snowfall' ) ) AND `blog_archives`.`deletetime` IS NULL LIMIT 1 [2 times, 0.002068s/0.036776s=5.62%]
- 4/5. SELECT * FROM `blog_archives` WHERE ( `id` < 101 AND `status` = 'normal' ) AND `blog_archives`.`deletetime` IS NULL ORDER BY `id` DESC LIMIT 1 [2 times, 0.003248s/0.036776s=8.83%]
- 5/5. set session sql_mode='STRICT_TRANS_TABLES,ERROR_FOR_DIVISION_BY_ZERO,NO_AUTO_CREATE_USER,NO_ENGINE_SUBSTITUTION' [2 times, 0.00141s/0.036776s=3.83%]