Jak szybki jest grep? -> grep vs awk, python, rugby, java, perl, C - przeszukiwanie logów - część 9.

Jako przykład co się dzieje podczas uruchamiania programów zamieszczę jeden raz cały output z uruchomienia tego konkretnego programu, wraz dodatkowymi znacznikami czasu:
output (multifindmaster - ram)
-------------------------- Start:0s
-------------------------- Po open:0.000141s
-------------------------- Po shmget:0.000158s
-------------------------- Przed read:0.000179s
-------------------------- Po read:0.397265s
@ fileinmemory: 0x7fcd8fa76000
@      len_max: 0x3B9ACA00
@          sum: 0x7FCDCB422A00
 0: shm_start: 0x7fcd8fa76000   shm_len: 1DCD64F   shm_diff:        0   shm_start+shm_len: 7FCD9184364F
 1: shm_start: 0x7fcd9184364f   shm_len: 1DCD650   shm_diff:  1DCD64F   shm_start+shm_len: 7FCD93610C9F
 2: shm_start: 0x7fcd93610c9f   shm_len: 1DCD650   shm_diff:  3B9AC9F   shm_start+shm_len: 7FCD953DE2EF
 3: shm_start: 0x7fcd953de2ef   shm_len: 1DCD650   shm_diff:  59682EF   shm_start+shm_len: 7FCD971AB93F
 4: shm_start: 0x7fcd971ab93f   shm_len: 1DCD650   shm_diff:  773593F   shm_start+shm_len: 7FCD98F78F8F
 5: shm_start: 0x7fcd98f78f8f   shm_len: 1DCD650   shm_diff:  9502F8F   shm_start+shm_len: 7FCD9AD465DF
 6: shm_start: 0x7fcd9ad465df   shm_len: 1DCD650   shm_diff:  B2D05DF   shm_start+shm_len: 7FCD9CB13C2F
 7: shm_start: 0x7fcd9cb13c2f   shm_len: 1DCD650   shm_diff:  D09DC2F   shm_start+shm_len: 7FCD9E8E127F
 8: shm_start: 0x7fcd9e8e127f   shm_len: 1DCD650   shm_diff:  EE6B27F   shm_start+shm_len: 7FCDA06AE8CF
 9: shm_start: 0x7fcda06ae8cf   shm_len: 1DCD650   shm_diff: 10C388CF   shm_start+shm_len: 7FCDA247BF1F
10: shm_start: 0x7fcda247bf1f   shm_len: 1DCD650   shm_diff: 12A05F1F   shm_start+shm_len: 7FCDA424956F
11: shm_start: 0x7fcda424956f   shm_len: 1DCD650   shm_diff: 147D356F   shm_start+shm_len: 7FCDA6016BBF
12: shm_start: 0x7fcda6016bbf   shm_len: 1DCD650   shm_diff: 165A0BBF   shm_start+shm_len: 7FCDA7DE420F
13: shm_start: 0x7fcda7de420f   shm_len: 1DCD650   shm_diff: 1836E20F   shm_start+shm_len: 7FCDA9BB185F
14: shm_start: 0x7fcda9bb185f   shm_len: 1DCD650   shm_diff: 1A13B85F   shm_start+shm_len: 7FCDAB97EEAF
15: shm_start: 0x7fcdab97eeaf   shm_len: 1DCD650   shm_diff: 1BF08EAF   shm_start+shm_len: 7FCDAD74C4FF
16: shm_start: 0x7fcdad74c4ff   shm_len: 1DCD650   shm_diff: 1DCD64FF   shm_start+shm_len: 7FCDAF519B4F
17: shm_start: 0x7fcdaf519b4f   shm_len: 1DCD650   shm_diff: 1FAA3B4F   shm_start+shm_len: 7FCDB12E719F
18: shm_start: 0x7fcdb12e719f   shm_len: 1DCD650   shm_diff: 2187119F   shm_start+shm_len: 7FCDB30B47EF
19: shm_start: 0x7fcdb30b47ef   shm_len: 1DCD650   shm_diff: 2363E7EF   shm_start+shm_len: 7FCDB4E81E3F
20: shm_start: 0x7fcdb4e81e3f   shm_len: 1DCD650   shm_diff: 2540BE3F   shm_start+shm_len: 7FCDB6C4F48F
21: shm_start: 0x7fcdb6c4f48f   shm_len: 1DCD650   shm_diff: 271D948F   shm_start+shm_len: 7FCDB8A1CADF
22: shm_start: 0x7fcdb8a1cadf   shm_len: 1DCD650   shm_diff: 28FA6ADF   shm_start+shm_len: 7FCDBA7EA12F
23: shm_start: 0x7fcdba7ea12f   shm_len: 1DCD650   shm_diff: 2AD7412F   shm_start+shm_len: 7FCDBC5B777F
24: shm_start: 0x7fcdbc5b777f   shm_len: 1DCD650   shm_diff: 2CB4177F   shm_start+shm_len: 7FCDBE384DCF
25: shm_start: 0x7fcdbe384dcf   shm_len: 1DCD650   shm_diff: 2E90EDCF   shm_start+shm_len: 7FCDC015241F
26: shm_start: 0x7fcdc015241f   shm_len: 1DCD650   shm_diff: 306DC41F   shm_start+shm_len: 7FCDC1F1FA6F
27: shm_start: 0x7fcdc1f1fa6f   shm_len: 1DCD650   shm_diff: 324A9A6F   shm_start+shm_len: 7FCDC3CED0BF
28: shm_start: 0x7fcdc3ced0bf   shm_len: 1DCD650   shm_diff: 342770BF   shm_start+shm_len: 7FCDC5ABA70F
29: shm_start: 0x7fcdc5aba70f   shm_len: 1DCD650   shm_diff: 3604470F   shm_start+shm_len: 7FCDC7887D5F
30: shm_start: 0x7fcdc7887d5f   shm_len: 1DCD650   shm_diff: 37E11D5F   shm_start+shm_len: 7FCDC96553AF
31: shm_start: 0x7fcdc96553af   shm_len: 1DCD651   shm_diff: 39BDF3AF   shm_start+shm_len: 7FCDCB422A00
Addr len_max: 3B9ACA00
    Diff sum: 3B9ACA00
