builder: fx-team_win7_ix_test-dromaeojs-pgo
slave: t-w732-ix-107
starttime: 1477045726.68
results: success (0)
buildid: 20161021003003
builduid: 650ccb0f4494475fb11a2dc2b8f79993
revision: 75c306c21c0f719b590d4ebd63e1ca7ad8981adc
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-21 03:28:46.680377) =========
master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-21 03:28:46.680848) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-21 03:28:46.681124) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-21 03:28:46.826800) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-21 03:28:46.827073) =========
'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-107
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-107
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
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-107
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
--03:28:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
=> `archiver_client.py'
Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org|63.245.215.25|:443... connected.
WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure
HTTP request sent, awaiting response... 200 Script output follows
Length: 12,179 (12K) [text/x-python]
0K .......... . 100% 2.46 GB/s
03:28:47 (2.46 GB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=1.275000
========= master_lag: 0.22 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-21 03:28:48.318623) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 19 secs) (at 2016-10-21 03:28:48.318902) =========
'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-107
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-107
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
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-107
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=15.407000
========= master_lag: 4.32 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 19 secs) (at 2016-10-21 03:29:08.048907) =========
========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-21 03:29:08.049232) =========
'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 75c306c21c0f719b590d4ebd63e1ca7ad8981adc --destination scripts --debug'
in dir C:\slave\test\. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 75c306c21c0f719b590d4ebd63e1ca7ad8981adc --destination scripts --debug']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-107
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-107
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
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-107
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
2016-10-21 03:29:08,012 truncating revision to first 12 chars
2016-10-21 03:29:08,012 Setting DEBUG logging.
2016-10-21 03:29:08,012 attempt 1/10
2016-10-21 03:29:08,012 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/75c306c21c0f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2016-10-21 03:29:09,095 unpacking tar archive at: fx-team-75c306c21c0f/testing/mozharness/
2016-10-21 03:29:09,213 skipping symlink on windows: configs/single_locale/linux32.py
program finished with exit code 0
elapsedTime=1.797000
========= master_lag: 3.57 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-10-21 03:29:13.415395) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-21 03:29:13.415676) =========
script_repo_revision: 75c306c21c0f719b590d4ebd63e1ca7ad8981adc
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-21 03:29:13.416030) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-21 03:29:13.416316) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-21 03:29:13.635538) =========
========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 43 secs) (at 2016-10-21 03:29:13.635802) =========
'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team'
in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team']
environment:
ALLUSERSPROFILE=C:\ProgramData
APPDATA=C:\Users\cltbld\AppData\Roaming
COMMONPROGRAMFILES=C:\Program Files\Common Files
COMPUTERNAME=T-W732-IX-107
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-107
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PROPERTIES_FILE=C:\slave\test/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-107
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
03:29:13 INFO - MultiFileLogger online at 20161021 03:29:13 in C:\slave\test
03:29:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team
03:29:13 INFO - Dumping config to C:\slave\test\logs\localconfig.json.
03:29:13 INFO - {'append_to_log': False,
03:29:13 INFO - 'base_work_dir': 'C:\\slave\\test',
03:29:13 INFO - 'blob_upload_branch': 'Fx-Team',
03:29:13 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt',
03:29:13 INFO - 'branch': 'Fx-Team',
03:29:13 INFO - 'buildbot_json_path': 'buildprops.json',
03:29:13 INFO - 'config_files': ('talos/windows_config.py',),
03:29:13 INFO - 'default_actions': ('clobber',
03:29:13 INFO - 'read-buildbot-config',
03:29:13 INFO - 'download-and-extract',
03:29:13 INFO - 'populate-webroot',
03:29:13 INFO - 'create-virtualenv',
03:29:13 INFO - 'install',
03:29:13 INFO - 'run-tests'),
03:29:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
03:29:13 INFO - 'download_minidump_stackwalk': True,
03:29:13 INFO - 'download_symbols': 'ondemand',
03:29:13 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python',
03:29:13 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'),
03:29:13 INFO - 'hg': 'c:/mozilla-build/hg/hg',
03:29:13 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python',
03:29:13 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'),
03:29:13 INFO - 'python': 'c:/mozilla-build/python27/python.exe',
03:29:13 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe',
03:29:13 INFO - 'C:/mozilla-build/tooltool.py'),
03:29:13 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe',
03:29:13 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')},
03:29:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
03:29:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
03:29:13 INFO - 'installer_path': 'installer.exe',
03:29:13 INFO - 'log_level': 'info',
03:29:13 INFO - 'log_name': 'talos',
03:29:13 INFO - 'log_to_console': True,
03:29:13 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe',
03:29:13 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe',
03:29:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest',
03:29:13 INFO - 'opt_config_files': (),
03:29:13 INFO - 'pip_index': False,
03:29:13 INFO - 'sps_profile': False,
03:29:13 INFO - 'sps_profile_interval': 0,
03:29:13 INFO - 'suite': 'dromaeojs',
03:29:13 INFO - 'system_bits': '32',
03:29:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
03:29:13 INFO - 'title': 'T-W732-IX-107',
03:29:13 INFO - 'use_talos_json': True,
03:29:13 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'),
03:29:13 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv',
03:29:13 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll',
03:29:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
03:29:13 INFO - 'work_dir': 'build'}
03:29:13 INFO - [mozharness: 2016-10-21 10:29:13.753000Z] Running clobber step.
03:29:13 INFO - Running pre-action listener: _resource_record_pre_action
03:29:13 INFO - Running main action method: clobber
03:29:13 INFO - rmtree: C:\slave\test\build
03:29:13 INFO - Using _rmtree_windows ...
03:29:13 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1
03:31:08 INFO - Running post-action listener: _resource_record_post_action
03:31:08 INFO - [mozharness: 2016-10-21 10:31:08.633000Z] Finished clobber step (success)
03:31:08 INFO - [mozharness: 2016-10-21 10:31:08.633000Z] Running read-buildbot-config step.
03:31:08 INFO - Running pre-action listener: _resource_record_pre_action
03:31:08 INFO - Running main action method: read_buildbot_config
03:31:08 INFO - Using buildbot properties:
03:31:08 INFO - {
03:31:08 INFO - "project": "",
03:31:08 INFO - "product": "firefox",
03:31:08 INFO - "script_repo_revision": "production",
03:31:08 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs",
03:31:08 INFO - "repository": "",
03:31:08 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs",
03:31:08 INFO - "buildid": "20161021003003",
03:31:08 INFO - "pgo_build": "True",
03:31:08 INFO - "basedir": "C:\\slave\\test",
03:31:08 INFO - "buildnumber": 243,
03:31:08 INFO - "slavename": "t-w732-ix-107",
03:31:08 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/",
03:31:08 INFO - "platform": "win7_ix",
03:31:08 INFO - "scheduler": "tests-fx-team-win32-pgo-talos",
03:31:08 INFO - "branch": "fx-team",
03:31:08 INFO - "repo_path": "integration/fx-team",
03:31:08 INFO - "revision": "75c306c21c0f719b590d4ebd63e1ca7ad8981adc",
03:31:08 INFO - "stage_platform": "win32-pgo",
03:31:08 INFO - "builduid": "650ccb0f4494475fb11a2dc2b8f79993",
03:31:08 INFO - "slavebuilddir": "test"
03:31:08 INFO - }
03:31:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip.
03:31:08 INFO - Running post-action listener: _resource_record_post_action
03:31:08 INFO - [mozharness: 2016-10-21 10:31:08.637000Z] Finished read-buildbot-config step (success)
03:31:08 INFO - [mozharness: 2016-10-21 10:31:08.637000Z] Running download-and-extract step.
03:31:08 INFO - Running pre-action listener: _resource_record_pre_action
03:31:08 INFO - Running main action method: download_and_extract
03:31:08 INFO - mkdir: C:\slave\test\build\tests
03:31:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:31:08 INFO - trying https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json
03:31:08 INFO - Downloading https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
03:31:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1
03:31:09 INFO - Downloaded 1218 bytes.
03:31:09 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json
03:31:09 INFO - Using the following test package requirements:
03:31:09 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
03:31:09 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
03:31:09 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'],
03:31:09 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
03:31:09 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
03:31:09 INFO - u'jsshell-win32.zip'],
03:31:09 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
03:31:09 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'],
03:31:09 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'],
03:31:09 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
03:31:09 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'],
03:31:09 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
03:31:09 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'],
03:31:09 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
03:31:09 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'],
03:31:09 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip',
03:31:09 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']}
03:31:09 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common
03:31:09 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
03:31:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
03:31:09 INFO - Fetch https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
03:31:10 INFO - Http code: 200
03:31:10 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront)
03:31:10 INFO - x-amz-cf-id: FnbL6_kWyFt88mtrahdLd22dFL0vpqnhz_lW2RXttQ2t6K0nvkrfcA==
03:31:10 INFO - x-amz-version-id: ZVNQlhL3sfUDYsJRvoX0C2bp.g1KzLNK
03:31:11 INFO - Expected file size: 17265264
03:31:11 INFO - Obtained file size: 17265264
03:31:40 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos
03:31:40 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip
03:31:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1
03:31:40 INFO - Fetch https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory
03:31:41 INFO - Http code: 200
03:31:41 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront)
03:31:41 INFO - x-amz-cf-id: iMoH5-mVGk4UhFp9bJeRXXfaW2FXWyeBHXWakDq2SmSNiU8ubHO5Dg==
03:31:41 INFO - x-amz-version-id: ZVNQlhL3sfUDYsJRvoX0C2bp.g1KzLNK
03:31:41 INFO - Expected file size: 17265264
03:31:41 INFO - Obtained file size: 17265264
03:31:53 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip
03:31:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1
03:31:53 INFO - Fetch https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory
03:31:54 INFO - Http code: 200
03:31:54 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront)
03:31:54 INFO - x-amz-cf-id: gL9PYz8K1nx310yRrDmr6F_gH8EC-9I7YLXpzHIBW8ugI-gorlh5rQ==
03:31:54 INFO - x-amz-version-id: hHQ8LYcymQGEj8S6TwoG12F__31BmW7Y
03:31:55 INFO - Expected file size: 14409028
03:31:55 INFO - Obtained file size: 14409028
03:31: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:31:57 INFO - trying https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
03:31:57 INFO - Downloading https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip
03:31:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1
03:31:58 INFO - Downloaded 54322116 bytes.
03:31:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
03:31:58 INFO - mkdir: C:\slave\test\properties
03:31:58 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url
03:31:58 INFO - Writing to file C:\slave\test\properties\build_url
03:31:58 INFO - Contents:
03:31:58 INFO - build_url:https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
03:31:59 INFO - Running post-action listener: _resource_record_post_action
03:31:59 INFO - Running post-action listener: set_extra_try_arguments
03:31:59 INFO - [mozharness: 2016-10-21 10:31:59.073000Z] Finished download-and-extract step (success)
03:31:59 INFO - [mozharness: 2016-10-21 10:31:59.073000Z] Running populate-webroot step.
03:31:59 INFO - Running pre-action listener: _resource_record_pre_action
03:31:59 INFO - Running main action method: populate_webroot
03:31:59 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']},
03:31:59 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']},
03:31:59 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome',
03:31:59 INFO - u'--tppagecycles',
03:31:59 INFO - u'7'],
03:31:59 INFO - u'tests': [u'tsvgm']}},
03:31:59 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'],
03:31:59 INFO - u'tests': [u'tresize', u'tcanvasmark']},
03:31:59 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']},
03:31:59 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'],
03:31:59 INFO - u'tests': [u'dromaeo_css', u'kraken']},
03:31:59 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']},
03:31:59 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:31:59 INFO - u'talos_options': [u'--disable-e10s'],
03:31:59 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
03:31:59 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:31:59 INFO - u'tests': [u'tp5o_scroll', u'glterrain']},
03:31:59 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:31:59 INFO - u'talos_options': [u'--disable-e10s'],
03:31:59 INFO - u'tests': [u'damp', u'tps']},
03:31:59 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:31:59 INFO - u'tests': [u'damp', u'tps']},
03:31:59 INFO - u'g3': {u'talos_options': [u'--disable-e10s'],
03:31:59 INFO - u'tests': [u'dromaeo_dom']},
03:31:59 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']},
03:31:59 INFO - u'g4': {u'talos_options': [u'--disable-e10s'],
03:31:59 INFO - u'tests': [u'basic_compositor_video']},
03:31:59 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']},
03:31:59 INFO - u'other': {u'talos_options': [u'--disable-e10s'],
03:31:59 INFO - u'tests': [u'a11yr',
03:31:59 INFO - u'ts_paint',
03:31:59 INFO - u'tpaint',
03:31:59 INFO - u'sessionrestore',
03:31:59 INFO - u'sessionrestore_no_auto_restore',
03:31:59 INFO - u'tabpaint']},
03:31:59 INFO - u'other-e10s': {u'tests': [u'a11yr',
03:31:59 INFO - u'ts_paint',
03:31:59 INFO - u'tpaint',
03:31:59 INFO - u'sessionrestore',
03:31:59 INFO - u'sessionrestore_no_auto_restore',
03:31:59 INFO - u'tabpaint']},
03:31:59 INFO - u'other-e10s_l64': {u'tests': [u'a11yr',
03:31:59 INFO - u'ts_paint',
03:31:59 INFO - u'tpaint',
03:31:59 INFO - u'sessionrestore',
03:31:59 INFO - u'sessionrestore_no_auto_restore',
03:31:59 INFO - u'tabpaint']},
03:31:59 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr',
03:31:59 INFO - u'ts_paint',
03:31:59 INFO - u'tpaint',
03:31:59 INFO - u'sessionrestore',
03:31:59 INFO - u'sessionrestore_no_auto_restore',
03:31:59 INFO - u'tabpaint']},
03:31:59 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'],
03:31:59 INFO - u'tests': [u'a11yr',
03:31:59 INFO - u'ts_paint',
03:31:59 INFO - u'tpaint',
03:31:59 INFO - u'sessionrestore',
03:31:59 INFO - u'sessionrestore_no_auto_restore',
03:31:59 INFO - u'tabpaint']},
03:31:59 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'],
03:31:59 INFO - u'tests': [u'a11yr',
03:31:59 INFO - u'ts_paint',
03:31:59 INFO - u'tpaint',
03:31:59 INFO - u'sessionrestore',
03:31:59 INFO - u'sessionrestore_no_auto_restore',
03:31:59 INFO - u'tabpaint']},
03:31:59 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'],
03:31:59 INFO - u'tests': [u'tsvgx',
03:31:59 INFO - u'tsvgr_opacity',
03:31:59 INFO - u'tart',
03:31:59 INFO - u'tscrollx',
03:31:59 INFO - u'cart']},
03:31:59 INFO - u'svgr-e10s': {u'tests': [u'tsvgx',
03:31:59 INFO - u'tsvgr_opacity',
03:31:59 INFO - u'tart',
03:31:59 INFO - u'tscrollx',
03:31:59 INFO - u'cart']},
03:31:59 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:31:59 INFO - u'talos_options': [u'--disable-e10s'],
03:31:59 INFO - u'tests': [u'tp5o']},
03:31:59 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:31:59 INFO - u'tests': [u'tp5o']},
03:31:59 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:31:59 INFO - u'talos_options': [u'--disable-e10s',
03:31:59 INFO - u'--xperf_path',
03:31:59 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:31:59 INFO - u'tests': [u'tp5n']},
03:31:59 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip',
03:31:59 INFO - u'talos_options': [u'--xperf_path',
03:31:59 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:31:59 INFO - u'tests': [u'tp5n']}},
03:31:59 INFO - u'talos.zip': {u'path': u'',
03:31:59 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}}
03:31:59 INFO - Running post-action listener: _resource_record_post_action
03:31:59 INFO - [mozharness: 2016-10-21 10:31:59.111000Z] Finished populate-webroot step (success)
03:31:59 INFO - [mozharness: 2016-10-21 10:31:59.111000Z] Running create-virtualenv step.
03:31:59 INFO - Running pre-action listener: _resource_record_pre_action
03:31:59 INFO - Running main action method: create_virtualenv
03:31:59 INFO - Creating virtualenv C:\slave\test\build/venv
03:31:59 INFO - mkdir: C:\slave\test\build\venv\Scripts
03:31:59 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll
03:31:59 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'!
03:31:59 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build
03:31:59 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv
03:32:12 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe
03:32:16 INFO - Installing distribute......................................................................................................................................................................................done.
03:32:17 INFO - Return code: 0
03:32:17 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version']
03:32:17 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version
03:32:18 INFO - Reading from file tmpfile_stdout
03:32:18 INFO - Output received:
03:32:18 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7)
03:32:18 INFO - Using _rmtree_windows ...
03:32:18 INFO - Using _rmtree_windows ...
03:32:18 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv
03:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:32:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:32:18 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01821640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D2E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01994520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-107', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', '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
03:32:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build
03:32:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
03:32:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:32:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:32:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:32:18 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
03:32:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:32:18 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:32:18 INFO - 'DCLOCATION': 'SCL3',
03:32:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:32:18 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:32:18 INFO - 'HOMEDRIVE': 'C:',
03:32:18 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:32:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:32:18 INFO - 'KTS_VERSION': '1.19c',
03:32:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:32:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
03:32:18 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:32:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:32:18 INFO - 'MOZ_NO_REMOTE': '1',
03:32:18 INFO - 'NO_EM_RESTART': '1',
03:32:18 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:32:18 INFO - 'OS': 'Windows_NT',
03:32:18 INFO - 'OURDRIVE': 'C:',
03:32:18 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:32:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:32:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:32:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:32:18 INFO - 'PROCESSOR_LEVEL': '6',
03:32:18 INFO - 'PROCESSOR_REVISION': '1e05',
03:32:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:32:18 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:32:18 INFO - 'PROMPT': '$P$G',
03:32:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:32:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:32:18 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:32:18 INFO - 'PWD': 'C:\\slave\\test',
03:32:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:32:18 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:32:18 INFO - 'SYSTEMDRIVE': 'C:',
03:32:18 INFO - 'SYSTEMROOT': 'C:\\windows',
03:32:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:18 INFO - 'TEST1': 'testie',
03:32:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:18 INFO - 'USERDOMAIN': 'T-W732-IX-107',
03:32:18 INFO - 'USERNAME': 'cltbld',
03:32:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:32:18 INFO - 'WINDIR': 'C:\\windows',
03:32:18 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:32:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:32:18 INFO - Ignoring indexes: http://pypi.python.org/simple/
03:32:18 INFO - Downloading/unpacking pip>=1.5
03:32:23 INFO - Running setup.py egg_info for package pip
03:32:23 INFO - warning: no files found matching 'pip\cacert.pem'
03:32:23 INFO - warning: no files found matching '*.html' under directory 'docs'
03:32:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
03:32:23 INFO - no previously-included directories found matching 'docs\_build\_sources'
03:32:23 INFO - Installing collected packages: pip
03:32:23 INFO - Found existing installation: pip 0.8.2
03:32:23 INFO - Uninstalling pip:
03:32:23 INFO - Successfully uninstalled pip
03:32:23 INFO - Running setup.py install for pip
03:32:23 INFO - warning: no files found matching 'pip\cacert.pem'
03:32:23 INFO - warning: no files found matching '*.html' under directory 'docs'
03:32:23 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build'
03:32:23 INFO - no previously-included directories found matching 'docs\_build\_sources'
03:32:23 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts
03:32:23 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts
03:32:23 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts
03:32:23 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts
03:32:23 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts
03:32:23 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts
03:32:23 INFO - Successfully installed pip
03:32:23 INFO - Cleaning up...
03:32:23 INFO - Return code: 0
03:32:23 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv
03:32:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:32:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:32:23 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 0x01821640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D2E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01994520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-107', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', '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
03:32:23 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
03:32:23 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
03:32:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:32:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:32:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:32:23 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
03:32:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:32:23 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:32:23 INFO - 'DCLOCATION': 'SCL3',
03:32:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:32:23 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:32:23 INFO - 'HOMEDRIVE': 'C:',
03:32:23 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:32:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:32:23 INFO - 'KTS_VERSION': '1.19c',
03:32:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:32:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
03:32:23 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:32:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:32:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:32:23 INFO - 'MOZ_NO_REMOTE': '1',
03:32:23 INFO - 'NO_EM_RESTART': '1',
03:32:23 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:32:23 INFO - 'OS': 'Windows_NT',
03:32:23 INFO - 'OURDRIVE': 'C:',
03:32:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:32:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:32:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:32:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:32:23 INFO - 'PROCESSOR_LEVEL': '6',
03:32:23 INFO - 'PROCESSOR_REVISION': '1e05',
03:32:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:32:23 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:32:23 INFO - 'PROMPT': '$P$G',
03:32:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:32:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:32:23 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:32:23 INFO - 'PWD': 'C:\\slave\\test',
03:32:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:32:23 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:32:23 INFO - 'SYSTEMDRIVE': 'C:',
03:32:23 INFO - 'SYSTEMROOT': 'C:\\windows',
03:32:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:23 INFO - 'TEST1': 'testie',
03:32:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:23 INFO - 'USERDOMAIN': 'T-W732-IX-107',
03:32:23 INFO - 'USERNAME': 'cltbld',
03:32:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:32:23 INFO - 'WINDIR': 'C:\\windows',
03:32:23 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:32:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:32:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:32:24 INFO - Downloading/unpacking psutil>=3.1.1
03:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
03:32:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
03:32:26 INFO - Installing collected packages: psutil
03:32:26 INFO - Successfully installed psutil
03:32:26 INFO - Cleaning up...
03:32:26 INFO - Return code: 0
03:32:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv
03:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:32:26 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 0x01821640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D2E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01994520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-107', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', '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
03:32:26 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
03:32:26 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
03:32:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:32:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:32:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:32:26 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
03:32:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:32:26 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:32:26 INFO - 'DCLOCATION': 'SCL3',
03:32:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:32:26 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:32:26 INFO - 'HOMEDRIVE': 'C:',
03:32:26 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:32:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:32:26 INFO - 'KTS_VERSION': '1.19c',
03:32:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:32:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
03:32:26 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:32:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:32:26 INFO - 'MOZ_NO_REMOTE': '1',
03:32:26 INFO - 'NO_EM_RESTART': '1',
03:32:26 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:32:26 INFO - 'OS': 'Windows_NT',
03:32:26 INFO - 'OURDRIVE': 'C:',
03:32:26 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:32:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:32:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:32:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:32:26 INFO - 'PROCESSOR_LEVEL': '6',
03:32:26 INFO - 'PROCESSOR_REVISION': '1e05',
03:32:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:32:26 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:32:26 INFO - 'PROMPT': '$P$G',
03:32:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:32:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:32:26 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:32:26 INFO - 'PWD': 'C:\\slave\\test',
03:32:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:32:26 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:32:26 INFO - 'SYSTEMDRIVE': 'C:',
03:32:26 INFO - 'SYSTEMROOT': 'C:\\windows',
03:32:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:26 INFO - 'TEST1': 'testie',
03:32:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:26 INFO - 'USERDOMAIN': 'T-W732-IX-107',
03:32:26 INFO - 'USERNAME': 'cltbld',
03:32:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:32:26 INFO - 'WINDIR': 'C:\\windows',
03:32:26 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:32:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:32:26 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:32:26 INFO - Downloading/unpacking mozsystemmonitor==0.3
03:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
03:32:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
03:32:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz
03:32:27 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor
03:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3)
03:32:27 INFO - Installing collected packages: mozsystemmonitor
03:32:27 INFO - Running setup.py install for mozsystemmonitor
03:32:27 INFO - Successfully installed mozsystemmonitor
03:32:27 INFO - Cleaning up...
03:32:28 INFO - Return code: 0
03:32:28 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv
03:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:32:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:32:28 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 0x01821640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D2E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01994520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-107', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', '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
03:32:28 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
03:32:28 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
03:32:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:32:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:32:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:32:28 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
03:32:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:32:28 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:32:28 INFO - 'DCLOCATION': 'SCL3',
03:32:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:32:28 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:32:28 INFO - 'HOMEDRIVE': 'C:',
03:32:28 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:32:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:32:28 INFO - 'KTS_VERSION': '1.19c',
03:32:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:32:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
03:32:28 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:32:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:32:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:32:28 INFO - 'MOZ_NO_REMOTE': '1',
03:32:28 INFO - 'NO_EM_RESTART': '1',
03:32:28 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:32:28 INFO - 'OS': 'Windows_NT',
03:32:28 INFO - 'OURDRIVE': 'C:',
03:32:28 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:32:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:32:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:32:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:32:28 INFO - 'PROCESSOR_LEVEL': '6',
03:32:28 INFO - 'PROCESSOR_REVISION': '1e05',
03:32:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:32:28 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:32:28 INFO - 'PROMPT': '$P$G',
03:32:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:32:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:32:28 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:32:28 INFO - 'PWD': 'C:\\slave\\test',
03:32:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:32:28 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:32:28 INFO - 'SYSTEMDRIVE': 'C:',
03:32:28 INFO - 'SYSTEMROOT': 'C:\\windows',
03:32:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:28 INFO - 'TEST1': 'testie',
03:32:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:28 INFO - 'USERDOMAIN': 'T-W732-IX-107',
03:32:28 INFO - 'USERNAME': 'cltbld',
03:32:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:32:28 INFO - 'WINDIR': 'C:\\windows',
03:32:28 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:32:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:32:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:32:28 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:32:28 INFO - Downloading/unpacking jsonschema==2.5.1
03:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
03:32:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
03:32:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
03:32:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
03:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
03:32:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
03:32:29 INFO - Downloading functools32-3.2.3-2.tar.gz
03:32:29 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32
03:32:29 INFO - warning: no files found matching '*.txt'
03:32:29 INFO - no previously-included directories found matching 'build'
03:32:29 INFO - no previously-included directories found matching 'dist'
03:32:29 INFO - no previously-included directories found matching '.git*'
03:32:29 INFO - Installing collected packages: jsonschema, functools32
03:32:29 INFO - Running setup.py install for functools32
03:32:30 INFO - warning: no files found matching '*.txt'
03:32:30 INFO - no previously-included directories found matching 'build'
03:32:30 INFO - no previously-included directories found matching 'dist'
03:32:30 INFO - no previously-included directories found matching '.git*'
03:32:30 INFO - Successfully installed jsonschema functools32
03:32:30 INFO - Cleaning up...
03:32:30 INFO - Return code: 0
03:32:30 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv
03:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:32:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:32:30 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01821640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D2E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01994520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-107', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', '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
03:32:30 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build
03:32:30 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
03:32:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:32:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:32:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:32:30 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
03:32:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:32:30 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:32:30 INFO - 'DCLOCATION': 'SCL3',
03:32:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:32:30 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:32:30 INFO - 'HOMEDRIVE': 'C:',
03:32:30 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:32:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:32:30 INFO - 'KTS_VERSION': '1.19c',
03:32:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:32:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
03:32:30 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:32:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:32:30 INFO - 'MOZ_NO_REMOTE': '1',
03:32:30 INFO - 'NO_EM_RESTART': '1',
03:32:30 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:32:30 INFO - 'OS': 'Windows_NT',
03:32:30 INFO - 'OURDRIVE': 'C:',
03:32:30 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:32:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:32:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:32:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:32:30 INFO - 'PROCESSOR_LEVEL': '6',
03:32:30 INFO - 'PROCESSOR_REVISION': '1e05',
03:32:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:32:30 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:32:30 INFO - 'PROMPT': '$P$G',
03:32:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:32:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:32:30 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:32:30 INFO - 'PWD': 'C:\\slave\\test',
03:32:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:32:30 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:32:30 INFO - 'SYSTEMDRIVE': 'C:',
03:32:30 INFO - 'SYSTEMROOT': 'C:\\windows',
03:32:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:30 INFO - 'TEST1': 'testie',
03:32:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:30 INFO - 'USERDOMAIN': 'T-W732-IX-107',
03:32:30 INFO - 'USERNAME': 'cltbld',
03:32:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:32:30 INFO - 'WINDIR': 'C:\\windows',
03:32:30 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:32:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages
03:32:31 INFO - Cleaning up...
03:32:31 INFO - Return code: 0
03:32:31 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv
03:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:32:31 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 0x01821640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D2E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01994520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-107', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', '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
03:32:31 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
03:32:31 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
03:32:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:32:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:32:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:32:31 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
03:32:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:32:31 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:32:31 INFO - 'DCLOCATION': 'SCL3',
03:32:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:32:31 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:32:31 INFO - 'HOMEDRIVE': 'C:',
03:32:31 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:32:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:32:31 INFO - 'KTS_VERSION': '1.19c',
03:32:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:32:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
03:32:31 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:32:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:32:31 INFO - 'MOZ_NO_REMOTE': '1',
03:32:31 INFO - 'NO_EM_RESTART': '1',
03:32:31 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:32:31 INFO - 'OS': 'Windows_NT',
03:32:31 INFO - 'OURDRIVE': 'C:',
03:32:31 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:32:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:32:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:32:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:32:31 INFO - 'PROCESSOR_LEVEL': '6',
03:32:31 INFO - 'PROCESSOR_REVISION': '1e05',
03:32:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:32:31 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:32:31 INFO - 'PROMPT': '$P$G',
03:32:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:32:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:32:31 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:32:31 INFO - 'PWD': 'C:\\slave\\test',
03:32:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:32:31 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:32:31 INFO - 'SYSTEMDRIVE': 'C:',
03:32:31 INFO - 'SYSTEMROOT': 'C:\\windows',
03:32:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:31 INFO - 'TEST1': 'testie',
03:32:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:31 INFO - 'USERDOMAIN': 'T-W732-IX-107',
03:32:31 INFO - 'USERNAME': 'cltbld',
03:32:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:32:31 INFO - 'WINDIR': 'C:\\windows',
03:32:31 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:32:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:32:31 INFO - Downloading/unpacking blobuploader==1.2.4
03:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
03:32:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
03:32:32 INFO - Downloading blobuploader-1.2.4.tar.gz
03:32:32 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader
03:32:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
03:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
03:32:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
03:32:32 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests
03:32:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
03:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
03:32:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
03:32:33 INFO - Downloading docopt-0.6.1.tar.gz
03:32:34 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt
03:32:34 INFO - Installing collected packages: blobuploader, requests, docopt
03:32:34 INFO - Running setup.py install for blobuploader
03:32:34 INFO - Running setup.py install for requests
03:32:35 INFO - Running setup.py install for docopt
03:32:35 INFO - Successfully installed blobuploader requests docopt
03:32:35 INFO - Cleaning up...
03:32:36 INFO - Return code: 0
03:32:36 INFO - Installing None into virtualenv C:\slave\test\build/venv
03:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:32:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:32:36 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01821640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D2E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01994520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-107', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', '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
03:32:36 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
03:32:36 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
03:32:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:32:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:32:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:32:36 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
03:32:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:32:36 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:32:36 INFO - 'DCLOCATION': 'SCL3',
03:32:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:32:36 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:32:36 INFO - 'HOMEDRIVE': 'C:',
03:32:36 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:32:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:32:36 INFO - 'KTS_VERSION': '1.19c',
03:32:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:32:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
03:32:36 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:32:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:32:36 INFO - 'MOZ_NO_REMOTE': '1',
03:32:36 INFO - 'NO_EM_RESTART': '1',
03:32:36 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:32:36 INFO - 'OS': 'Windows_NT',
03:32:36 INFO - 'OURDRIVE': 'C:',
03:32:36 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:32:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:32:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:32:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:32:36 INFO - 'PROCESSOR_LEVEL': '6',
03:32:36 INFO - 'PROCESSOR_REVISION': '1e05',
03:32:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:32:36 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:32:36 INFO - 'PROMPT': '$P$G',
03:32:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:32:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:32:36 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:32:36 INFO - 'PWD': 'C:\\slave\\test',
03:32:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:32:36 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:32:36 INFO - 'SYSTEMDRIVE': 'C:',
03:32:36 INFO - 'SYSTEMROOT': 'C:\\windows',
03:32:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:36 INFO - 'TEST1': 'testie',
03:32:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:36 INFO - 'USERDOMAIN': 'T-W732-IX-107',
03:32:36 INFO - 'USERNAME': 'cltbld',
03:32:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:32:36 INFO - 'WINDIR': 'C:\\windows',
03:32:36 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:32:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:32:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
03:32:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-us20uw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
03:32:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
03:32:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-od98k4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
03:32:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
03:32:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o4upg6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
03:32:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
03:32:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wjxipo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
03:32:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
03:32:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j9e9y6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
03:32:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
03:32:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jfezpw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
03:32:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
03:32:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ghqssn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
03:32:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
03:32:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mslv10-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
03:32:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
03:32:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wbognv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
03:32:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
03:32:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fpupck-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
03:32:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
03:32:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-offmzb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
03:32:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
03:32:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bihpt5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
03:32:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
03:32:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-s5rqnl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
03:32:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
03:32:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fiiwju-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
03:32:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
03:32:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-28wpxs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
03:32:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
03:32:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-amgevw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
03:32:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
03:32:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-97rnm3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
03:32:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
03:32:39 INFO - Running setup.py install for manifestparser
03:32:40 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts
03:32:40 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts
03:32:40 INFO - Running setup.py install for mozcrash
03:32:40 INFO - Running setup.py install for mozdebug
03:32:41 INFO - Running setup.py install for mozdevice
03:32:41 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts
03:32:41 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts
03:32:41 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts
03:32:41 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts
03:32:41 INFO - Running setup.py install for mozfile
03:32:42 INFO - Running setup.py install for mozhttpd
03:32:42 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts
03:32:42 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts
03:32:42 INFO - Running setup.py install for mozinfo
03:32:42 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts
03:32:42 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts
03:32:43 INFO - Running setup.py install for mozInstall
03:32:43 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts
03:32:43 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts
03:32:43 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts
03:32:43 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts
03:32:43 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts
03:32:43 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts
03:32:43 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts
03:32:43 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts
03:32:43 INFO - Running setup.py install for mozleak
03:32:43 INFO - Running setup.py install for mozlog
03:32:43 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts
03:32:43 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts
03:32:44 INFO - Running setup.py install for moznetwork
03:32:44 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts
03:32:44 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts
03:32:44 INFO - Running setup.py install for mozprocess
03:32:45 INFO - Running setup.py install for mozprofile
03:32:45 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts
03:32:45 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts
03:32:45 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts
03:32:45 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts
03:32:45 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts
03:32:45 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts
03:32:46 INFO - Running setup.py install for mozrunner
03:32:46 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts
03:32:46 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts
03:32:46 INFO - Running setup.py install for mozscreenshot
03:32:47 INFO - Running setup.py install for moztest
03:32:47 INFO - Running setup.py install for mozversion
03:32:47 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts
03:32:47 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts
03:32:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
03:32:48 INFO - Cleaning up...
03:32:48 INFO - Return code: 0
03:32:48 INFO - Installing None into virtualenv C:\slave\test\build/venv
03:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:32:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:32:48 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01821640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D2E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01994520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-107', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', '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
03:32:48 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config
03:32:48 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
03:32:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:32:48 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:32:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:32:48 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
03:32:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:32:48 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:32:48 INFO - 'DCLOCATION': 'SCL3',
03:32:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:32:48 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:32:48 INFO - 'HOMEDRIVE': 'C:',
03:32:48 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:32:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:32:48 INFO - 'KTS_VERSION': '1.19c',
03:32:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:32:48 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
03:32:48 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:32:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:32:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:32:48 INFO - 'MOZ_NO_REMOTE': '1',
03:32:48 INFO - 'NO_EM_RESTART': '1',
03:32:48 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:32:48 INFO - 'OS': 'Windows_NT',
03:32:48 INFO - 'OURDRIVE': 'C:',
03:32:48 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:32:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:32:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:32:48 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:32:48 INFO - 'PROCESSOR_LEVEL': '6',
03:32:48 INFO - 'PROCESSOR_REVISION': '1e05',
03:32:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:32:48 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:32:48 INFO - 'PROMPT': '$P$G',
03:32:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:32:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:32:48 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:32:48 INFO - 'PWD': 'C:\\slave\\test',
03:32:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:32:48 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:32:48 INFO - 'SYSTEMDRIVE': 'C:',
03:32:48 INFO - 'SYSTEMROOT': 'C:\\windows',
03:32:48 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:48 INFO - 'TEST1': 'testie',
03:32:48 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:48 INFO - 'USERDOMAIN': 'T-W732-IX-107',
03:32:48 INFO - 'USERNAME': 'cltbld',
03:32:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:32:48 INFO - 'WINDIR': 'C:\\windows',
03:32:48 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:32:48 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:32:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:32:48 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:32:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser
03:32:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fywnlw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser
03:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1))
03:32:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash
03:32:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vl93mg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash
03:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
03:32:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug
03:32:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-frmxn7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug
03:32:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
03:32:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice
03:32:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-swjelf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice
03:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
03:32:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile
03:32:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pkfpiu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile
03:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5))
03:32:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd
03:32:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_eiivc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd
03:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6))
03:32:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo
03:32:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n9dr2s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo
03:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7))
03:32:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall
03:32:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mdm67o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall
03:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8))
03:32:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak
03:32:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j52so1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak
03:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9))
03:32:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog
03:32:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-82lrda-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog
03:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
03:32:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork
03:32:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rqfncn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork
03:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11))
03:32:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess
03:32:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cq_c2l-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess
03:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12))
03:32:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile
03:32:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wpw1eg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile
03:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13))
03:32:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner
03:32:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-clbp65-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner
03:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14))
03:32:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot
03:32:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zdrfkg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot
03:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15))
03:32:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest
03:32:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eppqfy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest
03:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16))
03:32:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion
03:32:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p3bmwu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion
03:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17))
03:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
03:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2))
03:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3))
03:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
03:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4))
03:32:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10))
03:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
03:32:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
03:32:51 INFO - Downloading blessings-1.6.tar.gz
03:32:51 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings
03:32:52 INFO - Installing collected packages: blessings
03:32:52 INFO - Running setup.py install for blessings
03:32:52 INFO - Successfully installed blessings
03:32:52 INFO - Cleaning up...
03:32:55 INFO - Return code: 0
03:32:55 INFO - Done creating virtualenv C:\slave\test\build/venv.
03:32:55 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
03:32:55 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
03:32:55 INFO - Reading from file tmpfile_stdout
03:32:55 INFO - Using _rmtree_windows ...
03:32:55 INFO - Using _rmtree_windows ...
03:32:55 INFO - Current package versions:
03:32:55 INFO - blessings == 1.6
03:32:55 INFO - blobuploader == 1.2.4
03:32:55 INFO - docopt == 0.6.1
03:32:55 INFO - functools32 == 3.2.3-2
03:32:55 INFO - jsonschema == 2.5.1
03:32:55 INFO - manifestparser == 1.1
03:32:55 INFO - mozInstall == 1.12
03:32:55 INFO - mozcrash == 0.17
03:32:55 INFO - mozdebug == 0.1
03:32:55 INFO - mozdevice == 0.48
03:32:55 INFO - mozfile == 1.2
03:32:55 INFO - mozhttpd == 0.7
03:32:55 INFO - mozinfo == 0.9
03:32:55 INFO - mozleak == 0.1
03:32:55 INFO - mozlog == 3.4
03:32:55 INFO - moznetwork == 0.27
03:32:55 INFO - mozprocess == 0.23
03:32:55 INFO - mozprofile == 0.28
03:32:55 INFO - mozrunner == 6.12
03:32:55 INFO - mozscreenshot == 0.1
03:32:55 INFO - mozsystemmonitor == 0.3
03:32:55 INFO - moztest == 0.7
03:32:55 INFO - mozversion == 1.4
03:32:55 INFO - psutil == 3.1.1
03:32:55 INFO - requests == 1.2.3
03:32:55 INFO - Installing None into virtualenv C:\slave\test\build/venv
03:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:32:55 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01821640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D2E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01994520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-107', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', '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
03:32:55 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos
03:32:55 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
03:32:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:32:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:32:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:32:55 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
03:32:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:32:55 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:32:55 INFO - 'DCLOCATION': 'SCL3',
03:32:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:32:55 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:32:55 INFO - 'HOMEDRIVE': 'C:',
03:32:55 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:32:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:32:55 INFO - 'KTS_VERSION': '1.19c',
03:32:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:32:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
03:32:55 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:32:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:32:55 INFO - 'MOZ_NO_REMOTE': '1',
03:32:55 INFO - 'NO_EM_RESTART': '1',
03:32:55 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:32:55 INFO - 'OS': 'Windows_NT',
03:32:55 INFO - 'OURDRIVE': 'C:',
03:32:55 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:32:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:32:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:32:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:32:55 INFO - 'PROCESSOR_LEVEL': '6',
03:32:55 INFO - 'PROCESSOR_REVISION': '1e05',
03:32:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:32:55 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:32:55 INFO - 'PROMPT': '$P$G',
03:32:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:32:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:32:55 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:32:55 INFO - 'PWD': 'C:\\slave\\test',
03:32:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:32:55 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:32:55 INFO - 'SYSTEMDRIVE': 'C:',
03:32:55 INFO - 'SYSTEMROOT': 'C:\\windows',
03:32:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:55 INFO - 'TEST1': 'testie',
03:32:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:55 INFO - 'USERDOMAIN': 'T-W732-IX-107',
03:32:55 INFO - 'USERNAME': 'cltbld',
03:32:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:32:55 INFO - 'WINDIR': 'C:\\windows',
03:32:55 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:32:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1))
03:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2))
03:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3))
03:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4))
03:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5))
03:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6))
03:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7))
03:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8))
03:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9))
03:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1))
03:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4))
03:32:56 INFO - Cleaning up...
03:32:56 INFO - Return code: 0
03:32:56 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv
03:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:32:56 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01821640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D2E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01994520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-107', 'USERDOMAIN': 'T-W732-IX-107', 'KTS_VERSION': '1.19c', 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 'PROGRAMFILES': 'C:\\Program Files', 'PROCESSOR_REVISION': '1e05', 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 'SYSTEMROOT': 'C:\\windows', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\ProgramData', 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 'HOMEPATH': '\\Users\\cltbld', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'USERNAME': 'cltbld', 'LOGONSERVER': '\\\\T-W732-IX-107', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', '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
03:32:56 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build
03:32:56 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
03:32:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:32:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:32:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:32:56 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
03:32:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:32:56 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:32:56 INFO - 'DCLOCATION': 'SCL3',
03:32:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:32:56 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:32:56 INFO - 'HOMEDRIVE': 'C:',
03:32:56 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:32:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:32:56 INFO - 'KTS_VERSION': '1.19c',
03:32:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:32:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
03:32:56 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:32:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:32:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:32:56 INFO - 'MOZ_NO_REMOTE': '1',
03:32:56 INFO - 'NO_EM_RESTART': '1',
03:32:56 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:32:56 INFO - 'OS': 'Windows_NT',
03:32:56 INFO - 'OURDRIVE': 'C:',
03:32:56 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:32:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:32:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:32:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:32:56 INFO - 'PROCESSOR_LEVEL': '6',
03:32:56 INFO - 'PROCESSOR_REVISION': '1e05',
03:32:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:32:56 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:32:56 INFO - 'PROMPT': '$P$G',
03:32:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:32:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:32:56 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:32:56 INFO - 'PWD': 'C:\\slave\\test',
03:32:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:32:56 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:32:56 INFO - 'SYSTEMDRIVE': 'C:',
03:32:56 INFO - 'SYSTEMROOT': 'C:\\windows',
03:32:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:56 INFO - 'TEST1': 'testie',
03:32:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:32:56 INFO - 'USERDOMAIN': 'T-W732-IX-107',
03:32:56 INFO - 'USERNAME': 'cltbld',
03:32:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:32:56 INFO - 'WINDIR': 'C:\\windows',
03:32:56 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:32:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:32:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple/
03:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages
03:32:56 INFO - Cleaning up...
03:32:56 INFO - Return code: 0
03:32:56 INFO - Running post-action listener: _resource_record_post_action
03:32:56 INFO - Running post-action listener: _start_resource_monitoring
03:32:56 INFO - Starting resource monitoring.
03:32:56 INFO - [mozharness: 2016-10-21 10:32:56.509000Z] Finished create-virtualenv step (success)
03:32:56 INFO - [mozharness: 2016-10-21 10:32:56.509000Z] Running install step.
03:32:56 INFO - Running pre-action listener: _resource_record_pre_action
03:32:56 INFO - Running main action method: install
03:32:56 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze']
03:32:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze
03:32:56 INFO - Reading from file tmpfile_stdout
03:32:56 INFO - Using _rmtree_windows ...
03:32:56 INFO - Using _rmtree_windows ...
03:32:56 INFO - Detecting whether we're running mozinstall >=1.0...
03:32:56 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h']
03:32:56 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h
03:32:56 INFO - Reading from file tmpfile_stdout
03:32:56 INFO - Output received:
03:32:56 INFO - Usage: mozinstall-script.py [options] installer
03:32:56 INFO - Options:
03:32:56 INFO - -h, --help show this help message and exit
03:32:56 INFO - -d DEST, --destination=DEST
03:32:56 INFO - Directory to install application into. [default:
03:32:56 INFO - "C:\slave\test"]
03:32:56 INFO - --app=APP Application being installed. [default: firefox]
03:32:56 INFO - Using _rmtree_windows ...
03:32:56 INFO - Using _rmtree_windows ...
03:32:56 INFO - mkdir: C:\slave\test\build\application
03:32:56 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-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application']
03:32:56 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application
03:32:58 INFO - Reading from file tmpfile_stdout
03:32:58 INFO - Output received:
03:32:58 INFO - C:\slave\test\build\application\firefox\firefox.exe
03:32:58 INFO - Using _rmtree_windows ...
03:32:58 INFO - Using _rmtree_windows ...
03:32:58 INFO - Running post-action listener: _resource_record_post_action
03:32:58 INFO - [mozharness: 2016-10-21 10:32:58.892000Z] Finished install step (success)
03:32:58 INFO - [mozharness: 2016-10-21 10:32:58.892000Z] Running run-tests step.
03:32:58 INFO - Running pre-action listener: _resource_record_pre_action
03:32:58 INFO - Running main action method: run_tests
03:32:58 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version']
03:32:58 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version
03:32:58 INFO - Python 2.7.3
03:32:58 INFO - Return code: 0
03:32:58 INFO - grabbing minidump binary from tooltool
03:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:32:58 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018D2E90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01994520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1
03:32:58 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build
03:32:58 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o
03:32:58 INFO - Calling ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600
03:32:58 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'...
03:33:01 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmptorsla
03:33:01 INFO - INFO - File integrity verified, renaming tmptorsla to win32-minidump_stackwalk.exe
03:33:01 INFO - Return code: 0
03:33:01 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755
03:33:01 INFO - mkdir: C:\slave\test\build\blobber_upload_dir
03:33:01 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir
03:33:01 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe
03:33:01 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir
03:33:01 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-107', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] in C:\slave\test\build
03:33:01 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-107 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log
03:33:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData',
03:33:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming',
03:33:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files',
03:33:01 INFO - 'COMPUTERNAME': 'T-W732-IX-107',
03:33:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe',
03:33:01 INFO - 'CYGWINBASE': 'C:\\cygwin',
03:33:01 INFO - 'DCLOCATION': 'SCL3',
03:33:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com',
03:33:01 INFO - 'FP_NO_HOST_CHECK': 'NO',
03:33:01 INFO - 'HOMEDRIVE': 'C:',
03:33:01 INFO - 'HOMEPATH': '\\Users\\cltbld',
03:33:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS',
03:33:01 INFO - 'KTS_VERSION': '1.19c',
03:33:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local',
03:33:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-107',
03:33:01 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir',
03:33:01 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe',
03:33:01 INFO - 'MONDIR': 'C:\\Monitor_config\\',
03:33:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\',
03:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:33:01 INFO - 'MOZ_NO_REMOTE': '1',
03:33:01 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir',
03:33:01 INFO - 'NO_EM_RESTART': '1',
03:33:01 INFO - 'NUMBER_OF_PROCESSORS': '8',
03:33:01 INFO - 'OS': 'Windows_NT',
03:33:01 INFO - 'OURDRIVE': 'C:',
03:33:01 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;',
03:33:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC',
03:33:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86',
03:33:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel',
03:33:01 INFO - 'PROCESSOR_LEVEL': '6',
03:33:01 INFO - 'PROCESSOR_REVISION': '1e05',
03:33:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData',
03:33:01 INFO - 'PROGRAMFILES': 'C:\\Program Files',
03:33:01 INFO - 'PROMPT': '$P$G',
03:33:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json',
03:33:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\',
03:33:01 INFO - 'PUBLIC': 'C:\\Users\\Public',
03:33:01 INFO - 'PWD': 'C:\\slave\\test',
03:33:01 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos',
03:33:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log',
03:33:01 INFO - 'SLAVEDIR': 'C:\\slave\\',
03:33:01 INFO - 'SYSTEMDRIVE': 'C:',
03:33:01 INFO - 'SYSTEMROOT': 'C:\\windows',
03:33:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:33:01 INFO - 'TEST1': 'testie',
03:33:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp',
03:33:01 INFO - 'USERDOMAIN': 'T-W732-IX-107',
03:33:01 INFO - 'USERNAME': 'cltbld',
03:33:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld',
03:33:01 INFO - 'WINDIR': 'C:\\windows',
03:33:01 INFO - 'WINDOWS_TRACING_FLAGS': '3',
03:33:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log',
03:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
03:33:01 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-107', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] with output_timeout 3600
03:33:02 INFO - mozversion application_buildid: 20161021003003
03:33:02 INFO - mozversion application_changeset: 75c306c21c0f719b590d4ebd63e1ca7ad8981adc
03:33:02 INFO - mozversion application_display_name: Nightly
03:33:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
03:33:02 INFO - mozversion application_name: Firefox
03:33:02 INFO - mozversion application_remotingname: firefox
03:33:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team
03:33:02 INFO - mozversion application_vendor: Mozilla
03:33:02 INFO - mozversion application_version: 52.0a1
03:33:02 INFO - mozversion platform_buildid: 20161021003003
03:33:02 INFO - mozversion platform_changeset: 75c306c21c0f719b590d4ebd63e1ca7ad8981adc
03:33:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team
03:33:02 INFO - mozversion platform_version: 52.0a1
03:33:02 INFO - using testdate: 1477045982
03:33:02 INFO - actual date: 1477045982
03:33:02 INFO - starting webserver on 'localhost:49675'
03:33:02 INFO - SUITE-START | Running 2 tests
03:33:02 INFO - TEST-START | dromaeo_css
03:33:02 INFO - operating with platform_type : w7_
03:33:02 INFO - Initialising browser for dromaeo_css test...
03:33:02 INFO - TEST-INFO | started process 3132 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmlffmi\profile http://localhost:49675/getInfo.html)
03:33:09 INFO - PROCESS | 3132 | __metrics Screen width/height:1600/1200
03:33:09 INFO - PROCESS | 3132 | colorDepth:24
03:33:09 INFO - PROCESS | 3132 | Browser inner width/height: 1010/674
03:33:09 INFO - PROCESS | 3132 | __metrics
03:33:10 INFO - PROCESS | 3132 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
03:33:10 INFO - PROCESS | 3132 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
03:33:11 INFO - TEST-INFO | 3132: exit 0
03:33:11 INFO - Browser initialized.
03:33:11 INFO - Running cycle 1/1 for dromaeo_css test...
03:33:11 INFO - TEST-INFO | started process 3952 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmlffmi\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1)
03:33:11 INFO - PROCESS | 3952 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0
03:33:22 INFO - PROCESS | 3952 | RSS: Main: 122990592
03:33:22 INFO - PROCESS | 3952 |
03:34:13 INFO - PROCESS | 3952 | 1477046053505 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no]
03:36:24 INFO - PROCESS | 3952 | Cycle 1(1): loaded http://localhost:49675/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49675/tests/dromaeo/cssquery-ext.html)
03:36:26 INFO - PROCESS | 3952 | RSS: Main: 199757824
03:36:26 INFO - PROCESS | 3952 |
03:39:28 INFO - PROCESS | 3952 | Cycle 1(1): loaded http://localhost:49675/tests/dromaeo/cssquery-ext.html (next: http://localhost:49675/tests/dromaeo/cssquery-jquery.html)
03:39:29 INFO - PROCESS | 3952 | RSS: Main: 197120000
03:39:29 INFO - PROCESS | 3952 |
03:42:31 INFO - PROCESS | 3952 | Cycle 1(1): loaded http://localhost:49675/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49675/tests/dromaeo/cssquery-mootools.html)
03:42:31 INFO - PROCESS | 3952 | RSS: Main: 142626816
03:42:31 INFO - PROCESS | 3952 |
03:45:33 INFO - PROCESS | 3952 | Cycle 1(1): loaded http://localhost:49675/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49675/tests/dromaeo/cssquery-prototype.html)
03:45:34 INFO - PROCESS | 3952 | RSS: Main: 145588224
03:45:34 INFO - PROCESS | 3952 |
03:48:37 INFO - PROCESS | 3952 | Cycle 1(1): loaded http://localhost:49675/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49675/tests/dromaeo/cssquery-yui.html)
03:48:37 INFO - PROCESS | 3952 | RSS: Main: 131637248
03:48:37 INFO - PROCESS | 3952 |
03:51:40 INFO - PROCESS | 3952 | Cycle 1(1): loaded http://localhost:49675/tests/dromaeo/cssquery-yui.html (next: http://localhost:49675/tests/dromaeo/cssquery-dojo.html)
03:51:40 INFO - PROCESS | 3952 | RSS: Main: 188190720
03:51:40 INFO - PROCESS | 3952 |
03:51:40 INFO - PROCESS | 3952 | __start_tp_report
03:51:40 INFO - PROCESS | 3952 | _x_x_mozilla_page_load
03:51:40 INFO - PROCESS | 3952 | _x_x_mozilla_page_load_details
03:51:40 INFO - PROCESS | 3952 | |i|pagename|runs|
03:51:40 INFO - PROCESS | 3952 | |0;dojo.html;2574.086199399214;2659.6010598410235;2687.31473474264;2691.8788654510545;2693.070890542763;4549.88625284368;4564.817407303713;4585.28928016158;4639.953600463994;4662.020975595125;4505.481869584999;4536.296873984529;4587.701799383038;4661.184292748764;4672.369230153929;3882.669973052287;3974;4055.614716601918;4059.289624315748;4068.6338229559333;4031.2945234583976;4058.3506638937774;4065.3698676705158;4067.9593204068024;4075.1645912587846;3849.8652547160855;4018.919621607566;4054.736442131267;4103.117829666621;4105.692073094515;3887.941680874789;3921.2549615573234;3971.5631280559114;3982.2234664240486;4000.559938406802;3777.848886044555;3984.242993831163;4075.3683179107284;4107.055377263247;4107.116969851467;4410.676796960931;4480.126375356806;4496.977515112403;4517.480489743689;4566.977165114186;4700.341952126705;4765.8570242892765;4844.32179494871;4860.076585448789;4863.392075990501;4653.302004699289;4785.497522760095;4801.783919723621;4887.169181239198;4891.5353041461;4395.670324725658;4408.845690400821;4469.284914413711;4474.932876006863;4543.65922555803;4676.18166820805;4821.5178482151505;4852.902941941212;4957.677750946249;4976.278439626234;4681.227597446383;4683.672142949961;4782.019685964369;4784.473707892129;4791.041791641616;4491.416115904977;4502.986827963682;4697.365855609449;4737.739424331695;4745.572898439157;4742.976285118552;4806.344614310284;4830.372051633265;4840.854774356775;4852.272159176081;4633.119707255611;4763.095011947719;4768.451628062748;4854.732989685601;4857.0528746894015;3216.8230747308653;3250.6424293327714;3255.039763269841;3295.1926777939557;3298.175456135966;2946.882124714987;2949.528075507951;3016.457037733185;3036.8937087201843;3042.6196725409322;4480.865574032784;4484.327350897392;4524.660650451229;4528.547145285445;4568.200564901129;4699.271612900006;4757.5718185363485;4817.301491283744;4908.165611845995;4908.631852611068;4413.955860441419;4446.155230506193;4454.665900057508;4458.464984201916;4464.575865292792;3790.488284081669;3956.2878681837547;4006.0385507477995;4021.155557332934;4029.6171863672903;4830.082284365959;4875.561199492063;4945.876353091202;4953.356063711694;4954.851354459372;4622.520793346097;4654.511276316073;4657.324687920165;4754.405699287589;4766.261229509431;4826.155422801066;4885.047415753894;4939.876503087452;4948.777305021355;5002.599792016703;4605.953940460552;4673.509281525391;4698.553637404441;4699.365585645894;4744.881377965578;4602.2406302959635;4689.484156742687;4818.204996175592;4932.876678082933;4938.506149385176;4668.323093151542;4672.696274742131;4772.546608072227;4773.546513081252;4774.976125119352;4413.757243351646;4459.375687403831;4584.129015487047;4599.977000114978;4657.324687920165;4343.283358245854;4459.420275364182;4583.885402864955;4611.146937816515;4652.371929789435;4674.672772905864;4765.5949244314625;4772.904541909073;4773.307870358848;4792.544708252711;4455.6435485161755;4544.704594201498;4613.3771940787565;4713.198756211521;4717.174494463524
03:51:40 INFO - PROCESS | 3952 | |1;ext.html;544.8038706065817;563.6336381352121;575.2234483447345;590.3712546138362;604.2054698072037;29710.108696739106;40106;40986.99999999996;41194.58805411945;42746.72532746724;41481.377779333336;41512.169756604926;41643.37534936971;41887.58112418875;41971.580284197145;7864.606769661494;8006.439549231556;8017.278444939969;8141.389395795339;8150.144234855314;6935.618540980244;7125.073740413739;10210.846837297446;10492.213084018691;10522.736931576734;10303.381797092161;10319.122874555671;10386.48067596621;10387.324823886423;10424.113950314233;10183.847242291371;10314.9484252579;10326.173906087495;10390.27268091234;10402.947985260102;6909.1018167638385;6954.582725036464;9673.661421850273;10054.145397641209;10062.547185376676;37819.4327085094;37970.911599862;38000.80999595012;38001.80999095015;38003.04992375213;2952.291450051972;2996.760259179282;3079.461094308487;3093.8298393588343;3270.1988012936745;28972.710272897213;30579.388412231852;30643.927462538926;30915.381692366027;31428.842855785802;3451.4132597458492;3481.216726236602;3484.9128771780656;3505.1237190702323;3558.377283975294;332864;335167;335430.8112269907;335440;337114;53307.46692533025;53413.465865341626;53497.46502535003;53693.194602081;53777.73111134419;28462.146135615963;28945.815960244356;30185.245368865515;53480.465195348326;55379.44620553823;12554.183978041397;12759.93620031894;12917.612471625866;12962.870371296354;12975.09174357786;7780.377569794403;8146.429749917567;9387.436753794795;9629.133377996013;9727.270454715925;17218.913905430392;17368.91315543414;17677.823221767874;17986.100694965462;17996.190171442315;6743.123393958754;6743.766747352802;6749.122614060141;6786.728530858711;6821.420179284816;783.4085265624417;814.2590242878952;881.2377293641083;907.7276816955019;944.3719926248967;26595;27349.316267093083;28180.85909570439;28274;29260;922.2529750901657;923.8106188231396;926.2358554192817;947.0198344713189;1037.8754549453963;14277;14537.636559086108;14691.265436728121;14764.852351476562;14784.11295322284;511099;518445;519082;521888;522291;50452;50836;50866.74566627143;50877.745611271705;51073.744631276604;1028.9742756431149;1059.9364038157735;1062.198040479433;1142.497301187475;1157.542770605623;27524.385456677202;27555.724442756116;29105;29474.410511790073;30387.392252155274;48284;48901.26648100353;48944.75527622339;48993.51006490032;49009.75495122502;461131;463643.54495186365;464202;468504;472071;20502.589948200653;20986.055627497106;21467.141314347253;23126.768732312463;23460;42099;43221.351679725456;43292.56707432885;45279;45315.546844531134;35528.67740906174;35991;36505.452418213215;37385.252294954495;37892;36219.456708149926;36666.26667466689;37257.254854903294;37735;37908;34402;34434.48348274828;34507.30985380329;35100.473492898134;35125.64874351326;32151.356972860878;33391.33217335688;33715;34048.1487962793;34246;24044.63933041041;24892.875535622206;25259;25331.6200257;25668.358291042874
03:51:40 INFO - PROCESS | 3952 | |2;jquery.html;278.63498816550333;302.64741576063886;304.66639030261854;306.59835615343906;307.9630444346679;12946.870531294684;17004.659906801855;17146.54254388377;17572.824271757294;17621.64756704865;16918.492445226664;16931.83068169318;17178.484645460656;17208.13959302036;17275.654486910254;4997.275395067713;5104.515071068243;5162.457941916101;5166.225066240076;5168.198929165986;5011.824586139486;5175.068487672217;5201.0898092833795;5204.9739751301195;5240.37115546135;5001.949980500203;5116.360454943132;5179.741012949356;5184.662996905208;5185.040767458014;4783.330333753277;4975;5071.366079240095;5102.719350435725;5127.102757017507;4434.135343607998;4439.289713645801;4441.045175287297;4444.866654000353;4450.132224216279;20679.689804652942;21303.041363138695;21304.14783408662;21801.563968720697;22028.229011984502;1852.5924296654714;1863.3850829226324;1883.6138591588692;2378.9643155352683;2551.9234422967343;13412.597622071353;13756.931215343959;13758.93120534401;14028.509002184977;14226.786598201035;1680.932762689491;1713.9571510712208;1759.4369801663472;1788.9284428622839;1807.8372946434752;82714.17285827125;83079;83814;84098.57950710268;84236.73644895332;8588.441751286146;8714.520701361485;8745.650173993095;8858.335624828163;8888.866667;9870;10266.384016959006;10360.222983276133;11376.431178441046;11470.369129697947;6529.151210342625;6608.140941677647;6662.167229096363;6677.23211830636;6686.665666716644;6465.127207826883;6571.441427478622;6657.900131498031;6666.866662666719;6683.097781799492;5554.472325129106;5623.437656234344;5671.971640141773;5673.702150629002;5686.545076393879;1468.3319089814108;1481.9703605928037;1495.7980672609276;1539.207308236237;1549.8915075944576;843.2115971566604;856.4047986649343;876.202655583416;885.9468431894107;888.4580405952364;17537.64924701499;17955.551111222325;18385.26458941654;18398.908005459885;18737.90631046836;851.0128251228546;854.3208149521146;897.1611543207122;904.2539904578749;929.772205809581;9863.704088877344;10298.485075746183;10478.895211047791;10497.527611257512;10518.158547316196;381312;382228.95330064185;382953;393510;397231;78052.60973695209;78667.85328586907;79540.60229698815;81675;82113.17886821175;903.8463461211742;910.4673765846921;920.6639576554643;924.8104138651561;925.7130289610243;15964.840351596335;16177.59556011109;16259.10574918391;16516.669666606842;16759.1620418981;30766.692333076382;37543.43684844784;38079.42880856734;38954.6104538951;39787;49075.018499630525;49504;50043;50243.74878125586;50279.49720502748;6716.529842911144;6821.965890170517;6902.792916212522;7023.613701246481;7023.683934223074;12539.561115361283;12880.935595321964;12920.769807548113;13048;13113.08208425401;10022.298439109483;10859.402732849769;10885.564577416815;10932.508037138188;11050.558141614181;10606.946965265124;10620.84068738985;10702.946485267836;10720.946395268285;10778.245522813328;10748.175816108213;10970.725731856768;11011.724706882393;11130.443477825915;11153;6299.874002519832;6590.7693230736695;6657.001449782571;7072;7160.8925866113095;9489.335746497678;10726;10802.513886875267;10836.349819010884;10997.835032474359
03:51:40 INFO - PROCESS | 3952 | |3;mootools.html;1213.2720367779327;1230.7415442757017;1268.7716211082004;1538.8845836562261;1542.0593438002822;6152.138700581917;6297.181366422365;6673.432758215546;6696.765613203539;6698.497612679051;5804.303483581965;5811.244538210038;5963.986122359199;6482.351764823516;6489.1888513935755;1853.9073046347662;1878.8966606836618;1889.8771579847266;1890.3005887821525;1891.7919028906808;1872.7378167056622;1876.474587115603;1886.2077927270511;1905.1902941249914;1907.1131923655498;1653.9503814885572;1689.2904979908405;1704.81247062823;1705.982940170595;1708.197147340748;1814.3468351393487;1871.073818459871;1871.7005279155405;1872.2698147722467;1873.569079111812;1857.4056301983371;1867.1597780998604;1876.5777700017522;1878.7275845002534;1883.143169857725;6378.266499352588;6492.123563318939;6493.350664933516;6494.960806270974;6495.772647957346;3907.27715372657;3966;4001.7598944063157;4012.137390461036;4019.155977244782;2618.37159081819;2685.8657067146564;2699.5815648574307;2720.8775605097817;2724.4278701472713;858.6093327535956;880.3485420788635;892.8883889513811;894.7539426657656;898.4878619187066;8143.51138931666;8211.753647390588;8212.425130240885;8220.753377398627;8236.958815205946;3301.834908254578;3401.897943061712;3477.3393055319407;3527.753057286017;3563.6614521620404;6938.063361446241;6959.826004349932;7051.788446346515;7325.816854578678;7367.889481657672;3852.056246219694;3925.3130702127014;3975.20495900821;4009.91980160401;4013.7792421417103;3559.199180184438;3642.307961487309;3703.333399988028;3709.3508635989133;3716.6655001050035;2821.6190814240226;2913.2279945814375;2974.866131024109;2979.5381715834083;2987.4025194961096;936.8735220745249;959.9664011759555;960.4957397366438;964.3394274921701;965.82615129276;748.1807420874134;752.8531936272484;760.5854809129038;773.4817672159667;798.0822054637214;4212.599803018708;4265.87202383929;4360.542143074931;4372.103718737651;4436.245838207513;788.8382881509277;795.7095660084143;816.5631387207816;827.5324441690547;833.8582440985047;2984.8955286564865;3037.0888733379898;3061.1734831595345;3080.183751305906;3081.8613162407296;7515.8121046973165;7681.116671582839;7688.38492920565;7760;7796.259355361231;7511.812204694927;7621;7765.689372425039;7766.184550622103;7768.456208065381;5934.465898069108;6191.288001879841;6289.811305660837;6290.21372328459;6292.339304372975;3991.6607088397814;4253.234417804825;4253.893652658709;4265.829366825292;4339.652827773707;7235.2041275458605;7265.854682906206;7283.963580182065;7332.010178625817;7365.373943214672;7026.543274687129;7460.4031677466755;7521.05986751656;7526.134494533198;7540.434467414856;4890.755462226945;4995.400551933792;5200.531952124327;5402.08164612009;5450.972745136249;5873.236479257669;5894.764209431574;6076.787312444043;6300.873982520416;6335.334789847;5816.534677225896;5891.11633254998;5935.614185077956;5938.762449501972;5962.582619216613;5509.944900550943;5741.3971532988435;5800;5819.738111784895;5878.676672783038;4890.92663610039;5071.670341427795;5167.767450464813;5180;5202.713850738246;3553.2715793262837;3706.83319250629;3738.1962877981364;3756.887293381203;3831.1954489557506;3823.5411750590106;3853.614638536124;3854.421836724514;3867.453018792393;3915.6867450604454
03:51:40 INFO - PROCESS | 3952 | |4;prototype.html;244.18077350489096;252.47611206746;254.8560063564086;255.99394380086264;256.7830183494946;4943.802247910088;5081.263216833561;5116.258142569325;5160.870978225542;5172.818951336704;4874.147024270756;4934.58056065234;4949.876253093675;5009.749512524369;5037.77330020149;3690.4648825920226;3760.398336266198;3763.849446022156;3764.73646844721;3769.246150769843;3688.8524459021605;3761.6614504694708;3765.868194613189;3777.8677746278886;3779.924401511968;3564.269324788412;3651.178484840903;3661.835217415209;3716.3867961786273;3722.088088418341;3581.158427769472;3731.7574357666663;3731.75743576668;3732.2722069196516;3744.700423966076;3219.1630176154135;3223.2586505103723;3226.1934516370907;3230.499272612749;3237.3039796443877;4933.235348520985;4961.627877909135;5009.799608015675;5013.1227035268685;5013.170192879609;4159.708820382574;4244.702870799015;4266.57334266574;4358.978205108986;4364.148990946766;5272.209168624699;5272.736363181864;5276.88924430231;5277.393099793496;5300.708461034642;3477.426224672926;3508.298340331944;3582.1223800168864;3585.6414358563934;3594.7843129412317;5459.344878614552;5517.5585953124055;5523.585731070146;5526.99999999996;5529.585281103933;3178.268998130443;3663.7069034477177;3664.5419322584676;3668.605929746679;3671.935138809769;3446.638102999199;3521.436570148764;3569.2510669772146;3582.390993531106;3583.8028908409765;4230.725002874803;4251.298535741568;4261.722988005769;4269.594388533084;4270.210011147948;5313.54834839043;5355.732213389315;5356.276902618175;5366.865828354323;5373.9731301343245;1178.640514643025;1228.6068458093503;1233.4202924625413;1237.127824883455;1240.3550153919912;743.8475112601903;768.9653965571563;775.4920527054788;780.8360244348637;784.1061190243128;4513.661475389294;4534.88662783433;4544.6818722689095;4554.362389265505;4583.702059366131;4899.902001959941;4921.532454416824;4927.901441971212;4935;4937.703737775745;4571.188718937829;4579.313103034571;4581.450225972904;4594.839180628729;4605.319058543608;2567.2939941516233;2608.9433779319406;2620.2663254288827;2639.0499420208707;2661.2036875109225;5078.695278283315;5115.897682046339;5120.513551212629;5137.306463627438;5165.302684137669;5043.798248070036;5052.823151189691;5054.7978080876355;5066.264217322604;5079;4897.959183673525;5007.84976450707;5053.1157047516535;5150.819721309811;5170.5346518813485;4891.72534771333;4917.508249175054;4921.4832442593015;4926.531979461945;4926.556609904982;4842.709437433765;4880.365552478155;4884.926726099183;4896.853094407174;4913.28757330178;5012.724300163526;5096.394635689746;5137.510122064559;5146.50751282124;5155.587552995677;3290.5393244946204;3300.6534313896695;3303.966960330462;3326.3846188455213;3332.233586275122;5450.781968721207;5455.699936503372;5458.372287187024;5517.889642207214;5551.8889622206525;5314.574834013347;5332.466753324637;5339.652922560022;5340.065488539412;5340.9465905342;5215.843524694265;5270.20946857959;5270.420253772006;5302.8939421212135;5346.919796202982;5115.4373018967135;5236.554892834151;5251.343582052244;5252.65857719247;5279.868003299948;5183.082259563986;5334.599905007062;5340.958513090066;5342.519173274424;5352.170413585901;5173.844784656466;5249.763760630857;5322.920156197738;5347.224652425299;5356.464353564613
03:51:40 INFO - PROCESS | 3952 | |5;yui.html;615.8675884684792;616.6238594457382;630.5271046714964;676.3811112831759;706.0503622627564;12318.81521777173;13220;13251.271180085103;13432.865671343283;13463.124896881696;13212.14121082129;13438.596842094752;13589.38847751851;13740.519081832137;13833.239171845544;537.9784808607651;653.8888388973874;668.9163854518185;673.0341959288412;673.377126158304;545.1277955271573;637.8564822914853;657.6087228099274;660.7158921663682;663.4261363920223;694.503430047516;694.8436601764588;695.7390978383106;697.4455308030128;697.8569393274366;552.7208759576388;694.0248950224917;694.4964900447186;694.6387878303279;696.4219697650938;416.4253330404023;486.3992968683673;505.0479845491239;509.35820865709104;512.0322590304328;12823.487060517567;12830.55093071738;12840.1011929165;12865.807012894815;12892.41984110708;11547.653570392902;11665.941670291595;12294.754104917942;12418.00655947522;12422.81365779514;10609.9469502652;11388.430578471043;11889.346085965268;12344.70380610041;12378.133530652858;3877.8254978526033;4126.752394856288;4139.461869956916;4171.687123465722;4179.26862799009;78576.21423785803;78752.60623696902;80869.19130808675;82321.58839205766;82837;9641.614335426504;9706.126448619658;9706.70879873605;9777.266704997011;9866;16535.917320413322;16568.585785355222;17164.656706865793;17190.14049297555;17220.65558688819;6803.693833777492;6881.552699074544;6901.861962760717;7025.473089518307;7094.042304288855;3583.94624080639;3608.278344331144;3613.0064232335794;3676.00747798093;3685.1339935115225;2666.5066962611977;2827.5193217153133;2838.4181242845166;2845.8292502449917;2851.244420228641;486.248745687913;491.5035813828059;495.5218214423099;495.71991824618976;496.2110244710927;539.5953035223582;564.7430419159273;569.6155095310648;587.6356658871441;633.2369494758805;8499.915000849913;8792.252658523968;8933.19601235892;9096.226820720181;10062.597496100221;630.9274433440121;668.0747165176266;673.8551211489553;678.2911857109333;693.552658535252;4380.8685739427865;4415.867523974286;4695.131400690883;4703.95296047042;4707.340972263886;63440.36559634437;107313.4634326839;116511;118489.40755296341;118745;72396.29424087418;98551;99663;100370.4981475088;103786;83084.16915830765;86564.56717716371;91230.08769912348;91694.08305916989;92342.53828730948;8507.8723819144;8694.478331300143;8789.472631642122;8791.868121978303;8992.100789920956;52120.478795211566;52152.739236303576;52752.736236318575;52762.472375275756;54091;47207.527924720314;69550.43596512367;76642.23357766352;76825.61587192028;79014.604926975;10703.018928200698;11068.944655276671;11120.388378639253;11155.721106972065;11400.543978241109;13011.804822927854;13281.335933203494;13524.526641567498;13537;13617.319134043457;12237.265764054186;12354.505819767108;12443.751124977269;12814.871851281368;12893.548725794552;12524.37378131108;12570.87429125697;12702.36488175569;12867.099303048697;12898.742025159256;12065.457054432396;12264.95747861406;12376.81434778461;12483.93758031204;12518.311492867615;10455.790884182426;10777.407242601732;10802.459877005962;11119.610813621808;11250.324980501196;10131.189504839742;10374.740631484274;10477.37135771856;10509.421981791074;10580.788384232426
03:51:40 INFO - PROCESS | 3952 | __end_tp_report
03:51:40 INFO - PROCESS | 3952 | __start_cc_report
03:51:40 INFO - PROCESS | 3952 | _x_x_mozilla_cycle_collect,1131
03:51:40 INFO - PROCESS | 3952 | __end_cc_report
03:51:40 INFO - PROCESS | 3952 | __startTimestamp1477047100455__endTimestamp
03:51:40 INFO - PROCESS | 3952 |
03:51:40 INFO - PROCESS | 3952 | ------- Summary: start -------
03:51:40 INFO - PROCESS | 3952 | Number of tests: 6
03:51:40 INFO - PROCESS | 3952 |
03:51:40 INFO - PROCESS | 3952 | [#0] dojo.html Cycles:165 Average:4408.51 Median:4602.24 stddev:548.99 (11.9%) stddev-sans-first:531.47
03:51:40 INFO - PROCESS | 3952 | Values: 2574.1 2659.6 2687.3 2691.9 2693.1 4549.9 4564.8 4585.3 4640.0 4662.0 4505.5 4536.3 4587.7 4661.2 4672.4 3882.7 3974.0 4055.6 4059.3 4068.6 4031.3 4058.4 4065.4 4068.0 4075.2 3849.9 4018.9 4054.7 4103.1 4105.7 3887.9 3921.3 3971.6 3982.2 4000.6 3777.8 3984.2 4075.4 4107.1 4107.1 4410.7 4480.1 4497.0 4517.5 4567.0 4700.3 4765.9 4844.3 4860.1 4863.4 4653.3 4785.5 4801.8 4887.2 4891.5 4395.7 4408.8 4469.3 4474.9 4543.7 4676.2 4821.5 4852.9 4957.7 4976.3 4681.2 4683.7 4782.0 4784.5 4791.0 4491.4 4503.0 4697.4 4737.7 4745.6 4743.0 4806.3 4830.4 4840.9 4852.3 4633.1 4763.1 4768.5 4854.7 4857.1 3216.8 3250.6 3255.0 3295.2 3298.2 2946.9 2949.5 3016.5 3036.9 3042.6 4480.9 4484.3 4524.7 4528.5 4568.2 4699.3 4757.6 4817.3 4908.2 4908.6 4414.0 4446.2 4454.7 4458.5 4464.6 3790.5 3956.3 4006.0 4021.2 4029.6 4830.1 4875.6 4945.9 4953.4 4954.9 4622.5 4654.5 4657.3 4754.4 4766.3 4826.2 4885.0 4939.9 4948.8 5002.6 4606.0 4673.5 4698.6 4699.4 4744.9 4602.2 4689.5 4818.2 4932.9 4938.5 4668.3 4672.7 4772.5 4773.5 4775.0 4413.8 4459.4 4584.1 4600.0 4657.3 4343.3 4459.4 4583.9 4611.1 4652.4 4674.7 4765.6 4772.9 4773.3 4792.5 4455.6 4544.7 4613.4 4713.2 4717.2
03:51:40 INFO - PROCESS | 3952 |
03:51:40 INFO - PROCESS | 3952 | [#1] ext.html Cycles:180 Average:57450.17 Median:19774.69 stddev:118853.47 (601.0%) stddev-sans-first:119110.09
03:51:40 INFO - PROCESS | 3952 | Values: 544.8 563.6 575.2 590.4 604.2 29710.1 40106.0 40987.0 41194.6 42746.7 41481.4 41512.2 41643.4 41887.6 41971.6 7864.6 8006.4 8017.3 8141.4 8150.1 6935.6 7125.1 10210.8 10492.2 10522.7 10303.4 10319.1 10386.5 10387.3 10424.1 10183.8 10314.9 10326.2 10390.3 10402.9 6909.1 6954.6 9673.7 10054.1 10062.5 37819.4 37970.9 38000.8 38001.8 38003.0 2952.3 2996.8 3079.5 3093.8 3270.2 28972.7 30579.4 30643.9 30915.4 31428.8 3451.4 3481.2 3484.9 3505.1 3558.4 332864.0 335167.0 335430.8 335440.0 337114.0 53307.5 53413.5 53497.5 53693.2 53777.7 28462.1 28945.8 30185.2 53480.5 55379.4 12554.2 12759.9 12917.6 12962.9 12975.1 7780.4 8146.4 9387.4 9629.1 9727.3 17218.9 17368.9 17677.8 17986.1 17996.2 6743.1 6743.8 6749.1 6786.7 6821.4 783.4 814.3 881.2 907.7 944.4 26595.0 27349.3 28180.9 28274.0 29260.0 922.3 923.8 926.2 947.0 1037.9 14277.0 14537.6 14691.3 14764.9 14784.1 511099.0 518445.0 519082.0 521888.0 522291.0 50452.0 50836.0 50866.7 50877.7 51073.7 1029.0 1059.9 1062.2 1142.5 1157.5 27524.4 27555.7 29105.0 29474.4 30387.4 48284.0 48901.3 48944.8 48993.5 49009.8 461131.0 463643.5 464202.0 468504.0 472071.0 20502.6 20986.1 21467.1 23126.8 23460.0 42099.0 43221.4 43292.6 45279.0 45315.5 35528.7 35991.0 36505.5 37385.3 37892.0 36219.5 36666.3 37257.3 37735.0 37908.0 34402.0 34434.5 34507.3 35100.5 35125.6 32151.4 33391.3 33715.0 34048.1 34246.0 24044.6 24892.9 25259.0 25331.6 25668.4
03:51:40 INFO - PROCESS | 3952 |
03:51:40 INFO - PROCESS | 3952 | [#2] jquery.html Cycles:180 Average:24796.62 Median:218509.00 stddev:64377.46 (29.5%) stddev-sans-first:64531.74
03:51:40 INFO - PROCESS | 3952 | Values: 278.6 302.6 304.7 306.6 308.0 12946.9 17004.7 17146.5 17572.8 17621.6 16918.5 16931.8 17178.5 17208.1 17275.7 4997.3 5104.5 5162.5 5166.2 5168.2 5011.8 5175.1 5201.1 5205.0 5240.4 5001.9 5116.4 5179.7 5184.7 5185.0 4783.3 4975.0 5071.4 5102.7 5127.1 4434.1 4439.3 4441.0 4444.9 4450.1 20679.7 21303.0 21304.1 21801.6 22028.2 1852.6 1863.4 1883.6 2379.0 2551.9 13412.6 13756.9 13758.9 14028.5 14226.8 1680.9 1714.0 1759.4 1788.9 1807.8 82714.2 83079.0 83814.0 84098.6 84236.7 8588.4 8714.5 8745.7 8858.3 8888.9 9870.0 10266.4 10360.2 11376.4 11470.4 6529.2 6608.1 6662.2 6677.2 6686.7 6465.1 6571.4 6657.9 6666.9 6683.1 5554.5 5623.4 5672.0 5673.7 5686.5 1468.3 1482.0 1495.8 1539.2 1549.9 843.2 856.4 876.2 885.9 888.5 17537.6 17955.6 18385.3 18398.9 18737.9 851.0 854.3 897.2 904.3 929.8 9863.7 10298.5 10478.9 10497.5 10518.2 381312.0 382229.0 382953.0 393510.0 397231.0 78052.6 78667.9 79540.6 81675.0 82113.2 903.8 910.5 920.7 924.8 925.7 15964.8 16177.6 16259.1 16516.7 16759.2 30766.7 37543.4 38079.4 38954.6 39787.0 49075.0 49504.0 50043.0 50243.7 50279.5 6716.5 6822.0 6902.8 7023.6 7023.7 12539.6 12880.9 12920.8 13048.0 13113.1 10022.3 10859.4 10885.6 10932.5 11050.6 10606.9 10620.8 10702.9 10720.9 10778.2 10748.2 10970.7 11011.7 11130.4 11153.0 6299.9 6590.8 6657.0 7072.0 7160.9 9489.3 10726.0 10802.5 10836.3 10997.8
03:51:40 INFO - PROCESS | 3952 |
03:51:40 INFO - PROCESS | 3952 | [#3] mootools.html Cycles:180 Average:4238.57 Median:5190.27 stddev:2275.37 (43.8%) stddev-sans-first:2270.39
03:51:40 INFO - PROCESS | 3952 | Values: 1213.3 1230.7 1268.8 1538.9 1542.1 6152.1 6297.2 6673.4 6696.8 6698.5 5804.3 5811.2 5964.0 6482.4 6489.2 1853.9 1878.9 1889.9 1890.3 1891.8 1872.7 1876.5 1886.2 1905.2 1907.1 1654.0 1689.3 1704.8 1706.0 1708.2 1814.3 1871.1 1871.7 1872.3 1873.6 1857.4 1867.2 1876.6 1878.7 1883.1 6378.3 6492.1 6493.4 6495.0 6495.8 3907.3 3966.0 4001.8 4012.1 4019.2 2618.4 2685.9 2699.6 2720.9 2724.4 858.6 880.3 892.9 894.8 898.5 8143.5 8211.8 8212.4 8220.8 8237.0 3301.8 3401.9 3477.3 3527.8 3563.7 6938.1 6959.8 7051.8 7325.8 7367.9 3852.1 3925.3 3975.2 4009.9 4013.8 3559.2 3642.3 3703.3 3709.4 3716.7 2821.6 2913.2 2974.9 2979.5 2987.4 936.9 960.0 960.5 964.3 965.8 748.2 752.9 760.6 773.5 798.1 4212.6 4265.9 4360.5 4372.1 4436.2 788.8 795.7 816.6 827.5 833.9 2984.9 3037.1 3061.2 3080.2 3081.9 7515.8 7681.1 7688.4 7760.0 7796.3 7511.8 7621.0 7765.7 7766.2 7768.5 5934.5 6191.3 6289.8 6290.2 6292.3 3991.7 4253.2 4253.9 4265.8 4339.7 7235.2 7265.9 7284.0 7332.0 7365.4 7026.5 7460.4 7521.1 7526.1 7540.4 4890.8 4995.4 5200.5 5402.1 5451.0 5873.2 5894.8 6076.8 6300.9 6335.3 5816.5 5891.1 5935.6 5938.8 5962.6 5509.9 5741.4 5800.0 5819.7 5878.7 4890.9 5071.7 5167.8 5180.0 5202.7 3553.3 3706.8 3738.2 3756.9 3831.2 3823.5 3853.6 3854.4 3867.5 3915.7
03:51:40 INFO - PROCESS | 3952 |
03:51:40 INFO - PROCESS | 3952 | [#4] prototype.html Cycles:180 Average:4244.30 Median:4921.51 stddev:1309.57 (26.6%) stddev-sans-first:1278.36
03:51:40 INFO - PROCESS | 3952 | Values: 244.2 252.5 254.9 256.0 256.8 4943.8 5081.3 5116.3 5160.9 5172.8 4874.1 4934.6 4949.9 5009.7 5037.8 3690.5 3760.4 3763.8 3764.7 3769.2 3688.9 3761.7 3765.9 3777.9 3779.9 3564.3 3651.2 3661.8 3716.4 3722.1 3581.2 3731.8 3731.8 3732.3 3744.7 3219.2 3223.3 3226.2 3230.5 3237.3 4933.2 4961.6 5009.8 5013.1 5013.2 4159.7 4244.7 4266.6 4359.0 4364.1 5272.2 5272.7 5276.9 5277.4 5300.7 3477.4 3508.3 3582.1 3585.6 3594.8 5459.3 5517.6 5523.6 5527.0 5529.6 3178.3 3663.7 3664.5 3668.6 3671.9 3446.6 3521.4 3569.3 3582.4 3583.8 4230.7 4251.3 4261.7 4269.6 4270.2 5313.5 5355.7 5356.3 5366.9 5374.0 1178.6 1228.6 1233.4 1237.1 1240.4 743.8 769.0 775.5 780.8 784.1 4513.7 4534.9 4544.7 4554.4 4583.7 4899.9 4921.5 4927.9 4935.0 4937.7 4571.2 4579.3 4581.5 4594.8 4605.3 2567.3 2608.9 2620.3 2639.0 2661.2 5078.7 5115.9 5120.5 5137.3 5165.3 5043.8 5052.8 5054.8 5066.3 5079.0 4898.0 5007.8 5053.1 5150.8 5170.5 4891.7 4917.5 4921.5 4926.5 4926.6 4842.7 4880.4 4884.9 4896.9 4913.3 5012.7 5096.4 5137.5 5146.5 5155.6 3290.5 3300.7 3304.0 3326.4 3332.2 5450.8 5455.7 5458.4 5517.9 5551.9 5314.6 5332.5 5339.7 5340.1 5340.9 5215.8 5270.2 5270.4 5302.9 5346.9 5115.4 5236.6 5251.3 5252.7 5279.9 5183.1 5334.6 5341.0 5342.5 5352.2 5173.8 5249.8 5322.9 5347.2 5356.5
03:51:40 INFO - PROCESS | 3952 |
03:51:40 INFO - PROCESS | 3952 | [#5] yui.html Cycles:180 Average:19748.27 Median:4699.54 stddev:29550.01 (628.8%) stddev-sans-first:29597.99
03:51:40 INFO - PROCESS | 3952 | Values: 615.9 616.6 630.5 676.4 706.1 12318.8 13220.0 13251.3 13432.9 13463.1 13212.1 13438.6 13589.4 13740.5 13833.2 538.0 653.9 668.9 673.0 673.4 545.1 637.9 657.6 660.7 663.4 694.5 694.8 695.7 697.4 697.9 552.7 694.0 694.5 694.6 696.4 416.4 486.4 505.0 509.4 512.0 12823.5 12830.6 12840.1 12865.8 12892.4 11547.7 11665.9 12294.8 12418.0 12422.8 10609.9 11388.4 11889.3 12344.7 12378.1 3877.8 4126.8 4139.5 4171.7 4179.3 78576.2 78752.6 80869.2 82321.6 82837.0 9641.6 9706.1 9706.7 9777.3 9866.0 16535.9 16568.6 17164.7 17190.1 17220.7 6803.7 6881.6 6901.9 7025.5 7094.0 3583.9 3608.3 3613.0 3676.0 3685.1 2666.5 2827.5 2838.4 2845.8 2851.2 486.2 491.5 495.5 495.7 496.2 539.6 564.7 569.6 587.6 633.2 8499.9 8792.3 8933.2 9096.2 10062.6 630.9 668.1 673.9 678.3 693.6 4380.9 4415.9 4695.1 4704.0 4707.3 63440.4 107313.5 116511.0 118489.4 118745.0 72396.3 98551.0 99663.0 100370.5 103786.0 83084.2 86564.6 91230.1 91694.1 92342.5 8507.9 8694.5 8789.5 8791.9 8992.1 52120.5 52152.7 52752.7 52762.5 54091.0 47207.5 69550.4 76642.2 76825.6 79014.6 10703.0 11068.9 11120.4 11155.7 11400.5 13011.8 13281.3 13524.5 13537.0 13617.3 12237.3 12354.5 12443.8 12814.9 12893.5 12524.4 12570.9 12702.4 12867.1 12898.7 12065.5 12265.0 12376.8 12483.9 12518.3 10455.8 10777.4 10802.5 11119.6 11250.3 10131.2 10374.7 10477.4 10509.4 10580.8
03:51:40 INFO - PROCESS | 3952 | -------- Summary: end --------
03:51:40 INFO - PROCESS | 3952 |
03:51:41 INFO - TEST-INFO | 3952: exit 0
03:51:41 INFO - TEST-OK | dromaeo_css | took 1119491ms
03:51:41 INFO - TEST-START | kraken
03:51:41 INFO - operating with platform_type : w7_
03:51:41 INFO - Initialising browser for kraken test...
03:51:41 INFO - TEST-INFO | started process 2340 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpdryjiu\profile http://localhost:49675/getInfo.html)
03:51:46 INFO - PROCESS | 2340 | __metrics Screen width/height:1600/1200
03:51:46 INFO - PROCESS | 2340 | colorDepth:24
03:51:46 INFO - PROCESS | 2340 | Browser inner width/height: 1010/674
03:51:46 INFO - PROCESS | 2340 | __metrics
03:51:46 INFO - PROCESS | 2340 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows"
03:51:46 INFO - PROCESS | 2340 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver]
03:51:47 INFO - PROCESS | 2340 | console.error:
03:51:47 INFO - PROCESS | 2340 | Message: Error: Connection closed before committing the transaction.
03:51:47 INFO - PROCESS | 2340 | Stack:
03:51:47 INFO - PROCESS | 2340 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23
03:51:47 INFO - PROCESS | 2340 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7
03:51:47 INFO - PROCESS | 2340 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11
03:51:47 INFO - PROCESS | 2340 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7
03:51:47 INFO - PROCESS | 2340 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7
03:51:47 INFO - PROCESS | 2340 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15
03:51:47 INFO - PROCESS | 2340 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9
03:51:47 INFO - PROCESS | 2340 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15
03:51:47 INFO - PROCESS | 2340 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5
03:51:47 INFO - PROCESS | 2340 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29
03:51:47 INFO - PROCESS | 2340 | Barrier/this.client.addBlocker/promise13.2%
03:52:44 INFO - TinderboxPrint: I/O read bytes / time
84,435,968 / 1,015,220
03:52:44 INFO - TinderboxPrint: I/O write bytes / time
482,034,688 / 4,135,790
03:52:44 INFO - TinderboxPrint: CPU idle
8,245.6 (86.8%)
03:52:44 INFO - TinderboxPrint: CPU user
1,215.0 (12.8%)
03:52:44 INFO - install - Wall time: 2s; CPU: 10.0%; Read bytes: 0; Write bytes: 52579328; Read time: 0; Write time: 41890
03:52:44 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 84435968; Write bytes: 410354176; Read time: 1015220; Write time: 3825690
03:52:44 INFO - Running post-run listener: _upload_blobber_files
03:52:44 INFO - Blob upload gear active.
03:52:44 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir.
03:52:44 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
03:52:44 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', 'Fx-Team', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json']
03:52:44 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 Fx-Team -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json
03:52:45 INFO - (blobuploader) - INFO - Open directory for files ...
03:52:45 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ...
03:52:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:52:45 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:52:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded
03:52:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:52:46 INFO - (blobuploader) - INFO - Done attempting.
03:52:46 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ...
03:52:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:52:46 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:52:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded
03:52:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:52:48 INFO - (blobuploader) - INFO - Done attempting.
03:52:48 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ...
03:52:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:52:48 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:52:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
03:52:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:52:56 INFO - (blobuploader) - INFO - Done attempting.
03:52:56 INFO - (blobuploader) - INFO - Iteration through files over.
03:52:56 INFO - Return code: 0
03:52:56 INFO - rmtree: C:\slave\test\build\uploaded_files.json
03:52:56 INFO - Using _rmtree_windows ...
03:52:56 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1
03:52:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82818759472daf8434b822ba78e4be320a19109aa7a4fd12de065d721e9f0ca81ea8bcfe812974dd25dbc53289c6ad50db2fab77f0133f23cf16b56660b382e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8cd72dd252afefffbab20ebcf065e1382d60b13c134580dd17a8e5339c79e0cbff5f5a1643ba2ff3828391ca5a8d7ef6a283463798e55533e6bebe6fcc6c7394", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3be17f6a0d2e2254f7e4749effc650eb09d90429fbda851fea0839b7529f78a04f62a21ddf694c50142986aafa50a4ae5696d754a93e193bcd3d1badba67de4a"}
03:52:56 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files
03:52:56 INFO - Writing to file C:\slave\test\properties\blobber_files
03:52:56 INFO - Contents:
03:52:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82818759472daf8434b822ba78e4be320a19109aa7a4fd12de065d721e9f0ca81ea8bcfe812974dd25dbc53289c6ad50db2fab77f0133f23cf16b56660b382e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8cd72dd252afefffbab20ebcf065e1382d60b13c134580dd17a8e5339c79e0cbff5f5a1643ba2ff3828391ca5a8d7ef6a283463798e55533e6bebe6fcc6c7394", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3be17f6a0d2e2254f7e4749effc650eb09d90429fbda851fea0839b7529f78a04f62a21ddf694c50142986aafa50a4ae5696d754a93e193bcd3d1badba67de4a"}
03:52:56 INFO - Running post-run listener: copy_logs_to_upload_dir
03:52:56 INFO - Copying logs to upload dir...
03:52:56 INFO - mkdir: C:\slave\test\build\upload\logs
03:52:56 INFO - Copying logs to upload dir...
03:52:56 INFO - Using _rmtree_windows ...
03:52:56 INFO - Using _rmtree_windows ...
03:52:56 INFO - Using _rmtree_windows ...
03:52:56 INFO - Using _rmtree_windows ...
03:52:56 INFO - Using _rmtree_windows ...
03:52:56 INFO - Using _rmtree_windows ...
03:52:56 INFO - Using _rmtree_windows ...
program finished with exit code 0
elapsedTime=1423.189000
========= master_lag: 0.28 =========
========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 43 secs) (at 2016-10-21 03:52:57.103528) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-10-21 03:52:57.114612) =========
'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-107
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-107
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test\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-107
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82818759472daf8434b822ba78e4be320a19109aa7a4fd12de065d721e9f0ca81ea8bcfe812974dd25dbc53289c6ad50db2fab77f0133f23cf16b56660b382e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8cd72dd252afefffbab20ebcf065e1382d60b13c134580dd17a8e5339c79e0cbff5f5a1643ba2ff3828391ca5a8d7ef6a283463798e55533e6bebe6fcc6c7394", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3be17f6a0d2e2254f7e4749effc650eb09d90429fbda851fea0839b7529f78a04f62a21ddf694c50142986aafa50a4ae5696d754a93e193bcd3d1badba67de4a"}
build_url:https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip
program finished with exit code 0
elapsedTime=0.101000
build_url: 'https://queue.taskcluster.net/v1/task/UzA7IaRMQjevE5jPcBGDqw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip'
blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82818759472daf8434b822ba78e4be320a19109aa7a4fd12de065d721e9f0ca81ea8bcfe812974dd25dbc53289c6ad50db2fab77f0133f23cf16b56660b382e5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8cd72dd252afefffbab20ebcf065e1382d60b13c134580dd17a8e5339c79e0cbff5f5a1643ba2ff3828391ca5a8d7ef6a283463798e55533e6bebe6fcc6c7394", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3be17f6a0d2e2254f7e4749effc650eb09d90429fbda851fea0839b7529f78a04f62a21ddf694c50142986aafa50a4ae5696d754a93e193bcd3d1badba67de4a"}'
========= master_lag: 0.92 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-10-21 03:52:58.137625) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 03:52:58.138007) =========
'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-107
COMSPEC=C:\windows\system32\cmd.exe
CYGWINBASE=C:\cygwin
DCLOCATION=SCL3
DNSSUFFIX=wintest.releng.scl3.mozilla.com
FP_NO_HOST_CHECK=NO
HOMEDRIVE=C:
HOMEPATH=\Users\cltbld
KTS_HOME=C:\Program Files\KTS
KTS_VERSION=1.19c
LOCALAPPDATA=C:\Users\cltbld\AppData\Local
LOGONSERVER=\\T-W732-IX-107
MONDIR=C:\Monitor_config\
MOZBUILDDIR=C:\mozilla-build\
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NO_EM_RESTART=1
NUMBER_OF_PROCESSORS=8
OS=Windows_NT
OURDRIVE=C:
PATH=C:\Python24;C:\Python24\Scripts;C:\cygwin\bin;C:\WINDOWS\System32;C:\program files\gnuwin32\bin;C:\WINDOWS;C:\mozilla-build\msys\bin;C:\mozilla-build\python27;C:\mozilla-build\python27\Scripts;C:\mozilla-build\info-zip;C:\mozilla-build\wget;C:\Program Files (x86)\GnuWin32\bin;
PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC
PROCESSOR_ARCHITECTURE=x86
PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel
PROCESSOR_LEVEL=6
PROCESSOR_REVISION=1e05
PROGRAMDATA=C:\ProgramData
PROGRAMFILES=C:\Program Files
PROMPT=$P$G
PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\
PUBLIC=C:\Users\Public
PWD=C:\slave\test
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-107
USERNAME=cltbld
USERPROFILE=C:\Users\cltbld
WINDIR=C:\windows
WINDOWS_TRACING_FLAGS=3
WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log
XPCOM_DEBUG_BREAK=warn
using PTY: False
program finished with exit code 0
elapsedTime=0.101000
========= master_lag: 0.02 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-21 03:52:58.260624) =========
========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-21 03:52:58.260966) =========
========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-21 03:52:59.249558) =========
========= Total master_lag: 9.33 =========