builder: mozilla-aurora_win7-ix_test-svgr-pgo slave: t-w732-ix-142 starttime: 1456956330.7 results: success (0) buildid: 20160302112221 builduid: 2c7d3b82b3e347bbae1964ddbfb06844 revision: dc8bba6da2ed7bb8d860952438f001a13564de87 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-02 14:05:30.704178) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-02 14:05:30.704601) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-02 14:05:30.704903) ========= '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-142 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-142 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-142 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.619000 basedir: 'C:\\slave\\test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-02 14:05:31.368014) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-02 14:05:31.368397) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-02 14:05:31.567110) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-03-02 14:05:31.567736) ========= '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-142 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-142 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-142 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=1.001000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-03-02 14:05:32.586447) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-02 14:05:32.586782) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-02 14:05:32.587183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-02 14:05:32.587465) ========= '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-142 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-142 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-142 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 --14:05:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 7.59 MB/s 14:05:33 (7.59 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.035000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-02 14:05:33.641586) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 16 secs) (at 2016-03-02 14:05:33.641932) ========= '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-142 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-142 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-142 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=16.501000 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 16 secs) (at 2016-03-02 14:05:50.168591) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-03-02 14:05:50.168966) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dc8bba6da2ed7bb8d860952438f001a13564de87 --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-aurora --rev dc8bba6da2ed7bb8d860952438f001a13564de87 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-142 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-142 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-142 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 2016-03-02 14:05:50,831 truncating revision to first 12 chars 2016-03-02 14:05:50,832 Setting DEBUG logging. 2016-03-02 14:05:50,832 attempt 1/10 2016-03-02 14:05:50,832 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dc8bba6da2ed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-02 14:05:51,312 unpacking tar archive at: mozilla-aurora-dc8bba6da2ed/testing/mozharness/ program finished with exit code 0 elapsedTime=1.276000 ========= master_lag: 2.79 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-03-02 14:05:54.235626) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-02 14:05:54.235982) ========= 'echo' u'dc8bba6da2ed7bb8d860952438f001a13564de87' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'dc8bba6da2ed7bb8d860952438f001a13564de87'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-142 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-142 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-142 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 dc8bba6da2ed7bb8d860952438f001a13564de87 program finished with exit code 0 elapsedTime=0.101000 script_repo_revision: 'dc8bba6da2ed7bb8d860952438f001a13564de87' ========= master_lag: 0.03 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-02 14:05:54.362022) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-02 14:05:54.362435) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-02 14:05:54.378999) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 44 secs) (at 2016-03-02 14:05:54.379409) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'svgr' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Aurora' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Aurora' 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', 'svgr', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-142 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-142 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-142 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 14:05:55 INFO - MultiFileLogger online at 20160302 14:05:55 in C:\slave\test-pgo 14:05:55 INFO - Run as scripts/scripts/talos_script.py --suite svgr --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 14:05:55 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 14:05:55 INFO - {'append_to_log': False, 14:05:55 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 14:05:55 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:05:55 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 14:05:55 INFO - 'branch': 'Mozilla-Aurora', 14:05:55 INFO - 'buildbot_json_path': 'buildprops.json', 14:05:55 INFO - 'config_files': ('talos/windows_config.py',), 14:05:55 INFO - 'default_actions': ('clobber', 14:05:55 INFO - 'read-buildbot-config', 14:05:55 INFO - 'download-and-extract', 14:05:55 INFO - 'populate-webroot', 14:05:55 INFO - 'create-virtualenv', 14:05:55 INFO - 'install', 14:05:55 INFO - 'run-tests'), 14:05:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:05:55 INFO - 'download_minidump_stackwalk': True, 14:05:55 INFO - 'download_symbols': 'ondemand', 14:05:55 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 14:05:55 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 14:05:55 INFO - 'hg': 'c:/mozilla-build/hg/hg', 14:05:55 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 14:05:55 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 14:05:55 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 14:05:55 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 14:05:55 INFO - 'C:/mozilla-build/tooltool.py'), 14:05:55 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 14:05:55 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 14:05:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:05:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:05:55 INFO - 'installer_path': 'installer.exe', 14:05:55 INFO - 'log_level': 'info', 14:05:55 INFO - 'log_name': 'talos', 14:05:55 INFO - 'log_to_console': True, 14:05:55 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 14:05:55 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 14:05:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 14:05:55 INFO - 'opt_config_files': (), 14:05:55 INFO - 'pip_index': False, 14:05:55 INFO - 'sps_profile': False, 14:05:55 INFO - 'sps_profile_interval': 0, 14:05:55 INFO - 'suite': 'svgr', 14:05:55 INFO - 'system_bits': '32', 14:05:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:05:55 INFO - 'title': 'T-W732-IX-142', 14:05:55 INFO - 'use_talos_json': True, 14:05:55 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 14:05:55 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 14:05:55 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 14:05:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:05:55 INFO - 'work_dir': 'build'} 14:05:55 INFO - ##### 14:05:55 INFO - ##### Running clobber step. 14:05:55 INFO - ##### 14:05:55 INFO - Running pre-action listener: _resource_record_pre_action 14:05:55 INFO - Running main action method: clobber 14:05:55 INFO - rmtree: C:\slave\test-pgo\build 14:05:55 INFO - Using _rmtree_windows ... 14:05:55 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 14:07:30 INFO - Running post-action listener: _resource_record_post_action 14:07:30 INFO - ##### 14:07:30 INFO - ##### Running read-buildbot-config step. 14:07:30 INFO - ##### 14:07:30 INFO - Running pre-action listener: _resource_record_pre_action 14:07:30 INFO - Running main action method: read_buildbot_config 14:07:30 INFO - Using buildbot properties: 14:07:30 INFO - { 14:07:30 INFO - "project": "", 14:07:30 INFO - "product": "firefox", 14:07:30 INFO - "script_repo_revision": "production", 14:07:30 INFO - "builddir": "mozilla-aurora_win7-ix_test-svgr", 14:07:30 INFO - "repository": "", 14:07:30 INFO - "buildername": "Windows 7 32-bit mozilla-aurora pgo talos svgr", 14:07:30 INFO - "buildid": "20160302112221", 14:07:30 INFO - "pgo_build": "True", 14:07:30 INFO - "basedir": "C:\\slave\\test-pgo", 14:07:30 INFO - "buildnumber": 11, 14:07:30 INFO - "slavename": "t-w732-ix-142", 14:07:30 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 14:07:30 INFO - "platform": "win7-ix", 14:07:30 INFO - "scheduler": "tests-mozilla-aurora-win32-pgo-talos", 14:07:30 INFO - "branch": "mozilla-aurora", 14:07:30 INFO - "repo_path": "releases/mozilla-aurora", 14:07:30 INFO - "revision": "dc8bba6da2ed7bb8d860952438f001a13564de87", 14:07:30 INFO - "stage_platform": "win32-pgo", 14:07:30 INFO - "builduid": "2c7d3b82b3e347bbae1964ddbfb06844", 14:07:30 INFO - "slavebuilddir": "test" 14:07:30 INFO - } 14:07:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.zip. 14:07:30 INFO - Running post-action listener: _resource_record_post_action 14:07:30 INFO - ##### 14:07:30 INFO - ##### Running download-and-extract step. 14:07:30 INFO - ##### 14:07:30 INFO - Running pre-action listener: _resource_record_pre_action 14:07:30 INFO - Running main action method: download_and_extract 14:07:30 INFO - mkdir: C:\slave\test-pgo\build\tests 14:07: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')]} 14:07:30 INFO - https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 14:07:30 INFO - trying https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/test_packages.json 14:07:30 INFO - Downloading https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 14:07:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 14:07:31 INFO - Downloaded 1301 bytes. 14:07:31 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 14:07:31 INFO - Using the following test package requirements: 14:07:31 INFO - {u'common': [u'firefox-46.0a2.en-US.win32.common.tests.zip'], 14:07:31 INFO - u'cppunittest': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 14:07:31 INFO - u'firefox-46.0a2.en-US.win32.cppunittest.tests.zip'], 14:07:31 INFO - u'gtest': [u'firefox-46.0a2.en-US.win32.common.tests.zip'], 14:07:31 INFO - u'jittest': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 14:07:31 INFO - u'jsshell-win32.zip'], 14:07:31 INFO - u'mochitest': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 14:07:31 INFO - u'firefox-46.0a2.en-US.win32.mochitest.tests.zip'], 14:07:31 INFO - u'mozbase': [u'firefox-46.0a2.en-US.win32.common.tests.zip'], 14:07:31 INFO - u'reftest': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 14:07:31 INFO - u'firefox-46.0a2.en-US.win32.reftest.tests.zip'], 14:07:31 INFO - u'talos': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 14:07:31 INFO - u'firefox-46.0a2.en-US.win32.talos.tests.zip'], 14:07:31 INFO - u'web-platform': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 14:07:31 INFO - u'firefox-46.0a2.en-US.win32.web-platform.tests.zip'], 14:07:31 INFO - u'webapprt': [u'firefox-46.0a2.en-US.win32.common.tests.zip'], 14:07:31 INFO - u'xpcshell': [u'firefox-46.0a2.en-US.win32.common.tests.zip', 14:07:31 INFO - u'firefox-46.0a2.en-US.win32.xpcshell.tests.zip']} 14:07:31 INFO - Downloading packages: [u'firefox-46.0a2.en-US.win32.common.tests.zip'] for test suite category: common 14:07: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')]} 14:07:31 INFO - https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 14:07:31 INFO - trying https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip 14:07:31 INFO - Downloading https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.common.tests.zip 14:07:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.common.tests.zip'}, attempt #1 14:07:33 INFO - Downloaded 17895443 bytes. 14:07:33 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.common.tests.zip', '-d', 'C:\\slave\\test-pgo\\build\\tests'] 14:07:33 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.common.tests.zip -d C:\slave\test-pgo\build\tests 14:08:06 INFO - Return code: 0 14:08:06 INFO - Downloading packages: [u'firefox-46.0a2.en-US.win32.common.tests.zip', u'firefox-46.0a2.en-US.win32.talos.tests.zip'] for test suite category: talos 14:08:06 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')]} 14:08:06 INFO - https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 14:08:06 INFO - trying https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip 14:08:06 INFO - Downloading https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.common.tests.zip 14:08:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.common.tests.zip'}, attempt #1 14:08:07 INFO - Downloaded 17895443 bytes. 14:08:07 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.common.tests.zip', '-d', 'C:\\slave\\test-pgo\\build\\tests'] 14:08:07 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.common.tests.zip -d C:\slave\test-pgo\build\tests 14:08:31 INFO - Return code: 0 14:08: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')]} 14:08:31 INFO - https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 14:08:31 INFO - trying https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.talos.tests.zip 14:08:31 INFO - Downloading https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.talos.tests.zip to C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.talos.tests.zip 14:08:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.talos.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.talos.tests.zip'}, attempt #1 14:08:32 INFO - Downloaded 10706649 bytes. 14:08:32 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.talos.tests.zip', '-d', 'C:\\slave\\test-pgo\\build\\tests'] 14:08:32 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.talos.tests.zip -d C:\slave\test-pgo\build\tests 14:08:36 INFO - Return code: 0 14:08:36 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')]} 14:08:36 INFO - https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.zip matches https://queue.taskcluster.net 14:08:36 INFO - trying https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.zip 14:08:36 INFO - Downloading https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.zip to C:\slave\test-pgo\build\firefox-46.0a2.en-US.win32.zip 14:08:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.zip', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-46.0a2.en-US.win32.zip'}, attempt #1 14:08:40 INFO - Downloaded 55261059 bytes. 14:08:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.zip 14:08:40 INFO - mkdir: C:\slave\test-pgo\properties 14:08:40 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 14:08:40 INFO - Writing to file C:\slave\test-pgo\properties\build_url 14:08:40 INFO - Contents: 14:08:40 INFO - build_url:https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.zip 14:08:40 INFO - Running post-action listener: _resource_record_post_action 14:08:40 INFO - Running post-action listener: set_extra_try_arguments 14:08:40 INFO - ##### 14:08:40 INFO - ##### Running populate-webroot step. 14:08:40 INFO - ##### 14:08:40 INFO - Running pre-action listener: _resource_record_pre_action 14:08:40 INFO - Running main action method: populate_webroot 14:08:40 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 14:08:40 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 14:08:40 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 14:08:40 INFO - u'--tppagecycles', 14:08:40 INFO - u'7'], 14:08:40 INFO - u'tests': [u'tsvgm']}}, 14:08:40 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 14:08:40 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 14:08:40 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 14:08:40 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 14:08:40 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 14:08:40 INFO - u'tests': [u'dromaeo_css', 14:08:40 INFO - u'kraken', 14:08:40 INFO - u'v8_7']}, 14:08:40 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:40 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 14:08:40 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:40 INFO - u'talos_options': [u'--e10s'], 14:08:40 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 14:08:40 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:40 INFO - u'tests': [u'damp', u'tps']}, 14:08:40 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:40 INFO - u'talos_options': [u'--e10s'], 14:08:40 INFO - u'tests': [u'damp', u'tps']}, 14:08:40 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 14:08:40 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 14:08:40 INFO - u'other': {u'tests': [u'a11yr', 14:08:40 INFO - u'ts_paint', 14:08:40 INFO - u'tpaint', 14:08:40 INFO - u'sessionrestore', 14:08:40 INFO - u'sessionrestore_no_auto_restore']}, 14:08:40 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 14:08:40 INFO - u'tests': [u'a11yr', 14:08:40 INFO - u'ts_paint', 14:08:40 INFO - u'tpaint', 14:08:40 INFO - u'sessionrestore', 14:08:40 INFO - u'sessionrestore_no_auto_restore']}, 14:08:40 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 14:08:40 INFO - u'tests': [u'a11yr', 14:08:40 INFO - u'ts_paint', 14:08:40 INFO - u'tpaint', 14:08:40 INFO - u'sessionrestore', 14:08:40 INFO - u'sessionrestore_no_auto_restore']}, 14:08:40 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 14:08:40 INFO - u'tests': [u'a11yr', 14:08:40 INFO - u'ts_paint', 14:08:40 INFO - u'tpaint', 14:08:40 INFO - u'sessionrestore', 14:08:40 INFO - u'sessionrestore_no_auto_restore']}, 14:08:40 INFO - u'other_l64': {u'tests': [u'a11yr', 14:08:40 INFO - u'ts_paint', 14:08:40 INFO - u'tpaint', 14:08:40 INFO - u'sessionrestore', 14:08:40 INFO - u'sessionrestore_no_auto_restore']}, 14:08:40 INFO - u'other_nol64': {u'tests': [u'a11yr', 14:08:40 INFO - u'ts_paint', 14:08:40 INFO - u'tpaint', 14:08:40 INFO - u'sessionrestore', 14:08:40 INFO - u'sessionrestore_no_auto_restore']}, 14:08:40 INFO - u'svgr': {u'tests': [u'tsvgx', 14:08:40 INFO - u'tsvgr_opacity', 14:08:40 INFO - u'tart', 14:08:40 INFO - u'tscrollx', 14:08:40 INFO - u'cart']}, 14:08:40 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 14:08:40 INFO - u'tests': [u'tsvgx', 14:08:40 INFO - u'tsvgr_opacity', 14:08:40 INFO - u'tart', 14:08:40 INFO - u'tscrollx', 14:08:40 INFO - u'cart']}, 14:08:40 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:40 INFO - u'tests': [u'tp5o']}, 14:08:40 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:40 INFO - u'talos_options': [u'--e10s'], 14:08:40 INFO - u'tests': [u'tp5o']}, 14:08:40 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:40 INFO - u'talos_options': [u'--xperf_path', 14:08:40 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:08:40 INFO - u'tests': [u'tp5n']}, 14:08:40 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:08:40 INFO - u'talos_options': [u'--e10s', 14:08:40 INFO - u'--xperf_path', 14:08:40 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:08:40 INFO - u'tests': [u'tp5n']}}, 14:08:40 INFO - u'talos.zip': {u'path': u'', 14:08:40 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:08:40 INFO - Running post-action listener: _resource_record_post_action 14:08:40 INFO - ##### 14:08:40 INFO - ##### Running create-virtualenv step. 14:08:40 INFO - ##### 14:08:40 INFO - Running pre-action listener: _resource_record_pre_action 14:08:40 INFO - Running main action method: create_virtualenv 14:08:40 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 14:08:40 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 14:08:40 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 14:08:40 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'! 14:08:40 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 14:08:40 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 14:08:48 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 14:08:52 INFO - Installing distribute......................................................................................................................................................................................done. 14:08:53 INFO - Return code: 0 14:08:53 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 14:08: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')]} 14:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:08: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')]} 14:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:08:53 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268BF0>, '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 0x0128A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F625C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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 14:08:53 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test-pgo\build 14:08:53 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:08:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:08:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:08:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:08:53 INFO - 'COMPUTERNAME': 'T-W732-IX-142', 14:08:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:08:53 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:08:53 INFO - 'DCLOCATION': 'SCL3', 14:08:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:08:53 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:08:53 INFO - 'HOMEDRIVE': 'C:', 14:08:53 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:08:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:08:53 INFO - 'KTS_VERSION': '1.19c', 14:08:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:08:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142', 14:08:53 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:08:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:08:53 INFO - 'MOZ_NO_REMOTE': '1', 14:08:53 INFO - 'NO_EM_RESTART': '1', 14:08:53 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:08:53 INFO - 'OS': 'Windows_NT', 14:08:53 INFO - 'OURDRIVE': 'C:', 14:08:53 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;', 14:08:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:08:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:08:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:08:53 INFO - 'PROCESSOR_LEVEL': '6', 14:08:53 INFO - 'PROCESSOR_REVISION': '1e05', 14:08:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:08:53 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:08:53 INFO - 'PROMPT': '$P$G', 14:08:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 14:08:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:08:53 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:08:53 INFO - 'PWD': 'C:\\slave\\test-pgo', 14:08:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:08:53 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:08:53 INFO - 'SYSTEMDRIVE': 'C:', 14:08:53 INFO - 'SYSTEMROOT': 'C:\\windows', 14:08:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:08:53 INFO - 'TEST1': 'testie', 14:08:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:08:53 INFO - 'USERDOMAIN': 'T-W732-IX-142', 14:08:53 INFO - 'USERNAME': 'cltbld', 14:08:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:08:53 INFO - 'WINDIR': 'C:\\windows', 14:08:53 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:08:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:08:54 INFO - Ignoring indexes: http://pypi.python.org/simple/ 14:08:54 INFO - Downloading/unpacking pip>=1.5 14:08:58 INFO - Running setup.py egg_info for package pip 14:08:58 INFO - warning: no files found matching 'pip\cacert.pem' 14:08:58 INFO - warning: no files found matching '*.html' under directory 'docs' 14:08:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 14:08:58 INFO - no previously-included directories found matching 'docs\_build\_sources' 14:08:58 INFO - Installing collected packages: pip 14:08:58 INFO - Found existing installation: pip 0.8.2 14:08:58 INFO - Uninstalling pip: 14:08:58 INFO - Successfully uninstalled pip 14:08:58 INFO - Running setup.py install for pip 14:08:58 INFO - warning: no files found matching 'pip\cacert.pem' 14:08:58 INFO - warning: no files found matching '*.html' under directory 'docs' 14:08:58 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 14:08:58 INFO - no previously-included directories found matching 'docs\_build\_sources' 14:08:58 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:08:58 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 14:08:58 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:08:58 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 14:08:58 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:08:58 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 14:08:58 INFO - Successfully installed pip 14:08:58 INFO - Cleaning up... 14:08:58 INFO - Return code: 0 14:08:58 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 14:08:58 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')]} 14:08:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:08:58 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')]} 14:08:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:08:58 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268BF0>, '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 0x0128A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F625C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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 14:08:59 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 14:08:59 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --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 14:08:59 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:08:59 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:08:59 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:08:59 INFO - 'COMPUTERNAME': 'T-W732-IX-142', 14:08:59 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:08:59 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:08:59 INFO - 'DCLOCATION': 'SCL3', 14:08:59 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:08:59 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:08:59 INFO - 'HOMEDRIVE': 'C:', 14:08:59 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:08:59 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:08:59 INFO - 'KTS_VERSION': '1.19c', 14:08:59 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:08:59 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142', 14:08:59 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:08:59 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:08:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:08:59 INFO - 'MOZ_NO_REMOTE': '1', 14:08:59 INFO - 'NO_EM_RESTART': '1', 14:08:59 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:08:59 INFO - 'OS': 'Windows_NT', 14:08:59 INFO - 'OURDRIVE': 'C:', 14:08:59 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;', 14:08:59 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:08:59 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:08:59 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:08:59 INFO - 'PROCESSOR_LEVEL': '6', 14:08:59 INFO - 'PROCESSOR_REVISION': '1e05', 14:08:59 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:08:59 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:08:59 INFO - 'PROMPT': '$P$G', 14:08:59 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 14:08:59 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:08:59 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:08:59 INFO - 'PWD': 'C:\\slave\\test-pgo', 14:08:59 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:08:59 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:08:59 INFO - 'SYSTEMDRIVE': 'C:', 14:08:59 INFO - 'SYSTEMROOT': 'C:\\windows', 14:08:59 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:08:59 INFO - 'TEST1': 'testie', 14:08:59 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:08:59 INFO - 'USERDOMAIN': 'T-W732-IX-142', 14:08:59 INFO - 'USERNAME': 'cltbld', 14:08:59 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:08:59 INFO - 'WINDIR': 'C:\\windows', 14:08:59 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:08:59 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:08:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:01 INFO - Downloading/unpacking psutil>=0.7.1 14:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:02 INFO - Installing collected packages: psutil 14:09:02 INFO - Successfully installed psutil 14:09:02 INFO - Cleaning up... 14:09:02 INFO - Return code: 0 14:09:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 14:09:02 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')]} 14:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:02 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')]} 14:09:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:02 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268BF0>, '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 0x0128A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F625C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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 14:09:02 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 14:09:02 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --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 14:09:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:09:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:09:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:09:02 INFO - 'COMPUTERNAME': 'T-W732-IX-142', 14:09:02 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:09:02 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:09:02 INFO - 'DCLOCATION': 'SCL3', 14:09:02 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:09:02 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:09:02 INFO - 'HOMEDRIVE': 'C:', 14:09:02 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:09:02 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:09:02 INFO - 'KTS_VERSION': '1.19c', 14:09:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:09:02 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142', 14:09:02 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:09:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:09:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:02 INFO - 'MOZ_NO_REMOTE': '1', 14:09:02 INFO - 'NO_EM_RESTART': '1', 14:09:02 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:09:02 INFO - 'OS': 'Windows_NT', 14:09:02 INFO - 'OURDRIVE': 'C:', 14:09:02 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;', 14:09:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:09:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:09:02 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:09:02 INFO - 'PROCESSOR_LEVEL': '6', 14:09:02 INFO - 'PROCESSOR_REVISION': '1e05', 14:09:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:09:02 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:09:02 INFO - 'PROMPT': '$P$G', 14:09:02 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 14:09:02 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:09:02 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:09:02 INFO - 'PWD': 'C:\\slave\\test-pgo', 14:09:02 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:09:02 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:09:02 INFO - 'SYSTEMDRIVE': 'C:', 14:09:02 INFO - 'SYSTEMROOT': 'C:\\windows', 14:09:02 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:02 INFO - 'TEST1': 'testie', 14:09:02 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:02 INFO - 'USERDOMAIN': 'T-W732-IX-142', 14:09:02 INFO - 'USERNAME': 'cltbld', 14:09:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:09:02 INFO - 'WINDIR': 'C:\\windows', 14:09:02 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:09:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:09:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:09:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:02 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:09:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:09:03 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 14:09:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 14:09:03 INFO - Installing collected packages: mozsystemmonitor 14:09:03 INFO - Running setup.py install for mozsystemmonitor 14:09:03 INFO - Successfully installed mozsystemmonitor 14:09:03 INFO - Cleaning up... 14:09:04 INFO - Return code: 0 14:09:04 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 14:09:04 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')]} 14:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:04 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')]} 14:09:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:04 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268BF0>, '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 0x0128A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F625C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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 14:09:04 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--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 14:09:04 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --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 14:09:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:09:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:09:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:09:04 INFO - 'COMPUTERNAME': 'T-W732-IX-142', 14:09:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:09:04 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:09:04 INFO - 'DCLOCATION': 'SCL3', 14:09:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:09:04 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:09:04 INFO - 'HOMEDRIVE': 'C:', 14:09:04 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:09:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:09:04 INFO - 'KTS_VERSION': '1.19c', 14:09:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:09:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142', 14:09:04 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:09:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:09:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:04 INFO - 'MOZ_NO_REMOTE': '1', 14:09:04 INFO - 'NO_EM_RESTART': '1', 14:09:04 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:09:04 INFO - 'OS': 'Windows_NT', 14:09:04 INFO - 'OURDRIVE': 'C:', 14:09:04 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;', 14:09:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:09:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:09:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:09:04 INFO - 'PROCESSOR_LEVEL': '6', 14:09:04 INFO - 'PROCESSOR_REVISION': '1e05', 14:09:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:09:04 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:09:04 INFO - 'PROMPT': '$P$G', 14:09:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 14:09:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:09:04 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:09:04 INFO - 'PWD': 'C:\\slave\\test-pgo', 14:09:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:09:04 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:09:04 INFO - 'SYSTEMDRIVE': 'C:', 14:09:04 INFO - 'SYSTEMROOT': 'C:\\windows', 14:09:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:04 INFO - 'TEST1': 'testie', 14:09:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:04 INFO - 'USERDOMAIN': 'T-W732-IX-142', 14:09:04 INFO - 'USERNAME': 'cltbld', 14:09:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:09:04 INFO - 'WINDIR': 'C:\\windows', 14:09:04 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:09:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:09:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:09:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:04 INFO - Downloading/unpacking blobuploader==1.2.4 14:09:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:05 INFO - Downloading blobuploader-1.2.4.tar.gz 14:09:05 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 14:09:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:09:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:05 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 14:09:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:09:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:06 INFO - Downloading docopt-0.6.1.tar.gz 14:09:06 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 14:09:06 INFO - Installing collected packages: blobuploader, requests, docopt 14:09:06 INFO - Running setup.py install for blobuploader 14:09:06 INFO - Running setup.py install for requests 14:09:07 INFO - Running setup.py install for docopt 14:09:07 INFO - Successfully installed blobuploader requests docopt 14:09:07 INFO - Cleaning up... 14:09:08 INFO - Return code: 0 14:09:08 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 14:09: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')]} 14:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09: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')]} 14:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:08 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268BF0>, '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 0x0128A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F625C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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 14:09:08 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 14:09:08 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:09:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:09:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:09:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:09:08 INFO - 'COMPUTERNAME': 'T-W732-IX-142', 14:09:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:09:08 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:09:08 INFO - 'DCLOCATION': 'SCL3', 14:09:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:09:08 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:09:08 INFO - 'HOMEDRIVE': 'C:', 14:09:08 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:09:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:09:08 INFO - 'KTS_VERSION': '1.19c', 14:09:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:09:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142', 14:09:08 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:09:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:08 INFO - 'MOZ_NO_REMOTE': '1', 14:09:08 INFO - 'NO_EM_RESTART': '1', 14:09:08 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:09:08 INFO - 'OS': 'Windows_NT', 14:09:08 INFO - 'OURDRIVE': 'C:', 14:09:08 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;', 14:09:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:09:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:09:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:09:08 INFO - 'PROCESSOR_LEVEL': '6', 14:09:08 INFO - 'PROCESSOR_REVISION': '1e05', 14:09:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:09:08 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:09:08 INFO - 'PROMPT': '$P$G', 14:09:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 14:09:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:09:08 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:09:08 INFO - 'PWD': 'C:\\slave\\test-pgo', 14:09:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:09:08 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:09:08 INFO - 'SYSTEMDRIVE': 'C:', 14:09:08 INFO - 'SYSTEMROOT': 'C:\\windows', 14:09:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:08 INFO - 'TEST1': 'testie', 14:09:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:08 INFO - 'USERDOMAIN': 'T-W732-IX-142', 14:09:08 INFO - 'USERNAME': 'cltbld', 14:09:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:09:08 INFO - 'WINDIR': 'C:\\windows', 14:09:08 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:09:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:09:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 14:09:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-30itdb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 14:09:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 14:09:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v0dfc1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 14:09:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 14:09:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pcrk4x-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 14:09:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 14:09:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gpgaji-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 14:09:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 14:09:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qc49jk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 14:09:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 14:09:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4pt2p9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 14:09:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 14:09:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xts2vf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 14:09:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 14:09:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rpfkms-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 14:09:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 14:09:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g8hshn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 14:09:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 14:09:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a1g2mn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 14:09:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 14:09:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pzmorn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 14:09:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 14:09:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pqegty-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 14:09:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 14:09:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bbykex-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 14:09:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 14:09:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1ap2yg-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 14:09:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 14:09:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kwn6t1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:09:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 14:09:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_k8zf2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 14:09:11 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 14:09:11 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-itjsab-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 14:09:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:09:11 INFO - Running setup.py install for manifestparser 14:09:11 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:11 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:12 INFO - Running setup.py install for mozcrash 14:09:12 INFO - Running setup.py install for mozdebug 14:09:12 INFO - Running setup.py install for mozdevice 14:09:12 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:12 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:12 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:12 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:13 INFO - Running setup.py install for mozfile 14:09:13 INFO - Running setup.py install for mozhttpd 14:09:13 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:13 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:14 INFO - Running setup.py install for mozinfo 14:09:14 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:14 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:14 INFO - Running setup.py install for mozInstall 14:09:14 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:14 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:14 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:14 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:14 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:14 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:14 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:14 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:14 INFO - Running setup.py install for mozleak 14:09:15 INFO - Running setup.py install for mozlog 14:09:15 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:15 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:16 INFO - Running setup.py install for moznetwork 14:09:16 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:16 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:16 INFO - Running setup.py install for mozprocess 14:09:16 INFO - Running setup.py install for mozprofile 14:09:17 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:17 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:17 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:17 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:17 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:17 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:17 INFO - Running setup.py install for mozrunner 14:09:17 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:17 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:18 INFO - Running setup.py install for mozscreenshot 14:09:18 INFO - Running setup.py install for moztest 14:09:18 INFO - Running setup.py install for mozversion 14:09:19 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:19 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:09:19 INFO - Cleaning up... 14:09:19 INFO - Return code: 0 14:09:19 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 14:09:19 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')]} 14:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:19 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')]} 14:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:19 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268BF0>, '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 0x0128A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F625C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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 14:09:19 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\config 14:09:19 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:09:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:09:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:09:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:09:19 INFO - 'COMPUTERNAME': 'T-W732-IX-142', 14:09:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:09:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:09:19 INFO - 'DCLOCATION': 'SCL3', 14:09:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:09:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:09:19 INFO - 'HOMEDRIVE': 'C:', 14:09:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:09:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:09:19 INFO - 'KTS_VERSION': '1.19c', 14:09:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:09:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142', 14:09:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:09:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:19 INFO - 'MOZ_NO_REMOTE': '1', 14:09:19 INFO - 'NO_EM_RESTART': '1', 14:09:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:09:19 INFO - 'OS': 'Windows_NT', 14:09:19 INFO - 'OURDRIVE': 'C:', 14:09:19 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;', 14:09:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:09:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:09:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:09:19 INFO - 'PROCESSOR_LEVEL': '6', 14:09:19 INFO - 'PROCESSOR_REVISION': '1e05', 14:09:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:09:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:09:19 INFO - 'PROMPT': '$P$G', 14:09:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 14:09:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:09:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:09:19 INFO - 'PWD': 'C:\\slave\\test-pgo', 14:09:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:09:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:09:19 INFO - 'SYSTEMDRIVE': 'C:', 14:09:19 INFO - 'SYSTEMROOT': 'C:\\windows', 14:09:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:19 INFO - 'TEST1': 'testie', 14:09:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:19 INFO - 'USERDOMAIN': 'T-W732-IX-142', 14:09:19 INFO - 'USERNAME': 'cltbld', 14:09:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:09:19 INFO - 'WINDIR': 'C:\\windows', 14:09:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:09:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:19 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 14:09:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2wbuqx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 14:09:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 1)) 14:09:19 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 14:09:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nztaw6-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 14:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 14:09:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 14:09:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zlf0hw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 14:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 14:09:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 14:09:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wc_6wb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 14:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 14:09:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 14:09:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6ott3x-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 14:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 5)) 14:09:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 14:09:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gp6egr-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 14:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 6)) 14:09:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 14:09:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o3mtuv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 14:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 7)) 14:09:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 14:09:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-t8n4hb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 14:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 8)) 14:09:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 14:09:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v1eeq4-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 14:09:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 9)) 14:09:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 14:09:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y8p9wm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 14:09:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 14:09:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 14:09:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dwph3h-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 14:09:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 11)) 14:09:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 14:09:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rubbmo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 14:09:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 12)) 14:09:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 14:09:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ybzdzh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 14:09:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 13)) 14:09:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 14:09:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jyzhrx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 14:09:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14)) 14:09:21 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 14:09:21 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pdpdst-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 15)) 14:09:22 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 14:09:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3mpo0l-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 14:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/moztest in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 16)) 14:09:22 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 14:09:22 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dy0vov-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 14:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 17)) 14:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 14:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 14:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 14:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 14:09:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 14:09:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 14:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:23 INFO - Downloading blessings-1.6.tar.gz 14:09:23 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 14:09:23 INFO - Installing collected packages: blessings 14:09:23 INFO - Running setup.py install for blessings 14:09:24 INFO - Successfully installed blessings 14:09:24 INFO - Cleaning up... 14:09:26 INFO - Return code: 0 14:09:26 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 14:09:26 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 14:09:26 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 14:09:27 INFO - Reading from file tmpfile_stdout 14:09:27 INFO - Using _rmtree_windows ... 14:09:27 INFO - Using _rmtree_windows ... 14:09:27 INFO - Current package versions: 14:09:27 INFO - blessings == 1.6 14:09:27 INFO - blobuploader == 1.2.4 14:09:27 INFO - docopt == 0.6.1 14:09:27 INFO - manifestparser == 1.1 14:09:27 INFO - mozInstall == 1.12 14:09:27 INFO - mozcrash == 0.16 14:09:27 INFO - mozdebug == 0.1 14:09:27 INFO - mozdevice == 0.48 14:09:27 INFO - mozfile == 1.2 14:09:27 INFO - mozhttpd == 0.7 14:09:27 INFO - mozinfo == 0.9 14:09:27 INFO - mozleak == 0.1 14:09:27 INFO - mozlog == 3.1 14:09:27 INFO - moznetwork == 0.27 14:09:27 INFO - mozprocess == 0.22 14:09:27 INFO - mozprofile == 0.28 14:09:27 INFO - mozrunner == 6.11 14:09:27 INFO - mozscreenshot == 0.1 14:09:27 INFO - mozsystemmonitor == 0.0 14:09:27 INFO - moztest == 0.7 14:09:27 INFO - mozversion == 1.4 14:09:27 INFO - psutil == 3.1.1 14:09:27 INFO - requests == 1.2.3 14:09:27 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 14:09:27 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')]} 14:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:27 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')]} 14:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:27 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268BF0>, '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 0x0128A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F625C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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 14:09:27 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test-pgo\build\tests\talos 14:09:27 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test-pgo\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:09:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:09:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:09:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:09:27 INFO - 'COMPUTERNAME': 'T-W732-IX-142', 14:09:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:09:27 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:09:27 INFO - 'DCLOCATION': 'SCL3', 14:09:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:09:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:09:27 INFO - 'HOMEDRIVE': 'C:', 14:09:27 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:09:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:09:27 INFO - 'KTS_VERSION': '1.19c', 14:09:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:09:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142', 14:09:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:09:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:27 INFO - 'MOZ_NO_REMOTE': '1', 14:09:27 INFO - 'NO_EM_RESTART': '1', 14:09:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:09:27 INFO - 'OS': 'Windows_NT', 14:09:27 INFO - 'OURDRIVE': 'C:', 14:09:27 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;', 14:09:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:09:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:09:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:09:27 INFO - 'PROCESSOR_LEVEL': '6', 14:09:27 INFO - 'PROCESSOR_REVISION': '1e05', 14:09:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:09:27 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:09:27 INFO - 'PROMPT': '$P$G', 14:09:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 14:09:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:09:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:09:27 INFO - 'PWD': 'C:\\slave\\test-pgo', 14:09:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:09:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:09:27 INFO - 'SYSTEMDRIVE': 'C:', 14:09:27 INFO - 'SYSTEMROOT': 'C:\\windows', 14:09:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:27 INFO - 'TEST1': 'testie', 14:09:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:27 INFO - 'USERDOMAIN': 'T-W732-IX-142', 14:09:27 INFO - 'USERNAME': 'cltbld', 14:09:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:09:27 INFO - 'WINDIR': 'C:\\windows', 14:09:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:09:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 14:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 2)) 14:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3)) 14:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 4)) 14:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 5)) 14:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 6)) 14:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 7)) 14:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 8)) 14:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 9)) 14:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 14:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 4)) 14:09:27 INFO - Cleaning up... 14:09:27 INFO - Return code: 0 14:09:27 INFO - Installing jsonschema into virtualenv C:\slave\test-pgo\build/venv 14:09:27 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')]} 14:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:27 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')]} 14:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:27 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268BF0>, '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 0x0128A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F625C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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 14:09:27 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test-pgo\build 14:09:27 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:09:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:09:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:09:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:09:27 INFO - 'COMPUTERNAME': 'T-W732-IX-142', 14:09:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:09:27 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:09:27 INFO - 'DCLOCATION': 'SCL3', 14:09:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:09:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:09:27 INFO - 'HOMEDRIVE': 'C:', 14:09:27 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:09:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:09:27 INFO - 'KTS_VERSION': '1.19c', 14:09:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:09:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142', 14:09:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:09:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:27 INFO - 'MOZ_NO_REMOTE': '1', 14:09:27 INFO - 'NO_EM_RESTART': '1', 14:09:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:09:27 INFO - 'OS': 'Windows_NT', 14:09:27 INFO - 'OURDRIVE': 'C:', 14:09:27 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;', 14:09:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:09:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:09:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:09:27 INFO - 'PROCESSOR_LEVEL': '6', 14:09:27 INFO - 'PROCESSOR_REVISION': '1e05', 14:09:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:09:27 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:09:27 INFO - 'PROMPT': '$P$G', 14:09:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 14:09:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:09:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:09:27 INFO - 'PWD': 'C:\\slave\\test-pgo', 14:09:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:09:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:09:27 INFO - 'SYSTEMDRIVE': 'C:', 14:09:27 INFO - 'SYSTEMROOT': 'C:\\windows', 14:09:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:27 INFO - 'TEST1': 'testie', 14:09:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:27 INFO - 'USERDOMAIN': 'T-W732-IX-142', 14:09:27 INFO - 'USERNAME': 'cltbld', 14:09:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:09:27 INFO - 'WINDIR': 'C:\\windows', 14:09:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:09:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:09:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:27 INFO - Downloading/unpacking jsonschema 14:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:09:28 INFO - Downloading/unpacking functools32 (from jsonschema) 14:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:29 INFO - Downloading functools32-3.2.3-2.tar.gz 14:09:29 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\functools32\setup.py) egg_info for package functools32 14:09:29 INFO - warning: no files found matching '*.txt' 14:09:29 INFO - no previously-included directories found matching 'build' 14:09:29 INFO - no previously-included directories found matching 'dist' 14:09:29 INFO - no previously-included directories found matching '.git*' 14:09:29 INFO - Installing collected packages: jsonschema, functools32 14:09:29 INFO - Running setup.py install for functools32 14:09:29 INFO - warning: no files found matching '*.txt' 14:09:29 INFO - no previously-included directories found matching 'build' 14:09:29 INFO - no previously-included directories found matching 'dist' 14:09:29 INFO - no previously-included directories found matching '.git*' 14:09:29 INFO - Successfully installed jsonschema functools32 14:09:29 INFO - Cleaning up... 14:09:29 INFO - Return code: 0 14:09:29 INFO - Installing flake8 into virtualenv C:\slave\test-pgo\build/venv 14:09:29 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')]} 14:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:29 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')]} 14:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:29 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0183B6B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018DFEA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017EAF58>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268BF0>, '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 0x0128A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F625C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-142', 'USERDOMAIN': 'T-W732-IX-142', '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-142', '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 14:09:30 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in C:\slave\test-pgo\build 14:09:30 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:09:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:09:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:09:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:09:30 INFO - 'COMPUTERNAME': 'T-W732-IX-142', 14:09:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:09:30 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:09:30 INFO - 'DCLOCATION': 'SCL3', 14:09:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:09:30 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:09:30 INFO - 'HOMEDRIVE': 'C:', 14:09:30 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:09:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:09:30 INFO - 'KTS_VERSION': '1.19c', 14:09:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:09:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142', 14:09:30 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:09:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:09:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:30 INFO - 'MOZ_NO_REMOTE': '1', 14:09:30 INFO - 'NO_EM_RESTART': '1', 14:09:30 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:09:30 INFO - 'OS': 'Windows_NT', 14:09:30 INFO - 'OURDRIVE': 'C:', 14:09: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;', 14:09:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:09:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:09:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:09:30 INFO - 'PROCESSOR_LEVEL': '6', 14:09:30 INFO - 'PROCESSOR_REVISION': '1e05', 14:09:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:09:30 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:09:30 INFO - 'PROMPT': '$P$G', 14:09:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 14:09:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:09:30 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:09:30 INFO - 'PWD': 'C:\\slave\\test-pgo', 14:09:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:09:30 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:09:30 INFO - 'SYSTEMDRIVE': 'C:', 14:09:30 INFO - 'SYSTEMROOT': 'C:\\windows', 14:09:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:30 INFO - 'TEST1': 'testie', 14:09:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:30 INFO - 'USERDOMAIN': 'T-W732-IX-142', 14:09:30 INFO - 'USERNAME': 'cltbld', 14:09:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:09:30 INFO - 'WINDIR': 'C:\\windows', 14:09:30 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:09:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:09:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:30 INFO - Downloading/unpacking flake8 14:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:09:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:09:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:31 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\pep8\setup.py) egg_info for package pep8 14:09:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:09:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:09:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:09:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:09:31 INFO - no previously-included directories found matching 'docs\_build' 14:09:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:09:32 INFO - Running setup.py install for pep8 14:09:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:09:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:09:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:09:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:09:32 INFO - no previously-included directories found matching 'docs\_build' 14:09:32 INFO - Installing pep8-script.py script to C:\slave\test-pgo\build\venv\Scripts 14:09:32 INFO - Installing pep8.exe script to C:\slave\test-pgo\build\venv\Scripts 14:09:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:09:32 INFO - Cleaning up... 14:09:32 INFO - Return code: 0 14:09:32 INFO - Running post-action listener: _resource_record_post_action 14:09:32 INFO - Running post-action listener: _start_resource_monitoring 14:09:33 INFO - Starting resource monitoring. 14:09:33 INFO - ##### 14:09:33 INFO - ##### Running install step. 14:09:33 INFO - ##### 14:09:33 INFO - Running pre-action listener: _resource_record_pre_action 14:09:33 INFO - Running main action method: install 14:09:33 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 14:09:33 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 14:09:33 INFO - Reading from file tmpfile_stdout 14:09:33 INFO - Using _rmtree_windows ... 14:09:33 INFO - Using _rmtree_windows ... 14:09:33 INFO - Detecting whether we're running mozinstall >=1.0... 14:09:33 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 14:09:33 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 14:09:33 INFO - Reading from file tmpfile_stdout 14:09:33 INFO - Output received: 14:09:33 INFO - Usage: mozinstall-script.py [options] installer 14:09:33 INFO - Options: 14:09:33 INFO - -h, --help show this help message and exit 14:09:33 INFO - -d DEST, --destination=DEST 14:09:33 INFO - Directory to install application into. [default: 14:09:33 INFO - "C:\slave\test-pgo"] 14:09:33 INFO - --app=APP Application being installed. [default: firefox] 14:09:33 INFO - Using _rmtree_windows ... 14:09:33 INFO - Using _rmtree_windows ... 14:09:33 INFO - mkdir: C:\slave\test-pgo\build\application 14:09:33 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-46.0a2.en-US.win32.zip', '--destination', 'C:\\slave\\test-pgo\\build\\application'] 14:09:33 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-46.0a2.en-US.win32.zip --destination C:\slave\test-pgo\build\application 14:09:35 INFO - Reading from file tmpfile_stdout 14:09:35 INFO - Output received: 14:09:35 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 14:09:35 INFO - Using _rmtree_windows ... 14:09:35 INFO - Using _rmtree_windows ... 14:09:35 INFO - Running post-action listener: _resource_record_post_action 14:09:35 INFO - ##### 14:09:35 INFO - ##### Running run-tests step. 14:09:35 INFO - ##### 14:09:35 INFO - Running pre-action listener: _resource_record_pre_action 14:09:35 INFO - Running main action method: run_tests 14:09:35 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 14:09:35 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 14:09:35 INFO - Python 2.7.3 14:09:35 INFO - Return code: 0 14:09:35 INFO - grabbing minidump binary from tooltool 14:09:35 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')]} 14:09:35 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01268BF0>, '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 0x0128A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F625C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 14:09:35 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test-pgo\build 14:09:35 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test-pgo\build\tests\config/tooltool-manifests/win32/releng.manifest -o 14:09:35 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 14:09:36 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpauz7n_ 14:09:36 INFO - INFO - File integrity verified, renaming tmpauz7n_ to win32-minidump_stackwalk.exe 14:09:36 INFO - Return code: 0 14:09:36 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 14:09:36 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 14:09:36 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 14:09:36 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 14:09:36 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 14:09:36 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\flake8', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos'] 14:09:36 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\flake8 C:\slave\test-pgo\build\tests\talos\talos 14:09:38 INFO - Return code: 0 14:09:38 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'svgr', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-142', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test-pgo\\build\\blobber_upload_dir\\svgr_errorsummary.log', '--log-raw=C:\\slave\\test-pgo\\build\\blobber_upload_dir\\svgr_raw.log'] in C:\slave\test-pgo\build 14:09:38 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --branchName Mozilla-Aurora --suite svgr --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-142 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test-pgo\build\blobber_upload_dir\svgr_errorsummary.log --log-raw=C:\slave\test-pgo\build\blobber_upload_dir\svgr_raw.log 14:09:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 14:09:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 14:09:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 14:09:38 INFO - 'COMPUTERNAME': 'T-W732-IX-142', 14:09:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 14:09:38 INFO - 'CYGWINBASE': 'C:\\cygwin', 14:09:38 INFO - 'DCLOCATION': 'SCL3', 14:09:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 14:09:38 INFO - 'FP_NO_HOST_CHECK': 'NO', 14:09:38 INFO - 'HOMEDRIVE': 'C:', 14:09:38 INFO - 'HOMEPATH': '\\Users\\cltbld', 14:09:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 14:09:38 INFO - 'KTS_VERSION': '1.19c', 14:09:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 14:09:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-142', 14:09:38 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 14:09:38 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 14:09:38 INFO - 'MONDIR': 'C:\\Monitor_config\\', 14:09:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 14:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:38 INFO - 'MOZ_NO_REMOTE': '1', 14:09:38 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 14:09:38 INFO - 'NO_EM_RESTART': '1', 14:09:38 INFO - 'NUMBER_OF_PROCESSORS': '8', 14:09:38 INFO - 'OS': 'Windows_NT', 14:09:38 INFO - 'OURDRIVE': 'C:', 14:09:38 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;', 14:09:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 14:09:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 14:09:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 14:09:38 INFO - 'PROCESSOR_LEVEL': '6', 14:09:38 INFO - 'PROCESSOR_REVISION': '1e05', 14:09:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 14:09:38 INFO - 'PROGRAMFILES': 'C:\\Program Files', 14:09:38 INFO - 'PROMPT': '$P$G', 14:09:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 14:09:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 14:09:38 INFO - 'PUBLIC': 'C:\\Users\\Public', 14:09:38 INFO - 'PWD': 'C:\\slave\\test-pgo', 14:09:38 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 14:09:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 14:09:38 INFO - 'SLAVEDIR': 'C:\\slave\\', 14:09:38 INFO - 'SYSTEMDRIVE': 'C:', 14:09:38 INFO - 'SYSTEMROOT': 'C:\\windows', 14:09:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:38 INFO - 'TEST1': 'testie', 14:09:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 14:09:38 INFO - 'USERDOMAIN': 'T-W732-IX-142', 14:09:38 INFO - 'USERNAME': 'cltbld', 14:09:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 14:09:38 INFO - 'WINDIR': 'C:\\windows', 14:09:38 INFO - 'WINDOWS_TRACING_FLAGS': '3', 14:09:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 14:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 14:09:38 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'svgr', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-142', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test-pgo\\build\\blobber_upload_dir\\svgr_errorsummary.log', '--log-raw=C:\\slave\\test-pgo\\build\\blobber_upload_dir\\svgr_raw.log'] with output_timeout 3600 14:09:39 INFO - mozversion application_buildid: 20160302112221 14:09:39 INFO - mozversion application_changeset: dc8bba6da2ed7bb8d860952438f001a13564de87 14:09:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:09:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:09:39 INFO - mozversion application_name: Firefox 14:09:39 INFO - mozversion application_remotingname: firefox-dev 14:09:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:09:39 INFO - mozversion application_vendor: Mozilla 14:09:39 INFO - mozversion application_version: 46.0a2 14:09:39 INFO - mozversion platform_buildid: 20160302112221 14:09:39 INFO - mozversion platform_changeset: dc8bba6da2ed7bb8d860952438f001a13564de87 14:09:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:09:39 INFO - mozversion platform_version: 46.0a2 14:09:39 INFO - using testdate: 1456956579 14:09:39 INFO - actual date: 1456956579 14:09:39 INFO - starting webserver on 'localhost:49606' 14:09:39 INFO - SUITE-START | Running 5 tests 14:09:39 INFO - TEST-START | tsvgx 14:09:39 INFO - operating with platform_type : w7_ 14:09:39 INFO - Initialising browser for tsvgx test... 14:09:40 INFO - TEST-INFO | started process 856 (C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpesaolx\profile http://localhost:49606/getInfo.html) 14:09:48 INFO - PROCESS | 856 | __metrics Screen width/height:1600/1200 14:09:48 INFO - PROCESS | 856 | colorDepth:24 14:09:48 INFO - PROCESS | 856 | Browser inner width/height: 1010/674 14:09:48 INFO - PROCESS | 856 | __metrics 14:09:48 INFO - PROCESS | 856 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:09:48 INFO - PROCESS | 856 | console.error: 14:09:48 INFO - PROCESS | 856 | Message: Error: Connection closed before committing the transaction. 14:09:48 INFO - PROCESS | 856 | Stack: 14:09:48 INFO - PROCESS | 856 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:933:23 14:09:48 INFO - PROCESS | 856 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:812:7 14:09:48 INFO - PROCESS | 856 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:743:11 14:09:48 INFO - PROCESS | 856 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:775:7 14:09:48 INFO - PROCESS | 856 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:710:7 14:09:48 INFO - PROCESS | 856 | TaskImpl_run@resource://gre/modules/Task.jsm:320:13 14:09:48 INFO - PROCESS | 856 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9 14:09:48 INFO - PROCESS | 856 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 14:09:48 INFO - PROCESS | 856 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:384:5 14:09:48 INFO - PROCESS | 856 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 14:09:48 INFO - PROCESS | 856 | Barrier/this.client.addBlocker/promise resource://gre/modules/Promise-backend.js:933:23 14:17:36 INFO - PROCESS | 4048 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:812:7 14:17:36 INFO - PROCESS | 4048 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:743:11 14:17:36 INFO - PROCESS | 4048 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:775:7 14:17:36 INFO - PROCESS | 4048 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:710:7 14:17:36 INFO - PROCESS | 4048 | TaskImpl_run@resource://gre/modules/Task.jsm:320:13 14:17:36 INFO - PROCESS | 4048 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9 14:17:36 INFO - PROCESS | 4048 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 14:17:36 INFO - PROCESS | 4048 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:384:5 14:17:36 INFO - PROCESS | 4048 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 14:17:36 INFO - PROCESS | 4048 | Barrier/this.client.addBlocker/promisetsvgx: 381.89 14:28:33 INFO - RETURN: tsvgr_opacity: 499.27 14:28:33 INFO - RETURN: tart: 3.73 14:28:33 INFO - RETURN: tscrollx: 2.89 14:28:33 INFO - RETURN: cart: 23.24 14:28:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [254.0, 216.0, 213.0, 215.0, 212.0, 213.0, 214.0, 215.0, 211.0, 213.0, 211.0, 215.0, 217.0, 214.0, 212.0, 219.0, 211.0, 213.0, 211.0, 213.0, 211.0, 219.0, 211.0, 215.0, 212.0], "unit": "ms", "name": "gearflowers.svg", "value": 213.0}, {"lowerIsBetter": true, "replicates": [66.0, 41.0, 41.0, 41.0, 40.0, 40.0, 41.0, 41.0, 41.0, 41.0, 41.0, 42.0, 41.0, 41.0, 41.0, 41.0, 41.0, 41.0, 41.0, 41.0, 41.0, 43.0, 42.0, 41.0, 41.0], "unit": "ms", "name": "composite-scale.svg", "value": 41.0}, {"lowerIsBetter": true, "replicates": [129.0, 126.0, 128.0, 125.0, 124.0, 124.0, 123.0, 123.0, 124.0, 123.0, 123.0, 122.0, 123.0, 124.0, 122.0, 123.0, 122.0, 123.0, 123.0, 123.0, 122.0, 122.0, 122.0, 123.0, 123.0], "unit": "ms", "name": "composite-scale-opacity.svg", "value": 123.0}, {"lowerIsBetter": true, "replicates": [171.0, 172.0, 167.0, 169.0, 173.0, 178.0, 168.0, 167.0, 172.0, 166.0, 167.0, 166.0, 170.0, 170.0, 168.0, 166.0, 166.0, 168.0, 167.0, 185.0, 171.0, 167.0, 168.0, 167.0, 167.0], "unit": "ms", "name": "composite-scale-rotate.svg", "value": 167.5}, {"lowerIsBetter": true, "replicates": [254.0, 239.0, 241.0, 238.0, 239.0, 238.0, 239.0, 239.0, 246.0, 238.0, 239.0, 241.0, 239.0, 245.0, 238.0, 238.0, 239.0, 239.0, 241.0, 244.0, 246.0, 240.0, 239.0, 246.0, 247.0], "unit": "ms", "name": "composite-scale-rotate-opacity.svg", "value": 239.0}, {"lowerIsBetter": true, "replicates": [469.0, 430.0, 432.0, 425.0, 432.0, 432.0, 436.0, 433.0, 431.0, 431.0, 433.0, 429.0, 431.0, 429.0, 430.0, 430.0, 433.0, 431.0, 433.0, 431.0, 431.0, 438.0, 434.0, 430.0, 431.0], "unit": "ms", "name": "hixie-001.xml", "value": 431.0}, {"lowerIsBetter": true, "replicates": [438.0, 432.0, 428.0, 429.0, 428.0, 430.0, 433.0, 432.0, 430.0, 435.0, 426.0, 439.0, 429.0, 429.0, 433.0, 437.0, 449.0, 442.0, 456.0, 435.0, 432.0, 433.0, 442.0, 436.0, 424.0], "unit": "ms", "name": "hixie-002.xml", "value": 433.0}, {"lowerIsBetter": true, "replicates": [270.0, 220.0, 2726.0, 214.0, 217.0, 216.0, 219.0, 212.0, 217.0, 213.0, 210.0, 214.0, 211.0, 213.0, 210.0, 213.0, 216.0, 219.0, 212.0, 213.0, 210.0, 211.0, 214.0, 210.0, 211.0], "unit": "ms", "name": "hixie-003.xml", "value": 213.0}, {"lowerIsBetter": true, "replicates": [1125.0, 1087.0, 1080.0, 1113.0, 1108.0, 1111.0, 1099.0, 1094.0, 1090.0, 1092.0, 1106.0, 1091.0, 1110.0, 1088.0, 1103.0, 1066.0, 1119.0, 1087.0, 1117.0, 1084.0, 1090.0, 1079.0, 1097.0, 2018.0, 1113.0], "unit": "ms", "name": "hixie-004.xml", "value": 1095.5}, {"lowerIsBetter": true, "replicates": [2438.0, 2429.0, 2430.0, 2429.0, 2425.0, 2464.0, 2427.0, 2419.0, 2439.0, 2418.0, 2434.0, 2426.0, 2429.0, 2429.0, 2429.0, 2434.0, 2426.0, 2429.0, 2436.0, 2442.0, 2427.0, 2431.0, 2428.0, 2453.0, 2428.0], "unit": "ms", "name": "hixie-005.xml", "value": 2429.0}, {"lowerIsBetter": true, "replicates": [3763.0, 3774.0, 3767.0, 3786.0, 3754.0, 3772.0, 3773.0, 3767.0, 3757.0, 3778.0, 3760.0, 3831.0, 3771.0, 3818.0, 3763.0, 3777.0, 3753.0, 3749.0, 3760.0, 3768.0, 3784.0, 3781.0, 3774.0, 3762.0, 3759.0], "unit": "ms", "name": "hixie-006.xml", "value": 3769.5}, {"lowerIsBetter": true, "replicates": [636.0, 546.0, 542.0, 542.0, 543.0, 545.0, 542.0, 541.0, 547.0, 542.0, 545.0, 543.0, 551.0, 543.0, 544.0, 543.0, 548.0, 541.0, 549.0, 546.0, 548.0, 546.0, 551.0, 548.0, 551.0], "unit": "ms", "name": "hixie-007.xml", "value": 545.5}], "name": "tsvgx", "value": 381.88991764389084}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [739.0, 74.0, 705.0, 712.0, 702.0, 710.0, 713.0, 70.0, 707.0, 711.0, 710.0, 70.0, 706.0, 713.0, 712.0, 710.0, 70.0, 736.0, 711.0, 71.0, 715.0, 712.0, 70.0, 708.0, 712.0], "unit": "ms", "name": "big-optimizable-group-opacity-2500.svg", "value": 710.0}, {"lowerIsBetter": true, "replicates": [316.0, 351.0, 349.0, 353.0, 351.0, 358.0, 350.0, 352.0, 351.0, 352.0, 348.0, 351.0, 349.0, 351.0, 351.0, 350.0, 352.0, 350.0, 351.0, 352.0, 350.0, 351.0, 349.0, 350.0, 351.0], "unit": "ms", "name": "small-group-opacity-2500.svg", "value": 351.0}], "name": "tsvgr_opacity", "value": 499.27192605622}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [1.5872740582244036, 1.5179187718190645, 1.5433083613713583, 1.6331920724519542, 1.4832660464140086, 1.5233452524009503, 1.5501332648595174, 1.5381116088231404, 1.6030262509981792, 4.13095822869515, 1.549846550623576, 1.5688341066643998, 1.5245679820838727, 1.6561448880604335, 1.5469281689325969, 1.5553838497883565, 1.5480187288920084, 1.5448238674799601, 1.60239158405198, 1.5489505545298259, 1.632635017515908, 1.5552074248726304, 1.5613306116413426, 1.5592964694306657, 1.6360260835835632], "unit": "ms", "name": "simple-open-DPI1.half.TART", "value": 1.552670344866074}, {"lowerIsBetter": true, "replicates": [1.7318512720315635, 1.6997498321853228, 1.7056927528957393, 1.7584222488933139, 1.6741386024157205, 1.688845682144165, 1.7301219782861723, 1.72123409606315, 1.7913798051820675, 3.9751339182257652, 1.73238416918281, 1.7666408502393298, 1.7036464886377323, 1.8083748877048493, 1.7167792207485921, 1.7270630630506139, 1.7322222099823206, 1.7414987005599558, 1.7909552270257976, 1.724212669842952, 1.7874938518228665, 1.7075457396923295, 1.7765621046919924, 1.720982429949013, 1.761182223757108], "unit": "ms", "name": "simple-open-DPI1.all.TART", "value": 1.7311720941342466}, {"lowerIsBetter": true, "replicates": [25.420000000000073, 23.514999999999418, 24.3949999999968, 23.580000000001746, 27.635000000002037, 23.70999999999185, 24.554999999993015, 25.044999999998254, 24.664999999993597, 25.145000000004075, 25.019999999989523, 24.77499999999418, 24.17499999998836, 23.739999999990687, 24.369999999995343, 24.80500000002212, 24.81999999997788, 24.47500000000582, 24.60000000000582, 25.489999999990687, 24.079999999987194, 24.695000000006985, 24.320000000006985, 25.010000000009313, 23.930000000051223], "unit": "ms", "name": "simple-open-DPI1.error.TART", "value": 24.577499999999418}, {"lowerIsBetter": true, "replicates": [1.1721673993149189, 1.0879170135507044, 1.0999165171668643, 1.1176242793647988, 1.0655766355770606, 1.1066452763401544, 1.0917714671143945, 1.0973309369314284, 1.0845969029676135, 1.1044184094383602, 1.140496835260108, 1.1574236786365508, 1.1549699378013611, 1.1333436907506456, 1.1818047220609627, 1.1241447740388149, 1.1145024356933741, 1.0828168787688852, 1.1030031124750772, 1.1099277150172453, 1.1031713349478585, 1.1268066457174357, 1.1364570785971249, 1.140158462052298, 1.151358999828301], "unit": "ms", "name": "simple-close-DPI1.half.TART", "value": 1.1122150753553097}, {"lowerIsBetter": true, "replicates": [1.281182779136457, 1.1889942942596063, 1.2011984533864288, 1.2910375607707514, 1.16642290694373, 1.230552356050472, 1.2067408582259869, 1.2094628940074903, 1.1927256092792604, 1.2031108584310033, 1.3052212395132545, 1.3052089195200467, 1.3421845334571796, 1.2992569436418249, 1.2335864995336774, 1.2164439770579338, 1.2339016570888384, 1.1841623670812966, 1.2040472106980573, 1.1990699926527535, 1.2213043828508747, 1.198576077526691, 1.2326294364351216, 1.296158421546855, 1.30875729303309], "unit": "ms", "name": "simple-close-DPI1.all.TART", "value": 1.2188741799544043}, {"lowerIsBetter": true, "replicates": [15.854999999999563, 16.775000000001455, 16.44999999999709, 17.29500000000553, 17.485000000000582, 16.729999999995925, 17.72499999999127, 18.41500000000815, 17.210000000006403, 16.739999999990687, 16.699999999982538, 17.07500000001164, 16.89999999999418, 17.279999999998836, 15.595000000001164, 16.025000000023283, 16.979999999981374, 17.63499999998021, 17.145000000018626, 18.31500000000233, 18.11500000001979, 17.139999999955762, 16.755000000004657, 16.164999999979045, 17.770000000018626], "unit": "ms", "name": "simple-close-DPI1.error.TART", "value": 17.107499999983702}, {"lowerIsBetter": true, "replicates": [1.5987222807274923, 1.5573038313839886, 1.549042716026306, 1.573421312345041, 1.5210766384476109, 1.5859905628308857, 1.571548030183122, 1.6499076724052428, 1.5859169176180068, 1.5752011891957876, 1.5732915884739644, 1.536544901529948, 1.582581934863574, 1.6392927589550825, 2.6935136235037516, 1.61712746322155, 1.6334624978857981, 1.5459553734461466, 1.6566770008632115, 1.6066208829482396, 1.5828291618660704, 1.5954589778429842, 1.5688704316680495, 1.5756805057394994, 1.6116981771257188], "unit": "ms", "name": "icon-open-DPI1.half.TART", "value": 1.5827055483648222}, {"lowerIsBetter": true, "replicates": [1.8367943720851871, 1.7823945634014957, 1.76650752292739, 1.7924073825419788, 1.768144839339786, 1.7993896435683883, 1.8063177825711298, 2.0425675649642945, 1.8111735700715519, 1.814978135393021, 1.8416648930397586, 1.7658381900853581, 1.8172479595456805, 1.8598558189225023, 2.891185379841111, 1.8282387076521949, 1.8472940636717754, 1.8108004627498329, 1.8766766059048035, 1.8440189707106438, 1.8098118787116193, 1.7906064004965232, 1.787958968693102, 1.8043542212628303, 1.8774926092694788], "unit": "ms", "name": "icon-open-DPI1.all.TART", "value": 1.8109870164106923}, {"lowerIsBetter": true, "replicates": [26.210000000000946, 25.349999999998545, 24.779999999998836, 24.739999999997963, 24.955000000001746, 25.845000000001164, 24.869999999995343, 25.755000000004657, 25.619999999995343, 26.179999999993015, 24.580000000016298, 24.58499999999185, 24.68499999999767, 25.19499999997788, 25.029999999998836, 24.660000000003492, 25.480000000010477, 25.900000000023283, 25.535000000003492, 25.520000000018626, 25.43499999999767, 24.669999999983702, 24.385000000009313, 25.51500000001397, 25.800000000046566], "unit": "ms", "name": "icon-open-DPI1.error.TART", "value": 25.272499999988213}, {"lowerIsBetter": true, "replicates": [1.0831506324705678, 1.10986494158323, 1.0812737145156504, 1.0803693527373197, 1.1002931946799868, 1.1018145487422035, 1.1018474709419976, 1.096961566380092, 1.104414753686814, 1.1201593991622185, 1.1109541585812202, 1.0959631709825426, 1.093361792137038, 1.1018072287241618, 1.1063166627517114, 1.1393408161752365, 1.117633492900775, 1.1067855851008341, 1.1039210438728333, 1.118932855939402, 1.1209646787458254, 1.108720334676596, 1.1142476751254156, 1.108812648516435, 1.1058772876858711], "unit": "ms", "name": "icon-close-DPI1.half.TART", "value": 1.1060969752187912}, {"lowerIsBetter": true, "replicates": [1.1823480097720256, 1.2164958291385897, 1.2019736909983205, 1.2133592564870816, 1.2374303565552487, 1.2163114701167193, 1.2480672727986641, 1.2402744534039738, 1.2233021500706673, 1.2395006355011102, 1.238534825618821, 1.2050459848836137, 1.2333684828712712, 1.2132536739551376, 1.2228567054867745, 1.2670624489636766, 1.2504589566724555, 1.2369537299601876, 1.2213330418612827, 1.2213623579775934, 1.2431350386503972, 1.2363941369943283, 1.2297936157882214, 1.2352498709855966, 1.2463078614738252], "unit": "ms", "name": "icon-close-DPI1.all.TART", "value": 1.2343091769284338}, {"lowerIsBetter": true, "replicates": [19.520000000000437, 17.294999999998254, 18.040000000000873, 17.97500000000582, 19.18000000000029, 18.714999999996508, 18.66500000000815, 18.375, 17.485000000000582, 18.375, 18.160000000003492, 17.480000000010477, 18.349999999976717, 19.25, 18.025000000023283, 18.635000000009313, 19.205000000016298, 19.079999999987194, 18.39999999999418, 19.570000000006985, 18.919999999983702, 18.85999999998603, 18.81000000005588, 18.755000000004657, 19.630000000004657], "unit": "ms", "name": "icon-close-DPI1.error.TART", "value": 18.65000000000873}, {"lowerIsBetter": true, "replicates": [1.5879999660465816, 1.58328680796166, 1.609538197517395, 1.6370807248102108, 1.5773480036487317, 1.6185087892744277, 1.6229756063138936, 1.7264721215660892, 1.6553384968212672, 1.6690945608028467, 1.6453381214823042, 1.6461719461849758, 1.6394820481958523, 1.6530619416918073, 1.6445810845920017, 1.6839368585227192, 1.6692559459935064, 1.6449486374855042, 1.6320941700062281, 1.6685825638149097, 1.6699961130169854, 1.6440818990979875, 1.6328297081127974, 1.6495072313717434, 1.7348398112539034], "unit": "ms", "name": "icon-open-DPI2.half.TART", "value": 1.6451433794839043}, {"lowerIsBetter": true, "replicates": [1.8216560399028616, 1.7998655841384135, 1.8232520239693777, 1.879878474509015, 1.8006065244406042, 1.8672694750945933, 1.8524780282075854, 1.9438320578950825, 1.9071282415247675, 1.8961928005571718, 1.8689035603599826, 1.873847460045534, 1.8853165402131922, 1.8756686247446959, 1.8789128205355476, 1.8783820026061113, 1.8715943001648958, 1.859345445598381, 1.8549600856891577, 1.9193281446184431, 1.9143188609216446, 1.8671910023167186, 1.8627624085349759, 1.8922419053536874, 1.9235983029344028], "unit": "ms", "name": "icon-open-DPI2.all.TART", "value": 1.874758042395115}, {"lowerIsBetter": true, "replicates": [29.01000000000022, 26.0099999999984, 25.56500000000233, 26.214999999996508, 26.12999999999738, 26.114999999990687, 26.75999999999476, 27.014999999999418, 25.919999999998254, 26.269999999989523, 26.385000000009313, 25.300000000017462, 26.650000000023283, 25.369999999995343, 25.88499999998021, 25.79999999998836, 24.90999999997439, 26.820000000006985, 26.30999999999767, 25.59000000002561, 25.01500000001397, 26.099999999976717, 25.43499999999767, 25.960000000020955, 26.15500000002794], "unit": "ms", "name": "icon-open-DPI2.error.TART", "value": 26.054999999987558}, {"lowerIsBetter": true, "replicates": [1.0430250006753046, 1.045951171354814, 1.0474173399535092, 1.0561248146065878, 1.0332699923643045, 1.02286188634096, 1.053435621478341, 1.0495816918936642, 1.0589642737983564, 1.0655019757924256, 1.0620503414661513, 1.049239581823349, 1.040465024677483, 1.0459756125103343, 1.0554061384376037, 1.0372477533581022, 1.0503601594404741, 1.0433709471075385, 1.0404961597812068, 1.0391573589127343, 1.045277089686007, 1.0511176759546452, 1.047392904216593, 1.03137743845582, 1.038299415025625], "unit": "ms", "name": "icon-close-DPI2.half.TART", "value": 1.0466842583634637}, {"lowerIsBetter": true, "replicates": [1.1402764260490363, 1.1336565050967904, 1.1529870664289859, 1.1522262815578443, 1.1776846673053045, 1.1278319496163576, 1.1352969314213153, 1.144974593506303, 1.1606525745190366, 1.1476363590065861, 1.176202120250492, 1.1466826695791432, 1.1509697351937003, 1.1429051882195695, 1.1404416404491247, 1.1343760431088783, 1.1245871984630549, 1.1500234804420828, 1.135394706218331, 1.12379178255116, 1.1373416204606333, 1.15301713066281, 1.1558823680653818, 1.138627038306968, 1.136029368473424], "unit": "ms", "name": "icon-close-DPI2.all.TART", "value": 1.1439398908629363}, {"lowerIsBetter": true, "replicates": [17.599999999998545, 17.825000000000728, 19.054999999993015, 19.19000000000233, 18.409999999996217, 18.389999999999418, 18.779999999998836, 19.735000000000582, 20.710000000006403, 18.020000000004075, 19.304999999993015, 17.875, 19.279999999998836, 18.214999999996508, 18.81500000000233, 18.574999999982538, 18.714999999996508, 19.630000000004657, 18.989999999990687, 19.904999999998836, 20.295000000012806, 17.880000000004657, 19.679999999993015, 18.39000000001397, 18.919999999983702], "unit": "ms", "name": "icon-close-DPI2.error.TART", "value": 18.867499999993015}, {"lowerIsBetter": true, "replicates": [1.0251692628438493, 1.0150818213036186, 1.019409313666082, 1.011888585592571, 1.0425337574503444, 1.0159070784585518, 1.0422466322108432, 1.027959186051573, 1.0278164591409464, 1.016806437258135, 1.012390471864165, 1.0285248889454774, 1.012309631234721, 1.029316888323852, 1.0238031839902422, 1.0220836912636209, 1.042748252013782, 1.0304208765072482, 1.0132157326790325, 1.015779076438201, 1.018994728548337, 1.0194573658600188, 1.073868128436583, 1.0173453819333462, 1.5163171087440692], "unit": "ms", "name": "iconFade-close-DPI2.half.TART", "value": 1.0207705285618198}, {"lowerIsBetter": true, "replicates": [1.028054335476023, 1.0299505301281415, 1.0292606957718335, 1.0321029361089071, 1.0504204050842423, 1.0284417295350439, 1.0404322232518877, 1.0396865122020245, 1.0337891093889873, 1.027751893860049, 1.0397653675505094, 1.0388800491227044, 1.0261694547363316, 1.033751561111874, 1.0296667819529508, 1.0286694071461668, 1.0551082748093756, 1.0414434401194255, 1.0343523046705458, 1.0279489882224428, 1.0313047144265302, 1.0310549472285584, 1.0551743013826431, 1.0349837597211202, 1.2580728472890081], "unit": "ms", "name": "iconFade-close-DPI2.all.TART", "value": 1.0337703352504306}, {"lowerIsBetter": true, "replicates": [2.5349999999998545, 2.7150000000001455, 2.6700000000055297, 2.349999999998545, 4.114999999990687, 2.4250000000029104, 2.720000000001164, 2.8250000000116415, 2.625, 2.2949999999982538, 2.9400000000023283, 3.819999999977881, 2.9349999999976717, 2.6600000000034925, 2.709999999991851, 2.375, 3.125, 2.929999999993015, 2.8099999999976717, 2.3650000000197906, 3.01500000001397, 3.1900000000023283, 3.2000000000116415, 2.7550000000046566, 2.730000000039581], "unit": "ms", "name": "iconFade-close-DPI2.error.TART", "value": 2.742500000022119}, {"lowerIsBetter": true, "replicates": [1.5521966075897218, 1.5723471673759255, 1.7252226925607939, 1.590514357775858, 1.6145141621430714, 1.666885813644954, 1.6171914603975084, 1.6004449410570993, 1.6201199662517494, 1.6155754841036267, 1.6328946150524515, 1.6489202533449445, 1.6656021680150712, 1.671971751296002, 1.5759014397451323, 1.6308177843899794, 1.6205904953916308, 1.5817034767098623, 1.6651578273091998, 1.7094516982050503, 1.591098239977066, 1.6733296435812246, 1.602236928211318, 1.6278053051988843, 1.5896253896086183], "unit": "ms", "name": "iconFade-open-DPI2.half.TART", "value": 1.6203552308216902}, {"lowerIsBetter": true, "replicates": [1.5063447590797179, 1.5110195654553253, 1.5880771546947712, 1.5338217929789895, 1.5400658451168743, 1.57289721353634, 1.5642015653968657, 1.5689306547177717, 1.5645907197222613, 1.5482645524258645, 1.5464793463416446, 1.5728842358331423, 1.5948593012274128, 1.5777594455190607, 1.5103014363096905, 1.5548641029993693, 1.5638484930832115, 1.529175279966367, 1.5826280563056063, 1.6046533872341286, 1.5232923443800483, 1.5819201412655057, 1.5282215604595109, 1.5549127419789632, 1.48888242016932], "unit": "ms", "name": "iconFade-open-DPI2.all.TART", "value": 1.5593806175310874}, {"lowerIsBetter": true, "replicates": [3.5049999999973807, 2.710000000002765, 3.5099999999947613, 3.095000000001164, 2.514999999999418, 2.6750000000029104, 2.9900000000052387, 3.665000000008149, 4.189999999987776, 3.654999999998836, 3.584999999991851, 2.7699999999895226, 2.904999999998836, 3.404999999998836, 2.6000000000058208, 3.2399999999906868, 2.9400000000023283, 3.8249999999825377, 2.584999999991851, 2.7250000000058208, 3.0650000000023283, 2.6049999999813735, 3.76500000001397, 3.224999999976717, 3.75], "unit": "ms", "name": "iconFade-open-DPI2.error.TART", "value": 3.0800000000017462}, {"lowerIsBetter": true, "replicates": [2.25532560488757, 1.670446893443232, 1.7251596610937545, 1.723520501336055, 1.7200129583700379, 1.7608808965393992, 1.7304267082641374, 1.7073679443667917, 1.721910012302114, 1.7718021557881283, 1.7743365379480216, 1.7174453343918075, 1.7509318987528484, 1.727796040364166, 1.8960561322384193, 1.7869611685092632, 1.7917818051118117, 1.758099828705643, 1.7062216085546158, 1.7379920126786872, 1.7949246663313645, 1.7354301232010572, 1.7791630818293644, 1.7417746764510424, 1.7283519844510662], "unit": "ms", "name": "newtab-open-preload-no.half.TART", "value": 1.736711067939872}, {"lowerIsBetter": true, "replicates": [3.788901296528903, 2.3923533085350677, 2.5535324729315123, 2.532789568982872, 2.568385019004345, 2.5518368045882425, 2.617193538733203, 2.5504034710402537, 2.519148882731651, 2.546806834711887, 2.5926643128346916, 2.5193007459827497, 2.5656987275227463, 2.5432520136974825, 2.669529270880001, 2.566572560071945, 2.7041088537165994, 2.632509028425022, 2.504225204291853, 2.6268626989758745, 2.6570434385968236, 2.553802414695815, 2.5742486369609834, 2.547745928315833, 2.537356131217059], "unit": "ms", "name": "newtab-open-preload-no.all.TART", "value": 2.553667443813664}, {"lowerIsBetter": true, "replicates": [31.31999999999971, 27.989999999997963, 28.294999999998254, 30.19999999999709, 27.135000000009313, 28.779999999998836, 29.589999999996508, 29.90000000000873, 29.83499999999185, 27.68499999999767, 28.869999999995343, 27.489999999990687, 30.114999999990687, 27.35999999998603, 29.23499999998603, 27.07500000001164, 27.39000000001397, 28.304999999993015, 29.52499999999418, 27.72500000000582, 28.364999999990687, 28.84000000002561, 27.71500000002561, 27.68499999999767, 29.119999999995343], "unit": "ms", "name": "newtab-open-preload-no.error.TART", "value": 28.33499999999185}, {"lowerIsBetter": true, "replicates": [1.7594961794939907, 1.7571340185223203, 1.738639651839413, 1.7764160284629236, 1.804514529183507, 1.7419695373791366, 1.743769777543617, 1.759792898640488, 1.7602990793459343, 1.8227903712540865, 1.8101484794169664, 1.7606307087522564, 1.8548960954912248, 1.7429028767527956, 1.7440897695945972, 1.7509028008489897, 1.7343641145905453, 1.7571922089114334, 1.8237323556095362, 1.7490468187765642, 1.790417139346783, 1.762009617054101, 1.7631732532472322, 1.7665303078564731, 1.742382191899997], "unit": "ms", "name": "newtab-open-preload-yes.half.TART", "value": 1.7600459889932112}, {"lowerIsBetter": true, "replicates": [1.9506886194225486, 1.9748980370602867, 1.9428122876254657, 2.0474489274024963, 2.0356693547072373, 1.9572578022113214, 1.988605826400047, 1.9945652189180834, 1.9948088941164315, 2.0316656257898087, 2.05232723736763, 1.9769549508427464, 2.0492245383262633, 1.9861589637837669, 1.9243374242818445, 1.9790953947947576, 1.9623404272640024, 1.9832298764886782, 2.070445218634221, 1.9921153827693112, 2.0474735193252562, 2.0441333277831, 1.9966519128444582, 1.9979828707873821, 1.970269385438699], "unit": "ms", "name": "newtab-open-preload-yes.all.TART", "value": 1.9933403008436974}, {"lowerIsBetter": true, "replicates": [35.01499999999942, 34.92500000000291, 34.81499999999505, 36.575000000004366, 38.595000000001164, 36.245000000009895, 36.77499999999418, 37.61000000000058, 36.59000000001106, 36.33999999999651, 37.014999999984866, 35.179999999993015, 36.74000000001979, 36.39999999999418, 35.65999999997439, 37.60999999998603, 37.360000000015134, 36.14999999999418, 36.99500000002445, 37.595000000001164, 37.100000000034925, 37.75500000000466, 37.77000000001863, 36.774999999965075, 36.164999999979045], "unit": "ms", "name": "newtab-open-preload-yes.error.TART", "value": 36.75749999999243}], "name": "tart", "value": 3.7328051147086896}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [3.1142899408284026, 2.1088165680473367, 2.105355029585799, 2.1155621301775147, 2.1078402366863904, 2.1122189349112426, 2.1126923076923076, 2.1010059171597635, 2.1138461538461537, 2.1124852071005917, 2.1102662721893495, 2.1085798816568047, 2.116923076923077, 2.1158284023668643, 2.1137573964497043, 2.110976331360947, 2.126508875739645, 2.1002958579881654, 2.1371301775147935, 2.1111538461538464, 2.10508875739645, 2.1062130177514793, 2.111775147928994, 2.0908579881656806, 2.124556213017752], "unit": "ms", "name": "tiled.html", "value": 2.1119970414201186}, {"lowerIsBetter": true, "replicates": [3.136627218934911, 3.1459467455621306, 3.139082840236686, 3.182721893491124, 3.1316863905325443, 3.1524260355029594, 3.1244970414201183, 3.1524260355029585, 3.1310059171597637, 3.1499112426035505, 3.1421893491124258, 3.137544378698225, 3.141597633136094, 3.1367159763313612, 3.143047337278106, 3.146124260355029, 3.135887573964497, 3.135473372781065, 3.155828402366864, 3.146005917159763, 3.146065088757396, 3.1434023668639055, 3.1224556213017753, 3.1649704142011834, 3.125118343195266], "unit": "ms", "name": "tiled-fixed.html", "value": 3.1426183431952657}, {"lowerIsBetter": true, "replicates": [2.423491124260355, 2.3982544378698223, 2.3789053254437866, 2.3894970414201184, 2.391923076923077, 2.373343195266272, 2.3780177514792897, 2.3881952662721893, 2.3615976331360953, 2.398047337278107, 2.3939053254437868, 2.3859171597633133, 2.3765384615384613, 2.382633136094675, 2.373254437869823, 2.3972189349112427, 2.3844970414201185, 2.371923076923077, 2.3957396449704143, 2.386804733727811, 2.3730473372781065, 2.3902958579881655, 2.3750591715976332, 2.390769230769231, 2.377396449704142], "unit": "ms", "name": "tiled-downscale.html", "value": 2.383565088757397}, {"lowerIsBetter": true, "replicates": [3.5339349112426044, 3.533786982248521, 3.536804733727811, 3.5214792899408285, 3.527573964497042, 3.5329289940828397, 3.503402366863905, 3.532130177514793, 3.528284023668639, 3.540473372781065, 3.526745562130178, 3.5315976331360948, 3.53792899408284, 3.529852071005917, 3.542633136094675, 3.518609467455621, 3.5376035502958576, 3.5454733727810654, 3.5400887573964495, 3.5375147928994077, 3.547455621301775, 3.5487869822485205, 3.5247041420118346, 3.5363609467455626, 3.525384615384615], "unit": "ms", "name": "tiled-fixed-downscale.html", "value": 3.534644970414201}, {"lowerIsBetter": true, "replicates": [3.3497093023255817, 3.3286046511627907, 3.3559883720930235, 3.3218023255813947, 3.3420348837209297, 3.3256395348837215, 3.313779069767442, 3.357616279069768, 3.275232558139536, 3.3170348837209307, 3.3593604651162794, 3.333430232558139, 3.3281976744186044, 3.292267441860466, 3.310697674418605, 3.3649999999999998, 3.327267441860465, 3.3027325581395353, 3.3244767441860463, 3.319360465116279, 3.3069186046511634, 3.37593023255814, 3.327093023255814, 3.327267441860465, 3.3500581395348847], "unit": "ms", "name": "iframe.svg", "value": 3.3263662790697675}, {"lowerIsBetter": true, "replicates": [3.115635838150289, 3.0960982658959537, 3.0734104046242776, 3.0847976878612715, 3.1002312138728327, 3.083641618497109, 7.537485549132949, 3.103236994219653, 3.095173410404624, 3.084393063583815, 3.0988150289017335, 3.120086705202312, 3.0921387283237, 3.1069653179190753, 3.0780924855491327, 3.0712138728323697, 3.09835260115607, 3.0815028901734114, 3.0884682080924852, 3.088150289017341, 3.0812427745664746, 3.097167630057803, 3.1102312138728325, 3.0858670520231213, 3.0865028901734104], "unit": "ms", "name": "reader.htm", "value": 3.0903034682080923}], "name": "tscrollx", "value": 2.896593694681647}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [5.255935737490654, 5.172581888380504, 5.049776168096633, 5.200156609217326, 4.952807857876732, 5.253184636433919, 4.948894671031407, 5.242085354668753, 5.025017465863909, 5.1176155635288785, 4.9074962337811785, 7.5026373863220215, 5.104541335787092, 4.931962263016474, 5.032430377602577, 5.104907106785547, 5.0699999417577475, 6.226768458431417, 4.971440848850069, 4.845416738873436, 5.045936153048561, 4.942110782577878, 5.167919110684168, 5.07638167483466, 5.114909265722547], "unit": "ms", "name": "1-customize-enter.half.TART", "value": 5.073190808296204}, {"lowerIsBetter": true, "replicates": [10.691105628267248, 10.014414781615848, 9.71911745293196, 10.087818372540358, 9.993111645303122, 10.125740260612673, 9.97291890004786, 10.07144699445585, 9.97666528748303, 9.839257263001942, 9.96541705218757, 12.980939669486803, 10.167296229339227, 9.948495331264677, 10.043250863041196, 10.141353131794348, 9.917300166118713, 9.79157772944087, 9.81479131040119, 9.765127766700019, 10.078536696550323, 10.126910951079392, 10.025824954112371, 9.845090453113828, 9.96223685996873], "unit": "ms", "name": "1-customize-enter.all.TART", "value": 9.984888466393077}, {"lowerIsBetter": true, "replicates": [331.4250000000011, 229.72999999999956, 226.4399999999987, 222.82500000000073, 218.1349999999984, 224.38499999999476, 217.18499999999767, 221.38500000000204, 217.51499999999942, 221.76499999999942, 217.9649999999965, 316.26000000000204, 225.34999999999854, 226.34999999999127, 230.90499999999884, 224.31999999999243, 225.06500000000233, 219.73500000000058, 220.8249999999971, 218.63999999999942, 221.76499999999942, 223.59999999999127, 229.58499999999185, 221.98500000000058, 226.83000000000175], "unit": "ms", "name": "1-customize-enter.error.TART", "value": 223.212499999996}, {"lowerIsBetter": true, "replicates": [7.948951252869198, 7.61860443013055, 8.010637640953064, 7.989435476916177, 7.749555807847243, 8.137037875560614, 8.848849645027748, 7.990998923778534, 8.132419501032148, 7.7182241678237915, 8.0032320022583, 7.818913578987122, 8.004137081759316, 7.9017196382795065, 8.057156154087611, 7.8951917460986545, 7.846616097858974, 7.923881820269993, 7.752811244555882, 7.73476334980556, 7.8589039870670865, 8.086970678397588, 7.8713564446994235, 7.684103335653033, 8.019359690802437], "unit": "ms", "name": "2-customize-exit.half.TART", "value": 7.91280072927475}, {"lowerIsBetter": true, "replicates": [9.56576224063572, 9.007775065226433, 9.891648590564728, 10.083577970096043, 10.038527309894562, 10.542640663044793, 10.287886115482875, 10.0206573843956, 9.891552534368303, 9.832321094142067, 9.94951936858041, 9.96841581662496, 9.588252528293713, 9.85496617688073, 10.051231145858765, 9.883872598409653, 9.942090690135956, 10.032539340427943, 9.841440849834019, 9.881045891178978, 9.847254196802774, 9.96085328857104, 9.755695726420429, 9.818017131752438, 10.128439767020089], "unit": "ms", "name": "2-customize-exit.all.TART", "value": 9.916869640350342}, {"lowerIsBetter": true, "replicates": [185.88999999999942, 172.49499999999898, 176.79000000000087, 172.82500000000073, 182.13000000000102, 190.13500000000204, 175.61000000000058, 170.97999999999593, 176.25999999999476, 174.3199999999997, 168.0899999999965, 180.2300000000032, 175.22999999999593, 175.8350000000064, 184.08000000000175, 175.4149999999936, 177.97499999999127, 170.66500000000815, 174.54499999999825, 175.16999999999825, 174.19000000000233, 178.25, 181.10499999999593, 174.08499999999185, 173.9499999999971], "unit": "ms", "name": "2-customize-exit.error.TART", "value": 175.32249999999476}, {"lowerIsBetter": true, "replicates": [9.612240531227805, 10.829129505157471, 10.017667675018311, 10.044316959381103, 9.626797502691096, 9.665755618702281, 10.20478925704956, 9.959932067177512, 10.231361865997314, 9.525841279463334, 9.264897866682572, 9.270937139337713, 10.175605392456054, 10.212661361694336, 10.165659999847412, 10.153525733947754, 10.145346736907959, 9.64666054465554, 10.014058113098145, 9.693822340531783, 9.839531551707875, 9.509084874933416, 9.585779536854137, 9.621212005615234, 10.396403694152832], "unit": "ms", "name": "3-customize-enter-css.half.TART", "value": 9.986995090137828}, {"lowerIsBetter": true, "replicates": [12.981904574802943, 13.84564791227642, 13.424947118759155, 13.570520305633545, 13.003207206726074, 12.952739216032482, 13.3616455078125, 13.542949342727661, 13.562321996688842, 12.907299405052548, 12.6255738394601, 12.717861311776298, 13.469932556152344, 13.988696851228413, 13.798633766174316, 13.557752513885498, 13.620843410491943, 12.963600703648158, 13.569464349746704, 12.911651565915061, 12.980697722662063, 12.877676827566964, 12.787364641825357, 12.99318690527053, 13.21732006072998], "unit": "ms", "name": "3-customize-enter-css.all.TART", "value": 13.28948278427124}, {"lowerIsBetter": true, "replicates": [72.42500000000109, 62.88999999999942, 68.24500000000262, 71.15999999999985, 72.81999999999971, 71.80500000000029, 67.18999999999505, 70.61499999999796, 70.96499999999651, 70.73500000000058, 64.92499999999563, 66.72499999999854, 69.14500000000407, 65.74000000000524, 75.70500000000175, 70.95500000000175, 72.25999999999476, 71.84500000000116, 71.24000000000524, 70.96499999999651, 72.30999999999767, 70.33999999999651, 68.39500000000407, 72.8350000000064, 64.57000000000698], "unit": "ms", "name": "3-customize-enter-css.error.TART", "value": 70.84500000000116}], "name": "cart", "value": 23.244905393363403}]} 14:28:33 INFO - TinderboxPrint: TalosResult: {"graphserver": {"tscrollx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[287,52,25]]", "result": "2.89"}, "tsvgr_opacity": {"url": "http://graphs.mozilla.org/graph.html#tests=[[225,52,25]]", "result": "499.27"}, "tart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[293,52,25]]", "result": "3.73"}, "cart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[309,52,25]]", "result": "23.24"}, "tsvgx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[281,52,25]]", "result": "381.89"}}} 14:28:33 INFO - Return code: 0 14:28:33 INFO - Validating PERFHERDER_DATA against C:\slave\test-pgo\build\tests\talos\treeherder-schemas\performance-artifact.json 14:28:33 INFO - # TBPL SUCCESS # 14:28:33 INFO - Running post-action listener: _resource_record_post_action 14:28:33 INFO - Running post-run listener: _resource_record_post_run 14:28:34 INFO - Total resource usage - Wall time: 1140s; CPU: 8.0%; Read bytes: 244176384; Write bytes: 518786048; Read time: 3596930; Write time: 11433460 14:28:34 INFO - install - Wall time: 3s; CPU: 9.0%; Read bytes: 0; Write bytes: 23038464; Read time: 0; Write time: 416150 14:28:34 INFO - run-tests - Wall time: 1138s; CPU: 8.0%; Read bytes: 244176384; Write bytes: 490561024; Read time: 3596930; Write time: 10666270 14:28:34 INFO - Running post-run listener: _upload_blobber_files 14:28:34 INFO - Blob upload gear active. 14:28:34 INFO - Preparing to upload files from C:\slave\test-pgo\build\blobber_upload_dir. 14:28:34 INFO - Files from C:\slave\test-pgo\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:28:34 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test-pgo\\oauth.txt', '-b', 'Mozilla-Aurora', '-d', 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test-pgo\\build\\uploaded_files.json'] 14:28:34 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test-pgo\oauth.txt -b Mozilla-Aurora -d C:\slave\test-pgo\build\blobber_upload_dir --output-manifest C:\slave\test-pgo\build\uploaded_files.json 14:28:35 INFO - (blobuploader) - INFO - Open directory for files ... 14:28:35 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_errorsummary.log ... 14:28:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:28:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:28:36 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_errorsummary.log: uploaded 14:28:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:28:36 INFO - (blobuploader) - INFO - Done attempting. 14:28:36 INFO - (blobuploader) - INFO - Uploading C:\slave\test-pgo\build\blobber_upload_dir\svgr_raw.log ... 14:28:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:28:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:28:38 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_raw.log: uploaded 14:28:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:28:38 INFO - (blobuploader) - INFO - Done attempting. 14:28:38 INFO - (blobuploader) - INFO - Iteration through files over. 14:28:38 INFO - Return code: 0 14:28:38 INFO - rmtree: C:\slave\test-pgo\build\uploaded_files.json 14:28:38 INFO - Using _rmtree_windows ... 14:28:38 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build\\uploaded_files.json',), kwargs: {}, attempt #1 14:28:38 INFO - Setting buildbot property blobber_files to {"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01cbc5c3991c8997e28ced035dc3b2d9c6c969e5c9ed701bba5cb882bf4104975b8e6c62cec9b070e6cd9afb24d189df7d889c2c14ed0bfe158e0401dce0ea02", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bbfa465a28238a9c9cc399591ce6af0cda3334c8e69839a1db64256f8c37073b201d34b1919154dc3d80c6818960a70fdcb26cfa998c0151fad99239ad59de8"} 14:28:38 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test-pgo\properties\blobber_files 14:28:38 INFO - Writing to file C:\slave\test-pgo\properties\blobber_files 14:28:38 INFO - Contents: 14:28:38 INFO - blobber_files:{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01cbc5c3991c8997e28ced035dc3b2d9c6c969e5c9ed701bba5cb882bf4104975b8e6c62cec9b070e6cd9afb24d189df7d889c2c14ed0bfe158e0401dce0ea02", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bbfa465a28238a9c9cc399591ce6af0cda3334c8e69839a1db64256f8c37073b201d34b1919154dc3d80c6818960a70fdcb26cfa998c0151fad99239ad59de8"} 14:28:38 INFO - Running post-run listener: copy_logs_to_upload_dir 14:28:38 INFO - Copying logs to upload dir... 14:28:38 INFO - mkdir: C:\slave\test-pgo\build\upload\logs 14:28:38 INFO - Copying logs to upload dir... 14:28:38 INFO - Using _rmtree_windows ... 14:28:38 INFO - Using _rmtree_windows ... 14:28:38 INFO - Using _rmtree_windows ... 14:28:38 INFO - Using _rmtree_windows ... 14:28:38 INFO - Using _rmtree_windows ... 14:28:38 INFO - Using _rmtree_windows ... 14:28:38 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1363.918000 ========= master_lag: 0.34 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 22 mins, 44 secs) (at 2016-03-02 14:28:38.637629) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-02 14:28:38.638558) ========= '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-142 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-142 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-142 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 blobber_files:{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01cbc5c3991c8997e28ced035dc3b2d9c6c969e5c9ed701bba5cb882bf4104975b8e6c62cec9b070e6cd9afb24d189df7d889c2c14ed0bfe158e0401dce0ea02", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bbfa465a28238a9c9cc399591ce6af0cda3334c8e69839a1db64256f8c37073b201d34b1919154dc3d80c6818960a70fdcb26cfa998c0151fad99239ad59de8"} build_url:https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/RNS-Dzs_R9awhbE4CtViNA/artifacts/public/build/firefox-46.0a2.en-US.win32.zip' blobber_files: '{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/01cbc5c3991c8997e28ced035dc3b2d9c6c969e5c9ed701bba5cb882bf4104975b8e6c62cec9b070e6cd9afb24d189df7d889c2c14ed0bfe158e0401dce0ea02", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4bbfa465a28238a9c9cc399591ce6af0cda3334c8e69839a1db64256f8c37073b201d34b1919154dc3d80c6818960a70fdcb26cfa998c0151fad99239ad59de8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-02 14:28:38.765817) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 14:28:38.766165) ========= '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-142 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-142 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-142 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.101000 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 14:28:38.883213) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-03-02 14:28:38.883958) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-03-02 14:28:39.462836) ========= ========= Total master_lag: 3.30 =========