builder: autoland_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-084 starttime: 1475122986.81 results: success (0) buildid: 20160928183008 builduid: cccfcf79934c45869359b22c674d0c24 revision: 72f0acda20c0b6136058324bed919e2fb6c414c3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 21:23:06.813460) ========= master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 21:23:06.813922) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 21:23:06.814216) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 21:23:06.931543) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-28 21:23:06.931813) ========= '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-084 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-084 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-084 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 --21:23:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 2.45 GB/s 21:23:08 (2.45 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.148000 ========= master_lag: 3.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-28 21:23:11.113111) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-09-28 21:23:11.113459) ========= '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-084 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-084 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-084 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=11.701000 ========= master_lag: 3.28 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-09-28 21:23:26.096863) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-28 21:23:26.097252) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev 72f0acda20c0b6136058324bed919e2fb6c414c3 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 72f0acda20c0b6136058324bed919e2fb6c414c3 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-084 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-084 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-084 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-09-28 21:23:26,466 truncating revision to first 12 chars 2016-09-28 21:23:26,467 Setting DEBUG logging. 2016-09-28 21:23:26,467 attempt 1/10 2016-09-28 21:23:26,467 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/72f0acda20c0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-28 21:23:28,042 unpacking tar archive at: autoland-72f0acda20c0/testing/mozharness/ 2016-09-28 21:23:28,180 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=2.195000 ========= master_lag: 2.49 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-28 21:23:30.779100) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 21:23:30.779423) ========= script_repo_revision: 72f0acda20c0b6136058324bed919e2fb6c414c3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 21:23:30.779810) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 21:23:30.780085) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 21:23:31.102370) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 24 secs) (at 2016-09-28 21:23:31.102738) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Autoland' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Autoland' 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', 'Autoland', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-084 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-084 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-084 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 21:23:31 INFO - MultiFileLogger online at 20160928 21:23:31 in C:\slave\test 21:23:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 21:23:31 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 21:23:31 INFO - {'append_to_log': False, 21:23:31 INFO - 'base_work_dir': 'C:\\slave\\test', 21:23:31 INFO - 'blob_upload_branch': 'Autoland', 21:23:31 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 21:23:31 INFO - 'branch': 'Autoland', 21:23:31 INFO - 'buildbot_json_path': 'buildprops.json', 21:23:31 INFO - 'config_files': ('talos/windows_config.py',), 21:23:31 INFO - 'default_actions': ('clobber', 21:23:31 INFO - 'read-buildbot-config', 21:23:31 INFO - 'download-and-extract', 21:23:31 INFO - 'populate-webroot', 21:23:31 INFO - 'create-virtualenv', 21:23:31 INFO - 'install', 21:23:31 INFO - 'run-tests'), 21:23:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:23:31 INFO - 'download_minidump_stackwalk': True, 21:23:31 INFO - 'download_symbols': 'ondemand', 21:23:31 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 21:23:31 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 21:23:31 INFO - 'hg': 'c:/mozilla-build/hg/hg', 21:23:31 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 21:23:31 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 21:23:31 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 21:23:31 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 21:23:31 INFO - 'C:/mozilla-build/tooltool.py'), 21:23:31 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 21:23:31 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 21:23:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:23:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:23:31 INFO - 'installer_path': 'installer.exe', 21:23:31 INFO - 'log_level': 'info', 21:23:31 INFO - 'log_name': 'talos', 21:23:31 INFO - 'log_to_console': True, 21:23:31 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 21:23:31 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 21:23:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 21:23:31 INFO - 'opt_config_files': (), 21:23:31 INFO - 'pip_index': False, 21:23:31 INFO - 'sps_profile': False, 21:23:31 INFO - 'sps_profile_interval': 0, 21:23:31 INFO - 'suite': 'dromaeojs', 21:23:31 INFO - 'system_bits': '32', 21:23:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:23:31 INFO - 'title': 'T-W732-IX-084', 21:23:31 INFO - 'use_talos_json': True, 21:23:31 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 21:23:31 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 21:23:31 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 21:23:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:23:31 INFO - 'work_dir': 'build'} 21:23:31 INFO - [mozharness: 2016-09-29 04:23:31.679000Z] Running clobber step. 21:23:31 INFO - Running pre-action listener: _resource_record_pre_action 21:23:31 INFO - Running main action method: clobber 21:23:31 INFO - rmtree: C:\slave\test\build 21:23:31 INFO - Using _rmtree_windows ... 21:23:31 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 21:25:11 INFO - Running post-action listener: _resource_record_post_action 21:25:11 INFO - [mozharness: 2016-09-29 04:25:11.310000Z] Finished clobber step (success) 21:25:11 INFO - [mozharness: 2016-09-29 04:25:11.310000Z] Running read-buildbot-config step. 21:25:11 INFO - Running pre-action listener: _resource_record_pre_action 21:25:11 INFO - Running main action method: read_buildbot_config 21:25:11 INFO - Using buildbot properties: 21:25:11 INFO - { 21:25:11 INFO - "project": "", 21:25:11 INFO - "product": "firefox", 21:25:11 INFO - "script_repo_revision": "production", 21:25:11 INFO - "builddir": "autoland_win7_ix_test-dromaeojs", 21:25:11 INFO - "repository": "", 21:25:11 INFO - "buildername": "Windows 7 32-bit autoland pgo talos dromaeojs", 21:25:11 INFO - "buildid": "20160928183008", 21:25:11 INFO - "pgo_build": "True", 21:25:11 INFO - "basedir": "C:\\slave\\test", 21:25:11 INFO - "buildnumber": 107, 21:25:11 INFO - "slavename": "t-w732-ix-084", 21:25:11 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/", 21:25:11 INFO - "platform": "win7_ix", 21:25:11 INFO - "scheduler": "tests-autoland-win32-pgo-talos", 21:25:11 INFO - "branch": "autoland", 21:25:11 INFO - "repo_path": "integration/autoland", 21:25:11 INFO - "revision": "72f0acda20c0b6136058324bed919e2fb6c414c3", 21:25:11 INFO - "stage_platform": "win32-pgo", 21:25:11 INFO - "builduid": "cccfcf79934c45869359b22c674d0c24", 21:25:11 INFO - "slavebuilddir": "test" 21:25:11 INFO - } 21:25:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 21:25:11 INFO - Running post-action listener: _resource_record_post_action 21:25:11 INFO - [mozharness: 2016-09-29 04:25:11.314000Z] Finished read-buildbot-config step (success) 21:25:11 INFO - [mozharness: 2016-09-29 04:25:11.314000Z] Running download-and-extract step. 21:25:11 INFO - Running pre-action listener: _resource_record_pre_action 21:25:11 INFO - Running main action method: download_and_extract 21:25:11 INFO - mkdir: C:\slave\test\build\tests 21:25:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:25:11 INFO - trying https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 21:25:11 INFO - Downloading https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/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 21:25:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/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 21:25:12 INFO - Downloaded 1218 bytes. 21:25:12 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 21:25:12 INFO - Using the following test package requirements: 21:25:12 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 21:25:12 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:25:12 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 21:25:12 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 21:25:12 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:25:12 INFO - u'jsshell-win32.zip'], 21:25:12 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:25:12 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 21:25:12 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 21:25:12 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:25:12 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 21:25:12 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:25:12 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 21:25:12 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:25:12 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 21:25:12 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 21:25:12 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 21:25:12 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 21:25:12 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 21:25:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 21:25:12 INFO - Fetch https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 21:25:12 INFO - Http code: 200 21:25:12 INFO - Content-Encoding: None 21:25:12 INFO - Content-Type: application/zip 21:25:12 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 21:25:12 INFO - x-amz-cf-id: GQlMTLzo_XR-wlIzQqkwpgCAHml5hzeloHQByEXNXGrcMPlgrKpUJw== 21:25:12 INFO - x-amz-version-id: CbK3T1LA9dnXPsXw9cFkENRABGw.wIId 21:25:12 INFO - x-cache: Hit from cloudfront 21:25:13 INFO - Expected file size: 17325288 21:25:13 INFO - Obtained file size: 17325288 21:25:47 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 21:25:47 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 21:25:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 21:25:47 INFO - Fetch https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 21:25:48 INFO - Http code: 200 21:25:48 INFO - Content-Encoding: None 21:25:48 INFO - Content-Type: application/zip 21:25:48 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 21:25:48 INFO - x-amz-cf-id: DPjeSWv18tDApul6Q_FULwDcWRoGjut65Rpm5la5GFSeqlxhJE3wow== 21:25:48 INFO - x-amz-version-id: CbK3T1LA9dnXPsXw9cFkENRABGw.wIId 21:25:48 INFO - x-cache: Hit from cloudfront 21:25:48 INFO - Expected file size: 17325288 21:25:48 INFO - Obtained file size: 17325288 21:26:00 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 21:26:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 21:26:00 INFO - Fetch https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 21:26:01 INFO - Http code: 200 21:26:01 INFO - Content-Encoding: None 21:26:01 INFO - Content-Type: application/zip 21:26:01 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 21:26:01 INFO - x-amz-cf-id: CmAl_c5GdnTW0GQyJ0BiN1dV1Vp85oJmgkJoviUxZvo7wM19lb2vBQ== 21:26:01 INFO - x-amz-version-id: 3bgsMqVynhuvKW3dIxeUPdVUL_01JxBZ 21:26:01 INFO - x-cache: Hit from cloudfront 21:26:01 INFO - Expected file size: 14414067 21:26:01 INFO - Obtained file size: 14414067 21:26:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:05 INFO - trying https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 21:26:05 INFO - Downloading https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 21:26:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/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 21:26:08 INFO - Downloaded 54809873 bytes. 21:26:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 21:26:08 INFO - mkdir: C:\slave\test\properties 21:26:08 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 21:26:08 INFO - Writing to file C:\slave\test\properties\build_url 21:26:08 INFO - Contents: 21:26:08 INFO - build_url:https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 21:26:09 INFO - Running post-action listener: _resource_record_post_action 21:26:09 INFO - Running post-action listener: set_extra_try_arguments 21:26:09 INFO - [mozharness: 2016-09-29 04:26:09.084000Z] Finished download-and-extract step (success) 21:26:09 INFO - [mozharness: 2016-09-29 04:26:09.084000Z] Running populate-webroot step. 21:26:09 INFO - Running pre-action listener: _resource_record_pre_action 21:26:09 INFO - Running main action method: populate_webroot 21:26:09 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 21:26:09 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 21:26:09 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 21:26:09 INFO - u'--tppagecycles', 21:26:09 INFO - u'7'], 21:26:09 INFO - u'tests': [u'tsvgm']}}, 21:26:09 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 21:26:09 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 21:26:09 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 21:26:09 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 21:26:09 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 21:26:09 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 21:26:09 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:26:09 INFO - u'talos_options': [u'--disable-e10s'], 21:26:09 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 21:26:09 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:26:09 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 21:26:09 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:26:09 INFO - u'talos_options': [u'--disable-e10s'], 21:26:09 INFO - u'tests': [u'damp', u'tps']}, 21:26:09 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:26:09 INFO - u'tests': [u'damp', u'tps']}, 21:26:09 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 21:26:09 INFO - u'tests': [u'dromaeo_dom']}, 21:26:09 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 21:26:09 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 21:26:09 INFO - u'tests': [u'basic_compositor_video']}, 21:26:09 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 21:26:09 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 21:26:09 INFO - u'tests': [u'a11yr', 21:26:09 INFO - u'ts_paint', 21:26:09 INFO - u'tpaint', 21:26:09 INFO - u'sessionrestore', 21:26:09 INFO - u'sessionrestore_no_auto_restore', 21:26:09 INFO - u'tabpaint']}, 21:26:09 INFO - u'other-e10s': {u'tests': [u'a11yr', 21:26:09 INFO - u'ts_paint', 21:26:09 INFO - u'tpaint', 21:26:09 INFO - u'sessionrestore', 21:26:09 INFO - u'sessionrestore_no_auto_restore', 21:26:09 INFO - u'tabpaint']}, 21:26:09 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 21:26:09 INFO - u'ts_paint', 21:26:09 INFO - u'tpaint', 21:26:09 INFO - u'sessionrestore', 21:26:09 INFO - u'sessionrestore_no_auto_restore', 21:26:09 INFO - u'tabpaint']}, 21:26:09 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 21:26:09 INFO - u'ts_paint', 21:26:09 INFO - u'tpaint', 21:26:09 INFO - u'sessionrestore', 21:26:09 INFO - u'sessionrestore_no_auto_restore', 21:26:09 INFO - u'tabpaint']}, 21:26:09 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 21:26:09 INFO - u'tests': [u'a11yr', 21:26:09 INFO - u'ts_paint', 21:26:09 INFO - u'tpaint', 21:26:09 INFO - u'sessionrestore', 21:26:09 INFO - u'sessionrestore_no_auto_restore', 21:26:09 INFO - u'tabpaint']}, 21:26:09 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 21:26:09 INFO - u'tests': [u'a11yr', 21:26:09 INFO - u'ts_paint', 21:26:09 INFO - u'tpaint', 21:26:09 INFO - u'sessionrestore', 21:26:09 INFO - u'sessionrestore_no_auto_restore', 21:26:09 INFO - u'tabpaint']}, 21:26:09 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 21:26:09 INFO - u'tests': [u'tsvgx', 21:26:09 INFO - u'tsvgr_opacity', 21:26:09 INFO - u'tart', 21:26:09 INFO - u'tscrollx', 21:26:09 INFO - u'cart']}, 21:26:09 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 21:26:09 INFO - u'tsvgr_opacity', 21:26:09 INFO - u'tart', 21:26:09 INFO - u'tscrollx', 21:26:09 INFO - u'cart']}, 21:26:09 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:26:09 INFO - u'talos_options': [u'--disable-e10s'], 21:26:09 INFO - u'tests': [u'tp5o']}, 21:26:09 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:26:09 INFO - u'tests': [u'tp5o']}, 21:26:09 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:26:09 INFO - u'talos_options': [u'--disable-e10s', 21:26:09 INFO - u'--xperf_path', 21:26:09 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:26:09 INFO - u'tests': [u'tp5n']}, 21:26:09 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:26:09 INFO - u'talos_options': [u'--xperf_path', 21:26:09 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:26:09 INFO - u'tests': [u'tp5n']}}, 21:26:09 INFO - u'talos.zip': {u'path': u'', 21:26:09 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:26:09 INFO - Running post-action listener: _resource_record_post_action 21:26:09 INFO - [mozharness: 2016-09-29 04:26:09.176000Z] Finished populate-webroot step (success) 21:26:09 INFO - [mozharness: 2016-09-29 04:26:09.176000Z] Running create-virtualenv step. 21:26:09 INFO - Running pre-action listener: _resource_record_pre_action 21:26:09 INFO - Running main action method: create_virtualenv 21:26:09 INFO - Creating virtualenv C:\slave\test\build/venv 21:26:09 INFO - mkdir: C:\slave\test\build\venv\Scripts 21:26:09 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 21:26:09 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 21:26:09 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build 21:26:09 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 21:26:10 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 21:26:18 INFO - Installing distribute......................................................................................................................................................................................done. 21:26:19 INFO - Return code: 0 21:26:19 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 21:26:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 21:26:20 INFO - Reading from file tmpfile_stdout 21:26:20 INFO - Output received: 21:26:20 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 21:26:20 INFO - Using _rmtree_windows ... 21:26:20 INFO - Using _rmtree_windows ... 21:26:20 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 21:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:20 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01911640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C2E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A84520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-084', 'USERDOMAIN': 'T-W732-IX-084', '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-084', '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 21:26:20 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 21:26:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:26:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:26:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:26:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:26:20 INFO - 'COMPUTERNAME': 'T-W732-IX-084', 21:26:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:26:20 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:26:20 INFO - 'DCLOCATION': 'SCL3', 21:26:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:26:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:26:20 INFO - 'HOMEDRIVE': 'C:', 21:26:20 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:26:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:26:20 INFO - 'KTS_VERSION': '1.19c', 21:26:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:26:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-084', 21:26:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:26:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:26:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:20 INFO - 'MOZ_NO_REMOTE': '1', 21:26:20 INFO - 'NO_EM_RESTART': '1', 21:26:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:26:20 INFO - 'OS': 'Windows_NT', 21:26:20 INFO - 'OURDRIVE': 'C:', 21:26:20 INFO - 'PATH': 'C:\\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;', 21:26:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:26:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:26:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:26:20 INFO - 'PROCESSOR_LEVEL': '6', 21:26:20 INFO - 'PROCESSOR_REVISION': '1e05', 21:26:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:26:20 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:26:20 INFO - 'PROMPT': '$P$G', 21:26:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:26:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:26:20 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:26:20 INFO - 'PWD': 'C:\\slave\\test', 21:26:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:26:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:26:20 INFO - 'SYSTEMDRIVE': 'C:', 21:26:20 INFO - 'SYSTEMROOT': 'C:\\windows', 21:26:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:20 INFO - 'TEST1': 'testie', 21:26:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:20 INFO - 'USERDOMAIN': 'T-W732-IX-084', 21:26:20 INFO - 'USERNAME': 'cltbld', 21:26:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:26:20 INFO - 'WINDIR': 'C:\\windows', 21:26:20 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:26:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:26:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:26:21 INFO - Ignoring indexes: http://pypi.python.org/simple/ 21:26:21 INFO - Downloading/unpacking pip>=1.5 21:26:25 INFO - Running setup.py egg_info for package pip 21:26:25 INFO - warning: no files found matching 'pip\cacert.pem' 21:26:25 INFO - warning: no files found matching '*.html' under directory 'docs' 21:26:25 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 21:26:25 INFO - no previously-included directories found matching 'docs\_build\_sources' 21:26:25 INFO - Installing collected packages: pip 21:26:25 INFO - Found existing installation: pip 0.8.2 21:26:25 INFO - Uninstalling pip: 21:26:25 INFO - Successfully uninstalled pip 21:26:25 INFO - Running setup.py install for pip 21:26:25 INFO - warning: no files found matching 'pip\cacert.pem' 21:26:25 INFO - warning: no files found matching '*.html' under directory 'docs' 21:26:25 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 21:26:25 INFO - no previously-included directories found matching 'docs\_build\_sources' 21:26:25 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 21:26:25 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 21:26:25 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 21:26:25 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 21:26:25 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 21:26:25 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 21:26:25 INFO - Successfully installed pip 21:26:25 INFO - Cleaning up... 21:26:25 INFO - Return code: 0 21:26:25 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 21:26: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')]} 21:26:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26: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')]} 21:26:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26: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', '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 0x01911640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C2E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A84520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-084', 'USERDOMAIN': 'T-W732-IX-084', '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-084', '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 21:26: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', 'psutil>=3.1.1'] in C:\slave\test\build 21:26: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 psutil>=3.1.1 21:26:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:26:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:26:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:26:25 INFO - 'COMPUTERNAME': 'T-W732-IX-084', 21:26:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:26:25 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:26:25 INFO - 'DCLOCATION': 'SCL3', 21:26:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:26:25 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:26:25 INFO - 'HOMEDRIVE': 'C:', 21:26:25 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:26:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:26:25 INFO - 'KTS_VERSION': '1.19c', 21:26:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:26:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-084', 21:26:25 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:26:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:25 INFO - 'MOZ_NO_REMOTE': '1', 21:26:25 INFO - 'NO_EM_RESTART': '1', 21:26:25 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:26:25 INFO - 'OS': 'Windows_NT', 21:26:25 INFO - 'OURDRIVE': 'C:', 21:26: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;', 21:26:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:26:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:26:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:26:25 INFO - 'PROCESSOR_LEVEL': '6', 21:26:25 INFO - 'PROCESSOR_REVISION': '1e05', 21:26:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:26:25 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:26:25 INFO - 'PROMPT': '$P$G', 21:26:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:26:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:26:25 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:26:25 INFO - 'PWD': 'C:\\slave\\test', 21:26:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:26:25 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:26:25 INFO - 'SYSTEMDRIVE': 'C:', 21:26:25 INFO - 'SYSTEMROOT': 'C:\\windows', 21:26:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:25 INFO - 'TEST1': 'testie', 21:26:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:25 INFO - 'USERDOMAIN': 'T-W732-IX-084', 21:26:25 INFO - 'USERNAME': 'cltbld', 21:26:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:26:25 INFO - 'WINDIR': 'C:\\windows', 21:26:25 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:26:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:26:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:26 INFO - Downloading/unpacking psutil>=3.1.1 21:26: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 21:26: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 21:26:28 INFO - Installing collected packages: psutil 21:26:28 INFO - Successfully installed psutil 21:26:28 INFO - Cleaning up... 21:26:28 INFO - Return code: 0 21:26:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 21:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:28 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01911640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C2E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A84520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-084', 'USERDOMAIN': 'T-W732-IX-084', '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-084', '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 21:26:28 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 21:26:28 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 21:26:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:26:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:26:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:26:28 INFO - 'COMPUTERNAME': 'T-W732-IX-084', 21:26:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:26:28 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:26:28 INFO - 'DCLOCATION': 'SCL3', 21:26:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:26:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:26:28 INFO - 'HOMEDRIVE': 'C:', 21:26:28 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:26:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:26:28 INFO - 'KTS_VERSION': '1.19c', 21:26:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:26:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-084', 21:26:28 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:26:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:26:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:28 INFO - 'MOZ_NO_REMOTE': '1', 21:26:28 INFO - 'NO_EM_RESTART': '1', 21:26:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:26:28 INFO - 'OS': 'Windows_NT', 21:26:28 INFO - 'OURDRIVE': 'C:', 21:26:28 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:26:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:26:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:26:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:26:28 INFO - 'PROCESSOR_LEVEL': '6', 21:26:28 INFO - 'PROCESSOR_REVISION': '1e05', 21:26:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:26:28 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:26:28 INFO - 'PROMPT': '$P$G', 21:26:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:26:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:26:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:26:28 INFO - 'PWD': 'C:\\slave\\test', 21:26:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:26:28 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:26:28 INFO - 'SYSTEMDRIVE': 'C:', 21:26:28 INFO - 'SYSTEMROOT': 'C:\\windows', 21:26:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:28 INFO - 'TEST1': 'testie', 21:26:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:28 INFO - 'USERDOMAIN': 'T-W732-IX-084', 21:26:28 INFO - 'USERNAME': 'cltbld', 21:26:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:26:28 INFO - 'WINDIR': 'C:\\windows', 21:26:28 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:26:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:26:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:26:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:26: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 21:26: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 21:26:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:26:29 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 21:26:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3) 21:26:29 INFO - Installing collected packages: mozsystemmonitor 21:26:29 INFO - Running setup.py install for mozsystemmonitor 21:26:29 INFO - Successfully installed mozsystemmonitor 21:26:29 INFO - Cleaning up... 21:26:30 INFO - Return code: 0 21:26:30 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 21:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:30 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01911640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C2E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A84520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-084', 'USERDOMAIN': 'T-W732-IX-084', '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-084', '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 21:26:30 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build 21:26:30 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:26:30 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:26:30 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:26:30 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:26:30 INFO - 'COMPUTERNAME': 'T-W732-IX-084', 21:26:30 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:26:30 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:26:30 INFO - 'DCLOCATION': 'SCL3', 21:26:30 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:26:30 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:26:30 INFO - 'HOMEDRIVE': 'C:', 21:26:30 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:26:30 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:26:30 INFO - 'KTS_VERSION': '1.19c', 21:26:30 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:26:30 INFO - 'LOGONSERVER': '\\\\T-W732-IX-084', 21:26:30 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:26:30 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:26:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:30 INFO - 'MOZ_NO_REMOTE': '1', 21:26:30 INFO - 'NO_EM_RESTART': '1', 21:26:30 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:26:30 INFO - 'OS': 'Windows_NT', 21:26:30 INFO - 'OURDRIVE': 'C:', 21:26:30 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 21:26:30 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:26:30 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:26:30 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:26:30 INFO - 'PROCESSOR_LEVEL': '6', 21:26:30 INFO - 'PROCESSOR_REVISION': '1e05', 21:26:30 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:26:30 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:26:30 INFO - 'PROMPT': '$P$G', 21:26:30 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:26:30 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:26:30 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:26:30 INFO - 'PWD': 'C:\\slave\\test', 21:26:30 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:26:30 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:26:30 INFO - 'SYSTEMDRIVE': 'C:', 21:26:30 INFO - 'SYSTEMROOT': 'C:\\windows', 21:26:30 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:30 INFO - 'TEST1': 'testie', 21:26:30 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:30 INFO - 'USERDOMAIN': 'T-W732-IX-084', 21:26:30 INFO - 'USERNAME': 'cltbld', 21:26:30 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:26:30 INFO - 'WINDIR': 'C:\\windows', 21:26:30 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:26:30 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:26:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:26:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:30 INFO - Downloading/unpacking jsonschema==2.5.1 21:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:26:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:31 INFO - Downloading functools32-3.2.3-2.tar.gz 21:26:31 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 21:26:31 INFO - warning: no files found matching '*.txt' 21:26:31 INFO - no previously-included directories found matching 'build' 21:26:31 INFO - no previously-included directories found matching 'dist' 21:26:31 INFO - no previously-included directories found matching '.git*' 21:26:31 INFO - Installing collected packages: jsonschema, functools32 21:26:31 INFO - Running setup.py install for functools32 21:26:32 INFO - warning: no files found matching '*.txt' 21:26:32 INFO - no previously-included directories found matching 'build' 21:26:32 INFO - no previously-included directories found matching 'dist' 21:26:32 INFO - no previously-included directories found matching '.git*' 21:26:32 INFO - Successfully installed jsonschema functools32 21:26:32 INFO - Cleaning up... 21:26:32 INFO - Return code: 0 21:26:32 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 21:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:32 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 0x01911640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C2E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A84520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-084', 'USERDOMAIN': 'T-W732-IX-084', '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-084', '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 21:26:32 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build 21:26:32 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:26:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:26:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:26:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:26:32 INFO - 'COMPUTERNAME': 'T-W732-IX-084', 21:26:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:26:32 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:26:32 INFO - 'DCLOCATION': 'SCL3', 21:26:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:26:32 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:26:32 INFO - 'HOMEDRIVE': 'C:', 21:26:32 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:26:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:26:32 INFO - 'KTS_VERSION': '1.19c', 21:26:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:26:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-084', 21:26:32 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:26:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:26:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:32 INFO - 'MOZ_NO_REMOTE': '1', 21:26:32 INFO - 'NO_EM_RESTART': '1', 21:26:32 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:26:32 INFO - 'OS': 'Windows_NT', 21:26:32 INFO - 'OURDRIVE': 'C:', 21:26:32 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;', 21:26:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:26:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:26:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:26:32 INFO - 'PROCESSOR_LEVEL': '6', 21:26:32 INFO - 'PROCESSOR_REVISION': '1e05', 21:26:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:26:32 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:26:32 INFO - 'PROMPT': '$P$G', 21:26:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:26:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:26:32 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:26:32 INFO - 'PWD': 'C:\\slave\\test', 21:26:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:26:32 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:26:32 INFO - 'SYSTEMDRIVE': 'C:', 21:26:32 INFO - 'SYSTEMROOT': 'C:\\windows', 21:26:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:32 INFO - 'TEST1': 'testie', 21:26:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:32 INFO - 'USERDOMAIN': 'T-W732-IX-084', 21:26:32 INFO - 'USERNAME': 'cltbld', 21:26:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:26:32 INFO - 'WINDIR': 'C:\\windows', 21:26:32 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:26:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:26:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:26:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 21:26:32 INFO - Cleaning up... 21:26:32 INFO - Return code: 0 21:26:32 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 21:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:32 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 0x01911640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C2E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A84520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-084', 'USERDOMAIN': 'T-W732-IX-084', '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-084', '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 21:26:33 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build 21:26:33 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:26:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:26:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:26:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:26:33 INFO - 'COMPUTERNAME': 'T-W732-IX-084', 21:26:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:26:33 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:26:33 INFO - 'DCLOCATION': 'SCL3', 21:26:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:26:33 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:26:33 INFO - 'HOMEDRIVE': 'C:', 21:26:33 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:26:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:26:33 INFO - 'KTS_VERSION': '1.19c', 21:26:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:26:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-084', 21:26:33 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:26:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:26:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:33 INFO - 'MOZ_NO_REMOTE': '1', 21:26:33 INFO - 'NO_EM_RESTART': '1', 21:26:33 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:26:33 INFO - 'OS': 'Windows_NT', 21:26:33 INFO - 'OURDRIVE': 'C:', 21:26:33 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;', 21:26:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:26:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:26:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:26:33 INFO - 'PROCESSOR_LEVEL': '6', 21:26:33 INFO - 'PROCESSOR_REVISION': '1e05', 21:26:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:26:33 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:26:33 INFO - 'PROMPT': '$P$G', 21:26:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:26:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:26:33 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:26:33 INFO - 'PWD': 'C:\\slave\\test', 21:26:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:26:33 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:26:33 INFO - 'SYSTEMDRIVE': 'C:', 21:26:33 INFO - 'SYSTEMROOT': 'C:\\windows', 21:26:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:33 INFO - 'TEST1': 'testie', 21:26:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:33 INFO - 'USERDOMAIN': 'T-W732-IX-084', 21:26:33 INFO - 'USERNAME': 'cltbld', 21:26:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:26:33 INFO - 'WINDIR': 'C:\\windows', 21:26:33 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:26:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:26:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:26:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:33 INFO - Downloading/unpacking blobuploader==1.2.4 21:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:34 INFO - Downloading blobuploader-1.2.4.tar.gz 21:26:34 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 21:26:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:34 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 21:26:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:26:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:35 INFO - Downloading docopt-0.6.1.tar.gz 21:26:35 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 21:26:35 INFO - Installing collected packages: blobuploader, requests, docopt 21:26:35 INFO - Running setup.py install for blobuploader 21:26:35 INFO - Running setup.py install for requests 21:26:36 INFO - Running setup.py install for docopt 21:26:36 INFO - Successfully installed blobuploader requests docopt 21:26:36 INFO - Cleaning up... 21:26:38 INFO - Return code: 0 21:26:38 INFO - Installing None into virtualenv C:\slave\test\build/venv 21:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:38 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 0x01911640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C2E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A84520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-084', 'USERDOMAIN': 'T-W732-IX-084', '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-084', '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 21:26:38 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 21:26:38 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:26:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:26:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:26:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:26:38 INFO - 'COMPUTERNAME': 'T-W732-IX-084', 21:26:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:26:38 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:26:38 INFO - 'DCLOCATION': 'SCL3', 21:26:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:26:38 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:26:38 INFO - 'HOMEDRIVE': 'C:', 21:26:38 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:26:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:26:38 INFO - 'KTS_VERSION': '1.19c', 21:26:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:26:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-084', 21:26:38 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:26:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:26:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:38 INFO - 'MOZ_NO_REMOTE': '1', 21:26:38 INFO - 'NO_EM_RESTART': '1', 21:26:38 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:26:38 INFO - 'OS': 'Windows_NT', 21:26:38 INFO - 'OURDRIVE': 'C:', 21:26:38 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;', 21:26:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:26:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:26:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:26:38 INFO - 'PROCESSOR_LEVEL': '6', 21:26:38 INFO - 'PROCESSOR_REVISION': '1e05', 21:26:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:26:38 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:26:38 INFO - 'PROMPT': '$P$G', 21:26:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:26:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:26:38 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:26:38 INFO - 'PWD': 'C:\\slave\\test', 21:26:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:26:38 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:26:38 INFO - 'SYSTEMDRIVE': 'C:', 21:26:38 INFO - 'SYSTEMROOT': 'C:\\windows', 21:26:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:38 INFO - 'TEST1': 'testie', 21:26:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:38 INFO - 'USERDOMAIN': 'T-W732-IX-084', 21:26:38 INFO - 'USERNAME': 'cltbld', 21:26:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:26:38 INFO - 'WINDIR': 'C:\\windows', 21:26:38 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:26:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:26:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:26:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 21:26:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kinbuw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 21:26:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 21:26:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qwztdb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 21:26:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 21:26:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xkq7zp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 21:26:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 21:26:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sguk2x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 21:26:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 21:26:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mb0qbg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 21:26:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 21:26:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wri3lg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 21:26:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 21:26:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jyfcca-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 21:26:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 21:26:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-knfpal-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 21:26:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 21:26:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2ngxdy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 21:26:39 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 21:26:39 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ycy1we-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 21:26:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 21:26:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lujcbz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 21:26:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 21:26:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-clymi3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 21:26:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 21:26:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eup1as-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 21:26:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 21:26:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pcbzqs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 21:26:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 21:26:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-iwqis9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 21:26:40 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 21:26:40 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mrubdk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 21:26:41 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 21:26:41 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-snmq98-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 21:26:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:26:41 INFO - Running setup.py install for manifestparser 21:26:41 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 21:26:41 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 21:26:42 INFO - Running setup.py install for mozcrash 21:26:42 INFO - Running setup.py install for mozdebug 21:26:42 INFO - Running setup.py install for mozdevice 21:26:42 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 21:26:42 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 21:26:42 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 21:26:42 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 21:26:43 INFO - Running setup.py install for mozfile 21:26:43 INFO - Running setup.py install for mozhttpd 21:26:43 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 21:26:43 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 21:26:44 INFO - Running setup.py install for mozinfo 21:26:44 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 21:26:44 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 21:26:44 INFO - Running setup.py install for mozInstall 21:26:44 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 21:26:44 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 21:26:44 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 21:26:44 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 21:26:44 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 21:26:44 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 21:26:44 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 21:26:44 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 21:26:44 INFO - Running setup.py install for mozleak 21:26:45 INFO - Running setup.py install for mozlog 21:26:45 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 21:26:45 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 21:26:45 INFO - Running setup.py install for moznetwork 21:26:46 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 21:26:46 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 21:26:46 INFO - Running setup.py install for mozprocess 21:26:46 INFO - Running setup.py install for mozprofile 21:26:47 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 21:26:47 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 21:26:47 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 21:26:47 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 21:26:47 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 21:26:47 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 21:26:47 INFO - Running setup.py install for mozrunner 21:26:47 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 21:26:47 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 21:26:48 INFO - Running setup.py install for mozscreenshot 21:26:48 INFO - Running setup.py install for moztest 21:26:48 INFO - Running setup.py install for mozversion 21:26:49 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 21:26:49 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 21:26:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:26:49 INFO - Cleaning up... 21:26:49 INFO - Return code: 0 21:26:49 INFO - Installing None into virtualenv C:\slave\test\build/venv 21:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:49 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 0x01911640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C2E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A84520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-084', 'USERDOMAIN': 'T-W732-IX-084', '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-084', '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 21:26:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 21:26:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:26:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:26:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:26:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:26:49 INFO - 'COMPUTERNAME': 'T-W732-IX-084', 21:26:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:26:49 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:26:49 INFO - 'DCLOCATION': 'SCL3', 21:26:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:26:49 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:26:49 INFO - 'HOMEDRIVE': 'C:', 21:26:49 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:26:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:26:49 INFO - 'KTS_VERSION': '1.19c', 21:26:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:26:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-084', 21:26:49 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:26:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:26:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:49 INFO - 'MOZ_NO_REMOTE': '1', 21:26:49 INFO - 'NO_EM_RESTART': '1', 21:26:49 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:26:49 INFO - 'OS': 'Windows_NT', 21:26:49 INFO - 'OURDRIVE': 'C:', 21:26:49 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;', 21:26:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:26:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:26:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:26:49 INFO - 'PROCESSOR_LEVEL': '6', 21:26:49 INFO - 'PROCESSOR_REVISION': '1e05', 21:26:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:26:49 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:26:49 INFO - 'PROMPT': '$P$G', 21:26:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:26:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:26:49 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:26:49 INFO - 'PWD': 'C:\\slave\\test', 21:26:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:26:49 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:26:49 INFO - 'SYSTEMDRIVE': 'C:', 21:26:49 INFO - 'SYSTEMROOT': 'C:\\windows', 21:26:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:49 INFO - 'TEST1': 'testie', 21:26:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:49 INFO - 'USERDOMAIN': 'T-W732-IX-084', 21:26:49 INFO - 'USERNAME': 'cltbld', 21:26:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:26:49 INFO - 'WINDIR': 'C:\\windows', 21:26:49 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:26:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:26:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:26:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 21:26:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rpbgp4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 21:26:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1)) 21:26:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 21:26:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rkbyp2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 21:26:50 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)) 21:26:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 21:26:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rdem0q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 21:26:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 21:26:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 21:26:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nopejs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 21:26:50 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)) 21:26:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 21:26:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-opb3ix-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 21:26:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5)) 21:26:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 21:26:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ykpaig-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 21:26:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6)) 21:26:50 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 21:26:50 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qbdejs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 21:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7)) 21:26:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 21:26:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x2qzxq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 21:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8)) 21:26:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 21:26:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6avebp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 21:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9)) 21:26:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 21:26:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-umlwyv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 21:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 21:26:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 21:26:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bd8cst-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 21:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11)) 21:26:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 21:26:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l8sc_p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 21:26:51 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)) 21:26:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 21:26:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bxwwcf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 21:26:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13)) 21:26:51 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 21:26:51 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ovteda-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 21:26:52 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)) 21:26:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 21:26:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g02npc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 21:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15)) 21:26:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 21:26:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qfiufq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 21:26:52 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)) 21:26:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 21:26:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wljj43-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 21:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17)) 21:26:52 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)) 21:26:52 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)) 21:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 21:26:52 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)) 21:26:52 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)) 21:26:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 21:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:26:53 INFO - Downloading blessings-1.6.tar.gz 21:26:53 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 21:26:53 INFO - Installing collected packages: blessings 21:26:53 INFO - Running setup.py install for blessings 21:26:54 INFO - Successfully installed blessings 21:26:54 INFO - Cleaning up... 21:26:56 INFO - Return code: 0 21:26:56 INFO - Done creating virtualenv C:\slave\test\build/venv. 21:26:56 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 21:26:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 21:26:57 INFO - Reading from file tmpfile_stdout 21:26:57 INFO - Using _rmtree_windows ... 21:26:57 INFO - Using _rmtree_windows ... 21:26:57 INFO - Current package versions: 21:26:57 INFO - blessings == 1.6 21:26:57 INFO - blobuploader == 1.2.4 21:26:57 INFO - docopt == 0.6.1 21:26:57 INFO - functools32 == 3.2.3-2 21:26:57 INFO - jsonschema == 2.5.1 21:26:57 INFO - manifestparser == 1.1 21:26:57 INFO - mozInstall == 1.12 21:26:57 INFO - mozcrash == 0.17 21:26:57 INFO - mozdebug == 0.1 21:26:57 INFO - mozdevice == 0.48 21:26:57 INFO - mozfile == 1.2 21:26:57 INFO - mozhttpd == 0.7 21:26:57 INFO - mozinfo == 0.9 21:26:57 INFO - mozleak == 0.1 21:26:57 INFO - mozlog == 3.4 21:26:57 INFO - moznetwork == 0.27 21:26:57 INFO - mozprocess == 0.23 21:26:57 INFO - mozprofile == 0.28 21:26:57 INFO - mozrunner == 6.12 21:26:57 INFO - mozscreenshot == 0.1 21:26:57 INFO - mozsystemmonitor == 0.3 21:26:57 INFO - moztest == 0.7 21:26:57 INFO - mozversion == 1.4 21:26:57 INFO - psutil == 3.1.1 21:26:57 INFO - requests == 1.2.3 21:26:57 INFO - Installing None into virtualenv C:\slave\test\build/venv 21:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:57 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 0x01911640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C2E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A84520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-084', 'USERDOMAIN': 'T-W732-IX-084', '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-084', '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 21:26:57 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos 21:26:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:26:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:26:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:26:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:26:57 INFO - 'COMPUTERNAME': 'T-W732-IX-084', 21:26:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:26:57 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:26:57 INFO - 'DCLOCATION': 'SCL3', 21:26:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:26:57 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:26:57 INFO - 'HOMEDRIVE': 'C:', 21:26:57 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:26:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:26:57 INFO - 'KTS_VERSION': '1.19c', 21:26:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:26:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-084', 21:26:57 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:26:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:57 INFO - 'MOZ_NO_REMOTE': '1', 21:26:57 INFO - 'NO_EM_RESTART': '1', 21:26:57 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:26:57 INFO - 'OS': 'Windows_NT', 21:26:57 INFO - 'OURDRIVE': 'C:', 21:26:57 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;', 21:26:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:26:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:26:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:26:57 INFO - 'PROCESSOR_LEVEL': '6', 21:26:57 INFO - 'PROCESSOR_REVISION': '1e05', 21:26:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:26:57 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:26:57 INFO - 'PROMPT': '$P$G', 21:26:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:26:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:26:57 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:26:57 INFO - 'PWD': 'C:\\slave\\test', 21:26:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:26:57 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:26:57 INFO - 'SYSTEMDRIVE': 'C:', 21:26:57 INFO - 'SYSTEMROOT': 'C:\\windows', 21:26:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:57 INFO - 'TEST1': 'testie', 21:26:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:57 INFO - 'USERDOMAIN': 'T-W732-IX-084', 21:26:57 INFO - 'USERNAME': 'cltbld', 21:26:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:26:57 INFO - 'WINDIR': 'C:\\windows', 21:26:57 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:26:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 21:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2)) 21:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3)) 21:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 21:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5)) 21:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6)) 21:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7)) 21:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8)) 21:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9)) 21:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 21:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 21:26:57 INFO - Cleaning up... 21:26:57 INFO - Return code: 0 21:26:57 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 21:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:26:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:57 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 0x01911640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019B5E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018BB930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019C2E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A84520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-084', 'USERDOMAIN': 'T-W732-IX-084', '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-084', '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 21:26:57 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build 21:26:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:26:57 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:26:57 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:26:57 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:26:57 INFO - 'COMPUTERNAME': 'T-W732-IX-084', 21:26:57 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:26:57 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:26:57 INFO - 'DCLOCATION': 'SCL3', 21:26:57 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:26:57 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:26:57 INFO - 'HOMEDRIVE': 'C:', 21:26:57 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:26:57 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:26:57 INFO - 'KTS_VERSION': '1.19c', 21:26:57 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:26:57 INFO - 'LOGONSERVER': '\\\\T-W732-IX-084', 21:26:57 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:26:57 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:26:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:57 INFO - 'MOZ_NO_REMOTE': '1', 21:26:57 INFO - 'NO_EM_RESTART': '1', 21:26:57 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:26:57 INFO - 'OS': 'Windows_NT', 21:26:57 INFO - 'OURDRIVE': 'C:', 21:26:57 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;', 21:26:57 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:26:57 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:26:57 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:26:57 INFO - 'PROCESSOR_LEVEL': '6', 21:26:57 INFO - 'PROCESSOR_REVISION': '1e05', 21:26:57 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:26:57 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:26:57 INFO - 'PROMPT': '$P$G', 21:26:57 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:26:57 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:26:57 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:26:57 INFO - 'PWD': 'C:\\slave\\test', 21:26:57 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:26:57 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:26:57 INFO - 'SYSTEMDRIVE': 'C:', 21:26:57 INFO - 'SYSTEMROOT': 'C:\\windows', 21:26:57 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:57 INFO - 'TEST1': 'testie', 21:26:57 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:26:57 INFO - 'USERDOMAIN': 'T-W732-IX-084', 21:26:57 INFO - 'USERNAME': 'cltbld', 21:26:57 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:26:57 INFO - 'WINDIR': 'C:\\windows', 21:26:57 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:26:57 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:26:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:26:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 21:26:57 INFO - Cleaning up... 21:26:57 INFO - Return code: 0 21:26:57 INFO - Running post-action listener: _resource_record_post_action 21:26:57 INFO - Running post-action listener: _start_resource_monitoring 21:26:58 INFO - Starting resource monitoring. 21:26:58 INFO - [mozharness: 2016-09-29 04:26:58.106000Z] Finished create-virtualenv step (success) 21:26:58 INFO - [mozharness: 2016-09-29 04:26:58.107000Z] Running install step. 21:26:58 INFO - Running pre-action listener: _resource_record_pre_action 21:26:58 INFO - Running main action method: install 21:26:58 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 21:26:58 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 21:26:58 INFO - Reading from file tmpfile_stdout 21:26:58 INFO - Using _rmtree_windows ... 21:26:58 INFO - Using _rmtree_windows ... 21:26:58 INFO - Detecting whether we're running mozinstall >=1.0... 21:26:58 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 21:26:58 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 21:26:58 INFO - Reading from file tmpfile_stdout 21:26:58 INFO - Output received: 21:26:58 INFO - Usage: mozinstall-script.py [options] installer 21:26:58 INFO - Options: 21:26:58 INFO - -h, --help show this help message and exit 21:26:58 INFO - -d DEST, --destination=DEST 21:26:58 INFO - Directory to install application into. [default: 21:26:58 INFO - "C:\slave\test"] 21:26:58 INFO - --app=APP Application being installed. [default: firefox] 21:26:58 INFO - Using _rmtree_windows ... 21:26:58 INFO - Using _rmtree_windows ... 21:26:58 INFO - mkdir: C:\slave\test\build\application 21:26:58 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'] 21:26:58 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 21:27:01 INFO - Reading from file tmpfile_stdout 21:27:01 INFO - Output received: 21:27:01 INFO - C:\slave\test\build\application\firefox\firefox.exe 21:27:01 INFO - Using _rmtree_windows ... 21:27:01 INFO - Using _rmtree_windows ... 21:27:01 INFO - Running post-action listener: _resource_record_post_action 21:27:01 INFO - [mozharness: 2016-09-29 04:27:01.232000Z] Finished install step (success) 21:27:01 INFO - [mozharness: 2016-09-29 04:27:01.232000Z] Running run-tests step. 21:27:01 INFO - Running pre-action listener: _resource_record_pre_action 21:27:01 INFO - Running main action method: run_tests 21:27:01 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 21:27:01 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 21:27:01 INFO - Python 2.7.3 21:27:01 INFO - Return code: 0 21:27:01 INFO - grabbing minidump binary from tooltool 21:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:27:01 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 0x019C2E90>, '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 0x012FA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A84520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 21:27:01 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build 21:27:01 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o 21:27:01 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 21:27:01 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 21:27:03 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpnopyrp 21:27:03 INFO - INFO - File integrity verified, renaming tmpnopyrp to win32-minidump_stackwalk.exe 21:27:03 INFO - Return code: 0 21:27:03 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 21:27:03 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 21:27:03 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 21:27:03 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 21:27:03 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 21:27:03 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-084', '--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 21:27:03 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Autoland --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-084 --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 21:27:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 21:27:03 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 21:27:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 21:27:03 INFO - 'COMPUTERNAME': 'T-W732-IX-084', 21:27:03 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 21:27:03 INFO - 'CYGWINBASE': 'C:\\cygwin', 21:27:03 INFO - 'DCLOCATION': 'SCL3', 21:27:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 21:27:03 INFO - 'FP_NO_HOST_CHECK': 'NO', 21:27:03 INFO - 'HOMEDRIVE': 'C:', 21:27:03 INFO - 'HOMEPATH': '\\Users\\cltbld', 21:27:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 21:27:03 INFO - 'KTS_VERSION': '1.19c', 21:27:03 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 21:27:03 INFO - 'LOGONSERVER': '\\\\T-W732-IX-084', 21:27:03 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 21:27:03 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 21:27:03 INFO - 'MONDIR': 'C:\\Monitor_config\\', 21:27:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 21:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:27:03 INFO - 'MOZ_NO_REMOTE': '1', 21:27:03 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 21:27:03 INFO - 'NO_EM_RESTART': '1', 21:27:03 INFO - 'NUMBER_OF_PROCESSORS': '8', 21:27:03 INFO - 'OS': 'Windows_NT', 21:27:03 INFO - 'OURDRIVE': 'C:', 21:27:03 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;', 21:27:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 21:27:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 21:27:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 21:27:03 INFO - 'PROCESSOR_LEVEL': '6', 21:27:03 INFO - 'PROCESSOR_REVISION': '1e05', 21:27:03 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 21:27:03 INFO - 'PROGRAMFILES': 'C:\\Program Files', 21:27:03 INFO - 'PROMPT': '$P$G', 21:27:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 21:27:03 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 21:27:03 INFO - 'PUBLIC': 'C:\\Users\\Public', 21:27:03 INFO - 'PWD': 'C:\\slave\\test', 21:27:03 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 21:27:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 21:27:03 INFO - 'SLAVEDIR': 'C:\\slave\\', 21:27:03 INFO - 'SYSTEMDRIVE': 'C:', 21:27:03 INFO - 'SYSTEMROOT': 'C:\\windows', 21:27:03 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:27:03 INFO - 'TEST1': 'testie', 21:27:03 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 21:27:03 INFO - 'USERDOMAIN': 'T-W732-IX-084', 21:27:03 INFO - 'USERNAME': 'cltbld', 21:27:03 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 21:27:03 INFO - 'WINDIR': 'C:\\windows', 21:27:03 INFO - 'WINDOWS_TRACING_FLAGS': '3', 21:27:03 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 21:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 21:27:03 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-084', '--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 21:27:04 INFO - mozversion application_buildid: 20160928183008 21:27:04 INFO - mozversion application_changeset: 72f0acda20c0b6136058324bed919e2fb6c414c3 21:27:04 INFO - mozversion application_display_name: Nightly 21:27:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:27:04 INFO - mozversion application_name: Firefox 21:27:04 INFO - mozversion application_remotingname: firefox 21:27:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:27:04 INFO - mozversion application_vendor: Mozilla 21:27:04 INFO - mozversion application_version: 52.0a1 21:27:04 INFO - mozversion platform_buildid: 20160928183008 21:27:04 INFO - mozversion platform_changeset: 72f0acda20c0b6136058324bed919e2fb6c414c3 21:27:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:27:04 INFO - mozversion platform_version: 52.0a1 21:27:04 INFO - using testdate: 1475123224 21:27:04 INFO - actual date: 1475123224 21:27:04 INFO - starting webserver on 'localhost:49895' 21:27:04 INFO - SUITE-START | Running 2 tests 21:27:04 INFO - TEST-START | dromaeo_css 21:27:04 INFO - operating with platform_type : w7_ 21:27:04 INFO - Initialising browser for dromaeo_css test... 21:27:04 INFO - TEST-INFO | started process 776 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpquumsk\profile http://localhost:49895/getInfo.html) 21:27:10 INFO - PROCESS | 776 | __metrics Screen width/height:1600/1200 21:27:10 INFO - PROCESS | 776 | colorDepth:24 21:27:10 INFO - PROCESS | 776 | Browser inner width/height: 1010/674 21:27:10 INFO - PROCESS | 776 | __metrics 21:27:11 INFO - PROCESS | 776 | 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" 21:27:11 INFO - PROCESS | 776 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:27:12 INFO - TEST-INFO | 776: exit 0 21:27:12 INFO - Browser initialized. 21:27:12 INFO - Running cycle 1/1 for dromaeo_css test... 21:27:12 INFO - TEST-INFO | started process 3168 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpquumsk\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:27:13 INFO - PROCESS | 3168 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 21:27:24 INFO - PROCESS | 3168 | RSS: Main: 124436480 21:27:24 INFO - PROCESS | 3168 | 21:28:15 INFO - PROCESS | 3168 | 1475123295435 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] 21:30:24 INFO - PROCESS | 3168 | Cycle 1(1): loaded http://localhost:49895/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49895/tests/dromaeo/cssquery-ext.html) 21:30:25 INFO - PROCESS | 3168 | RSS: Main: 186925056 21:30:25 INFO - PROCESS | 3168 | 21:33:28 INFO - PROCESS | 3168 | Cycle 1(1): loaded http://localhost:49895/tests/dromaeo/cssquery-ext.html (next: http://localhost:49895/tests/dromaeo/cssquery-jquery.html) 21:33:28 INFO - PROCESS | 3168 | RSS: Main: 194912256 21:33:28 INFO - PROCESS | 3168 | 21:36:30 INFO - PROCESS | 3168 | Cycle 1(1): loaded http://localhost:49895/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49895/tests/dromaeo/cssquery-mootools.html) 21:36:31 INFO - PROCESS | 3168 | RSS: Main: 152039424 21:36:31 INFO - PROCESS | 3168 | 21:39:33 INFO - PROCESS | 3168 | Cycle 1(1): loaded http://localhost:49895/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49895/tests/dromaeo/cssquery-prototype.html) 21:39:33 INFO - PROCESS | 3168 | RSS: Main: 143769600 21:39:33 INFO - PROCESS | 3168 | 21:42:36 INFO - PROCESS | 3168 | Cycle 1(1): loaded http://localhost:49895/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49895/tests/dromaeo/cssquery-yui.html) 21:42:37 INFO - PROCESS | 3168 | RSS: Main: 133111808 21:42:37 INFO - PROCESS | 3168 | 21:45:39 INFO - PROCESS | 3168 | Cycle 1(1): loaded http://localhost:49895/tests/dromaeo/cssquery-yui.html (next: http://localhost:49895/tests/dromaeo/cssquery-dojo.html) 21:45:39 INFO - PROCESS | 3168 | RSS: Main: 181420032 21:45:39 INFO - PROCESS | 3168 | 21:45:39 INFO - PROCESS | 3168 | __start_tp_report 21:45:39 INFO - PROCESS | 3168 | _x_x_mozilla_page_load 21:45:39 INFO - PROCESS | 3168 | _x_x_mozilla_page_load_details 21:45:39 INFO - PROCESS | 3168 | |i|pagename|runs| 21:45:39 INFO - PROCESS | 3168 | |0;dojo.html;2570.9871450642745;2574.3950171709653;2575;2601.5577351850197;2710.972890271097;4338.956610433895;4513.14250292445;4516.322551617259;4520.434945631796;4592.540745925406;4649.30260460931;4687.812487500504;4732.526747325266;4814.1334559779225;4828.541288577589;3979.482667253253;4147.0461793787445;4175.3736939459095;4176.99752059505;4197.769122698251;3927.8428862845453;3958.010497375656;3967.801609919507;4020.1959608078355;4070.613291737295;3988.321985262498;4086.509618845728;4140.192662430827;4168.854090106847;4258.701890867641;3904.609539046101;3985.5416627087966;4140.8136633851545;4225.957740422587;4226.5139508956245;4091.5703851095504;4175;4183.414321994923;4186.079062606222;4193.245215861144;4462.576055274743;4580.290055041474;4641.094986477605;4737.526247375269;4740.1230772307235;4673.579377856009;4797.64017698674;4850.466448690641;4899.265110233458;4912.975435122838;4984.053029924303;4991.425986011619;4991.750412479398;5000.974995125038;5003.224500202475;4577.633789296849;4619.19164146273;4620.3762492063815;4629.884252893705;4632.675712700112;4775.5224477551965;4802.615790736732;4854.538818812278;5074.644774865724;5106.080905437057;4569.2689169732685;4573.49691533931;4574.062317224961;4584.1977653910435;4587.747673877969;4678.532146785295;4679.251319788817;4761.214399624093;4955.603551715854;5015.774290156879;4616.746078965689;4662.764367442631;4974.228994505857;4982.277569752366;4982.451930287665;4561.657875659339;4572.5198854119835;4685.625149987993;4688.671792974527;4742.264948932852;3106.782525223213;3272.361889431538;3291.9506207406907;3333.983330083334;3338.2488939988307;2826.3781967967016;2838.4890719670657;2843.5734639804195;2868.770498360126;3020.9848950755545;4712.95287047132;4720.984988227479;4722.055588882237;4729;4733.668643194943;4661.067786442725;4750.691205071659;4869.220924652039;4939.604831613534;4974.17926042199;4407.955920440819;4432.401625780542;4563.931541026887;4651.790669419885;4727.125481785881;3880.2821478026653;3885.300645883768;3896.4350169225304;3903.33643280643;4033.9193216135513;4914.5576898078625;5006.07387633289;5076.238564215323;5107.157319042391;5339.5728341732565;4662.766861656972;4769.807548112972;4855.465898751064;4967.40391153064;5030.874228144253;4941.777620007037;5044.949550504595;5263.973680131575;5355.571554275571;5378.354597448175;4854.757262136808;4880.389951256093;4890.535399137077;4931.235658472942;4935.6051515877925;4758.500357462417;4780.1873681473535;4814.277858321279;4841.540053694894;4997.400311962588;4732.834350797705;4894.094592500399;4970.105381031449;4995.750212489434;4995.825146119869;4583.587477126941;4863.173260545786;4864.148773964613;4973.278874563241;4981.352424184833;4566.771661416982;4695.929561056519;4732.810687572459;4732.952670473389;4746.905061898812;4559.817607295804;4753.429588449269;4797.592204662643;4870.245112007645;4963.404391473046;4598.241290187082;4680.555347242007;4753.191957367187;4796.472388037243;4888.437829649636 21:45:39 INFO - PROCESS | 3168 | |1;ext.html;511.59839525972114;529.3621186470301;543.5787264869723;547.0071819647341;551.9613627046106;29518.85240573797;30919.845400772963;31176.064718058493;31212.063638090865;31256;31130.533042004332;31551.526727099113;40046.79742614155;41909.16181676365;42409.57590424095;7623.923760762377;8232.1356257593;8545.487270763742;8640.39517233794;8677.739667809976;8576.69981550646;8645.265152462049;8660.263877570414;8661.69684061058;8677.305815534743;8120.2691757741795;8578.699745508908;8681.956590217073;8710.695125670603;8715.433496822718;7996.400269979746;8031.9598402007905;8041.075276343207;8052.637631306605;8626.956865215696;8102.513849169069;8152.959235203845;8548.74353769388;8606.913930860737;8609.913900860975;36818;36960.63039369599;39418;39462;39465;2899.884004639812;3004.1438190115714;3035.2260173655795;3107.254258977852;3141.937161256762;29868.402631947458;30253.0924072276;31076.689233107605;31151.688483115104;31496.212594685087;3447.672471115265;3575.2134530403528;3618.8371523281517;3625.6374362563533;3682.815859207029;330845;339829;339924;340601;341200.9999999975;50470.24294635584;52926.73536632292;54145.1878221827;54343;54371;27773.166804995883;28444.431111377657;55918.440815591326;55942.440575593726;56119.43880561224;12965.416556254802;13012.869871301355;13123.934380328037;13146;13158.539451119164;10527.94736026315;10574.788504229873;10600.257981941344;10620.468976551296;10636.734081648021;17658.381956631714;17701.46895593134;17718.91140544289;17727.645447090985;17740.645187096186;6536.379043990813;6644.607941141186;6717.731290748413;6730.860520779783;6854.691538880762;748.1695318196797;851.5997481183833;873.7204094689765;922.6678395777515;980.1227901028682;28390;28880.98916537953;29031.419371612872;29452.410951780843;29514.262143446587;748.988765168523;826.9958650206711;850.7174516197923;910.1048405085854;918.3709159225951;13032.54386096482;14889.553313400615;15000.399984000736;15098.245087745569;15244.695106097815;500903;503114;532563;534724;535684;47985.28022079672;50571;51698.74150629223;51707.882805196314;51776;950.2682934140811;973.0658567774874;1070.475467021154;1090.9945450272858;1115.8493603363604;27340.863295683394;29429.26426839346;29730.256743581584;30151.09546713602;30498.88265084274;44253.77873110614;48124.75937620289;48962.75518622384;49407;51801.48198518117;468579;469896;490095;498854.6660734101;505229;29573.2606684826;31655.366892662478;35679;36186.819065905554;38104.428433574074;41478;41833.79083104565;42158.57841421669;42709;44821;34802.303953921284;35407;36095.81952090223;36798.63201368059;37639.24721505609;35833.28333433369;36713.81643091767;36853.26293474169;37963.24073518459;38652.613473866026;34970.47544286787;35919.28161436809;36408.27183456263;36650.083747906516;36727.449088263165;30300.54549181808;31870.043898683078;31877.20306992344;31933.36133277275;32836.179095522806;24646.753532464445;24982.62526062074;25680.871595641904;26026.60960085638;26143.607845881947 21:45:39 INFO - PROCESS | 3168 | |2;jquery.html;284.6470376732852;308.7206078498958;308.79310861722655;310.0357886971519;318.7450039968025;13388.196708197504;17994.640107197847;18146.72779908299;18208.45374638763;19588;16967.15164241786;17341.566460838487;17474.56313592161;17900.462986110404;18527.72208416875;5211.244369566411;5412.4587541245855;5540.63985840921;5597.076482380402;5610.803621873235;5072.442031376546;5129.974350128263;5134.306868572751;5442.265294185294;5461.918071228934;5397.54120899724;5422.267993820843;5430.945690543083;5431.891362172754;5433.320834895638;5240.082985477546;5432.2123292122815;5433.049216387136;5469.753861076261;5473.425290344536;4523.7285762854335;4558.680892337537;4578.404807375054;4715.599174070208;4729.196036673774;21841.235556755437;23203.53592928149;23252.790984856412;23421.297361079196;23460.530789384116;1843.6497065557635;2088.498760297533;2474.5669507836055;2590.430105376814;2730.7405796449307;13444.579975604387;14269.215193164371;14605.12369257848;14705.117692938458;14834.629134271621;1431.291510702209;1497.3561368611493;1525.4279645133074;1574.283700916092;1588.2297085913324;79267;84889.17998690013;86101.56949215214;88460;89354.99999999935;8806.911930880737;8897.065808090058;8914.33142514314;9047.638094476279;9289.489078100634;9731.708048758548;10215.438150901624;10319.1744660427;11467.369294688706;11485.0811935045;6654.933450665431;6657.23441804189;6746.966265168643;6836.6923488443135;6965.19900211472;6578.671066446658;6847.144106986627;6912.688928998207;6961.721531138799;7011.544249623758;5740.512056475163;5798.565107616945;5813.476787089182;5849.736761845642;5907.911381329283;1479.3860547872728;1498.3706843125913;1502.8572285632847;1505.3000354835053;1535.247728612994;796.442490256811;806.5281810141016;815.8531464336357;826.6610689617228;838.3586556284447;18555.25778968853;18714.71927921082;18809.623807523774;18997.145128469252;19451.416457506297;865.9220670139591;866.5060915278357;887.8135591525852;917.2295271971576;950.2540505703129;10118.241131915136;10631.52158152885;10734.73163170927;10784.56861725538;11230.55077796879;371872;376046.19191641355;394442;395503;400687;74205.25794742092;83312;88102.55948720215;89844.55077724569;89947;734.5702763883081;764.6215123513896;784.6625950841193;788.2039140468054;804.3002587748579;16834.831651683326;16851.15744212809;17184.22670979834;17413.825861741727;17552.297908083532;33718.831405842815;41939.580604193565;44421;44603.33095003643;45618.543814562756;60622.69688651529;61913;64342.67828661014;66419.33580664132;66539.33460665331;7364.742234021784;7547.471676982558;7612.352949999312;7629.389648828192;7782.844343112993;8626.78433039179;13320.733585328433;13435.395407206895;13550.52573159974;14229.786553201504;10507.527161277914;10558.524866381185;10948.890511094787;10977.847326030651;11225.66323010311;10710.250282480152;10863.076638485818;10878.945605271923;11509.769804603693;11549.826752598885;11234;11305.943470282597;11372.374519401512;11893.702657433634;12050.035997120385;7164.140303163654;7303.123625165014;7317.524360916524;7406.851862962818;7659.463837531543;7405.85251268198;7417.36952359034;7499.925000749922;7582.279683430065;7594.468387212842 21:45:39 INFO - PROCESS | 3168 | |3;mootools.html;1161.7037655397871;1164.0396672744985;1270.9936450317748;1499.4077339450916;1529.4646873594236;6873.106496155499;7314.122305323369;7319.7438089666875;7323.121225452946;7323.523970941892;6224.6887655617265;6369.299377068531;6408.423241908227;6470.935290647092;7039.084918960528;1913.550315675815;1956.9902150489227;1958.5593241520614;1961.9705704414373;1964.5088727818045;1895.943121706344;1899.154876080145;1972.0337034852962;1983.4743792894894;1983.6429442700307;1660.1450253119654;1704.9488515344497;1820.2264037783955;1867.561123136062;1965.8427325813973;1933.8452923766135;1986.692062730279;1987.6024795040905;1990.6715391960308;1999.1303782854504;1794.3899074314666;1884.2463014794191;1894.7252648366048;1898.2691663709572;1900.2019151956213;6975.651217439107;7119.608421536814;7239.746608868717;7249.347558719741;7250.492465527415;4355;4429.977850110761;4439.378487011821;4450.933236001462;4460.531644177379;2828.4625921075135;2838.134369017449;2931.6482022157265;2969.331900322432;3010.503266960971;925.4909799610188;954.0650162840456;959.0744931141412;970.7573106723319;987.4371608183338;9185.632574697003;9194.35639505235;9248.6300547978;9257.90742092584;9269.629214831399;3694.815259237081;3731.1791405890663;3891.105045839416;3893.260280546687;3893.4549163117185;8073.717419890393;8111.432199745961;8134.918650813416;8159.143289954469;8165.714200002972;4134.793260336971;4200.306949353384;4351.869443916624;4415.050764085737;4430.8449204277695;3842.769433833979;3879.4568760373004;4101.8974525637095;4107.178564287155;4109.527404348479;3087.81473111614;3159.984200078985;3170.096522491125;3191.0586377018835;3194.6805319467867;1032.7882784029257;1033.9534720937577;1034.1726618705006;1039.3400190878695;1039.8128336899279;746.8244962433823;752.8757754970369;778.1401551285861;784.3685832904453;795.5067857928121;4344.804483798237;4420.226460369422;4464.843730469418;4627.8611641650805;4691.671582989226;330.16303670195936;341.66409339483;343.8212129692546;343.9810810405402;345.6577987792118;3120.7815452918076;3231.3537292541587;3287;3315.187778994162;3320.817355045496;8381.622826972802;8637.395382323177;8641.740747777576;8677.435966662148;8678.652853885775;8586.22723954847;8590.699325523577;8601.311895048506;8625.52559609215;8639.568021599021;6542.476601871839;6675.632840193739;6818.454523638097;6829.6243706596615;6857.588544687334;4344.45694288214;4360.694751367374;4477.373167756452;4592.862214133581;4678;7515.774526764206;7518.699252029857;7539.698412063456;7556.65995030214;7606.543607383574;7560.508566943131;8170.142135075969;8196.836063278583;8240.175982401712;8249;6242.094896239929;6291.370862913672;6313.463355614824;6320.399562041599;6340.080688300263;6647.102641143384;6702.83242918931;6875.07186529812;6878.002689610079;6942;5846.824595262149;6327.177466929454;6371.076193951943;6413.390727880844;6428.517861160338;5680.346760122474;5789.768409263583;5825.737841797214;5894.469497745224;5930.762769489172;5536.197251398444;5909.261342332208;5924.733386997682;5931.258592675916;5936.079907614327;3458.775179613317;3740.5137332146646;3773.9433908490846;3804.9751243781093;3825.7895815730403;3829.3107240695826;3851.268259030775;3982.780947047824;4098.918021639491;4186.769727664886 21:45:39 INFO - PROCESS | 3168 | |4;prototype.html;245.14444588386536;246.5254385308281;252.04098405566816;258.2626601053991;258.38504359624096;5116.1814109742445;5145.974270128653;5146.74266286686;5165.896682066361;5170.14692575725;4762.8571142865685;4860.902781944359;4964.354633897588;4985.476524964881;5043.621728370378;3713.8514459421726;3715.6841668458214;3716.3682174030346;3721.5348081489815;3726.869559565416;3709.4250391189407;3711.2763011212824;3723.3111874303204;3723.5345581802276;3735.4210097434943;3516.613172551017;3522.894313170596;3570.178858862463;3619.69232615228;3654.488371627974;3604.7476676632646;3636.5999740028574;3639.981800090996;3667.376545987162;3669.6880765134997;3185.2833112549492;3189.872405103793;3196.5524826524074;3197;3200.7119359257545;4948.4309304429735;4962.5533701967;4974.179260422026;4982.227754698028;4982.252662100714;3871.8838434846716;4184.497860256789;4325.437693099909;4337.219300525904;4340.13197360526;5279.683219006872;5284.709340986245;5284.867878303073;5295.867603309948;5319.414864364917;3446.207372304359;3448.6551344865566;3458.2737625098507;3460.186856088817;3462.567179102612;5500.009998200322;5513.641613295123;5531.861703457405;5534.592452283269;5538.6122971392015;3492.109512074405;3505.4566542186003;3506.6493350664728;3510.280392519527;3517.191046059421;3455.3089382123676;3519.6128425873135;3534.2401383702113;3542.875999340011;3554.804485753308;4081;4211.115665710174;4213.557576454489;4219.535851056381;4225.556316586775;5272.6309158358545;5279.551238144724;5284.3658760948165;5286.418493965661;5294.391145018295;1222.205566381859;1232.3961258983034;1232.827404163418;1234.6913271682079;1238.2013601227338;713.1228588835761;743.4795643049779;757.5038349880837;764.7055883484787;768.7194174126404;5014.34813474246;5070.873228169252;5189.221616757516;5196.246544251062;5210.384780717914;5454.318210223722;5508.531774799107;5528.474794894479;5548.611597188239;5568.192612071308;5219.947800522022;5220.243064755589;5220.243064755665;5226.216067589892;5226.738663066832;2792.3298408381843;2924.298168439559;2932.120363890824;2943.7203465670727;2960.6003189569565;5777.595568310178;5888.882222355614;5909.2908850938165;5941.524678025748;5947.078202878561;5631.183478395692;5655.953648575027;5665.858353541112;5672.205891175238;5680.801171959044;5746.942530574641;5847.093700476518;5908.350081490947;5920.8815823684145;5933.317668468095;5334.146536554133;5436.266104075899;5510.870271594234;5577.386487486455;5583.8883222336135;5452.400235974119;5473.31583552056;5490.3803378002785;5491.176323551499;5496.835094947158;5553.389127195925;5879.970600146972;5891.1752354671235;5908.172855800105;5918.408159184047;3627.183883626163;3663.3955397359136;3689.8155092245815;3695.5565332160313;3704.407294832814;5486.807961721321;5498.780048798003;5515.814099968526;5528.889422211453;5552.888942221052;5163.650539004819;5220.112580861339;5322.962022405593;5328.866778330573;5359.705216213146;5085.821996230115;5351.785928562814;5370.946290537045;5376.865578360573;5392.406835248198;5096.286519887145;5223.190405487156;5241.790328386975;5255.921161182662;5266.710330931835;5200.349956255468;5399.730013499231;5426.70153141581;5431.071969450788;5442.346918369821;5199.946021321482;5252.9737351313;5316.527814566245;5317.468253174651;5339.065663508948 21:45:39 INFO - PROCESS | 3168 | |5;yui.html;635.3202073781056;688.6074937285749;690.7824035428839;691.543581236384;709.7444919828861;14351.569452916403;14771.409143634255;15289.541313760592;15600.219989000547;15650.843491565081;14108.717825643482;14126.858731412683;14206.934479914022;14358.13851168928;14365.425382984668;641.1056576076382;652.438902543812;652.497576865813;653;653.7189008726245;539.5575627985035;544.8338256831664;637.4103953842676;641.5124505375925;652.2401402366238;681.002331584229;682.5836239893661;682.795161451565;683.0437387657295;685.1059367525378;678.7183318922666;680.7889554238152;680.928502507237;681.3016657925616;682.6552590941592;403.23788040603284;407.5109868158222;412.52559556509954;416.3255526047822;483.3957553058677;13453;13525.797113043312;14163.504277350243;14182.361793719307;14191.432342706279;12984;13357.332133393389;13390.665233369145;13646.931765341209;13653.453861845514;12558;12743.553975610808;12750.362481875962;12780.297083660394;12863.163894346837;4065.410515475269;4144.979275103636;4182.811773470201;4258.68059895509;4279.828806847722;79430;84496.15503844945;84991;85369;85944.57027714822;9869.407835529892;10092;10093.192544596415;10143.847842282372;10378.42918639467;17359.56601084983;17389.304427822997;17428.477145685396;17443.56391090233;17472;7406.814829629302;7410.814729631695;7453.701851925971;7508.887366689504;7636.465447418738;3944.2900277950243;4034.8587799426878;4040.2323558523785;4167.833006758113;4182.53992060873;2857.3856620826205;2923.736863682279;2945.808522446077;2945.8379789111805;2949.4690955627766;524.6930545630772;535.427093010475;540.2328693255587;542.5659472422126;547.1218693996159;531.3490973557439;600.1837500998638;626.6866566716642;633.3223450907485;678.1218322272669;8609.182127697859;8736;8851.07063758309;8919.42023768453;8953.238974687094;626.9592476489014;656.1961597043584;661.4146480364911;672.0989710343194;672.1799404726227;4241.724287921275;4299.570042995738;4334.978325108417;4370.191514569815;4515.932261016087;116911;117361.41319293348;118491.40754296174;121146;121432;99714;99719.54627486384;101008.49495752474;101214.4939275299;103648;86019;87443;88729.11270887208;89869;92294.53852730559;7962;8272.255497005299;8381.916180838358;8626.525541095049;8715.607797648994;50292.49707502878;51765.7411712939;52066.73966630142;52737;53611.73194134004;64083.67958160179;75217.24782752103;77184;77412.22587774051;77716;12493.562725304571;12577.874221258036;12843.486260549847;13103.410346534238;13182.406791694588;14921.850781492047;15280.006799557994;15329.46346877854;15710.842891571396;15746.9212653936;13660.590182294547;13753.862461375658;13809.309534523434;14157;14461.276936153361;10687.572497100029;10901.34591924487;13578.86421135776;13613.251271180181;14290.57128286153;13970;14075.718485630436;14158.292085395484;14354.138751674933;15069.321880515185;11538.94230528842;11822;12067.637970860887;12123.969462595778;12426.502939882303;11266.60566880188;11840.940795295968;11852.703682407664;12049.156559040783;12084.879151208726 21:45:39 INFO - PROCESS | 3168 | __end_tp_report 21:45:39 INFO - PROCESS | 3168 | __start_cc_report 21:45:39 INFO - PROCESS | 3168 | _x_x_mozilla_cycle_collect,724 21:45:39 INFO - PROCESS | 3168 | __end_cc_report 21:45:39 INFO - PROCESS | 3168 | __startTimestamp1475124339929__endTimestamp 21:45:39 INFO - PROCESS | 3168 | 21:45:39 INFO - PROCESS | 3168 | ------- Summary: start ------- 21:45:39 INFO - PROCESS | 3168 | Number of tests: 6 21:45:39 INFO - PROCESS | 3168 | 21:45:39 INFO - PROCESS | 3168 | [#0] dojo.html Cycles:165 Average:4494.18 Median:4685.63 stddev:603.41 (12.9%) stddev-sans-first:586.09 21:45:39 INFO - PROCESS | 3168 | Values: 2571.0 2574.4 2575.0 2601.6 2711.0 4339.0 4513.1 4516.3 4520.4 4592.5 4649.3 4687.8 4732.5 4814.1 4828.5 3979.5 4147.0 4175.4 4177.0 4197.8 3927.8 3958.0 3967.8 4020.2 4070.6 3988.3 4086.5 4140.2 4168.9 4258.7 3904.6 3985.5 4140.8 4226.0 4226.5 4091.6 4175.0 4183.4 4186.1 4193.2 4462.6 4580.3 4641.1 4737.5 4740.1 4673.6 4797.6 4850.5 4899.3 4913.0 4984.1 4991.4 4991.8 5001.0 5003.2 4577.6 4619.2 4620.4 4629.9 4632.7 4775.5 4802.6 4854.5 5074.6 5106.1 4569.3 4573.5 4574.1 4584.2 4587.7 4678.5 4679.3 4761.2 4955.6 5015.8 4616.7 4662.8 4974.2 4982.3 4982.5 4561.7 4572.5 4685.6 4688.7 4742.3 3106.8 3272.4 3292.0 3334.0 3338.2 2826.4 2838.5 2843.6 2868.8 3021.0 4713.0 4721.0 4722.1 4729.0 4733.7 4661.1 4750.7 4869.2 4939.6 4974.2 4408.0 4432.4 4563.9 4651.8 4727.1 3880.3 3885.3 3896.4 3903.3 4033.9 4914.6 5006.1 5076.2 5107.2 5339.6 4662.8 4769.8 4855.5 4967.4 5030.9 4941.8 5044.9 5264.0 5355.6 5378.4 4854.8 4880.4 4890.5 4931.2 4935.6 4758.5 4780.2 4814.3 4841.5 4997.4 4732.8 4894.1 4970.1 4995.8 4995.8 4583.6 4863.2 4864.1 4973.3 4981.4 4566.8 4695.9 4732.8 4733.0 4746.9 4559.8 4753.4 4797.6 4870.2 4963.4 4598.2 4680.6 4753.2 4796.5 4888.4 21:45:39 INFO - PROCESS | 3168 | 21:45:39 INFO - PROCESS | 3168 | [#1] ext.html Cycles:180 Average:58278.18 Median:37801.24 stddev:121413.39 (321.2%) stddev-sans-first:121676.52 21:45:39 INFO - PROCESS | 3168 | Values: 511.6 529.4 543.6 547.0 552.0 29518.9 30919.8 31176.1 31212.1 31256.0 31130.5 31551.5 40046.8 41909.2 42409.6 7623.9 8232.1 8545.5 8640.4 8677.7 8576.7 8645.3 8660.3 8661.7 8677.3 8120.3 8578.7 8682.0 8710.7 8715.4 7996.4 8032.0 8041.1 8052.6 8627.0 8102.5 8153.0 8548.7 8606.9 8609.9 36818.0 36960.6 39418.0 39462.0 39465.0 2899.9 3004.1 3035.2 3107.3 3141.9 29868.4 30253.1 31076.7 31151.7 31496.2 3447.7 3575.2 3618.8 3625.6 3682.8 330845.0 339829.0 339924.0 340601.0 341201.0 50470.2 52926.7 54145.2 54343.0 54371.0 27773.2 28444.4 55918.4 55942.4 56119.4 12965.4 13012.9 13123.9 13146.0 13158.5 10527.9 10574.8 10600.3 10620.5 10636.7 17658.4 17701.5 17718.9 17727.6 17740.6 6536.4 6644.6 6717.7 6730.9 6854.7 748.2 851.6 873.7 922.7 980.1 28390.0 28881.0 29031.4 29452.4 29514.3 749.0 827.0 850.7 910.1 918.4 13032.5 14889.6 15000.4 15098.2 15244.7 500903.0 503114.0 532563.0 534724.0 535684.0 47985.3 50571.0 51698.7 51707.9 51776.0 950.3 973.1 1070.5 1091.0 1115.8 27340.9 29429.3 29730.3 30151.1 30498.9 44253.8 48124.8 48962.8 49407.0 51801.5 468579.0 469896.0 490095.0 498854.7 505229.0 29573.3 31655.4 35679.0 36186.8 38104.4 41478.0 41833.8 42158.6 42709.0 44821.0 34802.3 35407.0 36095.8 36798.6 37639.2 35833.3 36713.8 36853.3 37963.2 38652.6 34970.5 35919.3 36408.3 36650.1 36727.4 30300.5 31870.0 31877.2 31933.4 32836.2 24646.8 24982.6 25680.9 26026.6 26143.6 21:45:39 INFO - PROCESS | 3168 | 21:45:39 INFO - PROCESS | 3168 | [#2] jquery.html Cycles:180 Average:25715.75 Median:4647.00 stddev:64827.03 (1395.0%) stddev-sans-first:64980.76 21:45:39 INFO - PROCESS | 3168 | Values: 284.6 308.7 308.8 310.0 318.7 13388.2 17994.6 18146.7 18208.5 19588.0 16967.2 17341.6 17474.6 17900.5 18527.7 5211.2 5412.5 5540.6 5597.1 5610.8 5072.4 5130.0 5134.3 5442.3 5461.9 5397.5 5422.3 5430.9 5431.9 5433.3 5240.1 5432.2 5433.0 5469.8 5473.4 4523.7 4558.7 4578.4 4715.6 4729.2 21841.2 23203.5 23252.8 23421.3 23460.5 1843.6 2088.5 2474.6 2590.4 2730.7 13444.6 14269.2 14605.1 14705.1 14834.6 1431.3 1497.4 1525.4 1574.3 1588.2 79267.0 84889.2 86101.6 88460.0 89355.0 8806.9 8897.1 8914.3 9047.6 9289.5 9731.7 10215.4 10319.2 11467.4 11485.1 6654.9 6657.2 6747.0 6836.7 6965.2 6578.7 6847.1 6912.7 6961.7 7011.5 5740.5 5798.6 5813.5 5849.7 5907.9 1479.4 1498.4 1502.9 1505.3 1535.2 796.4 806.5 815.9 826.7 838.4 18555.3 18714.7 18809.6 18997.1 19451.4 865.9 866.5 887.8 917.2 950.3 10118.2 10631.5 10734.7 10784.6 11230.6 371872.0 376046.2 394442.0 395503.0 400687.0 74205.3 83312.0 88102.6 89844.6 89947.0 734.6 764.6 784.7 788.2 804.3 16834.8 16851.2 17184.2 17413.8 17552.3 33718.8 41939.6 44421.0 44603.3 45618.5 60622.7 61913.0 64342.7 66419.3 66539.3 7364.7 7547.5 7612.4 7629.4 7782.8 8626.8 13320.7 13435.4 13550.5 14229.8 10507.5 10558.5 10948.9 10977.8 11225.7 10710.3 10863.1 10878.9 11509.8 11549.8 11234.0 11305.9 11372.4 11893.7 12050.0 7164.1 7303.1 7317.5 7406.9 7659.5 7405.9 7417.4 7499.9 7582.3 7594.5 21:45:39 INFO - PROCESS | 3168 | 21:45:39 INFO - PROCESS | 3168 | [#3] mootools.html Cycles:180 Average:4597.27 Median:4535.12 stddev:2574.45 (56.8%) stddev-sans-first:2568.72 21:45:39 INFO - PROCESS | 3168 | Values: 1161.7 1164.0 1271.0 1499.4 1529.5 6873.1 7314.1 7319.7 7323.1 7323.5 6224.7 6369.3 6408.4 6470.9 7039.1 1913.6 1957.0 1958.6 1962.0 1964.5 1895.9 1899.2 1972.0 1983.5 1983.6 1660.1 1704.9 1820.2 1867.6 1965.8 1933.8 1986.7 1987.6 1990.7 1999.1 1794.4 1884.2 1894.7 1898.3 1900.2 6975.7 7119.6 7239.7 7249.3 7250.5 4355.0 4430.0 4439.4 4450.9 4460.5 2828.5 2838.1 2931.6 2969.3 3010.5 925.5 954.1 959.1 970.8 987.4 9185.6 9194.4 9248.6 9257.9 9269.6 3694.8 3731.2 3891.1 3893.3 3893.5 8073.7 8111.4 8134.9 8159.1 8165.7 4134.8 4200.3 4351.9 4415.1 4430.8 3842.8 3879.5 4101.9 4107.2 4109.5 3087.8 3160.0 3170.1 3191.1 3194.7 1032.8 1034.0 1034.2 1039.3 1039.8 746.8 752.9 778.1 784.4 795.5 4344.8 4420.2 4464.8 4627.9 4691.7 330.2 341.7 343.8 344.0 345.7 3120.8 3231.4 3287.0 3315.2 3320.8 8381.6 8637.4 8641.7 8677.4 8678.7 8586.2 8590.7 8601.3 8625.5 8639.6 6542.5 6675.6 6818.5 6829.6 6857.6 4344.5 4360.7 4477.4 4592.9 4678.0 7515.8 7518.7 7539.7 7556.7 7606.5 7560.5 8170.1 8196.8 8240.2 8249.0 6242.1 6291.4 6313.5 6320.4 6340.1 6647.1 6702.8 6875.1 6878.0 6942.0 5846.8 6327.2 6371.1 6413.4 6428.5 5680.3 5789.8 5825.7 5894.5 5930.8 5536.2 5909.3 5924.7 5931.3 5936.1 3458.8 3740.5 3773.9 3805.0 3825.8 3829.3 3851.3 3982.8 4098.9 4186.8 21:45:39 INFO - PROCESS | 3168 | 21:45:39 INFO - PROCESS | 3168 | [#4] prototype.html Cycles:180 Average:4411.12 Median:5192.73 stddev:1415.62 (27.3%) stddev-sans-first:1384.62 21:45:39 INFO - PROCESS | 3168 | Values: 245.1 246.5 252.0 258.3 258.4 5116.2 5146.0 5146.7 5165.9 5170.1 4762.9 4860.9 4964.4 4985.5 5043.6 3713.9 3715.7 3716.4 3721.5 3726.9 3709.4 3711.3 3723.3 3723.5 3735.4 3516.6 3522.9 3570.2 3619.7 3654.5 3604.7 3636.6 3640.0 3667.4 3669.7 3185.3 3189.9 3196.6 3197.0 3200.7 4948.4 4962.6 4974.2 4982.2 4982.3 3871.9 4184.5 4325.4 4337.2 4340.1 5279.7 5284.7 5284.9 5295.9 5319.4 3446.2 3448.7 3458.3 3460.2 3462.6 5500.0 5513.6 5531.9 5534.6 5538.6 3492.1 3505.5 3506.6 3510.3 3517.2 3455.3 3519.6 3534.2 3542.9 3554.8 4081.0 4211.1 4213.6 4219.5 4225.6 5272.6 5279.6 5284.4 5286.4 5294.4 1222.2 1232.4 1232.8 1234.7 1238.2 713.1 743.5 757.5 764.7 768.7 5014.3 5070.9 5189.2 5196.2 5210.4 5454.3 5508.5 5528.5 5548.6 5568.2 5219.9 5220.2 5220.2 5226.2 5226.7 2792.3 2924.3 2932.1 2943.7 2960.6 5777.6 5888.9 5909.3 5941.5 5947.1 5631.2 5656.0 5665.9 5672.2 5680.8 5746.9 5847.1 5908.4 5920.9 5933.3 5334.1 5436.3 5510.9 5577.4 5583.9 5452.4 5473.3 5490.4 5491.2 5496.8 5553.4 5880.0 5891.2 5908.2 5918.4 3627.2 3663.4 3689.8 3695.6 3704.4 5486.8 5498.8 5515.8 5528.9 5552.9 5163.7 5220.1 5323.0 5328.9 5359.7 5085.8 5351.8 5370.9 5376.9 5392.4 5096.3 5223.2 5241.8 5255.9 5266.7 5200.3 5399.7 5426.7 5431.1 5442.3 5199.9 5253.0 5316.5 5317.5 5339.1 21:45:39 INFO - PROCESS | 3168 | 21:45:39 INFO - PROCESS | 3168 | [#5] yui.html Cycles:180 Average:20975.95 Median:2292.08 stddev:31126.28 (1358.0%) stddev-sans-first:31176.13 21:45:39 INFO - PROCESS | 3168 | Values: 635.3 688.6 690.8 691.5 709.7 14351.6 14771.4 15289.5 15600.2 15650.8 14108.7 14126.9 14206.9 14358.1 14365.4 641.1 652.4 652.5 653.0 653.7 539.6 544.8 637.4 641.5 652.2 681.0 682.6 682.8 683.0 685.1 678.7 680.8 680.9 681.3 682.7 403.2 407.5 412.5 416.3 483.4 13453.0 13525.8 14163.5 14182.4 14191.4 12984.0 13357.3 13390.7 13646.9 13653.5 12558.0 12743.6 12750.4 12780.3 12863.2 4065.4 4145.0 4182.8 4258.7 4279.8 79430.0 84496.2 84991.0 85369.0 85944.6 9869.4 10092.0 10093.2 10143.8 10378.4 17359.6 17389.3 17428.5 17443.6 17472.0 7406.8 7410.8 7453.7 7508.9 7636.5 3944.3 4034.9 4040.2 4167.8 4182.5 2857.4 2923.7 2945.8 2945.8 2949.5 524.7 535.4 540.2 542.6 547.1 531.3 600.2 626.7 633.3 678.1 8609.2 8736.0 8851.1 8919.4 8953.2 627.0 656.2 661.4 672.1 672.2 4241.7 4299.6 4335.0 4370.2 4515.9 116911.0 117361.4 118491.4 121146.0 121432.0 99714.0 99719.5 101008.5 101214.5 103648.0 86019.0 87443.0 88729.1 89869.0 92294.5 7962.0 8272.3 8381.9 8626.5 8715.6 50292.5 51765.7 52066.7 52737.0 53611.7 64083.7 75217.2 77184.0 77412.2 77716.0 12493.6 12577.9 12843.5 13103.4 13182.4 14921.9 15280.0 15329.5 15710.8 15746.9 13660.6 13753.9 13809.3 14157.0 14461.3 10687.6 10901.3 13578.9 13613.3 14290.6 13970.0 14075.7 14158.3 14354.1 15069.3 11538.9 11822.0 12067.6 12124.0 12426.5 11266.6 11840.9 11852.7 12049.2 12084.9 21:45:39 INFO - PROCESS | 3168 | -------- Summary: end -------- 21:45:39 INFO - PROCESS | 3168 | 21:45:40 INFO - TEST-INFO | 3168: exit 0 21:45:41 INFO - TEST-OK | dromaeo_css | took 1117072ms 21:45:41 INFO - TEST-START | kraken 21:45:41 INFO - operating with platform_type : w7_ 21:45:41 INFO - Initialising browser for kraken test... 21:45:41 INFO - TEST-INFO | started process 3440 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpoo_lul\profile http://localhost:49895/getInfo.html) 21:45:45 INFO - PROCESS | 3440 | __metrics Screen width/height:1600/1200 21:45:45 INFO - PROCESS | 3440 | colorDepth:24 21:45:45 INFO - PROCESS | 3440 | Browser inner width/height: 1010/674 21:45:45 INFO - PROCESS | 3440 | __metrics 21:45:46 INFO - PROCESS | 3440 | 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" 21:45:46 INFO - PROCESS | 3440 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:45:46 INFO - PROCESS | 3440 | console.error: 21:45:46 INFO - PROCESS | 3440 | Message: Error: Connection closed before committing the transaction. 21:45:46 INFO - PROCESS | 3440 | Stack: 21:45:46 INFO - PROCESS | 3440 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 21:45:46 INFO - PROCESS | 3440 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 21:45:46 INFO - PROCESS | 3440 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 21:45:46 INFO - PROCESS | 3440 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 21:45:46 INFO - PROCESS | 3440 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 21:45:46 INFO - PROCESS | 3440 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13 21:45:46 INFO - PROCESS | 3440 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 21:45:46 INFO - PROCESS | 3440 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 21:45:46 INFO - PROCESS | 3440 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 21:45:46 INFO - PROCESS | 3440 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 21:45:46 INFO - PROCESS | 3440 | Barrier/this.client.addBlocker/promise13.2% 21:46:44 INFO - TinderboxPrint: I/O read bytes / time
83,072,000 / 1,437,050 21:46:44 INFO - TinderboxPrint: I/O write bytes / time
447,991,296 / 3,485,500 21:46:44 INFO - TinderboxPrint: CPU idle
8,241.1 (86.8%) 21:46:44 INFO - TinderboxPrint: CPU user
1,208.5 (12.7%) 21:46:44 INFO - install - Wall time: 3s; CPU: 8.0%; Read bytes: 0; Write bytes: 34137088; Read time: 0; Write time: 447960 21:46:44 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 83072000; Write bytes: 375372288; Read time: 1437050; Write time: 3004550 21:46:45 INFO - Running post-run listener: _upload_blobber_files 21:46:45 INFO - Blob upload gear active. 21:46:45 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 21:46:45 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 21:46:45 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', 'Autoland', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 21:46:45 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 Autoland -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 21:46:45 INFO - (blobuploader) - INFO - Open directory for files ... 21:46:45 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 21:46:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:46:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:46:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:46:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:46:46 INFO - (blobuploader) - INFO - Done attempting. 21:46:46 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 21:46:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:46:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:46:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:46:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:46:47 INFO - (blobuploader) - INFO - Done attempting. 21:46:47 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 21:46:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:46:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:46:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:46:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:46:55 INFO - (blobuploader) - INFO - Done attempting. 21:46:55 INFO - (blobuploader) - INFO - Iteration through files over. 21:46:55 INFO - Return code: 0 21:46:55 INFO - rmtree: C:\slave\test\build\uploaded_files.json 21:46:55 INFO - Using _rmtree_windows ... 21:46:55 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 21:46:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ce99623ad67da8536732241dc6b426e45eac4271d7cef0fa7aefc4164b9a109b88c3951c5c938e82894284155165577cdfea39b85d0ebf7c711014136510b00a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6fe699c14161bb24a0a2be5f65e9bad27629ae58c1e8e3786f12b92446e5d0fa7898bb08ac568dd106e4cdbf2c05c2c2585a3bf80d6d095e25e6d264c7229f6d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2d13d267e6090394f9817022d64d353e85ab4faa53e4271627697282d407cf84682cd83c8bd2b53b6fa2e359685317d69872e15fd80a6fc054ac496a1ad02f25"} 21:46:55 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 21:46:55 INFO - Writing to file C:\slave\test\properties\blobber_files 21:46:55 INFO - Contents: 21:46:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ce99623ad67da8536732241dc6b426e45eac4271d7cef0fa7aefc4164b9a109b88c3951c5c938e82894284155165577cdfea39b85d0ebf7c711014136510b00a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6fe699c14161bb24a0a2be5f65e9bad27629ae58c1e8e3786f12b92446e5d0fa7898bb08ac568dd106e4cdbf2c05c2c2585a3bf80d6d095e25e6d264c7229f6d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2d13d267e6090394f9817022d64d353e85ab4faa53e4271627697282d407cf84682cd83c8bd2b53b6fa2e359685317d69872e15fd80a6fc054ac496a1ad02f25"} 21:46:55 INFO - Running post-run listener: copy_logs_to_upload_dir 21:46:55 INFO - Copying logs to upload dir... 21:46:55 INFO - mkdir: C:\slave\test\build\upload\logs 21:46:55 INFO - Copying logs to upload dir... 21:46:55 INFO - Using _rmtree_windows ... 21:46:55 INFO - Using _rmtree_windows ... 21:46:55 INFO - Using _rmtree_windows ... 21:46:55 INFO - Using _rmtree_windows ... 21:46:55 INFO - Using _rmtree_windows ... 21:46:55 INFO - Using _rmtree_windows ... 21:46:55 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1404.509000 ========= master_lag: 0.03 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 24 secs) (at 2016-09-28 21:46:55.640645) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 21:46:55.646398) ========= '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-084 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-084 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-084 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/Autoland/sha512/ce99623ad67da8536732241dc6b426e45eac4271d7cef0fa7aefc4164b9a109b88c3951c5c938e82894284155165577cdfea39b85d0ebf7c711014136510b00a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6fe699c14161bb24a0a2be5f65e9bad27629ae58c1e8e3786f12b92446e5d0fa7898bb08ac568dd106e4cdbf2c05c2c2585a3bf80d6d095e25e6d264c7229f6d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2d13d267e6090394f9817022d64d353e85ab4faa53e4271627697282d407cf84682cd83c8bd2b53b6fa2e359685317d69872e15fd80a6fc054ac496a1ad02f25"} build_url:https://queue.taskcluster.net/v1/task/efilm2wnRWOnCtlJ7ubXZw/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/efilm2wnRWOnCtlJ7ubXZw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/ce99623ad67da8536732241dc6b426e45eac4271d7cef0fa7aefc4164b9a109b88c3951c5c938e82894284155165577cdfea39b85d0ebf7c711014136510b00a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6fe699c14161bb24a0a2be5f65e9bad27629ae58c1e8e3786f12b92446e5d0fa7898bb08ac568dd106e4cdbf2c05c2c2585a3bf80d6d095e25e6d264c7229f6d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2d13d267e6090394f9817022d64d353e85ab4faa53e4271627697282d407cf84682cd83c8bd2b53b6fa2e359685317d69872e15fd80a6fc054ac496a1ad02f25"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 21:46:55.762251) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 21:46:55.762549) ========= '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-084 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-084 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-084 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 21:46:55.881043) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-28 21:46:55.881327) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-28 21:46:56.373656) ========= ========= Total master_lag: 8.86 =========