builder: mozilla-beta_win7-ix_test-xperf-pgo slave: t-w732-ix-100 starttime: 1446126746.99 results: success (0) buildid: 20151029024142 builduid: 487a0387ced54722bbe84f2d8c2058bc revision: 0ec8472a93ac ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:26.994291) ========= master: http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:27.002501) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:27.002891) ========= 'cd' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['cd'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-100 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-100 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-100 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False C:\slave\test-pgo program finished with exit code 0 elapsedTime=0.626000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:27.660584) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:27.660979) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:27.802236) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:27.802716) ========= 'rm' '-rf' 'properties' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-100 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-100 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-100 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.601000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:28.419561) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:28.419928) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:28.420360) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:28.420719) ========= 'bash' '-c' 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-100 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-100 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-100 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False --06:52:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 2.49 GB/s 06:52:29 (2.49 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.739000 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:29.177688) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 20 secs) (at 2015-10-29 06:52:29.178350) ========= 'rm' '-rf' 'scripts' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-100 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-100 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-100 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=14.301000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 20 secs) (at 2015-10-29 06:52:49.480800) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-29 06:52:49.481193) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0ec8472a93ac --destination scripts --debug' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0ec8472a93ac --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-100 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-100 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-100 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2015-10-29 06:52:50,407 Setting DEBUG logging. 2015-10-29 06:52:50,407 attempt 1/10 2015-10-29 06:52:50,407 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0ec8472a93ac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-29 06:52:51,463 unpacking tar archive at: mozilla-beta-0ec8472a93ac/testing/mozharness/ program finished with exit code 0 elapsedTime=1.814000 ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2015-10-29 06:52:52.213750) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:52.214094) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:52.234848) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:52.235156) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-29 06:52:52.235538) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 12 mins, 42 secs) (at 2015-10-29 06:52:52.235843) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'xperf' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Beta' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Beta' in dir C:\slave\test-pgo\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'xperf', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-100 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-100 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PROPERTIES_FILE=C:\slave\test-pgo/buildprops.json PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-100 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 06:52:53 INFO - MultiFileLogger online at 20151029 06:52:53 in C:\slave\test-pgo 06:52:53 INFO - Run as scripts/scripts/talos_script.py --suite xperf --add-option --webServer,localhost --branch-name Mozilla-Beta --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 06:52:53 INFO - Guessing talos json url... 06:52:53 INFO - Using buildbot properties: 06:52:53 INFO - { 06:52:53 INFO - "properties": { 06:52:53 INFO - "buildnumber": 160, 06:52:53 INFO - "product": "firefox", 06:52:53 INFO - "script_repo_revision": "production", 06:52:53 INFO - "builddir": "mozilla-beta_win7-ix_test-xperf", 06:52:53 INFO - "repository": "", 06:52:53 INFO - "buildername": "Windows 7 32-bit mozilla-beta pgo talos xperf", 06:52:53 INFO - "buildid": "20151029024142", 06:52:53 INFO - "slavename": "t-w732-ix-100", 06:52:53 INFO - "pgo_build": "True", 06:52:53 INFO - "basedir": "C:\\slave\\test-pgo", 06:52:53 INFO - "project": "", 06:52:53 INFO - "platform": "win7-ix", 06:52:53 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/", 06:52:53 INFO - "slavebuilddir": "test", 06:52:53 INFO - "scheduler": "tests-mozilla-beta-win32-pgo-talos", 06:52:53 INFO - "branch": "mozilla-beta", 06:52:53 INFO - "repo_path": "releases/mozilla-beta", 06:52:53 INFO - "stage_platform": "win32-pgo", 06:52:53 INFO - "builduid": "487a0387ced54722bbe84f2d8c2058bc", 06:52:53 INFO - "revision": "0ec8472a93ac" 06:52:53 INFO - }, 06:52:53 INFO - "sourcestamp": { 06:52:53 INFO - "repository": "", 06:52:53 INFO - "hasPatch": false, 06:52:53 INFO - "project": "", 06:52:53 INFO - "branch": "mozilla-beta-win32-pgo-talos", 06:52:53 INFO - "changes": [ 06:52:53 INFO - { 06:52:53 INFO - "category": null, 06:52:53 INFO - "files": [ 06:52:53 INFO - { 06:52:53 INFO - "url": null, 06:52:53 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.zip" 06:52:53 INFO - } 06:52:53 INFO - ], 06:52:53 INFO - "repository": "", 06:52:53 INFO - "rev": "0ec8472a93ac", 06:52:53 INFO - "who": "sendchange", 06:52:53 INFO - "when": 1446126643, 06:52:53 INFO - "number": 6593738, 06:52:53 INFO - "comments": "Bug 1218157: Only ever read from cached data in NotifyDataArrived. r=cpearce a=sylvestre", 06:52:53 INFO - "project": "", 06:52:53 INFO - "at": "Thu 29 Oct 2015 06:50:43", 06:52:53 INFO - "branch": "mozilla-beta-win32-pgo-talos", 06:52:53 INFO - "revlink": "", 06:52:53 INFO - "properties": [ 06:52:53 INFO - [ 06:52:53 INFO - "buildid", 06:52:53 INFO - "20151029024142", 06:52:53 INFO - "Change" 06:52:53 INFO - ], 06:52:53 INFO - [ 06:52:53 INFO - "builduid", 06:52:53 INFO - "487a0387ced54722bbe84f2d8c2058bc", 06:52:53 INFO - "Change" 06:52:53 INFO - ], 06:52:53 INFO - [ 06:52:53 INFO - "pgo_build", 06:52:53 INFO - "True", 06:52:53 INFO - "Change" 06:52:53 INFO - ] 06:52:53 INFO - ], 06:52:53 INFO - "revision": "0ec8472a93ac" 06:52:53 INFO - } 06:52:53 INFO - ], 06:52:53 INFO - "revision": "0ec8472a93ac" 06:52:53 INFO - } 06:52:53 INFO - } 06:52:53 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.zip. 06:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:52:53 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.txt 06:52:53 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.txt to C:\slave\test-pgo\build\firefox-42.0.en-US.win32.txt 06:52:53 INFO - retry: Calling _download_file with args: ('http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.txt', 'C:\\slave\\test-pgo\\build\\firefox-42.0.en-US.win32.txt'), kwargs: {}, attempt #1 06:52:53 INFO - Downloaded 77 bytes. 06:52:53 INFO - Reading from file C:\slave\test-pgo\build\firefox-42.0.en-US.win32.txt 06:52:53 INFO - Contents: 06:52:53 INFO - 20151029024142 06:52:53 INFO - https://hg.mozilla.org/releases/mozilla-beta/rev/0ec8472a93ac 06:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:52:53 INFO - trying https://hg.mozilla.org/releases/mozilla-beta/raw-file/0ec8472a93/testing/talos/talos.json 06:52:53 INFO - Downloading https://hg.mozilla.org/releases/mozilla-beta/raw-file/0ec8472a93/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 06:52:53 INFO - retry: Calling _download_file with args: ('https://hg.mozilla.org/releases/mozilla-beta/raw-file/0ec8472a93/testing/talos/talos.json', 'C:\\slave\\test-pgo\\build\\talos.json'), kwargs: {}, attempt #1 06:52:54 INFO - Downloaded 6942 bytes. 06:52:54 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 06:52:54 INFO - u'global': {u'talos_repo': u'https://hg.mozilla.org/build/talos', 06:52:54 INFO - u'talos_revision': u'c0039de7a1c6'}, 06:52:54 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 06:52:54 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 06:52:54 INFO - u'../fennec_ids.txt'], 06:52:54 INFO - u'tests': [u'tcheck2']}, 06:52:54 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 06:52:54 INFO - u'--tppagecycles', 06:52:54 INFO - u'7'], 06:52:54 INFO - u'tests': [u'tsvgm']}}, 06:52:54 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 06:52:54 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 06:52:54 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 06:52:54 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 06:52:54 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 06:52:54 INFO - u'tests': [u'dromaeo_css', 06:52:54 INFO - u'kraken', 06:52:54 INFO - u'v8_7']}, 06:52:54 INFO - u'g1': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5o.manifest', 06:52:54 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/', 06:52:54 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:52:54 INFO - u'plugins': {u'32': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip', 06:52:54 INFO - u'64': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash64_11_0_d1_98.zip'}, 06:52:54 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:52:54 INFO - u'g1-e10s': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5o.manifest', 06:52:54 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/', 06:52:54 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:52:54 INFO - u'plugins': {u'32': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip', 06:52:54 INFO - u'64': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash64_11_0_d1_98.zip'}, 06:52:54 INFO - u'talos_options': [u'--e10s'], 06:52:54 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:52:54 INFO - u'g2': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5o.manifest', 06:52:54 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/', 06:52:54 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:52:54 INFO - u'tests': [u'damp', u'tps']}, 06:52:54 INFO - u'g2-e10s': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5o.manifest', 06:52:54 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/', 06:52:54 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:52:54 INFO - u'talos_options': [u'--e10s'], 06:52:54 INFO - u'tests': [u'damp', u'tps']}, 06:52:54 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 06:52:54 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 06:52:54 INFO - u'other': {u'tests': [u'a11yr', 06:52:54 INFO - u'ts_paint', 06:52:54 INFO - u'tpaint', 06:52:54 INFO - u'sessionrestore', 06:52:54 INFO - u'sessionrestore_no_auto_restore']}, 06:52:54 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 06:52:54 INFO - u'tests': [u'a11yr', 06:52:54 INFO - u'ts_paint', 06:52:54 INFO - u'tpaint', 06:52:54 INFO - u'sessionrestore', 06:52:54 INFO - u'sessionrestore_no_auto_restore']}, 06:52:54 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 06:52:54 INFO - u'tests': [u'a11yr', 06:52:54 INFO - u'ts_paint', 06:52:54 INFO - u'tpaint', 06:52:54 INFO - u'sessionrestore', 06:52:54 INFO - u'sessionrestore_no_auto_restore']}, 06:52:54 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 06:52:54 INFO - u'tests': [u'a11yr', 06:52:54 INFO - u'ts_paint', 06:52:54 INFO - u'tpaint', 06:52:54 INFO - u'sessionrestore', 06:52:54 INFO - u'sessionrestore_no_auto_restore']}, 06:52:54 INFO - u'other_l64': {u'tests': [u'a11yr', 06:52:54 INFO - u'ts_paint', 06:52:54 INFO - u'tpaint', 06:52:54 INFO - u'sessionrestore', 06:52:54 INFO - u'sessionrestore_no_auto_restore']}, 06:52:54 INFO - u'other_nol64': {u'tests': [u'a11yr', 06:52:54 INFO - u'ts_paint', 06:52:54 INFO - u'tpaint', 06:52:54 INFO - u'sessionrestore', 06:52:54 INFO - u'sessionrestore_no_auto_restore']}, 06:52:54 INFO - u'svgr': {u'tests': [u'tsvgx', 06:52:54 INFO - u'tsvgr_opacity', 06:52:54 INFO - u'tart', 06:52:54 INFO - u'tscrollx', 06:52:54 INFO - u'cart']}, 06:52:54 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 06:52:54 INFO - u'tests': [u'tsvgx', 06:52:54 INFO - u'tsvgr_opacity', 06:52:54 INFO - u'tart', 06:52:54 INFO - u'tscrollx', 06:52:54 INFO - u'cart']}, 06:52:54 INFO - u'tp5o': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5o.manifest', 06:52:54 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/', 06:52:54 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:52:54 INFO - u'plugins': {u'32': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip', 06:52:54 INFO - u'64': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash64_11_0_d1_98.zip'}, 06:52:54 INFO - u'tests': [u'tp5o']}, 06:52:54 INFO - u'tp5o-e10s': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5o.manifest', 06:52:54 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/', 06:52:54 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:52:54 INFO - u'plugins': {u'32': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip', 06:52:54 INFO - u'64': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash64_11_0_d1_98.zip'}, 06:52:54 INFO - u'talos_options': [u'--e10s'], 06:52:54 INFO - u'tests': [u'tp5o']}, 06:52:54 INFO - u'xperf': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5n.manifest', 06:52:54 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/', 06:52:54 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:52:54 INFO - u'plugins': {u'32': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip', 06:52:54 INFO - u'64': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash64_11_0_d1_98.zip'}, 06:52:54 INFO - u'talos_options': [u'--xperf_path', 06:52:54 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:52:54 INFO - u'tests': [u'tp5n']}, 06:52:54 INFO - u'xperf-e10s': {u'pagesets_manifest_path': u'talos/page_load_test/tp5n/tp5n.manifest', 06:52:54 INFO - u'pagesets_parent_dir_path': u'talos/page_load_test/', 06:52:54 INFO - u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:52:54 INFO - u'plugins': {u'32': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip', 06:52:54 INFO - u'64': u'http://talos-bundles.pvt.build.mozilla.org/zips/flash64_11_0_d1_98.zip'}, 06:52:54 INFO - u'talos_options': [u'--e10s', 06:52:54 INFO - u'--xperf_path', 06:52:54 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 06:52:54 INFO - u'C:/slave/talos-data/talos/xperf.config'], 06:52:54 INFO - u'tests': [u'tp5n']}}, 06:52:54 INFO - u'talos.zip': {u'path': u'', 06:52:54 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:52:54 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 06:52:54 INFO - {'append_to_log': False, 06:52:54 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 06:52:54 INFO - 'blob_upload_branch': 'Mozilla-Beta', 06:52:54 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 06:52:54 INFO - 'branch': 'Mozilla-Beta', 06:52:54 INFO - 'buildbot_json_path': 'buildprops.json', 06:52:54 INFO - 'config_files': ('talos/windows_config.py',), 06:52:54 INFO - 'default_actions': ('clobber', 06:52:54 INFO - 'read-buildbot-config', 06:52:54 INFO - 'download-and-extract', 06:52:54 INFO - 'clone-talos', 06:52:54 INFO - 'create-virtualenv', 06:52:54 INFO - 'install', 06:52:54 INFO - 'run-tests'), 06:52:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:52:54 INFO - 'download_symbols': 'ondemand', 06:52:54 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 06:52:54 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 06:52:54 INFO - 'hg': 'c:/mozilla-build/hg/hg', 06:52:54 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 06:52:54 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 06:52:54 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 06:52:54 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 06:52:54 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 06:52:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:52:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:52:54 INFO - 'installer_path': 'installer.exe', 06:52:54 INFO - 'log_level': 'info', 06:52:54 INFO - 'log_name': 'talos', 06:52:54 INFO - 'log_to_console': True, 06:52:54 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 06:52:54 INFO - 'opt_config_files': (), 06:52:54 INFO - 'pip_index': False, 06:52:54 INFO - 'populate_webroot': True, 06:52:54 INFO - 'python_webserver': False, 06:52:54 INFO - 'sps_profile': False, 06:52:54 INFO - 'sps_profile_interval': 0, 06:52:54 INFO - 'suite': 'xperf', 06:52:54 INFO - 'system_bits': '32', 06:52:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:52:54 INFO - 'talos_url': 'https://hg.mozilla.org/build/talos/archive/tip.tar.gz', 06:52:54 INFO - 'tests': (), 06:52:54 INFO - 'title': 'T-W732-IX-100', 06:52:54 INFO - 'use_talos_json': True, 06:52:54 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 06:52:54 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 06:52:54 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 06:52:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:52:54 INFO - 'webroot': 'c:/slave/talos-data', 06:52:54 INFO - 'webroot_extract_cmd': 'c:/mozilla-build/msys/bin/bash -c "PATH=/c/mozilla-build/msys/bin:$PATH tar zx --strip-components=1 -f \'%(tarball)s\' --wildcards \'**/talos/\'"', 06:52:54 INFO - 'work_dir': 'build'} 06:52:54 INFO - ##### 06:52:54 INFO - ##### Running clobber step. 06:52:54 INFO - ##### 06:52:54 INFO - Running pre-action listener: _resource_record_pre_action 06:52:54 INFO - Running main action method: clobber 06:52:54 INFO - rmtree: C:\slave\test-pgo\build 06:52:54 INFO - Using _rmtree_windows ... 06:52:54 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 06:55:55 INFO - Running post-action listener: _resource_record_post_action 06:55:55 INFO - ##### 06:55:55 INFO - ##### Running read-buildbot-config step. 06:55:55 INFO - ##### 06:55:55 INFO - Running pre-action listener: _resource_record_pre_action 06:55:55 INFO - Running main action method: read_buildbot_config 06:55:55 INFO - Using buildbot properties: 06:55:55 INFO - { 06:55:55 INFO - "properties": { 06:55:55 INFO - "buildnumber": 160, 06:55:55 INFO - "product": "firefox", 06:55:55 INFO - "script_repo_revision": "production", 06:55:55 INFO - "builddir": "mozilla-beta_win7-ix_test-xperf", 06:55:55 INFO - "repository": "", 06:55:55 INFO - "buildername": "Windows 7 32-bit mozilla-beta pgo talos xperf", 06:55:55 INFO - "buildid": "20151029024142", 06:55:55 INFO - "slavename": "t-w732-ix-100", 06:55:55 INFO - "pgo_build": "True", 06:55:55 INFO - "basedir": "C:\\slave\\test-pgo", 06:55:55 INFO - "project": "", 06:55:55 INFO - "platform": "win7-ix", 06:55:55 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/", 06:55:55 INFO - "slavebuilddir": "test", 06:55:55 INFO - "scheduler": "tests-mozilla-beta-win32-pgo-talos", 06:55:55 INFO - "branch": "mozilla-beta", 06:55:55 INFO - "repo_path": "releases/mozilla-beta", 06:55:55 INFO - "stage_platform": "win32-pgo", 06:55:55 INFO - "builduid": "487a0387ced54722bbe84f2d8c2058bc", 06:55:55 INFO - "revision": "0ec8472a93ac" 06:55:55 INFO - }, 06:55:55 INFO - "sourcestamp": { 06:55:55 INFO - "repository": "", 06:55:55 INFO - "hasPatch": false, 06:55:55 INFO - "project": "", 06:55:55 INFO - "branch": "mozilla-beta-win32-pgo-talos", 06:55:55 INFO - "changes": [ 06:55:55 INFO - { 06:55:55 INFO - "category": null, 06:55:55 INFO - "files": [ 06:55:55 INFO - { 06:55:55 INFO - "url": null, 06:55:55 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.zip" 06:55:55 INFO - } 06:55:55 INFO - ], 06:55:55 INFO - "repository": "", 06:55:55 INFO - "rev": "0ec8472a93ac", 06:55:55 INFO - "who": "sendchange", 06:55:55 INFO - "when": 1446126643, 06:55:55 INFO - "number": 6593738, 06:55:55 INFO - "comments": "Bug 1218157: Only ever read from cached data in NotifyDataArrived. r=cpearce a=sylvestre", 06:55:55 INFO - "project": "", 06:55:55 INFO - "at": "Thu 29 Oct 2015 06:50:43", 06:55:55 INFO - "branch": "mozilla-beta-win32-pgo-talos", 06:55:55 INFO - "revlink": "", 06:55:55 INFO - "properties": [ 06:55:55 INFO - [ 06:55:55 INFO - "buildid", 06:55:55 INFO - "20151029024142", 06:55:55 INFO - "Change" 06:55:55 INFO - ], 06:55:55 INFO - [ 06:55:55 INFO - "builduid", 06:55:55 INFO - "487a0387ced54722bbe84f2d8c2058bc", 06:55:55 INFO - "Change" 06:55:55 INFO - ], 06:55:55 INFO - [ 06:55:55 INFO - "pgo_build", 06:55:55 INFO - "True", 06:55:55 INFO - "Change" 06:55:55 INFO - ] 06:55:55 INFO - ], 06:55:55 INFO - "revision": "0ec8472a93ac" 06:55:55 INFO - } 06:55:55 INFO - ], 06:55:55 INFO - "revision": "0ec8472a93ac" 06:55:55 INFO - } 06:55:55 INFO - } 06:55:55 INFO - Running post-action listener: _resource_record_post_action 06:55:55 INFO - ##### 06:55:55 INFO - ##### Running download-and-extract step. 06:55:55 INFO - ##### 06:55:55 INFO - Running pre-action listener: _resource_record_pre_action 06:55:55 INFO - Running main action method: download_and_extract 06:55:55 INFO - mkdir: C:\slave\test-pgo\build\tests 06:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:55:55 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/test_packages.json 06:55:55 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/test_packages.json to C:\slave\test-pgo\build\test_packages.json 06:55:55 INFO - retry: Calling _download_file with args: ('http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/test_packages.json', 'C:\\slave\\test-pgo\\build\\test_packages.json'), kwargs: {}, attempt #1 06:55:55 INFO - Downloaded 1057 bytes. 06:55:55 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 06:55:55 INFO - Using the following test package requirements: 06:55:55 INFO - {u'common': [u'firefox-42.0.en-US.win32.common.tests.zip'], 06:55:55 INFO - u'cppunittest': [u'firefox-42.0.en-US.win32.common.tests.zip', 06:55:55 INFO - u'firefox-42.0.en-US.win32.cppunittest.tests.zip'], 06:55:55 INFO - u'jittest': [u'firefox-42.0.en-US.win32.common.tests.zip', 06:55:55 INFO - u'jsshell-win32.zip'], 06:55:55 INFO - u'mochitest': [u'firefox-42.0.en-US.win32.common.tests.zip', 06:55:55 INFO - u'firefox-42.0.en-US.win32.mochitest.tests.zip'], 06:55:55 INFO - u'mozbase': [u'firefox-42.0.en-US.win32.common.tests.zip'], 06:55:55 INFO - u'reftest': [u'firefox-42.0.en-US.win32.common.tests.zip', 06:55:55 INFO - u'firefox-42.0.en-US.win32.reftest.tests.zip'], 06:55:55 INFO - u'web-platform': [u'firefox-42.0.en-US.win32.common.tests.zip', 06:55:55 INFO - u'firefox-42.0.en-US.win32.web-platform.tests.zip'], 06:55:55 INFO - u'webapprt': [u'firefox-42.0.en-US.win32.common.tests.zip'], 06:55:55 INFO - u'xpcshell': [u'firefox-42.0.en-US.win32.common.tests.zip', 06:55:55 INFO - u'firefox-42.0.en-US.win32.xpcshell.tests.zip']} 06:55:55 INFO - Downloading packages: [u'firefox-42.0.en-US.win32.common.tests.zip'] for test suite category: common 06:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:55:55 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.common.tests.zip 06:55:55 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-42.0.en-US.win32.common.tests.zip 06:55:55 INFO - retry: Calling _download_file with args: (u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.common.tests.zip', u'C:\\slave\\test-pgo\\build\\firefox-42.0.en-US.win32.common.tests.zip'), kwargs: {}, attempt #1 06:55:56 INFO - Downloaded 19954117 bytes. 06:55:56 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-42.0.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests 06:55:56 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-42.0.en-US.win32.common.tests.zip 06:55:56 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-42.0.en-US.win32.common.tests.zip'] with output_timeout 1760 06:56:21 INFO - Return code: 0 06:56:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:56:21 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.zip 06:56:21 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.zip to C:\slave\test-pgo\build\firefox-42.0.en-US.win32.zip 06:56:21 INFO - retry: Calling _download_file with args: ('http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.zip', 'C:\\slave\\test-pgo\\build\\firefox-42.0.en-US.win32.zip'), kwargs: {}, attempt #1 06:56:24 INFO - Downloaded 53798908 bytes. 06:56:24 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.zip 06:56:24 INFO - mkdir: C:\slave\test-pgo\properties 06:56:24 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 06:56:24 INFO - Writing to file C:\slave\test-pgo\properties\build_url 06:56:24 INFO - Contents: 06:56:24 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.zip 06:56:24 INFO - Running post-action listener: _resource_record_post_action 06:56:24 INFO - ##### 06:56:24 INFO - ##### Running clone-talos step. 06:56:24 INFO - ##### 06:56:24 INFO - Running pre-action listener: _resource_record_pre_action 06:56:24 INFO - Running main action method: clone_talos 06:56:24 INFO - Populating webroot c:/slave/talos-data... 06:56:24 INFO - rmtree: c:/slave/talos-data\talos 06:56:24 INFO - Using _rmtree_windows ... 06:56:24 INFO - retry: Calling _rmtree_windows with args: ('c:/slave/talos-data\\talos',), kwargs: {}, attempt #1 06:58:14 INFO - retry: Calling _get_revision with args: (, 'C:\\slave\\test-pgo\\build\\talos_repo'), kwargs: {}, attempt #1 06:58:14 INFO - Setting C:\slave\test-pgo\build\talos_repo to https://hg.mozilla.org/build/talos revision c0039de7a1c6. 06:58:14 INFO - Cloning https://hg.mozilla.org/build/talos to C:\slave\test-pgo\build\talos_repo. 06:58:14 INFO - Running command: ['c:/mozilla-build/hg/hg', '--config', 'ui.merge=internal:merge', 'clone', u'https://hg.mozilla.org/build/talos', 'C:\\slave\\test-pgo\\build\\talos_repo'] 06:58:14 INFO - Copy/paste: c:/mozilla-build/hg/hg --config ui.merge=internal:merge clone https://hg.mozilla.org/build/talos C:\slave\test-pgo\build\talos_repo 06:58:14 INFO - Calling ['c:/mozilla-build/hg/hg', '--config', 'ui.merge=internal:merge', 'clone', u'https://hg.mozilla.org/build/talos', 'C:\\slave\\test-pgo\\build\\talos_repo'] with output_timeout 1200 06:58:33 INFO - requesting all changes 06:58:33 INFO - adding changesets 06:58:33 INFO - adding manifests 06:58:33 INFO - adding file changes 06:58:33 INFO - added 1011 changesets with 4474 changes to 1905 files (+1 heads) 06:58:33 INFO - updating to branch default 06:58:33 INFO - 338 files updated, 0 files merged, 0 files removed, 0 files unresolved 06:58:33 INFO - Return code: 0 06:58:33 INFO - Updating C:\slave\test-pgo\build\talos_repo. 06:58:33 INFO - Getting output from command: ['c:/mozilla-build/hg/hg', '--config', 'ui.merge=internal:merge', 'branch'] in C:\slave\test-pgo\build\talos_repo 06:58:33 INFO - Copy/paste: c:/mozilla-build/hg/hg --config ui.merge=internal:merge branch 06:58:33 INFO - Reading from file tmpfile_stdout 06:58:33 INFO - Output received: 06:58:33 INFO - default 06:58:33 INFO - Using _rmtree_windows ... 06:58:33 INFO - Using _rmtree_windows ... 06:58:33 INFO - Running command: ['c:/mozilla-build/hg/hg', '--config', 'ui.merge=internal:merge', 'update', '-C'] in C:\slave\test-pgo\build\talos_repo 06:58:33 INFO - Copy/paste: c:/mozilla-build/hg/hg --config ui.merge=internal:merge update -C 06:58:33 INFO - 0 files updated, 0 files merged, 0 files removed, 0 files unresolved 06:58:33 INFO - Return code: 0 06:58:33 INFO - Getting output from command: ['c:/mozilla-build/hg/hg', '--config', 'ui.merge=internal:merge', 'parent', '--template', '{node|short}'] in C:\slave\test-pgo\build\talos_repo 06:58:33 INFO - Copy/paste: c:/mozilla-build/hg/hg --config ui.merge=internal:merge parent --template {node|short} 06:58:33 INFO - Reading from file tmpfile_stdout 06:58:33 INFO - Output received: 06:58:33 INFO - c0de097a7159 06:58:33 INFO - Using _rmtree_windows ... 06:58:33 INFO - Using _rmtree_windows ... 06:58:33 INFO - Updating C:\slave\test-pgo\build\talos_repo revision c0039de7a1c6. 06:58:33 INFO - Running command: ['c:/mozilla-build/hg/hg', '--config', 'ui.merge=internal:merge', 'update', '-C', '-r', u'c0039de7a1c6'] in C:\slave\test-pgo\build\talos_repo 06:58:33 INFO - Copy/paste: c:/mozilla-build/hg/hg --config ui.merge=internal:merge update -C -r c0039de7a1c6 06:58:34 INFO - 199 files updated, 0 files merged, 0 files removed, 0 files unresolved 06:58:34 INFO - Return code: 0 06:58:34 INFO - Getting output from command: ['c:/mozilla-build/hg/hg', '--config', 'ui.merge=internal:merge', 'parent', '--template', '{node|short}'] in C:\slave\test-pgo\build\talos_repo 06:58:34 INFO - Copy/paste: c:/mozilla-build/hg/hg --config ui.merge=internal:merge parent --template {node|short} 06:58:34 INFO - Reading from file tmpfile_stdout 06:58:34 INFO - Output received: 06:58:34 INFO - c0039de7a1c6 06:58:34 INFO - Using _rmtree_windows ... 06:58:34 INFO - Using _rmtree_windows ... 06:58:34 INFO - copying tree: C:\slave\test-pgo\build\talos_repo\talos to c:/slave/talos-data\talos 06:58:34 INFO - rmtree: c:/slave/talos-data\talos 06:58:34 INFO - Using _rmtree_windows ... 06:58:34 INFO - retry: Calling _rmtree_windows with args: ('c:/slave/talos-data\\talos',), kwargs: {}, attempt #1 06:58:34 INFO - Downloading pageset... 06:58:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:58:34 INFO - trying http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip 06:58:34 INFO - Downloading http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip to C:\slave\test-pgo\build\tp5n.zip 06:58:34 INFO - retry: Calling _download_file with args: (u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', u'C:\\slave\\test-pgo\\build\\tp5n.zip'), kwargs: {}, attempt #1 06:58:35 INFO - Downloaded 81753769 bytes. 06:58:35 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\tp5n.zip'] in c:/slave/talos-data\talos/page_load_test/ 06:58:35 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\tp5n.zip 06:58:35 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\tp5n.zip'] with output_timeout 1760 06:59:08 INFO - Return code: 0 06:59:08 INFO - mkdir: C:\slave\test-pgo\build\talos_repo\talos/page_load_test/tp5n 06:59:08 INFO - Copying c:/slave/talos-data\talos/page_load_test/tp5n/tp5n.manifest to C:\slave\test-pgo\build\talos_repo\talos/page_load_test/tp5n/tp5n.manifest 06:59:08 INFO - Downloading plugin... 06:59:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:59:08 INFO - trying http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip 06:59:08 INFO - Downloading http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip to C:\slave\test-pgo\build\flash32_10_3_183_5.zip 06:59:08 INFO - retry: Calling _download_file with args: (u'http://talos-bundles.pvt.build.mozilla.org/zips/flash32_10_3_183_5.zip', u'C:\\slave\\test-pgo\\build\\flash32_10_3_183_5.zip'), kwargs: {}, attempt #1 06:59:08 INFO - Downloaded 18109405 bytes. 06:59:08 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\flash32_10_3_183_5.zip'] in c:/slave/talos-data\talos\base_profile 06:59:08 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\flash32_10_3_183_5.zip 06:59:08 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\flash32_10_3_183_5.zip'] with output_timeout 1760 06:59:11 INFO - Return code: 0 06:59:11 INFO - Running post-action listener: _resource_record_post_action 06:59:11 INFO - ##### 06:59:11 INFO - ##### Running create-virtualenv step. 06:59:11 INFO - ##### 06:59:11 INFO - Running pre-action listener: _resource_record_pre_action 06:59:11 INFO - Running main action method: create_virtualenv 06:59:11 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 06:59:11 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 06:59:11 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 06:59:11 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 06:59:11 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test-pgo\\build/venv'] in C:\slave\test-pgo\build 06:59:11 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test-pgo\build/venv 06:59:16 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 06:59:21 INFO - Installing distribute......................................................................................................................................................................................done. 06:59:22 INFO - Return code: 0 06:59:22 INFO - Installing mozinstall into virtualenv C:\slave\test-pgo\build/venv 06:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:59:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:59:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:22 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\talos_repo\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozinstall']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01879AA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0136E760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01288BF0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0191B660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-100', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:59:22 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\talos_repo\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozinstall'] in C:\slave\test-pgo\build 06:59:22 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\build\talos_repo\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozinstall 06:59:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:59:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:59:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:59:22 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 06:59:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:59:22 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:59:22 INFO - 'DCLOCATION': 'SCL3', 06:59:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:59:22 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:59:22 INFO - 'HOMEDRIVE': 'C:', 06:59:22 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:59:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:59:22 INFO - 'KTS_VERSION': '1.19c', 06:59:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:59:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 06:59:22 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:59:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:59:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:22 INFO - 'MOZ_NO_REMOTE': '1', 06:59:22 INFO - 'NO_EM_RESTART': '1', 06:59:22 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:59:22 INFO - 'OS': 'Windows_NT', 06:59:22 INFO - 'OURDRIVE': 'C:', 06:59:22 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:59:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:59:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:59:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:59:22 INFO - 'PROCESSOR_LEVEL': '6', 06:59:22 INFO - 'PROCESSOR_REVISION': '1e05', 06:59:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:59:22 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:59:22 INFO - 'PROMPT': '$P$G', 06:59:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 06:59:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:59:22 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:59:22 INFO - 'PWD': 'C:\\slave\\test-pgo', 06:59:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:59:22 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:59:22 INFO - 'SYSTEMDRIVE': 'C:', 06:59:22 INFO - 'SYSTEMROOT': 'C:\\windows', 06:59:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:59:22 INFO - 'TEST1': 'testie', 06:59:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:59:22 INFO - 'USERDOMAIN': 'T-W732-IX-100', 06:59:22 INFO - 'USERNAME': 'cltbld', 06:59:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:59:22 INFO - 'WINDIR': 'C:\\windows', 06:59:22 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:59:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:59:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:59:26 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:59:26 INFO - Downloading/unpacking mozhttpd>=0.7 (from -r C:\slave\test-pgo\build\talos_repo\requirements.txt (line 3)) 06:59:26 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 06:59:26 INFO - Downloading mozhttpd-0.7.tar.gz 06:59:26 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozhttpd-0.7.tar.gz 06:59:26 INFO - Running setup.py egg_info for package mozhttpd 06:59:26 INFO - Downloading/unpacking mozprofile>=0.25 (from -r C:\slave\test-pgo\build\talos_repo\requirements.txt (line 7)) 06:59:26 INFO - Downloading mozprofile-0.27.tar.gz 06:59:26 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozprofile-0.27.tar.gz 06:59:26 INFO - Running setup.py egg_info for package mozprofile 06:59:26 INFO - Downloading/unpacking mozinfo>=0.8 (from -r C:\slave\test-pgo\build\talos_repo\requirements.txt (line 4)) 06:59:26 INFO - Downloading mozinfo-0.8.tar.gz 06:59:26 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozinfo-0.8.tar.gz 06:59:26 INFO - Running setup.py egg_info for package mozinfo 06:59:26 INFO - Downloading/unpacking mozcrash>=0.15 (from -r C:\slave\test-pgo\build\talos_repo\requirements.txt (line 1)) 06:59:26 INFO - Downloading mozcrash-0.16.tar.gz 06:59:26 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozcrash-0.16.tar.gz 06:59:26 INFO - Running setup.py egg_info for package mozcrash 06:59:26 INFO - Downloading/unpacking mozfile>=1.2 (from -r C:\slave\test-pgo\build\talos_repo\requirements.txt (line 2)) 06:59:26 INFO - Downloading mozfile-1.2.tar.gz 06:59:26 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozfile-1.2.tar.gz 06:59:26 INFO - Running setup.py egg_info for package mozfile 06:59:26 INFO - Downloading/unpacking mozprocess>=0.22 (from -r C:\slave\test-pgo\build\talos_repo\requirements.txt (line 5)) 06:59:26 INFO - Downloading mozprocess-0.22.tar.gz 06:59:26 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozprocess-0.22.tar.gz 06:59:26 INFO - Running setup.py egg_info for package mozprocess 06:59:26 INFO - Downloading/unpacking mozversion>=1.3 (from -r C:\slave\test-pgo\build\talos_repo\requirements.txt (line 6)) 06:59:26 INFO - Downloading mozversion-1.4.tar.gz 06:59:26 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozversion-1.4.tar.gz 06:59:26 INFO - Running setup.py egg_info for package mozversion 06:59:26 INFO - Downloading/unpacking mozinstall 06:59:26 INFO - Downloading mozInstall-1.12.tar.gz 06:59:26 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozinstall-1.12.tar.gz 06:59:26 INFO - Running setup.py egg_info for package mozinstall 06:59:26 INFO - Downloading/unpacking moznetwork>=0.24 (from mozhttpd>=0.7->-r C:\slave\test-pgo\build\talos_repo\requirements.txt (line 3)) 06:59:26 INFO - Downloading moznetwork-0.27.tar.gz 06:59:26 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmoznetwork-0.27.tar.gz 06:59:26 INFO - Running setup.py egg_info for package moznetwork 06:59:26 INFO - Downloading/unpacking mozlog>=3.0 (from mozprofile>=0.25->-r C:\slave\test-pgo\build\talos_repo\requirements.txt (line 7)) 06:59:26 INFO - Downloading mozlog-3.0.tar.gz 06:59:26 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozlog-3.0.tar.gz 06:59:26 INFO - Running setup.py egg_info for package mozlog 06:59:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog>=3.0->mozprofile>=0.25->-r C:\slave\test-pgo\build\talos_repo\requirements.txt (line 7)) 06:59:26 INFO - Downloading blessings-1.5.1.tar.gz 06:59:26 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblessings-1.5.1.tar.gz 06:59:26 INFO - Running setup.py egg_info for package blessings 06:59:26 INFO - Installing collected packages: blessings, mozcrash, mozfile, mozhttpd, mozinfo, mozinstall, mozlog, moznetwork, mozprocess, mozprofile, mozversion 06:59:26 INFO - Running setup.py install for blessings 06:59:26 INFO - Running setup.py install for mozcrash 06:59:26 INFO - Running setup.py install for mozfile 06:59:26 INFO - Running setup.py install for mozhttpd 06:59:30 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Running setup.py install for mozinfo 06:59:30 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Running setup.py install for mozinstall 06:59:30 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Running setup.py install for mozlog 06:59:30 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Running setup.py install for moznetwork 06:59:30 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Running setup.py install for mozprocess 06:59:30 INFO - Running setup.py install for mozprofile 06:59:30 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Running setup.py install for mozversion 06:59:30 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 06:59:30 INFO - Successfully installed blessings mozcrash mozfile mozhttpd mozinfo mozinstall mozlog moznetwork mozprocess mozprofile mozversion 06:59:30 INFO - Cleaning up... 06:59:30 INFO - Return code: 0 06:59:30 INFO - Installing psutil==0.7.1 into virtualenv C:\slave\test-pgo\build/venv 06:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:59:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:30 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil==0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01879AA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0136E760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01288BF0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0191B660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-100', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:59:30 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil==0.7.1'] in C:\slave\test-pgo\build 06:59:30 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil==0.7.1 06:59:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:59:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:59:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:59:30 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 06:59:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:59:30 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:59:30 INFO - 'DCLOCATION': 'SCL3', 06:59:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:59:30 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:59:30 INFO - 'HOMEDRIVE': 'C:', 06:59:30 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:59:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:59:30 INFO - 'KTS_VERSION': '1.19c', 06:59:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:59:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 06:59:30 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:59:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:59:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:30 INFO - 'MOZ_NO_REMOTE': '1', 06:59:30 INFO - 'NO_EM_RESTART': '1', 06:59:30 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:59:30 INFO - 'OS': 'Windows_NT', 06:59:30 INFO - 'OURDRIVE': 'C:', 06:59:30 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:59:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:59:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:59:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:59:30 INFO - 'PROCESSOR_LEVEL': '6', 06:59:30 INFO - 'PROCESSOR_REVISION': '1e05', 06:59:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:59:30 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:59:30 INFO - 'PROMPT': '$P$G', 06:59:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 06:59:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:59:30 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:59:30 INFO - 'PWD': 'C:\\slave\\test-pgo', 06:59:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:59:30 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:59:30 INFO - 'SYSTEMDRIVE': 'C:', 06:59:30 INFO - 'SYSTEMROOT': 'C:\\windows', 06:59:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:59:30 INFO - 'TEST1': 'testie', 06:59:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:59:30 INFO - 'USERDOMAIN': 'T-W732-IX-100', 06:59:30 INFO - 'USERNAME': 'cltbld', 06:59:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:59:30 INFO - 'WINDIR': 'C:\\windows', 06:59:30 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:59:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:59:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:59:30 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:59:30 INFO - Downloading/unpacking psutil==0.7.1 06:59:30 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpsutil-0.7.1.tar.gz 06:59:30 INFO - Running setup.py egg_info for package psutil 06:59:30 INFO - Installing collected packages: psutil 06:59:30 INFO - Running setup.py install for psutil 06:59:30 INFO - building '_psutil_mswindows' extension 06:59:30 INFO - error: Unable to find vcvarsall.bat 06:59:30 INFO - Complete output from command C:\slave\test-pgo\build\venv\Scripts\python.exe -c "import setuptools;__file__='C:\\slave\\test-pgo\\build\\venv\\build\\psutil\\setup.py';execfile(__file__)" install --single-version-externally-managed --record c:\users\cltbld\appdata\local\temp\pip-8cfkxo-record\install-record.txt --install-headers C:\slave\test-pgo\build\venv\include\site\python2.7: 06:59:30 INFO - running install 06:59:30 INFO - running build 06:59:30 INFO - running build_py 06:59:30 INFO - creating build 06:59:30 INFO - creating build\lib.win32-2.7 06:59:30 INFO - creating build\lib.win32-2.7\psutil 06:59:30 INFO - copying psutil\error.py -> build\lib.win32-2.7\psutil 06:59:30 INFO - copying psutil\_common.py -> build\lib.win32-2.7\psutil 06:59:30 INFO - copying psutil\_compat.py -> build\lib.win32-2.7\psutil 06:59:30 INFO - copying psutil\_error.py -> build\lib.win32-2.7\psutil 06:59:30 INFO - copying psutil\_psbsd.py -> build\lib.win32-2.7\psutil 06:59:30 INFO - copying psutil\_pslinux.py -> build\lib.win32-2.7\psutil 06:59:30 INFO - copying psutil\_psmswindows.py -> build\lib.win32-2.7\psutil 06:59:30 INFO - copying psutil\_psosx.py -> build\lib.win32-2.7\psutil 06:59:30 INFO - copying psutil\_psposix.py -> build\lib.win32-2.7\psutil 06:59:30 INFO - copying psutil\__init__.py -> build\lib.win32-2.7\psutil 06:59:30 INFO - running build_ext 06:59:30 INFO - building '_psutil_mswindows' extension 06:59:30 INFO - error: Unable to find vcvarsall.bat 06:59:30 INFO - ---------------------------------------- 06:59:30 INFO - Command C:\slave\test-pgo\build\venv\Scripts\python.exe -c "import setuptools;__file__='C:\\slave\\test-pgo\\build\\venv\\build\\psutil\\setup.py';execfile(__file__)" install --single-version-externally-managed --record c:\users\cltbld\appdata\local\temp\pip-8cfkxo-record\install-record.txt --install-headers C:\slave\test-pgo\build\venv\include\site\python2.7 failed with error code 1 06:59:30 INFO - Storing complete log in C:\Users\cltbld\AppData\Roaming\pip\pip.log 06:59:30 WARNING - Return code: 1 06:59:30 WARNING - Could not install python package: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil==0.7.1 failed after 1 tries! 06:59:30 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 06:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:59:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:30 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01879AA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0136E760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01288BF0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0191B660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-100', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:59:30 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test-pgo\build 06:59:30 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 06:59:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:59:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:59:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:59:30 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 06:59:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:59:30 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:59:30 INFO - 'DCLOCATION': 'SCL3', 06:59:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:59:30 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:59:30 INFO - 'HOMEDRIVE': 'C:', 06:59:30 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:59:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:59:30 INFO - 'KTS_VERSION': '1.19c', 06:59:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:59:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 06:59:30 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:59:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:59:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:30 INFO - 'MOZ_NO_REMOTE': '1', 06:59:30 INFO - 'NO_EM_RESTART': '1', 06:59:30 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:59:30 INFO - 'OS': 'Windows_NT', 06:59:30 INFO - 'OURDRIVE': 'C:', 06:59:30 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:59:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:59:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:59:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:59:30 INFO - 'PROCESSOR_LEVEL': '6', 06:59:30 INFO - 'PROCESSOR_REVISION': '1e05', 06:59:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:59:30 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:59:30 INFO - 'PROMPT': '$P$G', 06:59:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 06:59:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:59:30 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:59:30 INFO - 'PWD': 'C:\\slave\\test-pgo', 06:59:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:59:30 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:59:30 INFO - 'SYSTEMDRIVE': 'C:', 06:59:30 INFO - 'SYSTEMROOT': 'C:\\windows', 06:59:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:59:30 INFO - 'TEST1': 'testie', 06:59:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:59:30 INFO - 'USERDOMAIN': 'T-W732-IX-100', 06:59:30 INFO - 'USERNAME': 'cltbld', 06:59:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:59:30 INFO - 'WINDIR': 'C:\\windows', 06:59:30 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:59:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:59:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:59:31 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:59:31 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:59:31 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:59:31 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozsystemmonitor-0.0.tar.gz 06:59:31 INFO - Running setup.py egg_info for package mozsystemmonitor 06:59:31 INFO - Downloading/unpacking psutil>=0.7.1 (from mozsystemmonitor==0.0.0) 06:59:31 INFO - Running setup.py egg_info for package psutil 06:59:31 INFO - Installing collected packages: mozsystemmonitor, psutil 06:59:31 INFO - Running setup.py install for mozsystemmonitor 06:59:31 INFO - Running setup.py install for psutil 06:59:31 INFO - building '_psutil_mswindows' extension 06:59:31 INFO - error: Unable to find vcvarsall.bat 06:59:31 INFO - Complete output from command C:\slave\test-pgo\build\venv\Scripts\python.exe -c "import setuptools;__file__='C:\\slave\\test-pgo\\build\\venv\\build\\psutil\\setup.py';execfile(__file__)" install --single-version-externally-managed --record c:\users\cltbld\appdata\local\temp\pip-mfz0oq-record\install-record.txt --install-headers C:\slave\test-pgo\build\venv\include\site\python2.7: 06:59:31 INFO - running install 06:59:31 INFO - running build 06:59:31 INFO - running build_py 06:59:31 INFO - running build_ext 06:59:31 INFO - building '_psutil_mswindows' extension 06:59:31 INFO - error: Unable to find vcvarsall.bat 06:59:31 INFO - ---------------------------------------- 06:59:31 INFO - Command C:\slave\test-pgo\build\venv\Scripts\python.exe -c "import setuptools;__file__='C:\\slave\\test-pgo\\build\\venv\\build\\psutil\\setup.py';execfile(__file__)" install --single-version-externally-managed --record c:\users\cltbld\appdata\local\temp\pip-mfz0oq-record\install-record.txt --install-headers C:\slave\test-pgo\build\venv\include\site\python2.7 failed with error code 1 06:59:31 INFO - Storing complete log in C:\Users\cltbld\AppData\Roaming\pip\pip.log 06:59:31 WARNING - Return code: 1 06:59:31 WARNING - Could not install python package: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 failed after 1 tries! 06:59:31 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 06:59:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:59:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:59:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:59:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:59:31 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01879AA0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018CFE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0136E760>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01288BF0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012AA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0191B660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-100', 'USERDOMAIN': 'T-W732-IX-100', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-100', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 06:59:31 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test-pgo\build 06:59:31 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:59:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:59:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:59:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:59:31 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 06:59:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:59:31 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:59:31 INFO - 'DCLOCATION': 'SCL3', 06:59:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:59:31 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:59:31 INFO - 'HOMEDRIVE': 'C:', 06:59:31 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:59:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:59:31 INFO - 'KTS_VERSION': '1.19c', 06:59:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:59:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 06:59:31 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:59:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:59:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:31 INFO - 'MOZ_NO_REMOTE': '1', 06:59:31 INFO - 'NO_EM_RESTART': '1', 06:59:31 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:59:31 INFO - 'OS': 'Windows_NT', 06:59:31 INFO - 'OURDRIVE': 'C:', 06:59:31 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:59:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:59:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:59:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:59:31 INFO - 'PROCESSOR_LEVEL': '6', 06:59:31 INFO - 'PROCESSOR_REVISION': '1e05', 06:59:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:59:31 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:59:31 INFO - 'PROMPT': '$P$G', 06:59:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 06:59:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:59:31 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:59:31 INFO - 'PWD': 'C:\\slave\\test-pgo', 06:59:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:59:31 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:59:31 INFO - 'SYSTEMDRIVE': 'C:', 06:59:31 INFO - 'SYSTEMROOT': 'C:\\windows', 06:59:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:59:31 INFO - 'TEST1': 'testie', 06:59:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:59:31 INFO - 'USERDOMAIN': 'T-W732-IX-100', 06:59:31 INFO - 'USERNAME': 'cltbld', 06:59:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:59:31 INFO - 'WINDIR': 'C:\\windows', 06:59:31 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:59:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:59:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:59:32 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:59:32 INFO - Downloading/unpacking blobuploader==1.2.4 06:59:32 INFO - Downloading blobuploader-1.2.4.tar.gz 06:59:32 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz 06:59:32 INFO - Running setup.py egg_info for package blobuploader 06:59:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:59:35 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2frequests-1.2.3.tar.gz 06:59:35 INFO - Running setup.py egg_info for package requests 06:59:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:59:35 INFO - Downloading docopt-0.6.1.tar.gz 06:59:35 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fdocopt-0.6.1.tar.gz 06:59:35 INFO - Running setup.py egg_info for package docopt 06:59:35 INFO - Installing collected packages: blobuploader, docopt, requests 06:59:35 INFO - Running setup.py install for blobuploader 06:59:35 INFO - Running setup.py install for docopt 06:59:35 INFO - Running setup.py install for requests 06:59:35 INFO - Successfully installed blobuploader docopt requests 06:59:35 INFO - Cleaning up... 06:59:35 INFO - Return code: 0 06:59:35 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 06:59:35 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 06:59:35 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 06:59:35 INFO - Reading from file tmpfile_stdout 06:59:35 INFO - Using _rmtree_windows ... 06:59:35 INFO - Using _rmtree_windows ... 06:59:35 INFO - Current package versions: 06:59:35 INFO - mozfile == 1.2 06:59:35 INFO - mozhttpd == 0.7 06:59:35 INFO - mozprofile == 0.27 06:59:35 INFO - mozInstall == 1.12 06:59:35 INFO - mozcrash == 0.16 06:59:35 INFO - blobuploader == 1.2.4 06:59:35 INFO - distribute == 0.6.14 06:59:35 INFO - mozsystemmonitor == 0.0 06:59:35 INFO - moznetwork == 0.27 06:59:35 INFO - mozlog == 3.0 06:59:35 INFO - blessings == 1.5.1 06:59:35 INFO - mozversion == 1.4 06:59:35 INFO - mozprocess == 0.22 06:59:35 INFO - requests == 1.2.3 06:59:35 INFO - docopt == 0.6.1 06:59:35 INFO - mozinfo == 0.8 06:59:35 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '-c', 'from distutils.sysconfig import get_python_lib; print(get_python_lib())'] 06:59:35 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python -c "from distutils.sysconfig import get_python_lib; print(get_python_lib())" 06:59:35 INFO - Reading from file tmpfile_stdout 06:59:35 INFO - Output received: 06:59:35 INFO - C:\slave\test-pgo\build\venv\Lib\site-packages 06:59:35 INFO - Using _rmtree_windows ... 06:59:35 INFO - Using _rmtree_windows ... 06:59:35 INFO - mkdir: C:\slave\test-pgo\build\venv\Lib\site-packages\talos/page_load_test/tp5n 06:59:35 INFO - Copying c:/slave/talos-data\talos/page_load_test/tp5n/tp5n.manifest to C:\slave\test-pgo\build\venv\Lib\site-packages\talos/page_load_test/tp5n/tp5n.manifest 06:59:35 INFO - Running post-action listener: _resource_record_post_action 06:59:35 INFO - Running post-action listener: _start_resource_monitoring 06:59:35 INFO - Starting resource monitoring. 06:59:35 INFO - ##### 06:59:35 INFO - ##### Running install step. 06:59:35 INFO - ##### 06:59:35 INFO - Running pre-action listener: _resource_record_pre_action 06:59:35 INFO - Running main action method: install 06:59:35 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 06:59:35 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 06:59:35 INFO - Reading from file tmpfile_stdout 06:59:35 INFO - Using _rmtree_windows ... 06:59:35 INFO - Using _rmtree_windows ... 06:59:35 INFO - Detecting whether we're running mozinstall >=1.0... 06:59:35 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 06:59:35 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 06:59:36 INFO - Reading from file tmpfile_stdout 06:59:36 INFO - Output received: 06:59:36 INFO - Usage: mozinstall-script.py [options] installer 06:59:36 INFO - Options: 06:59:36 INFO - -h, --help show this help message and exit 06:59:36 INFO - -d DEST, --destination=DEST 06:59:36 INFO - Directory to install application into. [default: 06:59:36 INFO - "C:\slave\test-pgo"] 06:59:36 INFO - --app=APP Application being installed. [default: firefox] 06:59:36 INFO - Using _rmtree_windows ... 06:59:36 INFO - Using _rmtree_windows ... 06:59:36 INFO - mkdir: C:\slave\test-pgo\build\application 06:59:36 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test-pgo\\build\\firefox-42.0.en-US.win32.zip', '--destination', 'C:\\slave\\test-pgo\\build\\application'] 06:59:36 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py C:\slave\test-pgo\build\firefox-42.0.en-US.win32.zip --destination C:\slave\test-pgo\build\application 06:59:37 INFO - Reading from file tmpfile_stdout 06:59:37 INFO - Output received: 06:59:37 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 06:59:37 INFO - Using _rmtree_windows ... 06:59:37 INFO - Using _rmtree_windows ... 06:59:37 INFO - Running post-action listener: _resource_record_post_action 06:59:37 INFO - ##### 06:59:37 INFO - ##### Running run-tests step. 06:59:37 INFO - ##### 06:59:37 INFO - Running pre-action listener: _resource_record_pre_action 06:59:37 INFO - Running main action method: run_tests 06:59:37 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 06:59:37 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 06:59:37 INFO - Python 2.7.3 06:59:37 INFO - Return code: 0 06:59:37 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 06:59:37 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 06:59:37 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 06:59:37 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\talos_repo\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.crashreporter-symbols.zip', '--activeTests', u'tp5n', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--title', 'T-W732-IX-100', u'--xperf_path', u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', '--webServer', 'localhost'] in C:\slave\test-pgo\build 06:59:37 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\talos_repo\talos\run_tests.py --debug --branchName Mozilla-Beta --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.crashreporter-symbols.zip --activeTests tp5n --executablePath C:\slave\test-pgo\build\application\firefox\firefox --title T-W732-IX-100 --xperf_path "\"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe\"" --webServer localhost 06:59:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:59:37 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:59:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:59:37 INFO - 'COMPUTERNAME': 'T-W732-IX-100', 06:59:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:59:37 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:59:37 INFO - 'DCLOCATION': 'SCL3', 06:59:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:59:37 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:59:37 INFO - 'HOMEDRIVE': 'C:', 06:59:37 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:59:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:59:37 INFO - 'KTS_VERSION': '1.19c', 06:59:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:59:37 INFO - 'LOGONSERVER': '\\\\T-W732-IX-100', 06:59:37 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 06:59:37 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:59:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:59:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:59:37 INFO - 'MOZ_NO_REMOTE': '1', 06:59:37 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 06:59:37 INFO - 'NO_EM_RESTART': '1', 06:59:37 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:59:37 INFO - 'OS': 'Windows_NT', 06:59:37 INFO - 'OURDRIVE': 'C:', 06:59:37 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:59:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:59:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:59:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:59:37 INFO - 'PROCESSOR_LEVEL': '6', 06:59:37 INFO - 'PROCESSOR_REVISION': '1e05', 06:59:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:59:37 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:59:37 INFO - 'PROMPT': '$P$G', 06:59:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 06:59:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:59:37 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:59:37 INFO - 'PWD': 'C:\\slave\\test-pgo', 06:59:37 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\talos_repo', 06:59:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:59:37 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:59:37 INFO - 'SYSTEMDRIVE': 'C:', 06:59:37 INFO - 'SYSTEMROOT': 'C:\\windows', 06:59:37 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:59:37 INFO - 'TEST1': 'testie', 06:59:37 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:59:37 INFO - 'USERDOMAIN': 'T-W732-IX-100', 06:59:37 INFO - 'USERNAME': 'cltbld', 06:59:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:59:37 INFO - 'WINDIR': 'C:\\windows', 06:59:37 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:59:37 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:59:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:59:37 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\talos_repo\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.crashreporter-symbols.zip', '--activeTests', u'tp5n', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--title', 'T-W732-IX-100', u'--xperf_path', u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', '--webServer', 'localhost'] with output_timeout 3600 06:59:39 INFO - mozversion INFO | application_buildid: 20151029024142 06:59:39 INFO - mozversion INFO | application_changeset: 0ec8472a93ac 06:59:39 INFO - mozversion INFO | application_display_name: Nightly 06:59:39 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:59:39 INFO - mozversion INFO | application_name: Firefox 06:59:39 INFO - mozversion INFO | application_remotingname: firefox 06:59:39 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:59:39 INFO - mozversion INFO | application_vendor: Mozilla 06:59:39 INFO - mozversion INFO | application_version: 42.0 06:59:39 INFO - mozversion INFO | platform_buildid: 20151029024142 06:59:39 INFO - mozversion INFO | platform_changeset: 0ec8472a93ac 06:59:39 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:59:39 INFO - mozversion INFO | platform_version: 42.0 06:59:39 INFO - DEBUG : using testdate: 1446127179 06:59:39 INFO - DEBUG : actual date: 1446127179 06:59:39 INFO - T-W732-IX-100: 06:59:39 INFO - Started Thu, 29 Oct 2015 06:59:39 06:59:39 INFO - Running test tp5n: 06:59:39 INFO - Started Thu, 29 Oct 2015 06:59:39 06:59:39 INFO - DEBUG : operating with platform_type : w7_ 06:59:39 INFO - DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmppjwijl\profile http://localhost/getInfo.html 06:59:49 INFO - __metrics Screen width/height:1600/1200 06:59:49 INFO - colorDepth:24 06:59:49 INFO - Browser inner width/height: 1010/674 06:59:49 INFO - __metrics 06:59:49 INFO - JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:59:51 INFO - DEBUG : initialized firefox 06:59:51 INFO - DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmppjwijl\profile -tp file:\C:\slave\test-pgo\build\talos_repo\talos\page_load_test\tp5n\tp5n.manifest -tpchrome -tpmozafterpaint -tpnoisy -tpcycles 1 -tppagecycles 1 -tptimeout 5000 07:00:17 INFO - extending with xperf! 07:00:17 INFO - RSS: Main: 111558656 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/thesartorialist.blogspot.com/thesartorialist.blogspot.com/index.html (next: http://localhost/page_load_test/tp5n/cakewrecks.blogspot.com/cakewrecks.blogspot.com/index.html) 07:00:17 INFO - RSS: Main: 137760768 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/cakewrecks.blogspot.com/cakewrecks.blogspot.com/index.html (next: http://localhost/page_load_test/tp5n/baidu.com/www.baidu.com/s@wd=mozilla.html) 07:00:17 INFO - RSS: Main: 143851520 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/baidu.com/www.baidu.com/s@wd=mozilla.html (next: http://localhost/page_load_test/tp5n/en.wikipedia.org/en.wikipedia.org/wiki/Rorschach_test.html) 07:00:17 INFO - RSS: Main: 146198528 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/en.wikipedia.org/en.wikipedia.org/wiki/Rorschach_test.html (next: http://localhost/page_load_test/tp5n/twitter.com/twitter.com/ICHCheezburger.html) 07:00:17 INFO - RSS: Main: 152596480 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/twitter.com/twitter.com/ICHCheezburger.html (next: http://localhost/page_load_test/tp5n/msn.com/www.msn.com/index.html) 07:00:17 INFO - RSS: Main: 164642816 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/msn.com/www.msn.com/index.html (next: http://localhost/page_load_test/tp5n/yahoo.co.jp/www.yahoo.co.jp/index.html) 07:00:17 INFO - RSS: Main: 165556224 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/yahoo.co.jp/www.yahoo.co.jp/index.html (next: http://localhost/page_load_test/tp5n/amazon.com/www.amazon.com/Kindle-Wireless-Reader-Wifi-Graphite/dp/B002Y27P3M/507846.html) 07:00:17 INFO - RSS: Main: 165076992 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/amazon.com/www.amazon.com/Kindle-Wireless-Reader-Wifi-Graphite/dp/B002Y27P3M/507846.html (next: http://localhost/page_load_test/tp5n/linkedin.com/www.linkedin.com/in/christopherblizzard@goback=.nppvan_%252Flemuelf.html) 07:00:17 INFO - RSS: Main: 181006336 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/linkedin.com/www.linkedin.com/in/christopherblizzard@goback=.nppvan_%252Flemuelf.html (next: http://localhost/page_load_test/tp5n/bing.com/www.bing.com/search@q=mozilla&go=&form=QBLH&qs=n&sk=&sc=8-0.html) 07:00:17 INFO - RSS: Main: 189128704 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/bing.com/www.bing.com/search@q=mozilla&go=&form=QBLH&qs=n&sk=&sc=8-0.html (next: http://localhost/page_load_test/tp5n/icanhascheezburger.com/icanhascheezburger.com/index.html) 07:00:17 INFO - RSS: Main: 172445696 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/icanhascheezburger.com/icanhascheezburger.com/index.html (next: http://localhost/page_load_test/tp5n/yandex.ru/yandex.ru/yandsearch@text=mozilla&lr=21215.html) 07:00:17 INFO - RSS: Main: 182374400 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/yandex.ru/yandex.ru/yandsearch@text=mozilla&lr=21215.html (next: http://localhost/page_load_test/tp5n/cgi.ebay.com/cgi.ebay.com/ALL-NEW-KINDLE-3-eBOOK-WIRELESS-READING-DEVICE-W-WIFI-/130496077314@pt=LH_DefaultDomain_0&hash=item1e622c1e02.html) 07:00:17 INFO - RSS: Main: 172924928 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/cgi.ebay.com/cgi.ebay.com/ALL-NEW-KINDLE-3-eBOOK-WIRELESS-READING-DEVICE-W-WIFI-/130496077314@pt=LH_DefaultDomain_0&hash=item1e622c1e02.html (next: http://localhost/page_load_test/tp5n/163.com/www.163.com/index.html) 07:00:17 INFO - RSS: Main: 179773440 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/163.com/www.163.com/index.html (next: http://localhost/page_load_test/tp5n/mail.ru/mail.ru/index.html) 07:00:17 INFO - RSS: Main: 186793984 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/mail.ru/mail.ru/index.html (next: http://localhost/page_load_test/tp5n/bbc.co.uk/www.bbc.co.uk/news/index.html) 07:00:17 INFO - RSS: Main: 188215296 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/bbc.co.uk/www.bbc.co.uk/news/index.html (next: http://localhost/page_load_test/tp5n/store.apple.com/store.apple.com/us@mco=Nzc1MjMwNA.html) 07:00:17 INFO - RSS: Main: 192708608 07:00:17 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/store.apple.com/store.apple.com/us@mco=Nzc1MjMwNA.html (next: http://localhost/page_load_test/tp5n/imdb.com/www.imdb.com/title/tt1099212/index.html) 07:00:32 INFO - RSS: Main: 200282112 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/imdb.com/www.imdb.com/title/tt1099212/index.html (next: http://localhost/page_load_test/tp5n/mozilla.com/www.mozilla.com/en-US/firefox/all-older.html) 07:00:32 INFO - RSS: Main: 190607360 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/mozilla.com/www.mozilla.com/en-US/firefox/all-older.html (next: http://localhost/page_load_test/tp5n/ask.com/www.ask.com/web@q=What%27s+the+difference+between+brown+and+white+eggs%253F&gc=1&qsrc=3045&o=0&l=dir.html) 07:00:32 INFO - RSS: Main: 193814528 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/ask.com/www.ask.com/web@q=What%27s+the+difference+between+brown+and+white+eggs%253F&gc=1&qsrc=3045&o=0&l=dir.html (next: http://localhost/page_load_test/tp5n/cnn.com/www.cnn.com/index.html) 07:00:32 INFO - RSS: Main: 229888000 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/cnn.com/www.cnn.com/index.html (next: http://localhost/page_load_test/tp5n/sohu.com/www.sohu.com/index.html) 07:00:32 INFO - RSS: Main: 198995968 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/sohu.com/www.sohu.com/index.html (next: http://localhost/page_load_test/tp5n/vkontakte.ru/vkontakte.ru/help.php@page=about.html) 07:00:32 INFO - RSS: Main: 192319488 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/vkontakte.ru/vkontakte.ru/help.php@page=about.html (next: http://localhost/page_load_test/tp5n/youku.com/www.youku.com/index.html) 07:00:32 INFO - RSS: Main: 190722048 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/youku.com/www.youku.com/index.html (next: http://localhost/page_load_test/tp5n/myparentswereawesome.tumblr.com/myparentswereawesome.tumblr.com/index.html) 07:00:32 INFO - RSS: Main: 197718016 07:00:32 INFO - Corrupt JPEG data: 8 extraneous bytes before marker 0xe1 07:00:32 INFO - Corrupt JPEG data: 8 extraneous bytes before marker 0xe1 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/myparentswereawesome.tumblr.com/myparentswereawesome.tumblr.com/index.html (next: http://localhost/page_load_test/tp5n/ifeng.com/ifeng.com/index.html) 07:00:32 INFO - RSS: Main: 198365184 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/ifeng.com/ifeng.com/index.html (next: http://localhost/page_load_test/tp5n/ameblo.jp/ameblo.jp/index.html) 07:00:32 INFO - RSS: Main: 201805824 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/ameblo.jp/ameblo.jp/index.html (next: http://localhost/page_load_test/tp5n/tudou.com/www.tudou.com/index.html) 07:00:32 INFO - RSS: Main: 202891264 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/tudou.com/www.tudou.com/index.html (next: http://localhost/page_load_test/tp5n/chemistry.about.com/chemistry.about.com/index.html) 07:00:32 INFO - RSS: Main: 204099584 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/chemistry.about.com/chemistry.about.com/index.html (next: http://localhost/page_load_test/tp5n/beatonna.livejournal.com/beatonna.livejournal.com/index.html) 07:00:32 INFO - RSS: Main: 201105408 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/beatonna.livejournal.com/beatonna.livejournal.com/index.html (next: http://localhost/page_load_test/tp5n/hao123.com/hao123.com/index.html) 07:00:32 INFO - RSS: Main: 206704640 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/hao123.com/hao123.com/index.html (next: http://localhost/page_load_test/tp5n/rakuten.co.jp/www.rakuten.co.jp/index.html) 07:00:32 INFO - RSS: Main: 202108928 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/rakuten.co.jp/www.rakuten.co.jp/index.html (next: http://localhost/page_load_test/tp5n/alibaba.com/www.alibaba.com/product-tp/101509462/World_s_Cheapest_Laptop.html) 07:00:32 INFO - RSS: Main: 204947456 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/alibaba.com/www.alibaba.com/product-tp/101509462/World_s_Cheapest_Laptop.html (next: http://localhost/page_load_test/tp5n/uol.com.br/www.uol.com.br/index.html) 07:00:32 INFO - RSS: Main: 203268096 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/uol.com.br/www.uol.com.br/index.html (next: http://localhost/page_load_test/tp5n/cnet.com/www.cnet.com/index.html) 07:00:32 INFO - RSS: Main: 204779520 07:00:32 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/cnet.com/www.cnet.com/index.html (next: http://localhost/page_load_test/tp5n/ehow.com/www.ehow.com/how_4575878_prevent-fire-home.html) 07:00:42 INFO - RSS: Main: 212025344 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/ehow.com/www.ehow.com/how_4575878_prevent-fire-home.html (next: http://localhost/page_load_test/tp5n/thepiratebay.org/thepiratebay.org/top/201.html) 07:00:42 INFO - RSS: Main: 212713472 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/thepiratebay.org/thepiratebay.org/top/201.html (next: http://localhost/page_load_test/tp5n/page.renren.com/page.renren.com/index.html) 07:00:42 INFO - RSS: Main: 204783616 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/page.renren.com/page.renren.com/index.html (next: http://localhost/page_load_test/tp5n/chinaz.com/chinaz.com/index.html) 07:00:42 INFO - RSS: Main: 203431936 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/chinaz.com/chinaz.com/index.html (next: http://localhost/page_load_test/tp5n/globo.com/www.globo.com/index.html) 07:00:42 INFO - RSS: Main: 205533184 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/globo.com/www.globo.com/index.html (next: http://localhost/page_load_test/tp5n/spiegel.de/www.spiegel.de/index.html) 07:00:42 INFO - RSS: Main: 216432640 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/spiegel.de/www.spiegel.de/index.html (next: http://localhost/page_load_test/tp5n/dailymotion.com/www.dailymotion.com/us.html) 07:00:42 INFO - RSS: Main: 208433152 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/dailymotion.com/www.dailymotion.com/us.html (next: http://localhost/page_load_test/tp5n/goo.ne.jp/goo.ne.jp/index.html) 07:00:42 INFO - RSS: Main: 229920768 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/goo.ne.jp/goo.ne.jp/index.html (next: http://localhost/page_load_test/tp5n/alipay.com/www.alipay.com/index.html) 07:00:42 INFO - RSS: Main: 211095552 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/alipay.com/www.alipay.com/index.html (next: http://localhost/page_load_test/tp5n/stackoverflow.com/stackoverflow.com/questions/184618/what-is-the-best-comment-in-source-code-you-have-ever-encountered.html) 07:00:42 INFO - RSS: Main: 210653184 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/stackoverflow.com/stackoverflow.com/questions/184618/what-is-the-best-comment-in-source-code-you-have-ever-encountered.html (next: http://localhost/page_load_test/tp5n/nicovideo.jp/www.nicovideo.jp/index.html) 07:00:42 INFO - RSS: Main: 209080320 07:00:42 INFO - Corrupt JPEG data: 40 extraneous bytes before marker 0xee 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/nicovideo.jp/www.nicovideo.jp/index.html (next: http://localhost/page_load_test/tp5n/ezinearticles.com/ezinearticles.com/index.html@Migraine-Ocular---The-Eye-Migraines&id=4684133.html) 07:00:42 INFO - RSS: Main: 208556032 07:00:42 INFO - Corrupt JPEG data: 40 extraneous bytes before marker 0xee 07:00:42 INFO - Corrupt JPEG data: 40 extraneous bytes before marker 0xee 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/ezinearticles.com/ezinearticles.com/index.html@Migraine-Ocular---The-Eye-Migraines&id=4684133.html (next: http://localhost/page_load_test/tp5n/taringa.net/www.taringa.net/index.html) 07:00:42 INFO - RSS: Main: 208646144 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/taringa.net/www.taringa.net/index.html (next: http://localhost/page_load_test/tp5n/tmall.com/www.tmall.com/index.html@ver=2010s.html) 07:00:42 INFO - RSS: Main: 208797696 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/tmall.com/www.tmall.com/index.html@ver=2010s.html (next: http://localhost/page_load_test/tp5n/huffingtonpost.com/www.huffingtonpost.com/index.html) 07:00:42 INFO - RSS: Main: 214818816 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/huffingtonpost.com/www.huffingtonpost.com/index.html (next: http://localhost/page_load_test/tp5n/deviantart.com/www.deviantart.com/index.html) 07:00:42 INFO - RSS: Main: 215064576 07:00:42 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/deviantart.com/www.deviantart.com/index.html (next: http://localhost/page_load_test/tp5n/media.photobucket.com/media.photobucket.com/image/funny%20gif/findstuff22/Best%20Images/Funny/funny-gif1.jpg@o=1.html) 07:00:42 INFO - RSS: Main: 219131904 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/media.photobucket.com/media.photobucket.com/image/funny%20gif/findstuff22/Best%20Images/Funny/funny-gif1.jpg@o=1.html (next: http://localhost/page_load_test/tp5n/douban.com/www.douban.com/index.html) 07:00:57 INFO - RSS: Main: 238104576 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/douban.com/www.douban.com/index.html (next: http://localhost/page_load_test/tp5n/imgur.com/imgur.com/gallery/index.html) 07:00:57 INFO - RSS: Main: 214650880 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/imgur.com/imgur.com/gallery/index.html (next: http://localhost/page_load_test/tp5n/reddit.com/www.reddit.com/index.html) 07:00:57 INFO - RSS: Main: 220573696 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/reddit.com/www.reddit.com/index.html (next: http://localhost/page_load_test/tp5n/digg.com/digg.com/news/story/New_logo_for_Mozilla_Firefox_browser.html) 07:00:57 INFO - RSS: Main: 212406272 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/digg.com/digg.com/news/story/New_logo_for_Mozilla_Firefox_browser.html (next: http://localhost/page_load_test/tp5n/filestube.com/www.filestube.com/t/the+vampire+diaries.html) 07:00:57 INFO - RSS: Main: 212434944 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/filestube.com/www.filestube.com/t/the+vampire+diaries.html (next: http://localhost/page_load_test/tp5n/dailymail.co.uk/www.dailymail.co.uk/ushome/index.html) 07:00:57 INFO - RSS: Main: 213147648 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/dailymail.co.uk/www.dailymail.co.uk/ushome/index.html (next: http://localhost/page_load_test/tp5n/whois.domaintools.com/whois.domaintools.com/mozilla.com.html) 07:00:57 INFO - RSS: Main: 232484864 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/whois.domaintools.com/whois.domaintools.com/mozilla.com.html (next: http://localhost/page_load_test/tp5n/indiatimes.com/www.indiatimes.com/index.html) 07:00:57 INFO - RSS: Main: 219533312 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/indiatimes.com/www.indiatimes.com/index.html (next: http://localhost/page_load_test/tp5n/rambler.ru/www.rambler.ru/index.html) 07:00:57 INFO - RSS: Main: 222494720 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/rambler.ru/www.rambler.ru/index.html (next: http://localhost/page_load_test/tp5n/torrentz.eu/torrentz.eu/search@q=movies.html) 07:00:57 INFO - RSS: Main: 221962240 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/torrentz.eu/torrentz.eu/search@q=movies.html (next: http://localhost/page_load_test/tp5n/reuters.com/www.reuters.com/index.html) 07:00:57 INFO - RSS: Main: 216313856 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/reuters.com/www.reuters.com/index.html (next: http://localhost/page_load_test/tp5n/foxnews.com/www.foxnews.com/index.html) 07:00:57 INFO - RSS: Main: 225333248 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/foxnews.com/www.foxnews.com/index.html (next: http://localhost/page_load_test/tp5n/xinhuanet.com/xinhuanet.com/index.html) 07:00:57 INFO - RSS: Main: 221364224 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/xinhuanet.com/xinhuanet.com/index.html (next: http://localhost/page_load_test/tp5n/56.com/www.56.com/index.html) 07:00:57 INFO - RSS: Main: 226390016 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/56.com/www.56.com/index.html (next: http://localhost/page_load_test/tp5n/bild.de/www.bild.de/index.html) 07:00:57 INFO - RSS: Main: 231989248 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/bild.de/www.bild.de/index.html (next: http://localhost/page_load_test/tp5n/guardian.co.uk/www.guardian.co.uk/index.html) 07:00:57 INFO - RSS: Main: 253407232 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/guardian.co.uk/www.guardian.co.uk/index.html (next: http://localhost/page_load_test/tp5n/w3schools.com/www.w3schools.com/html/default.asp.html) 07:00:57 INFO - RSS: Main: 244895744 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/w3schools.com/www.w3schools.com/html/default.asp.html (next: http://localhost/page_load_test/tp5n/naver.com/www.naver.com/index.html) 07:00:57 INFO - RSS: Main: 240349184 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/naver.com/www.naver.com/index.html (next: http://localhost/page_load_test/tp5n/blogfa.com/blogfa.com/index.html) 07:00:57 INFO - RSS: Main: 248250368 07:00:57 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/blogfa.com/blogfa.com/index.html (next: http://localhost/page_load_test/tp5n/terra.com.br/www.terra.com.br/portal/index.html) 07:00:57 INFO - RSS: Main: 234127360 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/terra.com.br/www.terra.com.br/portal/index.html (next: http://localhost/page_load_test/tp5n/ucoz.ru/www.ucoz.ru/index.html) 07:01:10 INFO - RSS: Main: 236965888 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/ucoz.ru/www.ucoz.ru/index.html (next: http://localhost/page_load_test/tp5n/yelp.com/www.yelp.com/biz/alexanders-steakhouse-cupertino.html) 07:01:10 INFO - RSS: Main: 235102208 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/yelp.com/www.yelp.com/biz/alexanders-steakhouse-cupertino.html (next: http://localhost/page_load_test/tp5n/wsj.com/online.wsj.com/home-page.html) 07:01:10 INFO - RSS: Main: 237006848 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/wsj.com/online.wsj.com/home-page.html (next: http://localhost/page_load_test/tp5n/noimpactman.typepad.com/noimpactman.typepad.com/index.html) 07:01:10 INFO - RSS: Main: 237613056 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/noimpactman.typepad.com/noimpactman.typepad.com/index.html (next: http://localhost/page_load_test/tp5n/myspace.com/www.myspace.com/albumart.html) 07:01:10 INFO - RSS: Main: 237551616 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/myspace.com/www.myspace.com/albumart.html (next: http://localhost/page_load_test/tp5n/google.com/www.google.com/search@q=mozilla.html) 07:01:10 INFO - RSS: Main: 239702016 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/google.com/www.google.com/search@q=mozilla.html (next: http://localhost/page_load_test/tp5n/orange.fr/www.orange.fr/index.html) 07:01:10 INFO - RSS: Main: 236314624 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/orange.fr/www.orange.fr/index.html (next: http://localhost/page_load_test/tp5n/php.net/php.net/index.html) 07:01:10 INFO - RSS: Main: 234983424 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/php.net/php.net/index.html (next: http://localhost/page_load_test/tp5n/zol.com.cn/www.zol.com.cn/index.html) 07:01:10 INFO - RSS: Main: 232529920 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/zol.com.cn/www.zol.com.cn/index.html (next: http://localhost/page_load_test/tp5n/mashable.com/mashable.com/index.html) 07:01:10 INFO - RSS: Main: 240955392 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/mashable.com/mashable.com/index.html (next: http://localhost/page_load_test/tp5n/etsy.com/www.etsy.com/category/geekery/videogame.html) 07:01:10 INFO - RSS: Main: 240869376 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/etsy.com/www.etsy.com/category/geekery/videogame.html (next: http://localhost/page_load_test/tp5n/gmx.net/www.gmx.net/index.html) 07:01:10 INFO - RSS: Main: 243785728 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/gmx.net/www.gmx.net/index.html (next: http://localhost/page_load_test/tp5n/csdn.net/csdn.net/index.html) 07:01:10 INFO - RSS: Main: 242937856 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/csdn.net/csdn.net/index.html (next: http://localhost/page_load_test/tp5n/xunlei.com/xunlei.com/index.html) 07:01:10 INFO - RSS: Main: 235716608 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/xunlei.com/xunlei.com/index.html (next: http://localhost/page_load_test/tp5n/hatena.ne.jp/www.hatena.ne.jp/index.html) 07:01:10 INFO - RSS: Main: 236400640 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/hatena.ne.jp/www.hatena.ne.jp/index.html (next: http://localhost/page_load_test/tp5n/icious.com/www.delicious.com/index.html) 07:01:10 INFO - RSS: Main: 231505920 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/icious.com/www.delicious.com/index.html (next: http://localhost/page_load_test/tp5n/repubblica.it/www.repubblica.it/index.html) 07:01:10 INFO - RSS: Main: 232218624 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/repubblica.it/www.repubblica.it/index.html (next: http://localhost/page_load_test/tp5n/web.de/web.de/index.html) 07:01:10 INFO - RSS: Main: 252362752 07:01:10 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/web.de/web.de/index.html (next: http://localhost/page_load_test/tp5n/slideshare.net/www.slideshare.net/jameswillamor/lolcats-in-popular-culture-a-historical-perspective.html) 07:01:10 INFO - RSS: Main: 238219264 07:01:16 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/slideshare.net/www.slideshare.net/jameswillamor/lolcats-in-popular-culture-a-historical-perspective.html (next: http://localhost/page_load_test/tp5n/telegraph.co.uk/www.telegraph.co.uk/index.html) 07:01:16 INFO - RSS: Main: 234704896 07:01:16 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/telegraph.co.uk/www.telegraph.co.uk/index.html (next: http://localhost/page_load_test/tp5n/seesaa.net/blog.seesaa.jp/index.html) 07:01:16 INFO - RSS: Main: 238030848 07:01:16 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/seesaa.net/blog.seesaa.jp/index.html (next: http://localhost/page_load_test/tp5n/wp.pl/www.wp.pl/index.html) 07:01:16 INFO - RSS: Main: 237940736 07:01:16 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/wp.pl/www.wp.pl/index.html (next: http://localhost/page_load_test/tp5n/aljazeera.net/aljazeera.net/portal.html) 07:01:16 INFO - RSS: Main: 236609536 07:01:16 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/aljazeera.net/aljazeera.net/portal.html (next: http://localhost/page_load_test/tp5n/w3.org/www.w3.org/standards/webdesign/htmlcss.html) 07:01:16 INFO - RSS: Main: 239894528 07:01:16 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/w3.org/www.w3.org/standards/webdesign/htmlcss.html (next: http://localhost/page_load_test/tp5n/homeway.com.cn/www.hexun.com/index.html) 07:01:16 INFO - RSS: Main: 235933696 07:01:16 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/homeway.com.cn/www.hexun.com/index.html (next: http://localhost/page_load_test/tp5n/facebook.com/www.facebook.com/Google.html) 07:01:16 INFO - RSS: Main: 243122176 07:01:16 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/facebook.com/www.facebook.com/Google.html (next: http://localhost/page_load_test/tp5n/youtube.com/www.youtube.com/music.html) 07:01:16 INFO - RSS: Main: 244297728 07:01:16 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/youtube.com/www.youtube.com/music.html (next: http://localhost/page_load_test/tp5n/people.com.cn/people.com.cn/index.html) 07:01:16 INFO - RSS: Main: 242909184 07:01:16 INFO - Cycle 1(1): loaded http://localhost/page_load_test/tp5n/people.com.cn/people.com.cn/index.html (next: http://localhost/page_load_test/tp5n/thesartorialist.blogspot.com/thesartorialist.blogspot.com/index.html) 07:01:16 INFO - RSS: Main: 243175424 07:01:16 INFO - __start_tp_report 07:01:16 INFO - _x_x_mozilla_page_load 07:01:16 INFO - _x_x_mozilla_page_load_details 07:01:16 INFO - |i|pagename|runs| 07:01:16 INFO - |0;thesartorialist.blogspot.com/thesartorialist.blogspot.com/index.html;623 07:01:16 INFO - |1;cakewrecks.blogspot.com/cakewrecks.blogspot.com/index.html;601 07:01:16 INFO - |2;baidu.com/www.baidu.com/s@wd=mozilla.html;966 07:01:16 INFO - |3;en.wikipedia.org/en.wikipedia.org/wiki/Rorschach_test.html;821 07:01:16 INFO - |4;twitter.com/twitter.com/ICHCheezburger.html;220 07:01:16 INFO - |5;msn.com/www.msn.com/index.html;288 07:01:16 INFO - |6;yahoo.co.jp/www.yahoo.co.jp/index.html;434 07:01:16 INFO - |7;amazon.com/www.amazon.com/Kindle-Wireless-Reader-Wifi-Graphite/dp/B002Y27P3M/507846.html;1136 07:01:16 INFO - |8;linkedin.com/www.linkedin.com/in/christopherblizzard@goback=.nppvan_%252Flemuelf.html;161 07:01:16 INFO - |9;bing.com/www.bing.com/search@q=mozilla&go=&form=QBLH&qs=n&sk=&sc=8-0.html;109 07:01:16 INFO - |10;icanhascheezburger.com/icanhascheezburger.com/index.html;935 07:01:16 INFO - |11;yandex.ru/yandex.ru/yandsearch@text=mozilla&lr=21215.html;254 07:01:16 INFO - |12;cgi.ebay.com/cgi.ebay.com/ALL-NEW-KINDLE-3-eBOOK-WIRELESS-READING-DEVICE-W-WIFI-/130496077314@pt=LH_DefaultDomain_0&hash=item1e622c1e02.html;502 07:01:16 INFO - |13;163.com/www.163.com/index.html;695 07:01:16 INFO - |14;mail.ru/mail.ru/index.html;616 07:01:16 INFO - |15;bbc.co.uk/www.bbc.co.uk/news/index.html;444 07:01:16 INFO - |16;store.apple.com/store.apple.com/us@mco=Nzc1MjMwNA.html;244 07:01:16 INFO - |17;imdb.com/www.imdb.com/title/tt1099212/index.html;287 07:01:16 INFO - |18;mozilla.com/www.mozilla.com/en-US/firefox/all-older.html;1552 07:01:16 INFO - |19;ask.com/www.ask.com/web@q=What%27s+the+difference+between+brown+and+white+eggs%253F&gc=1&qsrc=3045&o=0&l=dir.html;159 07:01:16 INFO - |20;cnn.com/www.cnn.com/index.html;325 07:01:16 INFO - |21;sohu.com/www.sohu.com/index.html;365 07:01:16 INFO - |22;vkontakte.ru/vkontakte.ru/help.php@page=about.html;127 07:01:16 INFO - |23;youku.com/www.youku.com/index.html;2446 07:01:16 INFO - |24;myparentswereawesome.tumblr.com/myparentswereawesome.tumblr.com/index.html;146 07:01:16 INFO - |25;ifeng.com/ifeng.com/index.html;454 07:01:16 INFO - |26;ameblo.jp/ameblo.jp/index.html;469 07:01:16 INFO - |27;tudou.com/www.tudou.com/index.html;450 07:01:16 INFO - |28;chemistry.about.com/chemistry.about.com/index.html;147 07:01:16 INFO - |29;beatonna.livejournal.com/beatonna.livejournal.com/index.html;132 07:01:16 INFO - |30;hao123.com/hao123.com/index.html;592 07:01:16 INFO - |31;rakuten.co.jp/www.rakuten.co.jp/index.html;574 07:01:16 INFO - |32;alibaba.com/www.alibaba.com/product-tp/101509462/World_s_Cheapest_Laptop.html;107 07:01:16 INFO - |33;uol.com.br/www.uol.com.br/index.html;364 07:01:16 INFO - |34;cnet.com/www.cnet.com/index.html;454 07:01:16 INFO - |35;ehow.com/www.ehow.com/how_4575878_prevent-fire-home.html;288 07:01:16 INFO - |36;thepiratebay.org/thepiratebay.org/top/201.html;228 07:01:16 INFO - |37;page.renren.com/page.renren.com/index.html;181 07:01:16 INFO - |38;chinaz.com/chinaz.com/index.html;471 07:01:16 INFO - |39;globo.com/www.globo.com/index.html;364 07:01:16 INFO - |40;spiegel.de/www.spiegel.de/index.html;365 07:01:16 INFO - |41;dailymotion.com/www.dailymotion.com/us.html;220 07:01:16 INFO - |42;goo.ne.jp/goo.ne.jp/index.html;266 07:01:16 INFO - |43;alipay.com/www.alipay.com/index.html;204 07:01:16 INFO - |44;stackoverflow.com/stackoverflow.com/questions/184618/what-is-the-best-comment-in-source-code-you-have-ever-encountered.html;628 07:01:16 INFO - |45;nicovideo.jp/www.nicovideo.jp/index.html;502 07:01:16 INFO - |46;ezinearticles.com/ezinearticles.com/index.html@Migraine-Ocular---The-Eye-Migraines&id=4684133.html;142 07:01:16 INFO - |47;taringa.net/www.taringa.net/index.html;292 07:01:16 INFO - |48;tmall.com/www.tmall.com/index.html@ver=2010s.html;550 07:01:16 INFO - |49;huffingtonpost.com/www.huffingtonpost.com/index.html;415 07:01:16 INFO - |50;deviantart.com/www.deviantart.com/index.html;244 07:01:16 INFO - |51;media.photobucket.com/media.photobucket.com/image/funny%20gif/findstuff22/Best%20Images/Funny/funny-gif1.jpg@o=1.html;363 07:01:16 INFO - |52;douban.com/www.douban.com/index.html;378 07:01:16 INFO - |53;imgur.com/imgur.com/gallery/index.html;263 07:01:16 INFO - |54;reddit.com/www.reddit.com/index.html;188 07:01:16 INFO - |55;digg.com/digg.com/news/story/New_logo_for_Mozilla_Firefox_browser.html;260 07:01:16 INFO - |56;filestube.com/www.filestube.com/t/the+vampire+diaries.html;174 07:01:16 INFO - |57;dailymail.co.uk/www.dailymail.co.uk/ushome/index.html;584 07:01:16 INFO - |58;whois.domaintools.com/whois.domaintools.com/mozilla.com.html;109 07:01:16 INFO - |59;indiatimes.com/www.indiatimes.com/index.html;489 07:01:16 INFO - |60;rambler.ru/www.rambler.ru/index.html;353 07:01:16 INFO - |61;torrentz.eu/torrentz.eu/search@q=movies.html;140 07:01:16 INFO - |62;reuters.com/www.reuters.com/index.html;290 07:01:16 INFO - |63;foxnews.com/www.foxnews.com/index.html;281 07:01:16 INFO - |64;xinhuanet.com/xinhuanet.com/index.html;1334 07:01:16 INFO - |65;56.com/www.56.com/index.html;870 07:01:16 INFO - |66;bild.de/www.bild.de/index.html;788 07:01:16 INFO - |67;guardian.co.uk/www.guardian.co.uk/index.html;270 07:01:16 INFO - |68;w3schools.com/www.w3schools.com/html/default.asp.html;162 07:01:16 INFO - |69;naver.com/www.naver.com/index.html;1261 07:01:16 INFO - |70;blogfa.com/blogfa.com/index.html;251 07:01:16 INFO - |71;terra.com.br/www.terra.com.br/portal/index.html;297 07:01:16 INFO - |72;ucoz.ru/www.ucoz.ru/index.html;875 07:01:16 INFO - |73;yelp.com/www.yelp.com/biz/alexanders-steakhouse-cupertino.html;353 07:01:16 INFO - |74;wsj.com/online.wsj.com/home-page.html;422 07:01:16 INFO - |75;noimpactman.typepad.com/noimpactman.typepad.com/index.html;233 07:01:16 INFO - |76;myspace.com/www.myspace.com/albumart.html;554 07:01:16 INFO - |77;google.com/www.google.com/search@q=mozilla.html;92 07:01:16 INFO - |78;orange.fr/www.orange.fr/index.html;130 07:01:16 INFO - |79;php.net/php.net/index.html;151 07:01:16 INFO - |80;zol.com.cn/www.zol.com.cn/index.html;546 07:01:16 INFO - |81;mashable.com/mashable.com/index.html;447 07:01:16 INFO - |82;etsy.com/www.etsy.com/category/geekery/videogame.html;179 07:01:16 INFO - |83;gmx.net/www.gmx.net/index.html;210 07:01:16 INFO - |84;csdn.net/csdn.net/index.html;462 07:01:16 INFO - |85;xunlei.com/xunlei.com/index.html;441 07:01:16 INFO - |86;hatena.ne.jp/www.hatena.ne.jp/index.html;704 07:01:16 INFO - |87;icious.com/www.delicious.com/index.html;211 07:01:16 INFO - |88;repubblica.it/www.repubblica.it/index.html;386 07:01:16 INFO - |89;web.de/web.de/index.html;199 07:01:16 INFO - |90;slideshare.net/www.slideshare.net/jameswillamor/lolcats-in-popular-culture-a-historical-perspective.html;220 07:01:16 INFO - |91;telegraph.co.uk/www.telegraph.co.uk/index.html;228 07:01:16 INFO - |92;seesaa.net/blog.seesaa.jp/index.html;571 07:01:16 INFO - |93;wp.pl/www.wp.pl/index.html;241 07:01:16 INFO - |94;aljazeera.net/aljazeera.net/portal.html;471 07:01:16 INFO - |95;w3.org/www.w3.org/standards/webdesign/htmlcss.html;200 07:01:16 INFO - |96;homeway.com.cn/www.hexun.com/index.html;486 07:01:16 INFO - |97;facebook.com/www.facebook.com/Google.html;89 07:01:16 INFO - |98;youtube.com/www.youtube.com/music.html;296 07:01:16 INFO - |99;people.com.cn/people.com.cn/index.html;441 07:01:16 INFO - __end_tp_report 07:01:16 INFO - __start_cc_report 07:01:16 INFO - _x_x_mozilla_cycle_collect,5711 07:01:16 INFO - __end_cc_report 07:01:16 INFO - __startTimestamp1446127276196__endTimestamp 07:01:16 INFO - ------- Summary: start ------- 07:01:16 INFO - Number of tests: 100 07:01:16 INFO - [#0] thesartorialist.blogspot.com/thesartorialist.blogspot.com/index.html Cycles:1 Average:623.00 Median:623.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 623.0 07:01:16 INFO - [#1] cakewrecks.blogspot.com/cakewrecks.blogspot.com/index.html Cycles:1 Average:601.00 Median:601.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 601.0 07:01:16 INFO - [#2] baidu.com/www.baidu.com/s@wd=mozilla.html Cycles:1 Average:966.00 Median:966.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 966.0 07:01:16 INFO - [#3] en.wikipedia.org/en.wikipedia.org/wiki/Rorschach_test.html Cycles:1 Average:821.00 Median:821.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 821.0 07:01:16 INFO - [#4] twitter.com/twitter.com/ICHCheezburger.html Cycles:1 Average:220.00 Median:220.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 220.0 07:01:16 INFO - [#5] msn.com/www.msn.com/index.html Cycles:1 Average:288.00 Median:288.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 288.0 07:01:16 INFO - [#6] yahoo.co.jp/www.yahoo.co.jp/index.html Cycles:1 Average:434.00 Median:434.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 434.0 07:01:16 INFO - [#7] amazon.com/www.amazon.com/Kindle-Wireless-Reader-Wifi-Graphite/dp/B002Y27P3M/507846.html Cycles:1 Average:1136.00 Median:1136.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 1136.0 07:01:16 INFO - [#8] linkedin.com/www.linkedin.com/in/christopherblizzard@goback=.nppvan_%252Flemuelf.html Cycles:1 Average:161.00 Median:161.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 161.0 07:01:16 INFO - [#9] bing.com/www.bing.com/search@q=mozilla&go=&form=QBLH&qs=n&sk=&sc=8-0.html Cycles:1 Average:109.00 Median:109.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 109.0 07:01:16 INFO - [#10] icanhascheezburger.com/icanhascheezburger.com/index.html Cycles:1 Average:935.00 Median:935.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 935.0 07:01:16 INFO - [#11] yandex.ru/yandex.ru/yandsearch@text=mozilla&lr=21215.html Cycles:1 Average:254.00 Median:254.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 254.0 07:01:16 INFO - [#12] cgi.ebay.com/cgi.ebay.com/ALL-NEW-KINDLE-3-eBOOK-WIRELESS-READING-DEVICE-W-WIFI-/130496077314@pt=LH_DefaultDomain_0&hash=item1e622c1e02.html Cycles:1 Average:502.00 Median:502.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 502.0 07:01:16 INFO - [#13] 163.com/www.163.com/index.html Cycles:1 Average:695.00 Median:695.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 695.0 07:01:16 INFO - [#14] mail.ru/mail.ru/index.html Cycles:1 Average:616.00 Median:616.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 616.0 07:01:16 INFO - [#15] bbc.co.uk/www.bbc.co.uk/news/index.html Cycles:1 Average:444.00 Median:444.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 444.0 07:01:16 INFO - [#16] store.apple.com/store.apple.com/us@mco=Nzc1MjMwNA.html Cycles:1 Average:244.00 Median:244.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 244.0 07:01:16 INFO - [#17] imdb.com/www.imdb.com/title/tt1099212/index.html Cycles:1 Average:287.00 Median:287.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 287.0 07:01:16 INFO - [#18] mozilla.com/www.mozilla.com/en-US/firefox/all-older.html Cycles:1 Average:1552.00 Median:1552.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 1552.0 07:01:16 INFO - [#19] ask.com/www.ask.com/web@q=What%27s+the+difference+between+brown+and+white+eggs%253F&gc=1&qsrc=3045&o=0&l=dir.html Cycles:1 Average:159.00 Median:159.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 159.0 07:01:16 INFO - [#20] cnn.com/www.cnn.com/index.html Cycles:1 Average:325.00 Median:325.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 325.0 07:01:16 INFO - [#21] sohu.com/www.sohu.com/index.html Cycles:1 Average:365.00 Median:365.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 365.0 07:01:16 INFO - [#22] vkontakte.ru/vkontakte.ru/help.php@page=about.html Cycles:1 Average:127.00 Median:127.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 127.0 07:01:16 INFO - [#23] youku.com/www.youku.com/index.html Cycles:1 Average:2446.00 Median:2446.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 2446.0 07:01:16 INFO - [#24] myparentswereawesome.tumblr.com/myparentswereawesome.tumblr.com/index.html Cycles:1 Average:146.00 Median:146.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 146.0 07:01:16 INFO - [#25] ifeng.com/ifeng.com/index.html Cycles:1 Average:454.00 Median:454.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 454.0 07:01:16 INFO - [#26] ameblo.jp/ameblo.jp/index.html Cycles:1 Average:469.00 Median:469.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 469.0 07:01:16 INFO - [#27] tudou.com/www.tudou.com/index.html Cycles:1 Average:450.00 Median:450.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 450.0 07:01:16 INFO - [#28] chemistry.about.com/chemistry.about.com/index.html Cycles:1 Average:147.00 Median:147.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 147.0 07:01:16 INFO - [#29] beatonna.livejournal.com/beatonna.livejournal.com/index.html Cycles:1 Average:132.00 Median:132.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 132.0 07:01:16 INFO - [#30] hao123.com/hao123.com/index.html Cycles:1 Average:592.00 Median:592.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 592.0 07:01:16 INFO - [#31] rakuten.co.jp/www.rakuten.co.jp/index.html Cycles:1 Average:574.00 Median:574.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 574.0 07:01:16 INFO - [#32] alibaba.com/www.alibaba.com/product-tp/101509462/World_s_Cheapest_Laptop.html Cycles:1 Average:107.00 Median:107.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 107.0 07:01:16 INFO - [#33] uol.com.br/www.uol.com.br/index.html Cycles:1 Average:364.00 Median:364.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 364.0 07:01:16 INFO - [#34] cnet.com/www.cnet.com/index.html Cycles:1 Average:454.00 Median:454.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 454.0 07:01:16 INFO - [#35] ehow.com/www.ehow.com/how_4575878_prevent-fire-home.html Cycles:1 Average:288.00 Median:288.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 288.0 07:01:16 INFO - [#36] thepiratebay.org/thepiratebay.org/top/201.html Cycles:1 Average:228.00 Median:228.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 228.0 07:01:16 INFO - [#37] page.renren.com/page.renren.com/index.html Cycles:1 Average:181.00 Median:181.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 181.0 07:01:16 INFO - [#38] chinaz.com/chinaz.com/index.html Cycles:1 Average:471.00 Median:471.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 471.0 07:01:16 INFO - [#39] globo.com/www.globo.com/index.html Cycles:1 Average:364.00 Median:364.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 364.0 07:01:16 INFO - [#40] spiegel.de/www.spiegel.de/index.html Cycles:1 Average:365.00 Median:365.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 365.0 07:01:16 INFO - [#41] dailymotion.com/www.dailymotion.com/us.html Cycles:1 Average:220.00 Median:220.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 220.0 07:01:16 INFO - [#42] goo.ne.jp/goo.ne.jp/index.html Cycles:1 Average:266.00 Median:266.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 266.0 07:01:16 INFO - [#43] alipay.com/www.alipay.com/index.html Cycles:1 Average:204.00 Median:204.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 204.0 07:01:16 INFO - [#44] stackoverflow.com/stackoverflow.com/questions/184618/what-is-the-best-comment-in-source-code-you-have-ever-encountered.html Cycles:1 Average:628.00 Median:628.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 628.0 07:01:16 INFO - [#45] nicovideo.jp/www.nicovideo.jp/index.html Cycles:1 Average:502.00 Median:502.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 502.0 07:01:16 INFO - [#46] ezinearticles.com/ezinearticles.com/index.html@Migraine-Ocular---The-Eye-Migraines&id=4684133.html Cycles:1 Average:142.00 Median:142.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 142.0 07:01:16 INFO - [#47] taringa.net/www.taringa.net/index.html Cycles:1 Average:292.00 Median:292.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 292.0 07:01:16 INFO - [#48] tmall.com/www.tmall.com/index.html@ver=2010s.html Cycles:1 Average:550.00 Median:550.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 550.0 07:01:16 INFO - [#49] huffingtonpost.com/www.huffingtonpost.com/index.html Cycles:1 Average:415.00 Median:415.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 415.0 07:01:16 INFO - [#50] deviantart.com/www.deviantart.com/index.html Cycles:1 Average:244.00 Median:244.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 244.0 07:01:16 INFO - [#51] media.photobucket.com/media.photobucket.com/image/funny%20gif/findstuff22/Best%20Images/Funny/funny-gif1.jpg@o=1.html Cycles:1 Average:363.00 Median:363.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 363.0 07:01:16 INFO - [#52] douban.com/www.douban.com/index.html Cycles:1 Average:378.00 Median:378.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 378.0 07:01:16 INFO - [#53] imgur.com/imgur.com/gallery/index.html Cycles:1 Average:263.00 Median:263.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 263.0 07:01:16 INFO - [#54] reddit.com/www.reddit.com/index.html Cycles:1 Average:188.00 Median:188.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 188.0 07:01:16 INFO - [#55] digg.com/digg.com/news/story/New_logo_for_Mozilla_Firefox_browser.html Cycles:1 Average:260.00 Median:260.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 260.0 07:01:16 INFO - [#56] filestube.com/www.filestube.com/t/the+vampire+diaries.html Cycles:1 Average:174.00 Median:174.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 174.0 07:01:16 INFO - [#57] dailymail.co.uk/www.dailymail.co.uk/ushome/index.html Cycles:1 Average:584.00 Median:584.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 584.0 07:01:16 INFO - [#58] whois.domaintools.com/whois.domaintools.com/mozilla.com.html Cycles:1 Average:109.00 Median:109.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 109.0 07:01:16 INFO - [#59] indiatimes.com/www.indiatimes.com/index.html Cycles:1 Average:489.00 Median:489.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 489.0 07:01:16 INFO - [#60] rambler.ru/www.rambler.ru/index.html Cycles:1 Average:353.00 Median:353.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 353.0 07:01:16 INFO - [#61] torrentz.eu/torrentz.eu/search@q=movies.html Cycles:1 Average:140.00 Median:140.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 140.0 07:01:16 INFO - [#62] reuters.com/www.reuters.com/index.html Cycles:1 Average:290.00 Median:290.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 290.0 07:01:16 INFO - [#63] foxnews.com/www.foxnews.com/index.html Cycles:1 Average:281.00 Median:281.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 281.0 07:01:16 INFO - [#64] xinhuanet.com/xinhuanet.com/index.html Cycles:1 Average:1334.00 Median:1334.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 1334.0 07:01:16 INFO - [#65] 56.com/www.56.com/index.html Cycles:1 Average:870.00 Median:870.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 870.0 07:01:16 INFO - [#66] bild.de/www.bild.de/index.html Cycles:1 Average:788.00 Median:788.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 788.0 07:01:16 INFO - [#67] guardian.co.uk/www.guardian.co.uk/index.html Cycles:1 Average:270.00 Median:270.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 270.0 07:01:16 INFO - [#68] w3schools.com/www.w3schools.com/html/default.asp.html Cycles:1 Average:162.00 Median:162.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 162.0 07:01:16 INFO - [#69] naver.com/www.naver.com/index.html Cycles:1 Average:1261.00 Median:1261.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 1261.0 07:01:16 INFO - [#70] blogfa.com/blogfa.com/index.html Cycles:1 Average:251.00 Median:251.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 251.0 07:01:16 INFO - [#71] terra.com.br/www.terra.com.br/portal/index.html Cycles:1 Average:297.00 Median:297.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 297.0 07:01:16 INFO - [#72] ucoz.ru/www.ucoz.ru/index.html Cycles:1 Average:875.00 Median:875.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 875.0 07:01:16 INFO - [#73] yelp.com/www.yelp.com/biz/alexanders-steakhouse-cupertino.html Cycles:1 Average:353.00 Median:353.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 353.0 07:01:16 INFO - [#74] wsj.com/online.wsj.com/home-page.html Cycles:1 Average:422.00 Median:422.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 422.0 07:01:16 INFO - [#75] noimpactman.typepad.com/noimpactman.typepad.com/index.html Cycles:1 Average:233.00 Median:233.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 233.0 07:01:16 INFO - [#76] myspace.com/www.myspace.com/albumart.html Cycles:1 Average:554.00 Median:554.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 554.0 07:01:16 INFO - [#77] google.com/www.google.com/search@q=mozilla.html Cycles:1 Average:92.00 Median:92.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 92.0 07:01:16 INFO - [#78] orange.fr/www.orange.fr/index.html Cycles:1 Average:130.00 Median:130.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 130.0 07:01:16 INFO - [#79] php.net/php.net/index.html Cycles:1 Average:151.00 Median:151.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 151.0 07:01:16 INFO - [#80] zol.com.cn/www.zol.com.cn/index.html Cycles:1 Average:546.00 Median:546.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 546.0 07:01:16 INFO - [#81] mashable.com/mashable.com/index.html Cycles:1 Average:447.00 Median:447.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 447.0 07:01:16 INFO - [#82] etsy.com/www.etsy.com/category/geekery/videogame.html Cycles:1 Average:179.00 Median:179.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 179.0 07:01:16 INFO - [#83] gmx.net/www.gmx.net/index.html Cycles:1 Average:210.00 Median:210.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 210.0 07:01:16 INFO - [#84] csdn.net/csdn.net/index.html Cycles:1 Average:462.00 Median:462.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 462.0 07:01:16 INFO - [#85] xunlei.com/xunlei.com/index.html Cycles:1 Average:441.00 Median:441.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 441.0 07:01:16 INFO - [#86] hatena.ne.jp/www.hatena.ne.jp/index.html Cycles:1 Average:704.00 Median:704.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 704.0 07:01:16 INFO - [#87] icious.com/www.delicious.com/index.html Cycles:1 Average:211.00 Median:211.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 211.0 07:01:16 INFO - [#88] repubblica.it/www.repubblica.it/index.html Cycles:1 Average:386.00 Median:386.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 386.0 07:01:16 INFO - [#89] web.de/web.de/index.html Cycles:1 Average:199.00 Median:199.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 199.0 07:01:16 INFO - [#90] slideshare.net/www.slideshare.net/jameswillamor/lolcats-in-popular-culture-a-historical-perspective.html Cycles:1 Average:220.00 Median:220.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 220.0 07:01:16 INFO - [#91] telegraph.co.uk/www.telegraph.co.uk/index.html Cycles:1 Average:228.00 Median:228.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 228.0 07:01:16 INFO - [#92] seesaa.net/blog.seesaa.jp/index.html Cycles:1 Average:571.00 Median:571.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 571.0 07:01:16 INFO - [#93] wp.pl/www.wp.pl/index.html Cycles:1 Average:241.00 Median:241.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 241.0 07:01:16 INFO - [#94] aljazeera.net/aljazeera.net/portal.html Cycles:1 Average:471.00 Median:471.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 471.0 07:01:16 INFO - [#95] w3.org/www.w3.org/standards/webdesign/htmlcss.html Cycles:1 Average:200.00 Median:200.00 stddev:0.00 (0.0%) 07:01:16 INFO - Values: 200.0 07:01:17 INFO - [#96] homeway.com.cn/www.hexun.com/index.html Cycles:1 Average:486.00 MeINFO : Browser shutdown timed out after 5 seconds, terminating process. 07:01:17 INFO - INFO : Browser exited with error code: 0 07:01:17 INFO - The trace you have just captured "C:\slave\test-pgo\build\test.etl.kernel" may contain personally identifiable information, including but not necessarily limited to paths to files accessed, paths to registry accessed and process names. Exact information depends on the events that were logged. Please be aware of this when sharing out this trace with other people. 07:01:17 INFO - The trace you have just captured "C:\slave\test-pgo\build\test.etl.user" may contain personally identifiable information, including but not necessarily limited to paths to files accessed, paths to registry accessed and process names. Exact information depends on the events that were logged. Please be aware of this when sharing out this trace with other people. 07:01:41 INFO - Merged Etl: test.etl 07:02:59 INFO - c:/Program Files/Microsoft Windows Performance Toolkit/xperf: warning: applying restriction of access for trace processing 07:03:40 INFO - reading etl filename: test.etl 07:03:40 INFO - etlparser: in readfile: test.etl.csv 07:03:40 INFO - DEBUG : Terminating: 216 07:03:40 INFO - dian:486.00 stddev:0.00 (0.0%) 07:03:40 INFO - Values: 486.0 07:03:40 INFO - [#97] facebook.com/www.facebook.com/Google.html Cycles:1 Average:89.00 Median:89.00 stddev:0.00 (0.0%) 07:03:40 INFO - Values: 89.0 07:03:40 INFO - [#98] youtube.com/www.youtube.com/music.html Cycles:1 Average:296.00 Median:296.00 stddev:0.00 (0.0%) 07:03:40 INFO - Values: 296.0 07:03:40 INFO - [#99] people.com.cn/people.com.cn/index.html Cycles:1 Average:441.00 Median:441.00 stddev:0.00 (0.0%) 07:03:40 INFO - Values: 441.0 07:03:40 INFO - -------- Summary: end -------- 07:03:40 INFO - extending with xperf! 07:03:40 INFO - COUNTER: nonmain_normal_netio 07:03:40 INFO - [132692004.0] 07:03:40 INFO - COUNTER: mainthread_writebytes 07:03:40 INFO - [[32, 'C:\\ProgramData\\NVIDIA Corporation\\Drs\\nvAppTimestamps'], [806, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\compatibility.ini']] 07:03:40 INFO - COUNTER: main_startup_fileio 07:03:40 INFO - [47463138.0] 07:03:40 INFO - COUNTER: main_normal_netio 07:03:40 INFO - [87740.0] 07:03:40 INFO - COUNTER: nonmain_startup_fileio 07:03:40 INFO - [809170.0] 07:03:40 INFO - COUNTER: main_startup_netio 07:03:40 INFO - [11.0] 07:03:40 INFO - COUNTER: nonmain_normal_fileio 07:03:40 INFO - [517868222.0] 07:03:40 INFO - COUNTER: main_normal_fileio 07:03:40 INFO - [29364811.0] 07:03:40 INFO - COUNTER: mainthread_readcount 07:03:48 INFO - [[42, 'C:\\ProgramData\\NVIDIA Corporation\\Drs\\nvAppTimestamps'], [4, 'C:\\slave\\test-pgo\\build\\application\\firefox\\defaults\\pref\\channel-prefs.js'], [8, 'C:\\slave\\test-pgo\\build\\talos_repo\\talos\\page_load_test\\tp5n\\tp5n.manifest'], [4, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\key3.db'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions.ini'], [2, 'C:\\ProgramData\\NVIDIA Corporation\\Drs\\nvdrssel.bin'], [4, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\prefs.js'], [6, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\secmod.db'], [2, 'C:\\Users\\cltbld\\AppData\\Roaming\\Mozilla\\firefox\\Crash Reports\\InstallTime20151029024142'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\pluginreg.dat'], [2, 'C:\\slave\\test-pgo\\build\\application\\firefox\\browser\\chrome.manifest'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\places.sqlite-shm'], [8, 'C:\\slave\\test-pgo\\build\\application\\firefox\\sandboxbroker.dll'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\compatibility.ini'], [6, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\content-prefs.sqlite'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions\\talos-powers@mozilla.org\\chrome.manifest'], [2, 'C:\\slave\\test-pgo\\build\\application\\firefox\\browser\\components\\components.manifest'], [14, 'C:\\slave\\test-pgo\\build\\application\\firefox\\msvcp120.dll'], [4, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\xulstore.json'], [14, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\permissions.sqlite'], [2, 'C:\\windows\\Prefetch\\FIREFOX.EXE-E5FC76EE.pf'], [2, 'C:\\Program Files\\desktop.ini'], [8, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\places.sqlite'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions\\pageloader@mozilla.org\\chrome.manifest'], [2, 'C:\\Users\\cltbld\\Desktop\\desktop.ini'], [2, 'C:\\windows\\system32\\spool\\drivers\\color\\sRGB Color Space Profile.icm'], [74, 'C:\\slave\\test-pgo\\build\\application\\firefox\\omni.ja'], [2, 'C:\\Users\\desktop.ini'], [2, 'C:\\windows\\Fonts\\StaticCache.dat'], [4, 'C:\\slave\\test-pgo\\build\\application\\firefox\\dependentlibs.list'], [1208, 'C:\\slave\\test-pgo\\build\\application\\firefox\\xul.dll'], [46, 'C:\\slave\\test-pgo\\build\\application\\firefox\\browser\\omni.ja'], [4, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\user.js'], [30, 'C:\\slave\\test-pgo\\build\\application\\firefox\\msvcr120.dll'], [2, 'C:\\slave\\test-pgo\\build\\application\\firefox\\lgpllibs.dll'], [4, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\cert8.db'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions\\talos-powers@mozilla.org\\chrome\\talos-powers-content.js'], [4, 'C:\\slave\\test-pgo\\build\\application\\firefox\\mozglue.dll'], [54, 'C:\\slave\\test-pgo\\build\\application\\firefox\\nss3.dll'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\mimeTypes.rdf']] 07:03:48 INFO - COUNTER: mainthread_writecount 07:03:48 INFO - [[4, 'C:\\ProgramData\\NVIDIA Corporation\\Drs\\nvAppTimestamps'], [34, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\compatibility.ini']] 07:03:48 INFO - COUNTER: mainthread_readbytes 07:03:48 INFO - [[2360, 'C:\\ProgramData\\NVIDIA Corporation\\Drs\\nvAppTimestamps'], [1000, 'C:\\slave\\test-pgo\\build\\application\\firefox\\defaults\\pref\\channel-prefs.js'], [32768, 'C:\\slave\\test-pgo\\build\\talos_repo\\talos\\page_load_test\\tp5n\\tp5n.manifest'], [8712, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\key3.db'], [8192, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions.ini'], [2, 'C:\\ProgramData\\NVIDIA Corporation\\Drs\\nvdrssel.bin'], [42120, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\prefs.js'], [16904, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\secmod.db'], [20, 'C:\\Users\\cltbld\\AppData\\Roaming\\Mozilla\\firefox\\Crash Reports\\InstallTime20151029024142'], [1344, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\pluginreg.dat'], [80, 'C:\\slave\\test-pgo\\build\\application\\firefox\\browser\\chrome.manifest'], [65536, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\places.sqlite-shm'], [524288, 'C:\\slave\\test-pgo\\build\\application\\firefox\\sandboxbroker.dll'], [8192, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\compatibility.ini'], [65768, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\content-prefs.sqlite'], [584, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions\\talos-powers@mozilla.org\\chrome.manifest'], [68, 'C:\\slave\\test-pgo\\build\\application\\firefox\\browser\\components\\components.manifest'], [917504, 'C:\\slave\\test-pgo\\build\\application\\firefox\\msvcp120.dll'], [702, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\xulstore.json'], [4424, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\permissions.sqlite'], [1312, 'C:\\windows\\Prefetch\\FIREFOX.EXE-E5FC76EE.pf'], [352, 'C:\\Program Files\\desktop.ini'], [196808, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\places.sqlite'], [600, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions\\pageloader@mozilla.org\\chrome.manifest'], [568, 'C:\\Users\\cltbld\\Desktop\\desktop.ini'], [8192, 'C:\\windows\\system32\\spool\\drivers\\color\\sRGB Color Space Profile.icm'], [4849664, 'C:\\slave\\test-pgo\\build\\application\\firefox\\omni.ja'], [352, 'C:\\Users\\desktop.ini'], [120, 'C:\\windows\\Fonts\\StaticCache.dat'], [16384, 'C:\\slave\\test-pgo\\build\\application\\firefox\\dependentlibs.list'], [79167488, 'C:\\slave\\test-pgo\\build\\application\\firefox\\xul.dll'], [3014656, 'C:\\slave\\test-pgo\\build\\application\\firefox\\browser\\omni.ja'], [26688, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\user.js'], [1966080, 'C:\\slave\\test-pgo\\build\\application\\firefox\\msvcr120.dll'], [131072, 'C:\\slave\\test-pgo\\build\\application\\firefox\\lgpllibs.dll'], [33288, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\cert8.db'], [1966, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions\\talos-powers@mozilla.org\\chrome\\talos-powers-content.js'], [262144, 'C:\\slave\\test-pgo\\build\\application\\firefox\\mozglue.dll'], [3538944, 'C:\\slave\\test-pgo\\build\\application\\firefox\\nss3.dll'], [8192, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\mimeTypes.rdf']] 07:03:48 INFO - Completed test tp5n: 07:03:48 INFO - Stopped Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - cycle time: 00:04:09 07:03:48 INFO - T-W732-IX-100: 07:03:48 INFO - Stopped Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - INFO : Outputting talos results => {'results_urls': ['http://graphs.mozilla.org/server/collect.cgi'], 'datazilla_urls': ['local.json']} 07:03:48 INFO - DEBUG : Working with test: tp5n 07:03:48 INFO - Generating results file: tp5n: 07:03:48 INFO - Started Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Generating results file: tp5n_nonmain_normal_netio_paint: 07:03:48 INFO - Started Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Generating results file: tp5n_nonmain_normal_netio_paint: 07:03:48 INFO - Stopped Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Not uploading counter data for tp5n_mainthread_writebytes 07:03:48 INFO - [[32, 'C:\\ProgramData\\NVIDIA Corporation\\Drs\\nvAppTimestamps'], [806, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\compatibility.ini']] 07:03:48 INFO - Generating results file: tp5n_main_startup_fileio_paint: 07:03:48 INFO - Started Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Generating results file: tp5n_main_startup_fileio_paint: 07:03:48 INFO - Stopped Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Generating results file: tp5n_main_normal_netio_paint: 07:03:48 INFO - Started Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Generating results file: tp5n_main_normal_netio_paint: 07:03:48 INFO - Stopped Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Generating results file: tp5n_nonmain_startup_fileio_paint: 07:03:48 INFO - Started Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Generating results file: tp5n_nonmain_startup_fileio_paint: 07:03:48 INFO - Stopped Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Generating results file: tp5n_main_startup_netio_paint: 07:03:48 INFO - Started Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Generating results file: tp5n_main_startup_netio_paint: 07:03:48 INFO - Stopped Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Generating results file: tp5n_nonmain_normal_fileio_paint: 07:03:48 INFO - Started Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Generating results file: tp5n_nonmain_normal_fileio_paint: 07:03:48 INFO - Stopped Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Generating results file: tp5n_main_normal_fileio_paint: 07:03:48 INFO - Started Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Generating results file: tp5n_main_normal_fileio_paint: 07:03:48 INFO - Stopped Thu, 29 Oct 2015 07:03:48 07:03:48 INFO - Not uploading counter data for tp5n_mainthread_readcount 07:03:48 INFO - [[42, 'C:\\ProgramData\\NVIDIA Corporation\\Drs\\nvAppTimestamps'], [4, 'C:\\slave\\test-pgo\\build\\application\\firefox\\defaults\\pref\\channel-prefs.js'], [8, 'C:\\slave\\test-pgo\\build\\talos_repo\\talos\\page_load_test\\tp5n\\tp5n.manifest'], [4, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\key3.db'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions.ini'], [2, 'C:\\ProgramData\\NVIDIA Corporation\\Drs\\nvdrssel.bin'], [4, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\prefs.js'], [6, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\secmod.db'], [2, 'C:\\Users\\cltbld\\AppData\\Roaming\\Mozilla\\firefox\\Crash Reports\\InstallTime20151029024142'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\pluginreg.dat'], [2, 'C:\\slave\\test-pgo\\build\\application\\firefox\\browser\\chrome.manifest'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\places.sqlite-shm'], [8, 'C:\\slave\\test-pgo\\build\\application\\firefox\\sandboxbroker.dll'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\compatibility.ini'], [6, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\content-prefs.sqlite'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions\\talos-powers@mozilla.org\\chrome.manifest'], [2, 'C:\\slave\\test-pgo\\build\\application\\firefox\\browser\\components\\components.manifest'], [14, 'C:\\slave\\test-pgo\\build\\application\\firefox\\msvcp120.dll'], [4, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\xulstore.json'], [14, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\permissions.sqlite'], [2, 'C:\\windows\\Prefetch\\FIREFOX.EXE-E5FC76EE.pf'], [2, 'C:\\Program Files\\desktop.ini'], [8, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\places.sqlite'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions\\pageloader@mozilla.org\\chrome.manifest'], [2, 'C:\\Users\\cltbld\\Desktop\\desktop.ini'], [2, 'C:\\windows\\system32\\spool\\drivers\\color\\sRGB Color Space Profile.icm'], [74, 'C:\\slave\\test-pgo\\build\\application\\firefox\\omni.ja'], [2, 'C:\\Users\\desktop.ini'], [2, 'C:\\windows\\Fonts\\StaticCache.dat'], [4, 'C:\\slave\\test-pgo\\build\\application\\firefox\\dependentlibs.list'], [1208, 'C:\\slave\\test-pgo\\build\\application\\firefox\\xul.dll'], [46, 'C:\\slave\\test-pgo\\build\\application\\firefox\\browser\\omni.ja'], [4, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\user.js'], [30, 'C:\\slave\\test-pgo\\build\\application\\firefox\\msvcr120.dll'], [2, 'C:\\slave\\test-pgo\\build\\application\\firefox\\lgpllibs.dll'], [4, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\cert8.db'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions\\talos-powers@mozilla.org\\chrome\\talos-powers-content.js'], [4, 'C:\\slave\\test-pgo\\build\\application\\firefox\\mozglue.dll'], [54, 'C:\\slave\\test-pgo\\build\\application\\firefox\\nss3.dll'], [2, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\mimeTypes.rdf']] 07:03:48 INFO - Not uploading counter data for tp5n_mainthread_writecount 07:03:48 INFO - [[4, 'C:\\ProgramData\\NVIDIA Corporation\\Drs\\nvAppTimestamps'], [34, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\compatibility.ini']] 07:03:48 INFO - Not uploading counter data for tp5n_mainthread_readbytes 07:03:48 INFO - [[2360, 'C:\\ProgramData\\NVIDIA Corporation\\Drs\\nvAppTimestamps'], [1000, 'C:\\slave\\test-pgo\\build\\application\\firefox\\defaults\\pref\\channel-prefs.js'], [32768, 'C:\\slave\\test-pgo\\build\\talos_repo\\talos\\page_load_test\\tp5n\\tp5n.manifest'], [8712, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\key3.db'], [8192, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions.ini'], [2, 'C:\\ProgramData\\NVIDIA Corporation\\Drs\\nvdrssel.bin'], [42120, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\prefs.js'], [16904, 'C:\\Users\\cltbld\\AppData\\LocINFO : Posting result 0 of 7 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:03:48 INFO - DEBUG : process_Request line: tp5n_nonmain_normal_netio_paint graph.html#tests=[[251,53,25]] 07:03:48 INFO - DEBUG : process_Request line: tp5n_nonmain_normal_netio_paint 132692000.00 graph.html#tests=[[251,53,25]] 07:03:48 INFO - INFO : Posting result 1 of 7 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:03:48 INFO - DEBUG : process_Request line: tp5n_main_startup_fileio_paint graph.html#tests=[[242,53,25]] 07:03:48 INFO - DEBUG : process_Request line: tp5n_main_startup_fileio_paint 47463136.00 graph.html#tests=[[242,53,25]] 07:03:48 INFO - INFO : Posting result 2 of 7 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:03:48 INFO - DEBUG : process_Request line: tp5n_main_normal_netio_paint graph.html#tests=[[245,53,25]] 07:03:48 INFO - DEBUG : process_Request line: tp5n_main_normal_netio_paint 87740.00 graph.html#tests=[[245,53,25]] 07:03:48 INFO - INFO : Posting result 3 of 7 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:03:48 INFO - DEBUG : process_Request line: tp5n_nonmain_startup_fileio_paint graph.html#tests=[[248,53,25]] 07:03:48 INFO - DEBUG : process_Request line: tp5n_nonmain_startup_fileio_paint 809170.00 graph.html#tests=[[248,53,25]] 07:03:48 INFO - INFO : Posting result 4 of 7 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:03:48 INFO - DEBUG : process_Request line: tp5n_main_startup_netio_paint graph.html#tests=[[243,53,25]] 07:03:48 INFO - DEBUG : process_Request line: tp5n_main_startup_netio_paint 11.00 graph.html#tests=[[243,53,25]] 07:03:48 INFO - INFO : Posting result 5 of 7 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:03:48 INFO - DEBUG : process_Request line: tp5n_nonmain_normal_fileio_paint graph.html#tests=[[250,53,25]] 07:03:48 INFO - DEBUG : process_Request line: tp5n_nonmain_normal_fileio_paint 517868224.00 graph.html#tests=[[250,53,25]] 07:03:48 INFO - INFO : Posting result 6 of 7 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 07:03:48 INFO - DEBUG : process_Request line: tp5n_main_normal_fileio_paint graph.html#tests=[[244,53,25]] 07:03:48 INFO - DEBUG : process_Request line: tp5n_main_normal_fileio_paint 29364812.00 graph.html#tests=[[244,53,25]] 07:03:48 INFO - INFO : TALOSDATA: [{"test_machine": {"platform": "x86", "osversion": "6.1.7601", "os": "win", "name": "T-W732-IX-100"}, "test_build": {"name": "Firefox", "version": "42.0", "id": "20151029024142", "branch": "Mozilla-Beta", "revision": "0ec8472a93ac"}, "testrun": {"date": 1446127179, "suite": "tp5n", "options": {"responsiveness": false, "tpmozafterpaint": true, "tpchrome": true, "tppagecycles": 1, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "results": {}, "talos_counters": {"nonmain_normal_netio": {"mean": 132692004.0}, "main_startup_fileio": {"mean": 47463138.0}, "main_normal_netio": {"mean": 87740.0}, "main_startup_netio": {"mean": 11.0}, "nonmain_startup_fileio": {"mean": 809170.0}, "nonmain_normal_fileio": {"mean": 517868222.0}, "main_normal_fileio": {"mean": 29364811.0}}}] 07:03:48 INFO - al\\Temp\\tmppjwijl\\profile\\secmod.db'], [20, 'C:\\Users\\cltbld\\AppData\\Roaming\\Mozilla\\firefox\\Crash Reports\\InstallTime20151029024142'], [1344, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\pluginreg.dat'], [80, 'C:\\slave\\test-pgo\\build\\application\\firefox\\browser\\chrome.manifest'], [65536, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\places.sqlite-shm'], [524288, 'C:\\slave\\test-pgo\\build\\application\\firefox\\sandboxbroker.dll'], [8192, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\compatibility.ini'], [65768, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\content-prefs.sqlite'], [584, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions\\talos-powers@mozilla.org\\chrome.manifest'], [68, 'C:\\slave\\test-pgo\\build\\application\\firefox\\browser\\components\\components.manifest'], [917504, 'C:\\slave\\test-pgo\\build\\application\\firefox\\msvcp120.dll'], [702, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\xulstore.json'], [4424, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\permissions.sqlite'], [1312, 'C:\\windows\\Prefetch\\FIREFOX.EXE-E5FC76EE.pf'], [352, 'C:\\Program Files\\desktop.ini'], [196808, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\places.sqlite'], [600, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions\\pageloader@mozilla.org\\chrome.manifest'], [568, 'C:\\Users\\cltbld\\Desktop\\desktop.ini'], [8192, 'C:\\windows\\system32\\spool\\drivers\\color\\sRGB Color Space Profile.icm'], [4849664, 'C:\\slave\\test-pgo\\build\\application\\firefox\\omni.ja'], [352, 'C:\\Users\\desktop.ini'], [120, 'C:\\windows\\Fonts\\StaticCache.dat'], [16384, 'C:\\slave\\test-pgo\\build\\application\\firefox\\dependentlibs.list'], [79167488, 'C:\\slave\\test-pgo\\build\\application\\firefox\\xul.dll'], [3014656, 'C:\\slave\\test-pgo\\build\\application\\firefox\\browser\\omni.ja'], [26688, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\user.js'], [1966080, 'C:\\slave\\test-pgo\\build\\application\\firefox\\msvcr120.dll'], [131072, 'C:\\slave\\test-pgo\\build\\application\\firefox\\lgpllibs.dll'], [33288, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\cert8.db'], [1966, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\extensions\\talos-powers@mozilla.org\\chrome\\talos-powers-content.js'], [262144, 'C:\\slave\\test-pgo\\build\\application\\firefox\\mozglue.dll'], [3538944, 'C:\\slave\\test-pgo\\build\\application\\firefox\\nss3.dll'], [8192, 'C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjwijl\\profile\\mimeTypes.rdf']] 07:03:48 INFO - RETURN: tp5n_nonmain_normal_netio_paint: 132692000.00 07:03:48 INFO - RETURN: tp5n_main_startup_fileio_paint: 47463136.00 07:03:48 INFO - RETURN: tp5n_main_normal_netio_paint: 87740.00 07:03:48 INFO - RETURN: tp5n_nonmain_startup_fileio_paint: 809170.00 07:03:48 INFO - RETURN: tp5n_main_startup_netio_paint: 11.00 07:03:48 INFO - RETURN: tp5n_nonmain_normal_fileio_paint: 517868224.00 07:03:48 INFO - RETURN: tp5n_main_normal_fileio_paint: 29364812.00 07:03:48 INFO - TinderboxPrint: TalosResult: {"graphserver": {"tp5n_main_startup_fileio_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[242,53,25]]", "result": "47463136.00"}, "tp5n_nonmain_normal_netio_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[251,53,25]]", "result": "132692000.00"}, "tp5n_nonmain_startup_fileio_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[248,53,25]]", "result": "809170.00"}, "tp5n_nonmain_normal_fileio_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[250,53,25]]", "result": "517868224.00"}, "tp5n_main_normal_fileio_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[244,53,25]]", "result": "29364812.00"}, "tp5n_main_startup_netio_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[243,53,25]]", "result": "11.00"}, "tp5n_main_normal_netio_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[245,53,25]]", "result": "87740.00"}}} 07:03:48 INFO - Return code: 0 07:03:48 INFO - # TBPL SUCCESS # 07:03:48 INFO - Running post-action listener: _resource_record_post_action 07:03:48 INFO - Running post-run listener: _resource_record_post_run 07:03:49 INFO - Total resource usage - Wall time: 252s; CPU: 10.0%; Read bytes: 713511424; Write bytes: 2200007680; Read time: 6445490; Write time: 11108470 07:03:49 INFO - install - Wall time: 1s; CPU: 13.0%; Read bytes: 0; Write bytes: 3686400; Read time: 0; Write time: 3340 07:03:49 INFO - run-tests - Wall time: 252s; CPU: 10.0%; Read bytes: 713305088; Write bytes: 2171894784; Read time: 6409830; Write time: 10958620 07:03:49 INFO - Running post-run listener: _upload_blobber_files 07:03:49 INFO - Blob upload gear active. 07:03:49 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 07:03:49 INFO - Copying logs to upload dir... 07:03:49 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=657.712000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 12 mins, 42 secs) (at 2015-10-29 07:05:34.477469) ========= ========= Started set props: build_url (results: 0, elapsed: 15 secs) (at 2015-10-29 07:05:34.481407) ========= 'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f' in dir C:\slave\test-pgo\properties (timeout 1200 secs) watching logfiles {} argv: ['cmd', '/C', 'for', '%f', 'in', '(*)', 'do', '@type', '%f'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-100 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-100 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo\properties RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-100 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.zip program finished with exit code 0 elapsedTime=0.121000 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-win32/1446111702/firefox-42.0.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 15 secs) (at 2015-10-29 07:05:50.252021) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 18 secs) (at 2015-10-29 07:05:50.252345) ========= 'rm' '-f' 'oauth.txt' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-100 COMSPEC=C:\windows\system32\cmd.exe CYGWINBASE=C:\cygwin DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld\AppData\Local LOGONSERVER=\\T-W732-IX-100 MONDIR=C:\Monitor_config\ MOZBUILDDIR=C:\mozilla-build\ MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 NUMBER_OF_PROCESSORS=8 OS=Windows_NT OURDRIVE=C: PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin; PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\cltbld\AppData\Local\Temp TEST1=testie TMP=C:\Users\cltbld\AppData\Local\Temp USERDOMAIN=T-W732-IX-100 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.401000 ========= Finished 'rm -f ...' (results: 0, elapsed: 18 secs) (at 2015-10-29 07:06:08.830110) ========= ========= Started reboot slave lost (results: 0, elapsed: 2 mins, 5 secs) (at 2015-10-29 07:06:08.830406) ========= ========= Finished reboot slave lost (results: 0, elapsed: 2 mins, 5 secs) (at 2015-10-29 07:08:14.473471) =========