search

ftinx / webserv

http/1.1 multiplexing webserver
0 stars 0 forks source link

[console_log] 속도 측정 연구일지.. #298

Closed hochan222 closed 3 years ago

hochan222 commented 3 years ago

시간을 줄여보자..

hochan222 commented 3 years ago 
[163313958653] start select
[163313959226] Timeout Reset
[163313959778] start loop server
[163313960915] getRequest start
[163313961325] writeProcess start
[163313961685] writeProcess end
[163313962068] readProcess start
[163313962527] readProcess end
[163313962941] getRequest end
[163313962804] end loop server
163313962804 - 
[163313963237] start loop server
[163313964149] getRequest start
[163313964513] writeProcess start
[163313964930] writeProcess end
[163313965300] readProcess start
[163313965704] readProcess end
[163313966109] getRequest end
[163313965927] end loop server
163313965927 - [163313962804] end loop server
[164313966582] start select
[164313966743] Timeout Reset
[164313967354] start loop server
[164313968322] getRequest start
[164313968777] writeProcess start
[164313969237] writeProcess end
[164313969648] readProcess start
[164313970205] readProcess end
[164313970604] getRequest end
[164313970519] end loop server
164313970519 - [163313965927] end loop server
[164313970978] start loop server
[164313971887] getRequest start
[164313972320] writeProcess start
[164313972749] writeProcess end
[164313973116] readProcess start
[164313973568] readProcess end
[164313973998] getRequest end
[164313973981] end loop server
164313973981 - [164313970519] end loop server
[164314145208] start select
[164314145311] start loop server
[164314146414] getRequest start
[164314146906] writeProcess start
[164314147330] writeProcess end
[164314147761] acceptSocket start
[164314148200] acceptSocket end
[164314148556] getRequest end
[164314148418] end loop server
164314148418 - [164313973981] end loop server
[164314148880] start loop server
[164314149866] getRequest start
[164314150234] writeProcess start
[164314150726] writeProcess end
[164314151203] readProcess start
[164314151684] readProcess end
[164314152150] getRequest end
[164314152081] end loop server
164314152081 - [164314148418] end loop server
[164314152581] start select
[164314153177] start loop server
[164314154208] getRequest start
[164314154688] writeProcess start
[164314155139] writeProcess end
[164314155610] readProcess start
[164314193705] readProcess end
[164314193844] getRequest end
[164314193996] end loop server
164314193996 - [164314152081] end loop server
[164314194544] start loop server
[164314195750] getRequest start
[164314196120] writeProcess start
[164314196476] writeProcess end
[164314196906] readProcess start
[164314197326] readProcess end
[164314197669] getRequest end
[164314197549] end loop server
164314197549 - [164314193996] end loop server
[164314197958] start select
[164314198282] start loop server
[164314199138] getRequest start
[164314199500] writeProcess start
[164314199878] writeProcess end
[164314200307] acceptSocket start
[164314200655] acceptSocket end
[164314201043] getRequest end
[164314200868] end loop server
164314200868 - [164314197549] end loop server
[164314201318] start loop server
[164314202206] getRequest start
[164314202613] writeProcess start
[164314203015] writeProcess end
[164314203514] readProcess start
[164314203850] readProcess end
[164314204237] getRequest end
[164314204075] end loop server
164314204075 - [164314200868] end loop server
[164314204493] start select
[164314204870] start loop server
[164314205840] getRequest start
[164314206311] writeProcess start
[164314206818] writeProcess end
[164314207290] readProcess start
[164314218540] readProcess end
[164314218648] getRequest end
[164314218780] end loop server
164314218780 - [164314204075] end loop server
[164314219392] start loop server
[164314220462] getRequest start
[164314220967] writeProcess start
[164314221460] writeProcess end
[164314221953] readProcess start
[164314222411] readProcess end
[164314222855] getRequest end
[164314222846] end loop server
164314222846 - [164314218780] end loop server
[164314223353] start select
[164314223800] start loop server
[164314224816] getRequest start
[164314225256] writeProcess start
[164314225729] writeProcess end
[164314226164] readProcess start
[164314237265] readProcess end
[164314237536] getRequest end
[164314237627] end loop server
164314237627 - [164314222846] end loop server
[164314238447] start loop server
[164314239903] getRequest start
[164314240391] writeProcess start
[164314240847] writeProcess end
[164314241972] readProcess start
[164314242335] readProcess end
[164314242765] getRequest end
[164314242597] end loop server
164314242597 - [164314237627] end loop server
[164314243008] start select
[164314243362] start loop server
[164314244259] getRequest start
[164314244626] writeProcess start
[164314245037] writeProcess end
[164314245497] acceptSocket start
[164314245879] acceptSocket end
[164314246339] getRequest end
[164314246224] end loop server
164314246224 - [164314242597] end loop server
[164314246686] start loop server
[164314247594] getRequest start
[164314247961] writeProcess start
[164314248343] writeProcess end
[164314248749] readProcess start
[164314249124] readProcess end
[164314249505] getRequest end
[164314249414] end loop server
164314249414 - [164314246224] end loop server
[164314249794] start select
[164314250114] start loop server
[164314251124] getRequest start
[164314251494] writeProcess start
[164314251838] writeProcess end
[164314252216] readProcess start
[165313263635] readProcess end
[165313263772] getRequest end
[165313263839] end loop server
165313263839 - [164314249414] end loop server
[165313264294] start loop server
[165313265268] getRequest start
[165313265699] writeProcess start
[165313266069] writeProcess end
[165313266466] readProcess start
[165313266847] readProcess end
[165313267240] getRequest end
[165313267096] end loop server
165313267096 - [165313263839] end loop server
[165313267542] start select
[165313267925] start loop server
[165313268793] getRequest start
[165313269170] writeProcess start
[165313269552] writeProcess end
[165313269938] readProcess start
[165313281250] readProcess end
[165313281363] getRequest end
[165313281599] end loop server
165313281599 - [165313267096] end loop server
[165313282162] start loop server
[165313283203] getRequest start
[165313283665] writeProcess start
[165313284062] writeProcess end
[165313284440] readProcess start
[165313284844] readProcess end
[165313285313] getRequest end
[165313285231] end loop server
165313285231 - [165313281599] end loop server
[165313285626] start select
[165313286046] start loop server
[165313287159] getRequest start
[165313287520] writeProcess start
[165313288032] writeProcess end
[165313288429] readProcess start
[165313299380] readProcess end
[165313299530] getRequest end
[165313299701] end loop server
165313299701 - [165313285231] end loop server
[165313300277] start loop server
[165313301315] getRequest start
[165313301738] writeProcess start
[165313302151] writeProcess end
[165313302550] readProcess start
[165313302943] readProcess end
[165313303740] getRequest end
[165313303687] end loop server
165313303687 - [165313299701] end loop server
[165313304103] start select
[165313304521] start loop server
[165313305428] getRequest start
[165313305846] writeProcess start
[165313306214] writeProcess end
[165313306597] acceptSocket start
[165313306989] acceptSocket end
[165313307383] getRequest end
[165313307269] end loop server
165313307269 - [165313303687] end loop server
[165313307777] start loop server
[165313308644] getRequest start
[165313308997] writeProcess start
[165313309361] writeProcess end
[165313309725] readProcess start
[165313310103] readProcess end
[165313310461] getRequest end
[165313310309] end loop server
165313310309 - [165313307269] end loop server
[165313310754] start select
[165313311071] start loop server
[165313311950] getRequest start
[165313312348] writeProcess start
[165313312707] writeProcess end
[165313313071] readProcess start
[165313324449] readProcess end
[165313324591] getRequest end
[165313324662] end loop server
165313324662 - [165313310309] end loop server
[165313325213] start loop server
[165313326143] getRequest start
[165313326520] writeProcess start
[165313326959] writeProcess end
[165313327367] readProcess start
[165313327751] readProcess end
[165313328134] getRequest end
[165313327978] end loop server
165313327978 - [165313324662] end loop server
[165313328411] start select
[165313329042] start loop server
[165313329953] getRequest start
[165313330315] writeProcess start
[165313330760] writeProcess end
[165313331153] readProcess start
[165313342589] readProcess end
[165313342697] getRequest end
[165313342693] end loop server
165313342693 - [165313327978] end loop server
[165313343157] start loop server
[165313344117] getRequest start
[165313344537] writeProcess start
[165313344937] writeProcess end
[165313345307] readProcess start
[165313345664] readProcess end
[165313346023] getRequest end
[165313345897] end loop server
165313345897 - [165313342693] end loop server
[165313346287] start select
[165313346648] start loop server
[165313347559] getRequest start
[165313347901] writeProcess start
[165313348295] writeProcess end
[165313348730] readProcess start
[165313360365] readProcess end
[165313360507] getRequest end
[165313360423] end loop server
165313360423 - [165313345897] end loop server
[165313360848] start loop server
[165313361728] getRequest start
[165313362122] writeProcess start
[165313362496] writeProcess end
[165313362887] readProcess start
[165313363303] readProcess end
[165313363664] getRequest end
[165313363506] end loop server
165313363506 - [165313360423] end loop server
[165313364047] start select
[165313364597] start loop server
[165313366707] getRequest start
[165313367259] writeProcess start
[165313367658] writeProcess end
[165313368124] readProcess start
[165313379408] readProcess end
[165313379544] getRequest end
[165313379486] end loop server
165313379486 - [165313363506] end loop server
[165313379983] start loop server
[165313380883] getRequest start
[165313381257] writeProcess start
[165313381713] writeProcess end
[165313382078] readProcess start
[165313382450] readProcess end
[165313382839] getRequest end
[165313382726] end loop server
165313382726 - [165313379486] end loop server
[165313383116] start select
[165313383452] start loop server
[165313384396] getRequest start
[165313384748] writeProcess start
[165313385146] writeProcess end
[165313385543] readProcess start
[165313396887] readProcess end
[165313396999] getRequest end
[165313397181] end loop server
165313397181 - [165313382726] end loop server
[165313397722] start loop server
[165313398662] getRequest start
[165313399173] writeProcess start
[165313399532] writeProcess end
[165313399942] readProcess start
[165313400337] readProcess end
[165313400679] getRequest end
[165313400529] end loop server
165313400529 - [165313397181] end loop server
[165313400948] start select
[165313401335] start loop server
[165313402245] getRequest start
[165313402620] writeProcess start
[165313403151] writeProcess end
[165313403545] readProcess start
[165313414700] readProcess end
[165313414845] getRequest end
[165313414971] end loop server
165313414971 - [165313400529] end loop server
[165313415500] start loop server
[165313416974] getRequest start
[165313417368] writeProcess start
[165313417727] writeProcess end
[165313418070] readProcess start
[165313418409] readProcess end
[165313418770] getRequest end
[165313418634] end loop server
165313418634 - [165313414971] end loop server
[166313419508] start select
[166313419802] Timeout Reset
[166313420459] start loop server
[166313421650] getRequest start
[166313422185] writeProcess start
[166313422798] writeProcess end
[166313423235] readProcess start
[166313423634] readProcess end
[166313424042] getRequest end
[166313423932] end loop server
166313423932 - [165313418634] end loop server
[166313424369] start loop server
[166313425335] getRequest start

