builder: fx-team_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-099 starttime: 1476885651.41 results: success (0) buildid: 20161019033001 builduid: ffb753fb78844eddabf486158819cd06 revision: e4282badddda00fb555bd2e5300c92149434a884 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 07:00:51.410187) ========= master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 07:00:51.410665) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 07:00:51.410982) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 07:00:51.737548) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 07:00:51.738418) ========= '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-099 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-099 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-099 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 --07:00:52-- 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% 14.36 MB/s 07:00:52 (14.36 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.040000 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 07:00:52.819782) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-10-19 07:00:52.820206) ========= '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-099 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-099 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-099 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=7.201000 ========= master_lag: 3.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 10 secs) (at 2016-10-19 07:01:03.087127) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 07:01:03.087642) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev e4282badddda00fb555bd2e5300c92149434a884 --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 e4282badddda00fb555bd2e5300c92149434a884 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-099 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-099 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-099 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-19 07:01:03,348 truncating revision to first 12 chars 2016-10-19 07:01:03,348 Setting DEBUG logging. 2016-10-19 07:01:03,348 attempt 1/10 2016-10-19 07:01:03,348 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/e4282badddda?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-19 07:01:03,941 unpacking tar archive at: fx-team-e4282badddda/testing/mozharness/ 2016-10-19 07:01:04,058 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.197000 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 07:01:04.363410) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 07:01:04.363756) ========= script_repo_revision: e4282badddda00fb555bd2e5300c92149434a884 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 07:01:04.364191) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 07:01:04.364460) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 07:01:04.426173) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 45 secs) (at 2016-10-19 07:01:04.426691) ========= '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-099 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-099 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-099 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 07:01:04 INFO - MultiFileLogger online at 20161019 07:01:04 in C:\slave\test 07:01:04 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 07:01:04 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 07:01:05 INFO - {'append_to_log': False, 07:01:05 INFO - 'base_work_dir': 'C:\\slave\\test', 07:01:05 INFO - 'blob_upload_branch': 'Fx-Team', 07:01:05 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 07:01:05 INFO - 'branch': 'Fx-Team', 07:01:05 INFO - 'buildbot_json_path': 'buildprops.json', 07:01:05 INFO - 'config_files': ('talos/windows_config.py',), 07:01:05 INFO - 'default_actions': ('clobber', 07:01:05 INFO - 'read-buildbot-config', 07:01:05 INFO - 'download-and-extract', 07:01:05 INFO - 'populate-webroot', 07:01:05 INFO - 'create-virtualenv', 07:01:05 INFO - 'install', 07:01:05 INFO - 'run-tests'), 07:01:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:01:05 INFO - 'download_minidump_stackwalk': True, 07:01:05 INFO - 'download_symbols': 'ondemand', 07:01:05 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 07:01:05 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 07:01:05 INFO - 'hg': 'c:/mozilla-build/hg/hg', 07:01:05 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 07:01:05 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 07:01:05 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 07:01:05 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 07:01:05 INFO - 'C:/mozilla-build/tooltool.py'), 07:01:05 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 07:01:05 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 07:01:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:01:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:01:05 INFO - 'installer_path': 'installer.exe', 07:01:05 INFO - 'log_level': 'info', 07:01:05 INFO - 'log_name': 'talos', 07:01:05 INFO - 'log_to_console': True, 07:01:05 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 07:01:05 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 07:01:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 07:01:05 INFO - 'opt_config_files': (), 07:01:05 INFO - 'pip_index': False, 07:01:05 INFO - 'sps_profile': False, 07:01:05 INFO - 'sps_profile_interval': 0, 07:01:05 INFO - 'suite': 'dromaeojs', 07:01:05 INFO - 'system_bits': '32', 07:01:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:01:05 INFO - 'title': 'T-W732-IX-099', 07:01:05 INFO - 'use_talos_json': True, 07:01:05 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 07:01:05 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 07:01:05 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 07:01:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:01:05 INFO - 'work_dir': 'build'} 07:01:05 INFO - [mozharness: 2016-10-19 14:01:05.010000Z] Running clobber step. 07:01:05 INFO - Running pre-action listener: _resource_record_pre_action 07:01:05 INFO - Running main action method: clobber 07:01:05 INFO - rmtree: C:\slave\test\build 07:01:05 INFO - Using _rmtree_windows ... 07:01:05 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 07:03:19 INFO - Running post-action listener: _resource_record_post_action 07:03:19 INFO - [mozharness: 2016-10-19 14:03:19.704000Z] Finished clobber step (success) 07:03:19 INFO - [mozharness: 2016-10-19 14:03:19.704000Z] Running read-buildbot-config step. 07:03:19 INFO - Running pre-action listener: _resource_record_pre_action 07:03:19 INFO - Running main action method: read_buildbot_config 07:03:19 INFO - Using buildbot properties: 07:03:19 INFO - { 07:03:19 INFO - "project": "", 07:03:19 INFO - "product": "firefox", 07:03:19 INFO - "script_repo_revision": "production", 07:03:19 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs", 07:03:19 INFO - "repository": "", 07:03:19 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs", 07:03:19 INFO - "buildid": "20161019033001", 07:03:19 INFO - "pgo_build": "True", 07:03:19 INFO - "basedir": "C:\\slave\\test", 07:03:19 INFO - "buildnumber": 45, 07:03:19 INFO - "slavename": "t-w732-ix-099", 07:03:19 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/", 07:03:19 INFO - "platform": "win7_ix", 07:03:19 INFO - "scheduler": "tests-fx-team-win32-pgo-talos", 07:03:19 INFO - "branch": "fx-team", 07:03:19 INFO - "repo_path": "integration/fx-team", 07:03:19 INFO - "revision": "e4282badddda00fb555bd2e5300c92149434a884", 07:03:19 INFO - "stage_platform": "win32-pgo", 07:03:19 INFO - "builduid": "ffb753fb78844eddabf486158819cd06", 07:03:19 INFO - "slavebuilddir": "test" 07:03:19 INFO - } 07:03:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 07:03:19 INFO - Running post-action listener: _resource_record_post_action 07:03:19 INFO - [mozharness: 2016-10-19 14:03:19.708000Z] Finished read-buildbot-config step (success) 07:03:19 INFO - [mozharness: 2016-10-19 14:03:19.708000Z] Running download-and-extract step. 07:03:19 INFO - Running pre-action listener: _resource_record_pre_action 07:03:19 INFO - Running main action method: download_and_extract 07:03:19 INFO - mkdir: C:\slave\test\build\tests 07:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:03:19 INFO - trying https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 07:03:19 INFO - Downloading https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/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 07:03:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/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 07:03:20 INFO - Downloaded 1218 bytes. 07:03:20 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 07:03:20 INFO - Using the following test package requirements: 07:03:20 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 07:03:20 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:03:20 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 07:03:20 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 07:03:20 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:03:20 INFO - u'jsshell-win32.zip'], 07:03:20 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:03:20 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 07:03:20 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 07:03:20 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:03:20 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 07:03:20 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:03:20 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 07:03:20 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:03:20 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 07:03:20 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:03:20 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 07:03:20 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 07:03:20 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 07:03:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 07:03:20 INFO - Fetch https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 07:03:21 INFO - Http code: 200 07:03:21 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 07:03:21 INFO - x-amz-cf-id: W5jsaYrgQVZ8UXI8AwxyhnbvEPjCbyMTIqaEpcXQ5CsEOXnrYpFrXA== 07:03:21 INFO - x-amz-version-id: w_gffWb23VAQsR03P8A5ifkOTNW0p2in 07:03:22 INFO - Expected file size: 17258151 07:03:22 INFO - Obtained file size: 17258151 07:03:46 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 07:03:46 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 07:03:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 07:03:46 INFO - Fetch https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 07:03:46 INFO - Http code: 200 07:03:46 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 07:03:46 INFO - x-amz-cf-id: ZBcQm7_CL-VciiVV0E48tDJHNhWO04zm1xLSUSp_zyV69JIjU3viCA== 07:03:46 INFO - x-amz-version-id: w_gffWb23VAQsR03P8A5ifkOTNW0p2in 07:03:47 INFO - Expected file size: 17258151 07:03:47 INFO - Obtained file size: 17258151 07:03:57 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 07:03:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 07:03:57 INFO - Fetch https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 07:03:57 INFO - Http code: 200 07:03:57 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 07:03:57 INFO - x-amz-cf-id: 4DItphjAestTjpjUAUJiAuWZWh-YGahoQe8kgMweX2CU0zgcnAK6Hw== 07:03:57 INFO - x-amz-version-id: y88lsbSRqOZvpHHlVCW_c2JK5GIHqdG2 07:03:58 INFO - Expected file size: 14409028 07:03:58 INFO - Obtained file size: 14409028 07:03:59 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')]} 07:03:59 INFO - trying https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 07:03:59 INFO - Downloading https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 07:03:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/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 07:04:02 INFO - Downloaded 54361730 bytes. 07:04:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 07:04:02 INFO - mkdir: C:\slave\test\properties 07:04:02 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 07:04:02 INFO - Writing to file C:\slave\test\properties\build_url 07:04:02 INFO - Contents: 07:04:02 INFO - build_url:https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 07:04:03 INFO - Running post-action listener: _resource_record_post_action 07:04:03 INFO - Running post-action listener: set_extra_try_arguments 07:04:03 INFO - [mozharness: 2016-10-19 14:04:03.240000Z] Finished download-and-extract step (success) 07:04:03 INFO - [mozharness: 2016-10-19 14:04:03.241000Z] Running populate-webroot step. 07:04:03 INFO - Running pre-action listener: _resource_record_pre_action 07:04:03 INFO - Running main action method: populate_webroot 07:04:03 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 07:04:03 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 07:04:03 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 07:04:03 INFO - u'--tppagecycles', 07:04:03 INFO - u'7'], 07:04:03 INFO - u'tests': [u'tsvgm']}}, 07:04:03 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 07:04:03 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 07:04:03 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 07:04:03 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 07:04:03 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 07:04:03 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 07:04:03 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:04:03 INFO - u'talos_options': [u'--disable-e10s'], 07:04:03 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 07:04:03 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:04:03 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 07:04:03 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:04:03 INFO - u'talos_options': [u'--disable-e10s'], 07:04:03 INFO - u'tests': [u'damp', u'tps']}, 07:04:03 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:04:03 INFO - u'tests': [u'damp', u'tps']}, 07:04:03 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 07:04:03 INFO - u'tests': [u'dromaeo_dom']}, 07:04:03 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 07:04:03 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 07:04:03 INFO - u'tests': [u'basic_compositor_video']}, 07:04:03 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 07:04:03 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 07:04:03 INFO - u'tests': [u'a11yr', 07:04:03 INFO - u'ts_paint', 07:04:03 INFO - u'tpaint', 07:04:03 INFO - u'sessionrestore', 07:04:03 INFO - u'sessionrestore_no_auto_restore', 07:04:03 INFO - u'tabpaint']}, 07:04:03 INFO - u'other-e10s': {u'tests': [u'a11yr', 07:04:03 INFO - u'ts_paint', 07:04:03 INFO - u'tpaint', 07:04:03 INFO - u'sessionrestore', 07:04:03 INFO - u'sessionrestore_no_auto_restore', 07:04:03 INFO - u'tabpaint']}, 07:04:03 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 07:04:03 INFO - u'ts_paint', 07:04:03 INFO - u'tpaint', 07:04:03 INFO - u'sessionrestore', 07:04:03 INFO - u'sessionrestore_no_auto_restore', 07:04:03 INFO - u'tabpaint']}, 07:04:03 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 07:04:03 INFO - u'ts_paint', 07:04:03 INFO - u'tpaint', 07:04:03 INFO - u'sessionrestore', 07:04:03 INFO - u'sessionrestore_no_auto_restore', 07:04:03 INFO - u'tabpaint']}, 07:04:03 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 07:04:03 INFO - u'tests': [u'a11yr', 07:04:03 INFO - u'ts_paint', 07:04:03 INFO - u'tpaint', 07:04:03 INFO - u'sessionrestore', 07:04:03 INFO - u'sessionrestore_no_auto_restore', 07:04:03 INFO - u'tabpaint']}, 07:04:03 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 07:04:03 INFO - u'tests': [u'a11yr', 07:04:03 INFO - u'ts_paint', 07:04:03 INFO - u'tpaint', 07:04:03 INFO - u'sessionrestore', 07:04:03 INFO - u'sessionrestore_no_auto_restore', 07:04:03 INFO - u'tabpaint']}, 07:04:03 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 07:04:03 INFO - u'tests': [u'tsvgx', 07:04:03 INFO - u'tsvgr_opacity', 07:04:03 INFO - u'tart', 07:04:03 INFO - u'tscrollx', 07:04:03 INFO - u'cart']}, 07:04:03 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 07:04:03 INFO - u'tsvgr_opacity', 07:04:03 INFO - u'tart', 07:04:03 INFO - u'tscrollx', 07:04:03 INFO - u'cart']}, 07:04:03 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:04:03 INFO - u'talos_options': [u'--disable-e10s'], 07:04:03 INFO - u'tests': [u'tp5o']}, 07:04:03 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:04:03 INFO - u'tests': [u'tp5o']}, 07:04:03 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:04:03 INFO - u'talos_options': [u'--disable-e10s', 07:04:03 INFO - u'--xperf_path', 07:04:03 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:04:03 INFO - u'tests': [u'tp5n']}, 07:04:03 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:04:03 INFO - u'talos_options': [u'--xperf_path', 07:04:03 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:04:03 INFO - u'tests': [u'tp5n']}}, 07:04:03 INFO - u'talos.zip': {u'path': u'', 07:04:03 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:04:03 INFO - Running post-action listener: _resource_record_post_action 07:04:03 INFO - [mozharness: 2016-10-19 14:04:03.325000Z] Finished populate-webroot step (success) 07:04:03 INFO - [mozharness: 2016-10-19 14:04:03.325000Z] Running create-virtualenv step. 07:04:03 INFO - Running pre-action listener: _resource_record_pre_action 07:04:03 INFO - Running main action method: create_virtualenv 07:04:03 INFO - Creating virtualenv C:\slave\test\build/venv 07:04:03 INFO - mkdir: C:\slave\test\build\venv\Scripts 07:04:03 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 07:04:03 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'! 07:04:03 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 07:04:03 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 07:04:05 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 07:04:11 INFO - Installing distribute......................................................................................................................................................................................done. 07:04:12 INFO - Return code: 0 07:04:12 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 07:04:12 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 07:04:13 INFO - Reading from file tmpfile_stdout 07:04:13 INFO - Output received: 07:04:13 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 07:04:13 INFO - Using _rmtree_windows ... 07:04:13 INFO - Using _rmtree_windows ... 07:04:13 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 07:04:13 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')]} 07:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:13 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')]} 07:04:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:13 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 0x01822640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E3E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01999520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-099', 'USERDOMAIN': 'T-W732-IX-099', '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-099', '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 07:04:13 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 07:04:13 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:04:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:04:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:04:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:04:13 INFO - 'COMPUTERNAME': 'T-W732-IX-099', 07:04:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:04:13 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:04:13 INFO - 'DCLOCATION': 'SCL3', 07:04:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:04:13 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:04:13 INFO - 'HOMEDRIVE': 'C:', 07:04:13 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:04:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:04:13 INFO - 'KTS_VERSION': '1.19c', 07:04:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:04:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-099', 07:04:13 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:04:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:04:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:13 INFO - 'MOZ_NO_REMOTE': '1', 07:04:13 INFO - 'NO_EM_RESTART': '1', 07:04:13 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:04:13 INFO - 'OS': 'Windows_NT', 07:04:13 INFO - 'OURDRIVE': 'C:', 07:04:13 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;', 07:04:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:04:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:04:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:04:13 INFO - 'PROCESSOR_LEVEL': '6', 07:04:13 INFO - 'PROCESSOR_REVISION': '1e05', 07:04:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:04:13 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:04:13 INFO - 'PROMPT': '$P$G', 07:04:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:04:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:04:13 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:04:13 INFO - 'PWD': 'C:\\slave\\test', 07:04:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:04:13 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:04:13 INFO - 'SYSTEMDRIVE': 'C:', 07:04:13 INFO - 'SYSTEMROOT': 'C:\\windows', 07:04:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:13 INFO - 'TEST1': 'testie', 07:04:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:13 INFO - 'USERDOMAIN': 'T-W732-IX-099', 07:04:13 INFO - 'USERNAME': 'cltbld', 07:04:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:04:13 INFO - 'WINDIR': 'C:\\windows', 07:04:13 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:04:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:04:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:04:14 INFO - Ignoring indexes: http://pypi.python.org/simple/ 07:04:14 INFO - Downloading/unpacking pip>=1.5 07:04:18 INFO - Running setup.py egg_info for package pip 07:04:18 INFO - warning: no files found matching 'pip\cacert.pem' 07:04:18 INFO - warning: no files found matching '*.html' under directory 'docs' 07:04:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 07:04:18 INFO - no previously-included directories found matching 'docs\_build\_sources' 07:04:18 INFO - Installing collected packages: pip 07:04:18 INFO - Found existing installation: pip 0.8.2 07:04:18 INFO - Uninstalling pip: 07:04:18 INFO - Successfully uninstalled pip 07:04:18 INFO - Running setup.py install for pip 07:04:18 INFO - warning: no files found matching 'pip\cacert.pem' 07:04:18 INFO - warning: no files found matching '*.html' under directory 'docs' 07:04:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 07:04:18 INFO - no previously-included directories found matching 'docs\_build\_sources' 07:04:18 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 07:04:18 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 07:04:18 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 07:04:18 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 07:04:18 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 07:04:18 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 07:04:18 INFO - Successfully installed pip 07:04:18 INFO - Cleaning up... 07:04:18 INFO - Return code: 0 07:04:18 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 07:04: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')]} 07:04:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04: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')]} 07:04:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04: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', '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 0x01822640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E3E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01999520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-099', 'USERDOMAIN': 'T-W732-IX-099', '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-099', '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 07:04: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', 'psutil>=3.1.1'] in C:\slave\test\build 07:04: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 psutil>=3.1.1 07:04:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:04:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:04:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:04:18 INFO - 'COMPUTERNAME': 'T-W732-IX-099', 07:04:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:04:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:04:18 INFO - 'DCLOCATION': 'SCL3', 07:04:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:04:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:04:18 INFO - 'HOMEDRIVE': 'C:', 07:04:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:04:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:04:18 INFO - 'KTS_VERSION': '1.19c', 07:04:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:04:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-099', 07:04:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:04:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:04:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:18 INFO - 'MOZ_NO_REMOTE': '1', 07:04:18 INFO - 'NO_EM_RESTART': '1', 07:04:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:04:18 INFO - 'OS': 'Windows_NT', 07:04:18 INFO - 'OURDRIVE': 'C:', 07:04: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;', 07:04:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:04:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:04:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:04:18 INFO - 'PROCESSOR_LEVEL': '6', 07:04:18 INFO - 'PROCESSOR_REVISION': '1e05', 07:04:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:04:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:04:18 INFO - 'PROMPT': '$P$G', 07:04:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:04:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:04:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:04:18 INFO - 'PWD': 'C:\\slave\\test', 07:04:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:04:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:04:18 INFO - 'SYSTEMDRIVE': 'C:', 07:04:18 INFO - 'SYSTEMROOT': 'C:\\windows', 07:04:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:18 INFO - 'TEST1': 'testie', 07:04:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:18 INFO - 'USERDOMAIN': 'T-W732-IX-099', 07:04:18 INFO - 'USERNAME': 'cltbld', 07:04:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:04:18 INFO - 'WINDIR': 'C:\\windows', 07:04:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:04:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:04:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:04:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:20 INFO - Downloading/unpacking psutil>=3.1.1 07:04:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:04:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:04:21 INFO - Installing collected packages: psutil 07:04:21 INFO - Successfully installed psutil 07:04:21 INFO - Cleaning up... 07:04:21 INFO - Return code: 0 07:04:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 07:04:21 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')]} 07:04:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:21 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')]} 07:04:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:21 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 0x01822640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E3E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01999520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-099', 'USERDOMAIN': 'T-W732-IX-099', '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-099', '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 07:04:21 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 07:04:21 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 07:04:21 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:04:21 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:04:21 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:04:21 INFO - 'COMPUTERNAME': 'T-W732-IX-099', 07:04:21 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:04:21 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:04:21 INFO - 'DCLOCATION': 'SCL3', 07:04:21 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:04:21 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:04:21 INFO - 'HOMEDRIVE': 'C:', 07:04:21 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:04:21 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:04:21 INFO - 'KTS_VERSION': '1.19c', 07:04:21 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:04:21 INFO - 'LOGONSERVER': '\\\\T-W732-IX-099', 07:04:21 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:04:21 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:04:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:21 INFO - 'MOZ_NO_REMOTE': '1', 07:04:21 INFO - 'NO_EM_RESTART': '1', 07:04:21 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:04:21 INFO - 'OS': 'Windows_NT', 07:04:21 INFO - 'OURDRIVE': 'C:', 07:04:21 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;', 07:04:21 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:04:21 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:04:21 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:04:21 INFO - 'PROCESSOR_LEVEL': '6', 07:04:21 INFO - 'PROCESSOR_REVISION': '1e05', 07:04:21 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:04:21 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:04:21 INFO - 'PROMPT': '$P$G', 07:04:21 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:04:21 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:04:21 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:04:21 INFO - 'PWD': 'C:\\slave\\test', 07:04:21 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:04:21 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:04:21 INFO - 'SYSTEMDRIVE': 'C:', 07:04:21 INFO - 'SYSTEMROOT': 'C:\\windows', 07:04:21 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:21 INFO - 'TEST1': 'testie', 07:04:21 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:21 INFO - 'USERDOMAIN': 'T-W732-IX-099', 07:04:21 INFO - 'USERNAME': 'cltbld', 07:04:21 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:04:21 INFO - 'WINDIR': 'C:\\windows', 07:04:21 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:04:21 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:04:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:04:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:04:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:04:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:04:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:04:22 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 07:04:22 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) 07:04:22 INFO - Installing collected packages: mozsystemmonitor 07:04:22 INFO - Running setup.py install for mozsystemmonitor 07:04:23 INFO - Successfully installed mozsystemmonitor 07:04:23 INFO - Cleaning up... 07:04:23 INFO - Return code: 0 07:04:23 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 07:04: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')]} 07:04:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04: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')]} 07:04:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04: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', '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 0x01822640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E3E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01999520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-099', 'USERDOMAIN': 'T-W732-IX-099', '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-099', '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 07:04: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', 'jsonschema==2.5.1'] in C:\slave\test\build 07:04: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 jsonschema==2.5.1 07:04:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:04:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:04:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:04:23 INFO - 'COMPUTERNAME': 'T-W732-IX-099', 07:04:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:04:23 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:04:23 INFO - 'DCLOCATION': 'SCL3', 07:04:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:04:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:04:23 INFO - 'HOMEDRIVE': 'C:', 07:04:23 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:04:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:04:23 INFO - 'KTS_VERSION': '1.19c', 07:04:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:04:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-099', 07:04:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:04:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:04:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:23 INFO - 'MOZ_NO_REMOTE': '1', 07:04:23 INFO - 'NO_EM_RESTART': '1', 07:04:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:04:23 INFO - 'OS': 'Windows_NT', 07:04:23 INFO - 'OURDRIVE': 'C:', 07:04: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;', 07:04:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:04:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:04:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:04:23 INFO - 'PROCESSOR_LEVEL': '6', 07:04:23 INFO - 'PROCESSOR_REVISION': '1e05', 07:04:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:04:23 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:04:23 INFO - 'PROMPT': '$P$G', 07:04:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:04:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:04:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:04:23 INFO - 'PWD': 'C:\\slave\\test', 07:04:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:04:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:04:23 INFO - 'SYSTEMDRIVE': 'C:', 07:04:23 INFO - 'SYSTEMROOT': 'C:\\windows', 07:04:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:23 INFO - 'TEST1': 'testie', 07:04:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:23 INFO - 'USERDOMAIN': 'T-W732-IX-099', 07:04:23 INFO - 'USERNAME': 'cltbld', 07:04:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:04:23 INFO - 'WINDIR': 'C:\\windows', 07:04:23 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:04:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:04:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:04:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:23 INFO - Downloading/unpacking jsonschema==2.5.1 07:04:23 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 07:04:23 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 07:04:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:04:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:04: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 07:04:24 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 07:04:24 INFO - Downloading functools32-3.2.3-2.tar.gz 07:04:24 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 07:04:24 INFO - warning: no files found matching '*.txt' 07:04:24 INFO - no previously-included directories found matching 'build' 07:04:24 INFO - no previously-included directories found matching 'dist' 07:04:24 INFO - no previously-included directories found matching '.git*' 07:04:24 INFO - Installing collected packages: jsonschema, functools32 07:04:25 INFO - Running setup.py install for functools32 07:04:25 INFO - warning: no files found matching '*.txt' 07:04:25 INFO - no previously-included directories found matching 'build' 07:04:25 INFO - no previously-included directories found matching 'dist' 07:04:25 INFO - no previously-included directories found matching '.git*' 07:04:25 INFO - Successfully installed jsonschema functools32 07:04:25 INFO - Cleaning up... 07:04:25 INFO - Return code: 0 07:04:25 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 07:04:25 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')]} 07:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:25 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')]} 07:04:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:25 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 0x01822640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E3E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01999520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-099', 'USERDOMAIN': 'T-W732-IX-099', '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-099', '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 07:04:25 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 07:04:25 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 07:04:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:04:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:04:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:04:25 INFO - 'COMPUTERNAME': 'T-W732-IX-099', 07:04:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:04:25 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:04:25 INFO - 'DCLOCATION': 'SCL3', 07:04:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:04:25 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:04:25 INFO - 'HOMEDRIVE': 'C:', 07:04:25 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:04:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:04:25 INFO - 'KTS_VERSION': '1.19c', 07:04:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:04:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-099', 07:04:25 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:04:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:04:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:25 INFO - 'MOZ_NO_REMOTE': '1', 07:04:25 INFO - 'NO_EM_RESTART': '1', 07:04:25 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:04:25 INFO - 'OS': 'Windows_NT', 07:04:25 INFO - 'OURDRIVE': 'C:', 07:04:25 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;', 07:04:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:04:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:04:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:04:25 INFO - 'PROCESSOR_LEVEL': '6', 07:04:25 INFO - 'PROCESSOR_REVISION': '1e05', 07:04:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:04:25 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:04:25 INFO - 'PROMPT': '$P$G', 07:04:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:04:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:04:25 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:04:25 INFO - 'PWD': 'C:\\slave\\test', 07:04:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:04:25 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:04:25 INFO - 'SYSTEMDRIVE': 'C:', 07:04:25 INFO - 'SYSTEMROOT': 'C:\\windows', 07:04:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:25 INFO - 'TEST1': 'testie', 07:04:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:25 INFO - 'USERDOMAIN': 'T-W732-IX-099', 07:04:25 INFO - 'USERNAME': 'cltbld', 07:04:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:04:25 INFO - 'WINDIR': 'C:\\windows', 07:04:25 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:04:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:04:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:04:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 07:04:26 INFO - Cleaning up... 07:04:26 INFO - Return code: 0 07:04:26 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 07:04: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')]} 07:04:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04: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')]} 07:04:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04: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', '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 0x01822640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E3E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01999520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-099', 'USERDOMAIN': 'T-W732-IX-099', '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-099', '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 07:04: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', 'blobuploader==1.2.4'] in C:\slave\test\build 07:04: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 blobuploader==1.2.4 07:04:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:04:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:04:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:04:26 INFO - 'COMPUTERNAME': 'T-W732-IX-099', 07:04:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:04:26 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:04:26 INFO - 'DCLOCATION': 'SCL3', 07:04:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:04:26 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:04:26 INFO - 'HOMEDRIVE': 'C:', 07:04:26 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:04:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:04:26 INFO - 'KTS_VERSION': '1.19c', 07:04:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:04:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-099', 07:04:26 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:04:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:04:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:26 INFO - 'MOZ_NO_REMOTE': '1', 07:04:26 INFO - 'NO_EM_RESTART': '1', 07:04:26 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:04:26 INFO - 'OS': 'Windows_NT', 07:04:26 INFO - 'OURDRIVE': 'C:', 07:04: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;', 07:04:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:04:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:04:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:04:26 INFO - 'PROCESSOR_LEVEL': '6', 07:04:26 INFO - 'PROCESSOR_REVISION': '1e05', 07:04:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:04:26 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:04:26 INFO - 'PROMPT': '$P$G', 07:04:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:04:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:04:26 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:04:26 INFO - 'PWD': 'C:\\slave\\test', 07:04:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:04:26 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:04:26 INFO - 'SYSTEMDRIVE': 'C:', 07:04:26 INFO - 'SYSTEMROOT': 'C:\\windows', 07:04:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:26 INFO - 'TEST1': 'testie', 07:04:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:26 INFO - 'USERDOMAIN': 'T-W732-IX-099', 07:04:26 INFO - 'USERNAME': 'cltbld', 07:04:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:04:26 INFO - 'WINDIR': 'C:\\windows', 07:04:26 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:04:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:04:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:04:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:26 INFO - Downloading/unpacking blobuploader==1.2.4 07:04: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 07:04: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 07:04:27 INFO - Downloading blobuploader-1.2.4.tar.gz 07:04:27 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 07:04:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:04:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:04:27 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 07:04:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:04: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 07:04: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 07:04:28 INFO - Downloading docopt-0.6.1.tar.gz 07:04:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 07:04:28 INFO - Installing collected packages: blobuploader, requests, docopt 07:04:28 INFO - Running setup.py install for blobuploader 07:04:28 INFO - Running setup.py install for requests 07:04:29 INFO - Running setup.py install for docopt 07:04:29 INFO - Successfully installed blobuploader requests docopt 07:04:29 INFO - Cleaning up... 07:04:31 INFO - Return code: 0 07:04:31 INFO - Installing None into virtualenv C:\slave\test\build/venv 07:04: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')]} 07:04:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04: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')]} 07:04:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:31 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 0x01822640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E3E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01999520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-099', 'USERDOMAIN': 'T-W732-IX-099', '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-099', '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 07:04:31 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 07:04:31 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 07:04:31 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:04:31 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:04:31 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:04:31 INFO - 'COMPUTERNAME': 'T-W732-IX-099', 07:04:31 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:04:31 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:04:31 INFO - 'DCLOCATION': 'SCL3', 07:04:31 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:04:31 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:04:31 INFO - 'HOMEDRIVE': 'C:', 07:04:31 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:04:31 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:04:31 INFO - 'KTS_VERSION': '1.19c', 07:04:31 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:04:31 INFO - 'LOGONSERVER': '\\\\T-W732-IX-099', 07:04:31 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:04:31 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:04:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:31 INFO - 'MOZ_NO_REMOTE': '1', 07:04:31 INFO - 'NO_EM_RESTART': '1', 07:04:31 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:04:31 INFO - 'OS': 'Windows_NT', 07:04:31 INFO - 'OURDRIVE': 'C:', 07:04: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;', 07:04:31 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:04:31 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:04:31 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:04:31 INFO - 'PROCESSOR_LEVEL': '6', 07:04:31 INFO - 'PROCESSOR_REVISION': '1e05', 07:04:31 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:04:31 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:04:31 INFO - 'PROMPT': '$P$G', 07:04:31 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:04:31 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:04:31 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:04:31 INFO - 'PWD': 'C:\\slave\\test', 07:04:31 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:04:31 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:04:31 INFO - 'SYSTEMDRIVE': 'C:', 07:04:31 INFO - 'SYSTEMROOT': 'C:\\windows', 07:04:31 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:31 INFO - 'TEST1': 'testie', 07:04:31 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:31 INFO - 'USERDOMAIN': 'T-W732-IX-099', 07:04:31 INFO - 'USERNAME': 'cltbld', 07:04:31 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:04:31 INFO - 'WINDIR': 'C:\\windows', 07:04:31 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:04:31 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:04:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:04:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 07:04:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rwjx4o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 07:04:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 07:04:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zbpgem-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 07:04:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 07:04:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gy6dhh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 07:04:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 07:04:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u3hfsy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 07:04:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 07:04:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wqjuzb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 07:04:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 07:04:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tncdav-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 07:04:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 07:04:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tvm2_m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 07:04:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 07:04:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x0pzvc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 07:04:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 07:04:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gm1czb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 07:04:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 07:04:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j8mxzz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 07:04:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 07:04:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fggtsd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 07:04:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 07:04:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-totxmz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 07:04:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 07:04:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9ixxcx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 07:04:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 07:04:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bcqyln-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 07:04:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 07:04:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ky5zzs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 07:04:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 07:04:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hyhbgf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 07:04:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 07:04:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bs17pf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 07:04:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:04:33 INFO - Running setup.py install for manifestparser 07:04:34 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 07:04:34 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 07:04:34 INFO - Running setup.py install for mozcrash 07:04:35 INFO - Running setup.py install for mozdebug 07:04:35 INFO - Running setup.py install for mozdevice 07:04:35 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 07:04:35 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 07:04:35 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 07:04:35 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 07:04:36 INFO - Running setup.py install for mozfile 07:04:36 INFO - Running setup.py install for mozhttpd 07:04:36 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 07:04:36 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 07:04:36 INFO - Running setup.py install for mozinfo 07:04:37 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 07:04:37 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 07:04:37 INFO - Running setup.py install for mozInstall 07:04:37 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 07:04:37 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 07:04:37 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 07:04:37 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 07:04:37 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 07:04:37 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 07:04:37 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 07:04:37 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 07:04:37 INFO - Running setup.py install for mozleak 07:04:38 INFO - Running setup.py install for mozlog 07:04:38 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 07:04:38 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 07:04:39 INFO - Running setup.py install for moznetwork 07:04:39 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 07:04:39 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 07:04:39 INFO - Running setup.py install for mozprocess 07:04:39 INFO - Running setup.py install for mozprofile 07:04:40 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 07:04:40 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 07:04:40 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 07:04:40 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 07:04:40 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 07:04:40 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 07:04:40 INFO - Running setup.py install for mozrunner 07:04:40 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 07:04:40 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 07:04:41 INFO - Running setup.py install for mozscreenshot 07:04:41 INFO - Running setup.py install for moztest 07:04:41 INFO - Running setup.py install for mozversion 07:04:42 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 07:04:42 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 07:04:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:04:42 INFO - Cleaning up... 07:04:42 INFO - Return code: 0 07:04:42 INFO - Installing None into virtualenv C:\slave\test\build/venv 07:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:42 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 0x01822640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E3E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01999520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-099', 'USERDOMAIN': 'T-W732-IX-099', '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-099', '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 07:04:42 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 07:04:42 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 07:04:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:04:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:04:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:04:42 INFO - 'COMPUTERNAME': 'T-W732-IX-099', 07:04:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:04:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:04:42 INFO - 'DCLOCATION': 'SCL3', 07:04:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:04:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:04:42 INFO - 'HOMEDRIVE': 'C:', 07:04:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:04:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:04:42 INFO - 'KTS_VERSION': '1.19c', 07:04:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:04:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-099', 07:04:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:04:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:04:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:42 INFO - 'MOZ_NO_REMOTE': '1', 07:04:42 INFO - 'NO_EM_RESTART': '1', 07:04:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:04:42 INFO - 'OS': 'Windows_NT', 07:04:42 INFO - 'OURDRIVE': 'C:', 07:04:42 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;', 07:04:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:04:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:04:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:04:42 INFO - 'PROCESSOR_LEVEL': '6', 07:04:42 INFO - 'PROCESSOR_REVISION': '1e05', 07:04:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:04:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:04:42 INFO - 'PROMPT': '$P$G', 07:04:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:04:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:04:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:04:42 INFO - 'PWD': 'C:\\slave\\test', 07:04:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:04:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:04:42 INFO - 'SYSTEMDRIVE': 'C:', 07:04:42 INFO - 'SYSTEMROOT': 'C:\\windows', 07:04:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:42 INFO - 'TEST1': 'testie', 07:04:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:42 INFO - 'USERDOMAIN': 'T-W732-IX-099', 07:04:42 INFO - 'USERNAME': 'cltbld', 07:04:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:04:42 INFO - 'WINDIR': 'C:\\windows', 07:04:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:04:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:04:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 07:04:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qrg4yp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 07:04:42 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)) 07:04:42 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 07:04:42 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9dvuon-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 07:04:43 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)) 07:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 07:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tlhscs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 07:04:43 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)) 07:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 07:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qwwvlw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 07:04:43 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)) 07:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 07:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3fyny4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 07:04:43 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)) 07:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 07:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3z6bwm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 07:04:43 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)) 07:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 07:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ttu47d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 07:04:43 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)) 07:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 07:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ah2zkp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 07:04:43 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)) 07:04:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 07:04:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wmw_ad-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 07:04:44 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)) 07:04:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 07:04:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ds683l-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 07:04:44 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)) 07:04:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 07:04:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fa6vrn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 07:04:44 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)) 07:04:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 07:04:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hgkvfg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 07:04:44 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)) 07:04:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 07:04:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-okd1pf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 07:04:44 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)) 07:04:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 07:04:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9lwv8v-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 07:04:44 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)) 07:04:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 07:04:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6jeyre-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 07:04:45 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)) 07:04:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 07:04:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hkaj7r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 07:04:45 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)) 07:04:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 07:04:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bgsnx4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 07:04:45 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)) 07:04:45 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)) 07:04:45 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)) 07:04:45 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)) 07:04:45 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)) 07:04:45 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)) 07:04:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 07:04:45 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 07:04:45 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 07:04:46 INFO - Downloading blessings-1.6.tar.gz 07:04:46 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 07:04:46 INFO - Installing collected packages: blessings 07:04:46 INFO - Running setup.py install for blessings 07:04:47 INFO - Successfully installed blessings 07:04:47 INFO - Cleaning up... 07:04:49 INFO - Return code: 0 07:04:49 INFO - Done creating virtualenv C:\slave\test\build/venv. 07:04:49 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 07:04:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 07:04:50 INFO - Reading from file tmpfile_stdout 07:04:50 INFO - Using _rmtree_windows ... 07:04:50 INFO - Using _rmtree_windows ... 07:04:50 INFO - Current package versions: 07:04:50 INFO - blessings == 1.6 07:04:50 INFO - blobuploader == 1.2.4 07:04:50 INFO - docopt == 0.6.1 07:04:50 INFO - functools32 == 3.2.3-2 07:04:50 INFO - jsonschema == 2.5.1 07:04:50 INFO - manifestparser == 1.1 07:04:50 INFO - mozInstall == 1.12 07:04:50 INFO - mozcrash == 0.17 07:04:50 INFO - mozdebug == 0.1 07:04:50 INFO - mozdevice == 0.48 07:04:50 INFO - mozfile == 1.2 07:04:50 INFO - mozhttpd == 0.7 07:04:50 INFO - mozinfo == 0.9 07:04:50 INFO - mozleak == 0.1 07:04:50 INFO - mozlog == 3.4 07:04:50 INFO - moznetwork == 0.27 07:04:50 INFO - mozprocess == 0.23 07:04:50 INFO - mozprofile == 0.28 07:04:50 INFO - mozrunner == 6.12 07:04:50 INFO - mozscreenshot == 0.1 07:04:50 INFO - mozsystemmonitor == 0.3 07:04:50 INFO - moztest == 0.7 07:04:50 INFO - mozversion == 1.4 07:04:50 INFO - psutil == 3.1.1 07:04:50 INFO - requests == 1.2.3 07:04:50 INFO - Installing None into virtualenv C:\slave\test\build/venv 07:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:50 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 0x01822640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E3E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01999520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-099', 'USERDOMAIN': 'T-W732-IX-099', '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-099', '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 07:04:50 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 07:04:50 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 07:04:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:04:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:04:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:04:50 INFO - 'COMPUTERNAME': 'T-W732-IX-099', 07:04:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:04:50 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:04:50 INFO - 'DCLOCATION': 'SCL3', 07:04:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:04:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:04:50 INFO - 'HOMEDRIVE': 'C:', 07:04:50 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:04:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:04:50 INFO - 'KTS_VERSION': '1.19c', 07:04:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:04:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-099', 07:04:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:04:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:50 INFO - 'MOZ_NO_REMOTE': '1', 07:04:50 INFO - 'NO_EM_RESTART': '1', 07:04:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:04:50 INFO - 'OS': 'Windows_NT', 07:04:50 INFO - 'OURDRIVE': 'C:', 07:04:50 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;', 07:04:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:04:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:04:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:04:50 INFO - 'PROCESSOR_LEVEL': '6', 07:04:50 INFO - 'PROCESSOR_REVISION': '1e05', 07:04:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:04:50 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:04:50 INFO - 'PROMPT': '$P$G', 07:04:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:04:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:04:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:04:50 INFO - 'PWD': 'C:\\slave\\test', 07:04:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:04:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:04:50 INFO - 'SYSTEMDRIVE': 'C:', 07:04:50 INFO - 'SYSTEMROOT': 'C:\\windows', 07:04:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:50 INFO - 'TEST1': 'testie', 07:04:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:50 INFO - 'USERDOMAIN': 'T-W732-IX-099', 07:04:50 INFO - 'USERNAME': 'cltbld', 07:04:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:04:50 INFO - 'WINDIR': 'C:\\windows', 07:04:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:04:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:50 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)) 07:04:50 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)) 07:04:50 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)) 07:04:50 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)) 07:04:50 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)) 07:04:50 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)) 07:04:50 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)) 07:04:50 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)) 07:04:50 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)) 07:04:50 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)) 07:04:50 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)) 07:04:50 INFO - Cleaning up... 07:04:50 INFO - Return code: 0 07:04:50 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 07:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:04:50 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01822640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C9E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017CB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018E3E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01999520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-099', 'USERDOMAIN': 'T-W732-IX-099', '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-099', '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 07:04:50 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build 07:04:50 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:04:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:04:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:04:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:04:50 INFO - 'COMPUTERNAME': 'T-W732-IX-099', 07:04:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:04:50 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:04:50 INFO - 'DCLOCATION': 'SCL3', 07:04:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:04:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:04:50 INFO - 'HOMEDRIVE': 'C:', 07:04:50 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:04:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:04:50 INFO - 'KTS_VERSION': '1.19c', 07:04:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:04:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-099', 07:04:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:04:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:50 INFO - 'MOZ_NO_REMOTE': '1', 07:04:50 INFO - 'NO_EM_RESTART': '1', 07:04:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:04:50 INFO - 'OS': 'Windows_NT', 07:04:50 INFO - 'OURDRIVE': 'C:', 07:04:50 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;', 07:04:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:04:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:04:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:04:50 INFO - 'PROCESSOR_LEVEL': '6', 07:04:50 INFO - 'PROCESSOR_REVISION': '1e05', 07:04:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:04:50 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:04:50 INFO - 'PROMPT': '$P$G', 07:04:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:04:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:04:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:04:50 INFO - 'PWD': 'C:\\slave\\test', 07:04:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:04:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:04:50 INFO - 'SYSTEMDRIVE': 'C:', 07:04:50 INFO - 'SYSTEMROOT': 'C:\\windows', 07:04:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:50 INFO - 'TEST1': 'testie', 07:04:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:50 INFO - 'USERDOMAIN': 'T-W732-IX-099', 07:04:50 INFO - 'USERNAME': 'cltbld', 07:04:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:04:50 INFO - 'WINDIR': 'C:\\windows', 07:04:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:04:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:04:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 07:04:50 INFO - Cleaning up... 07:04:50 INFO - Return code: 0 07:04:50 INFO - Running post-action listener: _resource_record_post_action 07:04:50 INFO - Running post-action listener: _start_resource_monitoring 07:04:51 INFO - Starting resource monitoring. 07:04:51 INFO - [mozharness: 2016-10-19 14:04:51.103000Z] Finished create-virtualenv step (success) 07:04:51 INFO - [mozharness: 2016-10-19 14:04:51.103000Z] Running install step. 07:04:51 INFO - Running pre-action listener: _resource_record_pre_action 07:04:51 INFO - Running main action method: install 07:04:51 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 07:04:51 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 07:04:51 INFO - Reading from file tmpfile_stdout 07:04:51 INFO - Using _rmtree_windows ... 07:04:51 INFO - Using _rmtree_windows ... 07:04:51 INFO - Detecting whether we're running mozinstall >=1.0... 07:04:51 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 07:04:51 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 07:04:51 INFO - Reading from file tmpfile_stdout 07:04:51 INFO - Output received: 07:04:51 INFO - Usage: mozinstall-script.py [options] installer 07:04:51 INFO - Options: 07:04:51 INFO - -h, --help show this help message and exit 07:04:51 INFO - -d DEST, --destination=DEST 07:04:51 INFO - Directory to install application into. [default: 07:04:51 INFO - "C:\slave\test"] 07:04:51 INFO - --app=APP Application being installed. [default: firefox] 07:04:51 INFO - Using _rmtree_windows ... 07:04:51 INFO - Using _rmtree_windows ... 07:04:51 INFO - mkdir: C:\slave\test\build\application 07:04:51 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'] 07:04:51 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 07:04:53 INFO - Reading from file tmpfile_stdout 07:04:53 INFO - Output received: 07:04:53 INFO - C:\slave\test\build\application\firefox\firefox.exe 07:04:53 INFO - Using _rmtree_windows ... 07:04:53 INFO - Using _rmtree_windows ... 07:04:53 INFO - Running post-action listener: _resource_record_post_action 07:04:53 INFO - [mozharness: 2016-10-19 14:04:53.750000Z] Finished install step (success) 07:04:53 INFO - [mozharness: 2016-10-19 14:04:53.750000Z] Running run-tests step. 07:04:53 INFO - Running pre-action listener: _resource_record_pre_action 07:04:53 INFO - Running main action method: run_tests 07:04:53 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 07:04:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 07:04:53 INFO - Python 2.7.3 07:04:53 INFO - Return code: 0 07:04:53 INFO - grabbing minidump binary from tooltool 07:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:04:53 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 0x018E3E90>, '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 0x0126A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01999520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 07:04:53 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 07:04:53 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 07:04:53 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 07:04:53 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 07:04:54 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpkj00xd 07:04:54 INFO - INFO - File integrity verified, renaming tmpkj00xd to win32-minidump_stackwalk.exe 07:04:54 INFO - Return code: 0 07:04:54 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 07:04:54 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 07:04:54 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 07:04:54 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 07:04:54 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 07:04:54 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/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-099', '--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 07:04:54 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/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-099 --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 07:04:54 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:04:54 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:04:54 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:04:54 INFO - 'COMPUTERNAME': 'T-W732-IX-099', 07:04:54 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:04:54 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:04:54 INFO - 'DCLOCATION': 'SCL3', 07:04:54 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:04:54 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:04:54 INFO - 'HOMEDRIVE': 'C:', 07:04:54 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:04:54 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:04:54 INFO - 'KTS_VERSION': '1.19c', 07:04:54 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:04:54 INFO - 'LOGONSERVER': '\\\\T-W732-IX-099', 07:04:54 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 07:04:54 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 07:04:54 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:04:54 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:04:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:04:54 INFO - 'MOZ_NO_REMOTE': '1', 07:04:54 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 07:04:54 INFO - 'NO_EM_RESTART': '1', 07:04:54 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:04:54 INFO - 'OS': 'Windows_NT', 07:04:54 INFO - 'OURDRIVE': 'C:', 07:04:54 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;', 07:04:54 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:04:54 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:04:54 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:04:54 INFO - 'PROCESSOR_LEVEL': '6', 07:04:54 INFO - 'PROCESSOR_REVISION': '1e05', 07:04:54 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:04:54 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:04:54 INFO - 'PROMPT': '$P$G', 07:04:54 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:04:54 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:04:54 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:04:54 INFO - 'PWD': 'C:\\slave\\test', 07:04:54 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 07:04:54 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:04:54 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:04:54 INFO - 'SYSTEMDRIVE': 'C:', 07:04:54 INFO - 'SYSTEMROOT': 'C:\\windows', 07:04:54 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:54 INFO - 'TEST1': 'testie', 07:04:54 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:04:54 INFO - 'USERDOMAIN': 'T-W732-IX-099', 07:04:54 INFO - 'USERNAME': 'cltbld', 07:04:54 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:04:54 INFO - 'WINDIR': 'C:\\windows', 07:04:54 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:04:54 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:04:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:04:54 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/GqazMqeTQ_eoK6NoMdrX5g/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-099', '--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 07:04:55 INFO - mozversion application_buildid: 20161019033001 07:04:55 INFO - mozversion application_changeset: e4282badddda00fb555bd2e5300c92149434a884 07:04:55 INFO - mozversion application_display_name: Nightly 07:04:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:04:55 INFO - mozversion application_name: Firefox 07:04:55 INFO - mozversion application_remotingname: firefox 07:04:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:04:55 INFO - mozversion application_vendor: Mozilla 07:04:55 INFO - mozversion application_version: 52.0a1 07:04:55 INFO - mozversion platform_buildid: 20161019033001 07:04:55 INFO - mozversion platform_changeset: e4282badddda00fb555bd2e5300c92149434a884 07:04:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:04:55 INFO - mozversion platform_version: 52.0a1 07:04:55 INFO - using testdate: 1476885895 07:04:55 INFO - actual date: 1476885895 07:04:55 INFO - starting webserver on 'localhost:49325' 07:04:55 INFO - SUITE-START | Running 2 tests 07:04:55 INFO - TEST-START | dromaeo_css 07:04:55 INFO - operating with platform_type : w7_ 07:04:55 INFO - Initialising browser for dromaeo_css test... 07:04:56 INFO - TEST-INFO | started process 3080 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpgeyk0i\profile http://localhost:49325/getInfo.html) 07:05:02 INFO - PROCESS | 3080 | __metrics Screen width/height:1600/1200 07:05:02 INFO - PROCESS | 3080 | colorDepth:24 07:05:02 INFO - PROCESS | 3080 | Browser inner width/height: 1010/674 07:05:02 INFO - PROCESS | 3080 | __metrics 07:05:03 INFO - PROCESS | 3080 | 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" 07:05:03 INFO - PROCESS | 3080 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:05:04 INFO - TEST-INFO | 3080: exit 0 07:05:04 INFO - Browser initialized. 07:05:04 INFO - Running cycle 1/1 for dromaeo_css test... 07:05:04 INFO - TEST-INFO | started process 2832 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpgeyk0i\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:05:05 INFO - PROCESS | 2832 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 07:05:16 INFO - PROCESS | 2832 | RSS: Main: 123314176 07:05:16 INFO - PROCESS | 2832 | 07:06:07 INFO - PROCESS | 2832 | 1476885967194 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] 07:08:17 INFO - PROCESS | 2832 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49325/tests/dromaeo/cssquery-ext.html) 07:08:20 INFO - PROCESS | 2832 | RSS: Main: 314798080 07:08:20 INFO - PROCESS | 2832 | 07:11:22 INFO - PROCESS | 2832 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-ext.html (next: http://localhost:49325/tests/dromaeo/cssquery-jquery.html) 07:11:23 INFO - PROCESS | 2832 | RSS: Main: 196800512 07:11:23 INFO - PROCESS | 2832 | 07:14:25 INFO - PROCESS | 2832 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49325/tests/dromaeo/cssquery-mootools.html) 07:14:26 INFO - PROCESS | 2832 | RSS: Main: 144535552 07:14:26 INFO - PROCESS | 2832 | 07:17:28 INFO - PROCESS | 2832 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49325/tests/dromaeo/cssquery-prototype.html) 07:17:28 INFO - PROCESS | 2832 | RSS: Main: 144265216 07:17:28 INFO - PROCESS | 2832 | 07:20:31 INFO - PROCESS | 2832 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49325/tests/dromaeo/cssquery-yui.html) 07:20:32 INFO - PROCESS | 2832 | RSS: Main: 134180864 07:20:32 INFO - PROCESS | 2832 | 07:23:35 INFO - PROCESS | 2832 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-yui.html (next: http://localhost:49325/tests/dromaeo/cssquery-dojo.html) 07:23:35 INFO - PROCESS | 2832 | RSS: Main: 183463936 07:23:35 INFO - PROCESS | 2832 | 07:23:35 INFO - PROCESS | 2832 | __start_tp_report 07:23:35 INFO - PROCESS | 2832 | _x_x_mozilla_page_load 07:23:35 INFO - PROCESS | 2832 | _x_x_mozilla_page_load_details 07:23:35 INFO - PROCESS | 2832 | |i|pagename|runs| 07:23:35 INFO - PROCESS | 2832 | |0;dojo.html;2662.294491959631;2679.691835438924;2701.7568418842307;2712.443948990457;2714.9728502714966;4522;4522.77386130693;4587.426571678536;4607.401037865077;4623.029163875582;4511.72929624222;4558.680892337537;4579.610733087691;4668.883277918054;4675.251959686451;3825.9426108608395;4025.134596061846;4027.697922655798;4036.091879327157;4065.349544072949;3814.9618503815022;4006.0585762345972;4041.2321658884866;4096.242195193884;4112.794360281989;4013.4381186633896;4024.772414615131;4114.91770164598;4115.609017143366;4118.6499147572495;3970.66249368804;4000.1999600079953;4043.777592232426;4052.1085361220485;4085.7344272622186;3562.4834399012257;3994.840206391741;4085.5097388313593;4087.448194493735;4103.056297051665;4476.529964353728;4530.057181231916;4573.268277075686;4588.541145885418;4592.357070010168;4630.259158534652;4646.86059418218;4667.299905014241;4674.626029917598;4771.33201351811;4726.480087190406;4731.148393289207;4735.124002059595;4738.549837765407;4745.572898439123;4396.648268138541;4422.314541246112;4424.469063712343;4430;4437.223485890021;4727.810887564528;4803.159447096744;4822.590079843182;4825.083234185494;4829.5653391194965;4646.303054541778;4658.510856360102;4662.95337046632;4663.347131401607;4670.95329046712;4775.71345719258;4776.259679749644;4798.376211092535;4802.975985120052;4807.423109226846;4454.064646424223;4684.765761711901;4713.08094921487;4777.068471648065;4778.593819525309;4752.738599377068;4876.512348765166;4892.119418504632;4896.926546101811;4897.118518666674;3145.7640676949322;3248.545203671488;3323.1692076980753;3324.6675332467044;3333.3000069985574;2988.8804447822276;2990.551417287381;3012.1114271289584;3039.2553824313186;3057.2051266670383;4555.20283950314;4738.573528382462;4740.336352910526;4747;4757.904841903142;4856.781444834991;4857.271409288564;4883.975580122148;4967.180415231519;4967.801287948514;4571;4590.632749380042;4612.884677883079;4650.18621741194;4714.481407045222;4024.215278020758;4053.574374690674;4059.167870586523;4062.898427539335;4063.167050754588;5030.974845125897;5033.572146367526;5045.167547354646;5048.167052436307;5051.696898186047;4799.928001080057;4804.8798780030775;4806.158922188673;4811.08589368019;4812.590929771009;4934.753262336941;5011.699298042129;5040;5045.949540504548;5054.963732434769;4685.039566888711;4722.220833562561;4733.597644200144;4733.692309999839;4736.3003088239375;4475.462944446687;4874.926876096933;4878.146324393146;4890.7554622268035;4903.460619331942;4542.863714088583;4688.132695451352;4720.386349774507;4810.42274927011;4812.446568644651;4582.977085114687;4632.606228470483;4666.1834179019215;4800.279958006327;4811.133995880775;4613.492515823324;4616.1229366420275;4619.2378257587125;4623.329617205419;4628.629709623289;4717.292406138969;4723.551262630044;4725.29120631908;4726;4730.337752714554;4642.18761716705;4643.071385722801;4646.233371493666;4647.372604698322;4654.650901182357 07:23:35 INFO - PROCESS | 2832 | |1;ext.html;495.3337760711842;539.7490167072311;556.9331680198377;563.5013013483069;577.777555641078;39367;39949.80025099874;40545.18909621809;42347;42404;40524.39213411794;40581.68075544514;40595.391069133984;40989.59010409902;41017.38473922894;6496.902546461807;6893;7930.127685954526;7983.520988740665;8068.798280043016;9399.107084826965;9904.108630223278;10007.94996025019;10248.590056397772;10254.282200245985;9858.802823943553;10169.23730720199;10210.540525676326;10221.080102790747;10231.590736370536;6717.361850624183;6861.759838405682;9809.656662016796;10046.246531510129;10101.292909496302;9666.226701863834;9878.90121098787;9914.40513569181;9918.454485003322;9933.304668673196;37845.24309513822;38120;38274.425883611766;38319.80840095809;38344.42483362752;3002.8948986785576;3006.6241719785025;3033.3175035617032;3044.8325342106177;3110.1136176189675;29471.41057178866;30175.69824301751;30498.237544061576;30552.694473055206;30735.077947661604;3478.8608455661742;3491.6857482826667;3517.120719820045;3521.3133438979407;3546.7517273790845;327472;328442;328974;329395;329618;52652.736736316074;52742.73628631832;52779.73610132001;52913.47086529162;52980;28885.27786805347;29303.413931721247;29421.55867661987;30271.848640756656;54996;13973.161610303414;14032.298385080705;14040.1575905446;14076.366563504462;14093.929530352283;8749.387542871938;8906.421082629608;10045.296829221885;10136.290459667753;10485.790284194274;17072.573185670208;17155;17271.74092388615;17283.39508117235;17292.22185001678;6533.41199292066;6572.737090516326;6581.407673309425;6604.603723776589;6623.205215374186;752.578556008637;809.7165991902787;881.9470831750116;919.5907821019582;952.4095061062047;26945.922163113257;28000.8599957003;28158.43683126367;28426.43147137087;28479.857600711865;881.959288040106;900.7162743735702;903.7559858838077;952.8618350339162;965.8406362950175;13465.528706495226;14242.643933901736;14461.421543138156;14565.490207842675;14569.41722331116;191878;240237;546945;782854;788617;50936;51076.48923510792;51422;51482.742586287575;51610.74194629003;947.6162154327509;1022.4683164754434;1082.3181395720646;1102.7739313440725;1125.6004118537965;28267.858660706566;28436.857815710788;29117;29389.70610293955;29490.262743431587;41494.377584336864;42789.57210427856;43679;43689.344659830764;43695.56304436915;408966;409118;413325;418999;419103;23027;23448.531029379657;23474.413139671644;24343.878280608482;24346.147884823946;42395.7880210597;42758.144837103704;43554;43644.56355436405;43671.34492982544;36074.63925360713;36961.26077478382;37462.8126859364;38072.61927380801;38263.80868095642;36639.63360366469;37477.43783843299;37527.24945501129;37538.2492350146;38085.047873802265;34541.82729086339;34912.825435872655;35283.11792205215;35410.46884296789;35544.49060395643;32816;33005;33007.66992330142;33674.326513470085;33931.83034084814;23883.641745373487;25178.496430071664;25223.62164567496;25229.369265768502;25537.361565961 07:23:35 INFO - PROCESS | 2832 | |2;jquery.html;282.8910869315313;294.87025708688174;298.4731948111583;308.6913086913087;312.7169911230336;13375.464981400744;16689.165541722912;17011.744823827652;17197.312107515685;17999.370022049232;16449.506514804576;16777.748333775;16793.496195114167;16915.492535223933;16995.23521441535;4798.968221832305;4912.238603016529;5045.091883460976;5111.258867464216;5121.103806833789;3733.439984002408;3999.7200195986293;4175.686823488235;4846;6017.3380928098095;5751.166080918264;6010;6093.725782339805;6122.816315510542;6141.355157708444;5708.914366284509;5881.67650779207;5999.970000150015;6006.399360063981;6015.578909476339;5024.67339622927;5067.797288108472;5073.543381095719;5124.795008199668;5131.666441681316;21438.571228575496;22076.33770986873;22101.778982210133;22105.447363816034;22215.111395544158;1800.6308706715092;1862.1806405181676;2081.375587323797;2477.36827109086;2513.3465299022387;8437.114103019156;8831.173994503555;8921.286297096216;8953.507557084358;13600.31998400086;1705.3690134650135;1727.1019070083612;1743.947681569555;1760.5950631354733;1790.1228398084975;75376.36182733632;78318;79529.60235198845;82463.5876820618;84192;8675.262602678842;8688.782780430414;8812.427192232484;8857.11428857109;8904.777380565538;10104.949475252724;10558.52486638103;10849.349039057683;10866;10869.184811139037;5065.696058236444;5140.820071297487;5242.842714718565;7086.539374940716;7185.604791736505;7143.821404464826;7150.213476517579;7258.818529536804;7261.3101755333155;7275.454340924452;3618.9638103619154;3760.6803421708914;3762.887113386603;3765.9435108473394;3773.0755964788805;1515.9166245856584;1534.7390943539624;1542.5603702944607;1549.1324858079286;1554.3083327918966;790.8971833661702;800.8078061265254;818.2267756969607;862.7584276402617;868.5440143924491;16946.745798813026;17278.22247998843;17675.73486397705;17791.555211119827;17857.642847143245;768.3699366519512;798.3772657327294;817.9182081791773;830.2818062376001;880.5289170293862;9464.242860571137;9802.051984942824;9889.159421449114;9923.553440095213;10037.598496060076;371630;399947.00026500266;408183.9590802027;423943;425935;88845.55577222073;94694;95247.52376238075;99763;100430;834.5993922917033;843.5234092737584;846.585173265108;894.6510860764307;900.4462255712821;16280.674386511966;16701;16862.831371686127;16873.831261687224;17283.049432281347;32033.359332812746;39085.804570976965;40819;42159.578404216794;44073.77963110164;50821;59790;59804;60188.09717854148;60769.69615152073;6501.959686450145;6545.509086818603;6858.108445902001;6863.691133898887;6986.54587451814;7859.4891332063235;11412.82880756806;11463.770724585629;11545.422728863356;11589.768204635691;9491.762705932408;9507.714768556954;9574;9776;9875.111239988435;9630.717582878578;9784.461854598065;9787.85318220213;9811.607535698493;9970.55132519024;9708.368956017835;9816.754581135528;9980.401175929177;10169.593216271562;10357.119644830274;6071;6450.7097180627925;6473.320301368289;7107.857842843217;7129.7861064168155;8737.126287371211;9482.762930926781;9969.451680157661;10371.481425928825;10386 07:23:35 INFO - PROCESS | 2832 | |3;mootools.html;1172.202902026622;1173.32533793069;1243.4590952935473;1395.4139261510165;1412.9646758831027;5966.880662386755;6371.139896114023;6383.648899310536;6384.872302553946;6434.871302573945;5553.972230138844;6014.699265036753;6028.186194863692;6117.908231376533;6134.355892631265;1703.3697531913178;1738.0093346792332;1747.7940221247363;1771.973420398695;1861.4508719927653;1736.435658411015;1832.6517961587322;1833.065136780245;1852.3516769130763;1858.5632376391536;1569.8430156984268;1615.4749706345485;1812.601227729904;1817.7364282179076;1840.9079546022713;1710.042376269293;1719.8796084274106;1770.592763664358;1798.4784412520419;1799.9370022049166;1658.9004659720335;1671.515260574432;1748.8688348373796;1750.6848767221895;1753.9824601753946;6297.4962003039645;6344.270408902989;6354.364563543655;6506.316836732122;6635.402813746786;3707.388280933643;3787.696984241254;3789.3179227738997;3794.924101517982;3821.7515861468914;2573.7683608475327;2610.830296030752;2662.707102218774;2758.3242105684035;2761.585762135676;837.367787320569;841.3479553346144;846.834867200896;850.37497439382;918.0406475233397;7892.80267993299;8100.554469504175;8309.667613295462;8451.408401411903;8457.238848503664;3305.2397948471985;3312.983435082809;3661.7436779425716;3671.4579876451953;3715.5169827922196;6714.664266786641;6722;6747.966260168766;6759.391654751096;6768.695408706521;3936.4882565266903;3954.7429417088374;3956.7823769692945;3958.188571342868;3958.2083583283456;3528.1708798432346;3759.154190307159;3768.9434658480145;3768.981155094262;3770;2618.7904967602544;2671.19864040787;2692.407670312528;2723.714010028958;2794;803.9517628942282;839.9790005249918;853.9871901921476;858.8797568340437;859.1022381611232;603.8369640197122;697.8011266788941;747.8354761952362;782.0498094814827;788.3653658804602;3803;4541.409616749867;4678.789454474557;4821.397325334333;4922.138625740481;707.0737334092253;759.525296689569;766.7278116268644;777.6073083093002;795.530636924206;2966.347403571211;3029.636443626779;3145.2923092304504;3154.3218208085373;3288.1615188126875;7167.89248161278;7269.600171990592;7306.452016098814;7469.477136600386;7472.663730132157;7887.605619718991;7928.5242885425905;7941.086775020946;7955.920440795518;7969.601519923981;5626.887462250732;5836.854078648068;6174.598651087665;6327.683615819191;6385.36146385367;4512.142692888341;4617.884552886205;4663.650226233114;4714.363560919232;4790.7365094920115;7523.0972283326355;7749.380049596131;7755.883661744965;7763.650635721293;7785.961070194613;7101.573905565682;7126.893096603659;7335.193128755939;7807.453478256468;7827.882581761164;5856.560757943086;5951.970240148771;5968.22413086296;5970.701464926649;5971.402859713994;6528.477721782341;6545.836354091186;6580.539362244597;6675.699593518206;6675.766348177791;5855.531557475477;6010.879782404415;6051.546134039877;6062.727177277121;6111.480524155497;4423.513413524574;5595.552355811444;5629.4652008059165;5789.8552536186935;5941.435563621449;5421.078416669096;5467.699276539669;5495.120780675073;5495.945040549544;5502.504774570308;3756.530433695788;3786.280606684721;3831.4827498287373;3868.7291889568587;3906.7655940643654;3744.1762812181278;3895.5130608673917;3926.5680775115284;3929.25344184604;3932.5870783568457 07:23:35 INFO - PROCESS | 2832 | |4;prototype.html;231.35336733828947;241.9286310538391;244.5573511943382;249.92877030046435;251.11106682344501;4852.175130227861;4999.0751710933455;5049.671771334861;5050.343455350804;5065.569426598743;4871.320773574537;4940.59193129958;4965.056639238542;4986.476419975896;4988.077205716936;3649.2519033598182;3664.9083772905624;3672.4307732301536;3679.521662183912;3687.299413111514;3477.165480284739;3554.2003049313957;3560.4481305397576;3679.816009199543;3683.3922402803505;3501.982490087546;3512.297540491899;3523.2248905240936;3523.788572685647;3627.691646210062;3634.2549777295853;3636.9818150909077;3638.1268495561008;3643.143861192618;3643.599204087548;3140.230643492336;3144.119646498961;3144.261098641817;3146.449371360026;3157.7315928146136;4799.904001919977;4826.131296366652;4875.317455556225;4883.487233840431;4887.535684110004;4091.4272001919753;4175.791210439496;4233.5131459881895;4235.8093885775215;4251.298535741599;5162.483751624845;5167.560757335631;5170.379554453452;5177.249298851645;5178.4562620924635;3224.532442795805;3339.780979942328;3485.146139195888;3496.860125594973;3506.211102501942;5411.810586629449;5419.3225846769155;5426.647267927611;5436.266104075979;5437.864053398657;3324.5511855899626;3388.9322213555592;3421.5380923575494;3427.263138425201;3442.9483557746653;3433.364827506919;3471.340445315382;3473.6700013498676;3482.8606855725993;3487.8081705505933;3944.112574670676;4129;4143.523494798137;4152.584741525884;4153.460050193516;5117.590592752571;5178.948210517922;5252.868678282997;5258.6581872178185;5273.894522109613;1186.887245711656;1194.6416075177412;1197.0842305636195;1198.1313547678037;1203.3622180244483;731.3746746531639;732.1982429239973;741.5624781379013;744.4602663069339;748.8539734751671;5037.546620804145;5128.7179205144075;5151.377316145402;5162.174052151638;5172.27588137661;5368.785248590091;5427.348718153847;5451.863703407446;5451.974154344377;5455.972720136453;5104.821331253463;5154.5103215194495;5155.510226528474;5163.173892177234;5166.741662916839;2748.010716142187;2868.2399164221497;2877.7122287771476;2879.2370021944203;2888.6100376448912;5682.630629009184;5709.457601527848;5716.971415142898;5717.399673034354;5728.799492017843;5469.56243500519;5498.257735205776;5511.834644960658;5531.2809334787025;5540.196671482612;5528.55771538284;5686.09022556389;5693.4875861172695;5732.423583514484;5744.856378590569;5425.484578964992;5444.12893936984;5447.182922561489;5462.388595364457;5463.972680136574;5345.278387417648;5350.438203988525;5350.8127215547265;5369.221462887781;5379.865503362291;5469.699166545719;5492.423295553909;5716.171155182392;5718.456746609066;5721.685307308138;3423.623401425791;3430.2453460238708;3432.3821712092063;3436.295559410265;3446.9827650861585;5432.538234250133;5458.890822183614;5467.835964921059;5472.343318801769;5475.561955043508;5081.92377114351;5280.287161233185;5280.471952804689;5287.814926477555;5289.153735402318;5110.693358398508;5290.735463226746;5295.76169072385;5303.310569625924;5303.575713942798;5149.716765577929;5151.042603810528;5157.974210128926;5167.863070124567;5172.663776854492;5082.822101226439;5223.817166399158;5299.708516031501;5305.761240744098;5309.415964243853;5008.87477813043;5118.232265160255;5209.53114219722;5246.449122842047;5257.316548848626 07:23:35 INFO - PROCESS | 2832 | |5;yui.html;591.3672370563498;593.8010766393259;661.9304972977837;679.4598294355987;684.1482354468684;14589.927050364746;14998.400063997427;15153.469628563002;15501.06993580386;15515.767263491029;13831.30843457828;13879.791803122962;13950.930245348785;14002.089864158823;14002.579922602312;500.77465140686655;512.853836656553;598.5600583571072;609.1137395090133;617.9814605561841;605.2857627998959;619.2909119058675;621.4593303825678;621.5059028072693;628.9213848268967;646.5183438338445;650.5543702563735;652.0642877470821;652.644308851677;653.0335104046014;519.2315373247577;580.4398755201208;648.3743187823749;649.1463725201345;650.0476801485822;390.0424457955712;441.54079757052625;450.80615335405764;451.9480259770136;455.26020217147135;13109.082364234508;13572.864271357357;13593.320333983262;13635.250061246628;13647.181169129884;12599.181053231603;12680.429380677891;13086.47654093835;13144.211347319191;13572.864271357357;12602.21737856801;12620.116591838574;13224.537141200108;13272.336383180802;13300.467981280737;4233.640140588053;4263.893402664927;4277.764722940237;4297.798821988875;4340.153670034345;80876.52987760965;83165.58417207876;83539;84722.57638711829;86034;10105.696829095139;10134.898651013544;10278.17774578032;10377.27359084872;10378.792424151627;18528.523756554816;19077.713834292495;19120.904395477934;19121.426357209308;19419.4174174775;6962.373386395248;7238.855222895512;7253.165885923081;7304.196538380774;7306.4885458017425;3759.9248015039548;3763.7553559019125;3791.7724936503896;3827.61723827615;3883.4757307763657;2709.444563864403;2788.2053614719707;2793.9720602793714;2801.4117035422382;2805.8456784877026;484.41869756291885;491.2851800630075;496.1565338923844;507.36072548589044;508.14885067511767;543.3859738495554;584.5791030458064;609.3449541742644;640.3020707428925;641.931184577673;8525.701600443954;8947.373683842068;8953.407770524715;8971.685990990414;9270.814583708288;602.2381687165739;612.1124369663916;641.7208514296296;655.3413819111857;659.8119535932236;4106.342985122366;4126.381042843597;4144.3990621360335;4149.585041495826;4173.83304667816;105500;113436.86563134263;116014.41992790152;119777.40111299387;122296;84260;99441;101376;103934.48032759939;104786;85036.57481712553;87877.1212287869;88190.11809881947;89106;89868;7655.6937722490475;7858.72097091837;8028.157043510348;8151.79620509492;8213.219744124299;35499.64500354963;35732.285354293286;37846.43230351602;48389;49031;57053.71473142608;61717.742338649565;68769;71118;71579.64210178916;12047.397630118634;12333.321667308384;12723.172993755376;12792.104552681223;12882;15142.697146057237;15238.542843714706;15287.464938727091;15393.30730117125;15909.602259943595;13683.589492315246;14034.859651403764;14275.214863182624;14276;14866.702665946837;14039.578812635636;14172.929135354258;14244.430222791387;14301.213433261271;14847.85152148508;13991.930040349733;14006.43974241019;14078.296085195903;14377.137371757728;14631.195284259467;11119.26823944908;12174.878251217375;12597.433115510008;12601.684957876127;12750.298733569372;11198.83201751958;11979.580714674945;11982;11997.100217483548;12141.635750927486 07:23:35 INFO - PROCESS | 2832 | __end_tp_report 07:23:35 INFO - PROCESS | 2832 | __start_cc_report 07:23:35 INFO - PROCESS | 2832 | _x_x_mozilla_cycle_collect,2685 07:23:35 INFO - PROCESS | 2832 | __end_cc_report 07:23:35 INFO - PROCESS | 2832 | __startTimestamp1476887015313__endTimestamp 07:23:35 INFO - PROCESS | 2832 | 07:23:35 INFO - PROCESS | 2832 | ------- Summary: start ------- 07:23:35 INFO - PROCESS | 2832 | Number of tests: 6 07:23:35 INFO - PROCESS | 2832 | 07:23:35 INFO - PROCESS | 2832 | [#0] dojo.html Cycles:165 Average:4440.08 Median:4646.86 stddev:553.42 (11.9%) stddev-sans-first:537.26 07:23:35 INFO - PROCESS | 2832 | Values: 2662.3 2679.7 2701.8 2712.4 2715.0 4522.0 4522.8 4587.4 4607.4 4623.0 4511.7 4558.7 4579.6 4668.9 4675.3 3825.9 4025.1 4027.7 4036.1 4065.3 3815.0 4006.1 4041.2 4096.2 4112.8 4013.4 4024.8 4114.9 4115.6 4118.6 3970.7 4000.2 4043.8 4052.1 4085.7 3562.5 3994.8 4085.5 4087.4 4103.1 4476.5 4530.1 4573.3 4588.5 4592.4 4630.3 4646.9 4667.3 4674.6 4771.3 4726.5 4731.1 4735.1 4738.5 4745.6 4396.6 4422.3 4424.5 4430.0 4437.2 4727.8 4803.2 4822.6 4825.1 4829.6 4646.3 4658.5 4663.0 4663.3 4671.0 4775.7 4776.3 4798.4 4803.0 4807.4 4454.1 4684.8 4713.1 4777.1 4778.6 4752.7 4876.5 4892.1 4896.9 4897.1 3145.8 3248.5 3323.2 3324.7 3333.3 2988.9 2990.6 3012.1 3039.3 3057.2 4555.2 4738.6 4740.3 4747.0 4757.9 4856.8 4857.3 4884.0 4967.2 4967.8 4571.0 4590.6 4612.9 4650.2 4714.5 4024.2 4053.6 4059.2 4062.9 4063.2 5031.0 5033.6 5045.2 5048.2 5051.7 4799.9 4804.9 4806.2 4811.1 4812.6 4934.8 5011.7 5040.0 5045.9 5055.0 4685.0 4722.2 4733.6 4733.7 4736.3 4475.5 4874.9 4878.1 4890.8 4903.5 4542.9 4688.1 4720.4 4810.4 4812.4 4583.0 4632.6 4666.2 4800.3 4811.1 4613.5 4616.1 4619.2 4623.3 4628.6 4717.3 4723.6 4725.3 4726.0 4730.3 4642.2 4643.1 4646.2 4647.4 4654.7 07:23:35 INFO - PROCESS | 2832 | 07:23:35 INFO - PROCESS | 2832 | [#1] ext.html Cycles:180 Average:55407.40 Median:37470.13 stddev:120521.12 (321.6%) stddev-sans-first:120788.70 07:23:35 INFO - PROCESS | 2832 | Values: 495.3 539.7 556.9 563.5 577.8 39367.0 39949.8 40545.2 42347.0 42404.0 40524.4 40581.7 40595.4 40989.6 41017.4 6496.9 6893.0 7930.1 7983.5 8068.8 9399.1 9904.1 10007.9 10248.6 10254.3 9858.8 10169.2 10210.5 10221.1 10231.6 6717.4 6861.8 9809.7 10046.2 10101.3 9666.2 9878.9 9914.4 9918.5 9933.3 37845.2 38120.0 38274.4 38319.8 38344.4 3002.9 3006.6 3033.3 3044.8 3110.1 29471.4 30175.7 30498.2 30552.7 30735.1 3478.9 3491.7 3517.1 3521.3 3546.8 327472.0 328442.0 328974.0 329395.0 329618.0 52652.7 52742.7 52779.7 52913.5 52980.0 28885.3 29303.4 29421.6 30271.8 54996.0 13973.2 14032.3 14040.2 14076.4 14093.9 8749.4 8906.4 10045.3 10136.3 10485.8 17072.6 17155.0 17271.7 17283.4 17292.2 6533.4 6572.7 6581.4 6604.6 6623.2 752.6 809.7 881.9 919.6 952.4 26945.9 28000.9 28158.4 28426.4 28479.9 882.0 900.7 903.8 952.9 965.8 13465.5 14242.6 14461.4 14565.5 14569.4 191878.0 240237.0 546945.0 782854.0 788617.0 50936.0 51076.5 51422.0 51482.7 51610.7 947.6 1022.5 1082.3 1102.8 1125.6 28267.9 28436.9 29117.0 29389.7 29490.3 41494.4 42789.6 43679.0 43689.3 43695.6 408966.0 409118.0 413325.0 418999.0 419103.0 23027.0 23448.5 23474.4 24343.9 24346.1 42395.8 42758.1 43554.0 43644.6 43671.3 36074.6 36961.3 37462.8 38072.6 38263.8 36639.6 37477.4 37527.2 37538.2 38085.0 34541.8 34912.8 35283.1 35410.5 35544.5 32816.0 33005.0 33007.7 33674.3 33931.8 23883.6 25178.5 25223.6 25229.4 25537.4 07:23:35 INFO - PROCESS | 2832 | 07:23:35 INFO - PROCESS | 2832 | [#2] jquery.html Cycles:180 Average:25646.95 Median:5128.23 stddev:67878.85 (1323.6%) stddev-sans-first:68042.55 07:23:35 INFO - PROCESS | 2832 | Values: 282.9 294.9 298.5 308.7 312.7 13375.5 16689.2 17011.7 17197.3 17999.4 16449.5 16777.7 16793.5 16915.5 16995.2 4799.0 4912.2 5045.1 5111.3 5121.1 3733.4 3999.7 4175.7 4846.0 6017.3 5751.2 6010.0 6093.7 6122.8 6141.4 5708.9 5881.7 6000.0 6006.4 6015.6 5024.7 5067.8 5073.5 5124.8 5131.7 21438.6 22076.3 22101.8 22105.4 22215.1 1800.6 1862.2 2081.4 2477.4 2513.3 8437.1 8831.2 8921.3 8953.5 13600.3 1705.4 1727.1 1743.9 1760.6 1790.1 75376.4 78318.0 79529.6 82463.6 84192.0 8675.3 8688.8 8812.4 8857.1 8904.8 10104.9 10558.5 10849.3 10866.0 10869.2 5065.7 5140.8 5242.8 7086.5 7185.6 7143.8 7150.2 7258.8 7261.3 7275.5 3619.0 3760.7 3762.9 3765.9 3773.1 1515.9 1534.7 1542.6 1549.1 1554.3 790.9 800.8 818.2 862.8 868.5 16946.7 17278.2 17675.7 17791.6 17857.6 768.4 798.4 817.9 830.3 880.5 9464.2 9802.1 9889.2 9923.6 10037.6 371630.0 399947.0 408184.0 423943.0 425935.0 88845.6 94694.0 95247.5 99763.0 100430.0 834.6 843.5 846.6 894.7 900.4 16280.7 16701.0 16862.8 16873.8 17283.0 32033.4 39085.8 40819.0 42159.6 44073.8 50821.0 59790.0 59804.0 60188.1 60769.7 6502.0 6545.5 6858.1 6863.7 6986.5 7859.5 11412.8 11463.8 11545.4 11589.8 9491.8 9507.7 9574.0 9776.0 9875.1 9630.7 9784.5 9787.9 9811.6 9970.6 9708.4 9816.8 9980.4 10169.6 10357.1 6071.0 6450.7 6473.3 7107.9 7129.8 8737.1 9482.8 9969.5 10371.5 10386.0 07:23:35 INFO - PROCESS | 2832 | 07:23:35 INFO - PROCESS | 2832 | [#3] mootools.html Cycles:180 Average:4260.34 Median:5528.24 stddev:2325.38 (42.1%) stddev-sans-first:2320.32 07:23:35 INFO - PROCESS | 2832 | Values: 1172.2 1173.3 1243.5 1395.4 1413.0 5966.9 6371.1 6383.6 6384.9 6434.9 5554.0 6014.7 6028.2 6117.9 6134.4 1703.4 1738.0 1747.8 1772.0 1861.5 1736.4 1832.7 1833.1 1852.4 1858.6 1569.8 1615.5 1812.6 1817.7 1840.9 1710.0 1719.9 1770.6 1798.5 1799.9 1658.9 1671.5 1748.9 1750.7 1754.0 6297.5 6344.3 6354.4 6506.3 6635.4 3707.4 3787.7 3789.3 3794.9 3821.8 2573.8 2610.8 2662.7 2758.3 2761.6 837.4 841.3 846.8 850.4 918.0 7892.8 8100.6 8309.7 8451.4 8457.2 3305.2 3313.0 3661.7 3671.5 3715.5 6714.7 6722.0 6748.0 6759.4 6768.7 3936.5 3954.7 3956.8 3958.2 3958.2 3528.2 3759.2 3768.9 3769.0 3770.0 2618.8 2671.2 2692.4 2723.7 2794.0 804.0 840.0 854.0 858.9 859.1 603.8 697.8 747.8 782.0 788.4 3803.0 4541.4 4678.8 4821.4 4922.1 707.1 759.5 766.7 777.6 795.5 2966.3 3029.6 3145.3 3154.3 3288.2 7167.9 7269.6 7306.5 7469.5 7472.7 7887.6 7928.5 7941.1 7955.9 7969.6 5626.9 5836.9 6174.6 6327.7 6385.4 4512.1 4617.9 4663.7 4714.4 4790.7 7523.1 7749.4 7755.9 7763.7 7786.0 7101.6 7126.9 7335.2 7807.5 7827.9 5856.6 5952.0 5968.2 5970.7 5971.4 6528.5 6545.8 6580.5 6675.7 6675.8 5855.5 6010.9 6051.5 6062.7 6111.5 4423.5 5595.6 5629.5 5789.9 5941.4 5421.1 5467.7 5495.1 5495.9 5502.5 3756.5 3786.3 3831.5 3868.7 3906.8 3744.2 3895.5 3926.6 3929.3 3932.6 07:23:35 INFO - PROCESS | 2832 | 07:23:35 INFO - PROCESS | 2832 | [#4] prototype.html Cycles:180 Average:4328.76 Median:5114.14 stddev:1389.72 (27.2%) stddev-sans-first:1359.17 07:23:35 INFO - PROCESS | 2832 | Values: 231.4 241.9 244.6 249.9 251.1 4852.2 4999.1 5049.7 5050.3 5065.6 4871.3 4940.6 4965.1 4986.5 4988.1 3649.3 3664.9 3672.4 3679.5 3687.3 3477.2 3554.2 3560.4 3679.8 3683.4 3502.0 3512.3 3523.2 3523.8 3627.7 3634.3 3637.0 3638.1 3643.1 3643.6 3140.2 3144.1 3144.3 3146.4 3157.7 4799.9 4826.1 4875.3 4883.5 4887.5 4091.4 4175.8 4233.5 4235.8 4251.3 5162.5 5167.6 5170.4 5177.2 5178.5 3224.5 3339.8 3485.1 3496.9 3506.2 5411.8 5419.3 5426.6 5436.3 5437.9 3324.6 3388.9 3421.5 3427.3 3442.9 3433.4 3471.3 3473.7 3482.9 3487.8 3944.1 4129.0 4143.5 4152.6 4153.5 5117.6 5178.9 5252.9 5258.7 5273.9 1186.9 1194.6 1197.1 1198.1 1203.4 731.4 732.2 741.6 744.5 748.9 5037.5 5128.7 5151.4 5162.2 5172.3 5368.8 5427.3 5451.9 5452.0 5456.0 5104.8 5154.5 5155.5 5163.2 5166.7 2748.0 2868.2 2877.7 2879.2 2888.6 5682.6 5709.5 5717.0 5717.4 5728.8 5469.6 5498.3 5511.8 5531.3 5540.2 5528.6 5686.1 5693.5 5732.4 5744.9 5425.5 5444.1 5447.2 5462.4 5464.0 5345.3 5350.4 5350.8 5369.2 5379.9 5469.7 5492.4 5716.2 5718.5 5721.7 3423.6 3430.2 3432.4 3436.3 3447.0 5432.5 5458.9 5467.8 5472.3 5475.6 5081.9 5280.3 5280.5 5287.8 5289.2 5110.7 5290.7 5295.8 5303.3 5303.6 5149.7 5151.0 5158.0 5167.9 5172.7 5082.8 5223.8 5299.7 5305.8 5309.4 5008.9 5118.2 5209.5 5246.4 5257.3 07:23:35 INFO - PROCESS | 2832 | 07:23:35 INFO - PROCESS | 2832 | [#5] yui.html Cycles:180 Average:20287.33 Median:2136.76 stddev:29991.34 (1403.6%) stddev-sans-first:30039.01 07:23:35 INFO - PROCESS | 2832 | Values: 591.4 593.8 661.9 679.5 684.1 14589.9 14998.4 15153.5 15501.1 15515.8 13831.3 13879.8 13950.9 14002.1 14002.6 500.8 512.9 598.6 609.1 618.0 605.3 619.3 621.5 621.5 628.9 646.5 650.6 652.1 652.6 653.0 519.2 580.4 648.4 649.1 650.0 390.0 441.5 450.8 451.9 455.3 13109.1 13572.9 13593.3 13635.3 13647.2 12599.2 12680.4 13086.5 13144.2 13572.9 12602.2 12620.1 13224.5 13272.3 13300.5 4233.6 4263.9 4277.8 4297.8 4340.2 80876.5 83165.6 83539.0 84722.6 86034.0 10105.7 10134.9 10278.2 10377.3 10378.8 18528.5 19077.7 19120.9 19121.4 19419.4 6962.4 7238.9 7253.2 7304.2 7306.5 3759.9 3763.8 3791.8 3827.6 3883.5 2709.4 2788.2 2794.0 2801.4 2805.8 484.4 491.3 496.2 507.4 508.1 543.4 584.6 609.3 640.3 641.9 8525.7 8947.4 8953.4 8971.7 9270.8 602.2 612.1 641.7 655.3 659.8 4106.3 4126.4 4144.4 4149.6 4173.8 105500.0 113436.9 116014.4 119777.4 122296.0 84260.0 99441.0 101376.0 103934.5 104786.0 85036.6 87877.1 88190.1 89106.0 89868.0 7655.7 7858.7 8028.2 8151.8 8213.2 35499.6 35732.3 37846.4 48389.0 49031.0 57053.7 61717.7 68769.0 71118.0 71579.6 12047.4 12333.3 12723.2 12792.1 12882.0 15142.7 15238.5 15287.5 15393.3 15909.6 13683.6 14034.9 14275.2 14276.0 14866.7 14039.6 14172.9 14244.4 14301.2 14847.9 13991.9 14006.4 14078.3 14377.1 14631.2 11119.3 12174.9 12597.4 12601.7 12750.3 11198.8 11979.6 11982.0 11997.1 12141.6 07:23:35 INFO - PROCESS | 2832 | -------- Summary: end -------- 07:23:35 INFO - PROCESS | 2832 | 07:23:36 INFO - TEST-INFO | 2832: exit 0 07:23:36 INFO - TEST-OK | dromaeo_css | took 1120771ms 07:23:36 INFO - TEST-START | kraken 07:23:36 INFO - operating with platform_type : w7_ 07:23:36 INFO - Initialising browser for kraken test... 07:23:36 INFO - TEST-INFO | started process 1252 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpagkths\profile http://localhost:49325/getInfo.html) 07:23:41 INFO - PROCESS | 1252 | __metrics Screen width/height:1600/1200 07:23:41 INFO - PROCESS | 1252 | colorDepth:24 07:23:41 INFO - PROCESS | 1252 | Browser inner width/height: 1010/674 07:23:41 INFO - PROCESS | 1252 | __metrics 07:23:41 INFO - PROCESS | 1252 | 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" 07:23:41 INFO - PROCESS | 1252 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:23:42 INFO - PROCESS | 1252 | console.error: 07:23:42 INFO - PROCESS | 1252 | Message: Error: Connection closed before committing the transaction. 07:23:42 INFO - PROCESS | 1252 | Stack: 07:23:42 INFO - PROCESS | 1252 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 07:23:42 INFO - PROCESS | 1252 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 07:23:42 INFO - PROCESS | 1252 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 07:23:42 INFO - PROCESS | 1252 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 07:23:42 INFO - PROCESS | 1252 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 07:23:42 INFO - PROCESS | 1252 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 07:23:42 INFO - PROCESS | 1252 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 07:23:42 INFO - PROCESS | 1252 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 07:23:42 INFO - PROCESS | 1252 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 07:23:42 INFO - PROCESS | 1252 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 07:23:42 INFO - PROCESS | 1252 | Barrier/this.client.addBlocker/promise13.2% 07:24:39 INFO - TinderboxPrint: I/O read bytes / time
59,608,576 / 1,032,740 07:24:39 INFO - TinderboxPrint: I/O write bytes / time
443,155,456 / 3,316,160 07:24:39 INFO - TinderboxPrint: CPU idle
8,249.8 (86.9%) 07:24:39 INFO - TinderboxPrint: CPU user
1,211.4 (12.8%) 07:24:39 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 33889792; Read time: 0; Write time: 110430 07:24:39 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 59608576; Write bytes: 373208576; Read time: 1032740; Write time: 3171470 07:24:40 INFO - Running post-run listener: _upload_blobber_files 07:24:40 INFO - Blob upload gear active. 07:24:40 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 07:24:40 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 07:24:40 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'] 07:24:40 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 07:24:40 INFO - (blobuploader) - INFO - Open directory for files ... 07:24:40 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 07:24:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:24:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:41 INFO - (blobuploader) - INFO - Done attempting. 07:24:41 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 07:24:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:24:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:42 INFO - (blobuploader) - INFO - Done attempting. 07:24:42 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 07:24:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:24:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:24:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:24:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:24:49 INFO - (blobuploader) - INFO - Done attempting. 07:24:49 INFO - (blobuploader) - INFO - Iteration through files over. 07:24:49 INFO - Return code: 0 07:24:49 INFO - rmtree: C:\slave\test\build\uploaded_files.json 07:24:49 INFO - Using _rmtree_windows ... 07:24:49 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 07:24:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3440e08f3f11a59d9bb43f039bd15bffbd2e5c7539fea9e3a4ab2e9afb2ec2eb917389fd332d5ed8ce7e04cfb8b8048b4f04ce7cf5c8d331043edad22dbc9ad1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ceeaac32e04e3579e9dc91b76b26fbab6f18cfbe79e4eb75eb17f97a1e92ac51c0bc3fffbeaf8d0a219455a740bda7120e58d29d5824ac3ba2c70352cb35948", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f74e2cd0fcd22c3a0b19e5ca27a42f78e411e5813315e09b6430241d76147d38d08da11d7e73ab140bfb7c253eff7219fa6dce6c05660ae708761571464546b"} 07:24:49 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 07:24:49 INFO - Writing to file C:\slave\test\properties\blobber_files 07:24:49 INFO - Contents: 07:24:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3440e08f3f11a59d9bb43f039bd15bffbd2e5c7539fea9e3a4ab2e9afb2ec2eb917389fd332d5ed8ce7e04cfb8b8048b4f04ce7cf5c8d331043edad22dbc9ad1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ceeaac32e04e3579e9dc91b76b26fbab6f18cfbe79e4eb75eb17f97a1e92ac51c0bc3fffbeaf8d0a219455a740bda7120e58d29d5824ac3ba2c70352cb35948", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f74e2cd0fcd22c3a0b19e5ca27a42f78e411e5813315e09b6430241d76147d38d08da11d7e73ab140bfb7c253eff7219fa6dce6c05660ae708761571464546b"} 07:24:49 INFO - Running post-run listener: copy_logs_to_upload_dir 07:24:49 INFO - Copying logs to upload dir... 07:24:49 INFO - mkdir: C:\slave\test\build\upload\logs 07:24:49 INFO - Copying logs to upload dir... 07:24:49 INFO - Using _rmtree_windows ... 07:24:49 INFO - Using _rmtree_windows ... 07:24:49 INFO - Using _rmtree_windows ... 07:24:49 INFO - Using _rmtree_windows ... 07:24:49 INFO - Using _rmtree_windows ... 07:24:49 INFO - Using _rmtree_windows ... 07:24:49 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1425.353000 ========= master_lag: 0.01 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 45 secs) (at 2016-10-19 07:24:49.785642) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 07:24:49.792458) ========= '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-099 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-099 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-099 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/3440e08f3f11a59d9bb43f039bd15bffbd2e5c7539fea9e3a4ab2e9afb2ec2eb917389fd332d5ed8ce7e04cfb8b8048b4f04ce7cf5c8d331043edad22dbc9ad1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ceeaac32e04e3579e9dc91b76b26fbab6f18cfbe79e4eb75eb17f97a1e92ac51c0bc3fffbeaf8d0a219455a740bda7120e58d29d5824ac3ba2c70352cb35948", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f74e2cd0fcd22c3a0b19e5ca27a42f78e411e5813315e09b6430241d76147d38d08da11d7e73ab140bfb7c253eff7219fa6dce6c05660ae708761571464546b"} build_url:https://queue.taskcluster.net/v1/task/GqazMqeTQ_eoK6NoMdrX5g/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/GqazMqeTQ_eoK6NoMdrX5g/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/3440e08f3f11a59d9bb43f039bd15bffbd2e5c7539fea9e3a4ab2e9afb2ec2eb917389fd332d5ed8ce7e04cfb8b8048b4f04ce7cf5c8d331043edad22dbc9ad1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ceeaac32e04e3579e9dc91b76b26fbab6f18cfbe79e4eb75eb17f97a1e92ac51c0bc3fffbeaf8d0a219455a740bda7120e58d29d5824ac3ba2c70352cb35948", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6f74e2cd0fcd22c3a0b19e5ca27a42f78e411e5813315e09b6430241d76147d38d08da11d7e73ab140bfb7c253eff7219fa6dce6c05660ae708761571464546b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 07:24:49.912772) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 07:24:49.913133) ========= '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-099 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-099 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-099 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.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 07:24:50.025843) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-19 07:24:50.026222) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-19 07:24:50.549696) ========= ========= Total master_lag: 3.22 =========