Counting ins1 calls

ins1.txt

53236 ins1 called 3 times
53238 ins1 called 7 times
53284 ins1 called 7 times
53310 ins1 called 7 times
53333 ins1 called 7 times
53354 ins1 called 7 times
53378 ins1 called 7 times
53399 ins1 called 7 times
53420 ins1 called 7 times
53441 ins1 called 7 times
53463 ins1 called 7 times
53506 ins1 called 7 times
53532 ins1 called 7 times
53555 ins1 called 7 times
53579 ins1 called 7 times
53600 ins1 called 7 times
53621 ins1 called 7 times
53642 ins1 called 7 times
53674 ins1 called 7 times
53699 ins1 called 7 times
53723 ins1 called 7 times
53744 ins1 called 7 times
53237 ins1 called 4 times
53769 ins1 called 102 times
53774 ins1 called 102 times
53782 ins1 called 102 times
53790 ins1 called 102 times
53798 ins1 called 102 times
53803 ins1 called 102 times
53811 ins1 called 102 times
53819 ins1 called 102 times
53827 ins1 called 102 times
53832 ins1 called 102 times
53840 ins1 called 102 times
53848 ins1 called 102 times
53856 ins1 called 102 times
53861 ins1 called 102 times
53870 ins1 called 102 times
53881 ins1 called 102 times
53889 ins1 called 102 times
53897 ins1 called 102 times
53902 ins1 called 102 times
53910 ins1 called 102 times
53918 ins1 called 102 times
53765 ins1 called 100 times
53927 ins1 called 7 times
53938 ins1 called 7 times
53949 ins1 called 7 times
53960 ins1 called 7 times
53971 ins1 called 7 times
53982 ins1 called 7 times
53993 ins1 called 7 times
54004 ins1 called 7 times
54015 ins1 called 7 times
54026 ins1 called 7 times
54037 ins1 called 7 times
54054 ins1 called 7 times
54084 ins1 called 7 times
54095 ins1 called 7 times
54118 ins1 called 7 times
54134 ins1 called 7 times
54145 ins1 called 7 times
54162 ins1 called 7 times
54173 ins1 called 7 times
54184 ins1 called 7 times
54195 ins1 called 7 times
53926 ins1 called 4 times
54208 ins1 called 7 times
54219 ins1 called 7 times
54238 ins1 called 7 times
54273 ins1 called 7 times
54287 ins1 called 7 times
54298 ins1 called 7 times
54312 ins1 called 7 times
54323 ins1 called 7 times
54334 ins1 called 7 times
54349 ins1 called 7 times
54360 ins1 called 7 times
54374 ins1 called 7 times
54385 ins1 called 7 times
54396 ins1 called 7 times
54410 ins1 called 7 times
54421 ins1 called 7 times
54432 ins1 called 7 times
54443 ins1 called 7 times
54457 ins1 called 7 times
54468 ins1 called 7 times
54482 ins1 called 7 times
54207 ins1 called 4 times
54507 ins1 called 7 times
54516 ins1 called 7 times
54521 ins1 called 7 times
54526 ins1 called 7 times
54534 ins1 called 7 times
54539 ins1 called 7 times
54544 ins1 called 7 times
54552 ins1 called 7 times
54557 ins1 called 7 times
54562 ins1 called 7 times
54570 ins1 called 7 times
54575 ins1 called 7 times
54580 ins1 called 7 times
54588 ins1 called 7 times
54593 ins1 called 7 times
54598 ins1 called 7 times
54606 ins1 called 7 times
54611 ins1 called 7 times
54616 ins1 called 7 times
54625 ins1 called 7 times
54631 ins1 called 7 times
54506 ins1 called 4 times
54640 ins1 called 40050 times
54651 ins1 called 40050 times
54662 ins1 called 40050 times
54676 ins1 called 40050 times
54687 ins1 called 40050 times
54698 ins1 called 40050 times
54709 ins1 called 40050 times
54723 ins1 called 40050 times
54734 ins1 called 40050 times
54753 ins1 called 40050 times
54764 ins1 called 40050 times
54778 ins1 called 40050 times
54791 ins1 called 40050 times
54805 ins1 called 40050 times
54816 ins1 called 40050 times
54827 ins1 called 40050 times
54838 ins1 called 40050 times
54854 ins1 called 40050 times
54865 ins1 called 40050 times
54876 ins1 called 40050 times
54887 ins1 called 40050 times
54636 ins1 called 47 times
54902 ins1 called 7 times
54910 ins1 called 7 times
54918 ins1 called 7 times
54926 ins1 called 7 times
54934 ins1 called 7 times
54942 ins1 called 7 times
54950 ins1 called 7 times
54958 ins1 called 7 times
54966 ins1 called 7 times
54974 ins1 called 7 times
54979 ins1 called 7 times
54988 ins1 called 7 times
54996 ins1 called 7 times
55004 ins1 called 7 times
55012 ins1 called 7 times
55020 ins1 called 7 times
55028 ins1 called 7 times
55036 ins1 called 7 times
55044 ins1 called 7 times
55052 ins1 called 7 times
55060 ins1 called 7 times
54901 ins1 called 4 times
53235 ins1 called 18 times

instrumentation.patch

diff --git a/Include/listobject.h b/Include/listobject.h
index 6057279d51..1b04ab77e6 100644
--- a/Include/listobject.h
+++ b/Include/listobject.h
@@ -75,6 +75,8 @@ PyAPI_FUNC(void) _PyList_DebugMallocStats(FILE *out);
 #define _PyList_ITEMS(op)      (((PyListObject *)(op))->ob_item)
 #endif
 