start select - end loop server
5622
2804
3971
3111
3123
1000004592 [164313970519]
3462
174437 [164313970519]
3462
174437 [164314148418]
3663
41915 [164314193996]
3553
3319
3207
14705 [164314218780]
4066
14781 [164314237627]
4970
3627
3190
999014425 [165313263839]
3257
14503 [165313281599]
3632
14470 [165313299701]
3986
3582
3040
14353 [165313324662]
3316
14715 [165313342693]
3204
14526 [165313360423]
3083
15980 [165313379486]
3240
14455 [165313397181]
3348
14442
3663
1000005298 [166313423932]

14455는 정상작업이다. 1000005298인 애들을 찾아서 줄여보자.

hochan222 commented 3 years ago

GET MESSAGE FAIL 이후 다음 server loop 에서 항상 1000000000 이상 걸렸다. 또한 실패한뒤에는 아무 로직도 실행되지 않았다.

[182314144289] end loop server
[182314144722] start select
[182314145088] start loop server
[182314145984] getRequest start
[182314146394] writeProcess start
::4::
------ OK 0
[182314146872] writeProcess end
[182314147367] readProcess start
::1:: 6 ::
should peek true
--- PEEK: ret <= 0 
GET MESSAGE FAIL
[182314158591] readProcess end
[182314158766] getRequest end
[182314158704] end loop server
[182314159300] start loop server
[182314160327] getRequest start
[182314160876] writeProcess start
[182314162124] writeProcess end
[182314162558] readProcess start
[182314163050] readProcess end
[182314163574] getRequest end
[182314163567] end loop server
[183314169150] start select

