builder: oak_win7_ix_test_pgo-media-youtube-tests
slave: t-w732-ix-169
starttime: 1468035658.84
results: warnings (1)
buildid: 20160708183002
builduid: b3dde368fafd48a083e382e5fd0f73d9
revision: b8c431a838bbea940f4ff410fa119d614fa9c222

========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 20:40:58.844907) =========
master: http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 20:40:58.845841) =========

========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 20:40:58.846150) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 20:40:58.999520) =========

========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 20:40:58.999782) =========
'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\AppData\Roaming
  COMMONPROGRAMFILES=C:\Program Files\Common Files
  COMPUTERNAME=T-W732-IX-169
  COMSPEC=C:\windows\system32\cmd.exe
  DCLOCATION=SCL3
  DNSSUFFIX=wintest.releng.scl3.mozilla.com
  FP_NO_HOST_CHECK=NO
  HOMEDRIVE=C:
  HOMEPATH=\Users\cltbld
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld\AppData\Local
  LOGONSERVER=\\T-W732-IX-169
  MONDIR=C:\Monitor_config\
  MOZBUILDDIR=C:\mozilla-build\
  NUMBER_OF_PROCESSORS=8
  OS=Windows_NT
  OURDRIVE=C:
  PATH=C:\Program Files\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\;c:\Program Files\Microsoft Windows Performance Toolkit\;C:\mozilla-build\hg
  PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
  PROCESSOR_ARCHITECTURE=x86
  PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
  PROCESSOR_LEVEL=6
  PROCESSOR_REVISION=1e05
  PROGRAMDATA=C:\ProgramData
  PROGRAMFILES=C:\Program Files
  PROMPT=$P$G
  PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
  PUBLIC=C:\Users\Public
  PWD=C:\slave\test
  RUNLOGFILE=C:\slave\\runslave.log
  SLAVEDIR=C:\slave\
  SYSTEMDRIVE=C:
  SYSTEMROOT=C:\windows
  TEMP=C:\Users\cltbld\AppData\Local\Temp
  TEST1=testie
  TMP=C:\Users\cltbld\AppData\Local\Temp
  USERDOMAIN=T-W732-IX-169
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  WINDIR=C:\windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
 using PTY: False
--20:40:59--  https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
           => `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,141 (12K) [text/x-python]

    0K .......... .                                          100%    2.27 GB/s

20:41:00 (2.27 GB/s) - `archiver_client.py' saved [12141/12141]

program finished with exit code 0
elapsedTime=1.109000
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 20:41:00.140035) =========

========= Started 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-07-08 20:41:00.140570) =========
'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\AppData\Roaming
  COMMONPROGRAMFILES=C:\Program Files\Common Files
  COMPUTERNAME=T-W732-IX-169
  COMSPEC=C:\windows\system32\cmd.exe
  DCLOCATION=SCL3
  DNSSUFFIX=wintest.releng.scl3.mozilla.com
  FP_NO_HOST_CHECK=NO
  HOMEDRIVE=C:
  HOMEPATH=\Users\cltbld
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld\AppData\Local
  LOGONSERVER=\\T-W732-IX-169
  MONDIR=C:\Monitor_config\
  MOZBUILDDIR=C:\mozilla-build\
  NUMBER_OF_PROCESSORS=8
  OS=Windows_NT
  OURDRIVE=C:
  PATH=C:\Program Files\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\;c:\Program Files\Microsoft Windows Performance Toolkit\;C:\mozilla-build\hg
  PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
  PROCESSOR_ARCHITECTURE=x86
  PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
  PROCESSOR_LEVEL=6
  PROCESSOR_REVISION=1e05
  PROGRAMDATA=C:\ProgramData
  PROGRAMFILES=C:\Program Files
  PROMPT=$P$G
  PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
  PUBLIC=C:\Users\Public
  PWD=C:\slave\test
  RUNLOGFILE=C:\slave\\runslave.log
  SLAVEDIR=C:\slave\
  SYSTEMDRIVE=C:
  SYSTEMROOT=C:\windows
  TEMP=C:\Users\cltbld\AppData\Local\Temp
  TEST1=testie
  TMP=C:\Users\cltbld\AppData\Local\Temp
  USERDOMAIN=T-W732-IX-169
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  WINDIR=C:\windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
 using PTY: False
program finished with exit code 0
elapsedTime=3.501000
========= master_lag: 0.02 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 3 secs) (at 2016-07-08 20:41:03.657441) =========

========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 20:41:03.657836) =========
'bash' '-c' u'python archiver_client.py mozharness --repo projects/oak --rev b8c431a838bbea940f4ff410fa119d614fa9c222 --destination scripts --debug'
 in dir C:\slave\test\. (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/oak --rev b8c431a838bbea940f4ff410fa119d614fa9c222 --destination scripts --debug']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  COMMONPROGRAMFILES=C:\Program Files\Common Files
  COMPUTERNAME=T-W732-IX-169
  COMSPEC=C:\windows\system32\cmd.exe
  DCLOCATION=SCL3
  DNSSUFFIX=wintest.releng.scl3.mozilla.com
  FP_NO_HOST_CHECK=NO
  HOMEDRIVE=C:
  HOMEPATH=\Users\cltbld
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld\AppData\Local
  LOGONSERVER=\\T-W732-IX-169
  MONDIR=C:\Monitor_config\
  MOZBUILDDIR=C:\mozilla-build\
  NUMBER_OF_PROCESSORS=8
  OS=Windows_NT
  OURDRIVE=C:
  PATH=C:\Program Files\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\;c:\Program Files\Microsoft Windows Performance Toolkit\;C:\mozilla-build\hg
  PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
  PROCESSOR_ARCHITECTURE=x86
  PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
  PROCESSOR_LEVEL=6
  PROCESSOR_REVISION=1e05
  PROGRAMDATA=C:\ProgramData
  PROGRAMFILES=C:\Program Files
  PROMPT=$P$G
  PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
  PUBLIC=C:\Users\Public
  PWD=C:\slave\test
  RUNLOGFILE=C:\slave\\runslave.log
  SLAVEDIR=C:\slave\
  SYSTEMDRIVE=C:
  SYSTEMROOT=C:\windows
  TEMP=C:\Users\cltbld\AppData\Local\Temp
  TEST1=testie
  TMP=C:\Users\cltbld\AppData\Local\Temp
  USERDOMAIN=T-W732-IX-169
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  WINDIR=C:\windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
 using PTY: False
2016-07-08 20:41:03,996 truncating revision to first 12 chars
2016-07-08 20:41:03,996 Setting DEBUG logging.
2016-07-08 20:41:03,996 attempt 1/10
2016-07-08 20:41:03,996 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/oak/b8c431a838bb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-07-08 20:41:04,489 unpacking tar archive at: oak-b8c431a838bb/testing/mozharness/
2016-07-08 20:41:04,635 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.235000
========= master_lag: 0.06 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 20:41:04.957635) =========

========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 20:41:04.957963) =========
script_repo_revision: b8c431a838bbea940f4ff410fa119d614fa9c222
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 20:41:04.958386) =========

========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 20:41:04.958714) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 20:41:04.973835) =========

========= Started 'c:/mozilla-build/python27/python -u ...' warnings (results: 1, elapsed: 6 mins, 59 secs) (at 2016-07-08 20:41:04.974215) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/firefox_media_tests_buildbot.py' '--cfg' 'mediatests/buildbot_windows_config.py' '--suite' 'media-youtube-tests' '--blob-upload-branch' 'oak' '--download-symbols' 'ondemand'
 in dir C:\slave\test\. (timeout 1800 secs) (maxTime 7200 secs)
 watching logfiles {}
 argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/firefox_media_tests_buildbot.py', '--cfg', 'mediatests/buildbot_windows_config.py', '--suite', 'media-youtube-tests', '--blob-upload-branch', 'oak', '--download-symbols', 'ondemand']
 environment:
  ALLUSERSPROFILE=C:\ProgramData
  APPDATA=C:\Users\cltbld\AppData\Roaming
  COMMONPROGRAMFILES=C:\Program Files\Common Files
  COMPUTERNAME=T-W732-IX-169
  COMSPEC=C:\windows\system32\cmd.exe
  DCLOCATION=SCL3
  DNSSUFFIX=wintest.releng.scl3.mozilla.com
  DevEnvDir=D:\msvs8\Common7\IDE
  FP_NO_HOST_CHECK=NO
  FrameworkDir=C:\WINDOWS\Microsoft.NET\Framework
  FrameworkSDKDir=D:\msvs8\SDK\v2.0
  FrameworkVersion=v2.0.50727
  HOMEDRIVE=C:
  HOMEPATH=\Users\cltbld
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LIBPATH=C:\WINDOWS\Microsoft.NET\Framework\v2.0.50727;D:\msvs8\VC\ATLMFC\LIB
  LOCALAPPDATA=C:\Users\cltbld\AppData\Local
  LOGONSERVER=\\T-W732-IX-169
  MONDIR=C:\Monitor_config\
  MOZBUILDDIR=C:\mozilla-build\
  MOZILLABUILD=D:\mozilla-build
  MOZILLABUILDDRIVE=C:
  MOZILLABUILDPATH=\mozilla-build\
  MOZ_AIRBAG=1
  MOZ_CRASHREPORTER_NO_REPORT=1
  MOZ_HIDE_RESULTS_TABLE=1
  MOZ_MSVCVERSION=8
  MOZ_NO_REMOTE=1
  MOZ_TOOLS=D:\mozilla-build\moztools
  MSVCDir=D:\msvs8\VC
  NO_EM_RESTART=1
  NO_FAIL_ON_TEST_ERRORS=1
  NUMBER_OF_PROCESSORS=8
  OS=Windows_NT
  OURDRIVE=C:
  PATH=C:\Program Files\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\;c:\Program Files\Microsoft Windows Performance Toolkit\;C:\mozilla-build\hg
  PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
  PROCESSOR_ARCHITECTURE=x86
  PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
  PROCESSOR_LEVEL=6
  PROCESSOR_REVISION=1e05
  PROGRAMDATA=C:\ProgramData
  PROGRAMFILES=C:\Program Files
  PROMPT=$P$G
  PROPERTIES_FILE=C:\slave\test/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\AppData\Local\Temp
  TEST1=testie
  TMP=C:\Users\cltbld\AppData\Local\Temp
  USERDOMAIN=T-W732-IX-169
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  VCINSTALLDIR=D:\msvs8\VC
  VCVARS=D:\msvs8\VC\bin\vcvars32.bat
  VSINSTALLDIR=D:\msvs8
  WINDIR=C:\windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
  XPCOM_DEBUG_BREAK=warn
 using PTY: False
