Using "taskset -c" seems to help collecting longer traces: the largest trace I have seen with taskset is 77MB and the largest trace that I see when the process is allowed to be moved to different cpus is 28MB.
I also see warnings on dmesg: coresight-tmc 20070000.etr: timeout while waiting for completion of Manual Flush
Sebastian
++ seq 1 10 + for i in '$(seq 1 10)' + /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u --per-thread taskset -c 2 ./sort Bubble sorting array of 30000 elements 10201 ms [ perf record: Woken up 24 times to write data ] Warning: AUX data lost 4 times out of 80!
[ perf record: Captured and wrote 48.059 MB perf.data ] + for i in '$(seq 1 10)' + /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u --per-thread taskset -c 2 ./sort Bubble sorting array of 30000 elements 10378 ms [ perf record: Woken up 24 times to write data ] Warning: AUX data lost 9 times out of 68!
[ perf record: Captured and wrote 52.353 MB perf.data ] + for i in '$(seq 1 10)' + /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u --per-thread taskset -c 2 ./sort Bubble sorting array of 30000 elements 11220 ms [ perf record: Woken up 29 times to write data ] Warning: AUX data lost 3 times out of 113!
[ perf record: Captured and wrote 60.112 MB perf.data ] + for i in '$(seq 1 10)' + /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u --per-thread taskset -c 2 ./sort Bubble sorting array of 30000 elements 10912 ms [ perf record: Woken up 28 times to write data ] Warning: AUX data lost 5 times out of 99!
[ perf record: Captured and wrote 57.794 MB perf.data ] + for i in '$(seq 1 10)' + /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u --per-thread taskset -c 2 ./sort Bubble sorting array of 30000 elements 11706 ms [ perf record: Woken up 32 times to write data ] Warning: AUX data lost 2 times out of 125!
[ perf record: Captured and wrote 62.741 MB perf.data ] + for i in '$(seq 1 10)' + /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u --per-thread taskset -c 2 ./sort Bubble sorting array of 30000 elements 11314 ms [ perf record: Woken up 30 times to write data ] Warning: AUX data lost 4 times out of 105!
[ perf record: Captured and wrote 59.480 MB perf.data ] + for i in '$(seq 1 10)' + /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u --per-thread taskset -c 2 ./sort Bubble sorting array of 30000 elements 11737 ms [ perf record: Woken up 35 times to write data ] Warning: AUX data lost 15 times out of 71!
[ perf record: Captured and wrote 70.489 MB perf.data ] + for i in '$(seq 1 10)' + /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u --per-thread taskset -c 2 ./sort Bubble sorting array of 30000 elements 10418 ms [ perf record: Woken up 26 times to write data ] Warning: AUX data lost 9 times out of 69!
[ perf record: Captured and wrote 51.939 MB perf.data ] + for i in '$(seq 1 10)' + /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u --per-thread taskset -c 2 ./sort Bubble sorting array of 30000 elements 11825 ms [ perf record: Woken up 26 times to write data ] Warning: AUX data lost 25 times out of 31!
[ perf record: Captured and wrote 76.481 MB perf.data ] + for i in '$(seq 1 10)' + /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u --per-thread taskset -c 2 ./sort Bubble sorting array of 30000 elements 11824 ms [ perf record: Woken up 26 times to write data ] Warning: AUX data lost 25 times out of 31!
[ perf record: Captured and wrote 76.712 MB perf.data ]
On Thu, Dec 15, 2016 at 3:18 PM, Sebastian Pop sebpop@gmail.com wrote:
Mathieu, with today's perf-opencsd-4.9 branch I get larger traces, although I still see big variability and warnings about lost data. Changing the default ETR arm,buffer-size to 3MB I get larger traces, and still variable length:
1MB ETR:
++ seq 1 10
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 7895 ms [ perf record: Woken up 3 times to write data ] Warning: AUX data lost 3 times out of 4!
[ perf record: Captured and wrote 3.116 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 6946 ms [ perf record: Woken up 9 times to write data ] Warning: AUX data lost 9 times out of 15!
[ perf record: Captured and wrote 10.019 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 8245 ms [ perf record: Woken up 7 times to write data ] Warning: AUX data lost 7 times out of 22!
[ perf record: Captured and wrote 7.584 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 7181 ms [ perf record: Woken up 5 times to write data ] Warning: AUX data lost 5 times out of 7!
[ perf record: Captured and wrote 5.245 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 8354 ms [ perf record: Woken up 9 times to write data ] Warning: AUX data lost 8 times out of 17!
[ perf record: Captured and wrote 9.167 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 6458 ms [ perf record: Woken up 5 times to write data ] Warning: AUX data lost 4 times out of 10!
[ perf record: Captured and wrote 5.535 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 8247 ms [ perf record: Woken up 6 times to write data ] Warning: AUX data lost 6 times out of 14!
[ perf record: Captured and wrote 7.926 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 8495 ms [ perf record: Woken up 11 times to write data ] Warning: AUX data lost 9 times out of 33!
[ perf record: Captured and wrote 12.972 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 7446 ms [ perf record: Woken up 13 times to write data ] Warning: AUX data lost 11 times out of 24!
[ perf record: Captured and wrote 13.055 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 6652 ms [ perf record: Woken up 5 times to write data ] Warning: AUX data lost 5 times out of 9!
[ perf record: Captured and wrote 5.426 MB perf.data ]
With 3MB ETR:
++ seq 1 10
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 8283 ms [ perf record: Woken up 10 times to write data ] Warning: AUX data lost 8 times out of 10!
[ perf record: Captured and wrote 24.446 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 7418 ms [ perf record: Woken up 8 times to write data ] Warning: AUX data lost 4 times out of 11!
[ perf record: Captured and wrote 16.099 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 7869 ms [ perf record: Woken up 10 times to write data ] Warning: AUX data lost 7 times out of 14!
[ perf record: Captured and wrote 21.717 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 9250 ms [ perf record: Woken up 11 times to write data ] Warning: AUX data lost 7 times out of 14!
[ perf record: Captured and wrote 23.431 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 7748 ms [ perf record: Woken up 6 times to write data ] Warning: AUX data lost 5 times out of 7!
[ perf record: Captured and wrote 15.176 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 8185 ms [ perf record: Woken up 4 times to write data ] Warning: AUX data lost 4 times out of 5!
[ perf record: Captured and wrote 12.007 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 8886 ms [ perf record: Woken up 11 times to write data ] Warning: AUX data lost 6 times out of 20!
[ perf record: Captured and wrote 23.402 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 8866 ms [ perf record: Woken up 8 times to write data ] Warning: AUX data lost 6 times out of 12!
[ perf record: Captured and wrote 19.989 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 8994 ms [ perf record: Woken up 10 times to write data ] Warning: AUX data lost 8 times out of 10!
[ perf record: Captured and wrote 24.038 MB perf.data ]
- for i in '$(seq 1 10)'
- /root/etm/OpenCSD/tools/perf/perf record -e cs_etm/@20070000.etr/u
--per-thread ./sort Bubble sorting array of 30000 elements 8753 ms [ perf record: Woken up 13 times to write data ] Warning: AUX data lost 8 times out of 34!
[ perf record: Captured and wrote 27.545 MB perf.data ]