builder: mozilla-central_win8_64_test-dromaeojs-e10s
slave: t-w864-ix-271
starttime: 1492143735.14
results: failure (2)
buildid: 20170413201349
builduid: 9392e43db30746eb8fa6f63f70439b0b
revision: f77f3057b8ee04f1a7546e9cd69066d201e9a221

========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 21:22:15.136348) =========
master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 21:22:15.136898) =========

========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 21:22:15.137192) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 21:22:15.422990) =========

========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-13 21:22:15.423430) =========
'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-271\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-271
  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-271
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-271\AppData\Local
  LOGONSERVER=\\T-W864-IX-271
  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-271
  USERDOMAIN_ROAMINGPROFILE=T-W864-IX-271
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld.T-W864-IX-271
  WINDIR=C:\windows
  XPCOM_DEBUG_BREAK=warn
 using PTY: False
--21:22:16--  https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
           => `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]

    0K .......... .                                          100%   16.99 MB/s

21:22:17 (16.99 MB/s) - `archiver_client.py' saved [12179/12179]

program finished with exit code 0
elapsedTime=1.708000
========= master_lag: 0.13 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-13 21:22:17.259507) =========

========= Started 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2017-04-13 21:22:17.259843) =========
'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-271\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-271
  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-271
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-271\AppData\Local
  LOGONSERVER=\\T-W864-IX-271
  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-271
  USERDOMAIN_ROAMINGPROFILE=T-W864-IX-271
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld.T-W864-IX-271
  WINDIR=C:\windows
  XPCOM_DEBUG_BREAK=warn
 using PTY: False
program finished with exit code 0
elapsedTime=3.111000
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2017-04-13 21:22:20.397822) =========

========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-13 21:22:20.398230) =========
'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev f77f3057b8ee04f1a7546e9cd69066d201e9a221 --destination scripts --debug'
 in dir C:\slave\test\. (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f77f3057b8ee04f1a7546e9cd69066d201e9a221 --destination scripts --debug']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld.T-W864-IX-271\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-271
  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-271
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-271\AppData\Local
  LOGONSERVER=\\T-W864-IX-271
  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-271
  USERDOMAIN_ROAMINGPROFILE=T-W864-IX-271
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld.T-W864-IX-271
  WINDIR=C:\windows
  XPCOM_DEBUG_BREAK=warn
 using PTY: False
2017-04-13 21:22:21,022 truncating revision to first 12 chars
2017-04-13 21:22:21,022 Setting DEBUG logging.
2017-04-13 21:22:21,022 attempt 1/10
2017-04-13 21:22:21,022 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f77f3057b8ee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-04-13 21:22:22,276 unpacking tar archive at: mozilla-central-f77f3057b8ee/testing/mozharness/
2017-04-13 21:22:22,401 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.874000
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-13 21:22:22.313053) =========

========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 21:22:22.313381) =========
script_repo_revision: f77f3057b8ee04f1a7546e9cd69066d201e9a221
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 21:22:22.313819) =========

========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 21:22:22.314101) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 21:22:22.338668) =========

========= Started 'c:/mozilla-build/python27/python -u ...' failed (results: 2, elapsed: 23 mins, 22 secs) (at 2017-04-13 21:22:22.339048) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs-e10s' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox-Non-PGO'
 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', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld.T-W864-IX-271\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-271
  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-271
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-271\AppData\Local
  LOGONSERVER=\\T-W864-IX-271
  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-271
  USERDOMAIN_ROAMINGPROFILE=T-W864-IX-271
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld.T-W864-IX-271
  WINDIR=C:\windows
  XPCOM_DEBUG_BREAK=warn
 using PTY: False
