I have also seen a similar behavior on intel-pt when recording huge traces:
$ perf record -e intel_pt//u taskset -c 2 ./sort
Bubble sorting array of 70000 elements
7462 ms
[ perf record: Woken up 25808 times to write data ]
Warning:
AUX data lost 8 times out of 51593!
[ perf record: Captured and wrote 1616.765 MB perf.data ]
On Thu, Dec 15, 2016 at 4:03 PM, Sebastian Pop <sebpop@gmail.com> wrote:
> 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 ]
_______________________________________________
CoreSight mailing list
CoreSight@lists.linaro.org
https://lists.linaro.org/mailman/listinfo/coresight