[182314163567] end loop server
[183314169150] start select 
1000005583

— 

[181314070941] end loop server
[181314071444] start select
[181314071853] start loop server
[181314072840] getRequest start
[181314073346] writeProcess start
[181314073662] writeProcess end
[181314074065] readProcess start
::1:: 6 ::
sholud peek false
--- PEEK: ret <= 0 
GET MESSAGE FAIL
[181314085249] readProcess end
[181314085396] getRequest end
[181314085406] end loop server
[181314085841] start loop server
[181314086890] getRequest start
[181314087567] writeProcess start
[181314088032] writeProcess end
[181314088460] readProcess start
[181314088891] readProcess end
[181314089365] getRequest end
[181314089298] end loop server
[182314090338] start select

[181314089298] end loop server
[182314090338] start select 
1000001040

—
180313993821] start select
[180313994224] start loop server
[180313995170] getRequest start
[180313995580] writeProcess start
::4::
------ OK 0
[180313995975] writeProcess end
[180313996363] readProcess start
::1:: 6 ::
should peek true
--- PEEK: ret <= 0 
GET MESSAGE FAIL
[180314007379] readProcess end
[180314007535] getRequest end
[180314007666] end loop server
[180314008156] start loop server
[180314009142] getRequest start
[180314009826] writeProcess start
[180314010227] writeProcess end
[180314010702] readProcess start
[180314011156] readProcess end
[180314011526] getRequest end
[180314011374] end loop server
[181314016907] start select

