annulen · April 1, 2020 01:48
diff --git a/clone1_trace.log b/clone1_trace.log
 + export GIT_TRACE2_PERF=1
 + GIT_TRACE2_PERF=1
 + REPO=clone-1
 + time -p git clone --depth=1 --no-checkout [email protected]:qtwebkit/qtwebkit.git clone-1
 04:45:21.005590 common-main.c:48             | d0 | main                     | version      |     |           |           |              | 2.26.0
 04:45:21.005619 common-main.c:49             | d0 | main                     | start        |     |  0.000252 |           |              | git clone --depth=1 --no-checkout [email protected]:qtwebkit/qtwebkit.git clone-1
 04:45:21.005649 git.c:440                    | d0 | main                     | cmd_name     |     |           |           |              | clone (clone)
 04:45:21.005738 repository.c:130             | d0 | main                     | def_repo     | r1  |           |           |              | worktree:/home/ktokarev/WebKit/clone-1
 Cloning into 'clone-1'...
 04:45:21.006966 run-command.c:735            | d0 | main                     | child_start  |     |  0.001599 |           |              | [ch0] class:transport/ssh argv:[ssh -o SendEnv=GIT_PROTOCOL [email protected] 'git-upload-pack '\''qtwebkit/qtwebkit.git'\''']
 04:45:22.800099 fetch-pack.c:672             | d0 | main                     | region_enter |     |  1.794733 |           | fetch-pack   | label:parse_remote_refs_and_find_cutoff
 04:45:22.800267 fetch-pack.c:695             | d0 | main                     | region_leave |     |  1.794902 |  0.000169 | fetch-pack   | label:parse_remote_refs_and_find_cutoff
 04:45:22.800278 fetch-pack.c:701             | d0 | main                     | region_enter |     |  1.794914 |           | fetch-pack   | label:mark_complete_local_refs
 04:45:22.800285 fetch-pack.c:709             | d0 | main                     | region_leave |     |  1.794921 |  0.000007 | fetch-pack   | label:mark_complete_local_refs
 04:45:22.800292 fetch-pack.c:715             | d0 | main                     | region_enter |     |  1.794928 |           | fetch-pack   | label:mark_common_remote_refs
 04:45:22.800299 fetch-pack.c:728             | d0 | main                     | region_leave |     |  1.794934 |  0.000006 | fetch-pack   | label:mark_common_remote_refs
 04:45:22.800360 fetch-pack.c:1496            | d0 | main                     | region_enter | r1  |  1.794996 |           | fetch-pack   | label:negotiation_v2
 04:45:22.800385 fetch-pack.c:1523            | d0 | main                     | region_leave | r1  |  1.795020 |  0.000024 | fetch-pack   | label:negotiation_v2
 04:45:22.939119 run-command.c:735            | d0 | main                     | child_start  |     |  1.933749 |           |              | [ch1] class:? argv:[git --shallow-file /home/ktokarev/WebKit/clone-1/.git/shallow.lock index-pack --stdin --fix-thin '--keep=fetch-pack 5236 on RedEyes']
 04:45:22.940326 common-main.c:48             | d1 | main                     | version      |     |           |           |              | 2.26.0
 04:45:22.940359 common-main.c:49             | d1 | main                     | start        |     |  0.000220 |           |              | /usr/libexec/git-core/git --shallow-file /home/ktokarev/WebKit/clone-1/.git/shallow.lock index-pack --stdin --fix-thin '--keep=fetch-pack 5236 on RedEyes'
 04:45:22.940433 repository.c:130             | d1 | main                     | def_repo     | r1  |           |           |              | worktree:/home/ktokarev/WebKit
 04:45:22.940495 git.c:440                    | d1 | main                     | cmd_name     |     |           |           |              | index-pack (clone/index-pack)
 04:47:26.413280 git.c:674                    | d1 | main                     | exit         |     | 123.473145 |           |              | code:0
 04:47:26.413307 trace2/tr2_tgt_perf.c:213    | d1 | main                     | atexit       |     | 123.473174 |           |              | code:0
 04:47:26.416201 run-command.c:990            | d0 | main                     | child_exit   |     | 125.410834 | 123.477085 |              | [ch1] pid:5239 code:0
 04:47:26.600114 run-command.c:990            | d0 | main                     | child_exit   |     | 125.594746 | 125.593147 |              | [ch0] pid:5237 code:0
 04:47:26.600157 run-command.c:735            | d0 | main                     | child_start  |     | 125.594789 |           |              | [ch2] class:? argv:[git rev-list --objects --stdin --not --all --quiet --alternate-refs]
 04:47:26.601192 common-main.c:48             | d1 | main                     | version      |     |           |           |              | 2.26.0
 04:47:26.601220 common-main.c:49             | d1 | main                     | start        |     |  0.000205 |           |              | /usr/libexec/git-core/git rev-list --objects --stdin --not --all --quiet --alternate-refs
 04:47:26.601286 repository.c:130             | d1 | main                     | def_repo     | r1  |           |           |              | worktree:/home/ktokarev/WebKit
 04:47:26.601335 git.c:440                    | d1 | main                     | cmd_name     |     |           |           |              | rev-list (clone/rev-list)
 04:47:26.793235 git.c:674                    | d1 | main                     | exit         |     |  0.192222 |           |              | code:0
 04:47:26.793262 trace2/tr2_tgt_perf.c:213    | d1 | main                     | atexit       |     |  0.192252 |           |              | code:0
 04:47:26.797603 run-command.c:990            | d0 | main                     | child_exit   |     | 125.792227 |  0.197438 |              | [ch2] pid:5250 code:0
 04:47:26.798766 git.c:674                    | d0 | main                     | exit         |     | 125.793400 |           |              | code:0
 04:47:26.798785 trace2/tr2_tgt_perf.c:213    | d0 | main                     | atexit       |     | 125.793420 |           |              | code:0
 real 125.79
 user 27.50
 sys 4.67
 + cd clone-1
 + time -p git config core.sparseCheckout true
 04:47:26.800741 common-main.c:48             | d0 | main                     | version      |     |           |           |              | 2.26.0
 04:47:26.800766 common-main.c:49             | d0 | main                     | start        |     |  0.000227 |           |              | git config core.sparseCheckout true
 04:47:26.800842 repository.c:130             | d0 | main                     | def_repo     | r1  |           |           |              | worktree:/home/ktokarev/WebKit/clone-1
 04:47:26.800865 git.c:440                    | d0 | main                     | cmd_name     |     |           |           |              | config (config)
 04:47:26.801056 git.c:674                    | d0 | main                     | exit         |     |  0.000517 |           |              | code:0
 04:47:26.801070 trace2/tr2_tgt_perf.c:213    | d0 | main                     | atexit       |     |  0.000532 |           |              | code:0
 real 0.00
 user 0.00
 sys 0.00
 + time -p git sparse-checkout set '/*' '!LayoutTests'
 04:47:26.802461 common-main.c:48             | d0 | main                     | version      |     |           |           |              | 2.26.0
 04:47:26.802502 common-main.c:49             | d0 | main                     | start        |     |  0.000197 |           |              | git sparse-checkout set '/*' ''\!'LayoutTests'
 04:47:26.802570 repository.c:130             | d0 | main                     | def_repo     | r1  |           |           |              | worktree:/home/ktokarev/WebKit/clone-1
 04:47:26.802622 git.c:440                    | d0 | main                     | cmd_name     |     |           |           |              | sparse-checkout (sparse-checkout)
 04:47:26.802679 read-cache.c:2302            | d0 | main                     | region_enter | r1  |  0.000379 |           | index        | label:do_read_index .git/index
 04:47:26.802694 read-cache.c:2307            | d0 | main                     | region_leave | r1  |  0.000394 |  0.000015 | index        | label:do_read_index .git/index
 04:47:26.802808 read-cache.c:2302            | d0 | main                     | region_enter | r1  |  0.000508 |           | index        | label:do_read_index .git/index
 04:47:26.802822 read-cache.c:2307            | d0 | main                     | region_leave | r1  |  0.000522 |  0.000014 | index        | label:do_read_index .git/index
 04:47:26.803076 unpack-trees.c:1450          | d0 | main                     | region_enter | r1  |  0.000777 |           | unpack_trees | label:clear_ce_flags(0x00000000,0x02000000)
 04:47:26.803089 unpack-trees.c:1457          | d0 | main                     | region_leave | r1  |  0.000790 |  0.000013 | unpack_trees | label:clear_ce_flags(0x00000000,0x02000000)
 04:47:27.027728 unpack-trees.c:1450          | d0 | main                     | region_enter | r1  |  0.225425 |           | unpack_trees | label:clear_ce_flags(0x00080000,0x42000000)
 04:47:27.066884 unpack-trees.c:1457          | d0 | main                     | region_leave | r1  |  0.264581 |  0.039156 | unpack_trees | label:clear_ce_flags(0x00080000,0x42000000)
 04:47:38.314514 read-cache.c:3069            | d0 | main                     | region_enter | r1  | 11.512211 |           | index        | label:do_write_index /home/ktokarev/WebKit/clone-1/.git/index.lock
 04:47:38.409842 read-cache.c:3039            | d0 | main                     | data         | r1  | 11.607542 |  0.095331 | index        | ..write/version:3
 04:47:38.409860 read-cache.c:3041            | d0 | main                     | data         | r1  | 11.607560 |  0.095349 | index        | ..write/cache_nr:316371
 04:47:38.409865 read-cache.c:3072            | d0 | main                     | region_leave | r1  | 11.607566 |  0.095355 | index        | label:do_write_index /home/ktokarev/WebKit/clone-1/.git/index.lock
 04:47:38.409915 git.c:674                    | d0 | main                     | exit         |     | 11.607615 |           |              | code:0
 04:47:38.409923 trace2/tr2_tgt_perf.c:213    | d0 | main                     | atexit       |     | 11.607624 |           |              | code:0
 real 11.62
 user 4.84
 sys 1.85
	+ export GIT_TRACE2_PERF=1
	+ GIT_TRACE2_PERF=1
	+ REPO=clone-1
	+ time -p git clone --depth=1 --no-checkout [email protected]:qtwebkit/qtwebkit.git clone-1
	04:45:21.005590 common-main.c:48 \| d0 \| main \| version \| \| \| \| \| 2.26.0
	04:45:21.005619 common-main.c:49 \| d0 \| main \| start \| \| 0.000252 \| \| \| git clone --depth=1 --no-checkout [email protected]:qtwebkit/qtwebkit.git clone-1
	04:45:21.005649 git.c:440 \| d0 \| main \| cmd_name \| \| \| \| \| clone (clone)
	04:45:21.005738 repository.c:130 \| d0 \| main \| def_repo \| r1 \| \| \| \| worktree:/home/ktokarev/WebKit/clone-1
	Cloning into 'clone-1'...
	04:45:21.006966 run-command.c:735 \| d0 \| main \| child_start \| \| 0.001599 \| \| \| [ch0] class:transport/ssh argv:[ssh -o SendEnv=GIT_PROTOCOL [email protected] 'git-upload-pack '\''qtwebkit/qtwebkit.git'\''']
	04:45:22.800099 fetch-pack.c:672 \| d0 \| main \| region_enter \| \| 1.794733 \| \| fetch-pack \| label:parse_remote_refs_and_find_cutoff
	04:45:22.800267 fetch-pack.c:695 \| d0 \| main \| region_leave \| \| 1.794902 \| 0.000169 \| fetch-pack \| label:parse_remote_refs_and_find_cutoff
	04:45:22.800278 fetch-pack.c:701 \| d0 \| main \| region_enter \| \| 1.794914 \| \| fetch-pack \| label:mark_complete_local_refs
	04:45:22.800285 fetch-pack.c:709 \| d0 \| main \| region_leave \| \| 1.794921 \| 0.000007 \| fetch-pack \| label:mark_complete_local_refs
	04:45:22.800292 fetch-pack.c:715 \| d0 \| main \| region_enter \| \| 1.794928 \| \| fetch-pack \| label:mark_common_remote_refs
	04:45:22.800299 fetch-pack.c:728 \| d0 \| main \| region_leave \| \| 1.794934 \| 0.000006 \| fetch-pack \| label:mark_common_remote_refs
	04:45:22.800360 fetch-pack.c:1496 \| d0 \| main \| region_enter \| r1 \| 1.794996 \| \| fetch-pack \| label:negotiation_v2
	04:45:22.800385 fetch-pack.c:1523 \| d0 \| main \| region_leave \| r1 \| 1.795020 \| 0.000024 \| fetch-pack \| label:negotiation_v2
	04:45:22.939119 run-command.c:735 \| d0 \| main \| child_start \| \| 1.933749 \| \| \| [ch1] class:? argv:[git --shallow-file /home/ktokarev/WebKit/clone-1/.git/shallow.lock index-pack --stdin --fix-thin '--keep=fetch-pack 5236 on RedEyes']
	04:45:22.940326 common-main.c:48 \| d1 \| main \| version \| \| \| \| \| 2.26.0
	04:45:22.940359 common-main.c:49 \| d1 \| main \| start \| \| 0.000220 \| \| \| /usr/libexec/git-core/git --shallow-file /home/ktokarev/WebKit/clone-1/.git/shallow.lock index-pack --stdin --fix-thin '--keep=fetch-pack 5236 on RedEyes'
	04:45:22.940433 repository.c:130 \| d1 \| main \| def_repo \| r1 \| \| \| \| worktree:/home/ktokarev/WebKit
	04:45:22.940495 git.c:440 \| d1 \| main \| cmd_name \| \| \| \| \| index-pack (clone/index-pack)
	04:47:26.413280 git.c:674 \| d1 \| main \| exit \| \| 123.473145 \| \| \| code:0
	04:47:26.413307 trace2/tr2_tgt_perf.c:213 \| d1 \| main \| atexit \| \| 123.473174 \| \| \| code:0
	04:47:26.416201 run-command.c:990 \| d0 \| main \| child_exit \| \| 125.410834 \| 123.477085 \| \| [ch1] pid:5239 code:0
	04:47:26.600114 run-command.c:990 \| d0 \| main \| child_exit \| \| 125.594746 \| 125.593147 \| \| [ch0] pid:5237 code:0
	04:47:26.600157 run-command.c:735 \| d0 \| main \| child_start \| \| 125.594789 \| \| \| [ch2] class:? argv:[git rev-list --objects --stdin --not --all --quiet --alternate-refs]
	04:47:26.601192 common-main.c:48 \| d1 \| main \| version \| \| \| \| \| 2.26.0
	04:47:26.601220 common-main.c:49 \| d1 \| main \| start \| \| 0.000205 \| \| \| /usr/libexec/git-core/git rev-list --objects --stdin --not --all --quiet --alternate-refs
	04:47:26.601286 repository.c:130 \| d1 \| main \| def_repo \| r1 \| \| \| \| worktree:/home/ktokarev/WebKit
	04:47:26.601335 git.c:440 \| d1 \| main \| cmd_name \| \| \| \| \| rev-list (clone/rev-list)
	04:47:26.793235 git.c:674 \| d1 \| main \| exit \| \| 0.192222 \| \| \| code:0
	04:47:26.793262 trace2/tr2_tgt_perf.c:213 \| d1 \| main \| atexit \| \| 0.192252 \| \| \| code:0
	04:47:26.797603 run-command.c:990 \| d0 \| main \| child_exit \| \| 125.792227 \| 0.197438 \| \| [ch2] pid:5250 code:0
	04:47:26.798766 git.c:674 \| d0 \| main \| exit \| \| 125.793400 \| \| \| code:0
	04:47:26.798785 trace2/tr2_tgt_perf.c:213 \| d0 \| main \| atexit \| \| 125.793420 \| \| \| code:0
	real 125.79
	user 27.50
	sys 4.67
	+ cd clone-1
	+ time -p git config core.sparseCheckout true
	04:47:26.800741 common-main.c:48 \| d0 \| main \| version \| \| \| \| \| 2.26.0
	04:47:26.800766 common-main.c:49 \| d0 \| main \| start \| \| 0.000227 \| \| \| git config core.sparseCheckout true
	04:47:26.800842 repository.c:130 \| d0 \| main \| def_repo \| r1 \| \| \| \| worktree:/home/ktokarev/WebKit/clone-1
	04:47:26.800865 git.c:440 \| d0 \| main \| cmd_name \| \| \| \| \| config (config)
	04:47:26.801056 git.c:674 \| d0 \| main \| exit \| \| 0.000517 \| \| \| code:0
	04:47:26.801070 trace2/tr2_tgt_perf.c:213 \| d0 \| main \| atexit \| \| 0.000532 \| \| \| code:0
	real 0.00
	user 0.00
	sys 0.00
	+ time -p git sparse-checkout set '/*' '!LayoutTests'
	04:47:26.802461 common-main.c:48 \| d0 \| main \| version \| \| \| \| \| 2.26.0
	04:47:26.802502 common-main.c:49 \| d0 \| main \| start \| \| 0.000197 \| \| \| git sparse-checkout set '/*' ''\!'LayoutTests'
	04:47:26.802570 repository.c:130 \| d0 \| main \| def_repo \| r1 \| \| \| \| worktree:/home/ktokarev/WebKit/clone-1
	04:47:26.802622 git.c:440 \| d0 \| main \| cmd_name \| \| \| \| \| sparse-checkout (sparse-checkout)
	04:47:26.802679 read-cache.c:2302 \| d0 \| main \| region_enter \| r1 \| 0.000379 \| \| index \| label:do_read_index .git/index
	04:47:26.802694 read-cache.c:2307 \| d0 \| main \| region_leave \| r1 \| 0.000394 \| 0.000015 \| index \| label:do_read_index .git/index
	04:47:26.802808 read-cache.c:2302 \| d0 \| main \| region_enter \| r1 \| 0.000508 \| \| index \| label:do_read_index .git/index
	04:47:26.802822 read-cache.c:2307 \| d0 \| main \| region_leave \| r1 \| 0.000522 \| 0.000014 \| index \| label:do_read_index .git/index
	04:47:26.803076 unpack-trees.c:1450 \| d0 \| main \| region_enter \| r1 \| 0.000777 \| \| unpack_trees \| label:clear_ce_flags(0x00000000,0x02000000)
	04:47:26.803089 unpack-trees.c:1457 \| d0 \| main \| region_leave \| r1 \| 0.000790 \| 0.000013 \| unpack_trees \| label:clear_ce_flags(0x00000000,0x02000000)
	04:47:27.027728 unpack-trees.c:1450 \| d0 \| main \| region_enter \| r1 \| 0.225425 \| \| unpack_trees \| label:clear_ce_flags(0x00080000,0x42000000)
	04:47:27.066884 unpack-trees.c:1457 \| d0 \| main \| region_leave \| r1 \| 0.264581 \| 0.039156 \| unpack_trees \| label:clear_ce_flags(0x00080000,0x42000000)
	04:47:38.314514 read-cache.c:3069 \| d0 \| main \| region_enter \| r1 \| 11.512211 \| \| index \| label:do_write_index /home/ktokarev/WebKit/clone-1/.git/index.lock
	04:47:38.409842 read-cache.c:3039 \| d0 \| main \| data \| r1 \| 11.607542 \| 0.095331 \| index \| ..write/version:3
	04:47:38.409860 read-cache.c:3041 \| d0 \| main \| data \| r1 \| 11.607560 \| 0.095349 \| index \| ..write/cache_nr:316371
	04:47:38.409865 read-cache.c:3072 \| d0 \| main \| region_leave \| r1 \| 11.607566 \| 0.095355 \| index \| label:do_write_index /home/ktokarev/WebKit/clone-1/.git/index.lock
	04:47:38.409915 git.c:674 \| d0 \| main \| exit \| \| 11.607615 \| \| \| code:0
	04:47:38.409923 trace2/tr2_tgt_perf.c:213 \| d0 \| main \| atexit \| \| 11.607624 \| \| \| code:0
	real 11.62
	user 4.84
	sys 1.85