20:41:05     INFO - MultiFileLogger online at 20160708 20:41:05 in C:\slave\test
20:41:05     INFO - Run as scripts/scripts/firefox_media_tests_buildbot.py --cfg mediatests/buildbot_windows_config.py --suite media-youtube-tests --blob-upload-branch oak --download-symbols ondemand
20:41:05     INFO - Dumping config to C:\slave\test\logs\localconfig.json.
20:41:05     INFO - {'append_to_log': False,
20:41:05     INFO -  'base_work_dir': 'C:\\slave\\test',
20:41:05     INFO -  'blob_upload_branch': 'oak',
20:41:05     INFO -  'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
20:41:05     INFO -  'buildbot_json_path': 'buildprops.json',
20:41:05     INFO -  'config_files': ('mediatests/buildbot_windows_config.py',),
20:41:05     INFO -  'default_actions': ('clobber',
20:41:05     INFO -                      'read-buildbot-config',
20:41:05     INFO -                      'download-and-extract',
20:41:05     INFO -                      'create-virtualenv',
20:41:05     INFO -                      'install',
20:41:05     INFO -                      'run-media-tests'),
20:41:05     INFO -  'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
20:41:05     INFO -  'download_minidump_stackwalk': True,
20:41:05     INFO -  'download_symbols': 'ondemand',
20:41:05     INFO -  'e10s': False,
20:41:05     INFO -  'exes': {'hg': 'c:/mozilla-build/hg/hg',
20:41:05     INFO -           'hgtool.py': ('c:\\mozilla-build\\python27\\python.exe',
20:41:05     INFO -                         'C:\\slave\\test\\scripts\\external_tools\\hgtool.py'),
20:41:05     INFO -           'mozinstall': ('C:\\slave\\test/build/venv/scripts/python',
20:41:05     INFO -                          'C:\\slave\\test/build/venv/scripts/mozinstall-script.py'),
20:41:05     INFO -           'python': 'c:/mozilla-build/python27/python',
20:41:05     INFO -           'tooltool.py': ('c:\\mozilla-build\\python27\\python.exe',
20:41:05     INFO -                           'C:/mozilla-build/tooltool.py'),
20:41:05     INFO -           'virtualenv': ('c:/mozilla-build/python27/python',
20:41:05     INFO -                          'c:/mozilla-build/buildbotve/virtualenv.py')},
20:41:05     INFO -  'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
20:41:05     INFO -                 'http://pypi.pub.build.mozilla.org/pub'),
20:41:05     INFO -  'in_tree_config': 'config/mozharness/marionette.py',
20:41:05     INFO -  'log_level': 'info',
20:41:05     INFO -  'log_to_console': True,
20:41:05     INFO -  'opt_config_files': (),
20:41:05     INFO -  'pip_index': False,
20:41:05     INFO -  'suite_definitions': {'media-tests': {'options': ()},
20:41:05     INFO -                        'media-youtube-tests': {'options': ('%(test_manifest)s',)}},
20:41:05     INFO -  'test_suite': 'media-youtube-tests',
20:41:05     INFO -  'test_timeout': 10000,
20:41:05     INFO -  'virtualenv_path': 'venv',
20:41:05     INFO -  'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
20:41:05     INFO -  'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
20:41:05     INFO -  'work_dir': 'build'}
20:41:05     INFO - #####
20:41:05     INFO - ##### Running clobber step.
20:41:05     INFO - #####
20:41:05     INFO - Running pre-action listener: _resource_record_pre_action
20:41:05     INFO - Running main action method: clobber
20:41:05     INFO - rmtree: C:\slave\test\build
20:41:05     INFO - Using _rmtree_windows ...
20:41:05     INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
20:42:33     INFO - Running post-action listener: _resource_record_post_action
20:42:33     INFO - #####
20:42:33     INFO - ##### Finished clobber step (success)
20:42:33     INFO - #####
20:42:33     INFO - #####
20:42:33     INFO - ##### Running read-buildbot-config step.
20:42:33     INFO - #####
20:42:33     INFO - Running pre-action listener: _resource_record_pre_action
20:42:33     INFO - Running main action method: read_buildbot_config
20:42:33     INFO - Using buildbot properties:
20:42:33     INFO - {
20:42:33     INFO -     "project": "", 
20:42:33     INFO -     "product": "firefox", 
20:42:33     INFO -     "script_repo_revision": "production", 
20:42:33     INFO -     "scheduler": "tests-oak-win7_ix-pgo-unittest", 
20:42:33     INFO -     "repository": "", 
20:42:33     INFO -     "buildername": "Windows 7 32-bit oak pgo test media-youtube-tests", 
20:42:33     INFO -     "buildid": "20160708183002", 
20:42:33     INFO -     "pgo_build": "True", 
20:42:33     INFO -     "basedir": "C:\\slave\\test", 
20:42:33     INFO -     "buildnumber": 0, 
20:42:33     INFO -     "slavename": "t-w732-ix-169", 
20:42:33     INFO -     "revision": "b8c431a838bbea940f4ff410fa119d614fa9c222", 
20:42:33     INFO -     "master": "http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/", 
20:42:33     INFO -     "platform": "win32", 
20:42:33     INFO -     "branch": "oak", 
20:42:33     INFO -     "repo_path": "projects/oak", 
20:42:33     INFO -     "moz_repo_path": "", 
20:42:33     INFO -     "stage_platform": "win32-pgo", 
20:42:33     INFO -     "builduid": "b3dde368fafd48a083e382e5fd0f73d9", 
20:42:33     INFO -     "slavebuilddir": "test"
20:42:33     INFO - }
20:42:33     INFO - Found a test packages url https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.test_packages.json.
20:42:33     INFO - Found installer url https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.zip.
20:42:33     INFO - Running post-action listener: _resource_record_post_action
20:42:33     INFO - #####
20:42:33     INFO - ##### Finished read-buildbot-config step (success)
20:42:33     INFO - #####
20:42:33     INFO - #####
20:42:33     INFO - ##### Running download-and-extract step.
20:42:33     INFO - #####
20:42:33     INFO - Running pre-action listener: _resource_record_pre_action
20:42:33     INFO - Running main action method: download_and_extract
20:42:33     INFO - mkdir: C:\slave\test\build\tests
20:42:33     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:42:33     INFO - https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.test_packages.json matches https://queue.taskcluster.net
20:42:33     INFO - trying https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.test_packages.json
20:42:33     INFO - Downloading https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-50.0a1.en-US.win32.test_packages.json
20:42:33     INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-50.0a1.en-US.win32.test_packages.json'}, attempt #1
20:42:34     INFO - Downloaded 1218 bytes.
20:42:34     INFO - Reading from file C:\slave\test\build\firefox-50.0a1.en-US.win32.test_packages.json
20:42:34     INFO - Using the following test package requirements:
20:42:34     INFO - {u'common': [u'firefox-50.0a1.en-US.win32.common.tests.zip'],
20:42:34     INFO -  u'cppunittest': [u'firefox-50.0a1.en-US.win32.common.tests.zip',
20:42:34     INFO -                   u'firefox-50.0a1.en-US.win32.cppunittest.tests.zip'],
20:42:34     INFO -  u'gtest': [u'firefox-50.0a1.en-US.win32.common.tests.zip'],
20:42:34     INFO -  u'jittest': [u'firefox-50.0a1.en-US.win32.common.tests.zip',
20:42:34     INFO -               u'jsshell-win32.zip'],
20:42:34     INFO -  u'mochitest': [u'firefox-50.0a1.en-US.win32.common.tests.zip',
20:42:34     INFO -                 u'firefox-50.0a1.en-US.win32.mochitest.tests.zip'],
20:42:34     INFO -  u'mozbase': [u'firefox-50.0a1.en-US.win32.common.tests.zip'],
20:42:34     INFO -  u'reftest': [u'firefox-50.0a1.en-US.win32.common.tests.zip',
20:42:34     INFO -               u'firefox-50.0a1.en-US.win32.reftest.tests.zip'],
20:42:34     INFO -  u'talos': [u'firefox-50.0a1.en-US.win32.common.tests.zip',
20:42:34     INFO -             u'firefox-50.0a1.en-US.win32.talos.tests.zip'],
20:42:34     INFO -  u'web-platform': [u'firefox-50.0a1.en-US.win32.common.tests.zip',
20:42:34     INFO -                    u'firefox-50.0a1.en-US.win32.web-platform.tests.zip'],
20:42:34     INFO -  u'xpcshell': [u'firefox-50.0a1.en-US.win32.common.tests.zip',
20:42:34     INFO -                u'firefox-50.0a1.en-US.win32.xpcshell.tests.zip']}
20:42:34     INFO - Downloading packages: [u'firefox-50.0a1.en-US.win32.common.tests.zip'] for test suite category: common
20:42:34     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:42:34     INFO - https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net
20:42:34     INFO - trying https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.common.tests.zip
20:42:34     INFO - Downloading https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.common.tests.zip to C:\slave\test\build\firefox-50.0a1.en-US.win32.common.tests.zip
20:42:34     INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-50.0a1.en-US.win32.common.tests.zip'}, attempt #1
20:42:36     INFO - Downloaded 19658554 bytes.
20:42:36     INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-50.0a1.en-US.win32.common.tests.zip', '-d', 'C:\\slave\\test\\build\\tests', 'config/*', 'external-media-tests/*', 'marionette/*', 'mozbase/*', 'puppeteer/*', 'tools/wptserve/*']
20:42:36     INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-50.0a1.en-US.win32.common.tests.zip -d C:\slave\test\build\tests config/* external-media-tests/* marionette/* mozbase/* puppeteer/* tools/wptserve/*
20:42:38     INFO - Return code: 0
20:42:38     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:42:38     INFO - https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.zip matches https://queue.taskcluster.net
20:42:38     INFO - trying https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.zip
20:42:38     INFO - Downloading https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.zip to C:\slave\test\build\firefox-50.0a1.en-US.win32.zip
20:42:38     INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-50.0a1.en-US.win32.zip'}, attempt #1
20:42:42     INFO - Downloaded 55320430 bytes.
20:42:42     INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.zip
20:42:42     INFO - mkdir: C:\slave\test\properties
20:42:42     INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
20:42:42     INFO - Writing to file C:\slave\test\properties\build_url
20:42:42     INFO - Contents:
20:42:42     INFO -  build_url:https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.zip
20:42:43     INFO - Running post-action listener: _resource_record_post_action
20:42:43     INFO - Running post-action listener: set_extra_try_arguments
20:42:43     INFO - #####
20:42:43     INFO - ##### Finished download-and-extract step (success)
20:42:43     INFO - #####
20:42:43     INFO - #####
20:42:43     INFO - ##### Running create-virtualenv step.
20:42:43     INFO - #####
20:42:43     INFO - Running pre-action listener: _pre_create_virtualenv
20:42:43     INFO - Running pre-action listener: _resource_record_pre_action
20:42:43     INFO - Running main action method: create_virtualenv
20:42:43     INFO - Creating virtualenv C:\slave\test\build\venv
20:42:43     INFO - mkdir: C:\slave\test\build\venv\Scripts
20:42:43     INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
20:42:43  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'!
20:42:43     INFO - Running command: ['c:/mozilla-build/python27/python', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build\\venv'] in C:\slave\test\build
20:42:43     INFO - Copy/paste: c:/mozilla-build/python27/python c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build\venv
20:42:44     INFO -  New python executable in C:\slave\test\build\venv\Scripts\python.exe
20:42:47     INFO -  Installing distribute......................................................................................................................................................................................done.
20:42:48     INFO - Return code: 0
20:42:48     INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build\venv
20:42: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:42:48     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:42: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:42:48     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:42:48     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 0x01912B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01C9E278>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01B71820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01865AA0>, '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 0x019372E0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01C9D0C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'MOZILLABUILD': 'D:\\mozilla-build', 'COMPUTERNAME': 'T-W732-IX-169', 'USERDOMAIN': 'T-W732-IX-169', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'LIBPATH': 'C:\\WINDOWS\\Microsoft.NET\\Framework\\v2.0.50727;D:\\msvs8\\VC\\ATLMFC\\LIB', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'MOZILLABUILDPATH': '\\mozilla-build\\', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'MOZ_HIDE_RESULTS_TABLE': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'VCVARS': 'D:\\msvs8\\VC\\bin\\vcvars32.bat', 'VSINSTALLDIR': 'D:\\msvs8', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'MOZILLABUILDDRIVE': 'C:', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'FRAMEWORKDIR': 'C:\\WINDOWS\\Microsoft.NET\\Framework', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'FRAMEWORKSDKDIR': 'D:\\msvs8\\SDK\\v2.0', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-W732-IX-169', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'MSVCDIR': 'D:\\msvs8\\VC', 'PROGRAMDATA': 'C:\\ProgramData', 'MOZ_TOOLS': 'D:\\mozilla-build\\moztools', 'PATH': 'C:\\Program Files\\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\\;c:\\Program Files\\Microsoft Windows Performance Toolkit\\;C:\\mozilla-build\\hg', 'MOZ_AIRBAG': '1', 'PROMPT': '$P$G', 'USERNAME': 'cltbld', 'NO_FAIL_ON_TEST_ERRORS': '1', 'VCINSTALLDIR': 'D:\\msvs8\\VC', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'WINDOWS_TRACING_FLAGS': '3', 'FRAMEWORKVERSION': 'v2.0.50727', 'SYSTEMDRIVE': 'C:', 'MOZ_MSVCVERSION': '8', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'DEVENVDIR': 'D:\\msvs8\\Common7\\IDE', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
20:42:48     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
20:42:48     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
20:42:48     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:42:48     INFO -  'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:42:48     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:42:48     INFO -  'COMPUTERNAME': 'T-W732-IX-169',
20:42:48     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:42:48     INFO -  'DCLOCATION': 'SCL3',
20:42:48     INFO -  'DEVENVDIR': 'D:\\msvs8\\Common7\\IDE',
20:42:48     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:42:48     INFO -  'FP_NO_HOST_CHECK': 'NO',
20:42:48     INFO -  'FRAMEWORKDIR': 'C:\\WINDOWS\\Microsoft.NET\\Framework',
20:42:48     INFO -  'FRAMEWORKSDKDIR': 'D:\\msvs8\\SDK\\v2.0',
20:42:48     INFO -  'FRAMEWORKVERSION': 'v2.0.50727',
20:42:48     INFO -  'HOMEDRIVE': 'C:',
20:42:48     INFO -  'HOMEPATH': '\\Users\\cltbld',
20:42:48     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
20:42:48     INFO -  'KTS_VERSION': '1.19c',
20:42:48     INFO -  'LIBPATH': 'C:\\WINDOWS\\Microsoft.NET\\Framework\\v2.0.50727;D:\\msvs8\\VC\\ATLMFC\\LIB',
20:42:48     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:42:48     INFO -  'LOGONSERVER': '\\\\T-W732-IX-169',
20:42:48     INFO -  'MONDIR': 'C:\\Monitor_config\\',
20:42:48     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:42:48     INFO -  'MOZILLABUILD': 'D:\\mozilla-build',
20:42:48     INFO -  'MOZILLABUILDDRIVE': 'C:',
20:42:48     INFO -  'MOZILLABUILDPATH': '\\mozilla-build\\',
20:42:48     INFO -  'MOZ_AIRBAG': '1',
20:42:48     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:42:48     INFO -  'MOZ_HIDE_RESULTS_TABLE': '1',
20:42:48     INFO -  'MOZ_MSVCVERSION': '8',
20:42:48     INFO -  'MOZ_NO_REMOTE': '1',
20:42:48     INFO -  'MOZ_TOOLS': 'D:\\mozilla-build\\moztools',
20:42:48     INFO -  'MSVCDIR': 'D:\\msvs8\\VC',
20:42:48     INFO -  'NO_EM_RESTART': '1',
20:42:48     INFO -  'NO_FAIL_ON_TEST_ERRORS': '1',
20:42:48     INFO -  'NUMBER_OF_PROCESSORS': '8',
20:42:48     INFO -  'OS': 'Windows_NT',
20:42:48     INFO -  'OURDRIVE': 'C:',
20:42:48     INFO -  'PATH': 'C:\\Program Files\\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\\;c:\\Program Files\\Microsoft Windows Performance Toolkit\\;C:\\mozilla-build\\hg',
20:42:48     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:42:48     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
20:42:48     INFO -  'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:42:48     INFO -  'PROCESSOR_LEVEL': '6',
20:42:48     INFO -  'PROCESSOR_REVISION': '1e05',
20:42:48     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
20:42:48     INFO -  'PROGRAMFILES': 'C:\\Program Files',
20:42:48     INFO -  'PROMPT': '$P$G',
20:42:48     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:42:48     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:42:48     INFO -  'PUBLIC': 'C:\\Users\\Public',
20:42:48     INFO -  'PWD': 'C:\\slave\\test',
20:42:48     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:42:48     INFO -  'SLAVEDIR': 'C:\\slave\\',
20:42:48     INFO -  'SYSTEMDRIVE': 'C:',
20:42:48     INFO -  'SYSTEMROOT': 'C:\\windows',
20:42:48     INFO -  'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:42:48     INFO -  'TEST1': 'testie',
20:42:48     INFO -  'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:42:48     INFO -  'USERDOMAIN': 'T-W732-IX-169',
20:42:48     INFO -  'USERNAME': 'cltbld',
20:42:48     INFO -  'USERPROFILE': 'C:\\Users\\cltbld',
20:42:48     INFO -  'VCINSTALLDIR': 'D:\\msvs8\\VC',
20:42:48     INFO -  'VCVARS': 'D:\\msvs8\\VC\\bin\\vcvars32.bat',
20:42:48     INFO -  'VSINSTALLDIR': 'D:\\msvs8',
20:42:48     INFO -  'WINDIR': 'C:\\windows',
20:42:48     INFO -  'WINDOWS_TRACING_FLAGS': '3',
20:42:48     INFO -  'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:42:48     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
20:42:50     INFO -  Ignoring indexes: http://pypi.python.org/simple/
20:42:50     INFO -  Downloading/unpacking psutil>=3.1.1
20:42:50     INFO -    Running setup.py egg_info for package psutil
20:42:50     INFO -      warning: no previously-included files matching '*' found under directory 'docs\_build'
20:42:50     INFO -      warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects <dir> <pattern1> <pattern2> ...
20:42:50     INFO -  Installing collected packages: psutil
20:42:50     INFO -    Running setup.py install for psutil
20:42:50     INFO -      building 'psutil._psutil_windows' extension
20:42:50     INFO -      error: Unable to find vcvarsall.bat
20:42:50     INFO -      Complete output from command C:\slave\test\build\venv\Scripts\python.exe -c "import setuptools;__file__='C:\\slave\\test\\build\\venv\\build\\psutil\\setup.py';execfile(__file__)" install --single-version-externally-managed --record c:\users\cltbld\appdata\local\temp\pip-vhk391-record\install-record.txt --install-headers C:\slave\test\build\venv\include\site\python2.7:
20:42:50     INFO -      running install
20:42:50     INFO -  running build
20:42:50     INFO -  running build_py
20:42:50     INFO -  creating build
20:42:50     INFO -  creating build\lib.win32-2.7
20:42:50     INFO -  creating build\lib.win32-2.7\psutil
20:42:50     INFO -  copying psutil\_common.py -> build\lib.win32-2.7\psutil
20:42:50     INFO -  copying psutil\_compat.py -> build\lib.win32-2.7\psutil
20:42:50     INFO -  copying psutil\_psbsd.py -> build\lib.win32-2.7\psutil
20:42:50     INFO -  copying psutil\_pslinux.py -> build\lib.win32-2.7\psutil
20:42:50     INFO -  copying psutil\_psosx.py -> build\lib.win32-2.7\psutil
20:42:50     INFO -  copying psutil\_psposix.py -> build\lib.win32-2.7\psutil
20:42:50     INFO -  copying psutil\_pssunos.py -> build\lib.win32-2.7\psutil
20:42:50     INFO -  copying psutil\_pswindows.py -> build\lib.win32-2.7\psutil
20:42:50     INFO -  copying psutil\__init__.py -> build\lib.win32-2.7\psutil
20:42:50     INFO -  running build_ext
20:42:50     INFO -  building 'psutil._psutil_windows' extension
20:42:50     INFO -  error: Unable to find vcvarsall.bat
20:42:50     INFO -  ----------------------------------------
20:42:50     INFO -  Command C:\slave\test\build\venv\Scripts\python.exe -c "import setuptools;__file__='C:\\slave\\test\\build\\venv\\build\\psutil\\setup.py';execfile(__file__)" install --single-version-externally-managed --record c:\users\cltbld\appdata\local\temp\pip-vhk391-record\install-record.txt --install-headers C:\slave\test\build\venv\include\site\python2.7 failed with error code 1
20:42:50     INFO -  Storing complete log in C:\Users\cltbld\AppData\Roaming\pip\pip.log
20:42:50  WARNING - Return code: 1
20:42:50  WARNING - Could not install python package: 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 failed after 1 tries!
20:42:50     INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build\venv
20:42:50     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:42:50     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:42:50     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:42:50     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:42:50     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 0x01912B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01C9E278>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01B71820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01865AA0>, '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 0x019372E0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01C9D0C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'MOZILLABUILD': 'D:\\mozilla-build', 'COMPUTERNAME': 'T-W732-IX-169', 'USERDOMAIN': 'T-W732-IX-169', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'LIBPATH': 'C:\\WINDOWS\\Microsoft.NET\\Framework\\v2.0.50727;D:\\msvs8\\VC\\ATLMFC\\LIB', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'MOZILLABUILDPATH': '\\mozilla-build\\', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'MOZ_HIDE_RESULTS_TABLE': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'VCVARS': 'D:\\msvs8\\VC\\bin\\vcvars32.bat', 'VSINSTALLDIR': 'D:\\msvs8', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'MOZILLABUILDDRIVE': 'C:', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'FRAMEWORKDIR': 'C:\\WINDOWS\\Microsoft.NET\\Framework', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'FRAMEWORKSDKDIR': 'D:\\msvs8\\SDK\\v2.0', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-W732-IX-169', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'MSVCDIR': 'D:\\msvs8\\VC', 'PROGRAMDATA': 'C:\\ProgramData', 'MOZ_TOOLS': 'D:\\mozilla-build\\moztools', 'PATH': 'C:\\Program Files\\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\\;c:\\Program Files\\Microsoft Windows Performance Toolkit\\;C:\\mozilla-build\\hg', 'MOZ_AIRBAG': '1', 'PROMPT': '$P$G', 'USERNAME': 'cltbld', 'NO_FAIL_ON_TEST_ERRORS': '1', 'VCINSTALLDIR': 'D:\\msvs8\\VC', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'WINDOWS_TRACING_FLAGS': '3', 'FRAMEWORKVERSION': 'v2.0.50727', 'SYSTEMDRIVE': 'C:', 'MOZ_MSVCVERSION': '8', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'DEVENVDIR': 'D:\\msvs8\\Common7\\IDE', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
20:42:50     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
20:42:50     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
20:42:50     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:42:50     INFO -  'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:42:50     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:42:50     INFO -  'COMPUTERNAME': 'T-W732-IX-169',
20:42:50     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:42:50     INFO -  'DCLOCATION': 'SCL3',
20:42:50     INFO -  'DEVENVDIR': 'D:\\msvs8\\Common7\\IDE',
20:42:50     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:42:50     INFO -  'FP_NO_HOST_CHECK': 'NO',
20:42:50     INFO -  'FRAMEWORKDIR': 'C:\\WINDOWS\\Microsoft.NET\\Framework',
20:42:50     INFO -  'FRAMEWORKSDKDIR': 'D:\\msvs8\\SDK\\v2.0',
20:42:50     INFO -  'FRAMEWORKVERSION': 'v2.0.50727',
20:42:50     INFO -  'HOMEDRIVE': 'C:',
20:42:50     INFO -  'HOMEPATH': '\\Users\\cltbld',
20:42:50     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
20:42:50     INFO -  'KTS_VERSION': '1.19c',
20:42:50     INFO -  'LIBPATH': 'C:\\WINDOWS\\Microsoft.NET\\Framework\\v2.0.50727;D:\\msvs8\\VC\\ATLMFC\\LIB',
20:42:50     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:42:50     INFO -  'LOGONSERVER': '\\\\T-W732-IX-169',
20:42:50     INFO -  'MONDIR': 'C:\\Monitor_config\\',
20:42:50     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:42:50     INFO -  'MOZILLABUILD': 'D:\\mozilla-build',
20:42:50     INFO -  'MOZILLABUILDDRIVE': 'C:',
20:42:50     INFO -  'MOZILLABUILDPATH': '\\mozilla-build\\',
20:42:50     INFO -  'MOZ_AIRBAG': '1',
20:42:50     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:42:50     INFO -  'MOZ_HIDE_RESULTS_TABLE': '1',
20:42:50     INFO -  'MOZ_MSVCVERSION': '8',
20:42:50     INFO -  'MOZ_NO_REMOTE': '1',
20:42:50     INFO -  'MOZ_TOOLS': 'D:\\mozilla-build\\moztools',
20:42:50     INFO -  'MSVCDIR': 'D:\\msvs8\\VC',
20:42:50     INFO -  'NO_EM_RESTART': '1',
20:42:50     INFO -  'NO_FAIL_ON_TEST_ERRORS': '1',
20:42:50     INFO -  'NUMBER_OF_PROCESSORS': '8',
20:42:50     INFO -  'OS': 'Windows_NT',
20:42:50     INFO -  'OURDRIVE': 'C:',
20:42:50     INFO -  'PATH': 'C:\\Program Files\\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\\;c:\\Program Files\\Microsoft Windows Performance Toolkit\\;C:\\mozilla-build\\hg',
20:42:50     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:42:50     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
20:42:50     INFO -  'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:42:50     INFO -  'PROCESSOR_LEVEL': '6',
20:42:50     INFO -  'PROCESSOR_REVISION': '1e05',
20:42:50     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
20:42:50     INFO -  'PROGRAMFILES': 'C:\\Program Files',
20:42:50     INFO -  'PROMPT': '$P$G',
20:42:50     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:42:50     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:42:50     INFO -  'PUBLIC': 'C:\\Users\\Public',
20:42:50     INFO -  'PWD': 'C:\\slave\\test',
20:42:50     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:42:50     INFO -  'SLAVEDIR': 'C:\\slave\\',
20:42:50     INFO -  'SYSTEMDRIVE': 'C:',
20:42:50     INFO -  'SYSTEMROOT': 'C:\\windows',
20:42:50     INFO -  'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:42:50     INFO -  'TEST1': 'testie',
20:42:50     INFO -  'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:42:50     INFO -  'USERDOMAIN': 'T-W732-IX-169',
20:42:50     INFO -  'USERNAME': 'cltbld',
20:42:50     INFO -  'USERPROFILE': 'C:\\Users\\cltbld',
20:42:50     INFO -  'VCINSTALLDIR': 'D:\\msvs8\\VC',
20:42:50     INFO -  'VCVARS': 'D:\\msvs8\\VC\\bin\\vcvars32.bat',
20:42:50     INFO -  'VSINSTALLDIR': 'D:\\msvs8',
20:42:50     INFO -  'WINDIR': 'C:\\windows',
20:42:50     INFO -  'WINDOWS_TRACING_FLAGS': '3',
20:42:50     INFO -  'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:42:50     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
20:42:51     INFO -  Ignoring indexes: http://pypi.python.org/simple/
20:42:51     INFO -  Downloading/unpacking mozsystemmonitor==0.3
20:42:51     INFO -    Downloading mozsystemmonitor-0.3.tar.gz
20:42:51     INFO -    Running setup.py egg_info for package mozsystemmonitor
20:42:51     INFO -  Downloading/unpacking psutil>=3.1.1 (from mozsystemmonitor==0.3)
20:42:51     INFO -    Running setup.py egg_info for package psutil
20:42:51     INFO -      warning: no previously-included files matching '*' found under directory 'docs\_build'
20:42:51     INFO -      warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects <dir> <pattern1> <pattern2> ...
20:42:51     INFO -  Installing collected packages: mozsystemmonitor, psutil
20:42:51     INFO -    Running setup.py install for mozsystemmonitor
20:42:51     INFO -    Running setup.py install for psutil
20:42:51     INFO -      building 'psutil._psutil_windows' extension
20:42:51     INFO -      error: Unable to find vcvarsall.bat
20:42:51     INFO -      Complete output from command C:\slave\test\build\venv\Scripts\python.exe -c "import setuptools;__file__='C:\\slave\\test\\build\\venv\\build\\psutil\\setup.py';execfile(__file__)" install --single-version-externally-managed --record c:\users\cltbld\appdata\local\temp\pip-3e73h3-record\install-record.txt --install-headers C:\slave\test\build\venv\include\site\python2.7:
20:42:51     INFO -      running install
20:42:51     INFO -  running build
20:42:51     INFO -  running build_py
20:42:51     INFO -  running build_ext
20:42:51     INFO -  building 'psutil._psutil_windows' extension
20:42:51     INFO -  error: Unable to find vcvarsall.bat
20:42:51     INFO -  ----------------------------------------
20:42:51     INFO -  Command C:\slave\test\build\venv\Scripts\python.exe -c "import setuptools;__file__='C:\\slave\\test\\build\\venv\\build\\psutil\\setup.py';execfile(__file__)" install --single-version-externally-managed --record c:\users\cltbld\appdata\local\temp\pip-3e73h3-record\install-record.txt --install-headers C:\slave\test\build\venv\include\site\python2.7 failed with error code 1
20:42:51     INFO -  Storing complete log in C:\Users\cltbld\AppData\Roaming\pip\pip.log
20:42:51  WARNING - Return code: 1
20:42:51  WARNING - Could not install python package: 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 failed after 1 tries!
20:42:51     INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build\venv
20:42:51     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:42:51     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:42:51     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:42:51     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:42:51     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 0x01912B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01C9E278>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01B71820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01865AA0>, '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 0x019372E0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01C9D0C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'MOZILLABUILD': 'D:\\mozilla-build', 'COMPUTERNAME': 'T-W732-IX-169', 'USERDOMAIN': 'T-W732-IX-169', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'LIBPATH': 'C:\\WINDOWS\\Microsoft.NET\\Framework\\v2.0.50727;D:\\msvs8\\VC\\ATLMFC\\LIB', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'MOZILLABUILDPATH': '\\mozilla-build\\', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'MOZ_HIDE_RESULTS_TABLE': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'VCVARS': 'D:\\msvs8\\VC\\bin\\vcvars32.bat', 'VSINSTALLDIR': 'D:\\msvs8', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'MOZILLABUILDDRIVE': 'C:', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'FRAMEWORKDIR': 'C:\\WINDOWS\\Microsoft.NET\\Framework', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'FRAMEWORKSDKDIR': 'D:\\msvs8\\SDK\\v2.0', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-W732-IX-169', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'MSVCDIR': 'D:\\msvs8\\VC', 'PROGRAMDATA': 'C:\\ProgramData', 'MOZ_TOOLS': 'D:\\mozilla-build\\moztools', 'PATH': 'C:\\Program Files\\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\\;c:\\Program Files\\Microsoft Windows Performance Toolkit\\;C:\\mozilla-build\\hg', 'MOZ_AIRBAG': '1', 'PROMPT': '$P$G', 'USERNAME': 'cltbld', 'NO_FAIL_ON_TEST_ERRORS': '1', 'VCINSTALLDIR': 'D:\\msvs8\\VC', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'WINDOWS_TRACING_FLAGS': '3', 'FRAMEWORKVERSION': 'v2.0.50727', 'SYSTEMDRIVE': 'C:', 'MOZ_MSVCVERSION': '8', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'DEVENVDIR': 'D:\\msvs8\\Common7\\IDE', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
20:42:51     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
20:42:51     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
20:42:51     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:42:51     INFO -  'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:42:51     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:42:51     INFO -  'COMPUTERNAME': 'T-W732-IX-169',
20:42:51     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:42:51     INFO -  'DCLOCATION': 'SCL3',
20:42:51     INFO -  'DEVENVDIR': 'D:\\msvs8\\Common7\\IDE',
20:42:51     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:42:51     INFO -  'FP_NO_HOST_CHECK': 'NO',
20:42:51     INFO -  'FRAMEWORKDIR': 'C:\\WINDOWS\\Microsoft.NET\\Framework',
20:42:51     INFO -  'FRAMEWORKSDKDIR': 'D:\\msvs8\\SDK\\v2.0',
20:42:51     INFO -  'FRAMEWORKVERSION': 'v2.0.50727',
20:42:51     INFO -  'HOMEDRIVE': 'C:',
20:42:51     INFO -  'HOMEPATH': '\\Users\\cltbld',
20:42:51     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
20:42:51     INFO -  'KTS_VERSION': '1.19c',
20:42:51     INFO -  'LIBPATH': 'C:\\WINDOWS\\Microsoft.NET\\Framework\\v2.0.50727;D:\\msvs8\\VC\\ATLMFC\\LIB',
20:42:51     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:42:51     INFO -  'LOGONSERVER': '\\\\T-W732-IX-169',
20:42:51     INFO -  'MONDIR': 'C:\\Monitor_config\\',
20:42:51     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:42:51     INFO -  'MOZILLABUILD': 'D:\\mozilla-build',
20:42:51     INFO -  'MOZILLABUILDDRIVE': 'C:',
20:42:51     INFO -  'MOZILLABUILDPATH': '\\mozilla-build\\',
20:42:51     INFO -  'MOZ_AIRBAG': '1',
20:42:51     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:42:51     INFO -  'MOZ_HIDE_RESULTS_TABLE': '1',
20:42:51     INFO -  'MOZ_MSVCVERSION': '8',
20:42:51     INFO -  'MOZ_NO_REMOTE': '1',
20:42:51     INFO -  'MOZ_TOOLS': 'D:\\mozilla-build\\moztools',
20:42:51     INFO -  'MSVCDIR': 'D:\\msvs8\\VC',
20:42:51     INFO -  'NO_EM_RESTART': '1',
20:42:51     INFO -  'NO_FAIL_ON_TEST_ERRORS': '1',
20:42:51     INFO -  'NUMBER_OF_PROCESSORS': '8',
20:42:51     INFO -  'OS': 'Windows_NT',
20:42:51     INFO -  'OURDRIVE': 'C:',
20:42:51     INFO -  'PATH': 'C:\\Program Files\\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\\;c:\\Program Files\\Microsoft Windows Performance Toolkit\\;C:\\mozilla-build\\hg',
20:42:51     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:42:51     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
20:42:51     INFO -  'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:42:51     INFO -  'PROCESSOR_LEVEL': '6',
20:42:51     INFO -  'PROCESSOR_REVISION': '1e05',
20:42:51     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
20:42:51     INFO -  'PROGRAMFILES': 'C:\\Program Files',
20:42:51     INFO -  'PROMPT': '$P$G',
20:42:51     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:42:51     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:42:51     INFO -  'PUBLIC': 'C:\\Users\\Public',
20:42:51     INFO -  'PWD': 'C:\\slave\\test',
20:42:51     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:42:51     INFO -  'SLAVEDIR': 'C:\\slave\\',
20:42:51     INFO -  'SYSTEMDRIVE': 'C:',
20:42:51     INFO -  'SYSTEMROOT': 'C:\\windows',
20:42:51     INFO -  'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:42:51     INFO -  'TEST1': 'testie',
20:42:51     INFO -  'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:42:51     INFO -  'USERDOMAIN': 'T-W732-IX-169',
20:42:51     INFO -  'USERNAME': 'cltbld',
20:42:51     INFO -  'USERPROFILE': 'C:\\Users\\cltbld',
20:42:51     INFO -  'VCINSTALLDIR': 'D:\\msvs8\\VC',
20:42:51     INFO -  'VCVARS': 'D:\\msvs8\\VC\\bin\\vcvars32.bat',
20:42:51     INFO -  'VSINSTALLDIR': 'D:\\msvs8',
20:42:51     INFO -  'WINDIR': 'C:\\windows',
20:42:51     INFO -  'WINDOWS_TRACING_FLAGS': '3',
20:42:51     INFO -  'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:42:51     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
20:42:51     INFO -  Ignoring indexes: http://pypi.python.org/simple/
20:42:51     INFO -  Downloading/unpacking jsonschema==2.5.1
20:42:54     INFO -    Running setup.py egg_info for package jsonschema
20:42:54     INFO -      zip_safe flag not set; analyzing archive contents...
20:42:54     INFO -      install_dir .
20:42:54     INFO -      Installed c:\slave\test\build\venv\build\jsonschema\vcversioner-2.16.0.0-py2.7.egg
20:42:54     INFO -  Installing collected packages: jsonschema
20:42:54     INFO -    Running setup.py install for jsonschema
20:42:54     INFO -      Installing jsonschema-script.py script to C:\slave\test\build\venv\Scripts
20:42:54     INFO -      Installing jsonschema.exe script to C:\slave\test\build\venv\Scripts
20:42:54     INFO -  Successfully installed jsonschema
20:42:54     INFO -  Cleaning up...
20:42:54     INFO - Return code: 0
20:42:54     INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build\venv
20:42:54     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:42:54     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:42:54     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:42:54     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:42:54     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 0x01912B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01C9E278>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01B71820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01865AA0>, '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 0x019372E0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01C9D0C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'MOZILLABUILD': 'D:\\mozilla-build', 'COMPUTERNAME': 'T-W732-IX-169', 'USERDOMAIN': 'T-W732-IX-169', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'LIBPATH': 'C:\\WINDOWS\\Microsoft.NET\\Framework\\v2.0.50727;D:\\msvs8\\VC\\ATLMFC\\LIB', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'MOZILLABUILDPATH': '\\mozilla-build\\', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'MOZ_HIDE_RESULTS_TABLE': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'VCVARS': 'D:\\msvs8\\VC\\bin\\vcvars32.bat', 'VSINSTALLDIR': 'D:\\msvs8', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'MOZILLABUILDDRIVE': 'C:', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'FRAMEWORKDIR': 'C:\\WINDOWS\\Microsoft.NET\\Framework', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'FRAMEWORKSDKDIR': 'D:\\msvs8\\SDK\\v2.0', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-W732-IX-169', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'MSVCDIR': 'D:\\msvs8\\VC', 'PROGRAMDATA': 'C:\\ProgramData', 'MOZ_TOOLS': 'D:\\mozilla-build\\moztools', 'PATH': 'C:\\Program Files\\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\\;c:\\Program Files\\Microsoft Windows Performance Toolkit\\;C:\\mozilla-build\\hg', 'MOZ_AIRBAG': '1', 'PROMPT': '$P$G', 'USERNAME': 'cltbld', 'NO_FAIL_ON_TEST_ERRORS': '1', 'VCINSTALLDIR': 'D:\\msvs8\\VC', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'WINDOWS_TRACING_FLAGS': '3', 'FRAMEWORKVERSION': 'v2.0.50727', 'SYSTEMDRIVE': 'C:', 'MOZ_MSVCVERSION': '8', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'DEVENVDIR': 'D:\\msvs8\\Common7\\IDE', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
20:42:54     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
20:42:54     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
20:42:54     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:42:54     INFO -  'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:42:54     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:42:54     INFO -  'COMPUTERNAME': 'T-W732-IX-169',
20:42:54     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:42:54     INFO -  'DCLOCATION': 'SCL3',
20:42:54     INFO -  'DEVENVDIR': 'D:\\msvs8\\Common7\\IDE',
20:42:54     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:42:54     INFO -  'FP_NO_HOST_CHECK': 'NO',
20:42:54     INFO -  'FRAMEWORKDIR': 'C:\\WINDOWS\\Microsoft.NET\\Framework',
20:42:54     INFO -  'FRAMEWORKSDKDIR': 'D:\\msvs8\\SDK\\v2.0',
20:42:54     INFO -  'FRAMEWORKVERSION': 'v2.0.50727',
20:42:54     INFO -  'HOMEDRIVE': 'C:',
20:42:54     INFO -  'HOMEPATH': '\\Users\\cltbld',
20:42:54     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
20:42:54     INFO -  'KTS_VERSION': '1.19c',
20:42:54     INFO -  'LIBPATH': 'C:\\WINDOWS\\Microsoft.NET\\Framework\\v2.0.50727;D:\\msvs8\\VC\\ATLMFC\\LIB',
20:42:54     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:42:54     INFO -  'LOGONSERVER': '\\\\T-W732-IX-169',
20:42:54     INFO -  'MONDIR': 'C:\\Monitor_config\\',
20:42:54     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:42:54     INFO -  'MOZILLABUILD': 'D:\\mozilla-build',
20:42:54     INFO -  'MOZILLABUILDDRIVE': 'C:',
20:42:54     INFO -  'MOZILLABUILDPATH': '\\mozilla-build\\',
20:42:54     INFO -  'MOZ_AIRBAG': '1',
20:42:54     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:42:54     INFO -  'MOZ_HIDE_RESULTS_TABLE': '1',
20:42:54     INFO -  'MOZ_MSVCVERSION': '8',
20:42:54     INFO -  'MOZ_NO_REMOTE': '1',
20:42:54     INFO -  'MOZ_TOOLS': 'D:\\mozilla-build\\moztools',
20:42:54     INFO -  'MSVCDIR': 'D:\\msvs8\\VC',
20:42:54     INFO -  'NO_EM_RESTART': '1',
20:42:54     INFO -  'NO_FAIL_ON_TEST_ERRORS': '1',
20:42:54     INFO -  'NUMBER_OF_PROCESSORS': '8',
20:42:54     INFO -  'OS': 'Windows_NT',
20:42:54     INFO -  'OURDRIVE': 'C:',
20:42:54     INFO -  'PATH': 'C:\\Program Files\\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\\;c:\\Program Files\\Microsoft Windows Performance Toolkit\\;C:\\mozilla-build\\hg',
20:42:54     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:42:54     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
20:42:54     INFO -  'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:42:54     INFO -  'PROCESSOR_LEVEL': '6',
20:42:54     INFO -  'PROCESSOR_REVISION': '1e05',
20:42:54     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
20:42:54     INFO -  'PROGRAMFILES': 'C:\\Program Files',
20:42:54     INFO -  'PROMPT': '$P$G',
20:42:54     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:42:54     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:42:54     INFO -  'PUBLIC': 'C:\\Users\\Public',
20:42:54     INFO -  'PWD': 'C:\\slave\\test',
20:42:54     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:42:54     INFO -  'SLAVEDIR': 'C:\\slave\\',
20:42:54     INFO -  'SYSTEMDRIVE': 'C:',
20:42:54     INFO -  'SYSTEMROOT': 'C:\\windows',
20:42:54     INFO -  'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:42:54     INFO -  'TEST1': 'testie',
20:42:54     INFO -  'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:42:54     INFO -  'USERDOMAIN': 'T-W732-IX-169',
20:42:54     INFO -  'USERNAME': 'cltbld',
20:42:54     INFO -  'USERPROFILE': 'C:\\Users\\cltbld',
20:42:54     INFO -  'VCINSTALLDIR': 'D:\\msvs8\\VC',
20:42:54     INFO -  'VCVARS': 'D:\\msvs8\\VC\\bin\\vcvars32.bat',
20:42:54     INFO -  'VSINSTALLDIR': 'D:\\msvs8',
20:42:54     INFO -  'WINDIR': 'C:\\windows',
20:42:54     INFO -  'WINDOWS_TRACING_FLAGS': '3',
20:42:54     INFO -  'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:42:54     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
20:42:54     INFO -  Ignoring indexes: http://pypi.python.org/simple/
20:42:54     INFO -  Downloading/unpacking blobuploader==1.2.4
20:42:54     INFO -    Downloading blobuploader-1.2.4.tar.gz
20:42:54     INFO -    Running setup.py egg_info for package blobuploader
20:42:54     INFO -  Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
20:42:57     INFO -    Running setup.py egg_info for package requests
20:42:57     INFO -  Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
20:42:57     INFO -    Downloading docopt-0.6.1.tar.gz
20:42:57     INFO -    Running setup.py egg_info for package docopt
20:42:57     INFO -  Installing collected packages: blobuploader, docopt, requests
20:42:57     INFO -    Running setup.py install for blobuploader
20:42:57     INFO -    Running setup.py install for docopt
20:42:57     INFO -    Running setup.py install for requests
20:42:57     INFO -  Successfully installed blobuploader docopt requests
20:42:57     INFO -  Cleaning up...
20:42:57     INFO - Return code: 0
20:42:57     INFO - Installing None into virtualenv C:\slave\test\build\venv
20:42:57     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:42:57     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:42:57     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:42:57     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:42:57     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\\external-media-tests-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 0x01912B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01C9E278>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01B71820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01865AA0>, '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 0x019372E0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01C9D0C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'MOZILLABUILD': 'D:\\mozilla-build', 'COMPUTERNAME': 'T-W732-IX-169', 'USERDOMAIN': 'T-W732-IX-169', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'LIBPATH': 'C:\\WINDOWS\\Microsoft.NET\\Framework\\v2.0.50727;D:\\msvs8\\VC\\ATLMFC\\LIB', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'MOZILLABUILDPATH': '\\mozilla-build\\', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'MOZ_HIDE_RESULTS_TABLE': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'VCVARS': 'D:\\msvs8\\VC\\bin\\vcvars32.bat', 'VSINSTALLDIR': 'D:\\msvs8', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'MOZILLABUILDDRIVE': 'C:', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'FRAMEWORKDIR': 'C:\\WINDOWS\\Microsoft.NET\\Framework', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'FRAMEWORKSDKDIR': 'D:\\msvs8\\SDK\\v2.0', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-W732-IX-169', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'MSVCDIR': 'D:\\msvs8\\VC', 'PROGRAMDATA': 'C:\\ProgramData', 'MOZ_TOOLS': 'D:\\mozilla-build\\moztools', 'PATH': 'C:\\Program Files\\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\\;c:\\Program Files\\Microsoft Windows Performance Toolkit\\;C:\\mozilla-build\\hg', 'MOZ_AIRBAG': '1', 'PROMPT': '$P$G', 'USERNAME': 'cltbld', 'NO_FAIL_ON_TEST_ERRORS': '1', 'VCINSTALLDIR': 'D:\\msvs8\\VC', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'WINDOWS_TRACING_FLAGS': '3', 'FRAMEWORKVERSION': 'v2.0.50727', 'SYSTEMDRIVE': 'C:', 'MOZ_MSVCVERSION': '8', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'DEVENVDIR': 'D:\\msvs8\\Common7\\IDE', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
20:42:57     INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\external-media-tests-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
20:42:57     INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\build\tests\config\external-media-tests-requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
20:42:57     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:42:57     INFO -  'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:42:57     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:42:57     INFO -  'COMPUTERNAME': 'T-W732-IX-169',
20:42:57     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:42:57     INFO -  'DCLOCATION': 'SCL3',
20:42:57     INFO -  'DEVENVDIR': 'D:\\msvs8\\Common7\\IDE',
20:42:57     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:42:57     INFO -  'FP_NO_HOST_CHECK': 'NO',
20:42:57     INFO -  'FRAMEWORKDIR': 'C:\\WINDOWS\\Microsoft.NET\\Framework',
20:42:57     INFO -  'FRAMEWORKSDKDIR': 'D:\\msvs8\\SDK\\v2.0',
20:42:57     INFO -  'FRAMEWORKVERSION': 'v2.0.50727',
20:42:57     INFO -  'HOMEDRIVE': 'C:',
20:42:57     INFO -  'HOMEPATH': '\\Users\\cltbld',
20:42:57     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
20:42:57     INFO -  'KTS_VERSION': '1.19c',
20:42:57     INFO -  'LIBPATH': 'C:\\WINDOWS\\Microsoft.NET\\Framework\\v2.0.50727;D:\\msvs8\\VC\\ATLMFC\\LIB',
20:42:57     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:42:57     INFO -  'LOGONSERVER': '\\\\T-W732-IX-169',
20:42:57     INFO -  'MONDIR': 'C:\\Monitor_config\\',
20:42:57     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:42:57     INFO -  'MOZILLABUILD': 'D:\\mozilla-build',
20:42:57     INFO -  'MOZILLABUILDDRIVE': 'C:',
20:42:57     INFO -  'MOZILLABUILDPATH': '\\mozilla-build\\',
20:42:57     INFO -  'MOZ_AIRBAG': '1',
20:42:57     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:42:57     INFO -  'MOZ_HIDE_RESULTS_TABLE': '1',
20:42:57     INFO -  'MOZ_MSVCVERSION': '8',
20:42:57     INFO -  'MOZ_NO_REMOTE': '1',
20:42:57     INFO -  'MOZ_TOOLS': 'D:\\mozilla-build\\moztools',
20:42:57     INFO -  'MSVCDIR': 'D:\\msvs8\\VC',
20:42:57     INFO -  'NO_EM_RESTART': '1',
20:42:57     INFO -  'NO_FAIL_ON_TEST_ERRORS': '1',
20:42:57     INFO -  'NUMBER_OF_PROCESSORS': '8',
20:42:57     INFO -  'OS': 'Windows_NT',
20:42:57     INFO -  'OURDRIVE': 'C:',
20:42:57     INFO -  'PATH': 'C:\\Program Files\\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\\;c:\\Program Files\\Microsoft Windows Performance Toolkit\\;C:\\mozilla-build\\hg',
20:42:57     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:42:57     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
20:42:57     INFO -  'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:42:57     INFO -  'PROCESSOR_LEVEL': '6',
20:42:57     INFO -  'PROCESSOR_REVISION': '1e05',
20:42:57     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
20:42:57     INFO -  'PROGRAMFILES': 'C:\\Program Files',
20:42:57     INFO -  'PROMPT': '$P$G',
20:42:57     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:42:57     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:42:57     INFO -  'PUBLIC': 'C:\\Users\\Public',
20:42:57     INFO -  'PWD': 'C:\\slave\\test',
20:42:57     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:42:57     INFO -  'SLAVEDIR': 'C:\\slave\\',
20:42:57     INFO -  'SYSTEMDRIVE': 'C:',
20:42:57     INFO -  'SYSTEMROOT': 'C:\\windows',
20:42:57     INFO -  'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:42:57     INFO -  'TEST1': 'testie',
20:42:57     INFO -  'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:42:57     INFO -  'USERDOMAIN': 'T-W732-IX-169',
20:42:57     INFO -  'USERNAME': 'cltbld',
20:42:57     INFO -  'USERPROFILE': 'C:\\Users\\cltbld',
20:42:57     INFO -  'VCINSTALLDIR': 'D:\\msvs8\\VC',
20:42:57     INFO -  'VCVARS': 'D:\\msvs8\\VC\\bin\\vcvars32.bat',
20:42:57     INFO -  'VSINSTALLDIR': 'D:\\msvs8',
20:42:57     INFO -  'WINDIR': 'C:\\windows',
20:42:57     INFO -  'WINDOWS_TRACING_FLAGS': '3',
20:42:57     INFO -  'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:42:57     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
20:43:02     INFO -  Ignoring indexes: http://pypi.python.org/simple/
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\manifestparser
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmanifestparser
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozcrash
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozcrash
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozdebug
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozdebug
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozdevice
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozdevice
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozfile
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozfile
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozhttpd
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozinfo
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozinfo
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozinstall
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozinstall
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozleak
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozleak
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozlog
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozlog
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\moznetwork
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmoznetwork
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozprocess
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozprocess
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozprofile
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozprofile
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozrunner
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozrunner
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozscreenshot
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\moztest
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmoztest
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozversion
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozversion
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\tools\wptserve
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Ctools%5Cwptserve
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\marionette\client
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmarionette%5Cclient
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\marionette\marionette\runner\mixins\browsermob-proxy-py
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmarionette%5Cmarionette%5Crunner%5Cmixins%5Cbrowsermob-proxy-py
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\marionette
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmarionette
20:43:02     INFO -      warning: no files found matching '*.js' under directory 'marionette\touch'
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\puppeteer\firefox
20:43:02     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cpuppeteer%5Cfirefox
20:43:02     INFO -  Unpacking c:\slave\test\build\tests\external-media-tests
20:43:19     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cexternal-media-tests
20:43:19     INFO -  Installing collected packages: browsermob-proxy, external-media-tests, firefox-puppeteer, manifestparser, marionette-client, marionette-driver, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion, wptserve
20:43:19     INFO -    Running setup.py install for browsermob-proxy
20:43:19     INFO -    Running setup.py install for external-media-tests
20:43:19     INFO -      Installing external-media-tests-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing external-media-tests.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -    Running setup.py install for firefox-puppeteer
20:43:19     INFO -    Running setup.py install for manifestparser
20:43:19     INFO -      Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -    Running setup.py install for marionette-client
20:43:19     INFO -      warning: no files found matching '*.js' under directory 'marionette\touch'
20:43:19     INFO -      Installing marionette-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing marionette.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -    Running setup.py install for marionette-driver
20:43:19     INFO -    Running setup.py install for mozcrash
20:43:19     INFO -    Running setup.py install for mozdebug
20:43:19     INFO -    Running setup.py install for mozdevice
20:43:19     INFO -      Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing sutini.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing dm-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing dm.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -    Running setup.py install for mozfile
20:43:19     INFO -    Running setup.py install for mozhttpd
20:43:19     INFO -      Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -    Running setup.py install for mozinfo
20:43:19     INFO -      Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -    Running setup.py install for mozInstall
20:43:19     INFO -      Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -    Running setup.py install for mozleak
20:43:19     INFO -    Running setup.py install for mozlog
20:43:19     INFO -      Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing structlog.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -    Running setup.py install for moznetwork
20:43:19     INFO -      Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -    Running setup.py install for mozprocess
20:43:19     INFO -    Running setup.py install for mozprofile
20:43:19     INFO -      Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -    Running setup.py install for mozrunner
20:43:19     INFO -      Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -    Running setup.py install for mozscreenshot
20:43:19     INFO -    Running setup.py install for moztest
20:43:19     INFO -    Running setup.py install for mozversion
20:43:19     INFO -      Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -      Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
20:43:19     INFO -    Running setup.py install for wptserve
20:43:19     INFO -  Successfully installed browsermob-proxy external-media-tests firefox-puppeteer manifestparser marionette-client marionette-driver mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion wptserve
20:43:19     INFO -  Cleaning up...
20:43:19     INFO - Return code: 0
20:43:19     INFO - Installing None into virtualenv C:\slave\test\build\venv
20:43:19     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:43:19     INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:43:19     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:43:19     INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:43:19     INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\external-media-tests-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 0x01912B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01C9E278>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01B71820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01865AA0>, '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 0x019372E0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01C9D0C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'MOZILLABUILD': 'D:\\mozilla-build', 'COMPUTERNAME': 'T-W732-IX-169', 'USERDOMAIN': 'T-W732-IX-169', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'LIBPATH': 'C:\\WINDOWS\\Microsoft.NET\\Framework\\v2.0.50727;D:\\msvs8\\VC\\ATLMFC\\LIB', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'MOZILLABUILDPATH': '\\mozilla-build\\', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'MOZ_HIDE_RESULTS_TABLE': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'VCVARS': 'D:\\msvs8\\VC\\bin\\vcvars32.bat', 'VSINSTALLDIR': 'D:\\msvs8', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'MOZILLABUILDDRIVE': 'C:', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'FRAMEWORKDIR': 'C:\\WINDOWS\\Microsoft.NET\\Framework', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'FRAMEWORKSDKDIR': 'D:\\msvs8\\SDK\\v2.0', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-W732-IX-169', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'MSVCDIR': 'D:\\msvs8\\VC', 'PROGRAMDATA': 'C:\\ProgramData', 'MOZ_TOOLS': 'D:\\mozilla-build\\moztools', 'PATH': 'C:\\Program Files\\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\\;c:\\Program Files\\Microsoft Windows Performance Toolkit\\;C:\\mozilla-build\\hg', 'MOZ_AIRBAG': '1', 'PROMPT': '$P$G', 'USERNAME': 'cltbld', 'NO_FAIL_ON_TEST_ERRORS': '1', 'VCINSTALLDIR': 'D:\\msvs8\\VC', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'WINDOWS_TRACING_FLAGS': '3', 'FRAMEWORKVERSION': 'v2.0.50727', 'SYSTEMDRIVE': 'C:', 'MOZ_MSVCVERSION': '8', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'DEVENVDIR': 'D:\\msvs8\\Common7\\IDE', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1
20:43:20     INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\external-media-tests-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
20:43:20     INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\config\external-media-tests-requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
20:43:20     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:43:20     INFO -  'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:43:20     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:43:20     INFO -  'COMPUTERNAME': 'T-W732-IX-169',
20:43:20     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:43:20     INFO -  'DCLOCATION': 'SCL3',
20:43:20     INFO -  'DEVENVDIR': 'D:\\msvs8\\Common7\\IDE',
20:43:20     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:43:20     INFO -  'FP_NO_HOST_CHECK': 'NO',
20:43:20     INFO -  'FRAMEWORKDIR': 'C:\\WINDOWS\\Microsoft.NET\\Framework',
20:43:20     INFO -  'FRAMEWORKSDKDIR': 'D:\\msvs8\\SDK\\v2.0',
20:43:20     INFO -  'FRAMEWORKVERSION': 'v2.0.50727',
20:43:20     INFO -  'HOMEDRIVE': 'C:',
20:43:20     INFO -  'HOMEPATH': '\\Users\\cltbld',
20:43:20     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
20:43:20     INFO -  'KTS_VERSION': '1.19c',
20:43:20     INFO -  'LIBPATH': 'C:\\WINDOWS\\Microsoft.NET\\Framework\\v2.0.50727;D:\\msvs8\\VC\\ATLMFC\\LIB',
20:43:20     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:43:20     INFO -  'LOGONSERVER': '\\\\T-W732-IX-169',
20:43:20     INFO -  'MONDIR': 'C:\\Monitor_config\\',
20:43:20     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:43:20     INFO -  'MOZILLABUILD': 'D:\\mozilla-build',
20:43:20     INFO -  'MOZILLABUILDDRIVE': 'C:',
20:43:20     INFO -  'MOZILLABUILDPATH': '\\mozilla-build\\',
20:43:20     INFO -  'MOZ_AIRBAG': '1',
20:43:20     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:43:20     INFO -  'MOZ_HIDE_RESULTS_TABLE': '1',
20:43:20     INFO -  'MOZ_MSVCVERSION': '8',
20:43:20     INFO -  'MOZ_NO_REMOTE': '1',
20:43:20     INFO -  'MOZ_TOOLS': 'D:\\mozilla-build\\moztools',
20:43:20     INFO -  'MSVCDIR': 'D:\\msvs8\\VC',
20:43:20     INFO -  'NO_EM_RESTART': '1',
20:43:20     INFO -  'NO_FAIL_ON_TEST_ERRORS': '1',
20:43:20     INFO -  'NUMBER_OF_PROCESSORS': '8',
20:43:20     INFO -  'OS': 'Windows_NT',
20:43:20     INFO -  'OURDRIVE': 'C:',
20:43:20     INFO -  'PATH': 'C:\\Program Files\\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\\;c:\\Program Files\\Microsoft Windows Performance Toolkit\\;C:\\mozilla-build\\hg',
20:43:20     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:43:20     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
20:43:20     INFO -  'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:43:20     INFO -  'PROCESSOR_LEVEL': '6',
20:43:20     INFO -  'PROCESSOR_REVISION': '1e05',
20:43:20     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
20:43:20     INFO -  'PROGRAMFILES': 'C:\\Program Files',
20:43:20     INFO -  'PROMPT': '$P$G',
20:43:20     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:43:20     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:43:20     INFO -  'PUBLIC': 'C:\\Users\\Public',
20:43:20     INFO -  'PWD': 'C:\\slave\\test',
20:43:20     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:43:20     INFO -  'SLAVEDIR': 'C:\\slave\\',
20:43:20     INFO -  'SYSTEMDRIVE': 'C:',
20:43:20     INFO -  'SYSTEMROOT': 'C:\\windows',
20:43:20     INFO -  'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:43:20     INFO -  'TEST1': 'testie',
20:43:20     INFO -  'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:43:20     INFO -  'USERDOMAIN': 'T-W732-IX-169',
20:43:20     INFO -  'USERNAME': 'cltbld',
20:43:20     INFO -  'USERPROFILE': 'C:\\Users\\cltbld',
20:43:20     INFO -  'VCINSTALLDIR': 'D:\\msvs8\\VC',
20:43:20     INFO -  'VCVARS': 'D:\\msvs8\\VC\\bin\\vcvars32.bat',
20:43:20     INFO -  'VSINSTALLDIR': 'D:\\msvs8',
20:43:20     INFO -  'WINDIR': 'C:\\windows',
20:43:20     INFO -  'WINDOWS_TRACING_FLAGS': '3',
20:43:20     INFO -  'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:43:20     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
20:43:24     INFO -  Ignoring indexes: http://pypi.python.org/simple/
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\manifestparser
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmanifestparser
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozcrash
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozcrash
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozdebug
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozdebug
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozdevice
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozdevice
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozfile
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozfile
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozhttpd
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozinfo
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozinfo
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozinstall
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozinstall
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozleak
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozleak
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozlog
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozlog
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\moznetwork
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmoznetwork
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozprocess
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozprocess
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozprofile
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozprofile
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozrunner
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozrunner
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozscreenshot
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\moztest
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmoztest
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\mozbase\mozversion
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmozbase%5Cmozversion
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\tools\wptserve
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Ctools%5Cwptserve
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\marionette\client
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmarionette%5Cclient
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\marionette\marionette\runner\mixins\browsermob-proxy-py
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmarionette%5Cmarionette%5Crunner%5Cmixins%5Cbrowsermob-proxy-py
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\marionette
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cmarionette
20:43:24     INFO -      warning: no files found matching '*.js' under directory 'marionette\touch'
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\puppeteer\firefox
20:43:24     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cpuppeteer%5Cfirefox
20:43:24     INFO -  Unpacking c:\slave\test\build\tests\external-media-tests
20:43:37     INFO -    Running setup.py egg_info for package from file:///c%7C%5Cslave%5Ctest%5Cbuild%5Ctests%5Cexternal-media-tests
20:43:37     INFO -  Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
20:43:37     INFO -  Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
20:43:37     INFO -  Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
20:43:37     INFO -  Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
20:43:37     INFO -  Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
20:43:37     INFO -  Downloading/unpacking blessings>=1.3 (from mozlog->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
20:43:37     INFO -    Downloading blessings-1.6.tar.gz
20:43:37     INFO -    Running setup.py egg_info for package blessings
20:43:37     INFO -  Requirement already satisfied (use --upgrade to upgrade): requests>=1.1.0 in c:\slave\test\build\venv\lib\site-packages (from browsermob-proxy->-r C:\slave\test\build\tests\config\marionette_requirements.txt (line 4))
20:43:37     INFO -  Installing collected packages: blessings, browsermob-proxy, external-media-tests, firefox-puppeteer, manifestparser, marionette-client, marionette-driver, mozcrash, mozdebug, mozdevice, mozhttpd, mozInstall, mozleak, mozprofile, mozrunner, mozscreenshot, moztest, mozversion, wptserve
20:43:37     INFO -    Running setup.py install for blessings
20:43:37     INFO -    Running setup.py install for browsermob-proxy
20:43:37     INFO -    Running setup.py install for external-media-tests
20:43:37     INFO -      Installing external-media-tests-script.py script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing external-media-tests.exe script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -    Running setup.py install for firefox-puppeteer
20:43:37     INFO -    Running setup.py install for manifestparser
20:43:37     INFO -      Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -    Running setup.py install for marionette-client
20:43:37     INFO -      warning: no files found matching '*.js' under directory 'marionette\touch'
20:43:37     INFO -      Installing marionette-script.py script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing marionette.exe script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -    Running setup.py install for marionette-driver
20:43:37     INFO -    Running setup.py install for mozcrash
20:43:37     INFO -    Running setup.py install for mozdebug
20:43:37     INFO -    Running setup.py install for mozdevice
20:43:37     INFO -      Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing sutini.exe script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing dm-script.py script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing dm.exe script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -    Running setup.py install for mozhttpd
20:43:37     INFO -      Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -    Running setup.py install for mozInstall
20:43:37     INFO -      Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -      Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
20:43:37     INFO -    Running setup.py install for mozleak
20:43:37     INFO -    Running setup.py install for mozprofile
20:43:40     INFO -      Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
20:43:40     INFO -      Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
20:43:40     INFO -      Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
20:43:40     INFO -      Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
20:43:40     INFO -      Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
20:43:40     INFO -      Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
20:43:40     INFO -    Running setup.py install for mozrunner
20:43:40     INFO -      Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
20:43:40     INFO -      Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
20:43:40     INFO -    Running setup.py install for mozscreenshot
20:43:40     INFO -    Running setup.py install for moztest
20:43:40     INFO -    Running setup.py install for mozversion
20:43:40     INFO -      Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
20:43:40     INFO -      Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
20:43:40     INFO -    Running setup.py install for wptserve
20:43:40     INFO -  Successfully installed blessings browsermob-proxy external-media-tests firefox-puppeteer manifestparser marionette-client marionette-driver mozcrash mozdebug mozdevice mozhttpd mozInstall mozleak mozprofile mozrunner mozscreenshot moztest mozversion wptserve
20:43:40     INFO -  Cleaning up...
20:43:40     INFO - Return code: 0
20:43:40     INFO - Done creating virtualenv C:\slave\test\build\venv.
20:43:40     INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
20:43:40     INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
20:43:40     INFO - Reading from file tmpfile_stdout
20:43:40     INFO - Using _rmtree_windows ...
20:43:40     INFO - Using _rmtree_windows ...
20:43:40     INFO - Current package versions:
20:43:40     INFO -   blessings == 1.6
20:43:40     INFO -   blobuploader == 1.2.4
20:43:40     INFO -   browsermob-proxy == 0.6.0
20:43:40     INFO -   distribute == 0.6.14
20:43:40     INFO -   docopt == 0.6.1
20:43:40     INFO -   external-media-tests == 2.0
20:43:40     INFO -   firefox-puppeteer == 50.0.0
20:43:40     INFO -   jsonschema == 2.5.1
20:43:40     INFO -   manifestparser == 1.1
20:43:40     INFO -   marionette-client == 3.1.0
20:43:40     INFO -   marionette-driver == 2.0.0
20:43:40     INFO -   mozInstall == 1.12
20:43:40     INFO -   mozcrash == 0.17
20:43:40     INFO -   mozdebug == 0.1
20:43:40     INFO -   mozdevice == 0.48
20:43:40     INFO -   mozfile == 1.2
20:43:40     INFO -   mozhttpd == 0.7
20:43:40     INFO -   mozinfo == 0.9
20:43:40     INFO -   mozleak == 0.1
20:43:40     INFO -   mozlog == 3.2
20:43:40     INFO -   moznetwork == 0.27
20:43:40     INFO -   mozprocess == 0.23
20:43:40     INFO -   mozprofile == 0.28
20:43:40     INFO -   mozrunner == 6.12
20:43:40     INFO -   mozscreenshot == 0.1
20:43:40     INFO -   mozsystemmonitor == 0.3
20:43:40     INFO -   moztest == 0.7
20:43:40     INFO -   mozversion == 1.4
20:43:40     INFO -   requests == 1.2.3
20:43:40     INFO -   wptserve == 1.4.0
20:43:40     INFO - Running post-action listener: _resource_record_post_action
20:43:40     INFO - Running post-action listener: _start_resource_monitoring
20:43:40     INFO - Starting resource monitoring.
20:43:40     INFO - #####
20:43:40     INFO - ##### Finished create-virtualenv step (success)
20:43:40     INFO - #####
20:43:40     INFO - #####
20:43:40     INFO - ##### Running install step.
20:43:40     INFO - #####
20:43:40     INFO - Running pre-action listener: _resource_record_pre_action
20:43:40     INFO - Running main action method: install
20:43:40     INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
20:43:40     INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
20:43:41     INFO - Reading from file tmpfile_stdout
20:43:41     INFO - Using _rmtree_windows ...
20:43:41     INFO - Using _rmtree_windows ...
20:43:41     INFO - Detecting whether we're running mozinstall >=1.0...
20:43:41     INFO - Getting output from command: ['C:\\slave\\test/build/venv/scripts/python', 'C:\\slave\\test/build/venv/scripts/mozinstall-script.py', '-h']
20:43:41     INFO - Copy/paste: C:\slave\test/build/venv/scripts/python C:\slave\test/build/venv/scripts/mozinstall-script.py -h
20:43:41     INFO - Reading from file tmpfile_stdout
20:43:41     INFO - Output received:
20:43:41     INFO -  Usage: mozinstall-script.py [options] installer
20:43:41     INFO -  Options:
20:43:41     INFO -    -h, --help            show this help message and exit
20:43:41     INFO -    -d DEST, --destination=DEST
20:43:41     INFO -                          Directory to install application into. [default:
20:43:41     INFO -                          "C:\slave\test"]
20:43:41     INFO -    --app=APP             Application being installed. [default: firefox]
20:43:41     INFO - Using _rmtree_windows ...
20:43:41     INFO - Using _rmtree_windows ...
20:43:41     INFO - mkdir: C:\slave\test\build\application
20:43:41     INFO - Getting output from command: ['C:\\slave\\test/build/venv/scripts/python', 'C:\\slave\\test/build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-50.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
20:43:41     INFO - Copy/paste: C:\slave\test/build/venv/scripts/python C:\slave\test/build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-50.0a1.en-US.win32.zip --destination C:\slave\test\build\application
20:43:43     INFO - Reading from file tmpfile_stdout
20:43:43     INFO - Output received:
20:43:43     INFO -  C:\slave\test\build\application\firefox\firefox.exe
20:43:43     INFO - Using _rmtree_windows ...
20:43:43     INFO - Using _rmtree_windows ...
20:43:43     INFO - Running post-action listener: _resource_record_post_action
20:43:43     INFO - #####
20:43:43     INFO - ##### Finished install step (success)
20:43:43     INFO - #####
20:43:43     INFO - #####
20:43:43     INFO - ##### Running run-media-tests step.
20:43:43     INFO - #####
20:43:43     INFO - Running pre-action listener: _resource_record_pre_action
20:43:43     INFO - Running main action method: run_media_tests
C:\slave\test\build\venv\Lib\site-packages\mozrunner\utils.py:20: UserWarning: Module mozprocess was already imported from C:\slave\test\scripts\mozprocess\__init__.py, but c:\slave\test\build\venv\lib\site-packages is being added to sys.path
  import pkg_resources
20:43:44     INFO - Minidump tooltool manifest unknown. Determining based upon platform and architecture.
20:43:44     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:43:44     INFO - trying https://hg.mozilla.org/mozilla-central/raw-file/default/testing/config/tooltool-manifests/win32/releng.manifest
20:43:44     INFO - Downloading https://hg.mozilla.org/mozilla-central/raw-file/default/testing/config/tooltool-manifests/win32/releng.manifest to C:\slave\test\build\releng.manifest
20:43:44     INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/mozilla-central/raw-file/default/testing/config/tooltool-manifests/win32/releng.manifest', 'file_name': 'C:\\slave\\test\\build\\releng.manifest'}, attempt #1
20:43:45     INFO - Downloaded 261 bytes.
20:43:45     INFO - Minidump filename unknown. Determining based upon platform and architecture.
20:43:45     INFO - grabbing minidump binary from tooltool
20:43:45     INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]}
20:43:45     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\\releng.manifest', '-o'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01865AA0>, '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 0x019372E0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01C9D0C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
20:43:45     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\\releng.manifest', '-o'] in C:\slave\test\build
20:43:45     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\releng.manifest -o
20:43:45     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\\releng.manifest', '-o'] with output_timeout 600
20:43:45     INFO -  INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
20:43:46     INFO -  INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp_9hln_
20:43:46     INFO -  INFO - File integrity verified, renaming tmp_9hln_ to win32-minidump_stackwalk.exe
20:43:46     INFO - Return code: 0
20:43:46     INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
20:43:46     INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Lib\\site-packages\\external_media_harness\\runtests.py', '--binary', 'C:\\slave\\test\\build\\application\\firefox\\firefox.exe', '--symbols-path', 'https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.crashreporter-symbols.zip', '--disable-e10s', 'C:\\slave\\test\\build\\tests\\external-media-tests\\external_media_tests\\playback\\youtube\\manifest.ini', '--gecko-log', 'C:\\slave\\test\\build\\blobber_upload_dir\\gecko.log', '--log-html', 'C:\\slave\\test\\build\\blobber_upload_dir\\media_tests.html', '--log-mach', 'C:\\slave\\test\\build\\blobber_upload_dir\\media_tests_mach.log']
20:43:46     INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Lib\site-packages\external_media_harness\runtests.py --binary C:\slave\test\build\application\firefox\firefox.exe --symbols-path https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.crashreporter-symbols.zip --disable-e10s C:\slave\test\build\tests\external-media-tests\external_media_tests\playback\youtube\manifest.ini --gecko-log C:\slave\test\build\blobber_upload_dir\gecko.log --log-html C:\slave\test\build\blobber_upload_dir\media_tests.html --log-mach C:\slave\test\build\blobber_upload_dir\media_tests_mach.log
20:43:46     INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
20:43:46     INFO -  'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
20:43:46     INFO -  'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
20:43:46     INFO -  'COMPUTERNAME': 'T-W732-IX-169',
20:43:46     INFO -  'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
20:43:46     INFO -  'DCLOCATION': 'SCL3',
20:43:46     INFO -  'DEVENVDIR': 'D:\\msvs8\\Common7\\IDE',
20:43:46     INFO -  'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
20:43:46     INFO -  'FP_NO_HOST_CHECK': 'NO',
20:43:46     INFO -  'FRAMEWORKDIR': 'C:\\WINDOWS\\Microsoft.NET\\Framework',
20:43:46     INFO -  'FRAMEWORKSDKDIR': 'D:\\msvs8\\SDK\\v2.0',
20:43:46     INFO -  'FRAMEWORKVERSION': 'v2.0.50727',
20:43:46     INFO -  'HOMEDRIVE': 'C:',
20:43:46     INFO -  'HOMEPATH': '\\Users\\cltbld',
20:43:46     INFO -  'KTS_HOME': 'C:\\Program Files\\KTS',
20:43:46     INFO -  'KTS_VERSION': '1.19c',
20:43:46     INFO -  'LIBPATH': 'C:\\WINDOWS\\Microsoft.NET\\Framework\\v2.0.50727;D:\\msvs8\\VC\\ATLMFC\\LIB',
20:43:46     INFO -  'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
20:43:46     INFO -  'LOGONSERVER': '\\\\T-W732-IX-169',
20:43:46     INFO -  'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
20:43:46     INFO -  'MONDIR': 'C:\\Monitor_config\\',
20:43:46     INFO -  'MOZBUILDDIR': 'C:\\mozilla-build\\',
20:43:46     INFO -  'MOZILLABUILD': 'D:\\mozilla-build',
20:43:46     INFO -  'MOZILLABUILDDRIVE': 'C:',
20:43:46     INFO -  'MOZILLABUILDPATH': '\\mozilla-build\\',
20:43:46     INFO -  'MOZ_AIRBAG': '1',
20:43:46     INFO -  'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:43:46     INFO -  'MOZ_HIDE_RESULTS_TABLE': '1',
20:43:46     INFO -  'MOZ_MSVCVERSION': '8',
20:43:46     INFO -  'MOZ_NO_REMOTE': '1',
20:43:46     INFO -  'MOZ_TOOLS': 'D:\\mozilla-build\\moztools',
20:43:46     INFO -  'MSVCDIR': 'D:\\msvs8\\VC',
20:43:46     INFO -  'NO_EM_RESTART': '1',
20:43:46     INFO -  'NO_FAIL_ON_TEST_ERRORS': '1',
20:43:46     INFO -  'NUMBER_OF_PROCESSORS': '8',
20:43:46     INFO -  'OS': 'Windows_NT',
20:43:46     INFO -  'OURDRIVE': 'C:',
20:43:46     INFO -  'PATH': 'C:\\Program Files\\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\\;c:\\Program Files\\Microsoft Windows Performance Toolkit\\;C:\\mozilla-build\\hg',
20:43:46     INFO -  'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
20:43:46     INFO -  'PROCESSOR_ARCHITECTURE': 'x86',
20:43:46     INFO -  'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
20:43:46     INFO -  'PROCESSOR_LEVEL': '6',
20:43:46     INFO -  'PROCESSOR_REVISION': '1e05',
20:43:46     INFO -  'PROGRAMDATA': 'C:\\ProgramData',
20:43:46     INFO -  'PROGRAMFILES': 'C:\\Program Files',
20:43:46     INFO -  'PROMPT': '$P$G',
20:43:46     INFO -  'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
20:43:46     INFO -  'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
20:43:46     INFO -  'PUBLIC': 'C:\\Users\\Public',
20:43:46     INFO -  'PWD': 'C:\\slave\\test',
20:43:46     INFO -  'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
20:43:46     INFO -  'SLAVEDIR': 'C:\\slave\\',
20:43:46     INFO -  'SYSTEMDRIVE': 'C:',
20:43:46     INFO -  'SYSTEMROOT': 'C:\\windows',
20:43:46     INFO -  'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:43:46     INFO -  'TEST1': 'testie',
20:43:46     INFO -  'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
20:43:46     INFO -  'USERDOMAIN': 'T-W732-IX-169',
20:43:46     INFO -  'USERNAME': 'cltbld',
20:43:46     INFO -  'USERPROFILE': 'C:\\Users\\cltbld',
20:43:46     INFO -  'VCINSTALLDIR': 'D:\\msvs8\\VC',
20:43:46     INFO -  'VCVARS': 'D:\\msvs8\\VC\\bin\\vcvars32.bat',
20:43:46     INFO -  'VSINSTALLDIR': 'D:\\msvs8',
20:43:46     INFO -  'WINDIR': 'C:\\windows',
20:43:46     INFO -  'WINDOWS_TRACING_FLAGS': '3',
20:43:46     INFO -  'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
20:43:46     INFO -  'XPCOM_DEBUG_BREAK': 'warn'}
20:43:46     INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Lib\\site-packages\\external_media_harness\\runtests.py', '--binary', 'C:\\slave\\test\\build\\application\\firefox\\firefox.exe', '--symbols-path', 'https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.crashreporter-symbols.zip', '--disable-e10s', 'C:\\slave\\test\\build\\tests\\external-media-tests\\external_media_tests\\playback\\youtube\\manifest.ini', '--gecko-log', 'C:\\slave\\test\\build\\blobber_upload_dir\\gecko.log', '--log-html', 'C:\\slave\\test\\build\\blobber_upload_dir\\media_tests.html', '--log-mach', 'C:\\slave\\test\\build\\blobber_upload_dir\\media_tests_mach.log'] with output_timeout 10000
20:43:46     INFO -   0:00.00 LOG: MainThread INFO Using workspace for temporary data: "C:\slave\test"
20:43:53     INFO -   0:07.22 LOG: MainThread INFO Initial Profile Destination is "c:\users\cltbld\appdata\local\temp\tmptcb5cz.mozrunner"
20:43:53     INFO -   0:07.22 LOG: MainThread INFO starting httpd
20:43:53     INFO -   0:07.22 LOG: MainThread INFO running httpd on http://127.0.0.1:49926/
20:43:53     INFO -   0:07.22 LOG: MainThread INFO mozinfo updated with the following: None
20:43:53     INFO -   0:07.22 LOG: MainThread mozversion INFO application_buildid: 20160708183002
20:43:53     INFO -   0:07.22 LOG: MainThread mozversion INFO application_changeset: b8c431a838bbea940f4ff410fa119d614fa9c222
20:43:53     INFO -   0:07.22 LOG: MainThread mozversion INFO application_display_name: Nightly
20:43:53     INFO -   0:07.23 LOG: MainThread mozversion INFO application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
20:43:53     INFO -   0:07.23 LOG: MainThread mozversion INFO application_name: Firefox
20:43:53     INFO -   0:07.23 LOG: MainThread mozversion INFO application_remotingname: firefox
20:43:53     INFO -   0:07.23 LOG: MainThread mozversion INFO application_repository: https://hg.mozilla.org/projects/oak
20:43:53     INFO -   0:07.23 LOG: MainThread mozversion INFO application_vendor: Mozilla
20:43:53     INFO -   0:07.23 LOG: MainThread mozversion INFO application_version: 50.0a1
20:43:53     INFO -   0:07.23 LOG: MainThread mozversion INFO platform_buildid: 20160708183002
20:43:53     INFO -   0:07.23 LOG: MainThread mozversion INFO platform_changeset: b8c431a838bbea940f4ff410fa119d614fa9c222
20:43:53     INFO -   0:07.23 LOG: MainThread mozversion INFO platform_repository: https://hg.mozilla.org/projects/oak
20:43:53     INFO -   0:07.23 LOG: MainThread mozversion INFO platform_version: 50.0a1
20:43:53     INFO -   0:07.23 LOG: MainThread INFO running with e10s: False
20:43:53     INFO -   0:07.23 SUITE_START: MainThread 1
20:43:53     INFO -   0:07.36 TEST_START: MainThread test_basic_playback.py TestBasicYouTubePlayback.test_mse_is_enabled_by_default
20:43:58     INFO -   0:11.70 TEST_END: MainThread PASS
20:43:58     INFO -   0:11.70 TEST_START: MainThread test_basic_playback.py TestBasicYouTubePlayback.test_playback_starts
20:44:10     INFO -   0:24.10 TEST_END: MainThread PASS
20:44:10     INFO -   0:24.10 TEST_START: MainThread test_basic_playback.py TestBasicYouTubePlayback.test_video_playing_in_one_tab
20:44:10     INFO -   0:24.33 LOG: MainThread INFO https://youtu.be/AbAACm1IQE0
20:44:13     INFO -   0:26.95 LOG: MainThread INFO Expected duration: 12.581
20:44:14     INFO -   0:28.03 LOG: MainThread INFO Almost done: AbAACm1IQE0 - 12.581 seconds left.
20:44:24     INFO -   0:37.59 LOG: MainThread INFO https://www.youtube.com/watch?v=yOQQCoxs8-k
20:44:38     INFO -   0:51.66 LOG: MainThread INFO Expected duration: 138.781
20:45:36     INFO -   1:50.02 LOG: MainThread INFO Almost done: yOQQCoxs8-k - 88.7865 seconds left.
20:47:47    ERROR -   4:00.70 TEST_END: MainThread FAIL, expected PASS
20:47:47     INFO -  Traceback (most recent call last):
20:47:47     INFO -    File "C:\slave\test\build\venv\lib\site-packages\marionette\marionette_test.py", line 344, in run
20:47:47     INFO -      testMethod()
20:47:47     INFO -    File "C:\slave\test\build\tests\external-media-tests\external_media_tests\playback\youtube\test_basic_playback.py", line 65, in test_video_playing_in_one_tab
20:47:47    ERROR -      raise self.failureException(e)
20:47:47    ERROR -  AssertionError: TimeoutException: Timed out after 129.3 seconds with message:
20:47:47     INFO -  condition: playback_done
20:47:47     INFO -  YouTubePuppeteer - test url: https://www.youtube.com/watch?v=yOQQCoxs8-k: {
20:47:47     INFO -  	(video)
20:47:47     INFO -  	current_time: 49.9945,
20:47:47     INFO -  	duration: 138.781,
20:47:47     INFO -  	expected_duration: 138.781,
20:47:47     INFO -  	lag: 21.9552765912,
20:47:47     INFO -  	url: https://www.youtube.com/watch?v=yOQQCoxs8-k
20:47:47     INFO -  	src: mediasource:https://www.youtube.com/9c61d6cf-6f0d-42df-918e-8c378aa8e708
20:47:47     INFO -  	frames total: 1228
20:47:47     INFO -  	 - dropped: 0
20:47:47     INFO -  	 - corrupted: 0
20:47:47     INFO -  }
20:47:47     INFO -  #movie_player: {
20:47:47     INFO -  	video id: yOQQCoxs8-k,
20:47:47     INFO -  	video_title: How Do You Time Distance?
20:47:47     INFO -  	current_state: BUFFERING,
20:47:47     INFO -  	ad_state: UNSTARTED,
20:47:47     INFO -  	playback_quality: medium,
20:47:47     INFO -  	current_time: 49.9945,
20:47:47     INFO -  	duration: 138.781,
20:47:47     INFO -  }
20:47:52     INFO -   4:05.94 LOG: MainThread WARNING Failed to gather test failure debug.
20:47:52     INFO -  Traceback (most recent call last):
20:47:52     INFO -    File "C:\slave\test\build\venv\lib\site-packages\marionette\runner\base.py", line 551, in gather_debug
20:47:52     INFO -      rv['source'] = marionette.page_source
20:47:52     INFO -    File "C:\slave\test\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1235, in page_source
20:47:52     INFO -      return self._send_message("getPageSource", key="value")
20:47:52     INFO -    File "C:\slave\test\build\venv\lib\site-packages\marionette_driver\decorators.py", line 38, in _
20:47:52     INFO -      return func(*args, **kwargs)
20:47:52     INFO -    File "C:\slave\test\build\venv\lib\site-packages\marionette_driver\marionette.py", line 698, in _send_message
20:47:52     INFO -      self._handle_error(err)
20:47:52     INFO -    File "C:\slave\test\build\venv\lib\site-packages\marionette_driver\marionette.py", line 731, in _handle_error
20:47:52     INFO -      raise errors.lookup(error)(message, stacktrace=stacktrace)
20:47:52    ERROR -  NoSuchWindowException: NoSuchWindowException: No such content frame; perhaps the listener was not registered?
20:47:52     INFO -   4:05.99 LOG: MainThread WARNING Failed to gather test failure media debug
20:47:52     INFO -  Traceback (most recent call last):
20:47:52     INFO -    File "C:\slave\test\build\venv\Lib\site-packages\external_media_harness\runtests.py", line 73, in gather_media_debug
20:47:52     INFO -      debug_lines = marionette.execute_script(debug_script)
20:47:52     INFO -    File "C:\slave\test\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1591, in execute_script
20:47:52     INFO -      rv = self._send_message("executeScript", body, key="value")
20:47:52     INFO -    File "C:\slave\test\build\venv\lib\site-packages\marionette_driver\decorators.py", line 38, in _
20:47:52     INFO -      return func(*args, **kwargs)
20:47:52     INFO -    File "C:\slave\test\build\venv\lib\site-packages\marionette_driver\marionette.py", line 698, in _send_message
20:47:52     INFO -      self._handle_error(err)
20:47:52     INFO -    File "C:\slave\test\build\venv\lib\site-packages\marionette_driver\marionette.py", line 731, in _handle_error
20:47:52     INFO -      raise errors.lookup(error)(message, stacktrace=stacktrace)
20:47:52    ERROR -  JavascriptException: JavascriptException: TypeError: tabbrowser is undefined
20:47:52     INFO -  stacktrace:
20:47:52     INFO -  	execute_script @runtests.py, line 73
20:47:52     INFO -  	inline javascript, line 8
20:47:52     INFO -  	src: "for (var i=0; i < tabbrowser.browsers.length; ++i) {"
20:47:52     INFO -  	Stack:
20:47:52     INFO -  	@runtests.py:8:15
20:47:52     INFO -  	@runtests.py:0:49
20:47:52     INFO -  	evaluate.sandbox/promise<@chrome://marionette/content/evaluate.js:143:13
20:47:52     INFO -  	evaluate.sandbox@chrome://marionette/content/evaluate.js:104:17
20:47:52     INFO -  	GeckoDriver.prototype.execute_@chrome://marionette/content/driver.js:884:29
20:47:52     INFO -  	GeckoDriver.prototype.executeScript@chrome://marionette/content/driver.js:785:27
20:47:52     INFO -  	TaskImpl_run@resource://gre/modules/Task.jsm:319:40
20:47:52     INFO -  	TaskImpl@resource://gre/modules/Task.jsm:280:3
20:47:52     INFO -  	createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:254:14
20:47:52     INFO -  	Task_spawn@resource://gre/modules/Task.jsm:168:12
20:47:52     INFO -  	TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:388:16
20:47:52     INFO -  	TaskImpl_run@resource://gre/modules/Task.jsm:327:13
20:47:52     INFO -  	TaskImpl@resource://gre/modules/Task.jsm:280:3
20:47:52     INFO -  	createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:254:14
20:47:52     INFO -  	Task_spawn@resource://gre/modules/Task.jsm:168:12
20:47:52     INFO -  	Dispatcher.prototype.execute@chrome://marionette/content/dispatcher.js:117:13
20:47:52     INFO -  	Dispatcher.prototype.onPacket@chrome://marionette/content/dispatcher.js:88:5
20:47:52     INFO -  	DebuggerTransport.prototype._onJSONObjectReady/<@chrome://marionette/content/server.js -> resource://devtools/shared/transport/transport.js:482:11
20:47:52     INFO -  	exports.makeInfallible/<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://devtools/shared/ThreadSafeDevToolsUtils.js:101:14
20:47:52     INFO -  	exports.makeInfallible/<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://devtools/shared/ThreadSafeDevToolsUtils.js:101:14
20:47:52     INFO -  	openModalWindow@resource://gre/components/nsPrompter.js:365:5
20:47:52     INFO -  	ModalPrompter.prototype.openPrompt@resource://gre/components/nsPrompter.js:555:9
20:47:52     INFO -  	ModalPrompter.prototype.confirmEx@resource://gre/components/nsPrompter.js:699:9
20:47:52     INFO -  	A</c@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:7358
20:47:52     INFO -  	A</r<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:7297
20:47:52     INFO -  	Y.prototype.Y@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:5954
20:47:52     INFO -  	G/r<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:4687
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52     INFO -  	q.j@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10338
20:47:52     INFO -  	n@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:6431
20:47:52     INFO -  	Y.prototype.K@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:11678
20:47:52     INFO -  	W<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:10082
20:47:52     INFO -  	f</<@https://www.google.com/js/bg/lvyQzzZ4U4L1NgGCugp28RO7NLoZh4YkTlNGlqqKNAA.js line 1 > eval:1:1067
20:47:52    ERROR -   4:06.04 LOG: MainThread ERROR test_end for test_basic_playback.py TestBasicYouTubePlayback.test_video_playing_in_one_tab logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_basic_playback.TestBasicYouTubePlayback", "method_name": "test_video_playing_in_one_tab", "screenshot": "iVBORw0KGgoAAAANSUhEUgAAAlgAAACMCAYAAACgVtfMAAAgAElEQVR4nO2d+3tUVZrv999wpqdbQby2rUe0lcxIdU8zal9EEYVGNDItDVsQnbLnmZNnnuHR9tKc09NAnW6P6IyOCkirKIRsiAQDJCQkBhBIQlW4JCEkBknkkguBIgR4COj3/FCX7Mvat6pdqdrJ1+f5PJjae6/1rvd919rfvdaqXVI0GgUhhBBCCPEOKdsGEEIIIYSMNCiwCCGEEEI8hgKLEEIIIcRjKLAIIYQQQjyGAosQQgghxGMosAghhBBCPIYCixBCCCHEYyiwCCGEEEI8hgKLEEIIIcRjKLAIIYQQQjyGAosQQgghxGMyLrC6urrQ0tKC5uZm1NfXo7a2FnV1dWhtbcXhw4fR3t6OM2fODEtjFVmCJCuZKT8SQkAKIBTJflC1KJDN7MpZm3PIR8NyfRbIudj70If0h7XdGcwxRZYQCEW0nyty5sZ335PFfMq5sWb4yJjAOnLkCOrq6lBbW4vW1lYcPXoUHR0dOHHiBLq6utDb24uzZ8/izJkzaGlpweHDh3HixInMBjkgQw5kO9CZTnR9+X4dqP0UE7/7OBfspw25a0sO2h0JISDJUJKfRRDK+tieSz7z433ADza6w3OB1dnZierqakQiETQ1NaGlpQUby2rwx//egH9/4zM8v+hD/PP/+RhLP6rCp5tqcPTYcVy6dAmDg4M4deoUGhoa0NfX53lDI6EAAqFI8t+Rm0R+7FjZhgIr+/bThty1JTft1sxi+Xr2igLLPza6w1OBdejQIezYsQP79+/HntowXnpjHSbMfhe3zd6A8c9UGLjjma340dNr8PRra7EjfATffvsdLl26hH379uGbb77xsKGqp5tICIFACBHT8yRIUgxZsfo8lgyyHIAkyVA0yRH/fyWEQPya2ECgQJaGyhEOCJGha7Q2xMsMycljRqEoKt/MFn1Cm7VdV7+wnPjyq6hdEfX5IW0H0rRV/TRqXqesxMSyyAdGG2Jt0rRFkSEZ4m/dtlTsFPvYzudO7XWXM0M5KrA5YpeTuRED1+XaxkzvexM/2PVHq5i7zServqLzkyamwvLMxyxndrkYe0ztFoyJrsuwyftACBFRLti1xZHwEI+J4rHOyfic6bxL9T6QSn+x+jwT90bn+aOPlWnsIyEEMizKPRNY+/btQ01NDcLhMFau2YQJs9/FHXO3aEXVPHNum12Ep1/7FN2no+jvP4+6unocO3bMm4ZqRJXFVLLwKSiWQGZJLL6RxpNFM9iLBh09seuSCWG4TpV4imx9s49oy0zaorlOda7tE6BVm8zq17YndlM28YMimw8UGtt1T612PtC1S9vhnLQtBTst423jc0f2ussZ8UAvGPQscnLYY5BWuQ5iJsx3cd+x7I+mMXebT1Z9xegnUwGfKM+0fdY2ux97nPbxVMuwRpElyCH9Q7NdW5wILLNx36oPORifM5p39mXa3gdc9Zfhvzc6yR9NmWq7FRmBwNAK1nCsZnkisI4cOYKqqirU19fjpT9/gNueXmcQVQ8u3IGla1rw9mdtMTZ+hcf/sAd3PFOB8fMqMX5eJe6Qt+JnC95H4+FW9PaexhdffIGurq607dM7MhIKWM4eGZWxUyFjniimg7+hfm1d5teZlWN3nomdorZblqu7USpDTxaSukzDwKetT1LjaOCx+FtkQ1SBrO6Iwtkgi7alZKfDuAl97sTeVHPGzGan5w1nDNIo10nMTH2vn2F17ltHx8xss+orVn6yKs+ufQa7Uhh7LO22yjGnZdgQzwvj7FWa46jpuG+Vi07LzVTepdhvU+0vw3lvtLXfxF+q3FLk+MxZctbTYY6lQdoCq6urC+Xl5aitrcWf/nsdbv/tBo2wCvyuGmW1JzA4OIirV6/i22+/xXfffYfEf7WH+/CTF6pw57zKGPMr8Q/z30VTy1fo7DyObdsq0vyWoW7q0XIaNEZi+cNs4HGfROop7BwVWKK2W5arapPGbqvlWOtO4apTWZan7Tz2+++cts2pnVbxtve5q/2CuSSwPI1BGuU6ipnI9+nc6Kxi7iCf0hJYDscyl3b5QmCJzs2kwDLNRafjcybzzot+66K/DOe9MVWBlYxR4gEsglBAhmK5Vcg70hZYNTU12Lt3L1YrpfjRbz7RLPsFfleNfc0nEY1G0d/fjwsXLuDSpUu4fPkyrly5khRbh49F8dN/qcad82MC685nt2PKv7yLjo5vEA43oKFhf+o2Cvd7mCWlNpFiA7bVNKhVEkkmA779NKh2qtPsuswILG3bjbYJ22TY+6L1g/nygZspeJu/TW2IOvgGqV28nNhpdr0u1xRZuPxhEAiOv/HqJmfMfOuRwEo7BumUqx5gncRM5HuRDVa+dZgzjvLJqq/Y+cm6rdr2Wdvlfuyx6+NOBIjTMpzkjV1bnPVH03HfcqxzLrAyk3cp9tuU+8tw3xud5Y/eJ5FQwLA0KMuirR7ek5bA6uzsxPbt27F37178wzP/iTvnJ8RVbMnvL2sO4eTJk8nXMpw5cwb1h75GuKkDAwMDGsG1ZPWhIYE1vxJ3zNuM/7eqBK2t7di6tQy9vb0p2Sh8X0rUZJlQM/VrHLBFG/mshEtsk59+U6Bqk6TtJnenHcTYZsPmRjuBZdp27XXiNqk2OupvoqpyrTe5p7tEaGFDwieme8ys4+XUTtPr1b6VZUc+N9hrtdndcc6Y+9Y8J4c3Bu7LjR8zWdIwjZmJ7w1+sPGtOOYp5pNVXzH1k0l5wva5scvF2GNqt1OB5bQMJ3lj1xaL/igsWz/um+Wiw/E5o3mnxfl9IPX+Mnz3Rjf2i/qH/lxd7mVoNistgbV7927s3bsXy1auw3j5c9w5vzIprsY/U4H6A1/h6NGjOHbsGFrbOzD7j9UY/0w5xj9Tjtl/2pEsp7+/HzsiHRg/r0Ijsn767AdoaWlDTc0uRCLZfLWCW+yfXvyHB21yMRXtNfabxXMLvb1+s38kxMAaN0/buUKu2qUji+NE7uOTGPrI/kyOS2kJrMrKStTW1mLa/1qOuxdsTwqsO+dVYvwz29DW1oaWlha0trbi7aIw7ppfibue3Y4fx9m25yt0dXWhp6cH586dw53ztuHOZyuTjH92C9YUl2H//kPYtOlznD17NuvB8GsS5UKbFFnK2JOCJcO03p45exXIfr/h+C0GtlBgZYqsjRO+wB8x9I/9mR1bUxZYHR0d2LVrF3bt2oXxv12NuxfERNNd8dmn22aX4a7ZW3H7rM24a/ZW5M3bhrufq8Ldz1Xj7ueq8dCLu9B0JDa71dnZiZrawxgzTcGYaQqufawoxrT1mLfoUzQ0HERR0Xr09PTkQED8mETZapP2HSlWXyzIDIn6/RILv9k7WtsUBQVWJnIkW+OEn8jVGI4W+92RssBqa2tDXV0dVm8oQ96CLZjw3Hbc89z25EzWXfMr8eMFVUlRdc/z1fjxgirc+vRW/OpfK/H59gY0NTXh8OHDaG1txaK3q/G3D36C7z24Gt/71Wr8zS8/xv/4xUf4+bOrEA7vx/r1n6GjoyPrDiOEEEIIsSNlgRWJRFBfX4//Wvkp/v6Fakx4Piau7l6wPbYMuCAmrCY8X43b51bg+sc34gcPF+K+BZuwa08YdXV12LdvHxoaGrD/UAt+OH0Nvv/QGnx/8hr87YOf4nsPfoLv/Wo1bp36OurrIygqKkZLS0vWHUYIIYQQYkfKAmvPnj0Ih8N4/Z2/4t4XqpH3fBXuXrAdE56LzVrdExdWY6cV4wcPF+Kahwtx68wN2FS2E9XV1aipqcGuXbuwpzaC+58txjVT1uGahwvxg4cL8YOH1saF1if4mwf+gtraMIqKinHw4MGsO4wQQgghxI60ZrAikQje/mshfvqvNch7fjvynq9G3j9/gTvnb4/NWD20Ftc8XIhrpqzDtY8U4d/+XI7S0lJs2bIF5eXl2PFlHR54rgRjpioY84iCax8pwrVTimJC66G1+P5Da3D79DexZ0891q4tQmNjY9YdljpZXHs2/TmFYS7Dcx84KW+0rPlnw7cZwpNco42EkOyS1h6s/fv3Y+2mSvzkhTJMDFbh74Jf4PbfluOaKetis1ZT1uHaKUUY84iCsY8WY/W6UiiKguLiYlRWVuLnz5XgukeLMfbRYox9dAPGTl2PMVNjQitRxuQXVuPLL2uxevUatLW1Zd1hznH/4rnsk+mbNAVWdmOXzgtFc5HhtNEP/iCE5BJpfYuwrq4O4XAY9y4oxL0vVOOW/M9x7ZS1Q+LqEQVjpq7H2Ec34OYZJSgsLMTq1avx6aef4i/vbcK4aRuTXPfYZ7jusZjQGjN1Pa59RMG1jyiY/4c12LlzD1auXIUTJ05k3WHO4Q2LAivXYkeB5Y+6CCEjgZQF1tmzZ7Fz5040NjZi1ksf4eaZxRgzZS3GTCnEtY+oxVUxrp++CeNnbUZRURHWrFmDTz75BAVLS3HDrz/HDb/ehOunb8L100uGhNajxRg7dQPGPLYOf137OWpqvsQHH/wVfX19qTVU95Za/RuXQ8rQceOPWYrfhhwKqd88LP7ZC+1bcVOpS432q8zCt6jr3qQbe1uuDEX0RmWDHSKb9W3SlTHsPtCeY/1meF28hHU6fzuwVV1u7BiKSer5Ki7D6s3GiRwwi+9IzFcLG+zGA0NeW/jHtj+4eQM1X09AyEgirReN1tbWoqmpCf/5USmue+RjXDd1Ha6bWoQxU4uS4mrcY59h3LQS3DZ9BW54cDHG/fI/MO6X/4GbHvozbnx8M26cUYobZ6jFVkJoFSPvN+9h79592LChBGVl5SnaGRsctYOremCUnP22lCJrB1vNz1s4/S0oVV2a68zqUpWnyIKbiNVvQZkN8i7abNqeLPlAF0vL3yzTx8v174fZ1ZWaHfa/f2Wfr/Y/lmxlg5sZLD/nq3VdluOBMK/tcyW16+xynhDiV9ISWMeOHUN9fT1aWlrws9n/hbFT12HcowrGPhpbFrzusc9w/fSSmHiaXozbnijGfQs24Ze/q8RNj2/GzTO34uaZW3HT41twU1xs3fDrz3H99BJcP13Bn94uxM6de/Deeyvw9ddfp2anq18mVx3T/zaT8MlVXIb4c4u/TevSt0M0i5Dur5lb+0NcZhZ9YHjDud31btqrK8+qLg/s8CJfhb5OyYYRlq9mdbnyr9OcT/GYEz8SQnxLWgIrGo39HuGRI0dQWvElxk5ZgXEqcTVu2kbc8OtNuHFGKfLmluH1D6pRVVWF7du3Y/HynbjlyfIYT5QlhdaNM0px4+Ob8eBz76C2NoySks0oLi5O/WdyXA2oql9aT/lXyp2epx9onS0PxGZL0rHRos1ZF1gOls4shY0TX1i11yuB5cQOL/LVpPyUbBhh+eobgcVlQUJGKmkLrOPHj2P37t1ob2/H6ys3YdyjqzHusWKMm7YR10/fhBtnfI6bHt8M+X/XoLa2Frt378auXbsQDofxwye24Nb8CvzwyW245cly3PzEVtz8xFb8ZO572P7FbuzYsRvvvPNumm9wFy0JqKfvVU/ZVsc05XksLhwvH8WIhALxc62WQRzWZWhzlgSWIx/YL9vZLj9p2qu+WUfjSzteLBE6iUmq+ep8CdedDSMtX90sEaawnOf4mH2OpZ4rhJBcJm2BFY1G0djYiAMHDqCzsxNL392AcY99jOunxZYGb5yxGTc9vhmz/7ADBw8eRENDA8LhMPY1NOJH+eX40aztuPWpSvwwfxtueWIzfjZvOWp21WLPnnq8994K1NfXp99QzVS8cSCMba7Vb3jVX+d2iTD+o6VOrxPWpSpPkVVLCeqn3tggLdo0bHUzMGuz1mZ1e1IRWB77QOcH683l2jpNY6z2qyxry7OqK0U7NNeb/aCtTb46EkgmvjSP70jLV7O63PhX+7e5f2x8ZZVjjsYYQogf8URgRaOxDe/Nzc04efIkSir24p5Z7+PGGRtx0+ObcdPMLZgwpxKl1c04evQoDjW1ouCNOtz+m2rc9k/VuO2fqnBr/nr85uXVqAsfQl1dBB9+uBqVlZU4c+ZMBh0wGgey0dhmD7BazklhqWdoWYpYw3wlhPgTzwTW2bNnUV9fj0OHDqG3txedJ7rwf1eV4+9mr8AtMzfg1lmVuPWpCtz6RClue6ocd8zegTt+W4X/+fR6zF1UhNLqejQ3H0FdXRgrVqxCVVVV6q9lcMxoHLxHY5vTR5El0xknq2PmMeDeG+e+Yr4SQvyHZwIrQXNzM3bs2IGuri6cP38ely5dQt3BVrxTWI0/rqjCgj8qeGGxgtBH1fiouALNR9px9OgxNDcfwdat5VixYiUOHDiQ+qZ2V4zGwXs0tjkVtO9Q0i5zWR0j3sJ8JYT4E88FVjQaRXd3N/bu3Ys9e/ago6MDly9fxrfffofBwSu4cOEiotFz6Ok5jRMnTqGlpRVbtpTh449jS4InT57MulMIIYQQQtIhIwIrQXd3Nw4ePIjq6mrU1NRg586dqK7+AhUVlSgtLcX69RuwceNGRCIRnDp1KuvOIIQQQgjxgowKLDV9fX3o7u7GqVOncOrUKXR3dw/DHitCCCGEkOFn2AQWIYQQQshogQKLEEIIIcRjKLAIIYQQQjyGAosQQgghxGMosAghhBBCPIYCixBCCCHEYyiwCCGEEEI8hgKLEEIIIcRjKLAIIYQQQjyGAosQQgghxGMosAghhBBCPIYCixBCCCHEYyiwCCGEEEI8hgKLEEIIIcRjKLAIIYQQQjxmdAgsRYYkSXFkKG6ujYQQkAIIRbyyR4HsaXkp+kNW7D8b0eRAHOzi4yb3kvHzoF0plqXIEgKhiKuyFFlS9c0hYuWor1EgS5IgRwXlRkIIqMsLhBDxLOb6MiIIBSRVGx3amGi7RZ+zO+5ubFLZ4PWYpsiQcq4vWcQ6J+0lI5GRL7AiIQTUoioSQkjJRF1OB2+r8/THMiECIggF9GWKPhvp5KrASiUW6mvSbVcaZen7mquyRMf1AiuAQECCrFhcJxAPipywyXuBpRWVDm1M2BmQIZvF2u54TuR7QlzKOdiXRO3NZXvJSGTkCyxFVj3BZhKfCKxICAH9U7HosxFPjgqsVGKhuSbNdqVZlkZwaGZFvRFYISWEgKY/q8+J3UBl0wcobwVWJBTQzTA5sTFGJBRAIBRJ/quvx+54buW7XdkRhAIuVw4yalOO9n0y4hj5Ais+bS8epOJPNPGlhNjAHOt8shyILyeaDPKGpQzVkoSsWAg7p2XIxjJNrzVpi8CGSChguAGpP1Nk9Q1KMDsgK56dY4hHRN2ukGBmQh8rd8e0ZYpmPuyWkUX5YlW/MV6yEr8xG+Inio9RPIjaKLxGmCM2Pras3yGRhLjQix275T6HAisinjUaWvqyupGbx9wq34R5ExL1bwc2JvNIZbOhHLvj7vLDypea9tjki3m/cJInZsunJjmkqVMgnFVjm2aZWSToDeMgBRYZHkaBwIpCPfgMddLYZ0bhpRdkghtDorNqliN0ndZSYKnKUGTVwGU3g2VRv9m+KsPAoh8kdZ+py1FkBAJDT9DJp2mvzhH4JRGfmAjR+l0raNwfM5ZpHjujfdb5YlW/Nta6GR5NPPTx0d0khDcn0TVWOWrmD5v6XfQ3RZYgh8xmccxmmZwLLNP/14mRISFrdp2zfBMdS0nEqMVb0k7BkrDdcVf54cQ2SdNXTccjYb9wmSfxPuB4ljFRp6592gc4mzZSYJEsMUoEVpz4k5GsRC2edq1EjrFjDnX0VJcIrcq3XzJM1h9vm+VygmiA1H+mGtwVWb3cIX6qTuscfWzMBkFBrLTttjhmOrDqy9dttBYto4ryxap+t/E0xMdoo2387HLE6kbjoCxHCG+iQ/uTnN+krUWwYfO8MEbOxJjjY5oZQit7TWyMGh8w9EuNdsdd5YetLy1y0km/cJ0niYddk9kw0zrV4l+BrI6P5ktM5mN12nlNiEtGl8CKqgYrTwSW+mk8GwLLOBuQeGo3Pt053dye+CwxiMX3TwifqtM9R0XWBZbNHpGMCyxRLIw5pY2vk2tUOWLpD2f1O8NMLEkIBByIBbt4adpl/QCUMYGVmBmxnPEzs1H0zUn1rJHVcTf54cSXdgLLyd4pJ3mSEFY251nUKdyXpjnf6ZcqKLDI8DDyBZYSMgx4sc5pteRjJYAkk84teHq12oOVssAyq38IzTKdeqnI4eb2SChgWNKTZdnwVJ3+Oakt2Rj97uyY9RKhXS64WSJ0s+Srupk5/Hp/Mr6m11jlqIk/7OpXl2P2/5Z2D30mfJ2Da4EVVc12aGeHzEVPavlmnjfxY1avgdDbKBwXVCLY7ridT6JWS/AuBZZpv7Ab0/S4+WasRZ3Jb1bqtjTYbdngEiHJEiNfYOmfCA0ba8Wb3K0EUGwDvHEfRnKzpZNN7iY3XU0Zhr8t6he960tlg93mdg36r7qL3pvjyTkCUepg07HhKdjqmGmZikX5knBpR5wvVvU7F1jiWOhnS7TxtbrGLEfN/GFbv4cCKzmbYXvTcyYmrGKu3bTtIt+c5k2ijwZCiDiwUSwuh2bW7Y5bz6Y5ne1y8VAn7BeJc7Ub6yVJH9MUMa1T/c4xgQ2a11qIBFaG7CXEhFEgsLwk208+aSzZiL7xlMuDi+PliSyX6VVcTcW4xTe30o1f0h85ngvDFQPfLSlZ5QchJNtQYLki2wNttusfPoZmBXK7zIyS4Xe4+c4fGcWHAmvY3vFHCEkFCixXZHugzXb9mUQ/fe/Fk3kmyhwGBHuLctfHIwUfCayM5QchxEsosAghhBBCPIYCixBCCCHEYyiwCCGEEEI8hgKLEEIIIcRjKLAIIYQQQjyGAosQQgghxGMosAghhBBCPIYCixBCCCHEYyiwCCGEEEI8ZtQIrDeWLXNNtm0mhBBCiD8ZVQJrYGDAMRRYhBBCCEmVUSewij8vF/JZ6TaUlm9HRVWNA4GlQJbUv+kmIRCKeGKnInv9+2I59jtqbomEEPCz/YQQQkYlo1Jg1Tc0adh/qAVNLV+hrf1r1Oz80qHAUt/044JLVtK0U4EcCCEirCONMilQCCGEkGFlVAus8IFmHGxqRUvbUbR/3YHOb45j9569KQisaHymRYaSjp2REOTkTBgFFiGEEOJXRqXACh84jP2NR9B4uA1t7V+jo/MbnDh5El1dXdgXDqcmsKIRhAISZCX+dySEgGoJMfl54tqQbLG8qFuCFMyMKbLoeMwGbZ1qW81m2uLnKEM2y0oUkVBAbKOmbWJRKbZPe20gFNL4UXyN3n473xFCCCHZZ9QJrE1bK9HU8hVav/oaxzq+QVdXF06fPo2+vj709fWhsanZA4EVEzJasWUichTZRKQ4nXlSnafI5uIpohOAhnMkSInlSUXWiheNjTq7FNlG5BgFXsKGmIATtVEgqlz5jhBCCMkuo05glVVUo/3rDhw/cQI9PT04e/Ys+vv7k3zVfjQNgRX/TLBcqMiiGSWzsqw+j6PIqk326nr1szqxcgIBq9keO5tUf+tm5sxm2EztS+4xE9QjusZUbDnwESGEEJIlRp3AqqzegW+On0B3dzf6+vrQ39+PgYEBXLhwAQMDA+jo7Ex/D1amBZamfJWwix9PLO2pZ9MCgYDFJny3Astm1sjMPiuBZdomCixCCCH+Y9QJrB27difFVTQaxcDAAC5evJjk5MmTKX+LULvPSr9EaLLElorAUuSh5TyT1xhEQoH4jNVQOYpsNovlQmAlBJvVsqCpfRZLhJbXUGARQgjxF6NOYNXV70NjYxPa2r7CsY4OnDx1Cj29veg9fRq9vafR3d2TwnuwBDd5zVKaU/GiJbnp2zDzpNrMHpAhB1QixbD5XF1+/DrNLJITm3R/65cJndoX1dqo3eRudg0FFiGEEP8x6gQW3+SeQ3jxagtCCCEkBxlVAsur3yLUbPAWkO22+gVFFs2mEUIIIf5n1Agskgto39PFVywQQggZqVBgEUIIIYR4DAUWIYQQQojHUGARQgghhHgMBRYhhBBCiMdQYBFCCCGEeAwFFiGEEEKIx1BgEUIIIYR4DAUWIYQQQojHUGARQgghhHgMBRYhhBBCiMdQYBFCCCGEeAwFFvElb775Jl588UUUFBQQFS+++CLefPNN+tUn/v6wqhH5y7Zh0qISoiJ/2TZ8WNWY9XGGkHSgwCK+Y2kohLfeegtdXV24evUqUdHV1YW33noLS0Mh+jXH/b2q8hDuf3sHJtefw8MtV4iKyfXncP/bO7Cq8lDWxxtCUoUCi/iOhQsXoqenB4ODg+jv7ycqBgcH0dPTg4ULF9KvOe7vmW+UY/K+cwgGg+jv7+e/qn9faL+MyfvOYeYb5VkfbwhJFQos4jsKCgpw9erVrN9cc5WrV6+ioKCAfs1xf09aVIKHD1/Juv25SDAYxMOHr2DSopKsjzeEpAoFFvEdBQUFuHLlCs6dO0cEXLlyJWWBRb8On78TAisYDGa9DbkIBRbxOxRYxHckhEC27chV0hVY2bbfb6QrsLJtfy7CGSwyEqDAIr4jIQTOnj1LBKQrsLJtv99IR2A91DyIYDCY9TbkIg81D1JgEV9DgUV8R+pCIIylEyVIUpyJSxH28qYQXoqJ0kQsDWf3xuS9wFIgSyq/SRImLg07sEWBnAP+yHSs0hVYluUrssrvMpRs+y3DvkwQDAYpsIjvocAivqOgoACDg4Po6+tzzr4lmChJmLtO+9mSdS7KMLAOc6WJWLIvnTK8Z3BwMGWBJfarvp3rMFeSIM1d50v/eB3jVP2tnsEyz9m5WGfIV7/61Z3dFFjE71BgEd+REAKnT592SD0WT5Qwp9Dp+U4pxBxpIhbXe11ueqQrsBy1s34xJkpzUOhD/3gd43QE1uTmy+Z1F86BNHEx6keMX53bHQwGMbn5MgUW8TUUWMR3JIRAb2+vM+oW415pDgptzxlaAptTmDhWiDnSvVi8eE7y2L2L6+Kfq5bN5hQOnVtndV2v7jzB3xpbbOwWkK7AMm1EqVIAAAU8SURBVJapt7cXvb11WHyvyk9Cm+PXFQ4dS8UH9y5eLKh/yAZjzESfx8qfM+feuH2CWBnsFMXYO39PWlSCyU2XEQwGTWIZq3/IZ73mNtnlrzAGuedLNZObKLCIv6HAIr6joKAAFy9eRHt7uzOqXkNe3muoMj1nFWZJEmatUp0v5eG1qqFj0qxVsWOrZkGSZmFV8rrEefq/07lOdWzVLOS9VuW8re3tuHjxYsoCS+xXvb3taG+vwmt5CZ+Z2Rz3QcL3Br9a+y4Rj6rX8iAZ6o/7NOFfnV1Gn8XKHPpcECtHdnrn70mLSvCLAxds4hlrj6TOT6HvbPJX2Lbc82WCYDCIXxy4QIFFfA0FFvEdKQmspLhxdnzVLBPxYCWGvDhW9RrydBvKjTe+XBFYdjYbr3PkV4MgNrkxx+vVCADTWLuJh5Wd3gusYDDoMIdNbHKVv+pjuedLNRRYxO9QYBHfkRACbW1tDqnEq3kSZq00OV75KvKkWVip+mzlrMT5KzFLysOrlYlj6r8zcExgi1vSFVjGMvX26uw0tVl/nToONj7IexWVVvWrqHw1LzbDs9KNLVbxsLLTO39PWlSCn+8fcBzXylfzIM1aabTJVf5a94Vs+zJBMBjEz/cPUGARX0OBRXxHQgi0trY6Z+VTkCQJT61UfVbxCl5Z2YrW1pV4Sn2s4hXkSU9hZWviWB5eqUhcp/471WMVeCVPVd/KpyBprpOQ90qFu/apSFdgGcsUtUXtSzObdZ9r/Grvg8SxilfyVMfEVLySF68nVq7YFqtYmdmpv847fycEVjAYNMnZV1T1qttlEw+D/WZtyz1fqqHAIn6HAov4joKCAly4cAEtLS3u2PYyJqiXsSa8jG3CYxPw8rbEdcuRb/H38vz4NfnLdcesr2tZnj9kR36+9pjezvzlrtp54cKFlAWW2K/Lka9ZAlS3y8rmWJvz8yckP89frrrGygeqYxNeflnnS8H1Uj6Wm9gbq9MqHtZ2amPsnb8nLSrBA/vPW8RS53dV/QabbPLXNAY55ssEwWAQD+w/T4FFfA0FFvEdCSHQ3NxMBKQrsLJtv4Gy32OClI/3M1bH+8iXJuD3ZcPr70mLSvBAw3kEg8EM+i+9tmWzvgcaKLCIv6HAIr4jZ4VAjjDSBNb7+RKkCb9HWcbqyK7Ayqz//CmwgsEgBRbxPRRYxHcUFBRgYGAAjY2NRMDAwEDKAis3/LoFL92jXpJ8Eu9mtL538aR0D17aMrz+nrSoBPdH+hEMBnO2bdms7/5IPwUW8TUUWMR3JITAwYMHiYB0BVa27fcb6QqsbNufiwSDQQos4nsosIjvKCgowPnz53HgwAEi4Pz58ykLLPp1+Pw9aVEJ7gufQzAYzHobcpH7wucosIivocAivmPhwoXo7OzE6dOn0dDQQFScPn0anZ2dWLhwIf2a4/6e+UY57tvZjafDPQgGg2hoaOC/qn/v29mNmW+UZ328ISRVKLCI7wiFQli2bBmOHz+O8+fPExXHjx/HsmXLEAqF6Ncc9/eqykOY9NYX+Mdd3fjHcJSo2dWNSW99gVWVh7I+3hCSKhRYxJcsWbIECxcuREFBAVGxcOFCLFmyhH71ib9XVBzEjNfLMGlRCVEx4/UyrKg4mPVxhpB0oMAihBBCCPEYCixCCCGEEI+hwCKEEEII8RgKLEIIIYQQj6HAIoQQQgjxGAosQgghhBCPocAihBBCCPEYCixCCCGEEI+hwCKEEEII8RgKLEIIIYQQj6HAIoQQQgjxGAosQgghhBCPocAihBBCCPGY/w+3p7LOsoHwBAAAAABJRU5ErkJggg=="}, "expected": "PASS", "test": "test_basic_playback.py TestBasicYouTubePlayback.test_video_playing_in_one_tab", "message": "TimeoutException: TimeoutException: Timed out after 5.0 seconds with message: Cannot get window type for chrome window handle \"89\"", "stack": "Traceback (most recent call last):\n  File \"C:\\slave\\test\\build\\venv\\lib\\site-packages\\marionette\\marionette_test.py\", line 374, in run\n    self.tearDown()\n  File \"C:\\slave\\test\\build\\venv\\lib\\site-packages\\firefox_puppeteer\\testcases\\base.py\", line 117, in tearDown\n    self._check_and_fix_leaked_handles()\n  File \"C:\\slave\\test\\build\\venv\\lib\\site-packages\\firefox_puppeteer\\testcases\\base.py\", line 62, in _check_and_fix_leaked_handles\n    self.windows.close_all([self.browser])\n  File \"C:\\slave\\test\\build\\venv\\lib\\site-packages\\firefox_puppeteer\\ui\\windows.py\", line 93, in close_all\n    self.close(handle)\n  File \"C:\\slave\\test\\build\\venv\\lib\\site-packages\\firefox_puppeteer\\ui\\windows.py\", line 72, in close\n    self.switch_to(handle)\n  File \"C:\\slave\\test\\build\\venv\\lib\\site-packages\\firefox_puppeteer\\ui\\windows.py\", line 202, in switch_to\n    return self.create_window_instance(target_handle)\n  File \"C:\\slave\\test\\build\\venv\\lib\\site-packages\\firefox_puppeteer\\ui\\windows.py\", line 112, in create_window_instance\n    message='Cannot get window type for chrome window handle \"%s\"' % handle\n  File \"C:\\slave\\test\\build\\venv\\lib\\site-packages\\marionette_driver\\wait.py\", line 144, in until\n    cause=last_exc)\n"}
20:47:52     INFO -   4:06.05 LOG: MainThread INFO START LOG:
20:47:52     INFO -   4:06.05 LOG: MainThread INFO INFO TEST-START: C:\slave\test\build\tests\external-media-tests\external_media_tests\playback\youtube\test_basic_playback.py:test_mse_is_enabled_by_default Fri Jul 08 2016 20:43:54 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.05 LOG: MainThread INFO INFO URL: https://youtu.be/AbAACm1IQE0 Fri Jul 08 2016 20:43:55 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO video element obtained Fri Jul 08 2016 20:43:55 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO #movie_player element obtained Fri Jul 08 2016 20:43:56 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO TEST-END: C:\slave\test\build\tests\external-media-tests\external_media_tests\playback\youtube\test_basic_playback.py:test_mse_is_enabled_by_default Fri Jul 08 2016 20:43:58 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO END LOG:
20:47:52     INFO -   4:06.06 LOG: MainThread INFO START LOG:
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO TEST-START: C:\slave\test\build\tests\external-media-tests\external_media_tests\playback\youtube\test_basic_playback.py:test_playback_starts Fri Jul 08 2016 20:43:58 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO URL: https://youtu.be/AbAACm1IQE0 Fri Jul 08 2016 20:43:59 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO video element obtained Fri Jul 08 2016 20:43:59 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO #movie_player element obtained Fri Jul 08 2016 20:44:00 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO URL: https://www.youtube.com/watch?v=yOQQCoxs8-k Fri Jul 08 2016 20:44:02 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO video element obtained Fri Jul 08 2016 20:44:02 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO #movie_player element obtained Fri Jul 08 2016 20:44:02 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO URL: https://www.youtube.com/watch?v=1visYpIREUM Fri Jul 08 2016 20:44:04 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO video element obtained Fri Jul 08 2016 20:44:04 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO #movie_player element obtained Fri Jul 08 2016 20:44:06 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO URL: https://www.youtube.com/watch?v=rjmuKV9BTkE Fri Jul 08 2016 20:44:08 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO video element obtained Fri Jul 08 2016 20:44:08 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO #movie_player element obtained Fri Jul 08 2016 20:44:08 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO TEST-END: C:\slave\test\build\tests\external-media-tests\external_media_tests\playback\youtube\test_basic_playback.py:test_playback_starts Fri Jul 08 2016 20:44:10 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO END LOG:
20:47:52     INFO -   4:06.06 LOG: MainThread INFO START LOG:
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO TEST-START: C:\slave\test\build\tests\external-media-tests\external_media_tests\playback\youtube\test_basic_playback.py:test_video_playing_in_one_tab Fri Jul 08 2016 20:44:10 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO URL: https://youtu.be/AbAACm1IQE0 Fri Jul 08 2016 20:44:11 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO video element obtained Fri Jul 08 2016 20:44:11 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO #movie_player element obtained Fri Jul 08 2016 20:44:12 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO Toggled autoplay. Fri Jul 08 2016 20:44:14 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO Autoplay is False Fri Jul 08 2016 20:44:14 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO URL: https://www.youtube.com/watch?v=yOQQCoxs8-k Fri Jul 08 2016 20:44:25 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO video element obtained Fri Jul 08 2016 20:44:25 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO #movie_player element obtained Fri Jul 08 2016 20:44:25 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO Autoplay is False Fri Jul 08 2016 20:44:39 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO Buffering and no playback progress. Fri Jul 08 2016 20:45:36 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO WARNING time_left greater than final_piece - 88.7865 Fri Jul 08 2016 20:45:36 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO Screenshot saved in C:\slave\test\screenshots\test_basic_playback.py-TestBasicYouTubePlayback.test_video_playing_in_one_tab_1468035936.png Fri Jul 08 2016 20:45:36 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO INFO TEST-END: C:\slave\test\build\tests\external-media-tests\external_media_tests\playback\youtube\test_basic_playback.py:test_video_playing_in_one_tab Fri Jul 08 2016 20:47:52 GMT-0700 (Pacific Standard Time)
20:47:52     INFO -   4:06.06 LOG: MainThread INFO END LOG:
20:47:52     INFO -   4:06.07 LOG: MainThread INFO
20:47:52     INFO -  SUMMARY
20:47:52     INFO -  -------
20:47:52     INFO -   4:06.07 LOG: MainThread INFO passed: 2
20:47:52     INFO -   4:06.07 LOG: MainThread INFO failed: 2
20:47:52     INFO -   4:06.07 LOG: MainThread INFO todo: 0
20:47:52     INFO -   4:06.07 LOG: MainThread INFO
20:47:52    ERROR -  FAILED TESTS
20:47:52     INFO -  -------
20:47:52     INFO -   4:06.07 LOG: MainThread INFO test_basic_playback.py test_basic_playback.TestBasicYouTubePlayback.test_video_playing_in_one_tab
20:47:52     INFO -   4:06.07 LOG: MainThread INFO test_basic_playback.py test_basic_playback.TestBasicYouTubePlayback.test_video_playing_in_one_tab
20:47:53     INFO -   4:06.90 LOG: MainThread INFO mode: non-e10s
20:47:53     INFO -   4:06.90 SUITE_END: MainThread
20:47:53     INFO -  Summary
20:47:53     INFO -  =======
20:47:53     INFO -  Ran 6 tests
20:47:53     INFO -  Expected results: 4
20:47:53    ERROR -  Unexpected results: 2 (FAIL: 2)
20:47:53     INFO -  Unexpected Results
20:47:53     INFO -  ==================
20:47:53    ERROR -  FAIL test_basic_playback.py TestBasicYouTubePlayback.test_video_playing_in_one_tab
20:47:53    ERROR -  FAIL test_basic_playback.py TestBasicYouTubePlayback.test_video_playing_in_one_tab
20:47:53    ERROR - Return code: 10
20:47:53  WARNING - # TBPL WARNING #
20:47:53  WARNING - setting return code to 1
20:47:53     INFO - Running post-action listener: _collect_uploads
20:47:53     INFO - Moving C:\slave\test\screenshots to C:\slave\test\logs\screenshots
20:47:53     INFO - Copying C:\slave\test\logs\screenshots\test_basic_playback.py-TestBasicYouTubePlayback.test_video_playing_in_one_tab_1468035936.png to C:\slave\test\build\blobber_upload_dir\test_basic_playback.py-TestBasicYouTubePlayback.test_video_playing_in_one_tab_1468035936.png
20:47:53     INFO - Copying C:\slave\test\logs\log_error.log to C:\slave\test\build\blobber_upload_dir\log_error.log
20:47:53     INFO - Copying C:\slave\test\logs\log_info.log to C:\slave\test\build\blobber_upload_dir\log_info.log
20:47:53     INFO - Running post-action listener: _resource_record_post_action
20:47:53     INFO - #####
20:47:53     INFO - ##### Finished run-media-tests step (success)
20:47:53     INFO - #####
20:47:53     INFO - Running post-run listener: _resource_record_post_run
20:47:53  WARNING - Exception when reporting resource usage: Traceback (most recent call last):
20:47:53  WARNING -   File "C:\slave\test\scripts\mozharness\base\python.py", line 521, in _resource_record_post_run
20:47:53  WARNING -     self._log_resource_usage()
20:47:53  WARNING -   File "C:\slave\test\scripts\mozharness\base\python.py", line 539, in _log_resource_usage
20:47:53  WARNING -     import jsonschema
20:47:53  WARNING -   File "C:\slave\test\build\venv\Lib\site-packages\jsonschema\__init__.py", line 12, in <module>
20:47:53  WARNING -     from jsonschema.exceptions import (
20:47:53  WARNING -   File "C:\slave\test\build\venv\Lib\site-packages\jsonschema\exceptions.py", line 6, in <module>
20:47:53  WARNING -     from jsonschema import _utils
20:47:53  WARNING -   File "C:\slave\test\build\venv\Lib\site-packages\jsonschema\_utils.py", line 6, in <module>
20:47:53  WARNING -     from jsonschema.compat import str_types, MutableMapping, urlsplit
20:47:53  WARNING -   File "C:\slave\test\build\venv\Lib\site-packages\jsonschema\compat.py", line 39, in <module>
20:47:53  WARNING -     from functools32 import lru_cache
20:47:53  WARNING - ImportError: No module named functools32
20:47:53     INFO - Running post-run listener: _upload_blobber_files
20:47:53     INFO - Blob upload gear active.
20:47:53     INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
20:47:53     INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with <oak> branch at the following location(s): https://blobupload.elasticbeanstalk.com
20:47:53     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', 'oak', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
20:47:53     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 oak -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
20:47:54     INFO -  (blobuploader) - INFO - Open directory for files ...
20:47:54     INFO -  (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\gecko.log ...
20:47:54     INFO -  (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:47:54     INFO -  (blobuploader) - INFO - Uploading, attempt #1.
20:47:55     INFO -  (blobuploader) - INFO - TinderboxPrint: <a href='http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/6cc3779862d98e0030a85d2f0eb8bb5d73959e574d661edba8c22be4cc22c756660947632edbb866d8621cfd3395eca37b66b1311573df4a82cd343fe8256587'>gecko.log</a>: uploaded
20:47:55     INFO -  (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:47:55     INFO -  (blobuploader) - INFO - Done attempting.
20:47:55     INFO -  (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\log_error.log ...
20:47:55     INFO -  (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:47:55     INFO -  (blobuploader) - INFO - Uploading, attempt #1.
20:47:56     INFO -  (blobuploader) - INFO - TinderboxPrint: <a href='http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/8c3428b7f2a4a4353503aa64ee2fe157470ff7d4d524c82467d8754a63745f9b1b77c991436aa95d8fcb91025732fe06b3cfa81d06d7aaeb28f55e3cf1bec052'>log_error.log</a>: uploaded
20:47:56     INFO -  (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:47:56     INFO -  (blobuploader) - INFO - Done attempting.
20:47:56     INFO -  (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\log_info.log ...
20:47:56     INFO -  (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:47:56     INFO -  (blobuploader) - INFO - Uploading, attempt #1.
20:47:57     INFO -  (blobuploader) - INFO - TinderboxPrint: <a href='http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/28bc569e4cd5e3fee5b6411ef08d4bcabd61be0456f12485822c0511cf46b5e056eba9954c196f393f93d138aa6b406fd3e433e291c0491e35083de5c4bc967a'>log_info.log</a>: uploaded
20:47:57     INFO -  (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:47:57     INFO -  (blobuploader) - INFO - Done attempting.
20:47:57     INFO -  (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\media_tests.html ...
20:47:57     INFO -  (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:47:57     INFO -  (blobuploader) - INFO - Uploading, attempt #1.
20:48:01     INFO -  (blobuploader) - INFO - TinderboxPrint: <a href='http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/8f9530e73a75eab243ddadae8da06bafbc1afe814689518a5f2acb7f7be8f2f866bd014939b409cd30ccf228c6cf1f65c5c7fb9541b313bd66de4fe49f30fa73'>media_tests.html</a>: uploaded
20:48:01     INFO -  (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:48:01     INFO -  (blobuploader) - INFO - Done attempting.
20:48:01     INFO -  (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\media_tests_mach.log ...
20:48:01     INFO -  (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:48:01     INFO -  (blobuploader) - INFO - Uploading, attempt #1.
20:48:02     INFO -  (blobuploader) - INFO - TinderboxPrint: <a href='http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/0bf4ea655587e15abac6b7df80974f5599b6473d02277eacde1cc8a8b1d84e73e00e83c19209ed8c4be343157d2c80b7fe1c6d63fdcecef368e5b96a5b7a168e'>media_tests_mach.log</a>: uploaded
20:48:02     INFO -  (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:48:02     INFO -  (blobuploader) - INFO - Done attempting.
20:48:02     INFO -  (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\test_basic_playback.py-TestBasicYouTubePlayback.test_video_playing_in_one_tab_1468035936.png ...
20:48:02     INFO -  (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:48:02     INFO -  (blobuploader) - INFO - Uploading, attempt #1.
20:48:04     INFO -  (blobuploader) - INFO - TinderboxPrint: <a href='http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/4cceb1d3253304068feed1064aa957076eb94736cca7d6dea3e8fc3260f3fdaa7155bec216acada05da64527ed9eb95ab2e5daa50403a03011e59ef3ced1598e'>test_basic_playback.py-TestBasicYouTubePlayback.test_video_playing_in_one_tab_1468035936.png</a>: uploaded
20:48:04     INFO -  (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:48:04     INFO -  (blobuploader) - INFO - Done attempting.
20:48:04     INFO -  (blobuploader) - INFO - Iteration through files over.
20:48:04     INFO - Return code: 0
20:48:04     INFO - rmtree: C:\slave\test\build\uploaded_files.json
20:48:04     INFO - Using _rmtree_windows ...
20:48:04     INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
20:48:04     INFO - Setting buildbot property blobber_files to {"log_error.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/8c3428b7f2a4a4353503aa64ee2fe157470ff7d4d524c82467d8754a63745f9b1b77c991436aa95d8fcb91025732fe06b3cfa81d06d7aaeb28f55e3cf1bec052", "test_basic_playback.py-TestBasicYouTubePlayback.test_video_playing_in_one_tab_1468035936.png": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/4cceb1d3253304068feed1064aa957076eb94736cca7d6dea3e8fc3260f3fdaa7155bec216acada05da64527ed9eb95ab2e5daa50403a03011e59ef3ced1598e", "log_info.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/28bc569e4cd5e3fee5b6411ef08d4bcabd61be0456f12485822c0511cf46b5e056eba9954c196f393f93d138aa6b406fd3e433e291c0491e35083de5c4bc967a", "media_tests.html": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/8f9530e73a75eab243ddadae8da06bafbc1afe814689518a5f2acb7f7be8f2f866bd014939b409cd30ccf228c6cf1f65c5c7fb9541b313bd66de4fe49f30fa73", "gecko.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/6cc3779862d98e0030a85d2f0eb8bb5d73959e574d661edba8c22be4cc22c756660947632edbb866d8621cfd3395eca37b66b1311573df4a82cd343fe8256587", "media_tests_mach.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/0bf4ea655587e15abac6b7df80974f5599b6473d02277eacde1cc8a8b1d84e73e00e83c19209ed8c4be343157d2c80b7fe1c6d63fdcecef368e5b96a5b7a168e"}
20:48:04     INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
20:48:04     INFO - Writing to file C:\slave\test\properties\blobber_files
20:48:04     INFO - Contents:
20:48:04     INFO -  blobber_files:{"log_error.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/8c3428b7f2a4a4353503aa64ee2fe157470ff7d4d524c82467d8754a63745f9b1b77c991436aa95d8fcb91025732fe06b3cfa81d06d7aaeb28f55e3cf1bec052", "test_basic_playback.py-TestBasicYouTubePlayback.test_video_playing_in_one_tab_1468035936.png": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/4cceb1d3253304068feed1064aa957076eb94736cca7d6dea3e8fc3260f3fdaa7155bec216acada05da64527ed9eb95ab2e5daa50403a03011e59ef3ced1598e", "log_info.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/28bc569e4cd5e3fee5b6411ef08d4bcabd61be0456f12485822c0511cf46b5e056eba9954c196f393f93d138aa6b406fd3e433e291c0491e35083de5c4bc967a", "media_tests.html": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/8f9530e73a75eab243ddadae8da06bafbc1afe814689518a5f2acb7f7be8f2f866bd014939b409cd30ccf228c6cf1f65c5c7fb9541b313bd66de4fe49f30fa73", "gecko.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/6cc3779862d98e0030a85d2f0eb8bb5d73959e574d661edba8c22be4cc22c756660947632edbb866d8621cfd3395eca37b66b1311573df4a82cd343fe8256587", "media_tests_mach.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/0bf4ea655587e15abac6b7df80974f5599b6473d02277eacde1cc8a8b1d84e73e00e83c19209ed8c4be343157d2c80b7fe1c6d63fdcecef368e5b96a5b7a168e"}
20:48:04     INFO - Running post-run listener: copy_logs_to_upload_dir
20:48:04     INFO - Copying logs to upload dir...
20:48:04     INFO - mkdir: C:\slave\test\build\upload\logs
20:48:04     INFO - Copying logs to upload dir...
20:48:04     INFO - Using _rmtree_windows ...
20:48:04     INFO - Using _rmtree_windows ...
20:48:04     INFO - Using _rmtree_windows ...
20:48:04     INFO - Using _rmtree_windows ...
20:48:04     INFO - Using _rmtree_windows ...
20:48:04     INFO - Using _rmtree_windows ...
20:48:04     INFO - Using _rmtree_windows ...
20:48:04  WARNING - returning nonzero exit status 1
program finished with exit code 1
elapsedTime=419.586000
========= master_lag: 0.31 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' warnings (results: 1, elapsed: 6 mins, 59 secs) (at 2016-07-08 20:48:04.872595) =========

========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 20:48:04.873453) =========
'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\AppData\Roaming
  COMMONPROGRAMFILES=C:\Program Files\Common Files
  COMPUTERNAME=T-W732-IX-169
  COMSPEC=C:\windows\system32\cmd.exe
  DCLOCATION=SCL3
  DNSSUFFIX=wintest.releng.scl3.mozilla.com
  FP_NO_HOST_CHECK=NO
  HOMEDRIVE=C:
  HOMEPATH=\Users\cltbld
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld\AppData\Local
  LOGONSERVER=\\T-W732-IX-169
  MONDIR=C:\Monitor_config\
  MOZBUILDDIR=C:\mozilla-build\
  NUMBER_OF_PROCESSORS=8
  OS=Windows_NT
  OURDRIVE=C:
  PATH=C:\Program Files\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\;c:\Program Files\Microsoft Windows Performance Toolkit\;C:\mozilla-build\hg
  PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
  PROCESSOR_ARCHITECTURE=x86
  PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
  PROCESSOR_LEVEL=6
  PROCESSOR_REVISION=1e05
  PROGRAMDATA=C:\ProgramData
  PROGRAMFILES=C:\Program Files
  PROMPT=$P$G
  PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
  PUBLIC=C:\Users\Public
  PWD=C:\slave\test\properties
  RUNLOGFILE=C:\slave\\runslave.log
  SLAVEDIR=C:\slave\
  SYSTEMDRIVE=C:
  SYSTEMROOT=C:\windows
  TEMP=C:\Users\cltbld\AppData\Local\Temp
  TEST1=testie
  TMP=C:\Users\cltbld\AppData\Local\Temp
  USERDOMAIN=T-W732-IX-169
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  WINDIR=C:\windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
 using PTY: False
blobber_files:{"log_error.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/8c3428b7f2a4a4353503aa64ee2fe157470ff7d4d524c82467d8754a63745f9b1b77c991436aa95d8fcb91025732fe06b3cfa81d06d7aaeb28f55e3cf1bec052", "test_basic_playback.py-TestBasicYouTubePlayback.test_video_playing_in_one_tab_1468035936.png": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/4cceb1d3253304068feed1064aa957076eb94736cca7d6dea3e8fc3260f3fdaa7155bec216acada05da64527ed9eb95ab2e5daa50403a03011e59ef3ced1598e", "log_info.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/28bc569e4cd5e3fee5b6411ef08d4bcabd61be0456f12485822c0511cf46b5e056eba9954c196f393f93d138aa6b406fd3e433e291c0491e35083de5c4bc967a", "media_tests.html": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/8f9530e73a75eab243ddadae8da06bafbc1afe814689518a5f2acb7f7be8f2f866bd014939b409cd30ccf228c6cf1f65c5c7fb9541b313bd66de4fe49f30fa73", "gecko.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/6cc3779862d98e0030a85d2f0eb8bb5d73959e574d661edba8c22be4cc22c756660947632edbb866d8621cfd3395eca37b66b1311573df4a82cd343fe8256587", "media_tests_mach.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/0bf4ea655587e15abac6b7df80974f5599b6473d02277eacde1cc8a8b1d84e73e00e83c19209ed8c4be343157d2c80b7fe1c6d63fdcecef368e5b96a5b7a168e"}
build_url:https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.102000
build_url: 'https://queue.taskcluster.net/v1/task/WhHKDXVxS2Oa3e0ii-1p9A/artifacts/public/build/firefox-50.0a1.en-US.win32.zip'
blobber_files: '{"log_error.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/8c3428b7f2a4a4353503aa64ee2fe157470ff7d4d524c82467d8754a63745f9b1b77c991436aa95d8fcb91025732fe06b3cfa81d06d7aaeb28f55e3cf1bec052", "test_basic_playback.py-TestBasicYouTubePlayback.test_video_playing_in_one_tab_1468035936.png": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/4cceb1d3253304068feed1064aa957076eb94736cca7d6dea3e8fc3260f3fdaa7155bec216acada05da64527ed9eb95ab2e5daa50403a03011e59ef3ced1598e", "log_info.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/28bc569e4cd5e3fee5b6411ef08d4bcabd61be0456f12485822c0511cf46b5e056eba9954c196f393f93d138aa6b406fd3e433e291c0491e35083de5c4bc967a", "media_tests.html": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/8f9530e73a75eab243ddadae8da06bafbc1afe814689518a5f2acb7f7be8f2f866bd014939b409cd30ccf228c6cf1f65c5c7fb9541b313bd66de4fe49f30fa73", "gecko.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/6cc3779862d98e0030a85d2f0eb8bb5d73959e574d661edba8c22be4cc22c756660947632edbb866d8621cfd3395eca37b66b1311573df4a82cd343fe8256587", "media_tests_mach.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/oak/sha512/0bf4ea655587e15abac6b7df80974f5599b6473d02277eacde1cc8a8b1d84e73e00e83c19209ed8c4be343157d2c80b7fe1c6d63fdcecef368e5b96a5b7a168e"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 20:48:04.994367) =========

========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 20:48:04.994741) =========
'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\AppData\Roaming
  COMMONPROGRAMFILES=C:\Program Files\Common Files
  COMPUTERNAME=T-W732-IX-169
  COMSPEC=C:\windows\system32\cmd.exe
  DCLOCATION=SCL3
  DNSSUFFIX=wintest.releng.scl3.mozilla.com
  FP_NO_HOST_CHECK=NO
  HOMEDRIVE=C:
  HOMEPATH=\Users\cltbld
  KTS_HOME=C:\Program Files\KTS
  KTS_VERSION=1.19c
  LOCALAPPDATA=C:\Users\cltbld\AppData\Local
  LOGONSERVER=\\T-W732-IX-169
  MONDIR=C:\Monitor_config\
  MOZBUILDDIR=C:\mozilla-build\
  NUMBER_OF_PROCESSORS=8
  OS=Windows_NT
  OURDRIVE=C:
  PATH=C:\Program Files\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\;c:\Program Files\Microsoft Windows Performance Toolkit\;C:\mozilla-build\hg
  PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
  PROCESSOR_ARCHITECTURE=x86
  PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
  PROCESSOR_LEVEL=6
  PROCESSOR_REVISION=1e05
  PROGRAMDATA=C:\ProgramData
  PROGRAMFILES=C:\Program Files
  PROMPT=$P$G
  PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
  PUBLIC=C:\Users\Public
  PWD=C:\slave\test
  RUNLOGFILE=C:\slave\\runslave.log
  SLAVEDIR=C:\slave\
  SYSTEMDRIVE=C:
  SYSTEMROOT=C:\windows
  TEMP=C:\Users\cltbld\AppData\Local\Temp
  TEST1=testie
  TMP=C:\Users\cltbld\AppData\Local\Temp
  USERDOMAIN=T-W732-IX-169
  USERNAME=cltbld
  USERPROFILE=C:\Users\cltbld
  WINDIR=C:\windows
  WINDOWS_TRACING_FLAGS=3
  WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
 using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= master_lag: 0.02 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 20:48:05.112078) =========

========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-07-08 20:48:05.112393) =========

========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-07-08 20:48:05.636847) =========

========= Total master_lag: 0.46 =========