builder: mozilla-beta_win8_64_test-svgr-pgo slave: t-w864-ix-077 starttime: 1461113849.07 results: success (0) buildid: 20160419135143 builduid: 9778c14317944c68b633f5e1160e462f revision: 96c726556dc80f4a43a8423a7dc1a612789eeabc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 17:57:29.072957) ========= master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 17:57:29.073952) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 17:57:29.074655) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 17:57:29.199999) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 17:57:29.200695) ========= '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\. (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.T-W864-IX-077\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=T-W864-IX-077 COMSPEC=C:\windows\system32\cmd.exe DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld.T-W864-IX-077 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-077\AppData\Local LOGONSERVER=\\T-W864-IX-077 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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-077 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-077 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-077 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False --17:57:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 8.59 MB/s 17:57:30 (8.59 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.412000 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 17:57:30.696802) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-04-19 17:57:30.697197) ========= 'rm' '-rf' 'scripts' 'properties' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-077\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=T-W864-IX-077 COMSPEC=C:\windows\system32\cmd.exe DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld.T-W864-IX-077 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-077\AppData\Local LOGONSERVER=\\T-W864-IX-077 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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-077 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-077 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-077 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=3.006000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-04-19 17:57:33.719441) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-19 17:57:33.720389) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 96c726556dc80f4a43a8423a7dc1a612789eeabc --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 96c726556dc80f4a43a8423a7dc1a612789eeabc --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-077\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=T-W864-IX-077 COMSPEC=C:\windows\system32\cmd.exe DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld.T-W864-IX-077 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-077\AppData\Local LOGONSERVER=\\T-W864-IX-077 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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-077 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-077 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-077 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False 2016-04-19 17:57:34,025 truncating revision to first 12 chars 2016-04-19 17:57:34,025 Setting DEBUG logging. 2016-04-19 17:57:34,025 attempt 1/10 2016-04-19 17:57:34,025 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/96c726556dc8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 17:57:35,619 unpacking tar archive at: mozilla-beta-96c726556dc8/testing/mozharness/ program finished with exit code 0 elapsedTime=2.289000 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-19 17:57:36.060601) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 17:57:36.061043) ========= script_repo_revision: 96c726556dc80f4a43a8423a7dc1a612789eeabc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 17:57:36.061531) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 17:57:36.061860) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 17:57:36.076547) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 18 mins, 45 secs) (at 2016-04-19 17:57:36.077048) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'svgr' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Beta' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Beta' in dir C:\slave\test\. (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-Beta', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-077\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=T-W864-IX-077 COMSPEC=C:\windows\system32\cmd.exe DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld.T-W864-IX-077 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-077\AppData\Local LOGONSERVER=\\T-W864-IX-077 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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PROPERTIES_FILE=C:\slave\test/buildprops.json PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-077 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-077 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-077 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False 17:57:36 INFO - MultiFileLogger online at 20160419 17:57:36 in C:\slave\test 17:57:36 INFO - Run as scripts/scripts/talos_script.py --suite svgr --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:57:36 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 17:57:36 INFO - {'append_to_log': False, 17:57:36 INFO - 'base_work_dir': 'C:\\slave\\test', 17:57:36 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:57:36 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 17:57:36 INFO - 'branch': 'Mozilla-Beta', 17:57:36 INFO - 'buildbot_json_path': 'buildprops.json', 17:57:36 INFO - 'config_files': ('talos/windows_config.py',), 17:57:36 INFO - 'default_actions': ('clobber', 17:57:36 INFO - 'read-buildbot-config', 17:57:36 INFO - 'download-and-extract', 17:57:36 INFO - 'populate-webroot', 17:57:36 INFO - 'create-virtualenv', 17:57:36 INFO - 'install', 17:57:36 INFO - 'run-tests'), 17:57:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:57:36 INFO - 'download_minidump_stackwalk': True, 17:57:36 INFO - 'download_symbols': 'ondemand', 17:57:36 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 17:57:36 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 17:57:36 INFO - 'hg': 'c:/mozilla-build/hg/hg', 17:57:36 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 17:57:36 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 17:57:36 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 17:57:36 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 17:57:36 INFO - 'C:/mozilla-build/tooltool.py'), 17:57:36 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 17:57:36 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 17:57:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:57:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:57:36 INFO - 'installer_path': 'installer.exe', 17:57:36 INFO - 'log_level': 'info', 17:57:36 INFO - 'log_name': 'talos', 17:57:36 INFO - 'log_to_console': True, 17:57:36 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 17:57:36 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 17:57:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 17:57:36 INFO - 'opt_config_files': (), 17:57:36 INFO - 'pip_index': False, 17:57:36 INFO - 'sps_profile': False, 17:57:36 INFO - 'sps_profile_interval': 0, 17:57:36 INFO - 'suite': 'svgr', 17:57:36 INFO - 'system_bits': '32', 17:57:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:57:36 INFO - 'title': 'T-W864-IX-077', 17:57:36 INFO - 'use_talos_json': True, 17:57:36 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 17:57:36 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 17:57:36 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 17:57:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:57:36 INFO - 'work_dir': 'build'} 17:57:36 INFO - ##### 17:57:36 INFO - ##### Running clobber step. 17:57:36 INFO - ##### 17:57:36 INFO - Running pre-action listener: _resource_record_pre_action 17:57:36 INFO - Running main action method: clobber 17:57:36 INFO - rmtree: C:\slave\test\build 17:57:36 INFO - Using _rmtree_windows ... 17:57:36 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 17:58:42 INFO - Running post-action listener: _resource_record_post_action 17:58:42 INFO - ##### 17:58:42 INFO - ##### Running read-buildbot-config step. 17:58:42 INFO - ##### 17:58:42 INFO - Running pre-action listener: _resource_record_pre_action 17:58:42 INFO - Running main action method: read_buildbot_config 17:58:42 INFO - Using buildbot properties: 17:58:42 INFO - { 17:58:42 INFO - "project": "", 17:58:42 INFO - "product": "firefox", 17:58:42 INFO - "script_repo_revision": "production", 17:58:42 INFO - "builddir": "mozilla-beta_win8_64_test-svgr", 17:58:42 INFO - "repository": "", 17:58:42 INFO - "buildername": "Windows 8 64-bit mozilla-beta pgo talos svgr", 17:58:42 INFO - "buildid": "20160419135143", 17:58:42 INFO - "pgo_build": "True", 17:58:42 INFO - "basedir": "C:\\slave\\test", 17:58:42 INFO - "buildnumber": 9, 17:58:42 INFO - "slavename": "t-w864-ix-077", 17:58:42 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/", 17:58:42 INFO - "platform": "win8_64", 17:58:42 INFO - "scheduler": "tests-mozilla-beta-win64-pgo-talos", 17:58:42 INFO - "branch": "mozilla-beta", 17:58:42 INFO - "repo_path": "releases/mozilla-beta", 17:58:42 INFO - "revision": "96c726556dc80f4a43a8423a7dc1a612789eeabc", 17:58:42 INFO - "stage_platform": "win64-pgo", 17:58:42 INFO - "builduid": "9778c14317944c68b633f5e1160e462f", 17:58:42 INFO - "slavebuilddir": "test" 17:58:42 INFO - } 17:58:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.zip. 17:58:42 INFO - Running post-action listener: _resource_record_post_action 17:58:42 INFO - ##### 17:58:42 INFO - ##### Running download-and-extract step. 17:58:42 INFO - ##### 17:58:42 INFO - Running pre-action listener: _resource_record_pre_action 17:58:42 INFO - Running main action method: download_and_extract 17:58:42 INFO - mkdir: C:\slave\test\build\tests 17:58:42 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')]} 17:58:42 INFO - https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 17:58:42 INFO - trying https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/test_packages.json 17:58:42 INFO - Downloading https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json 17:58:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1 17:58:43 INFO - Downloaded 1267 bytes. 17:58:43 INFO - Reading from file C:\slave\test\build\test_packages.json 17:58:43 INFO - Using the following test package requirements: 17:58:43 INFO - {u'common': [u'firefox-46.0.en-US.win64.common.tests.zip'], 17:58:43 INFO - u'cppunittest': [u'firefox-46.0.en-US.win64.common.tests.zip', 17:58:43 INFO - u'firefox-46.0.en-US.win64.cppunittest.tests.zip'], 17:58:43 INFO - u'gtest': [u'firefox-46.0.en-US.win64.common.tests.zip'], 17:58:43 INFO - u'jittest': [u'firefox-46.0.en-US.win64.common.tests.zip', 17:58:43 INFO - u'jsshell-win64.zip'], 17:58:43 INFO - u'mochitest': [u'firefox-46.0.en-US.win64.common.tests.zip', 17:58:43 INFO - u'firefox-46.0.en-US.win64.mochitest.tests.zip'], 17:58:43 INFO - u'mozbase': [u'firefox-46.0.en-US.win64.common.tests.zip'], 17:58:43 INFO - u'reftest': [u'firefox-46.0.en-US.win64.common.tests.zip', 17:58:43 INFO - u'firefox-46.0.en-US.win64.reftest.tests.zip'], 17:58:43 INFO - u'talos': [u'firefox-46.0.en-US.win64.common.tests.zip', 17:58:43 INFO - u'firefox-46.0.en-US.win64.talos.tests.zip'], 17:58:43 INFO - u'web-platform': [u'firefox-46.0.en-US.win64.common.tests.zip', 17:58:43 INFO - u'firefox-46.0.en-US.win64.web-platform.tests.zip'], 17:58:43 INFO - u'webapprt': [u'firefox-46.0.en-US.win64.common.tests.zip'], 17:58:43 INFO - u'xpcshell': [u'firefox-46.0.en-US.win64.common.tests.zip', 17:58:43 INFO - u'firefox-46.0.en-US.win64.xpcshell.tests.zip']} 17:58:43 INFO - Downloading packages: [u'firefox-46.0.en-US.win64.common.tests.zip'] for test suite category: common 17:58:43 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')]} 17:58:43 INFO - https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.common.tests.zip matches https://queue.taskcluster.net 17:58:43 INFO - trying https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.common.tests.zip 17:58:43 INFO - Downloading https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.common.tests.zip to C:\slave\test\build\firefox-46.0.en-US.win64.common.tests.zip 17:58:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-46.0.en-US.win64.common.tests.zip'}, attempt #1 17:58:44 INFO - Downloaded 18047259 bytes. 17:58:44 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-46.0.en-US.win64.common.tests.zip', '-d', 'C:\\slave\\test\\build\\tests'] 17:58:44 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-46.0.en-US.win64.common.tests.zip -d C:\slave\test\build\tests 17:59:15 INFO - Return code: 0 17:59:15 INFO - Downloading packages: [u'firefox-46.0.en-US.win64.common.tests.zip', u'firefox-46.0.en-US.win64.talos.tests.zip'] for test suite category: talos 17:59:15 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')]} 17:59:15 INFO - https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.common.tests.zip matches https://queue.taskcluster.net 17:59:15 INFO - trying https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.common.tests.zip 17:59:15 INFO - Downloading https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.common.tests.zip to C:\slave\test\build\firefox-46.0.en-US.win64.common.tests.zip 17:59:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-46.0.en-US.win64.common.tests.zip'}, attempt #1 17:59:16 INFO - Downloaded 18047259 bytes. 17:59:16 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-46.0.en-US.win64.common.tests.zip', '-d', 'C:\\slave\\test\\build\\tests'] 17:59:16 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-46.0.en-US.win64.common.tests.zip -d C:\slave\test\build\tests 17:59:41 INFO - Return code: 0 17:59:41 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')]} 17:59:41 INFO - https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.talos.tests.zip matches https://queue.taskcluster.net 17:59:41 INFO - trying https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.talos.tests.zip 17:59:41 INFO - Downloading https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.talos.tests.zip to C:\slave\test\build\firefox-46.0.en-US.win64.talos.tests.zip 17:59:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.talos.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-46.0.en-US.win64.talos.tests.zip'}, attempt #1 17:59:42 INFO - Downloaded 10801548 bytes. 17:59:42 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-46.0.en-US.win64.talos.tests.zip', '-d', 'C:\\slave\\test\\build\\tests'] 17:59:42 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-46.0.en-US.win64.talos.tests.zip -d C:\slave\test\build\tests 17:59:44 INFO - Return code: 0 17:59:44 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')]} 17:59:44 INFO - https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.zip matches https://queue.taskcluster.net 17:59:44 INFO - trying https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.zip 17:59:44 INFO - Downloading https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.zip to C:\slave\test\build\firefox-46.0.en-US.win64.zip 17:59:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-46.0.en-US.win64.zip'}, attempt #1 17:59:45 INFO - Downloaded 57491674 bytes. 17:59:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.zip 17:59:45 INFO - mkdir: C:\slave\test\properties 17:59:45 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 17:59:45 INFO - Writing to file C:\slave\test\properties\build_url 17:59:45 INFO - Contents: 17:59:45 INFO - build_url:https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.zip 17:59:46 INFO - Running post-action listener: _resource_record_post_action 17:59:46 INFO - Running post-action listener: set_extra_try_arguments 17:59:46 INFO - ##### 17:59:46 INFO - ##### Running populate-webroot step. 17:59:46 INFO - ##### 17:59:46 INFO - Running pre-action listener: _resource_record_pre_action 17:59:46 INFO - Running main action method: populate_webroot 17:59:46 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 17:59:46 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 17:59:46 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 17:59:46 INFO - u'--tppagecycles', 17:59:46 INFO - u'7'], 17:59:46 INFO - u'tests': [u'tsvgm']}}, 17:59:46 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 17:59:46 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 17:59:46 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 17:59:46 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 17:59:46 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 17:59:46 INFO - u'tests': [u'dromaeo_css', 17:59:46 INFO - u'kraken', 17:59:46 INFO - u'v8_7']}, 17:59:46 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:46 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 17:59:46 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:46 INFO - u'talos_options': [u'--e10s'], 17:59:46 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 17:59:46 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:46 INFO - u'tests': [u'damp', u'tps']}, 17:59:46 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:46 INFO - u'talos_options': [u'--e10s'], 17:59:46 INFO - u'tests': [u'damp', u'tps']}, 17:59:46 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 17:59:46 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 17:59:46 INFO - u'other': {u'tests': [u'a11yr', 17:59:46 INFO - u'ts_paint', 17:59:46 INFO - u'tpaint', 17:59:46 INFO - u'sessionrestore', 17:59:46 INFO - u'sessionrestore_no_auto_restore']}, 17:59:46 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 17:59:46 INFO - u'tests': [u'a11yr', 17:59:46 INFO - u'ts_paint', 17:59:46 INFO - u'tpaint', 17:59:46 INFO - u'sessionrestore', 17:59:46 INFO - u'sessionrestore_no_auto_restore']}, 17:59:46 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 17:59:46 INFO - u'tests': [u'a11yr', 17:59:46 INFO - u'ts_paint', 17:59:46 INFO - u'tpaint', 17:59:46 INFO - u'sessionrestore', 17:59:46 INFO - u'sessionrestore_no_auto_restore']}, 17:59:46 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 17:59:46 INFO - u'tests': [u'a11yr', 17:59:46 INFO - u'ts_paint', 17:59:46 INFO - u'tpaint', 17:59:46 INFO - u'sessionrestore', 17:59:46 INFO - u'sessionrestore_no_auto_restore']}, 17:59:46 INFO - u'other_l64': {u'tests': [u'a11yr', 17:59:46 INFO - u'ts_paint', 17:59:46 INFO - u'tpaint', 17:59:46 INFO - u'sessionrestore', 17:59:46 INFO - u'sessionrestore_no_auto_restore']}, 17:59:46 INFO - u'other_nol64': {u'tests': [u'a11yr', 17:59:46 INFO - u'ts_paint', 17:59:46 INFO - u'tpaint', 17:59:46 INFO - u'sessionrestore', 17:59:46 INFO - u'sessionrestore_no_auto_restore']}, 17:59:46 INFO - u'svgr': {u'tests': [u'tsvgx', 17:59:46 INFO - u'tsvgr_opacity', 17:59:46 INFO - u'tart', 17:59:46 INFO - u'tscrollx', 17:59:46 INFO - u'cart']}, 17:59:46 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 17:59:46 INFO - u'tests': [u'tsvgx', 17:59:46 INFO - u'tsvgr_opacity', 17:59:46 INFO - u'tart', 17:59:46 INFO - u'tscrollx', 17:59:46 INFO - u'cart']}, 17:59:46 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:46 INFO - u'tests': [u'tp5o']}, 17:59:46 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:46 INFO - u'talos_options': [u'--e10s'], 17:59:46 INFO - u'tests': [u'tp5o']}, 17:59:46 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:46 INFO - u'talos_options': [u'--xperf_path', 17:59:46 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:59:46 INFO - u'tests': [u'tp5n']}, 17:59:46 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:59:46 INFO - u'talos_options': [u'--e10s', 17:59:46 INFO - u'--xperf_path', 17:59:46 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:59:46 INFO - u'tests': [u'tp5n']}}, 17:59:46 INFO - u'talos.zip': {u'path': u'', 17:59:46 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:59:46 INFO - Running post-action listener: _resource_record_post_action 17:59:46 INFO - ##### 17:59:46 INFO - ##### Running create-virtualenv step. 17:59:46 INFO - ##### 17:59:46 INFO - Running pre-action listener: _resource_record_pre_action 17:59:46 INFO - Running main action method: create_virtualenv 17:59:46 INFO - Creating virtualenv C:\slave\test\build/venv 17:59:46 INFO - mkdir: C:\slave\test\build\venv\Scripts 17:59:46 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 17:59:46 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 17:59:46 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build 17:59:46 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 17:59:53 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 17:59:56 INFO - Installing distribute......................................................................................................................................................................................done. 17:59:58 INFO - Return code: 0 17:59:58 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 17:59: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')]} 17:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:59: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')]} 17:59:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:59:58 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\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 0x023876B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02431EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02532D98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E68BF0>, '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 0x01E8A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028505C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-077', 'USERDOMAIN': 'T-W864-IX-077', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-077', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 17:59:58 INFO - Running command: ['C:\\slave\\test\\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\build 17:59:58 INFO - Copy/paste: C:\slave\test\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 17:59:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 17:59:58 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 17:59:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 17:59:58 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 17:59:58 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 17:59:58 INFO - 'COMPUTERNAME': 'T-W864-IX-077', 17:59:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 17:59:58 INFO - 'DCLOCATION': 'SCL3', 17:59:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 17:59:58 INFO - 'FP_NO_HOST_CHECK': 'NO', 17:59:58 INFO - 'HOMEDRIVE': 'C:', 17:59:58 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 17:59:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 17:59:58 INFO - 'KTS_VERSION': '1.19c', 17:59:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 17:59:58 INFO - 'LOGONSERVER': '\\\\T-W864-IX-077', 17:59:58 INFO - 'MONDIR': 'C:\\Monitor_config\\', 17:59:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 17:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:59:58 INFO - 'MOZ_NO_REMOTE': '1', 17:59:58 INFO - 'NO_EM_RESTART': '1', 17:59:58 INFO - 'NUMBER_OF_PROCESSORS': '8', 17:59:58 INFO - 'OS': 'Windows_NT', 17:59:58 INFO - 'OURDRIVE': 'C:', 17:59:58 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 17:59:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 17:59:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 17:59:58 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 17:59:58 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 17:59:58 INFO - 'PROCESSOR_LEVEL': '6', 17:59:58 INFO - 'PROCESSOR_REVISION': '1e05', 17:59:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 17:59:58 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 17:59:58 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 17:59:58 INFO - 'PROGRAMW6432': 'C:\\Program Files', 17:59:58 INFO - 'PROMPT': '$P$G', 17:59:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 17:59:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 17:59:58 INFO - 'PUBLIC': 'C:\\Users\\Public', 17:59:58 INFO - 'PWD': 'C:\\slave\\test', 17:59:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 17:59:58 INFO - 'SLAVEDIR': 'C:\\slave\\', 17:59:58 INFO - 'SYSTEMDRIVE': 'C:', 17:59:58 INFO - 'SYSTEMROOT': 'C:\\windows', 17:59:58 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 17:59:58 INFO - 'TEST1': 'testie', 17:59:58 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 17:59:58 INFO - 'USERDOMAIN': 'T-W864-IX-077', 17:59:58 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 17:59:58 INFO - 'USERNAME': 'cltbld', 17:59:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 17:59:58 INFO - 'WINDIR': 'C:\\windows', 17:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 17:59:59 INFO - Ignoring indexes: http://pypi.python.org/simple/ 17:59:59 INFO - Downloading/unpacking pip>=1.5 18:00:07 INFO - Running setup.py egg_info for package pip 18:00:07 INFO - warning: no files found matching 'pip\cacert.pem' 18:00:07 INFO - warning: no files found matching '*.html' under directory 'docs' 18:00:07 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 18:00:07 INFO - no previously-included directories found matching 'docs\_build\_sources' 18:00:07 INFO - Installing collected packages: pip 18:00:07 INFO - Found existing installation: pip 0.8.2 18:00:07 INFO - Uninstalling pip: 18:00:07 INFO - Successfully uninstalled pip 18:00:07 INFO - Running setup.py install for pip 18:00:07 INFO - warning: no files found matching 'pip\cacert.pem' 18:00:07 INFO - warning: no files found matching '*.html' under directory 'docs' 18:00:07 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 18:00:07 INFO - no previously-included directories found matching 'docs\_build\_sources' 18:00:07 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 18:00:07 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 18:00:07 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 18:00:07 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 18:00:07 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 18:00:07 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 18:00:07 INFO - Successfully installed pip 18:00:07 INFO - Cleaning up... 18:00:07 INFO - Return code: 0 18:00:07 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv 18:00:07 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')]} 18:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:07 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')]} 18:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:07 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\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 0x023876B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02431EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02532D98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E68BF0>, '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 0x01E8A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028505C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-077', 'USERDOMAIN': 'T-W864-IX-077', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-077', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 18:00:07 INFO - Running command: ['C:\\slave\\test\\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\build 18:00:07 INFO - Copy/paste: C:\slave\test\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 18:00:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:00:07 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 18:00:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 18:00:07 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 18:00:07 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 18:00:07 INFO - 'COMPUTERNAME': 'T-W864-IX-077', 18:00:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:00:07 INFO - 'DCLOCATION': 'SCL3', 18:00:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:00:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:00:07 INFO - 'HOMEDRIVE': 'C:', 18:00:07 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 18:00:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:00:07 INFO - 'KTS_VERSION': '1.19c', 18:00:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 18:00:07 INFO - 'LOGONSERVER': '\\\\T-W864-IX-077', 18:00:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:00:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:07 INFO - 'MOZ_NO_REMOTE': '1', 18:00:07 INFO - 'NO_EM_RESTART': '1', 18:00:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:00:07 INFO - 'OS': 'Windows_NT', 18:00:07 INFO - 'OURDRIVE': 'C:', 18:00:07 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 18:00:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:00:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:00:07 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 18:00:07 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 18:00:07 INFO - 'PROCESSOR_LEVEL': '6', 18:00:07 INFO - 'PROCESSOR_REVISION': '1e05', 18:00:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:00:07 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 18:00:07 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 18:00:07 INFO - 'PROGRAMW6432': 'C:\\Program Files', 18:00:07 INFO - 'PROMPT': '$P$G', 18:00:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:00:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:00:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:00:07 INFO - 'PWD': 'C:\\slave\\test', 18:00:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:00:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:00:07 INFO - 'SYSTEMDRIVE': 'C:', 18:00:07 INFO - 'SYSTEMROOT': 'C:\\windows', 18:00:07 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:07 INFO - 'TEST1': 'testie', 18:00:07 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:07 INFO - 'USERDOMAIN': 'T-W864-IX-077', 18:00:07 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 18:00:07 INFO - 'USERNAME': 'cltbld', 18:00:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 18:00:07 INFO - 'WINDIR': 'C:\\windows', 18:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:09 INFO - Downloading/unpacking psutil>=0.7.1 18:00:09 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 18:00:09 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 18:00:10 INFO - Installing collected packages: psutil 18:00:11 INFO - Successfully installed psutil 18:00:11 INFO - Cleaning up... 18:00:11 INFO - Return code: 0 18:00:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv 18:00:11 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')]} 18:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:11 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')]} 18:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:11 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\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 0x023876B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02431EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02532D98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E68BF0>, '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 0x01E8A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028505C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-077', 'USERDOMAIN': 'T-W864-IX-077', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-077', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 18:00:11 INFO - Running command: ['C:\\slave\\test\\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\build 18:00:11 INFO - Copy/paste: C:\slave\test\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 18:00:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:00:11 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 18:00:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 18:00:11 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 18:00:11 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 18:00:11 INFO - 'COMPUTERNAME': 'T-W864-IX-077', 18:00:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:00:11 INFO - 'DCLOCATION': 'SCL3', 18:00:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:00:11 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:00:11 INFO - 'HOMEDRIVE': 'C:', 18:00:11 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 18:00:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:00:11 INFO - 'KTS_VERSION': '1.19c', 18:00:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 18:00:11 INFO - 'LOGONSERVER': '\\\\T-W864-IX-077', 18:00:11 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:00:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:11 INFO - 'MOZ_NO_REMOTE': '1', 18:00:11 INFO - 'NO_EM_RESTART': '1', 18:00:11 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:00:11 INFO - 'OS': 'Windows_NT', 18:00:11 INFO - 'OURDRIVE': 'C:', 18:00:11 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 18:00:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:00:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:00:11 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 18:00:11 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 18:00:11 INFO - 'PROCESSOR_LEVEL': '6', 18:00:11 INFO - 'PROCESSOR_REVISION': '1e05', 18:00:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:00:11 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 18:00:11 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 18:00:11 INFO - 'PROGRAMW6432': 'C:\\Program Files', 18:00:11 INFO - 'PROMPT': '$P$G', 18:00:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:00:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:00:11 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:00:11 INFO - 'PWD': 'C:\\slave\\test', 18:00:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:00:11 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:00:11 INFO - 'SYSTEMDRIVE': 'C:', 18:00:11 INFO - 'SYSTEMROOT': 'C:\\windows', 18:00:11 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:11 INFO - 'TEST1': 'testie', 18:00:11 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:11 INFO - 'USERDOMAIN': 'T-W864-IX-077', 18:00:11 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 18:00:11 INFO - 'USERNAME': 'cltbld', 18:00:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 18:00:11 INFO - 'WINDIR': 'C:\\windows', 18:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:00:11 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 18:00:11 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 18:00:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:00:12 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 18:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 18:00:12 INFO - Installing collected packages: mozsystemmonitor 18:00:12 INFO - Running setup.py install for mozsystemmonitor 18:00:12 INFO - Successfully installed mozsystemmonitor 18:00:12 INFO - Cleaning up... 18:00:12 INFO - Return code: 0 18:00:12 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 18:00:12 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')]} 18:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:12 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')]} 18:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:12 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\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 0x023876B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02431EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02532D98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E68BF0>, '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 0x01E8A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028505C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-077', 'USERDOMAIN': 'T-W864-IX-077', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-077', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 18:00:12 INFO - Running command: ['C:\\slave\\test\\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\build 18:00:12 INFO - Copy/paste: C:\slave\test\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 18:00:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:00:12 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 18:00:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 18:00:12 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 18:00:12 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 18:00:12 INFO - 'COMPUTERNAME': 'T-W864-IX-077', 18:00:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:00:12 INFO - 'DCLOCATION': 'SCL3', 18:00:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:00:12 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:00:12 INFO - 'HOMEDRIVE': 'C:', 18:00:12 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 18:00:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:00:12 INFO - 'KTS_VERSION': '1.19c', 18:00:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 18:00:12 INFO - 'LOGONSERVER': '\\\\T-W864-IX-077', 18:00:12 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:00:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:12 INFO - 'MOZ_NO_REMOTE': '1', 18:00:12 INFO - 'NO_EM_RESTART': '1', 18:00:12 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:00:12 INFO - 'OS': 'Windows_NT', 18:00:12 INFO - 'OURDRIVE': 'C:', 18:00:12 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 18:00:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:00:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:00:12 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 18:00:12 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 18:00:12 INFO - 'PROCESSOR_LEVEL': '6', 18:00:12 INFO - 'PROCESSOR_REVISION': '1e05', 18:00:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:00:12 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 18:00:12 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 18:00:12 INFO - 'PROGRAMW6432': 'C:\\Program Files', 18:00:12 INFO - 'PROMPT': '$P$G', 18:00:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:00:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:00:12 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:00:12 INFO - 'PWD': 'C:\\slave\\test', 18:00:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:00:12 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:00:12 INFO - 'SYSTEMDRIVE': 'C:', 18:00:12 INFO - 'SYSTEMROOT': 'C:\\windows', 18:00:12 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:12 INFO - 'TEST1': 'testie', 18:00:12 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:12 INFO - 'USERDOMAIN': 'T-W864-IX-077', 18:00:12 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 18:00:12 INFO - 'USERNAME': 'cltbld', 18:00:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 18:00:12 INFO - 'WINDIR': 'C:\\windows', 18:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:13 INFO - Downloading/unpacking blobuploader==1.2.4 18:00:13 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 18:00:13 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 18:00:14 INFO - Downloading blobuploader-1.2.4.tar.gz 18:00:14 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 18:00:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:00:14 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 18:00:14 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 18:00:14 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 18:00:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:00:14 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 18:00:14 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 18:00:14 INFO - Downloading docopt-0.6.1.tar.gz 18:00:14 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 18:00:14 INFO - Installing collected packages: blobuploader, requests, docopt 18:00:14 INFO - Running setup.py install for blobuploader 18:00:15 INFO - Running setup.py install for requests 18:00:16 INFO - Running setup.py install for docopt 18:00:16 INFO - Successfully installed blobuploader requests docopt 18:00:16 INFO - Cleaning up... 18:00:17 INFO - Return code: 0 18:00:17 INFO - Installing None into virtualenv C:\slave\test\build/venv 18:00:17 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')]} 18:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:17 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')]} 18:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:17 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\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 0x023876B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02431EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02532D98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E68BF0>, '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 0x01E8A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028505C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-077', 'USERDOMAIN': 'T-W864-IX-077', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-077', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 18:00:17 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\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\build\tests\config 18:00:17 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\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 18:00:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:00:17 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 18:00:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 18:00:17 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 18:00:17 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 18:00:17 INFO - 'COMPUTERNAME': 'T-W864-IX-077', 18:00:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:00:17 INFO - 'DCLOCATION': 'SCL3', 18:00:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:00:17 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:00:17 INFO - 'HOMEDRIVE': 'C:', 18:00:17 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 18:00:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:00:17 INFO - 'KTS_VERSION': '1.19c', 18:00:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 18:00:17 INFO - 'LOGONSERVER': '\\\\T-W864-IX-077', 18:00:17 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:00:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:17 INFO - 'MOZ_NO_REMOTE': '1', 18:00:17 INFO - 'NO_EM_RESTART': '1', 18:00:17 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:00:17 INFO - 'OS': 'Windows_NT', 18:00:17 INFO - 'OURDRIVE': 'C:', 18:00:17 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 18:00:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:00:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:00:17 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 18:00:17 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 18:00:17 INFO - 'PROCESSOR_LEVEL': '6', 18:00:17 INFO - 'PROCESSOR_REVISION': '1e05', 18:00:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:00:17 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 18:00:17 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 18:00:17 INFO - 'PROGRAMW6432': 'C:\\Program Files', 18:00:17 INFO - 'PROMPT': '$P$G', 18:00:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:00:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:00:17 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:00:17 INFO - 'PWD': 'C:\\slave\\test', 18:00:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:00:17 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:00:17 INFO - 'SYSTEMDRIVE': 'C:', 18:00:17 INFO - 'SYSTEMROOT': 'C:\\windows', 18:00:17 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:17 INFO - 'TEST1': 'testie', 18:00:17 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:17 INFO - 'USERDOMAIN': 'T-W864-IX-077', 18:00:17 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 18:00:17 INFO - 'USERNAME': 'cltbld', 18:00:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 18:00:17 INFO - 'WINDIR': 'C:\\windows', 18:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 18:00:17 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-_ubmu5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 18:00:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 18:00:17 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-8vmon6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 18:00:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 18:00:18 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-pidjev-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 18:00:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 18:00:18 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-1vhmwh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 18:00:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 18:00:18 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ubyzf_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 18:00:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 18:00:18 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-li_hw9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 18:00:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 18:00:18 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-wfnuhp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 18:00:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 18:00:18 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-eqdjfx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 18:00:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 18:00:18 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-46w_or-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 18:00:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 18:00:18 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ryljgu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 18:00:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 18:00:18 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-j9kyvd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 18:00:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 18:00:19 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ovixla-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 18:00:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 18:00:19 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-iccrlv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 18:00:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 18:00:19 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-_aq6ux-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 18:00:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 18:00:19 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-5tohxr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 18:00:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 18:00:19 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-no7aji-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 18:00:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 18:00:19 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-mxthro-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 18:00:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:00:19 INFO - Running setup.py install for manifestparser 18:00:19 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 18:00:19 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 18:00:20 INFO - Running setup.py install for mozcrash 18:00:20 INFO - Running setup.py install for mozdebug 18:00:21 INFO - Running setup.py install for mozdevice 18:00:21 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 18:00:21 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 18:00:21 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 18:00:21 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 18:00:21 INFO - Running setup.py install for mozfile 18:00:22 INFO - Running setup.py install for mozhttpd 18:00:22 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 18:00:22 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 18:00:22 INFO - Running setup.py install for mozinfo 18:00:22 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 18:00:22 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 18:00:22 INFO - Running setup.py install for mozInstall 18:00:23 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 18:00:23 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 18:00:23 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 18:00:23 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 18:00:23 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 18:00:23 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 18:00:23 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 18:00:23 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 18:00:23 INFO - Running setup.py install for mozleak 18:00:23 INFO - Running setup.py install for mozlog 18:00:24 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 18:00:24 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 18:00:24 INFO - Running setup.py install for moznetwork 18:00:24 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 18:00:24 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 18:00:25 INFO - Running setup.py install for mozprocess 18:00:25 INFO - Running setup.py install for mozprofile 18:00:25 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 18:00:25 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 18:00:25 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 18:00:25 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 18:00:25 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 18:00:25 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 18:00:26 INFO - Running setup.py install for mozrunner 18:00:26 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 18:00:26 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 18:00:27 INFO - Running setup.py install for mozscreenshot 18:00:27 INFO - Running setup.py install for moztest 18:00:28 INFO - Running setup.py install for mozversion 18:00:28 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 18:00:28 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 18:00:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:00:28 INFO - Cleaning up... 18:00:28 INFO - Return code: 0 18:00:28 INFO - Installing None into virtualenv C:\slave\test\build/venv 18:00:28 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')]} 18:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:28 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')]} 18:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:28 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\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 0x023876B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02431EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02532D98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E68BF0>, '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 0x01E8A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028505C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-077', 'USERDOMAIN': 'T-W864-IX-077', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-077', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 18:00:28 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\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\build\tests\config 18:00:28 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\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 18:00:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:00:28 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 18:00:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 18:00:28 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 18:00:28 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 18:00:28 INFO - 'COMPUTERNAME': 'T-W864-IX-077', 18:00:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:00:28 INFO - 'DCLOCATION': 'SCL3', 18:00:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:00:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:00:28 INFO - 'HOMEDRIVE': 'C:', 18:00:28 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 18:00:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:00:28 INFO - 'KTS_VERSION': '1.19c', 18:00:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 18:00:28 INFO - 'LOGONSERVER': '\\\\T-W864-IX-077', 18:00:28 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:00:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:28 INFO - 'MOZ_NO_REMOTE': '1', 18:00:28 INFO - 'NO_EM_RESTART': '1', 18:00:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:00:28 INFO - 'OS': 'Windows_NT', 18:00:28 INFO - 'OURDRIVE': 'C:', 18:00:28 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 18:00:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:00:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:00:28 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 18:00:28 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 18:00:28 INFO - 'PROCESSOR_LEVEL': '6', 18:00:28 INFO - 'PROCESSOR_REVISION': '1e05', 18:00:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:00:28 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 18:00:28 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 18:00:28 INFO - 'PROGRAMW6432': 'C:\\Program Files', 18:00:28 INFO - 'PROMPT': '$P$G', 18:00:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:00:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:00:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:00:28 INFO - 'PWD': 'C:\\slave\\test', 18:00:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:00:28 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:00:28 INFO - 'SYSTEMDRIVE': 'C:', 18:00:28 INFO - 'SYSTEMROOT': 'C:\\windows', 18:00:28 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:28 INFO - 'TEST1': 'testie', 18:00:28 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:28 INFO - 'USERDOMAIN': 'T-W864-IX-077', 18:00:28 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 18:00:28 INFO - 'USERNAME': 'cltbld', 18:00:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 18:00:28 INFO - 'WINDIR': 'C:\\windows', 18:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 18:00:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-zyrufu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 18:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1)) 18:00:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 18:00:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-rwmu1w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 18:00:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 18:00:28 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 18:00:28 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-jzn8ei-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 18:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 18:00:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 18:00:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-yenv4y-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 18:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 18:00:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 18:00:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-lykizs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 18:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5)) 18:00:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 18:00:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-9dknyh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 18:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6)) 18:00:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 18:00:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ktyrrr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 18:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7)) 18:00:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 18:00:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-qqnxhf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 18:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8)) 18:00:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 18:00:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-crw3id-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 18:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9)) 18:00:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 18:00:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-kffp65-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 18:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 18:00:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 18:00:29 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-4zcgwh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 18:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11)) 18:00:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 18:00:30 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-9xvjob-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 18:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12)) 18:00:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 18:00:30 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-239cx_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 18:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13)) 18:00:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 18:00:30 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-10ihh2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 18:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 18:00:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 18:00:30 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-godqnm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 18:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15)) 18:00:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 18:00:30 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-kactjz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 18:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16)) 18:00:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 18:00:30 INFO - Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-lkopun-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 18:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17)) 18:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 18:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 18:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 18:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 18:00:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 18:00:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 18:00: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 18:00: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 18:00:31 INFO - Downloading blessings-1.6.tar.gz 18:00:31 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 18:00:32 INFO - Installing collected packages: blessings 18:00:32 INFO - Running setup.py install for blessings 18:00:32 INFO - Successfully installed blessings 18:00:32 INFO - Cleaning up... 18:00:35 INFO - Return code: 0 18:00:35 INFO - Done creating virtualenv C:\slave\test\build/venv. 18:00:35 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 18:00:35 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 18:00:35 INFO - Reading from file tmpfile_stdout 18:00:35 INFO - Using _rmtree_windows ... 18:00:35 INFO - Using _rmtree_windows ... 18:00:35 INFO - Current package versions: 18:00:35 INFO - blessings == 1.6 18:00:35 INFO - blobuploader == 1.2.4 18:00:35 INFO - docopt == 0.6.1 18:00:35 INFO - manifestparser == 1.1 18:00:35 INFO - mozInstall == 1.12 18:00:35 INFO - mozcrash == 0.16 18:00:35 INFO - mozdebug == 0.1 18:00:35 INFO - mozdevice == 0.48 18:00:35 INFO - mozfile == 1.2 18:00:35 INFO - mozhttpd == 0.7 18:00:35 INFO - mozinfo == 0.9 18:00:35 INFO - mozleak == 0.1 18:00:35 INFO - mozlog == 3.1 18:00:35 INFO - moznetwork == 0.27 18:00:35 INFO - mozprocess == 0.22 18:00:35 INFO - mozprofile == 0.28 18:00:35 INFO - mozrunner == 6.11 18:00:35 INFO - mozscreenshot == 0.1 18:00:35 INFO - mozsystemmonitor == 0.0 18:00:35 INFO - moztest == 0.7 18:00:35 INFO - mozversion == 1.4 18:00:35 INFO - psutil == 3.1.1 18:00:35 INFO - requests == 1.2.3 18:00:35 INFO - Installing None into virtualenv C:\slave\test\build/venv 18:00: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')]} 18:00:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00: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')]} 18:00:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:35 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\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 0x023876B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02431EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02532D98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E68BF0>, '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 0x01E8A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028505C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-077', 'USERDOMAIN': 'T-W864-IX-077', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-077', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 18:00:35 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\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\build\tests\talos 18:00:35 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\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 18:00:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:00:35 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 18:00:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 18:00:35 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 18:00:35 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 18:00:35 INFO - 'COMPUTERNAME': 'T-W864-IX-077', 18:00:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:00:35 INFO - 'DCLOCATION': 'SCL3', 18:00:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:00:35 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:00:35 INFO - 'HOMEDRIVE': 'C:', 18:00:35 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 18:00:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:00:35 INFO - 'KTS_VERSION': '1.19c', 18:00:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 18:00:35 INFO - 'LOGONSERVER': '\\\\T-W864-IX-077', 18:00:35 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:00:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:00:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:35 INFO - 'MOZ_NO_REMOTE': '1', 18:00:35 INFO - 'NO_EM_RESTART': '1', 18:00:35 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:00:35 INFO - 'OS': 'Windows_NT', 18:00:35 INFO - 'OURDRIVE': 'C:', 18:00:35 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 18:00:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:00:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:00:35 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 18:00:35 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 18:00:35 INFO - 'PROCESSOR_LEVEL': '6', 18:00:35 INFO - 'PROCESSOR_REVISION': '1e05', 18:00:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:00:35 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 18:00:35 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 18:00:35 INFO - 'PROGRAMW6432': 'C:\\Program Files', 18:00:35 INFO - 'PROMPT': '$P$G', 18:00:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:00:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:00:35 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:00:35 INFO - 'PWD': 'C:\\slave\\test', 18:00:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:00:35 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:00:35 INFO - 'SYSTEMDRIVE': 'C:', 18:00:35 INFO - 'SYSTEMROOT': 'C:\\windows', 18:00:35 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:35 INFO - 'TEST1': 'testie', 18:00:35 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:35 INFO - 'USERDOMAIN': 'T-W864-IX-077', 18:00:35 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 18:00:35 INFO - 'USERNAME': 'cltbld', 18:00:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 18:00:35 INFO - 'WINDIR': 'C:\\windows', 18:00:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 18:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2)) 18:00:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3)) 18:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 18:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5)) 18:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6)) 18:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7)) 18:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8)) 18:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9)) 18:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 18:00:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 18:00:36 INFO - Cleaning up... 18:00:36 INFO - Return code: 0 18:00:36 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 18:00: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')]} 18:00:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00: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')]} 18:00:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:36 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\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 0x023876B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02431EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02532D98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E68BF0>, '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 0x01E8A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028505C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-077', 'USERDOMAIN': 'T-W864-IX-077', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-077', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 18:00:36 INFO - Running command: ['C:\\slave\\test\\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\build 18:00:36 INFO - Copy/paste: C:\slave\test\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 18:00:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:00:36 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 18:00:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 18:00:36 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 18:00:36 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 18:00:36 INFO - 'COMPUTERNAME': 'T-W864-IX-077', 18:00:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:00:36 INFO - 'DCLOCATION': 'SCL3', 18:00:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:00:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:00:36 INFO - 'HOMEDRIVE': 'C:', 18:00:36 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 18:00:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:00:36 INFO - 'KTS_VERSION': '1.19c', 18:00:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 18:00:36 INFO - 'LOGONSERVER': '\\\\T-W864-IX-077', 18:00:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:00:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:00:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:36 INFO - 'MOZ_NO_REMOTE': '1', 18:00:36 INFO - 'NO_EM_RESTART': '1', 18:00:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:00:36 INFO - 'OS': 'Windows_NT', 18:00:36 INFO - 'OURDRIVE': 'C:', 18:00:36 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 18:00:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:00:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:00:36 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 18:00:36 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 18:00:36 INFO - 'PROCESSOR_LEVEL': '6', 18:00:36 INFO - 'PROCESSOR_REVISION': '1e05', 18:00:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:00:36 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 18:00:36 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 18:00:36 INFO - 'PROGRAMW6432': 'C:\\Program Files', 18:00:36 INFO - 'PROMPT': '$P$G', 18:00:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:00:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:00:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:00:36 INFO - 'PWD': 'C:\\slave\\test', 18:00:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:00:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:00:36 INFO - 'SYSTEMDRIVE': 'C:', 18:00:36 INFO - 'SYSTEMROOT': 'C:\\windows', 18:00:36 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:36 INFO - 'TEST1': 'testie', 18:00:36 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:36 INFO - 'USERDOMAIN': 'T-W864-IX-077', 18:00:36 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 18:00:36 INFO - 'USERNAME': 'cltbld', 18:00:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 18:00:36 INFO - 'WINDIR': 'C:\\windows', 18:00:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:00:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:36 INFO - Downloading/unpacking jsonschema 18:00:36 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 18:00:36 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 18:00:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:00:37 INFO - Downloading/unpacking functools32 (from jsonschema) 18:00:37 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 18:00:37 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 18:00:37 INFO - Downloading functools32-3.2.3-2.tar.gz 18:00:37 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 18:00:37 INFO - warning: no files found matching '*.txt' 18:00:37 INFO - no previously-included directories found matching 'build' 18:00:37 INFO - no previously-included directories found matching 'dist' 18:00:37 INFO - no previously-included directories found matching '.git*' 18:00:37 INFO - Installing collected packages: jsonschema, functools32 18:00:37 INFO - Running setup.py install for functools32 18:00:37 INFO - warning: no files found matching '*.txt' 18:00:37 INFO - no previously-included directories found matching 'build' 18:00:37 INFO - no previously-included directories found matching 'dist' 18:00:37 INFO - no previously-included directories found matching '.git*' 18:00:37 INFO - Successfully installed jsonschema functools32 18:00:37 INFO - Cleaning up... 18:00:38 INFO - Return code: 0 18:00:38 INFO - Installing flake8 into virtualenv C:\slave\test\build/venv 18:00:38 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')]} 18:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:00:38 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')]} 18:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:00:38 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\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 0x023876B0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x02431EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x02532D98>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E68BF0>, '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 0x01E8A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028505C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-077', 'USERDOMAIN': 'T-W864-IX-077', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files (x86)', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-077', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'PROGRAMDATA': 'C:\\ProgramData', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROCESSOR_ARCHITEW6432': 'AMD64', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 18:00:38 INFO - Running command: ['C:\\slave\\test\\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\build 18:00:38 INFO - Copy/paste: C:\slave\test\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 18:00:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:00:38 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 18:00:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 18:00:38 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 18:00:38 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 18:00:38 INFO - 'COMPUTERNAME': 'T-W864-IX-077', 18:00:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:00:38 INFO - 'DCLOCATION': 'SCL3', 18:00:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:00:38 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:00:38 INFO - 'HOMEDRIVE': 'C:', 18:00:38 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 18:00:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:00:38 INFO - 'KTS_VERSION': '1.19c', 18:00:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 18:00:38 INFO - 'LOGONSERVER': '\\\\T-W864-IX-077', 18:00:38 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:00:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:38 INFO - 'MOZ_NO_REMOTE': '1', 18:00:38 INFO - 'NO_EM_RESTART': '1', 18:00:38 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:00:38 INFO - 'OS': 'Windows_NT', 18:00:38 INFO - 'OURDRIVE': 'C:', 18:00:38 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 18:00:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:00:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:00:38 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 18:00:38 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 18:00:38 INFO - 'PROCESSOR_LEVEL': '6', 18:00:38 INFO - 'PROCESSOR_REVISION': '1e05', 18:00:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:00:38 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 18:00:38 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 18:00:38 INFO - 'PROGRAMW6432': 'C:\\Program Files', 18:00:38 INFO - 'PROMPT': '$P$G', 18:00:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:00:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:00:38 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:00:38 INFO - 'PWD': 'C:\\slave\\test', 18:00:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:00:38 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:00:38 INFO - 'SYSTEMDRIVE': 'C:', 18:00:38 INFO - 'SYSTEMROOT': 'C:\\windows', 18:00:38 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:38 INFO - 'TEST1': 'testie', 18:00:38 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:38 INFO - 'USERDOMAIN': 'T-W864-IX-077', 18:00:38 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 18:00:38 INFO - 'USERNAME': 'cltbld', 18:00:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 18:00:38 INFO - 'WINDIR': 'C:\\windows', 18:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:00:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:00:38 INFO - Downloading/unpacking flake8 18:00:38 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 18:00:38 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 18:00:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:00:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:00:39 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 18:00:39 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 18:00:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:00:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:00:39 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 18:00:39 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 18:00:39 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:00:39 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 18:00:39 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 18:00:40 INFO - Running setup.py (path:C:\slave\test\build\venv\build\pep8\setup.py) egg_info for package pep8 18:00:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:00:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:00:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:00:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:00:40 INFO - no previously-included directories found matching 'docs\_build' 18:00:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:00:40 INFO - Running setup.py install for pep8 18:00:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:00:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:00:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:00:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:00:40 INFO - no previously-included directories found matching 'docs\_build' 18:00:40 INFO - Installing pep8-script.py script to C:\slave\test\build\venv\Scripts 18:00:40 INFO - Installing pep8.exe script to C:\slave\test\build\venv\Scripts 18:00:40 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:00:40 INFO - Cleaning up... 18:00:41 INFO - Return code: 0 18:00:41 INFO - Running post-action listener: _resource_record_post_action 18:00:41 INFO - Running post-action listener: _start_resource_monitoring 18:00:41 INFO - Starting resource monitoring. 18:00:41 INFO - ##### 18:00:41 INFO - ##### Running install step. 18:00:41 INFO - ##### 18:00:41 INFO - Running pre-action listener: _resource_record_pre_action 18:00:41 INFO - Running main action method: install 18:00:41 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 18:00:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 18:00:41 INFO - Reading from file tmpfile_stdout 18:00:41 INFO - Using _rmtree_windows ... 18:00:41 INFO - Using _rmtree_windows ... 18:00:41 INFO - Detecting whether we're running mozinstall >=1.0... 18:00:41 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 18:00:41 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 18:00:41 INFO - Reading from file tmpfile_stdout 18:00:41 INFO - Output received: 18:00:41 INFO - Usage: mozinstall-script.py [options] installer 18:00:41 INFO - Options: 18:00:41 INFO - -h, --help show this help message and exit 18:00:41 INFO - -d DEST, --destination=DEST 18:00:41 INFO - Directory to install application into. [default: 18:00:41 INFO - "C:\slave\test"] 18:00:41 INFO - --app=APP Application being installed. [default: firefox] 18:00:41 INFO - Using _rmtree_windows ... 18:00:41 INFO - Using _rmtree_windows ... 18:00:41 INFO - mkdir: C:\slave\test\build\application 18:00:41 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-46.0.en-US.win64.zip', '--destination', 'C:\\slave\\test\\build\\application'] 18:00:41 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-46.0.en-US.win64.zip --destination C:\slave\test\build\application 18:00:43 INFO - Reading from file tmpfile_stdout 18:00:43 INFO - Output received: 18:00:43 INFO - C:\slave\test\build\application\firefox\firefox.exe 18:00:43 INFO - Using _rmtree_windows ... 18:00:43 INFO - Using _rmtree_windows ... 18:00:43 INFO - Running post-action listener: _resource_record_post_action 18:00:43 INFO - ##### 18:00:43 INFO - ##### Running run-tests step. 18:00:43 INFO - ##### 18:00:43 INFO - Running pre-action listener: _resource_record_pre_action 18:00:43 INFO - Running main action method: run_tests 18:00:43 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 18:00:43 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 18:00:43 INFO - Python 2.7.3 18:00:43 INFO - Return code: 0 18:00:43 INFO - grabbing minidump binary from tooltool 18:00:43 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')]} 18:00:43 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\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01E68BF0>, '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 0x01E8A9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x028505C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 18:00:43 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\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build 18:00:43 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\build\tests\config/tooltool-manifests/win32/releng.manifest -o 18:00:43 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 18:00:43 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpa3ag1i 18:00:43 INFO - INFO - File integrity verified, renaming tmpa3ag1i to win32-minidump_stackwalk.exe 18:00:43 INFO - Return code: 0 18:00:43 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 18:00:43 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 18:00:43 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 18:00:43 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 18:00:43 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 18:00:43 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\flake8', 'C:\\slave\\test\\build\\tests\\talos\\talos'] 18:00:43 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\flake8 C:\slave\test\build\tests\talos\talos 18:00:44 INFO - Return code: 0 18:00:44 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'svgr', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\svgr_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\svgr_raw.log'] in C:\slave\test\build 18:00:44 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Mozilla-Beta --suite svgr --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.crashreporter-symbols.zip --title T-W864-IX-077 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\svgr_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\svgr_raw.log 18:00:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 18:00:44 INFO - 'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Roaming', 18:00:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files', 18:00:44 INFO - 'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files', 18:00:44 INFO - 'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files', 18:00:44 INFO - 'COMPUTERNAME': 'T-W864-IX-077', 18:00:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 18:00:44 INFO - 'DCLOCATION': 'SCL3', 18:00:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 18:00:44 INFO - 'FP_NO_HOST_CHECK': 'NO', 18:00:44 INFO - 'HOMEDRIVE': 'C:', 18:00:44 INFO - 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-077', 18:00:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 18:00:44 INFO - 'KTS_VERSION': '1.19c', 18:00:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-077\\AppData\\Local', 18:00:44 INFO - 'LOGONSERVER': '\\\\T-W864-IX-077', 18:00:44 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 18:00:44 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 18:00:44 INFO - 'MONDIR': 'C:\\Monitor_config\\', 18:00:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 18:00:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:00:44 INFO - 'MOZ_NO_REMOTE': '1', 18:00:44 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 18:00:44 INFO - 'NO_EM_RESTART': '1', 18:00:44 INFO - 'NUMBER_OF_PROCESSORS': '8', 18:00:44 INFO - 'OS': 'Windows_NT', 18:00:44 INFO - 'OURDRIVE': 'C:', 18:00:44 INFO - 'PATH': 'C:\\Program Files (x86)\\NVIDIA Corporation\\PhysX\\Common;C:\\windows\\system32;C:\\windows;C:\\windows\\System32\\Wbem;C:\\windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\vim\\vim72;C:\\mozilla-build\\wget;C:\\mozilla-build\\info-zip;C:\\CoreUtils\\bin;C:\\mozilla-build\\buildbotve\\scripts;C:\\mozilla-build\\hg', 18:00:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 18:00:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 18:00:44 INFO - 'PROCESSOR_ARCHITEW6432': 'AMD64', 18:00:44 INFO - 'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel', 18:00:44 INFO - 'PROCESSOR_LEVEL': '6', 18:00:44 INFO - 'PROCESSOR_REVISION': '1e05', 18:00:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 18:00:44 INFO - 'PROGRAMFILES': 'C:\\Program Files (x86)', 18:00:44 INFO - 'PROGRAMFILES(X86)': 'C:\\Program Files (x86)', 18:00:44 INFO - 'PROGRAMW6432': 'C:\\Program Files', 18:00:44 INFO - 'PROMPT': '$P$G', 18:00:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 18:00:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 18:00:44 INFO - 'PUBLIC': 'C:\\Users\\Public', 18:00:44 INFO - 'PWD': 'C:\\slave\\test', 18:00:44 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 18:00:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 18:00:44 INFO - 'SLAVEDIR': 'C:\\slave\\', 18:00:44 INFO - 'SYSTEMDRIVE': 'C:', 18:00:44 INFO - 'SYSTEMROOT': 'C:\\windows', 18:00:44 INFO - 'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:44 INFO - 'TEST1': 'testie', 18:00:44 INFO - 'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 18:00:44 INFO - 'USERDOMAIN': 'T-W864-IX-077', 18:00:44 INFO - 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-077', 18:00:44 INFO - 'USERNAME': 'cltbld', 18:00:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-077', 18:00:44 INFO - 'WINDIR': 'C:\\windows', 18:00:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 18:00:44 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'svgr', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-077', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\svgr_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\svgr_raw.log'] with output_timeout 3600 ProcessManager NOT managing child processes ProcessManager UNABLE to use job objects to manage child processes 18:00:45 INFO - mozversion application_buildid: 20160419135143 18:00:45 INFO - mozversion application_changeset: 96c726556dc80f4a43a8423a7dc1a612789eeabc 18:00:45 INFO - mozversion application_display_name: Firefox 18:00:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:00:45 INFO - mozversion application_name: Firefox 18:00:45 INFO - mozversion application_remotingname: firefox 18:00:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:00:45 INFO - mozversion application_vendor: Mozilla 18:00:45 INFO - mozversion application_version: 46.0 18:00:45 INFO - mozversion platform_buildid: 20160419135143 18:00:45 INFO - mozversion platform_changeset: 96c726556dc80f4a43a8423a7dc1a612789eeabc 18:00:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:00:45 INFO - mozversion platform_version: 46.0 18:00:45 INFO - using testdate: 1461114045 18:00:45 INFO - actual date: 1461114045 18:00:45 INFO - starting webserver on 'localhost:49278' 18:00:45 INFO - SUITE-START | Running 5 tests 18:00:45 INFO - TEST-START | tsvgx 18:00:45 INFO - operating with platform_type : w8_ 18:00:45 INFO - Initialising browser for tsvgx test... 18:00:46 INFO - TEST-INFO | started process 3912 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpkd1qwf\profile http://localhost:49278/getInfo.html) 18:00:54 INFO - PROCESS | 3912 | __metrics Screen width/height:1600/1200 18:00:54 INFO - PROCESS | 3912 | colorDepth:24 18:00:54 INFO - PROCESS | 3912 | Browser inner width/height: 1010/674 18:00:54 INFO - PROCESS | 3912 | __metrics 18:00:55 INFO - PROCESS | 3912 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:00:55 INFO - PROCESS | 3912 | console.error: 18:00:55 INFO - PROCESS | 3912 | Message: Error: Connection closed before committing the transaction. 18:00:55 INFO - PROCESS | 3912 | Stack: 18:00:55 INFO - PROCESS | 3912 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:933:23 18:00:55 INFO - PROCESS | 3912 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:812:7 18:00:55 INFO - PROCESS | 3912 | this.PromiseWalker.scheduleWalkerLoop/<@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:746:11 18:00:55 INFO - PROCESS | 3912 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:529:9 18:00:55 INFO - PROCESS | 3912 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 18:00:55 INFO - PROCESS | 3912 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:384:5 18:00:55 INFO - PROCESS | 3912 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 18:00:55 INFO - PROCESS | 3912 | Barrier/this.client.addBlocker/promisetsvgx: 119.36 18:16:17 INFO - RETURN: tsvgr_opacity: 272.42 18:16:17 INFO - RETURN: tart: 4.01 18:16:17 INFO - RETURN: tscrollx: 2.65 18:16:17 INFO - RETURN: cart: 22.73 18:16:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [147.0, 104.0, 98.0, 97.0, 102.0, 104.0, 101.0, 101.0, 99.0, 98.0, 100.0, 102.0, 99.0, 101.0, 98.0, 100.0, 100.0, 101.0, 96.0, 101.0, 101.0, 101.0, 99.0, 100.0, 100.0], "unit": "ms", "name": "gearflowers.svg", "value": 100.0}, {"lowerIsBetter": true, "replicates": [73.0, 35.0, 34.0, 36.0, 36.0, 34.0, 36.0, 35.0, 33.0, 37.0, 33.0, 35.0, 37.0, 34.0, 36.0, 34.0, 33.0, 36.0, 34.0, 33.0, 35.0, 33.0, 38.0, 34.0, 33.0], "unit": "ms", "name": "composite-scale.svg", "value": 34.0}, {"lowerIsBetter": true, "replicates": [45.0, 37.0, 35.0, 37.0, 34.0, 34.0, 37.0, 35.0, 34.0, 38.0, 36.0, 38.0, 36.0, 35.0, 38.0, 35.0, 33.0, 39.0, 36.0, 37.0, 37.0, 35.0, 36.0, 36.0, 37.0], "unit": "ms", "name": "composite-scale-opacity.svg", "value": 36.0}, {"lowerIsBetter": true, "replicates": [44.0, 40.0, 35.0, 37.0, 36.0, 34.0, 37.0, 36.0, 36.0, 38.0, 36.0, 37.0, 34.0, 35.0, 38.0, 35.0, 35.0, 37.0, 34.0, 37.0, 36.0, 37.0, 37.0, 35.0, 35.0], "unit": "ms", "name": "composite-scale-rotate.svg", "value": 36.0}, {"lowerIsBetter": true, "replicates": [46.0, 37.0, 37.0, 38.0, 36.0, 36.0, 38.0, 36.0, 40.0, 38.0, 36.0, 40.0, 36.0, 35.0, 39.0, 36.0, 38.0, 40.0, 36.0, 39.0, 38.0, 37.0, 38.0, 36.0, 35.0], "unit": "ms", "name": "composite-scale-rotate-opacity.svg", "value": 37.5}, {"lowerIsBetter": true, "replicates": [541.0, 359.0, 357.0, 342.0, 348.0, 360.0, 348.0, 352.0, 347.0, 346.0, 351.0, 350.0, 354.0, 344.0, 353.0, 340.0, 347.0, 355.0, 345.0, 345.0, 350.0, 344.0, 348.0, 355.0, 351.0], "unit": "ms", "name": "hixie-001.xml", "value": 349.0}, {"lowerIsBetter": true, "replicates": [360.0, 351.0, 347.0, 369.0, 352.0, 377.0, 350.0, 353.0, 352.0, 356.0, 345.0, 347.0, 350.0, 351.0, 349.0, 355.0, 356.0, 350.0, 348.0, 339.0, 351.0, 345.0, 342.0, 345.0, 346.0], "unit": "ms", "name": "hixie-002.xml", "value": 350.0}, {"lowerIsBetter": true, "replicates": [413.0, 180.0, 176.0, 179.0, 176.0, 176.0, 179.0, 180.0, 176.0, 175.0, 177.0, 179.0, 174.0, 177.0, 174.0, 178.0, 175.0, 180.0, 183.0, 175.0, 176.0, 178.0, 176.0, 177.0, 181.0], "unit": "ms", "name": "hixie-003.xml", "value": 177.0}, {"lowerIsBetter": true, "replicates": [323.0, 302.0, 297.0, 299.0, 301.0, 299.0, 302.0, 307.0, 293.0, 303.0, 296.0, 306.0, 309.0, 292.0, 298.0, 309.0, 301.0, 304.0, 300.0, 299.0, 303.0, 295.0, 297.0, 307.0, 298.0], "unit": "ms", "name": "hixie-004.xml", "value": 300.5}, {"lowerIsBetter": true, "replicates": [194.0, 191.0, 192.0, 192.0, 191.0, 191.0, 194.0, 192.0, 192.0, 192.0, 192.0, 195.0, 192.0, 192.0, 196.0, 192.0, 193.0, 195.0, 191.0, 192.0, 193.0, 190.0, 191.0, 193.0, 194.0], "unit": "ms", "name": "hixie-005.xml", "value": 192.0}, {"lowerIsBetter": true, "replicates": [198.0, 193.0, 197.0, 192.0, 198.0, 190.0, 197.0, 192.0, 192.0, 190.0, 195.0, 193.0, 192.0, 193.0, 194.0, 195.0, 193.0, 191.0, 194.0, 190.0, 195.0, 194.0, 197.0, 192.0, 195.0], "unit": "ms", "name": "hixie-006.xml", "value": 193.0}, {"lowerIsBetter": true, "replicates": [270.0, 203.0, 196.0, 209.0, 199.0, 199.0, 197.0, 197.0, 202.0, 213.0, 196.0, 200.0, 202.0, 201.0, 197.0, 201.0, 193.0, 203.0, 200.0, 200.0, 198.0, 202.0, 200.0, 201.0, 199.0], "unit": "ms", "name": "hixie-007.xml", "value": 200.0}], "name": "tsvgx", "value": 119.36242552123238}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [431.0, 223.0, 217.0, 70.0, 278.0, 219.0, 222.0, 227.0, 222.0, 229.0, 221.0, 219.0, 223.0, 219.0, 220.0, 220.0, 222.0, 216.0, 226.0, 221.0, 222.0, 219.0, 222.0, 218.0, 222.0], "unit": "ms", "name": "big-optimizable-group-opacity-2500.svg", "value": 221.5}, {"lowerIsBetter": true, "replicates": [322.0, 332.0, 332.0, 343.0, 332.0, 337.0, 333.0, 335.0, 332.0, 339.0, 334.0, 335.0, 333.0, 335.0, 333.0, 335.0, 331.0, 339.0, 333.0, 335.0, 335.0, 380.0, 340.0, 336.0, 333.0], "unit": "ms", "name": "small-group-opacity-2500.svg", "value": 335.0}], "name": "tsvgr_opacity", "value": 272.4227496021498}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [1.676641383032868, 1.717846936254359, 1.7107679721187143, 1.7002814149155336, 1.6813996373743252, 1.710615503437379, 1.7298311600044591, 1.7086051562253166, 1.7240597009658813, 1.6956076086431309, 1.7374652873224288, 1.7306621928713215, 1.7148733822738422, 1.7029581052415512, 1.687443455060323, 1.7172222226413327, 1.7101016237455255, 1.700964703279383, 1.7052112887887394, 1.7243347950835726, 1.7298082273397872, 1.711016446352005, 1.7259682345746168, 1.714088434682173, 1.7170560075275934], "unit": "ms", "name": "simple-open-DPI1.half.TART", "value": 1.712552440517089}, {"lowerIsBetter": true, "replicates": [1.9189794944293463, 1.98288935931154, 1.9826950421700111, 2.0055596735328436, 1.9511094815803296, 1.9744420115764325, 1.9765835431905894, 1.9774549300854023, 1.9952279338540957, 1.9874645905901296, 1.9939449572747991, 1.9715590669558598, 1.9710096405102657, 1.9879468365233073, 1.9884260929832163, 2.0037266789004207, 1.9599444747881125, 1.9790440889505239, 1.9611111296952226, 1.9747521519660949, 1.9917778922605884, 1.9843181898427564, 1.9729000971867487, 1.9716004041525035, 1.964517110127669], "unit": "ms", "name": "simple-open-DPI1.all.TART", "value": 1.978249509517963}, {"lowerIsBetter": true, "replicates": [27.350000000000364, 26.06500000000233, 28.0, 26.889999999999418, 27.87000000000262, 26.919999999998254, 27.179999999993015, 27.289999999993597, 27.63499999999476, 26.695000000006985, 27.54000000000815, 26.654999999998836, 26.58499999999185, 26.705000000016298, 26.779999999998836, 27.26500000001397, 27.10000000000582, 27.705000000016298, 27.27499999999418, 26.989999999990687, 27.31500000000233, 26.294999999983702, 26.779999999969732, 26.675000000046566, 25.789999999979045], "unit": "ms", "name": "simple-open-DPI1.error.TART", "value": 26.95499999999447}, {"lowerIsBetter": true, "replicates": [1.3053173750973819, 1.333025092366098, 1.3376930300579515, 1.3396308851796528, 1.2980127669452282, 1.3391173911649128, 1.340340843034345, 1.343600371549296, 1.3520885400993878, 1.3464580372322437, 1.416086617039471, 1.3483691049176594, 1.3568368953817032, 1.346609846104023, 1.3963882305535926, 1.3499452915302543, 1.3548400935004739, 1.3357219065742931, 1.3393853071124056, 1.343095809914345, 1.3383895851844965, 1.3445023173509643, 1.343118131160736, 1.3328573676361435, 1.3518251042033351], "unit": "ms", "name": "simple-close-DPI1.half.TART", "value": 1.3433592513550159}, {"lowerIsBetter": true, "replicates": [1.3991334860665459, 1.4428120956701391, 1.4420915288083693, 1.453970153416906, 1.4247537262217944, 1.4404719521017635, 1.4488909399721044, 1.4379743135463425, 1.4581461399793625, 1.4570352882146835, 1.6038289085814827, 1.4586261418603716, 1.4409779345287996, 1.4391866831218496, 1.4651047319709183, 1.448182016434754, 1.4423761395847097, 1.4394374090082505, 1.4504632900452472, 1.450645065166541, 1.4264925042091057, 1.4459484554606783, 1.4495248716963818, 1.4286729926951447, 1.4458208455758936], "unit": "ms", "name": "simple-close-DPI1.all.TART", "value": 1.4458846505182859}, {"lowerIsBetter": true, "replicates": [17.665000000000873, 18.2400000000016, 17.965000000003783, 17.404999999998836, 18.064999999995052, 17.919999999998254, 17.760000000009313, 19.08499999999185, 17.769999999989523, 17.619999999995343, 16.760000000009313, 17.89999999999418, 17.76500000001397, 17.68499999999767, 17.420000000012806, 17.769999999989523, 18.079999999987194, 17.485000000015134, 17.875, 17.89999999999418, 16.820000000006985, 17.270000000018626, 17.71999999997206, 17.21500000002561, 18.76500000001397], "unit": "ms", "name": "simple-close-DPI1.error.TART", "value": 17.767500000001746}, {"lowerIsBetter": true, "replicates": [1.6669133970702903, 1.7172279482457176, 1.7091980997253866, 1.7249537919884297, 1.672228177388509, 1.7611956144824172, 1.7298770004243993, 1.7237788598928878, 1.735413464147653, 1.7975730039179325, 1.739792211731868, 1.7442181887048664, 1.7504087307236411, 1.7184372517599988, 1.7227414935382444, 1.7355051663384509, 1.729384112713942, 1.7435233130383847, 1.7478603648416924, 1.7144329144674189, 1.7187754129295918, 1.7462138244600007, 1.737396505341601, 1.7108752709977768, 1.741792445752158], "unit": "ms", "name": "icon-open-DPI1.half.TART", "value": 1.7326452322860262}, {"lowerIsBetter": true, "replicates": [2.0161508528266365, 2.039152415025802, 2.045401782989502, 2.060320693639017, 2.054399661064148, 2.0860156479889786, 2.04144115958895, 2.077882978974319, 2.056878751754761, 2.177321545216216, 2.076142099595839, 2.0551369314193724, 2.091068528714727, 2.0706556725308176, 2.121552409695797, 2.0611211614608766, 2.081573837226437, 2.0591950492858886, 2.0582365713119506, 2.099771450777523, 2.055434913635254, 2.0657230348587037, 2.035205760645488, 2.045140652656555, 2.097709816010272], "unit": "ms", "name": "icon-open-DPI1.all.TART", "value": 2.060720927549947}, {"lowerIsBetter": true, "replicates": [27.63000000000102, 27.510000000002037, 29.50999999999476, 27.18499999999767, 28.29500000000553, 26.845000000001164, 27.505000000004657, 27.57500000001164, 27.454999999987194, 31.07499999999709, 27.839999999996508, 27.31500000000233, 26.83499999999185, 28.255000000004657, 31.310000000026776, 27.970000000001164, 28.495000000024447, 27.714999999996508, 27.614999999990687, 26.630000000004657, 27.394999999989523, 28.494999999995343, 26.714999999967404, 27.324999999953434, 27.625], "unit": "ms", "name": "icon-open-DPI1.error.TART", "value": 27.595000000001164}, {"lowerIsBetter": true, "replicates": [1.291650112469991, 1.3565703574348897, 1.3341285346568317, 1.3298913076006134, 1.4064117103088192, 1.324259312673547, 1.3507936624593513, 1.3488915229952612, 1.3374653492850819, 1.356459876825643, 1.3538597709992353, 1.3338901955505897, 1.3408141385677248, 1.3477216626322546, 1.3451274411622869, 1.3376305172609728, 1.3509186894394631, 1.347806500834088, 1.3472438939782077, 1.371430863936742, 1.3600308614618637, 1.3518608212471008, 1.3434083766715472, 1.3610473310246187, 1.3395415821740793], "unit": "ms", "name": "icon-close-DPI1.half.TART", "value": 1.3477640817331713}, {"lowerIsBetter": true, "replicates": [1.410078568458557, 1.4652449891952697, 1.4429182536461773, 1.444250964417177, 1.502322570579808, 1.4443074275465573, 1.466422704713685, 1.4572684835400103, 1.4621941305342174, 1.4643129933448065, 1.4667472718727022, 1.4517016347343399, 1.4650398457334155, 1.476886836354604, 1.4618170041413534, 1.4653209909087135, 1.4762430141072074, 1.478887949363295, 1.4821264980787254, 1.4775513646131504, 1.483743450009679, 1.4714418613982059, 1.4826583312657065, 1.4777123178550584, 1.4715952610685712], "unit": "ms", "name": "icon-close-DPI1.all.TART", "value": 1.4665849882931936}, {"lowerIsBetter": true, "replicates": [20.80000000000109, 18.010000000002037, 18.720000000001164, 18.799999999995634, 19.590000000003783, 18.639999999999418, 19.59999999999127, 19.47500000000582, 18.875, 19.06500000000233, 19.595000000001164, 18.839999999996508, 19.394999999989523, 19.839999999996508, 19.535000000003492, 19.464999999996508, 19.830000000016298, 18.710000000020955, 19.20999999999185, 19.845000000001164, 19.55500000002212, 18.885000000009313, 20.885000000009313, 20.104999999981374, 20.349999999976717], "unit": "ms", "name": "icon-close-DPI1.error.TART", "value": 19.470000000001164}, {"lowerIsBetter": true, "replicates": [1.5460787073771158, 1.6228191567138888, 1.6519213693482535, 1.6677147875661436, 1.6481855988502503, 1.6468196545328413, 1.6495296035494122, 1.6682824362879214, 1.663611400127411, 1.671371136886486, 1.6439506445612226, 1.6487341744559152, 1.644625382763999, 1.6386442604199263, 1.676852862040202, 1.6502866217068264, 1.6529471976416452, 1.6445979561124529, 1.6477193117141724, 1.6695457455040752, 1.6467977132116045, 1.6949063988699429, 1.6532927938870021, 1.6583231840814863, 1.65084068775177], "unit": "ms", "name": "icon-open-DPI2.half.TART", "value": 1.6505636547292983}, {"lowerIsBetter": true, "replicates": [1.9121389424099642, 1.995418440463931, 1.9694242714015582, 1.9793808276836689, 1.9657429438370926, 1.993057880290719, 1.977483593216238, 1.976923224559197, 1.984392615251763, 1.990490235732152, 1.9897507318230563, 2.011488432959309, 1.9953956846147776, 1.995590690523386, 2.031157060870974, 1.9807490618654, 1.9775609840718351, 1.9694972900243906, 1.9639204327876751, 1.965965331055736, 2.041904414456988, 2.0469665139440507, 1.9782724343529043, 2.022140628658235, 1.9852380041004152], "unit": "ms", "name": "icon-open-DPI2.all.TART", "value": 1.984815309676089}, {"lowerIsBetter": true, "replicates": [30.334999999999127, 29.485000000000582, 28.30500000000029, 27.739999999997963, 27.25999999999476, 29.05999999999767, 27.205000000001746, 27.375, 27.955000000001746, 29.06500000000233, 28.73499999998603, 27.5, 27.514999999984866, 27.56500000000233, 29.69000000000233, 27.639999999984866, 27.244999999995343, 28.17499999998836, 27.5, 27.885000000009313, 27.55999999999767, 28.18499999999767, 27.335000000020955, 30.669999999983702, 28.125], "unit": "ms", "name": "icon-open-DPI2.error.TART", "value": 27.812500000003638}, {"lowerIsBetter": true, "replicates": [1.1814563225726693, 1.2644972395110916, 1.2651766198022025, 1.2391907079245454, 1.2544716492943142, 1.2431091570085095, 1.245099348406638, 1.2670333228268467, 1.2660501081864912, 1.2801856014463637, 1.281286401218838, 1.2687085696629115, 1.2585453805716142, 1.254471651237944, 1.2425558682410949, 1.2786368052164714, 1.2516125259192095, 1.2639360087258475, 1.258069555396619, 1.266965803209242, 1.2651175417742886, 1.2799424171447753, 1.257297387589579, 1.2614128550757533, 1.660755164819221], "unit": "ms", "name": "icon-close-DPI2.half.TART", "value": 1.2642166241184696}, {"lowerIsBetter": true, "replicates": [1.294589845591752, 1.3642750673823887, 1.3635347949133978, 1.3443251073034734, 1.3969435937064034, 1.352594257059677, 1.338118170128494, 1.3641001323858897, 1.3554243922892197, 1.3751081830999825, 1.372254963693672, 1.3621758633189731, 1.3670055446678033, 1.370023911558716, 1.344286818425734, 1.3736563157499506, 1.3665790577729544, 1.3687775241596072, 1.36322972840733, 1.355708683393278, 1.3692773593870622, 1.377412187249473, 1.3522654192223733, 1.3633619997236464, 1.628528579576126], "unit": "ms", "name": "icon-close-DPI2.all.TART", "value": 1.364187599884139}, {"lowerIsBetter": true, "replicates": [18.534999999999854, 19.525000000001455, 19.160000000003492, 19.87000000000262, 18.32499999999709, 18.460000000006403, 18.710000000006403, 19.44999999999709, 19.18499999999767, 18.56500000000233, 19.379999999975553, 18.95000000001164, 18.599999999976717, 19.014999999984866, 18.535000000003492, 18.339999999996508, 19.919999999983702, 18.949999999982538, 19.429999999993015, 19.179999999993015, 18.98499999998603, 19.054999999993015, 18.654999999969732, 19.35500000003958, 19.679999999993015], "unit": "ms", "name": "icon-close-DPI2.error.TART", "value": 19.03499999998894}, {"lowerIsBetter": true, "replicates": [1.1240220544407669, 4.67251946926117, 1.2201236047242816, 1.2086659856140614, 1.18856511656771, 1.2239203478427643, 1.2148567563609074, 1.2213887879722998, 1.2270658713705995, 1.2295496096002294, 1.2293004190668146, 1.2341290065582762, 1.2289491067541407, 1.2144525477760717, 1.2181510711971082, 1.2322498636042818, 1.2205480274401213, 1.2318004953100326, 1.227560171421538, 1.228217879508404, 1.2248394958516384, 1.2314532703541694, 1.2206450437244616, 1.2470936647025488, 1.2272660453268822], "unit": "ms", "name": "iconFade-close-DPI2.half.TART", "value": 1.2271659583487409}, {"lowerIsBetter": true, "replicates": [1.1538762606606625, 2.0045759544290345, 1.2474907145780676, 1.23096988945411, 1.2185988544793653, 1.251833785605687, 1.2467268233630746, 1.2470656474006367, 1.2475543723386877, 1.2359019416443846, 1.244223641201774, 1.253733136961537, 1.2417574218887697, 1.2423672988453014, 1.2382692618572966, 1.2461847054129616, 1.2487515453348823, 1.2580317168622404, 1.24771454372508, 1.2398808338540666, 1.2481786280392326, 1.244888967371242, 1.2457945493453326, 1.25306010823096, 1.2419335911882685], "unit": "ms", "name": "iconFade-close-DPI2.all.TART", "value": 1.246455764388018}, {"lowerIsBetter": true, "replicates": [3.0349999999998545, 2.594999999997526, 3.3549999999959255, 2.779999999998836, 2.7600000000093132, 2.8699999999953434, 3.150000000008731, 3.305000000007567, 3.2949999999982538, 2.3700000000098953, 2.76500000001397, 3.2149999999965075, 2.389999999984866, 2.25, 2.7850000000034925, 2.904999999998836, 3.514999999984866, 2.8249999999825377, 3.3399999999965075, 3.1300000000046566, 3.4200000000128057, 2.8150000000023283, 2.8399999999674037, 3.0900000000256114, 3.404999999969732], "unit": "ms", "name": "iconFade-close-DPI2.error.TART", "value": 2.8874999999970896}, {"lowerIsBetter": true, "replicates": [1.5470566256841023, 1.618477314710617, 1.6424085338350753, 1.6367242604913845, 1.6195813053184085, 1.620223105793268, 1.6399098366079197, 1.6155226462417178, 1.6442365898212916, 1.6561782734734671, 1.6440493771008082, 1.6484598875045777, 1.6345717000289701, 1.631764724221028, 1.6450806839125498, 1.6565513048853193, 1.6577142647334508, 1.6420028965238114, 1.6364376158781455, 1.6342634231271878, 1.6522450208663941, 1.6387740642252102, 1.645283658163888, 1.6512356502669199, 1.6323650554871896], "unit": "ms", "name": "iconFade-open-DPI2.half.TART", "value": 1.6409563665658655}, {"lowerIsBetter": true, "replicates": [1.5163785679773851, 1.593834062961683, 1.6116347255377934, 1.6101596396544884, 1.5809746642370481, 1.6086183449317668, 1.6019979952132866, 1.6063222934459818, 1.6095319986343384, 1.6184373050928116, 1.615455976790852, 1.6118174569360142, 1.6191333077020116, 1.5844806311081867, 1.5857919681639898, 1.6072862649786062, 1.627880699984677, 1.6288796379849628, 1.5909354727284437, 1.5978160818962202, 1.612582806061054, 1.6131071666191363, 1.6212186324927542, 1.6073604156231058, 1.6022227640809683], "unit": "ms", "name": "iconFade-open-DPI2.all.TART", "value": 1.6090751717830525}, {"lowerIsBetter": true, "replicates": [3.5450000000018917, 2.774999999997817, 3.8850000000020373, 3.625, 4.039999999993597, 3.2849999999889405, 4.029999999998836, 2.9249999999883585, 3.289999999993597, 3.1900000000023283, 2.6900000000023283, 3.73499999998603, 3.1900000000023283, 3.0099999999802094, 3.179999999993015, 3.1749999999883585, 2.8649999999906868, 3.0450000000128057, 3.9100000000034925, 3.3500000000058208, 3.919999999983702, 4.024999999965075, 3.455000000016298, 3.0949999999720603, 2.4500000000116415], "unit": "ms", "name": "iconFade-open-DPI2.error.TART", "value": 3.2374999999956344}, {"lowerIsBetter": true, "replicates": [23.06876196861267, 1.8638461924368335, 1.8869161918515065, 1.8691788142727268, 1.8389180822977944, 1.8726100364039022, 1.8877156797002574, 1.8824825421456368, 1.8819375249647325, 1.874926426718312, 1.9007338852178854, 1.873452361552946, 1.8821976434799932, 1.8874575759543748, 3.0401936989081535, 1.907545160074703, 1.8895727181043782, 1.8945143613658968, 1.9213856001694998, 1.8965539521858341, 1.88909105331667, 1.875787329289221, 1.8792866833748356, 1.8781284843721697, 1.9441759626070658], "unit": "ms", "name": "newtab-open-preload-no.half.TART", "value": 1.8846993669985717}, {"lowerIsBetter": true, "replicates": [16.83030790835619, 2.6922595389187336, 2.745877293830222, 2.6977914919455848, 2.65052985776331, 2.743797968042658, 2.738822340965271, 2.7393697424137846, 2.766513087416208, 2.739986594687117, 2.757405468758116, 2.7612568319484754, 2.7433608732324966, 2.7568181285294155, 3.651544539824776, 2.7514371250538114, 2.716884403479727, 2.7872217815855276, 2.7935870147269704, 2.756273091480296, 2.726973492220828, 2.7222240209579467, 2.7601915469733616, 2.7514545212509813, 2.863782843390664], "unit": "ms", "name": "newtab-open-preload-no.all.TART", "value": 2.7486572094420167}, {"lowerIsBetter": true, "replicates": [45.22999999999956, 28.904999999998836, 28.619999999995343, 29.540000000000873, 27.580000000001746, 28.414999999993597, 27.80999999999767, 27.979999999995925, 27.93499999999767, 27.964999999996508, 29.625, 27.14000000001397, 28.31999999997788, 26.910000000003492, 32.47500000000582, 29.05999999999767, 28.55999999999767, 26.755000000004657, 27.44000000000233, 26.875, 29.54999999998836, 28.98499999998603, 27.35500000003958, 26.445000000006985, 30.994999999995343], "unit": "ms", "name": "newtab-open-preload-no.error.TART", "value": 28.149999999986903}, {"lowerIsBetter": true, "replicates": [2.0302787412676895, 1.9385823706785839, 1.9063931664482492, 1.9085082988269995, 1.9156909731567884, 1.9135569548997722, 1.886768469887395, 1.918611891934129, 1.932566382487615, 1.9138024556832236, 1.9137458039111779, 1.9218656023343403, 1.9140668482076926, 1.9173340035266564, 1.9319519837697348, 1.9246239960193634, 1.9230496068795522, 1.9188768049081166, 1.922460812330246, 1.9123168187063249, 1.9046808950236587, 1.9178208112716675, 1.9066449521017856, 1.914746720282758, 1.9498120788800515], "unit": "ms", "name": "newtab-open-preload-yes.half.TART", "value": 1.9165124883417224}, {"lowerIsBetter": true, "replicates": [2.164417323903141, 2.1488799442679194, 2.155069504754018, 2.143565792148396, 2.136295833830106, 2.131573946799262, 2.1478093125052373, 2.1467061234732805, 2.1817883623057397, 2.1569244087251844, 2.1742207983620148, 2.182096235710999, 2.1656743430683756, 2.1597699579010663, 2.163538471378129, 2.148434104555744, 2.1844366470287584, 2.1705777135669675, 2.154669231277401, 2.1639676827650804, 2.1503280805329146, 2.1659762900099797, 2.159303245908123, 2.1686019296320076, 2.1443621521236516], "unit": "ms", "name": "newtab-open-preload-yes.all.TART", "value": 2.1581138273166536}, {"lowerIsBetter": true, "replicates": [33.294999999998254, 34.17500000000291, 34.904999999998836, 34.205000000001746, 32.84999999999127, 32.529999999998836, 34.19000000000233, 34.15999999998894, 34.22499999999127, 33.20000000001164, 35.30999999999767, 34.139999999984866, 34.19000000000233, 33.67499999998836, 31.139999999984866, 34.195000000006985, 34.360000000015134, 34.77000000001863, 35.29999999998836, 34.25999999998021, 34.72499999997672, 34.61499999999069, 35.79999999998836, 34.804999999993015, 35.02999999996973], "unit": "ms", "name": "newtab-open-preload-yes.error.TART", "value": 34.21499999999651}], "name": "tart", "value": 4.008097745583449}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [3.0809116022099445, 2.0585082872928178, 2.06353591160221, 2.0656077348066297, 2.0551104972375693, 2.0646132596685085, 2.0690055248618786, 2.0732044198895028, 2.0738397790055245, 2.0570165745856355, 2.067983425414365, 2.0612154696132596, 2.06267955801105, 2.065497237569061, 2.060386740331492, 2.0679834254143645, 2.062127071823204, 2.067624309392265, 2.064696132596685, 2.0611878453038672, 2.0657458563535913, 2.0617127071823207, 2.068729281767956, 2.0631767955801106, 2.0652209944751383], "unit": "ms", "name": "tiled.html", "value": 2.0649585635359116}, {"lowerIsBetter": true, "replicates": [2.8007734806629836, 2.7863259668508285, 2.783066298342542, 2.781685082872928, 2.7909392265193373, 2.7861049723756905, 2.7900828729281772, 2.771850828729282, 2.7863812154696133, 2.787817679558011, 2.7888950276243096, 2.7911602209944752, 2.7888950276243096, 3.156381215469614, 2.778342541436464, 2.7750276243093923, 2.790524861878453, 2.788425414364641, 2.7712430939226516, 2.786243093922652, 2.783646408839779, 2.781795580110497, 2.7923756906077344, 2.780718232044199, 2.781160220994475], "unit": "ms", "name": "tiled-fixed.html", "value": 2.786312154696133}, {"lowerIsBetter": true, "replicates": [2.43939226519337, 2.4380939226519334, 2.440994475138121, 2.429530386740331, 2.436381215469613, 2.435441988950276, 2.4355801104972374, 2.4296685082872926, 2.4266298342541437, 2.4351104972375692, 2.4340331491712712, 2.4244751381215472, 2.435138121546961, 2.424060773480663, 2.4361878453038677, 2.461436464088398, 2.425524861878453, 2.425828729281768, 2.4396961325966853, 2.4331491712707183, 2.431878453038674, 2.4417403314917125, 2.428701657458564, 2.437513812154696, 2.4352762430939223], "unit": "ms", "name": "tiled-downscale.html", "value": 2.4345718232044202}, {"lowerIsBetter": true, "replicates": [3.315883977900553, 3.31, 3.3164917127071827, 3.3187292817679555, 3.3162983425414363, 3.3280939226519344, 3.3144751381215474, 3.3111878453038677, 3.308038674033149, 3.3154696132596686, 3.310580110497237, 3.3081767955801102, 3.309005524861879, 3.3211325966850835, 3.3160220994475136, 3.3133977900552494, 3.3143370165745853, 3.315276243093922, 3.316022099447514, 3.3210497237569054, 3.320441988950277, 3.316878453038674, 3.3097513812154697, 3.3140883977900555, 3.3146132596685085], "unit": "ms", "name": "tiled-fixed-downscale.html", "value": 3.314544198895028}, {"lowerIsBetter": true, "replicates": [2.7560526315789478, 2.8054605263157897, 2.8033552631578957, 2.793421052631579, 2.778684210526316, 2.7901315789473693, 2.8066447368421055, 2.7971710526315796, 2.8444078947368414, 2.7307236842105267, 2.768684210526316, 2.74375, 2.736381578947368, 2.7457236842105264, 2.743289473684211, 2.743092105263158, 2.7507236842105263, 2.736578947368421, 2.738421052631579, 2.7649342105263166, 2.7335526315789473, 2.7598026315789475, 2.73546052631579, 2.754539473684211, 2.7361842105263157], "unit": "ms", "name": "iframe.svg", "value": 2.7447368421052634}, {"lowerIsBetter": true, "replicates": [2.6856372549019607, 2.682058823529412, 2.699975490196078, 2.688333333333334, 2.6918137254901966, 2.6936274509803924, 2.688553921568628, 2.68889705882353, 2.6902941176470585, 2.6966666666666668, 2.6849019607843134, 2.685171568627451, 2.6852941176470586, 2.694093137254902, 2.6865441176470592, 2.6929656862745097, 2.689313725490196, 2.690588235294117, 2.6890196078431376, 2.689436274509804, 2.6875245098039215, 2.691200980392157, 2.6944117647058823, 2.6929656862745093, 2.6850245098039216], "unit": "ms", "name": "reader.htm", "value": 2.689375}], "name": "tscrollx", "value": 2.652894325471039}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [5.265744829177857, 5.010366046428681, 5.072727525234223, 5.09534512758255, 5.1067499160766605, 5.080637919902801, 5.050724399089813, 5.087434768676758, 5.039415645599365, 5.040030002593994, 5.123876249790191, 5.154269766807556, 5.135607433319092, 5.04680757522583, 5.014436423778534, 5.037437987327576, 5.090986704826355, 5.05677238702774, 5.0407788276672365, 5.0546796202659605, 5.06965559720993, 5.067101955413818, 5.06882997751236, 5.10484904050827, 5.018890786170959], "unit": "ms", "name": "1-customize-enter.half.TART", "value": 5.067965966463089}, {"lowerIsBetter": true, "replicates": [10.805661956469217, 9.14557919813239, 9.244298134909736, 9.199072013730587, 9.247634781731499, 9.610744796016, 9.13932678492173, 9.29093279308743, 9.600193424658341, 9.516769582575018, 9.261535586251153, 9.300397779630577, 9.299534289042155, 9.354574351840549, 9.3614265176985, 9.33281438615587, 9.308938058217366, 9.322463379965889, 9.238597880469428, 9.340605200661553, 9.236575651168824, 9.333650599585638, 9.506541279229252, 9.332071903016832, 9.289285914103191], "unit": "ms", "name": "1-customize-enter.all.TART", "value": 9.304667918923972}, {"lowerIsBetter": true, "replicates": [379.755000000001, 230.04999999999927, 225.59500000000116, 232.63999999999942, 225.60999999999694, 232.4300000000003, 229.88999999999942, 227.64500000000407, 231.84500000000116, 227.93499999999767, 226.1999999999971, 237.20999999999913, 227.98999999999796, 230.51499999999942, 230.77500000000873, 229.30500000000757, 228.48500000000058, 229.01499999999942, 225.19500000000698, 229.74499999999534, 225.3000000000029, 229.49000000000524, 228.2899999999936, 229.77999999999884, 227.54999999998836], "unit": "ms", "name": "1-customize-enter.error.TART", "value": 229.1600000000035}, {"lowerIsBetter": true, "replicates": [8.953137159347534, 8.841969470183054, 9.053777029116949, 8.887761195500692, 8.836689432462057, 9.258294495669277, 9.147702563892711, 8.882417241732279, 8.909169236818949, 8.81086532274882, 8.67387373248736, 8.683217525482178, 8.722641547520956, 8.84798534711202, 8.854161242643992, 8.670577685038248, 8.789489408334097, 8.92305717865626, 8.82123327255249, 8.79998528957367, 8.542737732330957, 8.752337416013082, 8.603601594765982, 8.81931342681249, 8.807633320490519], "unit": "ms", "name": "2-customize-exit.half.TART", "value": 8.820273349682491}, {"lowerIsBetter": true, "replicates": [9.663081156580072, 9.800483735832008, 9.908366631817174, 9.782363559070387, 12.254008491834005, 10.907343773280873, 10.330091328234285, 10.07744051314689, 9.951467965100262, 10.135311126708984, 9.968083758611936, 9.3298355616056, 9.781449672338125, 10.023078763807142, 9.800532673534594, 10.177263100941977, 9.797854837618376, 10.001481301075703, 9.466706618284567, 9.419346791047316, 9.382935777688639, 9.756201091565584, 10.31752518245152, 9.775431313012776, 9.40113140986516], "unit": "ms", "name": "2-customize-exit.all.TART", "value": 9.854449652675884}, {"lowerIsBetter": true, "replicates": [188.63500000000022, 183.83000000000175, 187.51000000000204, 192.37000000000262, 188.50500000000102, 190.95499999999447, 203.0, 193.5500000000029, 188.65499999999884, 186.45999999999913, 189.5949999999939, 184.62000000000262, 182.5399999999936, 191.4149999999936, 192.90000000000873, 185.40499999999884, 192.82000000000698, 190.39499999998952, 189.77000000000407, 187.8000000000029, 186.31500000000233, 191.11000000000058, 181.84500000000116, 192.0, 187.02999999999884], "unit": "ms", "name": "2-customize-exit.error.TART", "value": 189.12499999999636}, {"lowerIsBetter": true, "replicates": [7.835329679342417, 8.310011900388277, 7.8039894837599535, 7.816395576183613, 7.778261478130634, 7.90944165449876, 7.6856648581368585, 8.00804090499878, 7.938271082364595, 7.85405709193303, 7.846170278695913, 8.169025127704327, 7.799558749565711, 7.7562258793757515, 7.676448856081281, 7.83051505455604, 7.647813490458897, 8.141288500565748, 7.939747957082895, 8.001719584831825, 8.003580496861385, 7.916294538057768, 7.856951823601356, 7.80475744834313, 7.888233038095327], "unit": "ms", "name": "3-customize-enter-css.half.TART", "value": 7.855504457767193}, {"lowerIsBetter": true, "replicates": [11.55697270234426, 11.48377271493276, 11.297117074330648, 11.355053186416626, 11.067414417266846, 11.415997008482615, 10.942322731018066, 11.499948978424072, 11.89277748439623, 11.351084927717844, 11.34114901224772, 11.71770709493886, 11.331453283627829, 11.523676931858063, 11.031011371612548, 11.292813301086426, 11.136611213684082, 11.441676815350851, 11.515164852142334, 11.084310340881348, 11.854310554006826, 11.41602881749471, 11.30999724070231, 11.074956245422364, 10.992104549407959], "unit": "ms", "name": "3-customize-enter-css.all.TART", "value": 11.346116969982782}, {"lowerIsBetter": true, "replicates": [77.22500000000036, 75.52999999999884, 71.02000000000044, 72.41499999999724, 76.59000000000015, 73.95000000000437, 73.43499999999767, 75.93499999999767, 73.19499999999971, 72.34500000000116, 72.06999999999971, 69.51499999999942, 71.89500000000407, 76.45000000001164, 75.72500000000582, 70.83999999999651, 78.22999999999593, 74.56999999999243, 76.18500000001222, 76.85000000000582, 72.52999999999884, 73.86499999999069, 71.28000000001339, 76.77000000000407, 74.64500000000407], "unit": "ms", "name": "3-customize-enter-css.error.TART", "value": 73.90749999999753}], "name": "cart", "value": 22.732835094577496}]} 18:16:17 INFO - TinderboxPrint: TalosResult: {"graphserver": {"tscrollx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[287,53,31]]", "result": "2.65"}, "tsvgr_opacity": {"url": "http://graphs.mozilla.org/graph.html#tests=[[225,53,31]]", "result": "272.42"}, "tart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[293,53,31]]", "result": "4.01"}, "cart": {"url": "http://graphs.mozilla.org/graph.html#tests=[[309,53,31]]", "result": "22.73"}, "tsvgx": {"url": "http://graphs.mozilla.org/graph.html#tests=[[281,53,31]]", "result": "119.36"}}} 18:16:17 INFO - Return code: 0 18:16:17 INFO - Validating PERFHERDER_DATA against C:\slave\test\build\tests\talos\treeherder-schemas\performance-artifact.json 18:16:17 INFO - # TBPL SUCCESS # 18:16:17 INFO - Running post-action listener: _resource_record_post_action 18:16:17 INFO - Running post-run listener: _resource_record_post_run 18:16:18 INFO - Total resource usage - Wall time: 935s; CPU: 8.0%; Read bytes: 209442304; Write bytes: 508213760; Read time: 2546200; Write time: 8413130 18:16:18 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 2100736; Read time: 0; Write time: 14400 18:16:18 INFO - run-tests - Wall time: 934s; CPU: 8.0%; Read bytes: 209442304; Write bytes: 465427968; Read time: 2546200; Write time: 8359570 18:16:18 INFO - Running post-run listener: _upload_blobber_files 18:16:18 INFO - Blob upload gear active. 18:16:18 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 18:16:18 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 18:16:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Mozilla-Beta', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 18:16:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Mozilla-Beta -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 18:16:19 INFO - (blobuploader) - INFO - Open directory for files ... 18:16:19 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\svgr_errorsummary.log ... 18:16:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:16:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:16:20 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_errorsummary.log: uploaded 18:16:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:16:20 INFO - (blobuploader) - INFO - Done attempting. 18:16:20 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\svgr_raw.log ... 18:16:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:16:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:16:21 INFO - (blobuploader) - INFO - TinderboxPrint: svgr_raw.log: uploaded 18:16:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:16:21 INFO - (blobuploader) - INFO - Done attempting. 18:16:21 INFO - (blobuploader) - INFO - Iteration through files over. 18:16:21 INFO - Return code: 0 18:16:21 INFO - rmtree: C:\slave\test\build\uploaded_files.json 18:16:21 INFO - Using _rmtree_windows ... 18:16:21 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 18:16:21 INFO - Setting buildbot property blobber_files to {"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/824118cc21a22e6a55b47828e51238005f109eddb5bdc2235df953581a0fea418db65511d061f2d880dc906e2bf260da43df50ded1828a8b8bc0b89c36d3b015", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa5268c1b2e3766f698ea72d73045007dc720de0f5309e6b76ab8cd4bca0efe4580294ea60ab290f250fa59d3e48a03f931f40ec566fb8a190670a86d55a49f0"} 18:16:21 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 18:16:21 INFO - Writing to file C:\slave\test\properties\blobber_files 18:16:21 INFO - Contents: 18:16:21 INFO - blobber_files:{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/824118cc21a22e6a55b47828e51238005f109eddb5bdc2235df953581a0fea418db65511d061f2d880dc906e2bf260da43df50ded1828a8b8bc0b89c36d3b015", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa5268c1b2e3766f698ea72d73045007dc720de0f5309e6b76ab8cd4bca0efe4580294ea60ab290f250fa59d3e48a03f931f40ec566fb8a190670a86d55a49f0"} 18:16:21 INFO - Running post-run listener: copy_logs_to_upload_dir 18:16:21 INFO - Copying logs to upload dir... 18:16:21 INFO - mkdir: C:\slave\test\build\upload\logs 18:16:21 INFO - Copying logs to upload dir... 18:16:21 INFO - Using _rmtree_windows ... 18:16:21 INFO - Using _rmtree_windows ... 18:16:21 INFO - Using _rmtree_windows ... 18:16:21 INFO - Using _rmtree_windows ... 18:16:21 INFO - Using _rmtree_windows ... 18:16:21 INFO - Using _rmtree_windows ... 18:16:21 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1125.839000 ========= master_lag: 0.05 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 18 mins, 45 secs) (at 2016-04-19 18:16:21.969802) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-04-19 18:16:21.970628) ========= 'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f' in dir C:\slave\test\properties (timeout 1200 secs) watching logfiles {} argv: ['cmd', '/C', 'for', '%f', 'in', '(*)', 'do', '@type', '%f'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-077\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=T-W864-IX-077 COMSPEC=C:\windows\system32\cmd.exe DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld.T-W864-IX-077 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-077\AppData\Local LOGONSERVER=\\T-W864-IX-077 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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test\properties RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-077 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-077 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-077 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/824118cc21a22e6a55b47828e51238005f109eddb5bdc2235df953581a0fea418db65511d061f2d880dc906e2bf260da43df50ded1828a8b8bc0b89c36d3b015", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa5268c1b2e3766f698ea72d73045007dc720de0f5309e6b76ab8cd4bca0efe4580294ea60ab290f250fa59d3e48a03f931f40ec566fb8a190670a86d55a49f0"} build_url:https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/KiQzaAufSpaCSWru1eVkUw/artifacts/public/build/firefox-46.0.en-US.win64.zip' blobber_files: '{"svgr_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/824118cc21a22e6a55b47828e51238005f109eddb5bdc2235df953581a0fea418db65511d061f2d880dc906e2bf260da43df50ded1828a8b8bc0b89c36d3b015", "svgr_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aa5268c1b2e3766f698ea72d73045007dc720de0f5309e6b76ab8cd4bca0efe4580294ea60ab290f250fa59d3e48a03f931f40ec566fb8a190670a86d55a49f0"}' ========= master_lag: 4.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 4 secs) (at 2016-04-19 18:16:26.092210) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 18:16:26.092535) ========= 'rm' '-f' 'oauth.txt' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld.T-W864-IX-077\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files (x86)\Common Files COMMONPROGRAMFILES(X86)=C:\Program Files (x86)\Common Files COMMONPROGRAMW6432=C:\Program Files\Common Files COMPUTERNAME=T-W864-IX-077 COMSPEC=C:\windows\system32\cmd.exe DCLOCATION=SCL3 DNSSUFFIX=wintest.releng.scl3.mozilla.com FP_NO_HOST_CHECK=NO HOMEDRIVE=C: HOMEPATH=\Users\cltbld.T-W864-IX-077 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-077\AppData\Local LOGONSERVER=\\T-W864-IX-077 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:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\windows\system32;C:\windows;C:\windows\System32\Wbem;C:\windows\System32\WindowsPowerShell\v1.0\;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\msys\bin;C:\mozilla-build\vim\vim72;C:\mozilla-build\wget;C:\mozilla-build\info-zip;C:\CoreUtils\bin;C:\mozilla-build\buildbotve\scripts;C:\mozilla-build\hg PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC PROCESSOR_ARCHITECTURE=x86 PROCESSOR_ARCHITEW6432=AMD64 PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMDATA=C:\ProgramData PROGRAMFILES=C:\Program Files (x86) PROGRAMFILES(X86)=C:\Program Files (x86) PROGRAMW6432=C:\Program Files PROMPT=$P$G PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\windows TEMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp TEST1=testie TMP=C:\Users\CLTBLD~1.T-W\AppData\Local\Temp USERDOMAIN=T-W864-IX-077 USERDOMAIN_ROAMINGPROFILE=T-W864-IX-077 USERNAME=cltbld USERPROFILE=C:\Users\cltbld.T-W864-IX-077 WINDIR=C:\windows XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 18:16:26.227244) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-04-19 18:16:26.227987) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-04-19 18:16:26.717603) ========= ========= Total master_lag: 4.26 =========