+void print_ins1_metrics(void);
+
 #ifdef __cplusplus
 }
 #endif
diff --git a/Modules/main.c b/Modules/main.c
index dff7894bf3..065e4c79b9 100644
--- a/Modules/main.c
+++ b/Modules/main.c
@@ -2667,7 +2667,9 @@ _Py_UnixMain(int argc, char **argv)
     pymain.argc = argc;
     pymain.bytes_argv = argv;
 
-    return pymain_main(&pymain);
+    int r = pymain_main(&pymain);
+    print_ins1_metrics();
+    return r;
 }
 
 
diff --git a/Objects/listobject.c b/Objects/listobject.c
index 8794e37364..bd57279491 100644
--- a/Objects/listobject.c
+++ b/Objects/listobject.c
@@ -234,10 +234,21 @@ PyList_SetItem(PyObject *op, Py_ssize_t i,
     return 0;
 }
 
+static int ins1_calls = 0;
+
+void print_ins1_metrics(void) {
+    FILE *fp = fopen("/tmp/ins1.txt", "a");
+    fprintf(fp, "%d ins1 called %d times\n", (int)getpid(), ins1_calls);
+    fclose(fp);
+}
+
 static int
 ins1(PyListObject *self, Py_ssize_t where, PyObject *v)
 {
     Py_ssize_t i, n = Py_SIZE(self);
+
+    ins1_calls++;
+
     PyObject **items;
     if (v == NULL) {
         PyErr_BadInternalCall();

pyperformance

jeethu-mbp:cpython jeethu$ pyperformance run -b 2to3,chameleon,chaos,crypto_pyaes,deltablue,django_template,dulwich_log -p ./python.exe
Python benchmark suite 0.6.0

[1/7] 2to3...
INFO:root:Running `/Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/bin/python -u /Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/lib/python3.7/site-packages/performance/benchmarks/bm_2to3.py --output /var/folders/pp/7g5yd1gx2_d1v_4k_8689zkhtrxwqs/T/tmp9k8vhe84`
.....................
2to3: Mean +- std dev: 2.19 sec +- 0.07 sec
[2/7] chameleon...
INFO:root:Running `/Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/bin/python -u /Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/lib/python3.7/site-packages/performance/benchmarks/bm_chameleon.py --output /var/folders/pp/7g5yd1gx2_d1v_4k_8689zkhtrxwqs/T/tmp5s1rlash`
.....................
chameleon: Mean +- std dev: 98.4 ms +- 4.4 ms
[3/7] chaos...
INFO:root:Running `/Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/bin/python -u /Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/lib/python3.7/site-packages/performance/benchmarks/bm_chaos.py --output /var/folders/pp/7g5yd1gx2_d1v_4k_8689zkhtrxwqs/T/tmpdpo24i2l`
.....................
chaos: Mean +- std dev: 901 ms +- 22 ms
[4/7] crypto_pyaes...
INFO:root:Running `/Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/bin/python -u /Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/lib/python3.7/site-packages/performance/benchmarks/bm_crypto_pyaes.py --output /var/folders/pp/7g5yd1gx2_d1v_4k_8689zkhtrxwqs/T/tmp704q_1mb`
.....................
crypto_pyaes: Mean +- std dev: 1.19 sec +- 0.05 sec
[5/7] deltablue...
INFO:root:Running `/Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/bin/python -u /Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/lib/python3.7/site-packages/performance/benchmarks/bm_deltablue.py --output /var/folders/pp/7g5yd1gx2_d1v_4k_8689zkhtrxwqs/T/tmpf7xxq04h`
.....................
deltablue: Mean +- std dev: 53.1 ms +- 2.2 ms
[6/7] django_template...
INFO:root:Running `/Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/bin/python -u /Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/lib/python3.7/site-packages/performance/benchmarks/bm_django_template.py --output /var/folders/pp/7g5yd1gx2_d1v_4k_8689zkhtrxwqs/T/tmp62zmwy5u`
.....................
django_template: Mean +- std dev: 1.01 sec +- 0.04 sec
[7/7] dulwich_log...
INFO:root:Running `/Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/bin/python -u /Users/jeethu/code/cpython/venv/cpython3.7-7cfdf137d07e/lib/python3.7/site-packages/performance/benchmarks/bm_dulwich_log.py --output /var/folders/pp/7g5yd1gx2_d1v_4k_8689zkhtrxwqs/T/tmp839z1gyz`
.....................
dulwich_log: Mean +- std dev: 416 ms +- 23 ms

Performance version: 0.6.0
Python version: 3.7.0a4+ (64-bit) revision 44a70e9336
Report on Darwin-17.3.0-x86_64-i386-64bit
Number of logical CPUs: 8
Start date: 2018-01-17 14:55:35.820745
End date: 2018-01-17 15:05:04.081538

### 2to3 ###
Mean +- std dev: 2.19 sec +- 0.07 sec

### chameleon ###
Mean +- std dev: 98.4 ms +- 4.4 ms

### chaos ###
Mean +- std dev: 901 ms +- 22 ms

### crypto_pyaes ###
Mean +- std dev: 1.19 sec +- 0.05 sec

### deltablue ###
Mean +- std dev: 53.1 ms +- 2.2 ms

### django_template ###
Mean +- std dev: 1.01 sec +- 0.04 sec

### dulwich_log ###
Mean +- std dev: 416 ms +- 23 ms