diff options
-rw-r--r-- | oe-build-perf-test.log | 54 | ||||
-rw-r--r-- | results.xml | 70 |
2 files changed, 62 insertions, 62 deletions
diff --git a/oe-build-perf-test.log b/oe-build-perf-test.log index b82cdc420c..6c863ca71e 100644 --- a/oe-build-perf-test.log +++ b/oe-build-perf-test.log @@ -1,27 +1,27 @@ -[2016-12-25 10:05:11,393] INFO: Using Git branch:commit master:dbb247cac5fbf7b037e4955f9793828451723924 (43626) -[2016-12-25 10:05:11,394] INFO: Executing test test1: Measure wall clock of bitbake core-image-sato and size of tmp dir -[2016-12-25 10:05:11,394] INFO: Logging command: bitbake core-image-sato -c fetchall -[2016-12-25 10:10:29,025] INFO: Timing command: bitbake core-image-sato -[2016-12-25 11:29:31,589] INFO: Saving buildstats in JSON format -[2016-12-25 11:40:28,697] INFO: Executing test test12: Measure bitbake virtual/kernel -[2016-12-25 11:40:28,714] INFO: Logging command: bitbake virtual/kernel -c fetchall -[2016-12-25 11:40:47,472] INFO: Logging command: bitbake virtual/kernel -[2016-12-25 11:40:50,598] INFO: Logging command: bitbake virtual/kernel -c cleansstate -[2016-12-25 11:41:25,987] INFO: Timing command: bitbake virtual/kernel -[2016-12-25 11:47:40,986] INFO: Executing test test13: Build core-image-sato with rm_work enabled -[2016-12-25 11:47:40,986] INFO: Logging command: bitbake core-image-sato -c fetchall -[2016-12-25 12:05:57,714] INFO: Timing command: bitbake -R /home/yocto/poky-perf/build-perf-test/results-dbb247c-20161225100505/test13/postfile.conf core-image-sato -[2016-12-25 13:17:49,816] INFO: Saving buildstats in JSON format -[2016-12-25 13:20:28,939] INFO: Executing test test2: Measure bitbake core-image-sato -c rootfs with sstate -[2016-12-25 13:20:28,939] INFO: Logging command: bitbake core-image-sato -c fetchall -[2016-12-25 13:21:09,443] INFO: Logging command: bitbake core-image-sato -[2016-12-25 13:22:24,221] INFO: Timing command: bitbake core-image-sato -c rootfs -[2016-12-25 13:25:28,238] INFO: Executing test test3: Parsing time metrics (bitbake -p) -[2016-12-25 13:25:28,266] INFO: Timing command: bitbake -p -[2016-12-25 13:25:52,701] INFO: Timing command: bitbake -p -[2016-12-25 13:26:08,386] INFO: Timing command: bitbake -p -[2016-12-25 13:26:09,816] INFO: Executing test test4: eSDK metrics -[2016-12-25 13:26:09,816] INFO: Logging command: bitbake core-image-sato -c fetchall -[2016-12-25 13:26:31,840] INFO: Logging command: bitbake core-image-sato -c do_populate_sdk_ext -[2016-12-25 13:56:17,523] INFO: Timing command: /home/yocto/poky-perf/build-perf-test/build-dbb247c-20161225100505/tmp/deploy/sdk/poky-glibc-x86_64-core-image-sato-i586-toolchain-ext-2.2.sh -y -d /home/yocto/poky-perf/build-perf-test/build-dbb247c-20161225100505/tmp/esdk-deploy -[2016-12-25 13:59:20,137] INFO: Committing test results into /home/yocto/perf-results/results.git ypperf-fedora22/master/qemux86 +[2016-12-25 15:05:10,102] INFO: Using Git branch:commit master:dbb247cac5fbf7b037e4955f9793828451723924 (43626) +[2016-12-25 15:05:10,102] INFO: Executing test test1: Measure wall clock of bitbake core-image-sato and size of tmp dir +[2016-12-25 15:05:10,103] INFO: Logging command: bitbake core-image-sato -c fetchall +[2016-12-25 15:10:27,576] INFO: Timing command: bitbake core-image-sato +[2016-12-25 16:27:31,065] INFO: Saving buildstats in JSON format +[2016-12-25 16:38:17,269] INFO: Executing test test12: Measure bitbake virtual/kernel +[2016-12-25 16:38:17,269] INFO: Logging command: bitbake virtual/kernel -c fetchall +[2016-12-25 16:38:35,960] INFO: Logging command: bitbake virtual/kernel +[2016-12-25 16:38:39,094] INFO: Logging command: bitbake virtual/kernel -c cleansstate +[2016-12-25 16:39:04,762] INFO: Timing command: bitbake virtual/kernel +[2016-12-25 16:45:02,199] INFO: Executing test test13: Build core-image-sato with rm_work enabled +[2016-12-25 16:45:02,199] INFO: Logging command: bitbake core-image-sato -c fetchall +[2016-12-25 17:03:02,089] INFO: Timing command: bitbake -R /home/yocto/poky-perf/build-perf-test/results-dbb247c-20161225150504/test13/postfile.conf core-image-sato +[2016-12-25 18:14:57,174] INFO: Saving buildstats in JSON format +[2016-12-25 18:18:05,343] INFO: Executing test test2: Measure bitbake core-image-sato -c rootfs with sstate +[2016-12-25 18:18:05,343] INFO: Logging command: bitbake core-image-sato -c fetchall +[2016-12-25 18:18:43,894] INFO: Logging command: bitbake core-image-sato +[2016-12-25 18:20:06,923] INFO: Timing command: bitbake core-image-sato -c rootfs +[2016-12-25 18:23:18,389] INFO: Executing test test3: Parsing time metrics (bitbake -p) +[2016-12-25 18:23:18,409] INFO: Timing command: bitbake -p +[2016-12-25 18:23:42,603] INFO: Timing command: bitbake -p +[2016-12-25 18:23:58,129] INFO: Timing command: bitbake -p +[2016-12-25 18:23:59,513] INFO: Executing test test4: eSDK metrics +[2016-12-25 18:23:59,513] INFO: Logging command: bitbake core-image-sato -c fetchall +[2016-12-25 18:24:21,300] INFO: Logging command: bitbake core-image-sato -c do_populate_sdk_ext +[2016-12-25 18:53:33,699] INFO: Timing command: /home/yocto/poky-perf/build-perf-test/build-dbb247c-20161225150504/tmp/deploy/sdk/poky-glibc-x86_64-core-image-sato-i586-toolchain-ext-2.2.sh -y -d /home/yocto/poky-perf/build-perf-test/build-dbb247c-20161225150504/tmp/esdk-deploy +[2016-12-25 18:56:31,374] INFO: Committing test results into /home/yocto/perf-results/results.git ypperf-fedora22/master/qemux86 diff --git a/results.xml b/results.xml index bb31e8ed9a..896eff2eda 100644 --- a/results.xml +++ b/results.xml @@ -1,68 +1,68 @@ <?xml version="1.0" encoding="utf-8"?> <testsuites> - <testsuite errors="0" failures="0" hostname="ypperf-fedora22" name="oeqa.buildperf" skipped="0" tests="6" time="14047.189287" timestamp="2016-12-25T08:05:11.394085"> - <testcase classname="oeqa.buildperf.test_basic.Test1P1" description="Measure wall clock of bitbake core-image-sato and size of tmp dir" name="test1" time="5717.280144" timestamp="2016-12-25T10:05:11.394149"> + <testsuite errors="0" failures="0" hostname="ypperf-fedora22" name="oeqa.buildperf" skipped="0" tests="6" time="13879.737683" timestamp="2016-12-25T13:05:10.102752"> + <testcase classname="oeqa.buildperf.test_basic.Test1P1" description="Measure wall clock of bitbake core-image-sato and size of tmp dir" name="test1" time="5587.140465" timestamp="2016-12-25T15:05:10.102812"> <sysres legend="bitbake core-image-sato" name="build"> - <time timestamp="2016-12-25T10:10:29.254110">4741.77909</time> - <iostat cancelled_write_bytes="6594093056" rchar="252137741034" read_bytes="8550174720" syscr="90846253" syscw="55094634" wchar="70803846175" write_bytes="42900508672"/> - <rusage ru_inblock="16695736" ru_majflt="97708" ru_maxrss="917780" ru_minflt="1235118435" ru_nivcsw="29218317" ru_nvcsw="31651653" ru_oublock="83790056" ru_stime="4894.964296" ru_utime="24587.209189"/> + <time timestamp="2016-12-25T15:10:27.876353">4622.577383</time> + <iostat cancelled_write_bytes="6630887424" rchar="252121169639" read_bytes="8539533312" syscr="90828743" syscw="55114703" wchar="70798907084" write_bytes="42895175680"/> + <rusage ru_inblock="16675072" ru_majflt="97300" ru_maxrss="917892" ru_minflt="1235136011" ru_nivcsw="29065475" ru_nvcsw="32264840" ru_oublock="83779640" ru_stime="4826.970815" ru_utime="24308.33995"/> </sysres> <diskusage legend="tmpdir" name="tmpdir"> - <size>27053784</size> + <size>27049900</size> </diskusage> </testcase> - <testcase classname="oeqa.buildperf.test_basic.Test1P2" description="Measure bitbake virtual/kernel" name="test12" time="432.302513" timestamp="2016-12-25T11:40:28.683626"> + <testcase classname="oeqa.buildperf.test_basic.Test1P2" description="Measure bitbake virtual/kernel" name="test12" time="404.945738" timestamp="2016-12-25T16:38:17.253456"> <sysres legend="bitbake virtual/kernel" name="build"> - <time timestamp="2016-12-25T11:41:26.109371">374.875443</time> - <iostat cancelled_write_bytes="297304064" rchar="17599147505" read_bytes="855875584" syscr="4073522" syscw="1565529" wchar="2118618416" write_bytes="1898819584"/> - <rusage ru_inblock="1671568" ru_majflt="4997" ru_maxrss="599532" ru_minflt="58502501" ru_nivcsw="281905" ru_nvcsw="720132" ru_oublock="3708632" ru_stime="165.004717" ru_utime="1563.209287"/> + <time timestamp="2016-12-25T16:39:04.852869">357.344934</time> + <iostat cancelled_write_bytes="297304064" rchar="17618302151" read_bytes="841822208" syscr="4091316" syscw="1565925" wchar="2118642819" write_bytes="1898815488"/> + <rusage ru_inblock="1644064" ru_majflt="5041" ru_maxrss="598380" ru_minflt="58466163" ru_nivcsw="280979" ru_nvcsw="720346" ru_oublock="3708624" ru_stime="164.730634" ru_utime="1568.954934"/> </sysres> </testcase> - <testcase classname="oeqa.buildperf.test_basic.Test1P3" description="Build core-image-sato with rm_work enabled" name="test13" time="5567.941031" timestamp="2016-12-25T11:47:40.986207"> + <testcase classname="oeqa.buildperf.test_basic.Test1P3" description="Build core-image-sato with rm_work enabled" name="test13" time="5583.121602" timestamp="2016-12-25T16:45:02.199265"> <sysres legend="bitbakecore-image-sato" name="build"> - <time timestamp="2016-12-25T12:05:57.791887">4311.779686</time> - <iostat cancelled_write_bytes="9027022848" rchar="252413505759" read_bytes="5401780224" syscr="92327440" syscw="55239154" wchar="70999567311" write_bytes="42915979264"/> - <rusage ru_inblock="10547480" ru_majflt="12581" ru_maxrss="917848" ru_minflt="1242074531" ru_nivcsw="33048550" ru_nvcsw="26152884" ru_oublock="83820272" ru_stime="5038.356578" ru_utime="25145.444333"/> + <time timestamp="2016-12-25T17:03:02.166325">4314.939159</time> + <iostat cancelled_write_bytes="9165991936" rchar="252467714170" read_bytes="5371756544" syscr="92244435" syscw="55256741" wchar="71003404792" write_bytes="42916904960"/> + <rusage ru_inblock="10488584" ru_majflt="12881" ru_maxrss="917776" ru_minflt="1245789833" ru_nivcsw="33204561" ru_nvcsw="26207852" ru_oublock="83822080" ru_stime="5052.731054" ru_utime="25237.086053"/> </sysres> <diskusage legend="tmpdir" name="tmpdir"> - <size>6607400</size> + <size>6607408</size> </diskusage> </testcase> - <testcase classname="oeqa.buildperf.test_basic.Test2" description="Measure bitbake core-image-sato -c rootfs with sstate" name="test2" time="299.31067" timestamp="2016-12-25T13:20:28.927330"> + <testcase classname="oeqa.buildperf.test_basic.Test2" description="Measure bitbake core-image-sato -c rootfs with sstate" name="test2" time="313.068123" timestamp="2016-12-25T18:18:05.320949"> <sysres legend="bitbake do_rootfs" name="do_rootfs"> - <time timestamp="2016-12-25T13:22:24.544604">183.69215</time> - <iostat cancelled_write_bytes="152653824" rchar="5216414792" read_bytes="1121910784" syscr="1606305" syscw="1040825" wchar="3870059480" write_bytes="2075566080"/> - <rusage ru_inblock="2191160" ru_majflt="393" ru_maxrss="177168" ru_minflt="15469313" ru_nivcsw="115669" ru_nvcsw="699989" ru_oublock="4053840" ru_stime="61.729504" ru_utime="440.869599"/> + <time timestamp="2016-12-25T18:20:07.171998">191.215736</time> + <iostat cancelled_write_bytes="154075136" rchar="5058323023" read_bytes="1126301696" syscr="1608732" syscw="1041454" wchar="3872288733" write_bytes="2075926528"/> + <rusage ru_inblock="2199720" ru_majflt="394" ru_maxrss="176100" ru_minflt="15114647" ru_nivcsw="109067" ru_nvcsw="704863" ru_oublock="4054544" ru_stime="60.474807" ru_utime="436.328296"/> </sysres> </testcase> - <testcase classname="oeqa.buildperf.test_basic.Test3" description="Parsing time metrics (bitbake -p)" name="test3" time="41.578031" timestamp="2016-12-25T13:25:28.238064"> + <testcase classname="oeqa.buildperf.test_basic.Test3" description="Parsing time metrics (bitbake -p)" name="test3" time="41.124111" timestamp="2016-12-25T18:23:18.389139"> <sysres legend="bitbake -p (no caches)" name="parse_1"> - <time timestamp="2016-12-25T13:25:28.267813">24.429986</time> - <iostat cancelled_write_bytes="1056768" rchar="26700009" read_bytes="0" syscr="9020" syscw="4725" wchar="30768316" write_bytes="15945728"/> - <rusage ru_inblock="0" ru_majflt="0" ru_maxrss="110564" ru_minflt="320463" ru_nivcsw="7925" ru_nvcsw="6295" ru_oublock="31144" ru_stime="1.000092" ru_utime="184.185245"/> + <time timestamp="2016-12-25T18:23:18.411143">24.188618</time> + <iostat cancelled_write_bytes="1040384" rchar="26470082" read_bytes="0" syscr="8986" syscw="4720" wchar="30698135" write_bytes="15876096"/> + <rusage ru_inblock="0" ru_majflt="0" ru_maxrss="109664" ru_minflt="318938" ru_nivcsw="7925" ru_nvcsw="6286" ru_oublock="31008" ru_stime="0.898208" ru_utime="182.856131"/> </sysres> <sysres legend="bitbake -p (no tmp/cache)" name="parse_2"> - <time timestamp="2016-12-25T13:25:52.702541">15.682219</time> - <iostat cancelled_write_bytes="897024" rchar="26377364" read_bytes="0" syscr="9035" syscw="4725" wchar="30089821" write_bytes="15273984"/> - <rusage ru_inblock="0" ru_majflt="0" ru_maxrss="112932" ru_minflt="288392" ru_nivcsw="7502" ru_nvcsw="6277" ru_oublock="29832" ru_stime="0.944534" ru_utime="115.623796"/> + <time timestamp="2016-12-25T18:23:42.604614">15.523201</time> + <iostat cancelled_write_bytes="892928" rchar="26626205" read_bytes="0" syscr="9075" syscw="4733" wchar="30101951" write_bytes="15286272"/> + <rusage ru_inblock="0" ru_majflt="0" ru_maxrss="112584" ru_minflt="296921" ru_nivcsw="7412" ru_nvcsw="6301" ru_oublock="29856" ru_stime="0.923473" ru_utime="114.599697"/> </sysres> <sysres legend="bitbake -p (cached)" name="parse_3"> - <time timestamp="2016-12-25T13:26:08.387870">1.426918</time> - <iostat cancelled_write_bytes="12288" rchar="19775550" read_bytes="0" syscr="6083" syscw="1498" wchar="641247" write_bytes="356352"/> - <rusage ru_inblock="0" ru_majflt="0" ru_maxrss="94880" ru_minflt="47818" ru_nivcsw="20" ru_nvcsw="8971" ru_oublock="696" ru_stime="0.11580199999999999" ru_utime="1.412735"/> + <time timestamp="2016-12-25T18:23:58.130856">1.381026</time> + <iostat cancelled_write_bytes="12288" rchar="19789991" read_bytes="0" syscr="6087" syscw="1498" wchar="641195" write_bytes="356352"/> + <rusage ru_inblock="0" ru_majflt="0" ru_maxrss="94920" ru_minflt="47703" ru_nivcsw="16" ru_nvcsw="8566" ru_oublock="696" ru_stime="0.12053799999999999" ru_utime="1.356657"/> </sysres> </testcase> - <testcase classname="oeqa.buildperf.test_basic.Test4" description="eSDK metrics" name="test4" time="1988.766789" timestamp="2016-12-25T13:26:09.816163"> + <testcase classname="oeqa.buildperf.test_basic.Test4" description="eSDK metrics" name="test4" time="1950.305217" timestamp="2016-12-25T18:23:59.513323"> <diskusage legend="eSDK installer" name="installer_bin"> - <size>1511883</size> + <size>1511931</size> </diskusage> <sysres legend="eSDK deploy" name="deploy"> - <time timestamp="2016-12-25T13:56:17.938797">180.155885</time> - <iostat cancelled_write_bytes="424710144" rchar="11065176524" read_bytes="1616228352" syscr="2392896" syscw="5897336" wchar="12126815790" write_bytes="5776121856"/> - <rusage ru_inblock="3156456" ru_majflt="291" ru_maxrss="220852" ru_minflt="16781087" ru_nivcsw="338773" ru_nvcsw="1058582" ru_oublock="11281488" ru_stime="82.460181" ru_utime="545.164709"/> + <time timestamp="2016-12-25T18:53:34.029006">175.325808</time> + <iostat cancelled_write_bytes="424742912" rchar="11064419886" read_bytes="1612591104" syscr="2410334" syscw="5896782" wchar="12126789273" write_bytes="5775986688"/> + <rusage ru_inblock="3149328" ru_majflt="287" ru_maxrss="219688" ru_minflt="16763859" ru_nivcsw="380505" ru_nvcsw="1061888" ru_oublock="11281224" ru_stime="83.458968" ru_utime="554.682689"/> </sysres> <diskusage legend="deploy dir" name="deploy_dir"> - <size>5002115</size> + <size>5001968</size> </diskusage> </testcase> </testsuite> |