[180314011374] end loop server
[181314016907] start select
1000005533

—

179313878027] end loop server
[179313878632] start select
[179313879069] start loop server
[179313880010] getRequest start
[179313880463] writeProcess start
::4::
------ OK 0
[179313881017] writeProcess end
[179313881359] readProcess start
::1:: 6 ::
should peek true
--- PEEK: ret <= 0 
GET MESSAGE FAIL
[179313892709] readProcess end
[179313892863] getRequest end
[179313892940] end loop server
[179313893471] start loop server
[179313894743] getRequest start
[179313895214] writeProcess start
[179313895741] writeProcess end
[179313896234] readProcess start
[179313896696] readProcess end
[179313897168] getRequest end
[179313897081] end loop server
[180313902704] start select

[179313897081] end loop server
[180313902704] start select
1000005623

—
[178313804585] end loop server
[178313805052] start select
[178313805378] start loop server
[178313806258] getRequest start
[178313806617] writeProcess start
::4::
------ OK 0
[178313807053] writeProcess end
[178313807629] readProcess start
::1:: 6 ::
should peek true
--- PEEK: ret <= 0 
GET MESSAGE FAIL
[178313818724] readProcess end
[178313818870] getRequest end
[178313818909] end loop server
[178313819381] start loop server
[178313820351] getRequest start
[178313820771] writeProcess start
[178313821201] writeProcess end
[178313821909] readProcess start
[178313822500] readProcess end
[178313822909] getRequest end
[178313822887] end loop server
[179313823487] start select

[178313822887] end loop server
[179313823487] start select
1000000600
hochan222 commented 3 years ago

GET MESSAGE FAIL의 경우는 recv() 의 return 이 0일때의 경우이다.

hochan222 commented 3 years ago

앗 select timeout 이었다.. 이론..