21:22:23     INFO - MultiFileLogger online at 20170413 21:22:23 in C:\slave\test
21:22:23     INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
21:22:23     INFO - Dumping config to C:\slave\test\logs\localconfig.json.
21:22:23     INFO - {'append_to_log': False,
21:22:23     INFO -  'base_work_dir': 'C:\\slave\\test',
21:22:23     INFO -  'blob_upload_branch': 'Firefox-Non-PGO',
21:22:23     INFO -  'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
21:22:23     INFO -  'branch': 'Firefox-Non-PGO',
21:22:23     INFO -  'buildbot_json_path': 'buildprops.json',
21:22:23     INFO -  'config_files': ('talos/windows_config.py',),
21:22:23     INFO -  'default_actions': ('clobber',
21:22:23     INFO -                      'read-buildbot-config',
21:22:23     INFO -                      'download-and-extract',
21:22:23     INFO -                      'populate-webroot',
21:22:23     INFO -                      'create-virtualenv',
21:22:23     INFO -                      'install',
21:22:23     INFO -                      'run-tests'),
21:22:23     INFO -  'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
21:22:23     INFO -  'download_minidump_stackwalk': True,
21:22:23     INFO -  'download_symbols': 'ondemand',
21:22:23     INFO -  'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
21:22:23     INFO -                            'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
21:22:23     INFO -           'hg': 'c:/mozilla-build/hg/hg',
21:22:23     INFO -           'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
21:22:23     INFO -                          'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
21:22:23     INFO -           'python': 'c:/mozilla-build/python27/python.exe',
21:22:23     INFO -           'tooltool.py': ('c:/mozilla-build/python27/python.exe',
21:22:23     INFO -                           'C:/mozilla-build/tooltool.py'),
21:22:23     INFO -           'virtualenv': ('c:/mozilla-build/python27/python.exe',
21:22:23     INFO -                          'c:/mozilla-build/buildbotve/virtualenv.py')},
21:22:23     INFO -  'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
21:22:23     INFO -                 'http://pypi.pub.build.mozilla.org/pub'),
21:22:23     INFO -  'gecko_profile': False,
21:22:23     INFO -  'gecko_profile_interval': 0,
21:22:23     INFO -  'installer_path': 'installer.exe',
21:22:23     INFO -  'log_level': 'info',
21:22:23     INFO -  'log_name': 'talos',
21:22:23     INFO -  'log_to_console': True,
21:22:23     INFO -  'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
21:22:23     INFO -  'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
21:22:23     INFO -  'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
21:22:23     INFO -  'opt_config_files': (),
21:22:23     INFO -  'pip_index': False,
21:22:23     INFO -  'suite': 'dromaeojs-e10s',
21:22:23     INFO -  'system_bits': '32',
21:22:23     INFO -  'talos_extra_options': ('--webServer', 'localhost'),
21:22:23     INFO -  'title': 'T-W864-IX-271',
21:22:23     INFO -  'use_talos_json': True,
21:22:23     INFO -  'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
21:22:23     INFO -  'virtualenv_path': 'C:\\slave\\test\\build/venv',
21:22:23     INFO -  'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
21:22:23     INFO -  'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
21:22:23     INFO -  'work_dir': 'build'}
21:22:23     INFO - [mozharness: 2017-04-14 04:22:23.271000Z] Running clobber step.
21:22:23     INFO - Running pre-action listener: _resource_record_pre_action
21:22:23     INFO - Running main action method: clobber
21:22:23     INFO - rmtree: C:\slave\test\build
21:22:23     INFO - Using _rmtree_windows ...
21:22:23     INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
21:24:07     INFO - Running post-action listener: _resource_record_post_action
21:24:07     INFO - [mozharness: 2017-04-14 04:24:07.403000Z] Finished clobber step (success)
21:24:07     INFO - [mozharness: 2017-04-14 04:24:07.403000Z] Running read-buildbot-config step.
21:24:07     INFO - Running pre-action listener: _resource_record_pre_action
21:24:07     INFO - Running main action method: read_buildbot_config
21:24:07     INFO - Using buildbot properties:
21:24:07     INFO - {
21:24:07     INFO -     "project": "", 
21:24:07     INFO -     "product": "firefox", 
21:24:07     INFO -     "script_repo_revision": "production", 
21:24:07     INFO -     "builddir": "mozilla-central_win8_64_test-dromaeojs-e10s", 
21:24:07     INFO -     "repository": "", 
21:24:07     INFO -     "buildername": "Windows 8 64-bit mozilla-central talos dromaeojs-e10s", 
21:24:07     INFO -     "buildid": "20170413201349", 
21:24:07     INFO -     "pgo_build": "False", 
21:24:07     INFO -     "basedir": "C:\\slave\\test", 
21:24:07     INFO -     "buildnumber": 15, 
21:24:07     INFO -     "slavename": "t-w864-ix-271", 
21:24:07     INFO -     "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 
21:24:07     INFO -     "platform": "win8_64", 
21:24:07     INFO -     "scheduler": "tests-mozilla-central-win64-talos", 
21:24:07     INFO -     "branch": "mozilla-central", 
21:24:07     INFO -     "repo_path": "mozilla-central", 
21:24:07     INFO -     "revision": "f77f3057b8ee04f1a7546e9cd69066d201e9a221", 
21:24:07     INFO -     "stage_platform": "win64", 
21:24:07     INFO -     "builduid": "9392e43db30746eb8fa6f63f70439b0b", 
21:24:07     INFO -     "slavebuilddir": "test"
21:24:07     INFO - }
21:24:07     INFO - Found installer url https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.zip.
21:24:07     INFO - Running post-action listener: _resource_record_post_action
21:24:07     INFO - [mozharness: 2017-04-14 04:24:07.406000Z] Finished read-buildbot-config step (success)
21:24:07     INFO - [mozharness: 2017-04-14 04:24:07.407000Z] Running download-and-extract step.
21:24:07     INFO - Running pre-action listener: _resource_record_pre_action
21:24:07     INFO - Running main action method: download_and_extract
21:24:07     INFO - mkdir: C:\slave\test\build\tests
21:24: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:24:07     INFO - trying https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.test_packages.json
21:24:07     INFO - Downloading https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.test_packages.json to C:\slave\test\build\firefox-55.0a1.en-US.win64.test_packages.json
21:24:07     INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-55.0a1.en-US.win64.test_packages.json'}, attempt #1
21:24:08     INFO - Downloaded 1408 bytes.
21:24:08     INFO - Reading from file C:\slave\test\build\firefox-55.0a1.en-US.win64.test_packages.json
21:24:08     INFO - Using the following test package requirements:
21:24:08     INFO - {u'awsy': [u'firefox-55.0a1.en-US.win64.common.tests.zip',
21:24:08     INFO -            u'firefox-55.0a1.en-US.win64.awsy.tests.zip'],
21:24:08     INFO -  u'common': [u'firefox-55.0a1.en-US.win64.common.tests.zip'],
21:24:08     INFO -  u'cppunittest': [u'firefox-55.0a1.en-US.win64.common.tests.zip',
21:24:08     INFO -                   u'firefox-55.0a1.en-US.win64.cppunittest.tests.zip'],
21:24:08     INFO -  u'gtest': [u'firefox-55.0a1.en-US.win64.common.tests.zip',
21:24:08     INFO -             u'firefox-55.0a1.en-US.win64.gtest.tests.zip'],
21:24:08     INFO -  u'jittest': [u'firefox-55.0a1.en-US.win64.common.tests.zip',
21:24:08     INFO -               u'jsshell-win64.zip'],
21:24:08     INFO -  u'mochitest': [u'firefox-55.0a1.en-US.win64.common.tests.zip',
21:24:08     INFO -                 u'firefox-55.0a1.en-US.win64.mochitest.tests.zip'],
21:24:08     INFO -  u'mozbase': [u'firefox-55.0a1.en-US.win64.common.tests.zip'],
21:24:08     INFO -  u'reftest': [u'firefox-55.0a1.en-US.win64.common.tests.zip',
21:24:08     INFO -               u'firefox-55.0a1.en-US.win64.reftest.tests.zip'],
21:24:08     INFO -  u'talos': [u'firefox-55.0a1.en-US.win64.common.tests.zip',
21:24:08     INFO -             u'firefox-55.0a1.en-US.win64.talos.tests.zip'],
21:24:08     INFO -  u'web-platform': [u'firefox-55.0a1.en-US.win64.common.tests.zip',
21:24:08     INFO -                    u'firefox-55.0a1.en-US.win64.web-platform.tests.zip'],
21:24:08     INFO -  u'xpcshell': [u'firefox-55.0a1.en-US.win64.common.tests.zip',
21:24:08     INFO -                u'firefox-55.0a1.en-US.win64.xpcshell.tests.zip']}
21:24:08     INFO - Downloading packages: [u'firefox-55.0a1.en-US.win64.common.tests.zip'] for test suite category: common
21:24:08     INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.common.tests.zip
21:24:08     INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.common.tests.zip'}, attempt #1
21:24:08     INFO - Fetch https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.common.tests.zip into memory
21:24:09     INFO - Expected file size: 53668449
21:24:09     INFO - Obtained file size: 53668449
21:25:07     INFO - Downloading packages: [u'firefox-55.0a1.en-US.win64.common.tests.zip', u'firefox-55.0a1.en-US.win64.talos.tests.zip'] for test suite category: talos
21:25:07     INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.common.tests.zip
21:25:07     INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.common.tests.zip'}, attempt #1
21:25:07     INFO - Fetch https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.common.tests.zip into memory
21:25:08     INFO - Expected file size: 53668449
21:25:08     INFO - Obtained file size: 53668449
21:25:35     INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.talos.tests.zip
21:25:35     INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.talos.tests.zip'}, attempt #1
21:25:35     INFO - Fetch https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.talos.tests.zip into memory
21:25:36     INFO - Expected file size: 14408853
21:25:36     INFO - Obtained file size: 14408853
21:25:48     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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:25:48     INFO - trying https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.zip
21:25:48     INFO - Downloading https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.zip to C:\slave\test\build\firefox-55.0a1.en-US.win64.zip
21:25:48     INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-55.0a1.en-US.win64.zip'}, attempt #1
21:25:49     INFO - Downloaded 55657997 bytes.
21:25:49     INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.zip
21:25:49     INFO - mkdir: C:\slave\test\properties
21:25:49     INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
21:25:49     INFO - Writing to file C:\slave\test\properties\build_url
21:25:49     INFO - Contents:
21:25:49     INFO -  build_url:https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.zip
21:25:50     INFO - Running post-action listener: _resource_record_post_action
21:25:50     INFO - Running post-action listener: set_extra_try_arguments
21:25:50     INFO - [mozharness: 2017-04-14 04:25:50.614000Z] Finished download-and-extract step (success)
21:25:50     INFO - [mozharness: 2017-04-14 04:25:50.614000Z] Running populate-webroot step.
21:25:50     INFO - Running pre-action listener: _resource_record_pre_action
21:25:50     INFO - Running main action method: populate_webroot
21:25:50     INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
21:25:50     INFO -  u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
21:25:50     INFO -                     u'remote-tsvgx': {u'talos_options': [u'--noChrome',
21:25:50     INFO -                                                          u'--tppagecycles',
21:25:50     INFO -                                                          u'7'],
21:25:50     INFO -                                       u'tests': [u'tsvgm']}},
21:25:50     INFO -  u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
21:25:50     INFO -                           u'tests': [u'tresize', u'tcanvasmark']},
21:25:50     INFO -              u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
21:25:50     INFO -              u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
21:25:50     INFO -                             u'tests': [u'dromaeo_css', u'kraken']},
21:25:50     INFO -              u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
21:25:50     INFO -              u'g1': {u'pagesets_name': u'tp5n.zip',
21:25:50     INFO -                      u'talos_options': [u'--disable-e10s'],
21:25:50     INFO -                      u'tests': [u'tp5o_scroll', u'glterrain']},
21:25:50     INFO -              u'g1-e10s': {u'pagesets_name': u'tp5n.zip',
21:25:50     INFO -                           u'tests': [u'tp5o_scroll', u'glterrain']},
21:25:50     INFO -              u'g2': {u'pagesets_name': u'tp5n.zip',
21:25:50     INFO -                      u'talos_options': [u'--disable-e10s'],
21:25:50     INFO -                      u'tests': [u'damp', u'tps']},
21:25:50     INFO -              u'g2-e10s': {u'pagesets_name': u'tp5n.zip',
21:25:50     INFO -                           u'tests': [u'damp', u'tps']},
21:25:50     INFO -              u'g3': {u'talos_options': [u'--disable-e10s'],
21:25:50     INFO -                      u'tests': [u'dromaeo_dom']},
21:25:50     INFO -              u'g3-e10s': {u'tests': [u'dromaeo_dom']},
21:25:50     INFO -              u'g4': {u'talos_options': [u'--disable-e10s'],
21:25:50     INFO -                      u'tests': [u'basic_compositor_video', u'glvideo']},
21:25:50     INFO -              u'g4-e10s': {u'tests': [u'basic_compositor_video', u'glvideo']},
21:25:50     INFO -              u'other': {u'talos_options': [u'--disable-e10s'],
21:25:50     INFO -                         u'tests': [u'a11yr',
21:25:50     INFO -                                    u'ts_paint',
21:25:50     INFO -                                    u'tpaint',
21:25:50     INFO -                                    u'sessionrestore',
21:25:50     INFO -                                    u'sessionrestore_no_auto_restore',
21:25:50     INFO -                                    u'tabpaint']},
21:25:50     INFO -              u'other-e10s': {u'tests': [u'a11yr',
21:25:50     INFO -                                         u'ts_paint',
21:25:50     INFO -                                         u'tpaint',
21:25:50     INFO -                                         u'sessionrestore',
21:25:50     INFO -                                         u'sessionrestore_no_auto_restore',
21:25:50     INFO -                                         u'tabpaint']},
21:25:50     INFO -              u'other-e10s_l64': {u'tests': [u'a11yr',
21:25:50     INFO -                                             u'ts_paint',
21:25:50     INFO -                                             u'tpaint',
21:25:50     INFO -                                             u'sessionrestore',
21:25:50     INFO -                                             u'sessionrestore_no_auto_restore',
21:25:50     INFO -                                             u'tabpaint']},
21:25:50     INFO -              u'other-e10s_nol64': {u'tests': [u'a11yr',
21:25:50     INFO -                                               u'ts_paint',
21:25:50     INFO -                                               u'tpaint',
21:25:50     INFO -                                               u'sessionrestore',
21:25:50     INFO -                                               u'sessionrestore_no_auto_restore',
21:25:50     INFO -                                               u'tabpaint']},
21:25:50     INFO -              u'other_l64': {u'talos_options': [u'--disable-e10s'],
21:25:50     INFO -                             u'tests': [u'a11yr',
21:25:50     INFO -                                        u'ts_paint',
21:25:50     INFO -                                        u'tpaint',
21:25:50     INFO -                                        u'sessionrestore',
21:25:50     INFO -                                        u'sessionrestore_no_auto_restore',
21:25:50     INFO -                                        u'tabpaint']},
21:25:50     INFO -              u'other_nol64': {u'talos_options': [u'--disable-e10s'],
21:25:50     INFO -                               u'tests': [u'a11yr',
21:25:50     INFO -                                          u'ts_paint',
21:25:50     INFO -                                          u'tpaint',
21:25:50     INFO -                                          u'sessionrestore',
21:25:50     INFO -                                          u'sessionrestore_no_auto_restore',
21:25:50     INFO -                                          u'tabpaint']},
21:25:50     INFO -              u'perf-reftest': {u'tests': [u'bloom_basic',
21:25:50     INFO -                                           u'bloom_basic_ref']},
21:25:50     INFO -              u'perf-reftest-e10s': {u'tests': [u'bloom_basic',
21:25:50     INFO -                                                u'bloom_basic_ref']},
21:25:50     INFO -              u'svgr': {u'talos_options': [u'--disable-e10s'],
21:25:50     INFO -                        u'tests': [u'tsvgx',
21:25:50     INFO -                                   u'tsvgr_opacity',
21:25:50     INFO -                                   u'tart',
21:25:50     INFO -                                   u'tscrollx',
21:25:50     INFO -                                   u'cart',
21:25:50     INFO -                                   u'tsvg_static']},
21:25:50     INFO -              u'svgr-e10s': {u'tests': [u'tsvgx',
21:25:50     INFO -                                        u'tsvgr_opacity',
21:25:50     INFO -                                        u'tart',
21:25:50     INFO -                                        u'tscrollx',
21:25:50     INFO -                                        u'cart',
21:25:50     INFO -                                        u'tsvg_static']},
21:25:50     INFO -              u'tp5o': {u'pagesets_name': u'tp5n.zip',
21:25:50     INFO -                        u'talos_options': [u'--disable-e10s'],
21:25:50     INFO -                        u'tests': [u'tp5o']},
21:25:50     INFO -              u'tp5o-e10s': {u'pagesets_name': u'tp5n.zip',
21:25:50     INFO -                             u'tests': [u'tp5o']},
21:25:50     INFO -              u'xperf': {u'pagesets_name': u'tp5n.zip',
21:25:50     INFO -                         u'talos_options': [u'--disable-e10s',
21:25:50     INFO -                                            u'--xperf_path',
21:25:50     INFO -                                            u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
21:25:50     INFO -                         u'tests': [u'tp5n']},
21:25:50     INFO -              u'xperf-e10s': {u'pagesets_name': u'tp5n.zip',
21:25:50     INFO -                              u'talos_options': [u'--xperf_path',
21:25:50     INFO -                                                 u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
21:25:50     INFO -                              u'tests': [u'tp5n']}},
21:25:50     INFO -  u'talos.zip': {u'path': u'',
21:25:50     INFO -                 u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
21:25:50     INFO - Running post-action listener: _resource_record_post_action
21:25:50     INFO - [mozharness: 2017-04-14 04:25:50.709000Z] Finished populate-webroot step (success)
21:25:50     INFO - [mozharness: 2017-04-14 04:25:50.709000Z] Running create-virtualenv step.
21:25:50     INFO - Running pre-action listener: _resource_record_pre_action
21:25:50     INFO - Running main action method: create_virtualenv
21:25:50     INFO - Creating virtualenv C:\slave\test\build/venv
21:25:50     INFO - mkdir: C:\slave\test\build\venv\Scripts
21:25:51     INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
21:25:51  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'!
21:25:51     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
21:25:51     INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
21:26:02     INFO -  New python executable in C:\slave\test\build/venv\Scripts\python.exe
21:26:06     INFO -  Installing distribute......................................................................................................................................................................................done.
21:26:07     INFO - Return code: 0
21:26:07     INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
21:26:07     INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
21:26:07     INFO - Reading from file tmpfile_stdout
21:26:07     INFO - Output received:
21:26:07     INFO -  pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
21:26:07     INFO - Using _rmtree_windows ...
21:26:07     INFO - Using _rmtree_windows ...
21:26:07     INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:07     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:07     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:26: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x022C4870>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x023C5110>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00567430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x028C40C8>, '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 0x004DA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023B55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-271', 'USERDOMAIN': 'T-W864-IX-271', '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-271', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-271', '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-271\\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
21:26: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', 'pip>=1.5'] in C:\slave\test\build
21:26: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 pip>=1.5
21:26:07     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:26:07     INFO -  'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Roaming',
21:26:07     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
21:26:07     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
21:26:07     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
21:26:07     INFO -  'COMPUTERNAME': 'T-W864-IX-271',
21:26:07     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:26:07     INFO -  'DCLOCATION': 'SCL3',
21:26:07     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:26:07     INFO -  'FP_NO_HOST_CHECK': 'NO',
21:26:07     INFO -  'HOMEDRIVE': 'C:',
21:26:07     INFO -  'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271',
21:26:07     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
21:26:07     INFO -  'KTS_VERSION': '1.19c',
21:26:07     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local',
21:26:07     INFO -  'LOGONSERVER': '\\\\T-W864-IX-271',
21:26:07     INFO -  'MONDIR': 'C:\\Monitor_config\\',
21:26:07     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:26:07     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:26:07     INFO -  'MOZ_NO_REMOTE': '1',
21:26:07     INFO -  'NO_EM_RESTART': '1',
21:26:07     INFO -  'NUMBER_OF_PROCESSORS': '8',
21:26:07     INFO -  'OS': 'Windows_NT',
21:26:07     INFO -  'OURDRIVE': 'C:',
21:26: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',
21:26:07     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:26:07     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
21:26:07     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
21:26:07     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
21:26:07     INFO -  'PROCESSOR_LEVEL': '6',
21:26:07     INFO -  'PROCESSOR_REVISION': '1e05',
21:26:07     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
21:26:07     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
21:26:07     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
21:26:07     INFO -  'PROGRAMW6432': 'C:\\Program Files',
21:26:07     INFO -  'PROMPT': '$P$G',
21:26:07     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:26:07     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:26:07     INFO -  'PUBLIC': 'C:\\Users\\Public',
21:26:07     INFO -  'PWD': 'C:\\slave\\test',
21:26:07     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:26:07     INFO -  'SLAVEDIR': 'C:\\slave\\',
21:26:07     INFO -  'SYSTEMDRIVE': 'C:',
21:26:07     INFO -  'SYSTEMROOT': 'C:\\windows',
21:26:07     INFO -  'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:07     INFO -  'TEST1': 'testie',
21:26:07     INFO -  'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:07     INFO -  'USERDOMAIN': 'T-W864-IX-271',
21:26:07     INFO -  'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271',
21:26:07     INFO -  'USERNAME': 'cltbld',
21:26:07     INFO -  'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-271',
21:26:07     INFO -  'WINDIR': 'C:\\windows',
21:26:07     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
21:26:08     INFO -  Ignoring indexes: http://pypi.python.org/simple/
21:26:08     INFO -  Downloading/unpacking pip>=1.5
21:26:12     INFO -    Running setup.py egg_info for package pip
21:26:12     INFO -      warning: no files found matching 'pip\cacert.pem'
21:26:12     INFO -      warning: no files found matching '*.html' under directory 'docs'
21:26:12     INFO -      warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
21:26:12     INFO -      no previously-included directories found matching 'docs\_build\_sources'
21:26:12     INFO -  Installing collected packages: pip
21:26:12     INFO -    Found existing installation: pip 0.8.2
21:26:12     INFO -      Uninstalling pip:
21:26:12     INFO -        Successfully uninstalled pip
21:26:12     INFO -    Running setup.py install for pip
21:26:12     INFO -      warning: no files found matching 'pip\cacert.pem'
21:26:12     INFO -      warning: no files found matching '*.html' under directory 'docs'
21:26:12     INFO -      warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
21:26:12     INFO -      no previously-included directories found matching 'docs\_build\_sources'
21:26:12     INFO -      Installing pip-script.py script to C:\slave\test\build\venv\Scripts
21:26:12     INFO -      Installing pip.exe script to C:\slave\test\build\venv\Scripts
21:26:12     INFO -      Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
21:26:12     INFO -      Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
21:26:12     INFO -      Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
21:26:12     INFO -      Installing pip2.exe script to C:\slave\test\build\venv\Scripts
21:26:12     INFO -  Successfully installed pip
21:26:12     INFO -  Cleaning up...
21:26:12     INFO - Return code: 0
21:26:12     INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:12     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:12     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:26: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', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x022C4870>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x023C5110>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00567430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x028C40C8>, '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 0x004DA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023B55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-271', 'USERDOMAIN': 'T-W864-IX-271', '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-271', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-271', '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-271\\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
21:26:13     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>=3.1.1'] in C:\slave\test\build
21:26:13     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>=3.1.1
21:26:13     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:26:13     INFO -  'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Roaming',
21:26:13     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
21:26:13     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
21:26:13     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
21:26:13     INFO -  'COMPUTERNAME': 'T-W864-IX-271',
21:26:13     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:26:13     INFO -  'DCLOCATION': 'SCL3',
21:26:13     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:26:13     INFO -  'FP_NO_HOST_CHECK': 'NO',
21:26:13     INFO -  'HOMEDRIVE': 'C:',
21:26:13     INFO -  'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271',
21:26:13     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
21:26:13     INFO -  'KTS_VERSION': '1.19c',
21:26:13     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local',
21:26:13     INFO -  'LOGONSERVER': '\\\\T-W864-IX-271',
21:26:13     INFO -  'MONDIR': 'C:\\Monitor_config\\',
21:26:13     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:26:13     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:26:13     INFO -  'MOZ_NO_REMOTE': '1',
21:26:13     INFO -  'NO_EM_RESTART': '1',
21:26:13     INFO -  'NUMBER_OF_PROCESSORS': '8',
21:26:13     INFO -  'OS': 'Windows_NT',
21:26:13     INFO -  'OURDRIVE': 'C:',
21:26:13     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',
21:26:13     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:26:13     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
21:26:13     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
21:26:13     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
21:26:13     INFO -  'PROCESSOR_LEVEL': '6',
21:26:13     INFO -  'PROCESSOR_REVISION': '1e05',
21:26:13     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
21:26:13     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
21:26:13     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
21:26:13     INFO -  'PROGRAMW6432': 'C:\\Program Files',
21:26:13     INFO -  'PROMPT': '$P$G',
21:26:13     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:26:13     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:26:13     INFO -  'PUBLIC': 'C:\\Users\\Public',
21:26:13     INFO -  'PWD': 'C:\\slave\\test',
21:26:13     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:26:13     INFO -  'SLAVEDIR': 'C:\\slave\\',
21:26:13     INFO -  'SYSTEMDRIVE': 'C:',
21:26:13     INFO -  'SYSTEMROOT': 'C:\\windows',
21:26:13     INFO -  'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:13     INFO -  'TEST1': 'testie',
21:26:13     INFO -  'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:13     INFO -  'USERDOMAIN': 'T-W864-IX-271',
21:26:13     INFO -  'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271',
21:26:13     INFO -  'USERNAME': 'cltbld',
21:26:13     INFO -  'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-271',
21:26:13     INFO -  'WINDIR': 'C:\\windows',
21:26:13     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
21:26:14     INFO -  Ignoring indexes: https://pypi.python.org/simple/
21:26:14     INFO -  Downloading/unpacking psutil>=3.1.1
21:26: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
21:26: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
21:26:15     INFO -  Installing collected packages: psutil
21:26:15     INFO -  Successfully installed psutil
21:26:15     INFO -  Cleaning up...
21:26:15     INFO - Return code: 0
21:26:15     INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:15     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:15     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:26:15     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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x022C4870>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x023C5110>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00567430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x028C40C8>, '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 0x004DA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023B55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-271', 'USERDOMAIN': 'T-W864-IX-271', '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-271', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-271', '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-271\\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
21:26:15     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.3'] in C:\slave\test\build
21:26:15     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.3
21:26:15     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:26:15     INFO -  'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Roaming',
21:26:15     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
21:26:15     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
21:26:15     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
21:26:15     INFO -  'COMPUTERNAME': 'T-W864-IX-271',
21:26:15     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:26:15     INFO -  'DCLOCATION': 'SCL3',
21:26:15     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:26:15     INFO -  'FP_NO_HOST_CHECK': 'NO',
21:26:15     INFO -  'HOMEDRIVE': 'C:',
21:26:15     INFO -  'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271',
21:26:15     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
21:26:15     INFO -  'KTS_VERSION': '1.19c',
21:26:15     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local',
21:26:15     INFO -  'LOGONSERVER': '\\\\T-W864-IX-271',
21:26:15     INFO -  'MONDIR': 'C:\\Monitor_config\\',
21:26:15     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:26:15     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:26:15     INFO -  'MOZ_NO_REMOTE': '1',
21:26:15     INFO -  'NO_EM_RESTART': '1',
21:26:15     INFO -  'NUMBER_OF_PROCESSORS': '8',
21:26:15     INFO -  'OS': 'Windows_NT',
21:26:15     INFO -  'OURDRIVE': 'C:',
21:26:15     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',
21:26:15     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:26:15     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
21:26:15     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
21:26:15     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
21:26:15     INFO -  'PROCESSOR_LEVEL': '6',
21:26:15     INFO -  'PROCESSOR_REVISION': '1e05',
21:26:15     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
21:26:15     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
21:26:15     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
21:26:15     INFO -  'PROGRAMW6432': 'C:\\Program Files',
21:26:15     INFO -  'PROMPT': '$P$G',
21:26:15     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:26:15     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:26:15     INFO -  'PUBLIC': 'C:\\Users\\Public',
21:26:15     INFO -  'PWD': 'C:\\slave\\test',
21:26:15     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:26:15     INFO -  'SLAVEDIR': 'C:\\slave\\',
21:26:15     INFO -  'SYSTEMDRIVE': 'C:',
21:26:15     INFO -  'SYSTEMROOT': 'C:\\windows',
21:26:15     INFO -  'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:15     INFO -  'TEST1': 'testie',
21:26:15     INFO -  'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:15     INFO -  'USERDOMAIN': 'T-W864-IX-271',
21:26:15     INFO -  'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271',
21:26:15     INFO -  'USERNAME': 'cltbld',
21:26:15     INFO -  'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-271',
21:26:15     INFO -  'WINDIR': 'C:\\windows',
21:26:15     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
21:26:16     INFO -  Ignoring indexes: https://pypi.python.org/simple/
21:26:16     INFO -  Downloading/unpacking mozsystemmonitor==0.3
21:26:16     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
21:26:16     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
21:26:17     INFO -    Downloading mozsystemmonitor-0.3.tar.gz
21:26:17     INFO -    Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
21:26:17     INFO -  Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3)
21:26:17     INFO -  Installing collected packages: mozsystemmonitor
21:26:17     INFO -    Running setup.py install for mozsystemmonitor
21:26:17     INFO -  Successfully installed mozsystemmonitor
21:26:17     INFO -  Cleaning up...
21:26:17     INFO - Return code: 0
21:26:17     INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:17     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:17     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:26:17     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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x022C4870>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x023C5110>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00567430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x028C40C8>, '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 0x004DA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023B55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-271', 'USERDOMAIN': 'T-W864-IX-271', '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-271', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-271', '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-271\\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
21:26:17     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==2.5.1'] in C:\slave\test\build
21:26:17     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==2.5.1
21:26:17     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:26:17     INFO -  'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Roaming',
21:26:17     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
21:26:17     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
21:26:17     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
21:26:17     INFO -  'COMPUTERNAME': 'T-W864-IX-271',
21:26:17     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:26:17     INFO -  'DCLOCATION': 'SCL3',
21:26:17     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:26:17     INFO -  'FP_NO_HOST_CHECK': 'NO',
21:26:17     INFO -  'HOMEDRIVE': 'C:',
21:26:17     INFO -  'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271',
21:26:17     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
21:26:17     INFO -  'KTS_VERSION': '1.19c',
21:26:17     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local',
21:26:17     INFO -  'LOGONSERVER': '\\\\T-W864-IX-271',
21:26:17     INFO -  'MONDIR': 'C:\\Monitor_config\\',
21:26:17     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:26:17     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:26:17     INFO -  'MOZ_NO_REMOTE': '1',
21:26:17     INFO -  'NO_EM_RESTART': '1',
21:26:17     INFO -  'NUMBER_OF_PROCESSORS': '8',
21:26:17     INFO -  'OS': 'Windows_NT',
21:26:17     INFO -  'OURDRIVE': 'C:',
21:26: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',
21:26:17     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:26:17     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
21:26:17     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
21:26:17     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
21:26:17     INFO -  'PROCESSOR_LEVEL': '6',
21:26:17     INFO -  'PROCESSOR_REVISION': '1e05',
21:26:17     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
21:26:17     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
21:26:17     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
21:26:17     INFO -  'PROGRAMW6432': 'C:\\Program Files',
21:26:17     INFO -  'PROMPT': '$P$G',
21:26:17     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:26:17     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:26:17     INFO -  'PUBLIC': 'C:\\Users\\Public',
21:26:17     INFO -  'PWD': 'C:\\slave\\test',
21:26:17     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:26:17     INFO -  'SLAVEDIR': 'C:\\slave\\',
21:26:17     INFO -  'SYSTEMDRIVE': 'C:',
21:26:17     INFO -  'SYSTEMROOT': 'C:\\windows',
21:26:17     INFO -  'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:17     INFO -  'TEST1': 'testie',
21:26:17     INFO -  'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:17     INFO -  'USERDOMAIN': 'T-W864-IX-271',
21:26:17     INFO -  'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271',
21:26:17     INFO -  'USERNAME': 'cltbld',
21:26:17     INFO -  'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-271',
21:26:17     INFO -  'WINDIR': 'C:\\windows',
21:26:17     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
21:26:17     INFO -  Ignoring indexes: https://pypi.python.org/simple/
21:26:17     INFO -  Downloading/unpacking jsonschema==2.5.1
21:26:17     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
21:26:17     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
21:26:19     INFO -    Downloading jsonschema-2.5.1-py2.py3-none-any.whl
21:26:19     INFO -  Downloading/unpacking functools32 (from jsonschema==2.5.1)
21:26:19     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
21:26:19     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
21:26:19     INFO -    Downloading functools32-3.2.3-2.tar.gz
21:26:19     INFO -    Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
21:26:19     INFO -      warning: no files found matching '*.txt'
21:26:19     INFO -      no previously-included directories found matching 'build'
21:26:19     INFO -      no previously-included directories found matching 'dist'
21:26:19     INFO -      no previously-included directories found matching '.git*'
21:26:19     INFO -  Installing collected packages: jsonschema, functools32
21:26:19     INFO -    Running setup.py install for functools32
21:26:19     INFO -      warning: no files found matching '*.txt'
21:26:19     INFO -      no previously-included directories found matching 'build'
21:26:19     INFO -      no previously-included directories found matching 'dist'
21:26:19     INFO -      no previously-included directories found matching '.git*'
21:26:19     INFO -  Successfully installed jsonschema functools32
21:26:19     INFO -  Cleaning up...
21:26:19     INFO - Return code: 0
21:26:19     INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
21:26:19     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:19     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:26:19     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:19     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:26:20     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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x022C4870>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x023C5110>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00567430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x028C40C8>, '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 0x004DA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023B55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-271', 'USERDOMAIN': 'T-W864-IX-271', '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-271', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-271', '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-271\\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
21:26:20     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', 'functools32==3.2.3-2'] in C:\slave\test\build
21:26:20     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 functools32==3.2.3-2
21:26:20     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:26:20     INFO -  'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Roaming',
21:26:20     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
21:26:20     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
21:26:20     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
21:26:20     INFO -  'COMPUTERNAME': 'T-W864-IX-271',
21:26:20     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:26:20     INFO -  'DCLOCATION': 'SCL3',
21:26:20     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:26:20     INFO -  'FP_NO_HOST_CHECK': 'NO',
21:26:20     INFO -  'HOMEDRIVE': 'C:',
21:26:20     INFO -  'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271',
21:26:20     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
21:26:20     INFO -  'KTS_VERSION': '1.19c',
21:26:20     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local',
21:26:20     INFO -  'LOGONSERVER': '\\\\T-W864-IX-271',
21:26:20     INFO -  'MONDIR': 'C:\\Monitor_config\\',
21:26:20     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:26:20     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:26:20     INFO -  'MOZ_NO_REMOTE': '1',
21:26:20     INFO -  'NO_EM_RESTART': '1',
21:26:20     INFO -  'NUMBER_OF_PROCESSORS': '8',
21:26:20     INFO -  'OS': 'Windows_NT',
21:26:20     INFO -  'OURDRIVE': 'C:',
21:26:20     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',
21:26:20     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:26:20     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
21:26:20     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
21:26:20     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
21:26:20     INFO -  'PROCESSOR_LEVEL': '6',
21:26:20     INFO -  'PROCESSOR_REVISION': '1e05',
21:26:20     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
21:26:20     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
21:26:20     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
21:26:20     INFO -  'PROGRAMW6432': 'C:\\Program Files',
21:26:20     INFO -  'PROMPT': '$P$G',
21:26:20     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:26:20     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:26:20     INFO -  'PUBLIC': 'C:\\Users\\Public',
21:26:20     INFO -  'PWD': 'C:\\slave\\test',
21:26:20     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:26:20     INFO -  'SLAVEDIR': 'C:\\slave\\',
21:26:20     INFO -  'SYSTEMDRIVE': 'C:',
21:26:20     INFO -  'SYSTEMROOT': 'C:\\windows',
21:26:20     INFO -  'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:20     INFO -  'TEST1': 'testie',
21:26:20     INFO -  'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:20     INFO -  'USERDOMAIN': 'T-W864-IX-271',
21:26:20     INFO -  'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271',
21:26:20     INFO -  'USERNAME': 'cltbld',
21:26:20     INFO -  'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-271',
21:26:20     INFO -  'WINDIR': 'C:\\windows',
21:26:20     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
21:26:20     INFO -  Ignoring indexes: https://pypi.python.org/simple/
21:26:20     INFO -  Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
21:26:20     INFO -  Cleaning up...
21:26:20     INFO - Return code: 0
21:26:20     INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
21:26:20     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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:20     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:26:20     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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:20     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:26:20     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 0x022C4870>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x023C5110>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00567430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x028C40C8>, '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 0x004DA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023B55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-271', 'USERDOMAIN': 'T-W864-IX-271', '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-271', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-271', '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-271\\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
21:26:20     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
21:26:20     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
21:26:20     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:26:20     INFO -  'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Roaming',
21:26:20     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
21:26:20     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
21:26:20     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
21:26:20     INFO -  'COMPUTERNAME': 'T-W864-IX-271',
21:26:20     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:26:20     INFO -  'DCLOCATION': 'SCL3',
21:26:20     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:26:20     INFO -  'FP_NO_HOST_CHECK': 'NO',
21:26:20     INFO -  'HOMEDRIVE': 'C:',
21:26:20     INFO -  'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271',
21:26:20     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
21:26:20     INFO -  'KTS_VERSION': '1.19c',
21:26:20     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local',
21:26:20     INFO -  'LOGONSERVER': '\\\\T-W864-IX-271',
21:26:20     INFO -  'MONDIR': 'C:\\Monitor_config\\',
21:26:20     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:26:20     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:26:20     INFO -  'MOZ_NO_REMOTE': '1',
21:26:20     INFO -  'NO_EM_RESTART': '1',
21:26:20     INFO -  'NUMBER_OF_PROCESSORS': '8',
21:26:20     INFO -  'OS': 'Windows_NT',
21:26:20     INFO -  'OURDRIVE': 'C:',
21:26:20     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',
21:26:20     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:26:20     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
21:26:20     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
21:26:20     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
21:26:20     INFO -  'PROCESSOR_LEVEL': '6',
21:26:20     INFO -  'PROCESSOR_REVISION': '1e05',
21:26:20     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
21:26:20     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
21:26:20     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
21:26:20     INFO -  'PROGRAMW6432': 'C:\\Program Files',
21:26:20     INFO -  'PROMPT': '$P$G',
21:26:20     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:26:20     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:26:20     INFO -  'PUBLIC': 'C:\\Users\\Public',
21:26:20     INFO -  'PWD': 'C:\\slave\\test',
21:26:20     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:26:20     INFO -  'SLAVEDIR': 'C:\\slave\\',
21:26:20     INFO -  'SYSTEMDRIVE': 'C:',
21:26:20     INFO -  'SYSTEMROOT': 'C:\\windows',
21:26:20     INFO -  'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:20     INFO -  'TEST1': 'testie',
21:26:20     INFO -  'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:20     INFO -  'USERDOMAIN': 'T-W864-IX-271',
21:26:20     INFO -  'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271',
21:26:20     INFO -  'USERNAME': 'cltbld',
21:26:20     INFO -  'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-271',
21:26:20     INFO -  'WINDIR': 'C:\\windows',
21:26:20     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
21:26:20     INFO -  Ignoring indexes: https://pypi.python.org/simple/
21:26:20     INFO -  Downloading/unpacking blobuploader==1.2.4
21:26:20     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
21:26:20     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
21:26:21     INFO -    Downloading blobuploader-1.2.4.tar.gz
21:26:21     INFO -    Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
21:26:21     INFO -  Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
21:26:21     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
21:26:21     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
21:26:22     INFO -    Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
21:26:22     INFO -  Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
21:26:22     INFO -    http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
21:26:22     INFO -    http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
21:26:22     INFO -    Downloading docopt-0.6.1.tar.gz
21:26:22     INFO -    Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
21:26:22     INFO -  Installing collected packages: blobuploader, requests, docopt
21:26:22     INFO -    Running setup.py install for blobuploader
21:26:22     INFO -    Running setup.py install for requests
21:26:23     INFO -    Running setup.py install for docopt
21:26:23     INFO -  Successfully installed blobuploader requests docopt
21:26:23     INFO -  Cleaning up...
21:26:24     INFO - Return code: 0
21:26:24     INFO - Installing None into virtualenv C:\slave\test\build/venv
21:26:24     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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:24     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:26:24     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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:24     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:26:24     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 0x022C4870>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x023C5110>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00567430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x028C40C8>, '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 0x004DA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023B55C0>, '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-271', 'USERDOMAIN': 'T-W864-IX-271', '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-271', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-271', '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-271\\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
21:26:24     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
21:26:24     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
21:26:24     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:26:24     INFO -  'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Roaming',
21:26:24     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
21:26:24     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
21:26:24     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
21:26:24     INFO -  'COMPUTERNAME': 'T-W864-IX-271',
21:26:24     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:26:24     INFO -  'DCLOCATION': 'SCL3',
21:26:24     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:26:24     INFO -  'FP_NO_HOST_CHECK': 'NO',
21:26:24     INFO -  'HOMEDRIVE': 'C:',
21:26:24     INFO -  'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271',
21:26:24     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
21:26:24     INFO -  'KTS_VERSION': '1.19c',
21:26:24     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local',
21:26:24     INFO -  'LOGONSERVER': '\\\\T-W864-IX-271',
21:26:24     INFO -  'MONDIR': 'C:\\Monitor_config\\',
21:26:24     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:26:24     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:26:24     INFO -  'MOZ_NO_REMOTE': '1',
21:26:24     INFO -  'NO_EM_RESTART': '1',
21:26:24     INFO -  'NUMBER_OF_PROCESSORS': '8',
21:26:24     INFO -  'OS': 'Windows_NT',
21:26:24     INFO -  'OURDRIVE': 'C:',
21:26:24     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',
21:26:24     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:26:24     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
21:26:24     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
21:26:24     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
21:26:24     INFO -  'PROCESSOR_LEVEL': '6',
21:26:24     INFO -  'PROCESSOR_REVISION': '1e05',
21:26:24     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
21:26:24     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
21:26:24     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
21:26:24     INFO -  'PROGRAMW6432': 'C:\\Program Files',
21:26:24     INFO -  'PROMPT': '$P$G',
21:26:24     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:26:24     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:26:24     INFO -  'PUBLIC': 'C:\\Users\\Public',
21:26:24     INFO -  'PWD': 'C:\\slave\\test',
21:26:24     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:26:24     INFO -  'SLAVEDIR': 'C:\\slave\\',
21:26:24     INFO -  'SYSTEMDRIVE': 'C:',
21:26:24     INFO -  'SYSTEMROOT': 'C:\\windows',
21:26:24     INFO -  'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:24     INFO -  'TEST1': 'testie',
21:26:24     INFO -  'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:24     INFO -  'USERDOMAIN': 'T-W864-IX-271',
21:26:24     INFO -  'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271',
21:26:24     INFO -  'USERNAME': 'cltbld',
21:26:24     INFO -  'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-271',
21:26:24     INFO -  'WINDIR': 'C:\\windows',
21:26:24     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
21:26:24     INFO -  Ignoring indexes: https://pypi.python.org/simple/
21:26:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\manifestparser
21:26:24     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-cwfmwp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
21:26:25     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozcrash
21:26:25     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-hswmck-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
21:26:25     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozdebug
21:26:25     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-j_ww7p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
21:26:25     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozdevice
21:26:25     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-6_lq_e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
21:26:25     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozfile
21:26:25     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-sw8ooq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
21:26:25     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
21:26:25     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-_vixqz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
21:26:25     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozinfo
21:26:25     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-pv9gd9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
21:26:25     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozinstall
21:26:25     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-km7kmh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
21:26:25     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozleak
21:26:25     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-3frmzf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
21:26:25     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozlog
21:26:25     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-mzhogd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
21:26:26     INFO -  Unpacking c:\slave\test\build\tests\mozbase\moznetwork
21:26:26     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-s4ouqu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
21:26:26     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozprocess
21:26:26     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-c6fnes-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
21:26:26     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozprofile
21:26:26     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-usc_gb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
21:26:26     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozrunner
21:26:26     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-0kg5tg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
21:26:26     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
21:26:26     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-hfd3pr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
21:26:26     INFO -  Unpacking c:\slave\test\build\tests\mozbase\moztest
21:26:26     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-die5oj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
21:26:26     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozversion
21:26:26     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-xonfti-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
21:26:26     INFO -  Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
21:26:26     INFO -    Running setup.py install for manifestparser
21:26:26     INFO -      Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
21:26:26     INFO -      Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
21:26:27     INFO -    Running setup.py install for mozcrash
21:26:27     INFO -    Running setup.py install for mozdebug
21:26:28     INFO -    Running setup.py install for mozdevice
21:26:28     INFO -      Installing dm-script.py script to C:\slave\test\build\venv\Scripts
21:26:28     INFO -      Installing dm.exe script to C:\slave\test\build\venv\Scripts
21:26:28     INFO -    Running setup.py install for mozfile
21:26:29     INFO -    Running setup.py install for mozhttpd
21:26:29     INFO -      Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
21:26:29     INFO -      Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
21:26:29     INFO -    Running setup.py install for mozinfo
21:26:29     INFO -      Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
21:26:29     INFO -      Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
21:26:29     INFO -    Running setup.py install for mozInstall
21:26:30     INFO -      Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
21:26:30     INFO -      Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
21:26:30     INFO -      Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
21:26:30     INFO -      Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
21:26:30     INFO -      Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
21:26:30     INFO -      Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
21:26:30     INFO -      Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
21:26:30     INFO -      Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
21:26:30     INFO -    Running setup.py install for mozleak
21:26:30     INFO -    Running setup.py install for mozlog
21:26:30     INFO -      Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
21:26:30     INFO -      Installing structlog.exe script to C:\slave\test\build\venv\Scripts
21:26:31     INFO -    Running setup.py install for moznetwork
21:26:31     INFO -      Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
21:26:31     INFO -      Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
21:26:31     INFO -    Running setup.py install for mozprocess
21:26:32     INFO -    Running setup.py install for mozprofile
21:26:32     INFO -      Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
21:26:32     INFO -      Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
21:26:32     INFO -      Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
21:26:32     INFO -      Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
21:26:32     INFO -      Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
21:26:32     INFO -      Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
21:26:33     INFO -    Running setup.py install for mozrunner
21:26:33     INFO -      Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
21:26:33     INFO -      Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
21:26:33     INFO -    Running setup.py install for mozscreenshot
21:26:34     INFO -    Running setup.py install for moztest
21:26:34     INFO -    Running setup.py install for mozversion
21:26:34     INFO -      Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
21:26:34     INFO -      Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
21:26:35     INFO -  Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
21:26:35     INFO -  Cleaning up...
21:26:35     INFO - Return code: 0
21:26:35     INFO - Installing None into virtualenv C:\slave\test\build/venv
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:35     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:35     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:26:35     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 0x022C4870>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x023C5110>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00567430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x028C40C8>, '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 0x004DA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023B55C0>, '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-271', 'USERDOMAIN': 'T-W864-IX-271', '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-271', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-271', '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-271\\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
21:26:35     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
21:26:35     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
21:26:35     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:26:35     INFO -  'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Roaming',
21:26:35     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
21:26:35     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
21:26:35     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
21:26:35     INFO -  'COMPUTERNAME': 'T-W864-IX-271',
21:26:35     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:26:35     INFO -  'DCLOCATION': 'SCL3',
21:26:35     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:26:35     INFO -  'FP_NO_HOST_CHECK': 'NO',
21:26:35     INFO -  'HOMEDRIVE': 'C:',
21:26:35     INFO -  'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271',
21:26:35     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
21:26:35     INFO -  'KTS_VERSION': '1.19c',
21:26:35     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local',
21:26:35     INFO -  'LOGONSERVER': '\\\\T-W864-IX-271',
21:26:35     INFO -  'MONDIR': 'C:\\Monitor_config\\',
21:26:35     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:26:35     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:26:35     INFO -  'MOZ_NO_REMOTE': '1',
21:26:35     INFO -  'NO_EM_RESTART': '1',
21:26:35     INFO -  'NUMBER_OF_PROCESSORS': '8',
21:26:35     INFO -  'OS': 'Windows_NT',
21:26:35     INFO -  'OURDRIVE': 'C:',
21:26: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',
21:26:35     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:26:35     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
21:26:35     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
21:26:35     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
21:26:35     INFO -  'PROCESSOR_LEVEL': '6',
21:26:35     INFO -  'PROCESSOR_REVISION': '1e05',
21:26:35     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
21:26:35     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
21:26:35     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
21:26:35     INFO -  'PROGRAMW6432': 'C:\\Program Files',
21:26:35     INFO -  'PROMPT': '$P$G',
21:26:35     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:26:35     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:26:35     INFO -  'PUBLIC': 'C:\\Users\\Public',
21:26:35     INFO -  'PWD': 'C:\\slave\\test',
21:26:35     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:26:35     INFO -  'SLAVEDIR': 'C:\\slave\\',
21:26:35     INFO -  'SYSTEMDRIVE': 'C:',
21:26:35     INFO -  'SYSTEMROOT': 'C:\\windows',
21:26:35     INFO -  'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:35     INFO -  'TEST1': 'testie',
21:26:35     INFO -  'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:35     INFO -  'USERDOMAIN': 'T-W864-IX-271',
21:26:35     INFO -  'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271',
21:26:35     INFO -  'USERNAME': 'cltbld',
21:26:35     INFO -  'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-271',
21:26:35     INFO -  'WINDIR': 'C:\\windows',
21:26:35     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
21:26:35     INFO -  Ignoring indexes: https://pypi.python.org/simple/
21:26:35     INFO -  Unpacking c:\slave\test\build\tests\mozbase\manifestparser
21:26:35     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-mtxoxf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
21:26:35     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))
21:26:35     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozcrash
21:26:35     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-ulnzwf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
21:26:35     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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))
21:26:35     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozdebug
21:26:35     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-0eu76i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
21:26:35     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))
21:26:35     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozdevice
21:26:35     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-3kl71i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
21:26:35     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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))
21:26:35     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozfile
21:26:35     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-xujktq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
21:26:36     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))
21:26:36     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
21:26:36     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-hqimct-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
21:26:36     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))
21:26:36     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozinfo
21:26:36     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-whabnc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
21:26:36     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))
21:26:36     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozinstall
21:26:36     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-uzstho-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
21:26:36     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))
21:26:36     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozleak
21:26:36     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-pfntmu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
21:26:36     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))
21:26:36     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozlog
21:26:36     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-duzfzd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
21:26:36     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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))
21:26:36     INFO -  Unpacking c:\slave\test\build\tests\mozbase\moznetwork
21:26:36     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-spfxey-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
21:26:36     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))
21:26:36     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozprocess
21:26:36     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-5uhg06-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
21:26:36     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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))
21:26:36     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozprofile
21:26:36     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-c4ghco-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
21:26:37     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))
21:26:37     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozrunner
21:26:37     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-re2pfx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
21:26:37     INFO -    Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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))
21:26:37     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
21:26:37     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-g1zx7s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
21:26:37     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))
21:26:37     INFO -  Unpacking c:\slave\test\build\tests\mozbase\moztest
21:26:37     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-_f1ymi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
21:26:37     INFO -    Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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))
21:26:37     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozversion
21:26:37     INFO -    Running setup.py (path:c:\users\cltbld~1.t-w\appdata\local\temp\pip-jyesqj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
21:26:37     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))
21:26:37     INFO -  Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
21:26:37     INFO -  Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
21:26:37     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))
21:26:37     INFO -  Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.50->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
21:26:37     INFO -  Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.50->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
21:26:37     INFO -  Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
21:26: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
21:26: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
21:26:38     INFO -    Downloading blessings-1.6.tar.gz
21:26:38     INFO -    Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
21:26:38     INFO -  Installing collected packages: blessings
21:26:38     INFO -    Running setup.py install for blessings
21:26:39     INFO -  Successfully installed blessings
21:26:39     INFO -  Cleaning up...
21:26:41     INFO - Return code: 0
21:26:41     INFO - Done creating virtualenv C:\slave\test\build/venv.
21:26:41     INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
21:26:41     INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
21:26:42     INFO - Reading from file tmpfile_stdout
21:26:42     INFO - Using _rmtree_windows ...
21:26:42     INFO - Using _rmtree_windows ...
21:26:42     INFO - Current package versions:
21:26:42     INFO -   blessings == 1.6
21:26:42     INFO -   blobuploader == 1.2.4
21:26:42     INFO -   docopt == 0.6.1
21:26:42     INFO -   functools32 == 3.2.3-2
21:26:42     INFO -   jsonschema == 2.5.1
21:26:42     INFO -   manifestparser == 1.1
21:26:42     INFO -   mozInstall == 1.12
21:26:42     INFO -   mozcrash == 1.0
21:26:42     INFO -   mozdebug == 0.1
21:26:42     INFO -   mozdevice == 0.50
21:26:42     INFO -   mozfile == 1.2
21:26:42     INFO -   mozhttpd == 0.7
21:26:42     INFO -   mozinfo == 0.9
21:26:42     INFO -   mozleak == 0.1
21:26:42     INFO -   mozlog == 3.4
21:26:42     INFO -   moznetwork == 0.27
21:26:42     INFO -   mozprocess == 0.25
21:26:42     INFO -   mozprofile == 0.28
21:26:42     INFO -   mozrunner == 6.13
21:26:42     INFO -   mozscreenshot == 0.1
21:26:42     INFO -   mozsystemmonitor == 0.3
21:26:42     INFO -   moztest == 0.8
21:26:42     INFO -   mozversion == 1.4
21:26:42     INFO -   psutil == 3.1.1
21:26:42     INFO -   requests == 1.2.3
21:26:42     INFO - Installing None into virtualenv C:\slave\test\build/venv
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:42     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:42     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:26:42     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 0x022C4870>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x023C5110>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00567430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x028C40C8>, '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 0x004DA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023B55C0>, '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-271', 'USERDOMAIN': 'T-W864-IX-271', '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-271', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-271', '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-271\\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
21:26:42     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
21:26:42     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
21:26:42     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:26:42     INFO -  'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Roaming',
21:26:42     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
21:26:42     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
21:26:42     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
21:26:42     INFO -  'COMPUTERNAME': 'T-W864-IX-271',
21:26:42     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:26:42     INFO -  'DCLOCATION': 'SCL3',
21:26:42     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:26:42     INFO -  'FP_NO_HOST_CHECK': 'NO',
21:26:42     INFO -  'HOMEDRIVE': 'C:',
21:26:42     INFO -  'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271',
21:26:42     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
21:26:42     INFO -  'KTS_VERSION': '1.19c',
21:26:42     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local',
21:26:42     INFO -  'LOGONSERVER': '\\\\T-W864-IX-271',
21:26:42     INFO -  'MONDIR': 'C:\\Monitor_config\\',
21:26:42     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:26:42     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:26:42     INFO -  'MOZ_NO_REMOTE': '1',
21:26:42     INFO -  'NO_EM_RESTART': '1',
21:26:42     INFO -  'NUMBER_OF_PROCESSORS': '8',
21:26:42     INFO -  'OS': 'Windows_NT',
21:26:42     INFO -  'OURDRIVE': 'C:',
21:26:42     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',
21:26:42     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:26:42     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
21:26:42     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
21:26:42     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
21:26:42     INFO -  'PROCESSOR_LEVEL': '6',
21:26:42     INFO -  'PROCESSOR_REVISION': '1e05',
21:26:42     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
21:26:42     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
21:26:42     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
21:26:42     INFO -  'PROGRAMW6432': 'C:\\Program Files',
21:26:42     INFO -  'PROMPT': '$P$G',
21:26:42     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:26:42     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:26:42     INFO -  'PUBLIC': 'C:\\Users\\Public',
21:26:42     INFO -  'PWD': 'C:\\slave\\test',
21:26:42     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:26:42     INFO -  'SLAVEDIR': 'C:\\slave\\',
21:26:42     INFO -  'SYSTEMDRIVE': 'C:',
21:26:42     INFO -  'SYSTEMROOT': 'C:\\windows',
21:26:42     INFO -  'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:42     INFO -  'TEST1': 'testie',
21:26:42     INFO -  'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:42     INFO -  'USERDOMAIN': 'T-W864-IX-271',
21:26:42     INFO -  'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271',
21:26:42     INFO -  'USERNAME': 'cltbld',
21:26:42     INFO -  'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-271',
21:26:42     INFO -  'WINDIR': 'C:\\windows',
21:26:42     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
21:26:42     INFO -  Ignoring indexes: https://pypi.python.org/simple/
21:26:42     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))
21:26:42     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))
21:26:42     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))
21:26:42     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))
21:26:42     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))
21:26:42     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))
21:26:42     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))
21:26:42     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))
21:26:42     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))
21:26:42     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))
21:26:42     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))
21:26:42     INFO -  Cleaning up...
21:26:42     INFO - Return code: 0
21:26:42     INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:42     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
21:26: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:42     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
21:26:42     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 0x022C4870>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x023C5110>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00567430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x028C40C8>, '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 0x004DA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023B55C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W864-IX-271', 'USERDOMAIN': 'T-W864-IX-271', '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-271', 'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271', 'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'PROGRAMW6432': 'C:\\Program Files', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W864-IX-271', '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-271\\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
21:26:42     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
21:26:42     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
21:26:42     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:26:42     INFO -  'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Roaming',
21:26:42     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
21:26:42     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
21:26:42     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
21:26:42     INFO -  'COMPUTERNAME': 'T-W864-IX-271',
21:26:42     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:26:42     INFO -  'DCLOCATION': 'SCL3',
21:26:42     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:26:42     INFO -  'FP_NO_HOST_CHECK': 'NO',
21:26:42     INFO -  'HOMEDRIVE': 'C:',
21:26:42     INFO -  'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271',
21:26:42     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
21:26:42     INFO -  'KTS_VERSION': '1.19c',
21:26:42     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local',
21:26:42     INFO -  'LOGONSERVER': '\\\\T-W864-IX-271',
21:26:42     INFO -  'MONDIR': 'C:\\Monitor_config\\',
21:26:42     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:26:42     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:26:42     INFO -  'MOZ_NO_REMOTE': '1',
21:26:42     INFO -  'NO_EM_RESTART': '1',
21:26:42     INFO -  'NUMBER_OF_PROCESSORS': '8',
21:26:42     INFO -  'OS': 'Windows_NT',
21:26:42     INFO -  'OURDRIVE': 'C:',
21:26:42     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',
21:26:42     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:26:42     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
21:26:42     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
21:26:42     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
21:26:42     INFO -  'PROCESSOR_LEVEL': '6',
21:26:42     INFO -  'PROCESSOR_REVISION': '1e05',
21:26:42     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
21:26:42     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
21:26:42     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
21:26:42     INFO -  'PROGRAMW6432': 'C:\\Program Files',
21:26:42     INFO -  'PROMPT': '$P$G',
21:26:42     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:26:42     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:26:42     INFO -  'PUBLIC': 'C:\\Users\\Public',
21:26:42     INFO -  'PWD': 'C:\\slave\\test',
21:26:42     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:26:42     INFO -  'SLAVEDIR': 'C:\\slave\\',
21:26:42     INFO -  'SYSTEMDRIVE': 'C:',
21:26:42     INFO -  'SYSTEMROOT': 'C:\\windows',
21:26:42     INFO -  'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:42     INFO -  'TEST1': 'testie',
21:26:42     INFO -  'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:42     INFO -  'USERDOMAIN': 'T-W864-IX-271',
21:26:42     INFO -  'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271',
21:26:42     INFO -  'USERNAME': 'cltbld',
21:26:42     INFO -  'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-271',
21:26:42     INFO -  'WINDIR': 'C:\\windows',
21:26:42     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
21:26:42     INFO -  Ignoring indexes: https://pypi.python.org/simple/
21:26:42     INFO -  Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
21:26:42     INFO -  Cleaning up...
21:26:42     INFO - Return code: 0
21:26:42     INFO - Running post-action listener: _resource_record_post_action
21:26:42     INFO - Running post-action listener: _start_resource_monitoring
21:26:42     INFO - Starting resource monitoring.
21:26:43     INFO - [mozharness: 2017-04-14 04:26:43.111000Z] Finished create-virtualenv step (success)
21:26:43     INFO - [mozharness: 2017-04-14 04:26:43.111000Z] Running install step.
21:26:43     INFO - Running pre-action listener: _resource_record_pre_action
21:26:43     INFO - Running main action method: install
21:26:43     INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
21:26:43     INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
21:26:43     INFO - Reading from file tmpfile_stdout
21:26:43     INFO - Using _rmtree_windows ...
21:26:43     INFO - Using _rmtree_windows ...
21:26:43     INFO - Detecting whether we're running mozinstall >=1.0...
21:26:43     INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
21:26:43     INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
21:26:43     INFO - Reading from file tmpfile_stdout
21:26:43     INFO - Output received:
21:26:43     INFO -  Usage: mozinstall-script.py [options] installer
21:26:43     INFO -  Options:
21:26:43     INFO -    -h, --help            show this help message and exit
21:26:43     INFO -    -d DEST, --destination=DEST
21:26:43     INFO -                          Directory to install application into. [default:
21:26:43     INFO -                          "C:\slave\test"]
21:26:43     INFO -    --app=APP             Application being installed. [default: firefox]
21:26:43     INFO - Using _rmtree_windows ...
21:26:43     INFO - Using _rmtree_windows ...
21:26:43     INFO - mkdir: C:\slave\test\build\application
21:26:43     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-55.0a1.en-US.win64.zip', '--destination', 'C:\\slave\\test\\build\\application']
21:26:43     INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-55.0a1.en-US.win64.zip --destination C:\slave\test\build\application
ProcessManager NOT managing child processes
21:26:46     INFO - Reading from file tmpfile_stdout
21:26:46     INFO - Output received:
21:26:46     INFO -  C:\slave\test\build\application\firefox\firefox.exe
21:26:46     INFO - Using _rmtree_windows ...
21:26:46     INFO - Using _rmtree_windows ...
21:26:46     INFO - Running post-action listener: _resource_record_post_action
21:26:46     INFO - [mozharness: 2017-04-14 04:26:46.054000Z] Finished install step (success)
21:26:46     INFO - [mozharness: 2017-04-14 04:26:46.054000Z] Running run-tests step.
21:26:46     INFO - Running pre-action listener: _resource_record_pre_action
21:26:46     INFO - Running main action method: run_tests
21:26:46     INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
21:26:46     INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
21:26:46     INFO -  Python 2.7.3
21:26:46     INFO - Return code: 0
21:26:46     INFO - grabbing minidump binary from tooltool
21:26:46     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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
21:26:46     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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x028C40C8>, '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 0x004DA9C0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x023B55C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
21:26:46     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
21:26:46     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
21:26:46     INFO - Calling ['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'] with output_timeout 600
ProcessManager UNABLE to use job objects to manage child processes
21:26:46     INFO -  INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
21:26:47     INFO -  INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp0hb_fj
21:26:47     INFO -  INFO - File integrity verified, renaming tmp0hb_fj to win32-minidump_stackwalk.exe
21:26:47     INFO - Return code: 0
21:26:47     INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
21:26:47     INFO - mkdir: C:\slave\test\build\blobber_upload_dir
21:26:47     INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
21:26:47     INFO - ENV: RUST_BACKTRACE is now 1
21:26:47     INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
21:26:47     INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
21:26:47     INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-271', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] in C:\slave\test\build
21:26:47     INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.crashreporter-symbols.zip --title T-W864-IX-271 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log
21:26:47     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
21:26:47     INFO -  'APPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Roaming',
21:26:47     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files (x86)\\Common Files',
21:26:47     INFO -  'COMMONPROGRAMFILES(X86)': 'C:\\Program Files (x86)\\Common Files',
21:26:47     INFO -  'COMMONPROGRAMW6432': 'C:\\Program Files\\Common Files',
21:26:47     INFO -  'COMPUTERNAME': 'T-W864-IX-271',
21:26:47     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
21:26:47     INFO -  'DCLOCATION': 'SCL3',
21:26:47     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
21:26:47     INFO -  'FP_NO_HOST_CHECK': 'NO',
21:26:47     INFO -  'HOMEDRIVE': 'C:',
21:26:47     INFO -  'HOMEPATH': '\\Users\\cltbld.T-W864-IX-271',
21:26:47     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
21:26:47     INFO -  'KTS_VERSION': '1.19c',
21:26:47     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld.T-W864-IX-271\\AppData\\Local',
21:26:47     INFO -  'LOGONSERVER': '\\\\T-W864-IX-271',
21:26:47     INFO -  'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
21:26:47     INFO -  'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
21:26:47     INFO -  'MONDIR': 'C:\\Monitor_config\\',
21:26:47     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
21:26:47     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
21:26:47     INFO -  'MOZ_NO_REMOTE': '1',
21:26:47     INFO -  'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
21:26:47     INFO -  'NO_EM_RESTART': '1',
21:26:47     INFO -  'NUMBER_OF_PROCESSORS': '8',
21:26:47     INFO -  'OS': 'Windows_NT',
21:26:47     INFO -  'OURDRIVE': 'C:',
21:26:47     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',
21:26:48     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
21:26:48     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
21:26:48     INFO -  'PROCESSOR_ARCHITEW6432': 'AMD64',
21:26:48     INFO -  'PROCESSOR_IDENTIFIER': 'Intel64 Family 6 Model 30 Stepping 5, GenuineIntel',
21:26:48     INFO -  'PROCESSOR_LEVEL': '6',
21:26:48     INFO -  'PROCESSOR_REVISION': '1e05',
21:26:48     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
21:26:48     INFO -  'PROGRAMFILES': 'C:\\Program Files (x86)',
21:26:48     INFO -  'PROGRAMFILES(X86)': 'C:\\Program Files (x86)',
21:26:48     INFO -  'PROGRAMW6432': 'C:\\Program Files',
21:26:48     INFO -  'PROMPT': '$P$G',
21:26:48     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
21:26:48     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
21:26:48     INFO -  'PUBLIC': 'C:\\Users\\Public',
21:26:48     INFO -  'PWD': 'C:\\slave\\test',
21:26:48     INFO -  'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
21:26:48     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
21:26:48     INFO -  'RUST_BACKTRACE': '1',
21:26:48     INFO -  'SLAVEDIR': 'C:\\slave\\',
21:26:48     INFO -  'SYSTEMDRIVE': 'C:',
21:26:48     INFO -  'SYSTEMROOT': 'C:\\windows',
21:26:48     INFO -  'TEMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:48     INFO -  'TEST1': 'testie',
21:26:48     INFO -  'TMP': 'C:\\Users\\CLTBLD~1.T-W\\AppData\\Local\\Temp',
21:26:48     INFO -  'USERDOMAIN': 'T-W864-IX-271',
21:26:48     INFO -  'USERDOMAIN_ROAMINGPROFILE': 'T-W864-IX-271',
21:26:48     INFO -  'USERNAME': 'cltbld',
21:26:48     INFO -  'USERPROFILE': 'C:\\Users\\cltbld.T-W864-IX-271',
21:26:48     INFO -  'WINDIR': 'C:\\windows',
21:26:48     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
21:26:48     INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.crashreporter-symbols.zip', '--title', 'T-W864-IX-271', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs-e10s_raw.log'] with output_timeout 3600
ProcessManager NOT managing child processes
ProcessManager UNABLE to use job objects to manage child processes
21:26:49     INFO -  mozversion application_buildid: 20170413201349
21:26:49     INFO -  mozversion application_changeset: f77f3057b8ee04f1a7546e9cd69066d201e9a221
21:26:49     INFO -  mozversion application_display_name: Nightly
21:26:49     INFO -  mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
21:26:49     INFO -  mozversion application_name: Firefox
21:26:49     INFO -  mozversion application_remotingname: firefox
21:26:49     INFO -  mozversion application_repository: https://hg.mozilla.org/mozilla-central
21:26:49     INFO -  mozversion application_vendor: Mozilla
21:26:49     INFO -  mozversion application_version: 55.0a1
21:26:49     INFO -  mozversion platform_buildid: 20170413201349
21:26:49     INFO -  mozversion platform_changeset: f77f3057b8ee04f1a7546e9cd69066d201e9a221
21:26:49     INFO -  mozversion platform_repository: https://hg.mozilla.org/mozilla-central
21:26:49     INFO -  mozversion platform_version: 55.0a1
21:26:49     INFO -  using testdate: 1492144009
21:26:49     INFO -  actual date: 1492144009
21:26:49     INFO -  starting webserver on 'localhost:49275'
21:26:49     INFO -  SUITE-START | Running 2 tests
21:26:49     INFO -  TEST-START | dromaeo_css
21:26:49     INFO -  operating with platform_type : w8_
21:26:49     INFO -  Initialising browser for dromaeo_css test...
21:26:49     INFO -  TEST-INFO | started process 2940 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpnh5dor\profile http://localhost:49275/getInfo.html)
21:26:59     INFO -  PID 2940 | __metrics	Screen width/height:1600/1200
21:26:59     INFO -  PID 2940 | 	colorDepth:24
21:26:59     INFO -  PID 2940 | 	Browser inner width/height: 1010/676
21:26:59     INFO -  PID 2940 | __metrics
21:27:02     INFO -  PID 2940 | Unable to read VR Path Registry from C:\Users\cltbld.T-W864-IX-271\AppData\Local\openvr\openvrpaths.vrpath
21:27:02     INFO -  PID 2940 | [GPU 884] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w64-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
21:27:02     INFO -  PID 2940 | Unable to read VR Path Registry from C:\Users\cltbld.T-W864-IX-271\AppData\Local\openvr\openvrpaths.vrpath
21:27:02    ERROR -  PID 2940 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined
21:27:02     INFO -  PID 2940 | [GPU 884] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w64-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
21:27:02     INFO -  PID 2940 | Unable to read VR Path Registry from C:\Users\cltbld.T-W864-IX-271\AppData\Local\openvr\openvrpaths.vrpath
21:27:02     INFO -  PID 2940 | [Child 2528] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w64-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
21:27:02     INFO -  PID 2940 | [Child 2528] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w64-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
21:27:02     INFO -  PID 2940 | [GPU 884] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w64-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
21:27:02     INFO -  PID 2940 | Unable to read VR Path Registry from C:\Users\cltbld.T-W864-IX-271\AppData\Local\openvr\openvrpaths.vrpath
21:27:02     INFO -  PID 2940 | [Child 2848] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w64-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
21:27:02     INFO -  PID 2940 | console.error:
21:27:02     INFO -  PID 2940 |   Message: Error: Connection closed before committing the transaction.
21:27:02     INFO -  PID 2940 |   Stack:
21:27:02     INFO -  PID 2940 |     executeTransaction/promise</transactionPromise<@resource://gre/modules/Sqlite.jsm:617:19
21:27:02     INFO -  PID 2940 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42
21:27:02     INFO -  PID 2940 | process@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:922:23
21:27:02     INFO -  PID 2940 | walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:806:7
21:27:02     INFO -  PID 2940 | Promise*scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:739:11
21:27:02     INFO -  PID 2940 | schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:770:7
21:27:02     INFO -  PID 2940 | completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:707:7
21:27:02     INFO -  PID 2940 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15
21:27:02     INFO -  PID 2940 | observe@resource://gre/modules/AsyncShutdown.jsm:531:9
21:27:02     INFO -  PID 2940 | addBlocker/moduleBlocker/<@jar:file:///C:/slave/test/build/application/firefox/omni.ja!/components/nsAsyncShutdown.js:157:15
21:27:02     INFO -  PID 2940 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:390:5
21:27:02     INFO -  PID 2940 | moduleBlocker@jar:file:///C:/slave/test/build/application/firefox/omni.ja!/components/nsAsyncShutdown.js:153:29
21:27:02     INFO -  PID 2940 | trigger@resource://gre/modules/AsyncShutdown.jsm:700:23
21:27:02     INFO -  PID 2940 | _wait@resource://gre/modules/AsyncShutdown.jsm:847:7
21:27:02     INFO -  PID 2940 | wait@resource://gre/modules/AsyncShutdown.jsm:831:28
21:27:02     INFO -  PID 2940 | observe@resource://gre/modules/AsyncShutdown.jsm:514:17
21:27:02     INFO -  PID 2940 |
21:27:03     INFO -  PID 2940 | [GFX1-]: Failed to OpenSharedResource for SyncObjectD3D11: 0x80070057
21:27:03     INFO -  PID 2940 | [GFX1 4]: Without device reset: 0x80070057
21:27:03     INFO -  PID 2940 |
21:27:03     INFO -  PID 2940 | ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv
21:27:03     INFO -  PID 2940 |
21:27:06     INFO -  TEST-INFO | 2940: exit 0
21:27:06     INFO -  Browser initialized.
21:27:06     INFO -  Running cycle 1/1 for dromaeo_css test...
21:27:06     INFO -  TEST-INFO | started process 3828 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld~1.t-w\appdata\local\temp\tmpnh5dor\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
21:27:18     INFO -  PID 3828 | RSS: Main: 157536256
21:27:18     INFO -  PID 3828 |
21:30:19     INFO -  PID 3828 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html)
21:30:20     INFO -  PID 3828 | RSS: Main: 133439488
21:30:20     INFO -  PID 3828 |
21:32:07     INFO -  PID 3828 | *************************
21:32:07     INFO -  PID 3828 | A coding exception was thrown and uncaught in a Task.
21:32:07     INFO -  PID 3828 |
21:32:07    ERROR -  PID 3828 | Full message: TypeError: NetworkError when attempting to fetch resource.
21:32:07     INFO -  PID 3828 | Full stack:
21:32:07     INFO -  PID 3828 | *************************
21:32:07     INFO -  PID 3828 | *************************
21:32:07     INFO -  PID 3828 | A coding exception was thrown and uncaught in a Task.
21:32:07     INFO -  PID 3828 |
21:32:07    ERROR -  PID 3828 | Full message: TypeError: NetworkError when attempting to fetch resource.
21:32:07     INFO -  PID 3828 | Full stack:
21:32:07     INFO -  PID 3828 | *************************
21:32:07     INFO -  PID 3828 | *************************
21:32:07     INFO -  PID 3828 | A coding exception was thrown and uncaught in a Task.
21:32:07     INFO -  PID 3828 |
21:32:07    ERROR -  PID 3828 | Full message: TypeError: NetworkError when attempting to fetch resource.
21:32:07     INFO -  PID 3828 | Full stack:
21:32:07     INFO -  PID 3828 | *************************
21:32:07     INFO -  PID 3828 | *************************
21:32:07     INFO -  PID 3828 | A coding exception was thrown and uncaught in a Task.
21:32:07     INFO -  PID 3828 |
21:32:07    ERROR -  PID 3828 | Full message: TypeError: NetworkError when attempting to fetch resource.
21:32:07     INFO -  PID 3828 | Full stack:
21:32:07     INFO -  PID 3828 | *************************
21:33:22     INFO -  PID 3828 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html)
21:33:23     INFO -  PID 3828 | RSS: Main: 92667904
21:33:23     INFO -  PID 3828 |
21:36:25     INFO -  PID 3828 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html)
21:36:25     INFO -  PID 3828 | RSS: Main: 99143680
21:36:25     INFO -  PID 3828 |
21:36:25     INFO -  PID 3828 | Unable to read VR Path Registry from C:\Users\cltbld.T-W864-IX-271\AppData\Local\openvr\openvrpaths.vrpath
21:39:27     INFO -  PID 3828 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html)
21:39:28     INFO -  PID 3828 | RSS: Main: 86159360
21:39:28     INFO -  PID 3828 |
21:42:30     INFO -  PID 3828 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html)
21:42:31     INFO -  PID 3828 | RSS: Main: 109641728
21:42:31     INFO -  PID 3828 |
21:45:32     INFO -  PID 3828 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html)
21:45:33     INFO -  PID 3828 | RSS: Main: 92151808
21:45:33     INFO -  PID 3828 |
21:45:33     INFO -  PID 3828 | __start_tp_report
21:45:33     INFO -  PID 3828 | _x_x_mozilla_page_load
21:45:33     INFO -  PID 3828 | _x_x_mozilla_page_load_details
21:45:33     INFO -  PID 3828 | |i|pagename|runs|
21:45:33     INFO -  PID 3828 | |0;dojo.html;2901.5067438535452;2903.7034102669477;2927.736503714666;2931.091361677881;2939.4121175764853;4943.728094954776;5036.017976494585;5058.215976523645;5093.210552364385;5122.948770512294;4945.777440015199;4982.626303027269;5022.472640372763;5117.950820081884;5228.163493840986;4618.468876079243;4686.43762748469;4697.600703940169;4765.761711914408;4775.641826862981;4584.954150458503;4625.236835922069;4720.90558188836;4725.574698277154;4751.667383283154;4501.4598248210095;4649.674522783406;4696.812127514895;4711.929321060187;4748.738819364934;4619.468761092484;4643.326717625959;4656.511066338054;4704.835330763441;4709.1288111699105;4641.466231383401;4666.580007799281;4688.789004494806;4692.41344831896;4704.929426058612;4912.459629440758;5112.2549337753335;5138.306328645623;5145.614078944094;5214;5207.817726379596;5439.864003399907;5517.641353312062;5525.254090697785;5540.9168862467095;5494.230807686927;5514.310711161105;5522.978249023903;5523.751431185607;5525.806596769094;5010.849674509771;5043.470435604246;5061.544460998491;5062.11413002723;5111.4121875984365;5264.710440925709;5373.328333958255;5381.48875856793;5395.70323632204;5405.729713514309;5150.8969820604125;5175.327207463006;5186.455422180692;5193.2989046479;5194.32473778414;5427.565794736412;5443.047466693313;5472.972635136799;5493.945060549343;5494.917576236359;5362.195670649434;5404.405515393303;5419.3225846769155;5422.539084177889;5424.972875135599;5171.974140129275;5331.840044798662;5375.5162035416215;5389.407165211824;5428.809991650273;3533.4876442915634;3590.2281009583066;3598.460230965376;3612.855485780592;3646;3281;3288.2108294009267;3294.5387645729616;3311.602607687045;3319.6016478022793;4970.3290055842;4977.054359671651;4996.7001979881325;5014.398272207285;5044.520770526794;5292.5236728693835;5327.786888524493;5332.2801421808335;5332.626716129834;5333.7866485340155;4945.431275403303;4954.603631709455;4964.379452568429;4975.378077740283;4977.452480227172;4459.620932220732;4464.687471877002;4530.252508336153;4559.931601025987;4587.770611469413;5562.721863906789;5567.47109024642;5583.078791999355;5586.16207568868;5610.1584762285165;5460.890782184255;5475.2334673145;5488.615796894339;5495.835124946258;5498.395176530658;5328.813491527933;5344.465553444625;5407.215953686772;5507.228987941771;5534;5453.836384908459;5463.4263402342185;5476.972615136899;5480.260164877691;5526.640768350205;5544.556435485232;5564.137558678412;5572.3034620672415;5575.8884822304135;5576.386597474194;5114.105031619377;5189;5384.569234461312;5390.838274851761;5431.4840090191365;5197.324347834758;5214.634975551675;5214.947850521598;5219.08665983459;5228.346456692913;5138.8972220556125;5149.948500514947;5160.09698302803;5212.270282160576;5259.921101183409;5378.784848606168;5404.7297635118875;5427.375851776938;5427.701476418835;5468.808591699271;5260;5275.393329766973;5291.788328466972;5303.734813259399;5354.892902142014
21:45:33     INFO -  PID 3828 | |1;ext.html;574.5301553910577;614.1218058176806;633.5058654249685;1124.3984468309457;1136.5056200552765;32450;33205.83397083014;33249.33501329972;33318.33363332732;33321.83339083301;31523.527147092755;31922.521162182587;31935;32010.199745006386;32093.35813283733;6418.358164183579;6779.728810847585;6934.029235906977;6964.373206411422;6981.162260528744;7027.086478757755;7104.502684812065;7123.715051397938;7124.251953544881;7136.571805691675;7014.333638304353;7098;7154.499185057022;7166.175889772665;7167.677454514559;7120.928790712104;7125.358717715405;7146.249643787405;7148.571085734872;7148.749793757245;6986.266442023616;7013.193482749498;7033.50765446419;7035.68339424722;7037.472189585802;31322.21694457657;31351.686483135105;31441.528377074133;31577.21056973571;31609.89365372224;3032.9839503766266;3093.520504321811;3106.7359274461473;3110.12916383413;3116.1897906544236;25016.874915625307;25053.874730626412;25115.623265650847;25159.87420062888;25169.24492265235;3579.6420357963993;3610.295992281506;3648.543932008499;3653.4154535274492;3676.5955744868043;130101;131447.34276328556;132748;135029;135861.32069339688;36101.27797444037;36106.45840312397;36283.4557481638;36316.455253171225;36401.63598364036;37868.2426351477;38057.61942380541;39756.20487590232;41124.79437602793;41224.58775412267;10856.402897840695;10912.50893709785;10916.617918372818;10952.890471095347;10964.616238431776;8290.626921788535;8411.579421028924;8475.449095808875;8476.321894248445;8614.353923455767;12067.818982715266;12132.45403956824;12205.633830985085;12236.449359778831;12340.50637974489;4972.67677600962;5035.194368900959;5049.813293875936;5094.5160209780015;5114.590832733373;969.4377261188494;986.9703908882746;992.2111921022804;997.2719914462483;1010.368519675203;20680.172793088408;20792.1683132676;21302.78697213039;21698.240561580267;21768.346949591538;1021.6577446555488;1028.840529717895;1038.958441662325;1043.9164866810636;1053.1311667874033;11399.48702308398;11438.084953203725;11454.140939429577;11496.195266331277;11628.76742465146;183019;183400;184069;184705;184717;40139.59860401358;40156.397654035216;40361.59638403637;40511.392329115086;40542;1272.5100836178137;1298.5974347952165;1299.123091912955;1304.8108024336607;1319.0898280186832;20155.496112597302;20780.896095519427;21231.36305910825;24105.397365066012;24892.75107248977;35858.28283434369;38003;38082.428763568016;38124;39144.6085539141;172884;173741;174674.12662936604;174708.1264593669;175270;28458.146255612366;28837.27906802347;28890.855545722137;28920.7107928918;28992.275193120342;34247.828760856035;34462.13844653904;34588.654113459546;34619.4807077889;34728.65271347355;26192.8690356547;26814.865925671027;26883.731162688124;26971.325716857235;27782.44435111246;26233;26683.46633067289;26689.33276668099;26842.32894177661;26990.32524186911;29545.26136846596;29609.25976850596;29927;29943;30036.24909377283;26134.607980880683;26438.471230575666;26474;26718.19845404641;26890.59664105079;21389.14443422308;21839.563208735417;22122.22572209965;22241.888790555942;22243.332700019026
21:45:33     INFO -  PID 3828 | |2;jquery.html;404.09079570965326;405.2765813023753;408.9815958281877;415.113732181792;421.91772604342157;17640;18017.09914504273;18346.633067338644;18375.632487350245;18772.71840922384;15663.451779187732;15737;15839.683206335867;15960.04239745616;16011.519654410356;5214.0875346814255;5256.684598924057;5440.9934162179925;5446.782128714846;5448.863778405532;5239.711815850127;5398.703071331075;5403.2165336026255;5412.404635490093;5414.539764120035;5289.021531016755;5361.222622719703;5362.43694412087;5376.247325374451;5396.91904621431;5191.948080519203;5218.8695282617855;5313.867153321178;5335.093034184198;5335.813246536372;4813.033683278692;4833.516648335139;4836.105320515716;4842.539958703917;4864.173090574611;20586.897065514728;20655.277065302642;20788;20871.060802263935;20876.956152192328;1744.520256929342;1775.1568005197557;1782.5187199456075;1787.177898166845;1796.380248814157;13931.721365572632;14151.221682807442;14895.77656335145;14942.103473791607;14946.850531494654;2087.4676957375773;2117.311873641073;2142.582196471689;2144.6890200920934;2147.3450597567735;82644.17355826424;84517.57741211315;84688;84884;85052.57473712592;10241.23190760681;10508.684739457829;10611.31026483276;10676.572937082585;10766.600341955496;14880.851191488164;15031.774523382159;15048.699026019418;15090.094594324375;15194.696106077816;7098.793205155136;7122.2521635228595;7155.856882862314;7270.745523906638;7278.490505664553;6465.676716164267;6504.60972341661;6588.538802283794;6590.901136482957;6604.273529911706;6763.864722705518;6875.424779114832;6888.724451021903;6910.689018994158;6921.515493915377;1937.903104844752;1942.2619404626435;1945.8248757611882;1951.2877799603043;2106.3470324199548;938.5448057692014;960.3277705606104;963.3016063354153;964.5466630683568;984.3257368702461;17348.39280625172;17364.91317543404;17622.735658965124;17776.200070996838;18061.819381806275;948.4167237149242;950.5864948747179;955.1880901234034;959.5921733263335;974.28390133252;9447.385919915183;9840.3603765755;9858.704238872846;9951.25365597583;9972.50137493137;358249;358866;362982;382732;395192;83635;85023.57488212519;85290;86028;86121;1037.6264544763878;1054.5254635414094;1085.755704966392;1091.8307662312354;1096.1395304685784;17026.914865425595;17056.744148838025;17565.385211517536;17657.38199163023;17809.198586063398;39131;39159.41260881032;39473.210535789694;39617.2076558473;41104.38343424911;50141;50817;50858.49141508537;51678.741606291725;52630.73684631552;7479.139898911695;7678.69285228607;7689.231076892266;7788.8831667526165;7792.805179870548;11456.713582160513;11480.081593472669;11623.07015438746;11624.198249271376;12501;9848.409095454295;9902.653407130716;10160.390376577428;10165.44090075053;10274.280800343904;10015.349002314826;10045.899541004497;10071.143952764098;10175.796484070424;10302.948485257526;9501.667441639509;9615.759606009906;10340.482975851328;10588.258821882395;10702.357858528514;7700;7985.960070199611;8011.879821802794;8083.878741818995;8138.87791683137;10317.329373590992;10405.479726013518;10668.1998850085;10709.250352475563;11107.777844443228
21:45:33     INFO -  PID 3828 | |3;mootools.html;1211.388248934288;1288.1820044271885;1288.845338559373;1301.9283939383342;1305.2103477396174;8909.955450222747;9032.72901812945;9216.032316606761;9326.253899688034;9341.01919298474;8938.284937205024;9066.45601263923;9086.818263634723;9086.81826363474;9131.726048218547;2074.460640233535;2083.1354987680093;2090.1325949730845;2098.2341445372404;2108.314797690749;1928;1947.7175809507612;1954.6188493243824;2044.488877780555;2077.366403247009;1997.990010049955;2083.677030060343;2102.5899949509853;2113.006886763219;2116.8624039437464;2084.7498300204074;2086.6244076066228;2094.3402828109097;2097.8216851567636;2102.2536999365157;1985.3547597031022;1985.4639247403265;2006.067178761868;2027.6248893954519;2094.979050209494;9118.224950879183;9225;9257;9297.860532092023;9299.349045566812;4862.416510018785;5002;5027.522385373383;5034.068697290976;5048.217526283432;3026.243439140215;3156.068959656907;3222.081706713599;3227.7256433203206;3250.0737289872272;1127.1433710380088;1172.0857730969772;1173.806321956876;1179.3572502985894;1190.5892467098838;12899.161554498927;12916.677083072904;12923.67690807728;13017.47930082805;13030.86969130306;4135.110951145458;4185.895352616209;4312.827486900489;4324.697271191049;4381.605655491021;9742.46416447088;9817;9835.065668761456;9844.80310393788;9860.802783944424;4919.188333924933;4919.581835544019;4920.778564964585;4930.926036109461;4941.110600092018;4243.193793179286;4449.666275029385;4535.659825513099;4540.228161212602;4553;3495.790252584853;3531.5585551806025;3535.1515636247113;3565.714742820568;3596.4065929121925;1020.9693709188737;1025.0518270599814;1028.388109075099;1029.4132344563677;1029.8146333660013;655.3741177175785;681.8704446155215;692.2281655953575;693.5734523268154;696.6725638949686;4769.809207631656;4782.282657601388;5750.338711048199;5879.764809407661;6046.727897244635;817.264461984219;843.6709683223547;847.2840449819893;848.3043903998786;853.9957300213459;3206.246532064963;3283.5567198427907;3315.55240042596;3379.881704140343;3605;11429.257098288585;11861.5848445304;11870.525178992917;11882.524699012116;11908.52365905354;11691.941540292244;11717.589884354007;11764.352960587079;11769.764604707858;11791.469383877746;8451.915480845113;8510.36172287081;8610.483370997636;8696.521691306913;8867.91132088671;5261.842144735664;5439.945600543944;5494.862628434321;5549.195366671891;5720.513756330759;10324.535395907353;10347.327423717434;10388.220883433924;10433.26967112295;10491.580336786443;10527.684169474927;10530.05229529346;10668.253222274367;10668.83996740065;10685.519151638351;7324.340809327186;7571.848563028599;7575.318221359884;7580.431467639839;7597.886031709419;8758.211760941545;8778.297736181217;8801.955990220007;8822.647094116164;8831.425957312755;7354.338109570164;7408.592527411044;7497.512661676974;7546.849063018819;7619.580923049286;7246.44192512941;7256.129264488295;7339.559626422431;7408;7423.1834498206235;6942.06282151903;6952.339527744856;6979.546329488567;7018.227994920452;7030.437564994686;4378.649708023287;4419.3812866199405;4456.710313829591;4666.34671146033;4762.52374762521;4500.954990450054;4524.185646583646;4536.773161341854;4590.380298659772;4623.745693986863
21:45:33     INFO -  PID 3828 | |4;prototype.html;362.8330967754833;387.6917850309004;392.77611761296055;395.2726982352471;398.5357059026235;5380.596455265856;5383.488568585988;5398.514133727964;5415.106507426275;5440.2383666286705;5101.642884998052;5241.39723931748;5253.4221235664045;5281.313429254192;5286.286351342567;4063.390491426295;4104.056067104568;4118.38224266361;4124.030852749602;4128.669706423494;4093.3450647896193;4125;4137.6069273419125;4142.71001029928;4150.211459822624;3820.331441997653;4037.3943908413826;4039.7778122203267;4045.5954404559457;4099.139180772041;4027.2750904837117;4031.637152656275;4063.817128229237;4070.4125391097496;4073.3279008963486;3533.3040140732337;3550.4496802995577;3551.3607550640872;3552.5381700379044;3569.8750543730844;5005.399352077774;5140.871478213037;5167.597067363657;5170.6122040847085;5171.793128274864;4635.745034023127;4636.235021221494;4648.814047438098;4656.604188643969;4678.649101317381;5497.47773961473;5518.227448157261;5518.586106042023;5523.199136125279;5539.501444869981;3618.8009659468717;3839.2513459875336;3845.1732877431486;3845.8846234613006;3865.072382628177;5637.63355381903;5733.77064917407;5794.246747922743;5817.505512031483;5879.589837894054;3938.1926705025403;3952.8616498422994;3965.861194858166;3974.8012599369918;3978.303796835542;4004.7349572704975;4010.957151140666;4015.9397609035886;4020.5175378954127;4027.6979226558124;4416.1609294233995;4515.525869783625;4605.8848528786375;4609.101225261042;4609.423822022247;5447.782088716486;5449.836504904859;5490.176473529003;5492.505674489315;5506.146547285177;1356.0914187494402;1359.9596308823757;1376.6558360409897;1377.1874593989594;1380.5582213691523;887.3052469782684;919.6137622198693;937.7468083617385;938.887333519982;941.7033634405141;5228.895422091537;5280;5290.81482148123;5314.92027619586;5322.574194064466;5677.091665333527;5755.395683453177;5779.450952159538;5784.739686714108;5786.450287222791;5092.618053645917;5250.894982100336;5260.947390526122;5271.604629652715;5308.814191503279;3054.655951381385;3069.6316442026655;3077.107638784772;3084.259777653347;3094.257378229209;5693.153423288356;5825.766969321265;5832.766689332379;5833.620814647034;5842.5618078644275;5769.365369809318;5864.912026319608;5867.266591676041;5907.202527658797;5915.970420147872;5792.391798861143;5814.098814683731;5833.82498525045;5844.912326315109;5877.38287479817;5553.6390134641115;5707.5433965281845;5722.198892155018;5764.538836892954;5771.1920331154515;5562.5549956002615;5583.302087239095;5612.915806262991;5616.326040875121;5641.576881733969;5656.1798539212405;5767.7404516796005;5832.854178645568;5836.533077353716;5847.356790752929;3920.8039598019413;3925.4111883217747;3932.6264004919485;3933.289019276624;3947.2500224957166;5811.56413269015;5812.418758124154;5813.94186058134;5818.94181058201;5829;5354.866128346823;5565.3599836019375;5572.554195664418;5581.218629391808;5591.245181900392;5527.50252477279;5564.137558678412;5570.5265052470995;5574.581906356958;5580.804671836466;5319.510537049632;5410.242566040679;5475.808346707846;5484.2596249505805;5498.752556134904;5564.693941833076;5567;5603.271574695264;5616.157576363578;5628.986782379211;5412.594055445936;5468.26178465902;5474.151506516497;5476.67139971603;5528.778848846001
21:45:33     INFO -  PID 3828 | |5;yui.html;790.9367250619944;791.6793698552086;803.4215364937243;815.522919092331;816.6488409983707;14689.706205875877;15376.769348459768;15394;15938.123403212847;16670.33318667252;13782;14332.57002289933;15795.052296862197;16034;16046;876.7851876290301;895.9104089591021;896.789254525186;898.380117718772;900.7973206028624;855.0423525651279;893.5889490834192;900.1403659505157;903.0382642485731;903.5933829776594;916.4730280088942;925.3892430995544;926.4904302633524;926.6756635177701;927.5779520318273;918.2424499787669;918.93108016899;919.3426699909563;920.0707285641483;923.3351986569693;681.2812482830611;682.1779755394738;683.5454422808826;683.548857753885;683.6035099642196;14997;15168.696626067527;15528.912976091679;15550.844491555054;15661.608459788597;15274.541763747106;15339.846601533953;15543.222838858012;15701.764473532907;15791.92104039484;11959.043276537855;12198.939005305005;12255.571055013032;12316.076294277966;12357.876421235762;3182.2521707398973;3187.4103290891026;3195.025517217248;3197.8560964756407;3203.19920019995;64408.67795661039;64959;65245.67377163084;65816.6709166451;66698;11585.073194144448;11631.59289424883;11680.415979201007;11689.415529223505;11689.473973671205;20261.29085482001;20356.491087722927;20704.58590828175;20814.271500497714;20964.266250681154;7337.339639432476;7659.846803064019;7691.961585185928;7692.884606730903;7698.653560589787;3773.9433908491396;3848.191879705293;3860.6332398422105;3867.941980870289;3873.1672690371142;3304.2730599268602;3353.698167164967;3372.51098590703;3434.364642541138;3434.9313013739584;658.5752189837528;666.4701562258017;666.506784979112;667.0694381338006;668.7325069972051;633.1610615933906;650.9869802604017;652.709544252803;656.6880731652427;677.9051831292526;8931;9012.504312262889;9066.138716821892;9199.4940278284;9830.950845245728;640.4108220437209;649.818050945736;655.416679155552;666.3935818405228;673.8045966669611;4359.585839345257;4364.432623758891;4466.59800617946;4570.697351254877;4610.861674149781;51291.48708512942;52167.217491737654;52592.474075259524;52698.736506317226;52979;47132.5286747135;47162.5283747165;47753;47777.761111194224;47971.80070498196;43292;43574.78212608853;43722;43859.12281754347;43869.122617548106;10078.344907580984;10135.341202821794;10347.068763811292;10349.9482502587;10407.167426606005;22398.552028959657;22707.205247816248;23104;23113.653295200922;23569.41076473033;28695.713042869305;38249.79478122346;39857.60142398539;40581.797091014356;41276.55532056364;12725.55460558876;12872.35638218104;12944.935275323563;12988.285644289655;13075.934620326838;13359.53241636538;13517.662058448617;13593.320333983063;13726.656833578842;13760.04159105797;12561.371931403575;12598.307093109966;12616.87383126157;12652;12706.30115343628;12800.231986080866;12859.549915752903;13014.349282536024;13036.022298327853;13057.21656700601;13149.802752958522;13294.268815215257;13311.600651980456;13561.932190338985;13699.726005480034;7428.8456347658575;7432.442566807619;7454.850902982019;7570.470067095471;7597.696092156473;8377.83244335122;8514.361422893184;8572.657093716181;8638.352123590881;8684.78288042804
21:45:33     INFO -  PID 3828 | __end_tp_report
21:45:33     INFO -  PID 3828 | __start_cc_report
21:45:33     INFO -  PID 3828 | _x_x_mozilla_cycle_collect,354
21:45:33     INFO -  PID 3828 | __end_cc_report
21:45:33     INFO -  PID 3828 | __startTimestamp1492145133209__endTimestamp
21:45:33     INFO -  PID 3828 |
21:45:33     INFO -  PID 3828 | ------- Summary: start -------
21:45:33     INFO -  PID 3828 | Number of tests: 6
21:45:33     INFO -  PID 3828 |
21:45:33     INFO -  PID 3828 | [#0] dojo.html  Cycles:165  Average:4999.11  Median:5193.30  stddev:630.36 (12.1%)  stddev-sans-first:610.44
21:45:33     INFO -  PID 3828 | Values: 2901.5  2903.7  2927.7  2931.1  2939.4  4943.7  5036.0  5058.2  5093.2  5122.9  4945.8  4982.6  5022.5  5118.0  5228.2  4618.5  4686.4  4697.6  4765.8  4775.6  4585.0  4625.2  4720.9  4725.6  4751.7  4501.5  4649.7  4696.8  4711.9  4748.7  4619.5  4643.3  4656.5  4704.8  4709.1  4641.5  4666.6  4688.8  4692.4  4704.9  4912.5  5112.3  5138.3  5145.6  5214.0  5207.8  5439.9  5517.6  5525.3  5540.9  5494.2  5514.3  5523.0  5523.8  5525.8  5010.8  5043.5  5061.5  5062.1  5111.4  5264.7  5373.3  5381.5  5395.7  5405.7  5150.9  5175.3  5186.5  5193.3  5194.3  5427.6  5443.0  5473.0  5493.9  5494.9  5362.2  5404.4  5419.3  5422.5  5425.0  5172.0  5331.8  5375.5  5389.4  5428.8  3533.5  3590.2  3598.5  3612.9  3646.0  3281.0  3288.2  3294.5  3311.6  3319.6  4970.3  4977.1  4996.7  5014.4  5044.5  5292.5  5327.8  5332.3  5332.6  5333.8  4945.4  4954.6  4964.4  4975.4  4977.5  4459.6  4464.7  4530.3  4559.9  4587.8  5562.7  5567.5  5583.1  5586.2  5610.2  5460.9  5475.2  5488.6  5495.8  5498.4  5328.8  5344.5  5407.2  5507.2  5534.0  5453.8  5463.4  5477.0  5480.3  5526.6  5544.6  5564.1  5572.3  5575.9  5576.4  5114.1  5189.0  5384.6  5390.8  5431.5  5197.3  5214.6  5214.9  5219.1  5228.3  5138.9  5149.9  5160.1  5212.3  5259.9  5378.8  5404.7  5427.4  5427.7  5468.8  5260.0  5275.4  5291.8  5303.7  5354.9
21:45:33     INFO -  PID 3828 |
21:45:33     INFO -  PID 3828 | [#1] ext.html  Cycles:180  Average:30550.29  Median:3108.43  stddev:42694.18 (1373.5%)  stddev-sans-first:42754.61
21:45:33     INFO -  PID 3828 | Values: 574.5  614.1  633.5  1124.4  1136.5  32450.0  33205.8  33249.3  33318.3  33321.8  31523.5  31922.5  31935.0  32010.2  32093.4  6418.4  6779.7  6934.0  6964.4  6981.2  7027.1  7104.5  7123.7  7124.3  7136.6  7014.3  7098.0  7154.5  7166.2  7167.7  7120.9  7125.4  7146.2  7148.6  7148.7  6986.3  7013.2  7033.5  7035.7  7037.5  31322.2  31351.7  31441.5  31577.2  31609.9  3033.0  3093.5  3106.7  3110.1  3116.2  25016.9  25053.9  25115.6  25159.9  25169.2  3579.6  3610.3  3648.5  3653.4  3676.6  130101.0  131447.3  132748.0  135029.0  135861.3  36101.3  36106.5  36283.5  36316.5  36401.6  37868.2  38057.6  39756.2  41124.8  41224.6  10856.4  10912.5  10916.6  10952.9  10964.6  8290.6  8411.6  8475.4  8476.3  8614.4  12067.8  12132.5  12205.6  12236.4  12340.5  4972.7  5035.2  5049.8  5094.5  5114.6  969.4  987.0  992.2  997.3  1010.4  20680.2  20792.2  21302.8  21698.2  21768.3  1021.7  1028.8  1039.0  1043.9  1053.1  11399.5  11438.1  11454.1  11496.2  11628.8  183019.0  183400.0  184069.0  184705.0  184717.0  40139.6  40156.4  40361.6  40511.4  40542.0  1272.5  1298.6  1299.1  1304.8  1319.1  20155.5  20780.9  21231.4  24105.4  24892.8  35858.3  38003.0  38082.4  38124.0  39144.6  172884.0  173741.0  174674.1  174708.1  175270.0  28458.1  28837.3  28890.9  28920.7  28992.3  34247.8  34462.1  34588.7  34619.5  34728.7  26192.9  26814.9  26883.7  26971.3  27782.4  26233.0  26683.5  26689.3  26842.3  26990.3  29545.3  29609.3  29927.0  29943.0  30036.2  26134.6  26438.5  26474.0  26718.2  26890.6  21389.1  21839.6  22122.2  22241.9  22243.3
21:45:33     INFO -  PID 3828 |
21:45:33     INFO -  PID 3828 | [#2] jquery.html  Cycles:180  Average:24902.25  Median:404.68  stddev:62045.43 (15331.8%)  stddev-sans-first:62192.22
21:45:33     INFO -  PID 3828 | Values: 404.1  405.3  409.0  415.1  421.9  17640.0  18017.1  18346.6  18375.6  18772.7  15663.5  15737.0  15839.7  15960.0  16011.5  5214.1  5256.7  5441.0  5446.8  5448.9  5239.7  5398.7  5403.2  5412.4  5414.5  5289.0  5361.2  5362.4  5376.2  5396.9  5191.9  5218.9  5313.9  5335.1  5335.8  4813.0  4833.5  4836.1  4842.5  4864.2  20586.9  20655.3  20788.0  20871.1  20877.0  1744.5  1775.2  1782.5  1787.2  1796.4  13931.7  14151.2  14895.8  14942.1  14946.9  2087.5  2117.3  2142.6  2144.7  2147.3  82644.2  84517.6  84688.0  84884.0  85052.6  10241.2  10508.7  10611.3  10676.6  10766.6  14880.9  15031.8  15048.7  15090.1  15194.7  7098.8  7122.3  7155.9  7270.7  7278.5  6465.7  6504.6  6588.5  6590.9  6604.3  6763.9  6875.4  6888.7  6910.7  6921.5  1937.9  1942.3  1945.8  1951.3  2106.3  938.5  960.3  963.3  964.5  984.3  17348.4  17364.9  17622.7  17776.2  18061.8  948.4  950.6  955.2  959.6  974.3  9447.4  9840.4  9858.7  9951.3  9972.5  358249.0  358866.0  362982.0  382732.0  395192.0  83635.0  85023.6  85290.0  86028.0  86121.0  1037.6  1054.5  1085.8  1091.8  1096.1  17026.9  17056.7  17565.4  17657.4  17809.2  39131.0  39159.4  39473.2  39617.2  41104.4  50141.0  50817.0  50858.5  51678.7  52630.7  7479.1  7678.7  7689.2  7788.9  7792.8  11456.7  11480.1  11623.1  11624.2  12501.0  9848.4  9902.7  10160.4  10165.4  10274.3  10015.3  10045.9  10071.1  10175.8  10302.9  9501.7  9615.8  10340.5  10588.3  10702.4  7700.0  7986.0  8011.9  8083.9  8138.9  10317.3  10405.5  10668.2  10709.3  11107.8
21:45:33     INFO -  PID 3828 |
21:45:33     INFO -  PID 3828 | [#3] mootools.html  Cycles:180  Average:5711.03  Median:4512.57  stddev:3568.93 (79.1%)  stddev-sans-first:3562.92
21:45:33     INFO -  PID 3828 | Values: 1211.4  1288.2  1288.8  1301.9  1305.2  8910.0  9032.7  9216.0  9326.3  9341.0  8938.3  9066.5  9086.8  9086.8  9131.7  2074.5  2083.1  2090.1  2098.2  2108.3  1928.0  1947.7  1954.6  2044.5  2077.4  1998.0  2083.7  2102.6  2113.0  2116.9  2084.7  2086.6  2094.3  2097.8  2102.3  1985.4  1985.5  2006.1  2027.6  2095.0  9118.2  9225.0  9257.0  9297.9  9299.3  4862.4  5002.0  5027.5  5034.1  5048.2  3026.2  3156.1  3222.1  3227.7  3250.1  1127.1  1172.1  1173.8  1179.4  1190.6  12899.2  12916.7  12923.7  13017.5  13030.9  4135.1  4185.9  4312.8  4324.7  4381.6  9742.5  9817.0  9835.1  9844.8  9860.8  4919.2  4919.6  4920.8  4930.9  4941.1  4243.2  4449.7  4535.7  4540.2  4553.0  3495.8  3531.6  3535.2  3565.7  3596.4  1021.0  1025.1  1028.4  1029.4  1029.8  655.4  681.9  692.2  693.6  696.7  4769.8  4782.3  5750.3  5879.8  6046.7  817.3  843.7  847.3  848.3  854.0  3206.2  3283.6  3315.6  3379.9  3605.0  11429.3  11861.6  11870.5  11882.5  11908.5  11691.9  11717.6  11764.4  11769.8  11791.5  8451.9  8510.4  8610.5  8696.5  8867.9  5261.8  5439.9  5494.9  5549.2  5720.5  10324.5  10347.3  10388.2  10433.3  10491.6  10527.7  10530.1  10668.3  10668.8  10685.5  7324.3  7571.8  7575.3  7580.4  7597.9  8758.2  8778.3  8802.0  8822.6  8831.4  7354.3  7408.6  7497.5  7546.8  7619.6  7246.4  7256.1  7339.6  7408.0  7423.2  6942.1  6952.3  6979.5  7018.2  7030.4  4378.6  4419.4  4456.7  4666.3  4762.5  4501.0  4524.2  4536.8  4590.4  4623.7
21:45:33     INFO -  PID 3828 |
21:45:33     INFO -  PID 3828 | [#4] prototype.html  Cycles:180  Average:4648.89  Median:5338.72  stddev:1383.57 (25.9%)  stddev-sans-first:1349.53
21:45:33     INFO -  PID 3828 | Values: 362.8  387.7  392.8  395.3  398.5  5380.6  5383.5  5398.5  5415.1  5440.2  5101.6  5241.4  5253.4  5281.3  5286.3  4063.4  4104.1  4118.4  4124.0  4128.7  4093.3  4125.0  4137.6  4142.7  4150.2  3820.3  4037.4  4039.8  4045.6  4099.1  4027.3  4031.6  4063.8  4070.4  4073.3  3533.3  3550.4  3551.4  3552.5  3569.9  5005.4  5140.9  5167.6  5170.6  5171.8  4635.7  4636.2  4648.8  4656.6  4678.6  5497.5  5518.2  5518.6  5523.2  5539.5  3618.8  3839.3  3845.2  3845.9  3865.1  5637.6  5733.8  5794.2  5817.5  5879.6  3938.2  3952.9  3965.9  3974.8  3978.3  4004.7  4011.0  4015.9  4020.5  4027.7  4416.2  4515.5  4605.9  4609.1  4609.4  5447.8  5449.8  5490.2  5492.5  5506.1  1356.1  1360.0  1376.7  1377.2  1380.6  887.3  919.6  937.7  938.9  941.7  5228.9  5280.0  5290.8  5314.9  5322.6  5677.1  5755.4  5779.5  5784.7  5786.5  5092.6  5250.9  5260.9  5271.6  5308.8  3054.7  3069.6  3077.1  3084.3  3094.3  5693.2  5825.8  5832.8  5833.6  5842.6  5769.4  5864.9  5867.3  5907.2  5916.0  5792.4  5814.1  5833.8  5844.9  5877.4  5553.6  5707.5  5722.2  5764.5  5771.2  5562.6  5583.3  5612.9  5616.3  5641.6  5656.2  5767.7  5832.9  5836.5  5847.4  3920.8  3925.4  3932.6  3933.3  3947.3  5811.6  5812.4  5813.9  5818.9  5829.0  5354.9  5565.4  5572.6  5581.2  5591.2  5527.5  5564.1  5570.5  5574.6  5580.8  5319.5  5410.2  5475.8  5484.3  5498.8  5564.7  5567.0  5603.3  5616.2  5629.0  5412.6  5468.3  5474.2  5476.7  5528.8
21:45:33     INFO -  PID 3828 |
21:45:33     INFO -  PID 3828 | [#5] yui.html  Cycles:180  Average:14070.71  Median:43433.39  stddev:15854.53 (36.5%)  stddev-sans-first:15867.64
21:45:33     INFO -  PID 3828 | Values: 790.9  791.7  803.4  815.5  816.6  14689.7  15376.8  15394.0  15938.1  16670.3  13782.0  14332.6  15795.1  16034.0  16046.0  876.8  895.9  896.8  898.4  900.8  855.0  893.6  900.1  903.0  903.6  916.5  925.4  926.5  926.7  927.6  918.2  918.9  919.3  920.1  923.3  681.3  682.2  683.5  683.5  683.6  14997.0  15168.7  15528.9  15550.8  15661.6  15274.5  15339.8  15543.2  15701.8  15791.9  11959.0  12198.9  12255.6  12316.1  12357.9  3182.3  3187.4  3195.0  3197.9  3203.2  64408.7  64959.0  65245.7  65816.7  66698.0  11585.1  11631.6  11680.4  11689.4  11689.5  20261.3  20356.5  20704.6  20814.3  20964.3  7337.3  7659.8  7692.0  7692.9  7698.7  3773.9  3848.2  3860.6  3867.9  3873.2  3304.3  3353.7  3372.5  3434.4  3434.9  658.6  666.5  666.5  667.1  668.7  633.2  651.0  652.7  656.7  677.9  8931.0  9012.5  9066.1  9199.5  9831.0  640.4  649.8  655.4  666.4  673.8  4359.6  4364.4  4466.6  4570.7  4610.9  51291.5  52167.2  52592.5  52698.7  52979.0  47132.5  47162.5  47753.0  47777.8  47971.8  43292.0  43574.8  43722.0  43859.1  43869.1  10078.3  10135.3  10347.1  10349.9  10407.2  22398.6  22707.2  23104.0  23113.7  23569.4  28695.7  38249.8  39857.6  40581.8  41276.6  12725.6  12872.4  12944.9  12988.3  13075.9  13359.5  13517.7  13593.3  13726.7  13760.0  12561.4  12598.3  12616.9  12652.0  12706.3  12800.2  12859.5  13014.3  13036.0  13057.2  13149.8  13294.3  13311.6  13561.9  13699.7  7428.8  7432.4  7454.9  7570.5  7597.7  8377.8  8514.4  8572.7  8638.4  8684.8
21:45:33     INFO -  PID 3828 | -------- Summary: end --------
21:45:33     INFO -  PID 3828 |
21:45:33     INFO -  PID 3828 | Unable to read VR Path Registry from C:\Users\cltbld.T-W864-IX-271\AppData\Local\openvr\openvrpaths.vrpath
21:45:33     INFO -  PID 3828 | [GPU 984] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w64-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
21:45:33     INFO -  PID 3828 | [GPU 984] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w64-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
21:45:33     INFO -  PID 3828 | [Child 3648] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w64-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
21:45:33     INFO -  PID 3828 | [Child 3648] WARNING: pipe error: 109: file c:/builds/moz2_slave/m-cen-w64-00000000000000000000/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
21:45:34     INFO -  PID 3828 | Unable to read VR Path Registry from C:\Users\cltbld.T-W864-IX-271\AppData\Local\openvr\openvrpaths.vrpath
21:45:34     INFO -  TEST-INFO | 3828: exit 0
21:45:34     INFO -  mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.crashreporter-symbols.zip
21:45:37     INFO -  mozcrash Copy/paste: C:\slave\test\build\win32-minidump_stackwalk.exe c:\users\cltbld~1.t-w\appdata\local\temp\tmpnh5dor\profile\minidumps\b5e7f035-8ccd-489a-b65e-759850ee5f18.dmp c:\users\cltbld~1.t-w\appdata\local\temp\tmpgtweri
21:45:38     INFO -  mozcrash Saved minidump as C:\slave\test\build\blobber_upload_dir\b5e7f035-8ccd-489a-b65e-759850ee5f18.dmp
21:45:38     INFO -  PROCESS-CRASH | dromaeo_css | application crashed [unknown top frame]
21:45:38     INFO -  Crash dump filename: c:\users\cltbld~1.t-w\appdata\local\temp\tmpnh5dor\profile\minidumps\b5e7f035-8ccd-489a-b65e-759850ee5f18.dmp
21:45:38     INFO -  stderr from minidump_stackwalk:
21:45:38     INFO -  2017-04-13 21:45:38: minidump.cc:4359: INFO: Minidump opened minidump c:\users\cltbld~1.t-w\appdata\local\temp\tmpnh5dor\profile\minidumps\b5e7f035-8ccd-489a-b65e-759850ee5f18.dmp
21:45:38     INFO -  2017-04-13 21:45:38: minidump.cc:4479: INFO: Minidump not byte-swapping minidump
21:45:38     INFO -  2017-04-13 21:45:38: minidump.cc:4956: INFO: GetStream: type 15 not present
21:45:38     INFO -  2017-04-13 21:45:38: minidump.cc:4956: INFO: GetStream: type 7 not present
21:45:38     INFO -  2017-04-13 21:45:38: minidump.cc:4956: INFO: GetStream: type 7 not present
21:45:38     INFO -  2017-04-13 21:45:38: minidump.cc:4956: INFO: GetStream: type 1197932545 not present
21:45:38     INFO -  2017-04-13 21:45:38: minidump.cc:4956: INFO: GetStream: type 6 not present
21:45:38     INFO -  2017-04-13 21:45:38: minidump.cc:4956: INFO: GetStream: type 1197932546 not present
21:45:38     INFO -  2017-04-13 21:45:38: minidump.cc:4956: INFO: GetStream: type 4 not present
21:45:38     INFO -  2017-04-13 21:45:38: minidump.cc:4956: INFO: GetStream: type 5 not present
21:45:38     INFO -  2017-04-13 21:45:38: minidump.cc:4956: INFO: GetStream: type 3 not present
21:45:38     INFO -  2017-04-13 21:45:38: minidump_processor.cc:152: ERROR: Minidump c:\users\cltbld~1.t-w\appdata\local\temp\tmpnh5dor\profile\minidumps\b5e7f035-8ccd-489a-b65e-759850ee5f18.dmp has no thread list
21:45:38     INFO -  2017-04-13 21:45:38: stackwalk.cc:139: ERROR: MinidumpProcessor::Process failed
21:45:38     INFO -  2017-04-13 21:45:38: minidump.cc:4331: INFO: Minidump closing minidump
21:45:38     INFO -  minidump_stackwalk exited with return code 1
21:45:38     INFO -  TEST-UNEXPECTED-ERROR | dromaeo_css | Found crashes after test run, terminating test
21:45:38    ERROR -  Traceback (most recent call last):
21:45:38     INFO -    File "C:\slave\test\build\tests\talos\talos\run_tests.py", line 202, in run_tests
21:45:38     INFO -      talos_results.add(mytest.runTest(browser_config, test))
21:45:38     INFO -    File "C:\slave\test\build\tests\talos\talos\ttest.py", line 70, in runTest
21:45:38     INFO -      return self._runTest(browser_config, test_config, setup)
21:45:38     INFO -    File "C:\slave\test\build\tests\talos\talos\ttest.py", line 243, in _runTest
21:45:38     INFO -      test_config['name'])
21:45:38     INFO -    File "C:\slave\test\build\tests\talos\talos\ttest.py", line 47, in check_for_crashes
21:45:38     INFO -      raise TalosCrash("Found crashes after test run, terminating test")
21:45:38     INFO -  TalosCrash: Found crashes after test run, terminating test
21:45:38     INFO -  TEST-INFO took 1129895ms
21:45:38     INFO -  SUITE-END | took 1129s
21:45:39    ERROR - Return code: 2
21:45:39  WARNING - setting return code to 2
21:45:39    ERROR - # TBPL FAILURE #
21:45:39     INFO - Running post-action listener: _resource_record_post_action
21:45:39     INFO - [mozharness: 2017-04-14 04:45:39.074000Z] Finished run-tests step (success)
21:45:39     INFO - Running post-run listener: _resource_record_post_run
21:45:39     INFO - Total resource usage - Wall time: 1135s; CPU: 14.0%; Read bytes: 234983936; Write bytes: 272655360; Read time: 7258450; Write time: 3993160
21:45:39     INFO - TinderboxPrint: CPU usage<br/>13.6%
21:45:39     INFO - TinderboxPrint: I/O read bytes / time<br/>234,983,936 / 7,258,450
21:45:39     INFO - TinderboxPrint: I/O write bytes / time<br/>272,655,360 / 3,993,160
21:45:39     INFO - TinderboxPrint: CPU idle<br/>7,846.0 (86.4%)
21:45:39     INFO - TinderboxPrint: CPU user<br/>1,187.7 (13.1%)
21:45:39     INFO - install - Wall time: 3s; CPU: 9.0%; Read bytes: 0; Write bytes: 34284032; Read time: 0; Write time: 69190
21:45:39     INFO - run-tests - Wall time: 1133s; CPU: 14.0%; Read bytes: 234983936; Write bytes: 208732672; Read time: 7258450; Write time: 3824970
21:45:39     INFO - Running post-run listener: _upload_blobber_files
21:45:39     INFO - Blob upload gear active.
21:45:39     INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
21:45:39     INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with <Firefox-Non-PGO> branch at the following location(s): https://blobupload.elasticbeanstalk.com
21:45:39     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', 'Firefox-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
21:45:39     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 Firefox-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
21:45:40     INFO -  (blobuploader) - INFO - Open directory for files ...
21:45:40     INFO -  (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\b5e7f035-8ccd-489a-b65e-759850ee5f18.dmp ...
21:45:40     INFO -  (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
21:45:40     INFO -  (blobuploader) - INFO - Uploading, attempt #1.
21:45:41     INFO -  (blobuploader) - INFO - TinderboxPrint: <a href='http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78765a53215234dabe6fa7a1878f89462301775b148bf375437e336544312add0ae33bffb75c0a1da8f0ff12d1e2c0575402f3d313bace317c36ef70dcbd0fd5'>b5e7f035-8ccd-489a-b65e-759850ee5f18.dmp</a>: uploaded
21:45:41     INFO -  (blobuploader) - INFO - Blobserver returned 202. File uploaded!
21:45:41     INFO -  (blobuploader) - INFO - Done attempting.
21:45:41     INFO -  (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_errorsummary.log ...
21:45:41     INFO -  (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
21:45:41     INFO -  (blobuploader) - INFO - Uploading, attempt #1.
21:45:41     INFO -  (blobuploader) - INFO - TinderboxPrint: <a href='http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/597985324d1a02f0a4d877457d7fa23b362927d6778312fea3b90ad45b241315b5d1219f5ea628dc7dff7344e81dff2fabb84a180bdffa46690b6901268e7102'>dromaeojs-e10s_errorsummary.log</a>: uploaded
21:45:41     INFO -  (blobuploader) - INFO - Blobserver returned 202. File uploaded!
21:45:41     INFO -  (blobuploader) - INFO - Done attempting.
21:45:41     INFO -  (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs-e10s_raw.log ...
21:45:41     INFO -  (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
21:45:41     INFO -  (blobuploader) - INFO - Uploading, attempt #1.
21:45:42     INFO -  (blobuploader) - INFO - TinderboxPrint: <a href='http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34bbc8d1e446c924b4eac749f735226815df3f7b98e1e63d44df7c5435e1d8e3a64e1f0e37857b9b73ec542de6c5d1b89bc6113acc7af59cd3f987f8609050bb'>dromaeojs-e10s_raw.log</a>: uploaded
21:45:42     INFO -  (blobuploader) - INFO - Blobserver returned 202. File uploaded!
21:45:42     INFO -  (blobuploader) - INFO - Done attempting.
21:45:42     INFO -  (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
21:45:42     INFO -  (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
21:45:42     INFO -  (blobuploader) - INFO - Uploading, attempt #1.
21:45:45     INFO -  (blobuploader) - INFO - TinderboxPrint: <a href='http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c25a7afaacf7d90c2798b6da0de16aac23e71393436e1fc543afc165ed1175471fc33618273f50083d9b79cb6d1c555d54efa30980a3c442a0c888abb4a5632a'>resource-usage.json</a>: uploaded
21:45:45     INFO -  (blobuploader) - INFO - Blobserver returned 202. File uploaded!
21:45:45     INFO -  (blobuploader) - INFO - Done attempting.
21:45:45     INFO -  (blobuploader) - INFO - Iteration through files over.
21:45:45     INFO - Return code: 0
21:45:45     INFO - rmtree: C:\slave\test\build\uploaded_files.json
21:45:45     INFO - Using _rmtree_windows ...
21:45:45     INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
21:45:45     INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/597985324d1a02f0a4d877457d7fa23b362927d6778312fea3b90ad45b241315b5d1219f5ea628dc7dff7344e81dff2fabb84a180bdffa46690b6901268e7102", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c25a7afaacf7d90c2798b6da0de16aac23e71393436e1fc543afc165ed1175471fc33618273f50083d9b79cb6d1c555d54efa30980a3c442a0c888abb4a5632a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34bbc8d1e446c924b4eac749f735226815df3f7b98e1e63d44df7c5435e1d8e3a64e1f0e37857b9b73ec542de6c5d1b89bc6113acc7af59cd3f987f8609050bb", "b5e7f035-8ccd-489a-b65e-759850ee5f18.dmp": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78765a53215234dabe6fa7a1878f89462301775b148bf375437e336544312add0ae33bffb75c0a1da8f0ff12d1e2c0575402f3d313bace317c36ef70dcbd0fd5"}
21:45:45     INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
21:45:45     INFO - Writing to file C:\slave\test\properties\blobber_files
21:45:45     INFO - Contents:
21:45:45     INFO -  blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/597985324d1a02f0a4d877457d7fa23b362927d6778312fea3b90ad45b241315b5d1219f5ea628dc7dff7344e81dff2fabb84a180bdffa46690b6901268e7102", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c25a7afaacf7d90c2798b6da0de16aac23e71393436e1fc543afc165ed1175471fc33618273f50083d9b79cb6d1c555d54efa30980a3c442a0c888abb4a5632a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34bbc8d1e446c924b4eac749f735226815df3f7b98e1e63d44df7c5435e1d8e3a64e1f0e37857b9b73ec542de6c5d1b89bc6113acc7af59cd3f987f8609050bb", "b5e7f035-8ccd-489a-b65e-759850ee5f18.dmp": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78765a53215234dabe6fa7a1878f89462301775b148bf375437e336544312add0ae33bffb75c0a1da8f0ff12d1e2c0575402f3d313bace317c36ef70dcbd0fd5"}
21:45:45     INFO - Running post-run listener: copy_logs_to_upload_dir
21:45:45     INFO - Copying logs to upload dir...
21:45:45     INFO - mkdir: C:\slave\test\build\upload\logs
21:45:45     INFO - Copying logs to upload dir...
21:45:45     INFO - Using _rmtree_windows ...
21:45:45     INFO - Using _rmtree_windows ...
21:45:45     INFO - Using _rmtree_windows ...
21:45:45     INFO - Using _rmtree_windows ...
21:45:45     INFO - Using _rmtree_windows ...
21:45:45     INFO - Using _rmtree_windows ...
21:45:45     INFO - Using _rmtree_windows ...
21:45:45  WARNING - returning nonzero exit status 2
program finished with exit code 2
elapsedTime=1402.391000
========= master_lag: 0.30 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' failed (results: 2, elapsed: 23 mins, 22 secs) (at 2017-04-13 21:45:45.026622) =========

========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 21:45:45.032391) =========
'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-271\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-271
  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-271
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-271\AppData\Local
  LOGONSERVER=\\T-W864-IX-271
  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-271
  USERDOMAIN_ROAMINGPROFILE=T-W864-IX-271
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld.T-W864-IX-271
  WINDIR=C:\windows
  XPCOM_DEBUG_BREAK=warn
 using PTY: False
blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/597985324d1a02f0a4d877457d7fa23b362927d6778312fea3b90ad45b241315b5d1219f5ea628dc7dff7344e81dff2fabb84a180bdffa46690b6901268e7102", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c25a7afaacf7d90c2798b6da0de16aac23e71393436e1fc543afc165ed1175471fc33618273f50083d9b79cb6d1c555d54efa30980a3c442a0c888abb4a5632a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34bbc8d1e446c924b4eac749f735226815df3f7b98e1e63d44df7c5435e1d8e3a64e1f0e37857b9b73ec542de6c5d1b89bc6113acc7af59cd3f987f8609050bb", "b5e7f035-8ccd-489a-b65e-759850ee5f18.dmp": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78765a53215234dabe6fa7a1878f89462301775b148bf375437e336544312add0ae33bffb75c0a1da8f0ff12d1e2c0575402f3d313bace317c36ef70dcbd0fd5"}
build_url:https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/WzSY9VGrQbecPTfYeZjPVQ/artifacts/public/build/firefox-55.0a1.en-US.win64.zip'
blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/597985324d1a02f0a4d877457d7fa23b362927d6778312fea3b90ad45b241315b5d1219f5ea628dc7dff7344e81dff2fabb84a180bdffa46690b6901268e7102", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c25a7afaacf7d90c2798b6da0de16aac23e71393436e1fc543afc165ed1175471fc33618273f50083d9b79cb6d1c555d54efa30980a3c442a0c888abb4a5632a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34bbc8d1e446c924b4eac749f735226815df3f7b98e1e63d44df7c5435e1d8e3a64e1f0e37857b9b73ec542de6c5d1b89bc6113acc7af59cd3f987f8609050bb", "b5e7f035-8ccd-489a-b65e-759850ee5f18.dmp": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/78765a53215234dabe6fa7a1878f89462301775b148bf375437e336544312add0ae33bffb75c0a1da8f0ff12d1e2c0575402f3d313bace317c36ef70dcbd0fd5"}'
========= master_lag: 0.03 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 21:45:45.158820) =========

========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 21:45:45.159155) =========
'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-271\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-271
  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-271
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld.T-W864-IX-271\AppData\Local
  LOGONSERVER=\\T-W864-IX-271
  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-271
  USERDOMAIN_ROAMINGPROFILE=T-W864-IX-271
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld.T-W864-IX-271
  WINDIR=C:\windows
  XPCOM_DEBUG_BREAK=warn
 using PTY: False
program finished with exit code 0
elapsedTime=0.100000
========= master_lag: 0.02 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 21:45:45.283440) =========

========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2017-04-13 21:45:45.283752) =========

========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2017-04-13 21:45:45.800945) =========

========= Total master_lag: 0.54 =========