-------------------------- Przed fork:0.397357s
-------------------------- Po fork w main:0.400042s
-------------------------- Po wait na end_main:0.434868s
-------------------------- Przed write:0.435204s
-------------------------- Po write:0.437367s
*** Proces: [0], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.026s
Czas procesora w trybie systemowym:     0.002s
*** Proces: [1], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.026s
Czas procesora w trybie systemowym:     0.004s
*** Proces: [2], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.028s
Czas procesora w trybie systemowym:     0.003s
*** Proces: [3], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.023s
Czas procesora w trybie systemowym:     0.005s
*** Proces: [4], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.022s
Czas procesora w trybie systemowym:     0.005s
*** Proces: [5], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.025s
Czas procesora w trybie systemowym:     0.007s
*** Proces: [6], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.035s
Czas procesora w trybie systemowym:     0.002s
*** Proces: [7], kod zakonczenia: 44
Czas pracy procesu: 0.02s
Czas procesora w trybie uzytkownika:    0.022s
Czas procesora w trybie systemowym:     0.004s
*** Proces: [8], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.029s
Czas procesora w trybie systemowym:     0.003s
*** Proces: [9], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.024s
Czas procesora w trybie systemowym:     0.005s
*** Proces: [10], kod zakonczenia: 44
Czas pracy procesu: 0.02s
Czas procesora w trybie uzytkownika:    0.019s
Czas procesora w trybie systemowym:     0.007s
*** Proces: [11], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.023s
Czas procesora w trybie systemowym:     0.008s
*** Proces: [12], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:     0.03s
Czas procesora w trybie systemowym:     0.001s
*** Proces: [13], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.021s
Czas procesora w trybie systemowym:     0.005s
*** Proces: [14], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.025s
Czas procesora w trybie systemowym:     0.005s
*** Proces: [15], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.028s
Czas procesora w trybie systemowym:     0.003s
*** Proces: [16], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.021s
Czas procesora w trybie systemowym:     0.006s
*** Proces: [17], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:     0.02s
Czas procesora w trybie systemowym:     0.008s
*** Proces: [18], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.026s
Czas procesora w trybie systemowym:     0.004s
*** Proces: [19], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.026s
Czas procesora w trybie systemowym:     0.009s
*** Proces: [20], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.019s
Czas procesora w trybie systemowym:     0.008s
*** Proces: [21], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.028s
Czas procesora w trybie systemowym:     0.003s
*** Proces: [22], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.024s
Czas procesora w trybie systemowym:     0.004s
*** Proces: [23], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:     0.02s
Czas procesora w trybie systemowym:     0.006s
*** Proces: [24], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.026s
Czas procesora w trybie systemowym:     0.005s
*** Proces: [25], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.022s
Czas procesora w trybie systemowym:     0.006s
*** Proces: [26], kod zakonczenia: 44
Czas pracy procesu: 0.02s
Czas procesora w trybie uzytkownika:    0.024s
Czas procesora w trybie systemowym:     0.001s
*** Proces: [27], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.026s
Czas procesora w trybie systemowym:     0.005s
*** Proces: [28], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.027s
Czas procesora w trybie systemowym:     0.003s
*** Proces: [29], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.023s
Czas procesora w trybie systemowym:     0.003s
*** Proces: [30], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.027s
Czas procesora w trybie systemowym:     0.002s
*** Proces: [31], kod zakonczenia: 44
Czas pracy procesu: 0.03s
Czas procesora w trybie uzytkownika:    0.025s
Czas procesora w trybie systemowym:     0.003s
-------------------------- Przed shmdt:0.437655s
-------------------------- End:0.525615s
Zestawienie znaczników czasu z powyższego output'u:
Start:0s
Po open:0.000141s
Po shmget:0.000158s
Przed read:0.000179s
Po read:0.397265s
Przed fork:0.397357s
Po fork w main:0.400042s
Po wait na end_main:0.434868s
Przed write:0.435204s
Po write:0.437367s
Przed shmdt:0.437655s
End:0.525615s



***

Inne wpisy:



Update: 2018.07.17
Create: 2018.07.17

Brak komentarzy:

Prześlij komentarz