builder: mozilla-central_win7_ix_test-dromaeojs slave: t-w732-ix-101 starttime: 1480143969.0 results: success (0) buildid: 20161125212619 builduid: 6dda5ec114504956b67d6e1531a80fba revision: 180c7af5922ee89f848c25c3847bd1fb22a71e37 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 23:06:09.001226) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-25 23:06:09.001672) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 23:06:09.001959) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-25 23:06:09.544975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 23:06:09.545402) ========= '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-101 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-101 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-101 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 --23:06:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,179 (12K) [text/x-python] 0K .......... . 100% 9.02 MB/s 23:06:10 (9.02 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.117000 ========= master_lag: 0.24 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 23:06:10.904440) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 8 secs) (at 2016-11-25 23:06:10.904752) ========= '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-101 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-101 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-101 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=7.101000 ========= master_lag: 1.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 8 secs) (at 2016-11-25 23:06:19.092165) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 23:06:19.092471) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev 180c7af5922ee89f848c25c3847bd1fb22a71e37 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 180c7af5922ee89f848c25c3847bd1fb22a71e37 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-101 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-101 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-101 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-11-25 23:06:19,480 truncating revision to first 12 chars 2016-11-25 23:06:19,482 Setting DEBUG logging. 2016-11-25 23:06:19,482 attempt 1/10 2016-11-25 23:06:19,482 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/180c7af5922e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-25 23:06:20,076 unpacking tar archive at: mozilla-central-180c7af5922e/testing/mozharness/ 2016-11-25 23:06:20,203 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.274000 ========= master_lag: 0.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-25 23:06:20.631701) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 23:06:20.631970) ========= script_repo_revision: 180c7af5922ee89f848c25c3847bd1fb22a71e37 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-25 23:06:20.632351) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 23:06:20.632616) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-25 23:06:20.964826) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 10 secs) (at 2016-11-25 23:06:20.966050) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-101 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-101 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-101 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 23:06:21 INFO - MultiFileLogger online at 20161125 23:06:21 in C:\slave\test 23:06:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 23:06:21 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 23:06:21 INFO - {'append_to_log': False, 23:06:21 INFO - 'base_work_dir': 'C:\\slave\\test', 23:06:21 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 23:06:21 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 23:06:21 INFO - 'branch': 'Firefox-Non-PGO', 23:06:21 INFO - 'buildbot_json_path': 'buildprops.json', 23:06:21 INFO - 'config_files': ('talos/windows_config.py',), 23:06:21 INFO - 'default_actions': ('clobber', 23:06:21 INFO - 'read-buildbot-config', 23:06:21 INFO - 'download-and-extract', 23:06:21 INFO - 'populate-webroot', 23:06:21 INFO - 'create-virtualenv', 23:06:21 INFO - 'install', 23:06:21 INFO - 'run-tests'), 23:06:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:06:21 INFO - 'download_minidump_stackwalk': True, 23:06:21 INFO - 'download_symbols': 'ondemand', 23:06:21 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 23:06:21 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 23:06:21 INFO - 'hg': 'c:/mozilla-build/hg/hg', 23:06:21 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 23:06:21 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 23:06:21 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 23:06:21 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 23:06:21 INFO - 'C:/mozilla-build/tooltool.py'), 23:06:21 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 23:06:21 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 23:06:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:06:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:06:21 INFO - 'installer_path': 'installer.exe', 23:06:21 INFO - 'log_level': 'info', 23:06:21 INFO - 'log_name': 'talos', 23:06:21 INFO - 'log_to_console': True, 23:06:21 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 23:06:21 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 23:06:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 23:06:21 INFO - 'opt_config_files': (), 23:06:21 INFO - 'pip_index': False, 23:06:21 INFO - 'sps_profile': False, 23:06:21 INFO - 'sps_profile_interval': 0, 23:06:21 INFO - 'suite': 'dromaeojs', 23:06:21 INFO - 'system_bits': '32', 23:06:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:06:21 INFO - 'title': 'T-W732-IX-101', 23:06:21 INFO - 'use_talos_json': True, 23:06:21 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 23:06:21 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 23:06:21 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 23:06:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:06:21 INFO - 'work_dir': 'build'} 23:06:21 INFO - [mozharness: 2016-11-26 07:06:21.417000Z] Running clobber step. 23:06:21 INFO - Running pre-action listener: _resource_record_pre_action 23:06:21 INFO - Running main action method: clobber 23:06:21 INFO - rmtree: C:\slave\test\build 23:06:21 INFO - Using _rmtree_windows ... 23:06:21 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 23:07:31 INFO - Running post-action listener: _resource_record_post_action 23:07:31 INFO - [mozharness: 2016-11-26 07:07:31.484000Z] Finished clobber step (success) 23:07:31 INFO - [mozharness: 2016-11-26 07:07:31.484000Z] Running read-buildbot-config step. 23:07:31 INFO - Running pre-action listener: _resource_record_pre_action 23:07:31 INFO - Running main action method: read_buildbot_config 23:07:31 INFO - Using buildbot properties: 23:07:31 INFO - { 23:07:31 INFO - "project": "", 23:07:31 INFO - "product": "firefox", 23:07:31 INFO - "script_repo_revision": "production", 23:07:31 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs", 23:07:31 INFO - "repository": "", 23:07:31 INFO - "buildername": "Windows 7 32-bit mozilla-central talos dromaeojs", 23:07:31 INFO - "buildid": "20161125212619", 23:07:31 INFO - "pgo_build": "False", 23:07:31 INFO - "basedir": "C:\\slave\\test", 23:07:31 INFO - "buildnumber": 28, 23:07:31 INFO - "slavename": "t-w732-ix-101", 23:07:31 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 23:07:31 INFO - "platform": "win7_ix", 23:07:31 INFO - "scheduler": "tests-mozilla-central-win32-talos", 23:07:31 INFO - "branch": "mozilla-central", 23:07:31 INFO - "repo_path": "mozilla-central", 23:07:31 INFO - "revision": "180c7af5922ee89f848c25c3847bd1fb22a71e37", 23:07:31 INFO - "stage_platform": "win32", 23:07:31 INFO - "builduid": "6dda5ec114504956b67d6e1531a80fba", 23:07:31 INFO - "slavebuilddir": "test" 23:07:31 INFO - } 23:07:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip. 23:07:31 INFO - Running post-action listener: _resource_record_post_action 23:07:31 INFO - [mozharness: 2016-11-26 07:07:31.488000Z] Finished read-buildbot-config step (success) 23:07:31 INFO - [mozharness: 2016-11-26 07:07:31.488000Z] Running download-and-extract step. 23:07:31 INFO - Running pre-action listener: _resource_record_pre_action 23:07:31 INFO - Running main action method: download_and_extract 23:07:31 INFO - mkdir: C:\slave\test\build\tests 23:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:07:31 INFO - trying https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json 23:07:31 INFO - Downloading https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json 23:07:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.test_packages.json'}, attempt #1 23:07:32 INFO - Downloaded 1274 bytes. 23:07:32 INFO - Reading from file C:\slave\test\build\firefox-53.0a1.en-US.win32.test_packages.json 23:07:32 INFO - Using the following test package requirements: 23:07:32 INFO - {u'common': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 23:07:32 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:07:32 INFO - u'firefox-53.0a1.en-US.win32.cppunittest.tests.zip'], 23:07:32 INFO - u'gtest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:07:32 INFO - u'firefox-53.0a1.en-US.win32.gtest.tests.zip'], 23:07:32 INFO - u'jittest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:07:32 INFO - u'jsshell-win32.zip'], 23:07:32 INFO - u'mochitest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:07:32 INFO - u'firefox-53.0a1.en-US.win32.mochitest.tests.zip'], 23:07:32 INFO - u'mozbase': [u'firefox-53.0a1.en-US.win32.common.tests.zip'], 23:07:32 INFO - u'reftest': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:07:32 INFO - u'firefox-53.0a1.en-US.win32.reftest.tests.zip'], 23:07:32 INFO - u'talos': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:07:32 INFO - u'firefox-53.0a1.en-US.win32.talos.tests.zip'], 23:07:32 INFO - u'web-platform': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:07:32 INFO - u'firefox-53.0a1.en-US.win32.web-platform.tests.zip'], 23:07:32 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.win32.common.tests.zip', 23:07:32 INFO - u'firefox-53.0a1.en-US.win32.xpcshell.tests.zip']} 23:07:32 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip'] for test suite category: common 23:07:32 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip 23:07:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1 23:07:32 INFO - Fetch https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory 23:07:33 INFO - Http code: 200 23:07:33 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 23:07:33 INFO - x-amz-cf-id: qGHFB_9QH0xhWyx1kyF9l_m8tWlnbaoQkcp1hqlcSHJndsH-UvwCjQ== 23:07:33 INFO - x-amz-version-id: uBQO7erxu_v20MTso6bYiQ1DOuyvMNpt 23:07:33 INFO - Expected file size: 16887131 23:07:33 INFO - Obtained file size: 16887131 23:08:04 INFO - Downloading packages: [u'firefox-53.0a1.en-US.win32.common.tests.zip', u'firefox-53.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 23:08:04 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip 23:08:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip'}, attempt #1 23:08:04 INFO - Fetch https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.common.tests.zip into memory 23:08:05 INFO - Http code: 200 23:08:05 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 23:08:05 INFO - x-amz-cf-id: fWHQwgN9MTadHMMytOSwbjFj0iGse-sGo2mDzWkz-YMMM7Wh6dN4sA== 23:08:05 INFO - x-amz-version-id: uBQO7erxu_v20MTso6bYiQ1DOuyvMNpt 23:08:06 INFO - Expected file size: 16887131 23:08:06 INFO - Obtained file size: 16887131 23:08:16 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip 23:08:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip'}, attempt #1 23:08:16 INFO - Fetch https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.talos.tests.zip into memory 23:08:17 INFO - Http code: 200 23:08:17 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 23:08:17 INFO - x-amz-cf-id: rK5_CCJdQDD3MTUfiXc_i2VEEZAnyJbyZrWLw5r6sjSYZTwI7toHfw== 23:08:17 INFO - x-amz-version-id: 23P2WbGIra0P_xydcKp6PuZxzfpuRwCX 23:08:17 INFO - Expected file size: 14409702 23:08:17 INFO - Obtained file size: 14409702 23:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:08:19 INFO - trying https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 23:08:19 INFO - Downloading https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip to C:\slave\test\build\firefox-53.0a1.en-US.win32.zip 23:08:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-53.0a1.en-US.win32.zip'}, attempt #1 23:08:22 INFO - Downloaded 53402105 bytes. 23:08:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 23:08:22 INFO - mkdir: C:\slave\test\properties 23:08:22 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 23:08:22 INFO - Writing to file C:\slave\test\properties\build_url 23:08:22 INFO - Contents: 23:08:22 INFO - build_url:https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip 23:08:23 INFO - Running post-action listener: _resource_record_post_action 23:08:23 INFO - Running post-action listener: set_extra_try_arguments 23:08:23 INFO - [mozharness: 2016-11-26 07:08:23.529000Z] Finished download-and-extract step (success) 23:08:23 INFO - [mozharness: 2016-11-26 07:08:23.529000Z] Running populate-webroot step. 23:08:23 INFO - Running pre-action listener: _resource_record_pre_action 23:08:23 INFO - Running main action method: populate_webroot 23:08:23 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 23:08:23 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 23:08:23 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 23:08:23 INFO - u'--tppagecycles', 23:08:23 INFO - u'7'], 23:08:23 INFO - u'tests': [u'tsvgm']}}, 23:08:23 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 23:08:23 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 23:08:23 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 23:08:23 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 23:08:23 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 23:08:23 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 23:08:23 INFO - u'g1': {u'pagesets_name': u'tp5n.zip', 23:08:23 INFO - u'talos_options': [u'--disable-e10s'], 23:08:23 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 23:08:23 INFO - u'g1-e10s': {u'pagesets_name': u'tp5n.zip', 23:08:23 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 23:08:23 INFO - u'g2': {u'pagesets_name': u'tp5n.zip', 23:08:23 INFO - u'talos_options': [u'--disable-e10s'], 23:08:23 INFO - u'tests': [u'damp', u'tps']}, 23:08:23 INFO - u'g2-e10s': {u'pagesets_name': u'tp5n.zip', 23:08:23 INFO - u'tests': [u'damp', u'tps']}, 23:08:23 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 23:08:23 INFO - u'tests': [u'dromaeo_dom']}, 23:08:23 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 23:08:23 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 23:08:23 INFO - u'tests': [u'basic_compositor_video']}, 23:08:23 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 23:08:23 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 23:08:23 INFO - u'tests': [u'a11yr', 23:08:23 INFO - u'ts_paint', 23:08:23 INFO - u'tpaint', 23:08:23 INFO - u'sessionrestore', 23:08:23 INFO - u'sessionrestore_no_auto_restore', 23:08:23 INFO - u'tabpaint']}, 23:08:23 INFO - u'other-e10s': {u'tests': [u'a11yr', 23:08:23 INFO - u'ts_paint', 23:08:23 INFO - u'tpaint', 23:08:23 INFO - u'sessionrestore', 23:08:23 INFO - u'sessionrestore_no_auto_restore', 23:08:23 INFO - u'tabpaint']}, 23:08:23 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 23:08:23 INFO - u'ts_paint', 23:08:23 INFO - u'tpaint', 23:08:23 INFO - u'sessionrestore', 23:08:23 INFO - u'sessionrestore_no_auto_restore', 23:08:23 INFO - u'tabpaint']}, 23:08:23 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 23:08:23 INFO - u'ts_paint', 23:08:23 INFO - u'tpaint', 23:08:23 INFO - u'sessionrestore', 23:08:23 INFO - u'sessionrestore_no_auto_restore', 23:08:23 INFO - u'tabpaint']}, 23:08:23 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 23:08:23 INFO - u'tests': [u'a11yr', 23:08:23 INFO - u'ts_paint', 23:08:23 INFO - u'tpaint', 23:08:23 INFO - u'sessionrestore', 23:08:23 INFO - u'sessionrestore_no_auto_restore', 23:08:23 INFO - u'tabpaint']}, 23:08:23 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 23:08:23 INFO - u'tests': [u'a11yr', 23:08:23 INFO - u'ts_paint', 23:08:23 INFO - u'tpaint', 23:08:23 INFO - u'sessionrestore', 23:08:23 INFO - u'sessionrestore_no_auto_restore', 23:08:23 INFO - u'tabpaint']}, 23:08:23 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 23:08:23 INFO - u'tests': [u'tsvgx', 23:08:23 INFO - u'tsvgr_opacity', 23:08:23 INFO - u'tart', 23:08:23 INFO - u'tscrollx', 23:08:23 INFO - u'cart', 23:08:23 INFO - u'tsvg_static']}, 23:08:23 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 23:08:23 INFO - u'tsvgr_opacity', 23:08:23 INFO - u'tart', 23:08:23 INFO - u'tscrollx', 23:08:23 INFO - u'cart', 23:08:23 INFO - u'tsvg_static']}, 23:08:23 INFO - u'tp5o': {u'pagesets_name': u'tp5n.zip', 23:08:23 INFO - u'talos_options': [u'--disable-e10s'], 23:08:23 INFO - u'tests': [u'tp5o']}, 23:08:23 INFO - u'tp5o-e10s': {u'pagesets_name': u'tp5n.zip', 23:08:23 INFO - u'tests': [u'tp5o']}, 23:08:23 INFO - u'xperf': {u'pagesets_name': u'tp5n.zip', 23:08:23 INFO - u'talos_options': [u'--disable-e10s', 23:08:23 INFO - u'--xperf_path', 23:08:23 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:08:23 INFO - u'tests': [u'tp5n']}, 23:08:23 INFO - u'xperf-e10s': {u'pagesets_name': u'tp5n.zip', 23:08:23 INFO - u'talos_options': [u'--xperf_path', 23:08:23 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:08:23 INFO - u'tests': [u'tp5n']}}, 23:08:23 INFO - u'talos.zip': {u'path': u'', 23:08:23 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:08:23 INFO - Running post-action listener: _resource_record_post_action 23:08:23 INFO - [mozharness: 2016-11-26 07:08:23.620000Z] Finished populate-webroot step (success) 23:08:23 INFO - [mozharness: 2016-11-26 07:08:23.620000Z] Running create-virtualenv step. 23:08:23 INFO - Running pre-action listener: _resource_record_pre_action 23:08:23 INFO - Running main action method: create_virtualenv 23:08:23 INFO - Creating virtualenv C:\slave\test\build/venv 23:08:23 INFO - mkdir: C:\slave\test\build\venv\Scripts 23:08:24 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 23:08:24 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'! 23:08:24 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 23:08:24 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 23:08:38 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 23:08:40 INFO - Installing distribute......................................................................................................................................................................................done. 23:08:42 INFO - Return code: 0 23:08:42 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 23:08:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 23:08:42 INFO - Reading from file tmpfile_stdout 23:08:42 INFO - Output received: 23:08:42 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 23:08:42 INFO - Using _rmtree_windows ... 23:08:42 INFO - Using _rmtree_windows ... 23:08:42 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 23:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:42 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01950640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018FB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A03E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F815C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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 23:08:42 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 23:08:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:08:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:08:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:08:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:08:42 INFO - 'COMPUTERNAME': 'T-W732-IX-101', 23:08:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:08:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:08:42 INFO - 'DCLOCATION': 'SCL3', 23:08:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:08:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:08:42 INFO - 'HOMEDRIVE': 'C:', 23:08:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:08:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:08:42 INFO - 'KTS_VERSION': '1.19c', 23:08:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:08:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101', 23:08:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:08:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:42 INFO - 'MOZ_NO_REMOTE': '1', 23:08:42 INFO - 'NO_EM_RESTART': '1', 23:08:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:08:42 INFO - 'OS': 'Windows_NT', 23:08:42 INFO - 'OURDRIVE': 'C:', 23:08:42 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 23:08:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:08:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:08:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:08:42 INFO - 'PROCESSOR_LEVEL': '6', 23:08:42 INFO - 'PROCESSOR_REVISION': '1e05', 23:08:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:08:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:08:42 INFO - 'PROMPT': '$P$G', 23:08:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:08:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:08:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:08:42 INFO - 'PWD': 'C:\\slave\\test', 23:08:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:08:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:08:42 INFO - 'SYSTEMDRIVE': 'C:', 23:08:42 INFO - 'SYSTEMROOT': 'C:\\windows', 23:08:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:08:42 INFO - 'TEST1': 'testie', 23:08:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:08:42 INFO - 'USERDOMAIN': 'T-W732-IX-101', 23:08:42 INFO - 'USERNAME': 'cltbld', 23:08:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:08:42 INFO - 'WINDIR': 'C:\\windows', 23:08:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:08:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:08:43 INFO - Ignoring indexes: http://pypi.python.org/simple/ 23:08:43 INFO - Downloading/unpacking pip>=1.5 23:08:47 INFO - Running setup.py egg_info for package pip 23:08:47 INFO - warning: no files found matching 'pip\cacert.pem' 23:08:47 INFO - warning: no files found matching '*.html' under directory 'docs' 23:08:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 23:08:47 INFO - no previously-included directories found matching 'docs\_build\_sources' 23:08:47 INFO - Installing collected packages: pip 23:08:47 INFO - Found existing installation: pip 0.8.2 23:08:47 INFO - Uninstalling pip: 23:08:47 INFO - Successfully uninstalled pip 23:08:47 INFO - Running setup.py install for pip 23:08:47 INFO - warning: no files found matching 'pip\cacert.pem' 23:08:47 INFO - warning: no files found matching '*.html' under directory 'docs' 23:08:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 23:08:47 INFO - no previously-included directories found matching 'docs\_build\_sources' 23:08:47 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 23:08:47 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 23:08:47 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 23:08:47 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 23:08:47 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 23:08:47 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 23:08:47 INFO - Successfully installed pip 23:08:47 INFO - Cleaning up... 23:08:47 INFO - Return code: 0 23:08:47 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 23:08:47 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')]} 23:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:47 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')]} 23:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:47 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 0x01950640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018FB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A03E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F815C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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 23:08:47 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 23:08:47 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 23:08:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:08:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:08:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:08:47 INFO - 'COMPUTERNAME': 'T-W732-IX-101', 23:08:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:08:47 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:08:47 INFO - 'DCLOCATION': 'SCL3', 23:08:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:08:47 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:08:47 INFO - 'HOMEDRIVE': 'C:', 23:08:47 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:08:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:08:47 INFO - 'KTS_VERSION': '1.19c', 23:08:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:08:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101', 23:08:47 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:08:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:47 INFO - 'MOZ_NO_REMOTE': '1', 23:08:47 INFO - 'NO_EM_RESTART': '1', 23:08:47 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:08:47 INFO - 'OS': 'Windows_NT', 23:08:47 INFO - 'OURDRIVE': 'C:', 23:08:47 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;', 23:08:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:08:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:08:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:08:47 INFO - 'PROCESSOR_LEVEL': '6', 23:08:47 INFO - 'PROCESSOR_REVISION': '1e05', 23:08:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:08:47 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:08:47 INFO - 'PROMPT': '$P$G', 23:08:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:08:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:08:47 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:08:47 INFO - 'PWD': 'C:\\slave\\test', 23:08:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:08:47 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:08:47 INFO - 'SYSTEMDRIVE': 'C:', 23:08:47 INFO - 'SYSTEMROOT': 'C:\\windows', 23:08:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:08:47 INFO - 'TEST1': 'testie', 23:08:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:08:47 INFO - 'USERDOMAIN': 'T-W732-IX-101', 23:08:47 INFO - 'USERNAME': 'cltbld', 23:08:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:08:47 INFO - 'WINDIR': 'C:\\windows', 23:08:47 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:08:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:50 INFO - Downloading/unpacking psutil>=3.1.1 23:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:08:51 INFO - Installing collected packages: psutil 23:08:51 INFO - Successfully installed psutil 23:08:51 INFO - Cleaning up... 23:08:51 INFO - Return code: 0 23:08:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 23:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:51 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01950640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018FB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A03E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F815C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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 23:08:51 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 23:08:51 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:08:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:08:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:08:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:08:51 INFO - 'COMPUTERNAME': 'T-W732-IX-101', 23:08:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:08:51 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:08:51 INFO - 'DCLOCATION': 'SCL3', 23:08:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:08:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:08:51 INFO - 'HOMEDRIVE': 'C:', 23:08:51 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:08:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:08:51 INFO - 'KTS_VERSION': '1.19c', 23:08:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:08:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101', 23:08:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:08:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:51 INFO - 'MOZ_NO_REMOTE': '1', 23:08:51 INFO - 'NO_EM_RESTART': '1', 23:08:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:08:51 INFO - 'OS': 'Windows_NT', 23:08:51 INFO - 'OURDRIVE': 'C:', 23:08:51 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;', 23:08:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:08:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:08:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:08:51 INFO - 'PROCESSOR_LEVEL': '6', 23:08:51 INFO - 'PROCESSOR_REVISION': '1e05', 23:08:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:08:51 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:08:51 INFO - 'PROMPT': '$P$G', 23:08:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:08:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:08:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:08:51 INFO - 'PWD': 'C:\\slave\\test', 23:08:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:08:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:08:51 INFO - 'SYSTEMDRIVE': 'C:', 23:08:51 INFO - 'SYSTEMROOT': 'C:\\windows', 23:08:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:08:51 INFO - 'TEST1': 'testie', 23:08:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:08:51 INFO - 'USERDOMAIN': 'T-W732-IX-101', 23:08:51 INFO - 'USERNAME': 'cltbld', 23:08:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:08:51 INFO - 'WINDIR': 'C:\\windows', 23:08:51 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:08:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:08:51 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 23:08:51 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 23:08:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:08:52 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 23:08:52 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) 23:08:52 INFO - Installing collected packages: mozsystemmonitor 23:08:52 INFO - Running setup.py install for mozsystemmonitor 23:08:53 INFO - Successfully installed mozsystemmonitor 23:08:53 INFO - Cleaning up... 23:08:53 INFO - Return code: 0 23:08:53 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 23:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:08:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:53 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 0x01950640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018FB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A03E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F815C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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 23:08:53 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 23:08:53 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 23:08:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:08:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:08:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:08:53 INFO - 'COMPUTERNAME': 'T-W732-IX-101', 23:08:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:08:53 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:08:53 INFO - 'DCLOCATION': 'SCL3', 23:08:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:08:53 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:08:53 INFO - 'HOMEDRIVE': 'C:', 23:08:53 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:08:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:08:53 INFO - 'KTS_VERSION': '1.19c', 23:08:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:08:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101', 23:08:53 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:08:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:08:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:53 INFO - 'MOZ_NO_REMOTE': '1', 23:08:53 INFO - 'NO_EM_RESTART': '1', 23:08:53 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:08:53 INFO - 'OS': 'Windows_NT', 23:08:53 INFO - 'OURDRIVE': 'C:', 23:08:53 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;', 23:08:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:08:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:08:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:08:53 INFO - 'PROCESSOR_LEVEL': '6', 23:08:53 INFO - 'PROCESSOR_REVISION': '1e05', 23:08:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:08:53 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:08:53 INFO - 'PROMPT': '$P$G', 23:08:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:08:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:08:53 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:08:53 INFO - 'PWD': 'C:\\slave\\test', 23:08:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:08:53 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:08:53 INFO - 'SYSTEMDRIVE': 'C:', 23:08:53 INFO - 'SYSTEMROOT': 'C:\\windows', 23:08:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:08:53 INFO - 'TEST1': 'testie', 23:08:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:08:53 INFO - 'USERDOMAIN': 'T-W732-IX-101', 23:08:53 INFO - 'USERNAME': 'cltbld', 23:08:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:08:53 INFO - 'WINDIR': 'C:\\windows', 23:08:53 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:08:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:08:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:08:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:53 INFO - Downloading/unpacking jsonschema==2.5.1 23:08:53 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 23:08:53 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 23:08:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:08:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:08:54 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 23:08:54 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 23:08:54 INFO - Downloading functools32-3.2.3-2.tar.gz 23:08:54 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 23:08:54 INFO - warning: no files found matching '*.txt' 23:08:54 INFO - no previously-included directories found matching 'build' 23:08:54 INFO - no previously-included directories found matching 'dist' 23:08:54 INFO - no previously-included directories found matching '.git*' 23:08:54 INFO - Installing collected packages: jsonschema, functools32 23:08:54 INFO - Running setup.py install for functools32 23:08:55 INFO - warning: no files found matching '*.txt' 23:08:55 INFO - no previously-included directories found matching 'build' 23:08:55 INFO - no previously-included directories found matching 'dist' 23:08:55 INFO - no previously-included directories found matching '.git*' 23:08:55 INFO - Successfully installed jsonschema functools32 23:08:55 INFO - Cleaning up... 23:08:55 INFO - Return code: 0 23:08:55 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 23:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:08:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:08:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:55 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 0x01950640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018FB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A03E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F815C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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 23:08:55 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 23:08:55 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 23:08:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:08:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:08:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:08:55 INFO - 'COMPUTERNAME': 'T-W732-IX-101', 23:08:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:08:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:08:55 INFO - 'DCLOCATION': 'SCL3', 23:08:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:08:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:08:55 INFO - 'HOMEDRIVE': 'C:', 23:08:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:08:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:08:55 INFO - 'KTS_VERSION': '1.19c', 23:08:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:08:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101', 23:08:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:08:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:55 INFO - 'MOZ_NO_REMOTE': '1', 23:08:55 INFO - 'NO_EM_RESTART': '1', 23:08:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:08:55 INFO - 'OS': 'Windows_NT', 23:08:55 INFO - 'OURDRIVE': 'C:', 23:08:55 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 23:08:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:08:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:08:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:08:55 INFO - 'PROCESSOR_LEVEL': '6', 23:08:55 INFO - 'PROCESSOR_REVISION': '1e05', 23:08:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:08:55 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:08:55 INFO - 'PROMPT': '$P$G', 23:08:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:08:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:08:55 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:08:55 INFO - 'PWD': 'C:\\slave\\test', 23:08:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:08:55 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:08:55 INFO - 'SYSTEMDRIVE': 'C:', 23:08:55 INFO - 'SYSTEMROOT': 'C:\\windows', 23:08:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:08:55 INFO - 'TEST1': 'testie', 23:08:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:08:55 INFO - 'USERDOMAIN': 'T-W732-IX-101', 23:08:55 INFO - 'USERNAME': 'cltbld', 23:08:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:08:55 INFO - 'WINDIR': 'C:\\windows', 23:08:55 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:08:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 23:08:56 INFO - Cleaning up... 23:08:56 INFO - Return code: 0 23:08:56 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 23:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:08:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:08:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:08:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:08:56 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01950640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018FB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A03E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F815C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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 23:08:56 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build 23:08:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:08:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:08:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:08:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:08:56 INFO - 'COMPUTERNAME': 'T-W732-IX-101', 23:08:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:08:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:08:56 INFO - 'DCLOCATION': 'SCL3', 23:08:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:08:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:08:56 INFO - 'HOMEDRIVE': 'C:', 23:08:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:08:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:08:56 INFO - 'KTS_VERSION': '1.19c', 23:08:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:08:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101', 23:08:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:08:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:08:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:08:56 INFO - 'MOZ_NO_REMOTE': '1', 23:08:56 INFO - 'NO_EM_RESTART': '1', 23:08:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:08:56 INFO - 'OS': 'Windows_NT', 23:08:56 INFO - 'OURDRIVE': 'C:', 23:08:56 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 23:08:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:08:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:08:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:08:56 INFO - 'PROCESSOR_LEVEL': '6', 23:08:56 INFO - 'PROCESSOR_REVISION': '1e05', 23:08:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:08:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:08:56 INFO - 'PROMPT': '$P$G', 23:08:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:08:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:08:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:08:56 INFO - 'PWD': 'C:\\slave\\test', 23:08:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:08:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:08:56 INFO - 'SYSTEMDRIVE': 'C:', 23:08:56 INFO - 'SYSTEMROOT': 'C:\\windows', 23:08:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:08:56 INFO - 'TEST1': 'testie', 23:08:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:08:56 INFO - 'USERDOMAIN': 'T-W732-IX-101', 23:08:56 INFO - 'USERNAME': 'cltbld', 23:08:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:08:56 INFO - 'WINDIR': 'C:\\windows', 23:08:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:08:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:08:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:08:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:08:56 INFO - Downloading/unpacking blobuploader==1.2.4 23:08:56 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 23:08:56 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 23:08:57 INFO - Downloading blobuploader-1.2.4.tar.gz 23:08:58 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 23:08:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:08:58 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 23:08:58 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 23:08:58 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 23:08:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:08:58 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 23:08:58 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 23:08:58 INFO - Downloading docopt-0.6.1.tar.gz 23:08:58 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 23:08:59 INFO - Installing collected packages: blobuploader, requests, docopt 23:08:59 INFO - Running setup.py install for blobuploader 23:08:59 INFO - Running setup.py install for requests 23:09:00 INFO - Running setup.py install for docopt 23:09:00 INFO - Successfully installed blobuploader requests docopt 23:09:00 INFO - Cleaning up... 23:09:01 INFO - Return code: 0 23:09:01 INFO - Installing None into virtualenv C:\slave\test\build/venv 23:09: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')]} 23:09:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09: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')]} 23:09:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:01 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 0x01950640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018FB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A03E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F815C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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 23:09:01 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 23:09:01 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 23:09:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:09:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:09:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:09:01 INFO - 'COMPUTERNAME': 'T-W732-IX-101', 23:09:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:09:01 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:09:01 INFO - 'DCLOCATION': 'SCL3', 23:09:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:09:01 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:09:01 INFO - 'HOMEDRIVE': 'C:', 23:09:01 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:09:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:09:01 INFO - 'KTS_VERSION': '1.19c', 23:09:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:09:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101', 23:09:01 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:09:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:01 INFO - 'MOZ_NO_REMOTE': '1', 23:09:01 INFO - 'NO_EM_RESTART': '1', 23:09:01 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:09:01 INFO - 'OS': 'Windows_NT', 23:09:01 INFO - 'OURDRIVE': 'C:', 23:09:01 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 23:09:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:09:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:09:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:09:01 INFO - 'PROCESSOR_LEVEL': '6', 23:09:01 INFO - 'PROCESSOR_REVISION': '1e05', 23:09:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:09:01 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:09:01 INFO - 'PROMPT': '$P$G', 23:09:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:09:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:09:01 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:09:01 INFO - 'PWD': 'C:\\slave\\test', 23:09:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:09:01 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:09:01 INFO - 'SYSTEMDRIVE': 'C:', 23:09:01 INFO - 'SYSTEMROOT': 'C:\\windows', 23:09:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:09:01 INFO - 'TEST1': 'testie', 23:09:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:09:01 INFO - 'USERDOMAIN': 'T-W732-IX-101', 23:09:01 INFO - 'USERNAME': 'cltbld', 23:09:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:09:01 INFO - 'WINDIR': 'C:\\windows', 23:09:01 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:09:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:09:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 23:09:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vaqmg0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 23:09:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 23:09:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uiyg7o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 23:09:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 23:09:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-owuhu7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 23:09:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 23:09:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4ub3di-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 23:09:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 23:09:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mg61y8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 23:09:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 23:09:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kpwrzi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 23:09:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 23:09:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-owhnik-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 23:09:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 23:09:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vr4wcp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 23:09:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 23:09:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rgxixi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 23:09:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 23:09:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pry5ji-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 23:09:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 23:09:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ybspj2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 23:09:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 23:09:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gat3h1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 23:09:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 23:09:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vjlj0u-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 23:09:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 23:09:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v8l01s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 23:09:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 23:09:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-30k1b5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 23:09:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 23:09:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-itm1ss-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 23:09:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 23:09:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hzd5nn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 23:09:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:09:04 INFO - Running setup.py install for manifestparser 23:09:05 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 23:09:05 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 23:09:05 INFO - Running setup.py install for mozcrash 23:09:05 INFO - Running setup.py install for mozdebug 23:09:06 INFO - Running setup.py install for mozdevice 23:09:06 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 23:09:06 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 23:09:06 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 23:09:06 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 23:09:06 INFO - Running setup.py install for mozfile 23:09:07 INFO - Running setup.py install for mozhttpd 23:09:07 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 23:09:07 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 23:09:07 INFO - Running setup.py install for mozinfo 23:09:07 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 23:09:07 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 23:09:07 INFO - Running setup.py install for mozInstall 23:09:07 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 23:09:07 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 23:09:07 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 23:09:07 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 23:09:07 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 23:09:07 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 23:09:07 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 23:09:07 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 23:09:08 INFO - Running setup.py install for mozleak 23:09:08 INFO - Running setup.py install for mozlog 23:09:08 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 23:09:08 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 23:09:09 INFO - Running setup.py install for moznetwork 23:09:09 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 23:09:09 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 23:09:09 INFO - Running setup.py install for mozprocess 23:09:10 INFO - Running setup.py install for mozprofile 23:09:10 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 23:09:10 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 23:09:10 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 23:09:10 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 23:09:10 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 23:09:10 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 23:09:10 INFO - Running setup.py install for mozrunner 23:09:11 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 23:09:11 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 23:09:11 INFO - Running setup.py install for mozscreenshot 23:09:11 INFO - Running setup.py install for moztest 23:09:12 INFO - Running setup.py install for mozversion 23:09:12 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 23:09:12 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 23:09:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:09:12 INFO - Cleaning up... 23:09:12 INFO - Return code: 0 23:09:12 INFO - Installing None into virtualenv C:\slave\test\build/venv 23:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:09:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:09:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:12 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 0x01950640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018FB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A03E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F815C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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 23:09:12 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 23:09:12 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 23:09:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:09:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:09:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:09:12 INFO - 'COMPUTERNAME': 'T-W732-IX-101', 23:09:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:09:12 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:09:12 INFO - 'DCLOCATION': 'SCL3', 23:09:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:09:12 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:09:12 INFO - 'HOMEDRIVE': 'C:', 23:09:12 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:09:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:09:12 INFO - 'KTS_VERSION': '1.19c', 23:09:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:09:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101', 23:09:12 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:09:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:09:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:12 INFO - 'MOZ_NO_REMOTE': '1', 23:09:12 INFO - 'NO_EM_RESTART': '1', 23:09:12 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:09:12 INFO - 'OS': 'Windows_NT', 23:09:12 INFO - 'OURDRIVE': 'C:', 23:09:12 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;', 23:09:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:09:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:09:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:09:12 INFO - 'PROCESSOR_LEVEL': '6', 23:09:12 INFO - 'PROCESSOR_REVISION': '1e05', 23:09:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:09:12 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:09:12 INFO - 'PROMPT': '$P$G', 23:09:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:09:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:09:12 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:09:12 INFO - 'PWD': 'C:\\slave\\test', 23:09:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:09:12 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:09:12 INFO - 'SYSTEMDRIVE': 'C:', 23:09:12 INFO - 'SYSTEMROOT': 'C:\\windows', 23:09:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:09:12 INFO - 'TEST1': 'testie', 23:09:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:09:12 INFO - 'USERDOMAIN': 'T-W732-IX-101', 23:09:12 INFO - 'USERNAME': 'cltbld', 23:09:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:09:12 INFO - 'WINDIR': 'C:\\windows', 23:09:12 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:09:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:09:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 23:09:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gmsb6i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 23:09:13 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)) 23:09:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 23:09:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gqxmxz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 23:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 23:09:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 23:09:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hkjrci-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 23:09:13 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)) 23:09:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 23:09:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g3ygcw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 23:09:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 23:09:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 23:09:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dq7e60-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 23:09:13 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)) 23:09:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 23:09:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zokfno-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 23:09:14 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)) 23:09:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 23:09:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_gfmmd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 23:09:14 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)) 23:09:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 23:09:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i5bnrf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 23:09:14 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)) 23:09:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 23:09:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rbft7h-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 23:09:14 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)) 23:09:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 23:09:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1qtskt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 23:09:14 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)) 23:09:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 23:09:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kco0ze-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 23:09:14 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)) 23:09:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 23:09:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qfsd9a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 23:09:14 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)) 23:09:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 23:09:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-agerra-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 23:09:15 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)) 23:09:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 23:09:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-r8moa9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 23:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 23:09:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 23:09:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2nycdb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 23:09:15 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)) 23:09:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 23:09:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cjj1jb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 23:09:15 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)) 23:09:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 23:09:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-c9oybh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 23:09:15 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)) 23:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 23:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==1.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 23:09:15 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)) 23:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 23:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.49->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 23:09:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 23:09:15 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 23:09:15 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 23:09:16 INFO - Downloading blessings-1.6.tar.gz 23:09:16 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 23:09:17 INFO - Installing collected packages: blessings 23:09:17 INFO - Running setup.py install for blessings 23:09:17 INFO - Successfully installed blessings 23:09:17 INFO - Cleaning up... 23:09:20 INFO - Return code: 0 23:09:20 INFO - Done creating virtualenv C:\slave\test\build/venv. 23:09:20 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 23:09:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 23:09:20 INFO - Reading from file tmpfile_stdout 23:09:20 INFO - Using _rmtree_windows ... 23:09:20 INFO - Using _rmtree_windows ... 23:09:20 INFO - Current package versions: 23:09:20 INFO - blessings == 1.6 23:09:20 INFO - blobuploader == 1.2.4 23:09:20 INFO - docopt == 0.6.1 23:09:20 INFO - functools32 == 3.2.3-2 23:09:20 INFO - jsonschema == 2.5.1 23:09:20 INFO - manifestparser == 1.1 23:09:20 INFO - mozInstall == 1.12 23:09:20 INFO - mozcrash == 1.0 23:09:20 INFO - mozdebug == 0.1 23:09:20 INFO - mozdevice == 0.49 23:09:20 INFO - mozfile == 1.2 23:09:20 INFO - mozhttpd == 0.7 23:09:20 INFO - mozinfo == 0.9 23:09:20 INFO - mozleak == 0.1 23:09:20 INFO - mozlog == 3.4 23:09:20 INFO - moznetwork == 0.27 23:09:20 INFO - mozprocess == 0.23 23:09:20 INFO - mozprofile == 0.28 23:09:20 INFO - mozrunner == 6.13 23:09:20 INFO - mozscreenshot == 0.1 23:09:20 INFO - mozsystemmonitor == 0.3 23:09:20 INFO - moztest == 0.7 23:09:20 INFO - mozversion == 1.4 23:09:20 INFO - psutil == 3.1.1 23:09:20 INFO - requests == 1.2.3 23:09:20 INFO - Installing None into virtualenv C:\slave\test\build/venv 23:09: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')]} 23:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09: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')]} 23:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:20 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 0x01950640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018FB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A03E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F815C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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 23:09:20 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 23:09:20 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 23:09:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:09:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:09:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:09:20 INFO - 'COMPUTERNAME': 'T-W732-IX-101', 23:09:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:09:20 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:09:20 INFO - 'DCLOCATION': 'SCL3', 23:09:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:09:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:09:20 INFO - 'HOMEDRIVE': 'C:', 23:09:20 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:09:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:09:20 INFO - 'KTS_VERSION': '1.19c', 23:09:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:09:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101', 23:09:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:09:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:20 INFO - 'MOZ_NO_REMOTE': '1', 23:09:20 INFO - 'NO_EM_RESTART': '1', 23:09:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:09:20 INFO - 'OS': 'Windows_NT', 23:09:20 INFO - 'OURDRIVE': 'C:', 23:09: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;', 23:09:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:09:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:09:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:09:20 INFO - 'PROCESSOR_LEVEL': '6', 23:09:20 INFO - 'PROCESSOR_REVISION': '1e05', 23:09:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:09:20 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:09:20 INFO - 'PROMPT': '$P$G', 23:09:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:09:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:09:20 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:09:20 INFO - 'PWD': 'C:\\slave\\test', 23:09:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:09:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:09:20 INFO - 'SYSTEMDRIVE': 'C:', 23:09:20 INFO - 'SYSTEMROOT': 'C:\\windows', 23:09:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:09:20 INFO - 'TEST1': 'testie', 23:09:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:09:20 INFO - 'USERDOMAIN': 'T-W732-IX-101', 23:09:20 INFO - 'USERNAME': 'cltbld', 23:09:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:09:20 INFO - 'WINDIR': 'C:\\windows', 23:09:20 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:09:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:20 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)) 23:09:20 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)) 23:09:20 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)) 23:09:20 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)) 23:09:20 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)) 23:09:20 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)) 23:09:20 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)) 23:09:20 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)) 23:09:20 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)) 23:09:20 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)) 23:09:20 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)) 23:09:20 INFO - Cleaning up... 23:09:20 INFO - Return code: 0 23:09:20 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 23:09: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')]} 23:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09: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')]} 23:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01950640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01F9F020>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018FB0C0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A03E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F815C0>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-101', 'USERDOMAIN': 'T-W732-IX-101', '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-101', '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 23:09: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', 'jsonschema'] in C:\slave\test\build 23:09: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 jsonschema 23:09:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:09:20 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:09:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:09:20 INFO - 'COMPUTERNAME': 'T-W732-IX-101', 23:09:20 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:09:20 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:09:20 INFO - 'DCLOCATION': 'SCL3', 23:09:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:09:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:09:20 INFO - 'HOMEDRIVE': 'C:', 23:09:20 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:09:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:09:20 INFO - 'KTS_VERSION': '1.19c', 23:09:20 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:09:20 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101', 23:09:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:09:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:20 INFO - 'MOZ_NO_REMOTE': '1', 23:09:20 INFO - 'NO_EM_RESTART': '1', 23:09:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:09:20 INFO - 'OS': 'Windows_NT', 23:09:20 INFO - 'OURDRIVE': 'C:', 23:09: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;', 23:09:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:09:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:09:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:09:20 INFO - 'PROCESSOR_LEVEL': '6', 23:09:20 INFO - 'PROCESSOR_REVISION': '1e05', 23:09:20 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:09:20 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:09:20 INFO - 'PROMPT': '$P$G', 23:09:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:09:20 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:09:20 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:09:20 INFO - 'PWD': 'C:\\slave\\test', 23:09:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:09:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:09:20 INFO - 'SYSTEMDRIVE': 'C:', 23:09:20 INFO - 'SYSTEMROOT': 'C:\\windows', 23:09:20 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:09:20 INFO - 'TEST1': 'testie', 23:09:20 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:09:20 INFO - 'USERDOMAIN': 'T-W732-IX-101', 23:09:20 INFO - 'USERNAME': 'cltbld', 23:09:20 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:09:20 INFO - 'WINDIR': 'C:\\windows', 23:09:20 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:09:20 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 23:09:21 INFO - Cleaning up... 23:09:21 INFO - Return code: 0 23:09:21 INFO - Running post-action listener: _resource_record_post_action 23:09:21 INFO - Running post-action listener: _start_resource_monitoring 23:09:21 INFO - Starting resource monitoring. 23:09:21 INFO - [mozharness: 2016-11-26 07:09:21.260000Z] Finished create-virtualenv step (success) 23:09:21 INFO - [mozharness: 2016-11-26 07:09:21.260000Z] Running install step. 23:09:21 INFO - Running pre-action listener: _resource_record_pre_action 23:09:21 INFO - Running main action method: install 23:09:21 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 23:09:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 23:09:21 INFO - Reading from file tmpfile_stdout 23:09:21 INFO - Using _rmtree_windows ... 23:09:21 INFO - Using _rmtree_windows ... 23:09:21 INFO - Detecting whether we're running mozinstall >=1.0... 23:09:21 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 23:09:21 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 23:09:21 INFO - Reading from file tmpfile_stdout 23:09:21 INFO - Output received: 23:09:21 INFO - Usage: mozinstall-script.py [options] installer 23:09:21 INFO - Options: 23:09:21 INFO - -h, --help show this help message and exit 23:09:21 INFO - -d DEST, --destination=DEST 23:09:21 INFO - Directory to install application into. [default: 23:09:21 INFO - "C:\slave\test"] 23:09:21 INFO - --app=APP Application being installed. [default: firefox] 23:09:21 INFO - Using _rmtree_windows ... 23:09:21 INFO - Using _rmtree_windows ... 23:09:21 INFO - mkdir: C:\slave\test\build\application 23:09:21 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-53.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 23:09:21 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-53.0a1.en-US.win32.zip --destination C:\slave\test\build\application 23:09:24 INFO - Reading from file tmpfile_stdout 23:09:24 INFO - Output received: 23:09:24 INFO - C:\slave\test\build\application\firefox\firefox.exe 23:09:24 INFO - Using _rmtree_windows ... 23:09:24 INFO - Using _rmtree_windows ... 23:09:24 INFO - Running post-action listener: _resource_record_post_action 23:09:24 INFO - [mozharness: 2016-11-26 07:09:24.198000Z] Finished install step (success) 23:09:24 INFO - [mozharness: 2016-11-26 07:09:24.199000Z] Running run-tests step. 23:09:24 INFO - Running pre-action listener: _resource_record_pre_action 23:09:24 INFO - Running main action method: run_tests 23:09:24 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 23:09:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 23:09:24 INFO - Python 2.7.3 23:09:24 INFO - Return code: 0 23:09:24 INFO - grabbing minidump binary from tooltool 23:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:09:24 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 0x01A03E90>, '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 0x0131A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F815C0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 23:09:24 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 23:09:24 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 23:09:24 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 23:09:24 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 23:09:25 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpgono1a 23:09:25 INFO - INFO - File integrity verified, renaming tmpgono1a to win32-minidump_stackwalk.exe 23:09:25 INFO - Return code: 0 23:09:25 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 23:09:25 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 23:09:25 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 23:09:25 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 23:09:25 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 23:09:25 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-101', '--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 23:09:25 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-101 --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 23:09:25 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 23:09:25 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 23:09:25 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 23:09:25 INFO - 'COMPUTERNAME': 'T-W732-IX-101', 23:09:25 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 23:09:25 INFO - 'CYGWINBASE': 'C:\\cygwin', 23:09:25 INFO - 'DCLOCATION': 'SCL3', 23:09:25 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 23:09:25 INFO - 'FP_NO_HOST_CHECK': 'NO', 23:09:25 INFO - 'HOMEDRIVE': 'C:', 23:09:25 INFO - 'HOMEPATH': '\\Users\\cltbld', 23:09:25 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 23:09:25 INFO - 'KTS_VERSION': '1.19c', 23:09:25 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 23:09:25 INFO - 'LOGONSERVER': '\\\\T-W732-IX-101', 23:09:25 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 23:09:25 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 23:09:25 INFO - 'MONDIR': 'C:\\Monitor_config\\', 23:09:25 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 23:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:25 INFO - 'MOZ_NO_REMOTE': '1', 23:09:25 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 23:09:25 INFO - 'NO_EM_RESTART': '1', 23:09:25 INFO - 'NUMBER_OF_PROCESSORS': '8', 23:09:25 INFO - 'OS': 'Windows_NT', 23:09:25 INFO - 'OURDRIVE': 'C:', 23:09: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;', 23:09:25 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 23:09:25 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 23:09:25 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 23:09:25 INFO - 'PROCESSOR_LEVEL': '6', 23:09:25 INFO - 'PROCESSOR_REVISION': '1e05', 23:09:25 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 23:09:25 INFO - 'PROGRAMFILES': 'C:\\Program Files', 23:09:25 INFO - 'PROMPT': '$P$G', 23:09:25 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 23:09:25 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 23:09:25 INFO - 'PUBLIC': 'C:\\Users\\Public', 23:09:25 INFO - 'PWD': 'C:\\slave\\test', 23:09:25 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 23:09:25 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 23:09:25 INFO - 'SLAVEDIR': 'C:\\slave\\', 23:09:25 INFO - 'SYSTEMDRIVE': 'C:', 23:09:25 INFO - 'SYSTEMROOT': 'C:\\windows', 23:09:25 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:09:25 INFO - 'TEST1': 'testie', 23:09:25 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 23:09:25 INFO - 'USERDOMAIN': 'T-W732-IX-101', 23:09:25 INFO - 'USERNAME': 'cltbld', 23:09:25 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 23:09:25 INFO - 'WINDIR': 'C:\\windows', 23:09:25 INFO - 'WINDOWS_TRACING_FLAGS': '3', 23:09:25 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 23:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 23:09:25 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-101', '--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 23:09:26 INFO - mozversion application_buildid: 20161125212619 23:09:26 INFO - mozversion application_changeset: 180c7af5922ee89f848c25c3847bd1fb22a71e37 23:09:26 INFO - mozversion application_display_name: Nightly 23:09:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:09:26 INFO - mozversion application_name: Firefox 23:09:26 INFO - mozversion application_remotingname: firefox 23:09:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 23:09:26 INFO - mozversion application_vendor: Mozilla 23:09:26 INFO - mozversion application_version: 53.0a1 23:09:26 INFO - mozversion platform_buildid: 20161125212619 23:09:26 INFO - mozversion platform_changeset: 180c7af5922ee89f848c25c3847bd1fb22a71e37 23:09:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 23:09:26 INFO - mozversion platform_version: 53.0a1 23:09:26 INFO - using testdate: 1480144166 23:09:26 INFO - actual date: 1480144166 23:09:26 INFO - starting webserver on 'localhost:50011' 23:09:26 INFO - SUITE-START | Running 2 tests 23:09:26 INFO - TEST-START | dromaeo_css 23:09:26 INFO - operating with platform_type : w7_ 23:09:26 INFO - Initialising browser for dromaeo_css test... 23:09:26 INFO - TEST-INFO | started process 2164 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpppjojl\profile http://localhost:50011/getInfo.html) 23:09:33 INFO - PROCESS | 2164 | __metrics Screen width/height:1600/1200 23:09:33 INFO - PROCESS | 2164 | colorDepth:24 23:09:33 INFO - PROCESS | 2164 | Browser inner width/height: 1010/674 23:09:33 INFO - PROCESS | 2164 | __metrics 23:09:34 INFO - PROCESS | 2164 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:09:34 INFO - PROCESS | 2164 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:09:35 INFO - TEST-INFO | 2164: exit 0 23:09:35 INFO - Browser initialized. 23:09:35 INFO - Running cycle 1/1 for dromaeo_css test... 23:09:35 INFO - TEST-INFO | started process 4004 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpppjojl\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:09:36 INFO - PROCESS | 4004 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 23:09:47 INFO - PROCESS | 4004 | RSS: Main: 138489856 23:09:47 INFO - PROCESS | 4004 | 23:10:38 INFO - PROCESS | 4004 | 1480144238380 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] 23:12:55 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:50011/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50011/tests/dromaeo/cssquery-ext.html) 23:12:56 INFO - PROCESS | 4004 | RSS: Main: 191004672 23:12:56 INFO - PROCESS | 4004 | 23:15:59 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:50011/tests/dromaeo/cssquery-ext.html (next: http://localhost:50011/tests/dromaeo/cssquery-jquery.html) 23:15:59 INFO - PROCESS | 4004 | RSS: Main: 204763136 23:15:59 INFO - PROCESS | 4004 | 23:19:02 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:50011/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50011/tests/dromaeo/cssquery-mootools.html) 23:19:02 INFO - PROCESS | 4004 | RSS: Main: 151113728 23:19:02 INFO - PROCESS | 4004 | 23:22:04 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:50011/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50011/tests/dromaeo/cssquery-prototype.html) 23:22:05 INFO - PROCESS | 4004 | RSS: Main: 139350016 23:22:05 INFO - PROCESS | 4004 | 23:25:08 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:50011/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50011/tests/dromaeo/cssquery-yui.html) 23:25:09 INFO - PROCESS | 4004 | RSS: Main: 144998400 23:25:09 INFO - PROCESS | 4004 | 23:28:12 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:50011/tests/dromaeo/cssquery-yui.html (next: http://localhost:50011/tests/dromaeo/cssquery-dojo.html) 23:28:12 INFO - PROCESS | 4004 | RSS: Main: 189992960 23:28:12 INFO - PROCESS | 4004 | 23:28:12 INFO - PROCESS | 4004 | __start_tp_report 23:28:12 INFO - PROCESS | 4004 | _x_x_mozilla_page_load 23:28:12 INFO - PROCESS | 4004 | _x_x_mozilla_page_load_details 23:28:12 INFO - PROCESS | 4004 | |i|pagename|runs| 23:28:12 INFO - PROCESS | 4004 | |0;dojo.html;2344.7069116360453;2375.643653451982;2414.058517178301;2432.781049705526;2437.4881274932263;4101.138760860219;4108.630223279908;4146;4222.7888605569715;4232.682548808836;4000.8799736007895;4070.531888832785;4108.958910410895;4186.372044193373;4232.470941132358;3515.156362473014;3586.9820650896772;3639;3672.540932383452;3682.668559829628;3611.494390785292;3633.4004889193384;3658.6890114340313;3676.963230367689;3688.188598508324;3626.70986321095;3648.890533283992;3660.396034654279;3687.59436461989;3692.7415080944343;3310.817905015223;3571.2143328467696;3580.1228698968657;3673.2653469306247;3684.871029513954;3525.6121826599056;3594.101474631342;3596.892093237207;3646.106703857545;3685.94471082934;4012;4060.2082593893906;4094.078832262747;4174.478190226222;4176.979115104436;4157.189348077126;4313.676474264443;4404.801783919699;4419.889502762425;4427.867163985085;4232.259354612961;4248.87253382399;4298.548652391516;4302.182585308781;4339.5443478434945;4041.6766658667234;4080.1635664688965;4133.400656904732;4142.730722503088;4147.543770185277;4269.4449721536;4413.183561041217;4420.071784925138;4496.527864574238;4500.752458614742;4152.252594532953;4195.391668208093;4267.8292868284925;4327.1345730853955;4346.304591265445;4246.214450326699;4281.357796330575;4284.828606855691;4287.549807270254;4305.354196870432;4284.76433796138;4328.415663885398;4330.112326972963;4382.737035777864;4386.758728269976;4324.459442569679;4345.500267469219;4374.19077470669;4403.581659742319;4411.889702757457;3045.4822680143984;3054.083774867531;3090.5518699788854;3107.704768047032;3117.267442151093;2764.25365151408;2776.694563598003;2799.90200342987;2806.424682940033;2859.2851787053237;3993.381025940984;4027.718059735849;4048.1701251243426;4058.2492238935893;4064;4241.936370954438;4278.165757677223;4297.441332626739;4308.116836048603;4322.827086916488;3862.575116737157;4033.032072302685;4051.513818341818;4054.736442131252;4058.9594104059174;3612.710983121344;3628.9455658165148;3635;3679.932819482374;3698.2973235085246;4409.382686423967;4442.20040392726;4477.171723231082;4520.932186017279;4533.274676051816;4203.0543127796;4244.405783190294;4433.334999750064;4437.667174961955;4439.8002089905385;4407.228734971303;4424.60178583929;4571.748553829571;4576.81692732287;4590.150822097789;4138.234426631083;4149.190907772955;4232.703710740218;4278.144371125725;4304.612584867377;4294.871153865389;4332.3718060880365;4338.240807858549;4404.889877752954;4424.247877860707;4123.072308730511;4195.958040419679;4281.785910704514;4283.657307415337;4288.635465985426;4004.759714417144;4072.572379900068;4084.7140700150703;4276.615104640598;4291.806868690854;4064.1058967027207;4082.999665082074;4103;4136.627703506699;4159.646430053479;4185.330347144442;4188.643965262987;4278.122984788049;4280.4863416390235;4288.93566596495;4033.9394909076973;4144.772037537964;4215.072684009513;4215.873523794291;4220.620144187038 23:28:12 INFO - PROCESS | 4004 | |1;ext.html;516.5970542976478;516.8087807511221;524.2110623511616;537.9488948549889;549.2091388400705;24316;33365;33876.322473550514;34130.65869341306;34144;33235;33318.66681333186;34336.48495272567;34394.65605343946;34395.31209375811;6954.965225173867;7063.57618542889;7071.681774320142;7089.822754431155;7095.716171353166;6368.3313252108555;6505.284418713938;6535.24844642865;6540.37866402691;6679.866402671943;8981.865272020892;9455.432674039545;9466.763330916749;9467.147956683897;9489.05109489049;9184.724458266262;9247.583858726373;9252.768680782967;9255.907440925572;9258.259339252843;6237.906431403532;6429.807105786834;9037.728868133967;9075.273978081737;9105.954470227607;24279.878600606884;24499.755002449925;30158.94443694482;30857.537136942963;31047.050530199595;2268.7277526696903;2278.498730279336;2332.3702600297825;2362.232274510791;2393.8324317297797;23683;23778.16776412817;24463.143789967267;25341;27095;3072.1551573317292;3108.704673056056;3158.494640857452;3175.2061984503875;3200.6319273283407;198331;200082;200742.9962850191;201005;201390;44387.11225775531;45137.32294015592;45341.54658453439;45592.772036139606;45616.77191614087;46315.536844631795;46320.305195422094;46341;46373.768131159806;46471.767641161576;9120.58957346921;9336.11306925841;9367.297452690938;9392.966773654893;9403.43579385239;7071.116110486189;7270.3456688897195;7334.376577990931;7358.153812311546;7371.520851144658;12749.681257968625;13074.150180238254;14616.26918654063;14882.404703811942;14930.850691493164;5952.255968003999;6224.253089629183;6260.46786023184;6365.586236894587;6445.581037232565;700.1178515071047;749.3218637133294;819.1732436666267;888.5355066342048;914.4604683236922;22158.667619985714;22860.885695571415;23119.88440057789;23486;23967.7603223969;791.0309870408819;840.537704262653;863.1842908451579;900.7162743735702;928.9224499580454;11819.290842549475;12323.507059717691;12370.938145309216;12469.75060498803;12549.435275412628;281144;282776;284503;284959;285040;40195;40247.597524024386;40525.594744052774;40683.796581016904;40824;867.3278209387648;893.1113542025726;986.8470387090013;994.2096033653263;1009.1422291052546;22490.21284255043;24211.878940605184;24316.635250470903;24518.63222051635;24617;34225.657743423246;34996;35028.29943401067;35244.29511409706;35533;243815;244821;245754;245803;246851;19177.137028834015;19947.102380392625;19976.60046799085;20320.79679203189;20761.89619051895;34367.312653747285;34419.827900860335;34503.654963451045;34509.65490345165;34590.48114278237;28587.857060715396;29091.471846560067;29296.70703293025;30544;33665.49501757426;30156.547651784804;30292.24269393283;30389.240268993453;30775.384492309582;31509.212269693442;29733.70266297396;29783.404331913673;29883;30159.246018849703;30403;27205.183844484698;27313.86343068272;27719.30701732473;27978.44043119167;28147;21064.684029739874;21391.358259251625;21470.892645537297;21514;21565.568688625826 23:28:12 INFO - PROCESS | 4004 | |2;jquery.html;276.3409268893688;306.69024285471676;314.9086764838197;318.6049298869401;321.969412905774;11019.779604407917;11942.582009629665;14502.274886255698;15082.170480623561;15087.698246035072;11804.173707840433;12367.443465044073;12378.319192444435;12381.780737008376;12384.442700078496;4189.895252618686;4835.540623640749;4849.466558678542;4853.878653033667;4863.732494712789;3494.9301013979834;3673.5040769496177;3676.5036720042717;3713.702903767692;4786.138495070886;3457.2048428861376;3677.209399979004;5415.214793854909;5610.24261724668;5716.742746576393;5527.585431092684;5683.857903552403;5754.424557544254;5755.395683453261;5762.1068734346245;4885.56029957302;4941.061198372297;4944.653874228806;4948.802047918115;4953;18624.627507449775;18637.53406164843;18888.33890813829;19041.42875713731;19200.61598768017;1540.6456515001612;1548.1330454945155;1557.4393218441353;1688.7889013873266;1702.7275635898197;11878.210124507888;12382.380880955916;12405.25568465886;12545.184563003468;12885.806712899313;1486.3385793321977;1575.393473512706;1577.227158692244;1583.667429839735;1597.5446997605743;62981;63196.36803631951;63432.682836585984;63644.04533931995;63695.68152159256;8309.37679674027;8463.66145354179;8528.786780330542;8640.481571105754;8736.475811451333;9841.704748857546;10496.632617858339;10528.157747380192;11248.26886252391;11436.714082148013;5603.831885043455;5645.379008309083;5699.886002279931;5729.369769325371;5785.479306862402;5176.197689358156;5176.9741151294;5232.92150617741;5245.10833158364;5272.894542109137;5466.316710411199;5574.108142697149;5577.494076599295;5585.329760428694;5609.439056094439;1520.2094910646624;1548.1175729834113;1549.6590750034975;1571.080917663157;1575.9842401575838;774.9302562769378;779.6218833865571;782.4366456151562;844.4595458906305;888.2228050455851;15397.84602153964;15448.536543903701;15686.215689215494;15757.290921908541;16150.919245403698;745.8321877577611;755.2749360613873;832.8167803083312;855.6449073634496;856.6059612578159;8783.956080219557;8810.823783524294;9013.94372532363;9145.63417463294;9180.495072771062;255470;255589;259855;264597;265962;60630.39369606248;60652.69673651604;60665.393346065975;61005;62840;745.0947099274299;752.6801109528539;780.0210735526991;781.1095351299523;783.0759703549752;10810.729731756453;14012.929935350257;14269.571912842632;14814.333354998835;14935.925320373328;32752;34168;34349.65650343464;34722.82638586892;34799.30401391907;41611.79194104131;42614.14771704611;42627;42811.7859410701;43450.565494345916;5945.137954996588;6244.812655620339;6265.780697675559;6282.465990390868;6294.087357333069;9833.16418104469;10082.596696132072;10284.382937023802;10624.150067994697;10671.786564268827;9132.178103970675;9156;9247.676331328372;9277.460254074264;9315.301352398461;8828.911710882809;8917.955410223167;9051.954740226256;9141.222996045411;9197.908020919705;8740.956295218482;9126.680566180152;9173.312001599774;9492.857607136037;9656.71029869105;6202.782902598387;6291.528135389955;6394.296627370893;6398;6406.327335629878;6709.36261055199;8905.064968178507;8908.376413650982;9089.318650997839;9168.495732734764 23:28:12 INFO - PROCESS | 4004 | |3;mootools.html;1151.14239891281;1153.4002318794228;1159.942002899855;1356.1252991820277;1421.2751496736662;5738.569607279455;6102.359252278514;6103.084537319399;6104.450599446049;6114.877702445949;5015.824446144386;5177.4045984711765;5198.45416231295;5302.363716354033;5311.468853114687;1688.1474855198169;1704.480133559264;1724.49127507385;1770.0530216334264;1775.0148667489539;1770.8583313334966;1778.5464706499824;1781.6882045642026;1782.946511604654;1787.4726955548078;1511.2368254031699;1732.6188238587554;1755.2013833705632;1771.8316759907789;1775.7070083436217;1475.4688312207597;1560.758082497209;1648.5878530367409;1661.2358315175034;1671.3314674130324;1649.6783127290182;1690.9577260568585;1694.8220436854076;1695.652391259801;1700.957476063096;5656.943430565724;5781.219535362714;5781.364049954502;5781.479666829963;5786.016377215883;3467.323871844991;3525.854097418324;3550.751447398683;3561.8397172127316;3571.607123216444;2192.2875065603744;2302.044651469638;2310.6418505131733;2317.6060069788177;2324.0819876148994;698.6820996446604;711.1040089487283;731.6414956671246;735.6763024269304;737.4653376302192;7582;7590.1650818409935;7669.233076692342;7686.846263074764;7705.113911900091;3026.8183908965534;3318.1372843060976;3346.4980252961764;3358.1604598850286;3366.0575038989123;6712.731490740413;6819.829504262433;6822.283660215705;6836.077129587442;6867.278935711678;3615.475756015363;3622.601513833476;3630.128769095398;3643.763155394891;3645.4714066460215;3221.581194444754;3370.005848274765;3371.140359208386;3374.122728090665;3375.6793104655017;2893.2766808297924;2923.1230630811096;2925.9707402926124;2948.0566218809777;2951.8671659775364;948.1750876737145;988.6094992478074;999.3604093380243;1001.1340190734962;1002.1281485108003;679.1341040173818;684.8287928017995;698.0192829075157;701.106089735581;711.4557363616838;3768.8680896168503;3772.13240954582;3808.409696496992;3880.825362858678;3995.10110225197;325.32170424664366;327.9016295111457;329.8845404108543;332.9483929990855;333.3000698533061;2559.436923876744;2563.935901602475;2596.8052396069993;2609.6216048673214;2621.9475610487684;6433.485321174388;6770.661466926634;6827.249002609709;6892.276310987374;6901.137357830271;6812;6853.417459515997;6865.038894554767;6865.176178858569;6870.519063665495;5372.221027951004;5458.372287186945;5497.835064948058;5515.862103447365;5537.058700021006;3635.309291234657;3701.2967536168044;3728.6084961078695;3795.981020094882;3825.407061905409;6268.1851359323;6335.65153916539;6352.396522330371;6392.93607063942;6394.936050639621;6380.489560835215;6403.29563747978;6464.127342808661;6464.612123272431;6487.22153341602;4933.963867587847;4965.081459929925;4985.850424487271;4992.326035985241;5003.599712023102;5387.784488620411;5413.9729301353245;5498.505134537911;5502.0921547944145;5503.20203570472;4979.775910083982;4994.076095922266;4995.375578052744;5130.8973820524125;5143.4085080216255;4984.127777638972;4992.80028798844;5009.972955544032;5056.494350564914;5060.089183946925;4360.4767427908855;4516.570925762047;4569.885752856205;4577.450705915311;4584.495705472462;3318.7179089777637;3367.343368043208;3383.3571621391857;3430.2110514581286;3441.7246620269816;3356.2112903467178;3359.664033596621;3389;3399.6940275375337;3440.483927410808 23:28:12 INFO - PROCESS | 4004 | |4;prototype.html;214.8624880076752;243.30657625766563;245.18353881574347;246.9641901924221;249.07220603252884;4192.5597812229735;4357.760323182228;4446.155230506201;4474.597286244238;4500.144972455235;4396.868093957179;4397.670174736892;4401.713888597247;4415.801288941997;4426.734395936246;3117.1427857339186;3205.4710972689477;3272.5582046423783;3273.5908011498564;3294.1270563300686;3179.841007949605;3261.5107733839995;3263.233140212048;3272.3291725196277;3282.294306724054;3199.6800319967933;3206.8295072298674;3255.6744325567374;3256.609206895164;3262.21706790371;3015.381846721417;3091.458994675923;3166.208447888028;3216.2120280531303;3225.080851957193;2676.437948030916;2712.281245469952;2769.0862015534826;2776.1255204610684;2800.8879644814183;3931.2923673738715;3935.8032098394965;3994.7003974702016;4012.879613611596;4035.8385664573384;3325.636489039482;3566.465030245458;3592.802395868226;3620.6922411595046;3753.193063491335;4424.778761061934;4473.709208901411;4511.571400716959;4518.638508919278;4547.818087276505;3143.3398986212924;3157.810531368102;3208.925010121612;3234.9353012939846;3237.303979644364;4632.698874573176;4698.459677137104;4713.841726043559;4716.316134160562;4719.560534037083;3267.787593806395;3298.8515516801804;3300.9106994692174;3305.669433056675;3316.6517515660507;3310.5034244863464;3328.6005679318637;3342.3315336932224;3343.3814744272386;3347.2636020075543;3811.9618803812164;3818.980905095457;3826.7512611680154;3830.8467661293234;3842.7502212356108;4200.432941552851;4319.092990472036;4524.321351797257;4563.475200351935;4565.589096981287;1124.9550017999352;1128.3906690387098;1130.5528922978435;1136.2046567402851;1150.4592841364545;747.6037700018999;770.8014038170586;776.9922300776919;779.9610019499003;784.2275358771606;4129.442525259052;4196.748195108304;4205.600467955601;4217.030083080847;4229.111886503807;4502.3471596618965;4534.319852022223;4548.2722764358205;4555.567221113989;4574.199515084847;4229.915401691949;4234.87295381139;4237.957620423818;4247.277962746341;4257;2411.481531470742;2423.7333893271725;2424.4544977379946;2427.83005189635;2434.8052155827845;4431.379606855043;4692.906141877212;4705.741184234831;4715.811367545329;4723.937114149289;4618.838340658061;4655.930161047587;4656.604188644003;4676.321933319649;4679.1577516046755;4687.070580886945;4708.81164753406;4711.623070154448;4738.407699037621;4763.499832517605;4530.977345113253;4555.772211389483;4557.248054071041;4566.762286479668;4567.406237189144;4348.9130217396105;4648.83729069481;4666.393368862026;4670.1126785910565;4677.087967846237;4870.975645121752;4877.268409738567;4883.511648835088;4883.926741098815;4884.926726099183;3206.3266713990324;3212.136960562821;3212.6466088729753;3226.225705830631;3233.1917020744813;4506.143832671913;4678.953210467852;4705.788239529161;4709.458412282632;4710.6231501479115;4407.801648925742;4465;4503.36952826598;4505.526919673518;4522.796474158737;4454.933176002298;4493.303537951622;4503;4511.774411279357;4517.548245175456;4408.162449134782;4428.68999170055;4448.288273876165;4460.710053846489;4462.397576327284;4429.712068715523;4517.796699148481;4531.320301954602;4577.764003719044;4584.15193189261;4473.686841921034;4485.48416932057;4490.3040028795585;4492.9775351123035;4522.191123550606 23:28:12 INFO - PROCESS | 4004 | |5;yui.html;528.8545649946266;539.7031632602072;549.1597855281418;561.2367180634336;620.3827191944016;10390.792184156313;10585.153187744982;10805.243632945687;11114;11121;10201.642942496996;10224;10231.437270950095;10246.129079028291;10302.072813446788;480.2124515794083;612.1643956000072;622.5704264057786;626.2234828812285;627.3788948940562;485.6673178872466;596.2546816479401;622.56420505646;628.5317438508295;628.6825153297578;655.416679155552;655.6000839487908;656.2027137028509;656.3305428462965;658.0524045374646;502.9245613158019;650.4763665249473;651.4039653716843;651.7588492257895;652.2629428745535;379.5350695398143;380.1332960849268;381.7499537802716;381.88543436968797;467.6118821378248;10163.695089147337;10470.424126673106;10480;10491.16070714341;10494.947525262402;10848.891511084867;11394.202405831595;11415.600453984154;11521.711957201138;11693.298402095901;10833.512491937881;10904.291221070684;10922.617708380169;10953.397563134024;11010.50452729629;3826.1773718650347;3828.25349056934;3836.5971572984713;3844.2888065707934;3966.1869316790276;50284;51372.48627513715;51431.74284128593;52034;52115.73942130265;8286.0056793184;8343.66625334992;8391.62237699305;8578.571071446402;8697.91302086971;16058.839411605968;16382;16447.753283700753;16494.34022639105;16496.34014639425;5682;5692.402297758758;5716.456936591017;5744.138379243147;5876.529877609781;3018;3130.6086739157604;3142.9528557071662;3145.386649603305;3246.935061298761;2072.202202152152;2103.568768402474;2153.7415510138885;2158.125958986613;2159.7732238115086;377.45646269372565;380.25659835022464;385.2679908174491;388.81531272645833;389.7349802134519;475.57198521331077;481.41267653462717;481.86026052444396;494.1346417274816;509.2259765156941;6137.447629713347;6154.353792851775;6164.815055548341;6235.968820155961;6310.968445157745;396.4885297965592;422.34747315397783;452.954704529551;503.21498462398773;536.7960175133425;2990.820550766961;3012.2168236258294;3085.5834462347298;3159.494480883048;3208.9358212835614;42338;62666.68666656638;63498.365016350166;63559.0466142999;63918.6804065986;52183;53668;53903.19145212747;53903.92192156135;55704;51523.742381287855;51949.48050519522;52845.2073218902;53435.19847202295;54116;5856.677882716321;5949.434803693643;6053.545984051126;6185.876282474415;6186.628802271878;37006.25987480289;37337.4399384004;37554;37895.81052094722;37935.81032094822;37022.8148859254;48835.511644883096;48967.51032489772;49218;50020;8422.284105851071;8426.41015128935;8437.535935523605;8560;8597.699080532151;8910.910890891268;8929.285657147542;9221.49281789508;9286.62853485853;9472.28957828152;8272.131426200162;8516.190961858614;8567.871481927652;8600.827983440422;9008.279337653103;6263.874722505433;8970.686025989058;9103;9460.337776355589;9977.750556235862;9311.487868167316;9465.148136667733;9604.279679024241;9848.409095454295;9871.950640246752;8278.793030174294;8503.702370417006;8793.29653627721;8996.820063598821;9018.774530636787;8223.383246256435;8316.916830831615;8376;8384.70653527148;8493.660253590035 23:28:12 INFO - PROCESS | 4004 | __end_tp_report 23:28:12 INFO - PROCESS | 4004 | __start_cc_report 23:28:12 INFO - PROCESS | 4004 | _x_x_mozilla_cycle_collect,1106 23:28:12 INFO - PROCESS | 4004 | __end_cc_report 23:28:12 INFO - PROCESS | 4004 | __startTimestamp1480145292697__endTimestamp 23:28:12 INFO - PROCESS | 4004 | 23:28:12 INFO - PROCESS | 4004 | ------- Summary: start ------- 23:28:12 INFO - PROCESS | 4004 | Number of tests: 6 23:28:12 INFO - PROCESS | 4004 | 23:28:12 INFO - PROCESS | 4004 | [#0] dojo.html Cycles:165 Average:4000.17 Median:4157.19 stddev:484.68 (11.7%) stddev-sans-first:468.44 23:28:12 INFO - PROCESS | 4004 | Values: 2344.7 2375.6 2414.1 2432.8 2437.5 4101.1 4108.6 4146.0 4222.8 4232.7 4000.9 4070.5 4109.0 4186.4 4232.5 3515.2 3587.0 3639.0 3672.5 3682.7 3611.5 3633.4 3658.7 3677.0 3688.2 3626.7 3648.9 3660.4 3687.6 3692.7 3310.8 3571.2 3580.1 3673.3 3684.9 3525.6 3594.1 3596.9 3646.1 3685.9 4012.0 4060.2 4094.1 4174.5 4177.0 4157.2 4313.7 4404.8 4419.9 4427.9 4232.3 4248.9 4298.5 4302.2 4339.5 4041.7 4080.2 4133.4 4142.7 4147.5 4269.4 4413.2 4420.1 4496.5 4500.8 4152.3 4195.4 4267.8 4327.1 4346.3 4246.2 4281.4 4284.8 4287.5 4305.4 4284.8 4328.4 4330.1 4382.7 4386.8 4324.5 4345.5 4374.2 4403.6 4411.9 3045.5 3054.1 3090.6 3107.7 3117.3 2764.3 2776.7 2799.9 2806.4 2859.3 3993.4 4027.7 4048.2 4058.2 4064.0 4241.9 4278.2 4297.4 4308.1 4322.8 3862.6 4033.0 4051.5 4054.7 4059.0 3612.7 3628.9 3635.0 3679.9 3698.3 4409.4 4442.2 4477.2 4520.9 4533.3 4203.1 4244.4 4433.3 4437.7 4439.8 4407.2 4424.6 4571.7 4576.8 4590.2 4138.2 4149.2 4232.7 4278.1 4304.6 4294.9 4332.4 4338.2 4404.9 4424.2 4123.1 4196.0 4281.8 4283.7 4288.6 4004.8 4072.6 4084.7 4276.6 4291.8 4064.1 4083.0 4103.0 4136.6 4159.6 4185.3 4188.6 4278.1 4280.5 4288.9 4033.9 4144.8 4215.1 4215.9 4220.6 23:28:12 INFO - PROCESS | 4004 | 23:28:12 INFO - PROCESS | 4004 | [#1] ext.html Cycles:180 Average:37639.72 Median:34003.49 stddev:64309.87 (189.1%) stddev-sans-first:64429.87 23:28:12 INFO - PROCESS | 4004 | Values: 516.6 516.8 524.2 537.9 549.2 24316.0 33365.0 33876.3 34130.7 34144.0 33235.0 33318.7 34336.5 34394.7 34395.3 6955.0 7063.6 7071.7 7089.8 7095.7 6368.3 6505.3 6535.2 6540.4 6679.9 8981.9 9455.4 9466.8 9467.1 9489.1 9184.7 9247.6 9252.8 9255.9 9258.3 6237.9 6429.8 9037.7 9075.3 9106.0 24279.9 24499.8 30158.9 30857.5 31047.1 2268.7 2278.5 2332.4 2362.2 2393.8 23683.0 23778.2 24463.1 25341.0 27095.0 3072.2 3108.7 3158.5 3175.2 3200.6 198331.0 200082.0 200743.0 201005.0 201390.0 44387.1 45137.3 45341.5 45592.8 45616.8 46315.5 46320.3 46341.0 46373.8 46471.8 9120.6 9336.1 9367.3 9393.0 9403.4 7071.1 7270.3 7334.4 7358.2 7371.5 12749.7 13074.2 14616.3 14882.4 14930.9 5952.3 6224.3 6260.5 6365.6 6445.6 700.1 749.3 819.2 888.5 914.5 22158.7 22860.9 23119.9 23486.0 23967.8 791.0 840.5 863.2 900.7 928.9 11819.3 12323.5 12370.9 12469.8 12549.4 281144.0 282776.0 284503.0 284959.0 285040.0 40195.0 40247.6 40525.6 40683.8 40824.0 867.3 893.1 986.8 994.2 1009.1 22490.2 24211.9 24316.6 24518.6 24617.0 34225.7 34996.0 35028.3 35244.3 35533.0 243815.0 244821.0 245754.0 245803.0 246851.0 19177.1 19947.1 19976.6 20320.8 20761.9 34367.3 34419.8 34503.7 34509.7 34590.5 28587.9 29091.5 29296.7 30544.0 33665.5 30156.5 30292.2 30389.2 30775.4 31509.2 29733.7 29783.4 29883.0 30159.2 30403.0 27205.2 27313.9 27719.3 27978.4 28147.0 21064.7 21391.4 21470.9 21514.0 21565.6 23:28:12 INFO - PROCESS | 4004 | 23:28:12 INFO - PROCESS | 4004 | [#2] jquery.html Cycles:180 Average:18977.46 Median:5176.59 stddev:43529.48 (840.9%) stddev-sans-first:43628.94 23:28:12 INFO - PROCESS | 4004 | Values: 276.3 306.7 314.9 318.6 322.0 11019.8 11942.6 14502.3 15082.2 15087.7 11804.2 12367.4 12378.3 12381.8 12384.4 4189.9 4835.5 4849.5 4853.9 4863.7 3494.9 3673.5 3676.5 3713.7 4786.1 3457.2 3677.2 5415.2 5610.2 5716.7 5527.6 5683.9 5754.4 5755.4 5762.1 4885.6 4941.1 4944.7 4948.8 4953.0 18624.6 18637.5 18888.3 19041.4 19200.6 1540.6 1548.1 1557.4 1688.8 1702.7 11878.2 12382.4 12405.3 12545.2 12885.8 1486.3 1575.4 1577.2 1583.7 1597.5 62981.0 63196.4 63432.7 63644.0 63695.7 8309.4 8463.7 8528.8 8640.5 8736.5 9841.7 10496.6 10528.2 11248.3 11436.7 5603.8 5645.4 5699.9 5729.4 5785.5 5176.2 5177.0 5232.9 5245.1 5272.9 5466.3 5574.1 5577.5 5585.3 5609.4 1520.2 1548.1 1549.7 1571.1 1576.0 774.9 779.6 782.4 844.5 888.2 15397.8 15448.5 15686.2 15757.3 16150.9 745.8 755.3 832.8 855.6 856.6 8784.0 8810.8 9013.9 9145.6 9180.5 255470.0 255589.0 259855.0 264597.0 265962.0 60630.4 60652.7 60665.4 61005.0 62840.0 745.1 752.7 780.0 781.1 783.1 10810.7 14012.9 14269.6 14814.3 14935.9 32752.0 34168.0 34349.7 34722.8 34799.3 41611.8 42614.1 42627.0 42811.8 43450.6 5945.1 6244.8 6265.8 6282.5 6294.1 9833.2 10082.6 10284.4 10624.2 10671.8 9132.2 9156.0 9247.7 9277.5 9315.3 8828.9 8918.0 9052.0 9141.2 9197.9 8741.0 9126.7 9173.3 9492.9 9656.7 6202.8 6291.5 6394.3 6398.0 6406.3 6709.4 8905.1 8908.4 9089.3 9168.5 23:28:12 INFO - PROCESS | 4004 | 23:28:12 INFO - PROCESS | 4004 | [#3] mootools.html Cycles:180 Average:3800.49 Median:3937.96 stddev:2046.43 (52.0%) stddev-sans-first:2042.49 23:28:12 INFO - PROCESS | 4004 | Values: 1151.1 1153.4 1159.9 1356.1 1421.3 5738.6 6102.4 6103.1 6104.5 6114.9 5015.8 5177.4 5198.5 5302.4 5311.5 1688.1 1704.5 1724.5 1770.1 1775.0 1770.9 1778.5 1781.7 1782.9 1787.5 1511.2 1732.6 1755.2 1771.8 1775.7 1475.5 1560.8 1648.6 1661.2 1671.3 1649.7 1691.0 1694.8 1695.7 1701.0 5656.9 5781.2 5781.4 5781.5 5786.0 3467.3 3525.9 3550.8 3561.8 3571.6 2192.3 2302.0 2310.6 2317.6 2324.1 698.7 711.1 731.6 735.7 737.5 7582.0 7590.2 7669.2 7686.8 7705.1 3026.8 3318.1 3346.5 3358.2 3366.1 6712.7 6819.8 6822.3 6836.1 6867.3 3615.5 3622.6 3630.1 3643.8 3645.5 3221.6 3370.0 3371.1 3374.1 3375.7 2893.3 2923.1 2926.0 2948.1 2951.9 948.2 988.6 999.4 1001.1 1002.1 679.1 684.8 698.0 701.1 711.5 3768.9 3772.1 3808.4 3880.8 3995.1 325.3 327.9 329.9 332.9 333.3 2559.4 2563.9 2596.8 2609.6 2621.9 6433.5 6770.7 6827.2 6892.3 6901.1 6812.0 6853.4 6865.0 6865.2 6870.5 5372.2 5458.4 5497.8 5515.9 5537.1 3635.3 3701.3 3728.6 3796.0 3825.4 6268.2 6335.7 6352.4 6392.9 6394.9 6380.5 6403.3 6464.1 6464.6 6487.2 4934.0 4965.1 4985.9 4992.3 5003.6 5387.8 5414.0 5498.5 5502.1 5503.2 4979.8 4994.1 4995.4 5130.9 5143.4 4984.1 4992.8 5010.0 5056.5 5060.1 4360.5 4516.6 4569.9 4577.5 4584.5 3318.7 3367.3 3383.4 3430.2 3441.7 3356.2 3359.7 3389.0 3399.7 3440.5 23:28:12 INFO - PROCESS | 4004 | 23:28:12 INFO - PROCESS | 4004 | [#4] prototype.html Cycles:180 Average:3755.25 Median:4397.27 stddev:1130.50 (25.7%) stddev-sans-first:1102.00 23:28:12 INFO - PROCESS | 4004 | Values: 214.9 243.3 245.2 247.0 249.1 4192.6 4357.8 4446.2 4474.6 4500.1 4396.9 4397.7 4401.7 4415.8 4426.7 3117.1 3205.5 3272.6 3273.6 3294.1 3179.8 3261.5 3263.2 3272.3 3282.3 3199.7 3206.8 3255.7 3256.6 3262.2 3015.4 3091.5 3166.2 3216.2 3225.1 2676.4 2712.3 2769.1 2776.1 2800.9 3931.3 3935.8 3994.7 4012.9 4035.8 3325.6 3566.5 3592.8 3620.7 3753.2 4424.8 4473.7 4511.6 4518.6 4547.8 3143.3 3157.8 3208.9 3234.9 3237.3 4632.7 4698.5 4713.8 4716.3 4719.6 3267.8 3298.9 3300.9 3305.7 3316.7 3310.5 3328.6 3342.3 3343.4 3347.3 3812.0 3819.0 3826.8 3830.8 3842.8 4200.4 4319.1 4524.3 4563.5 4565.6 1125.0 1128.4 1130.6 1136.2 1150.5 747.6 770.8 777.0 780.0 784.2 4129.4 4196.7 4205.6 4217.0 4229.1 4502.3 4534.3 4548.3 4555.6 4574.2 4229.9 4234.9 4238.0 4247.3 4257.0 2411.5 2423.7 2424.5 2427.8 2434.8 4431.4 4692.9 4705.7 4715.8 4723.9 4618.8 4655.9 4656.6 4676.3 4679.2 4687.1 4708.8 4711.6 4738.4 4763.5 4531.0 4555.8 4557.2 4566.8 4567.4 4348.9 4648.8 4666.4 4670.1 4677.1 4871.0 4877.3 4883.5 4883.9 4884.9 3206.3 3212.1 3212.6 3226.2 3233.2 4506.1 4679.0 4705.8 4709.5 4710.6 4407.8 4465.0 4503.4 4505.5 4522.8 4454.9 4493.3 4503.0 4511.8 4517.5 4408.2 4428.7 4448.3 4460.7 4462.4 4429.7 4517.8 4531.3 4577.8 4584.2 4473.7 4485.5 4490.3 4493.0 4522.2 23:28:12 INFO - PROCESS | 4004 | 23:28:12 INFO - PROCESS | 4004 | [#5] yui.html Cycles:180 Average:13247.54 Median:26355.93 stddev:17396.83 (66.0%) stddev-sans-first:17419.42 23:28:12 INFO - PROCESS | 4004 | Values: 528.9 539.7 549.2 561.2 620.4 10390.8 10585.2 10805.2 11114.0 11121.0 10201.6 10224.0 10231.4 10246.1 10302.1 480.2 612.2 622.6 626.2 627.4 485.7 596.3 622.6 628.5 628.7 655.4 655.6 656.2 656.3 658.1 502.9 650.5 651.4 651.8 652.3 379.5 380.1 381.7 381.9 467.6 10163.7 10470.4 10480.0 10491.2 10494.9 10848.9 11394.2 11415.6 11521.7 11693.3 10833.5 10904.3 10922.6 10953.4 11010.5 3826.2 3828.3 3836.6 3844.3 3966.2 50284.0 51372.5 51431.7 52034.0 52115.7 8286.0 8343.7 8391.6 8578.6 8697.9 16058.8 16382.0 16447.8 16494.3 16496.3 5682.0 5692.4 5716.5 5744.1 5876.5 3018.0 3130.6 3143.0 3145.4 3246.9 2072.2 2103.6 2153.7 2158.1 2159.8 377.5 380.3 385.3 388.8 389.7 475.6 481.4 481.9 494.1 509.2 6137.4 6154.4 6164.8 6236.0 6311.0 396.5 422.3 453.0 503.2 536.8 2990.8 3012.2 3085.6 3159.5 3208.9 42338.0 62666.7 63498.4 63559.0 63918.7 52183.0 53668.0 53903.2 53903.9 55704.0 51523.7 51949.5 52845.2 53435.2 54116.0 5856.7 5949.4 6053.5 6185.9 6186.6 37006.3 37337.4 37554.0 37895.8 37935.8 37022.8 48835.5 48967.5 49218.0 50020.0 8422.3 8426.4 8437.5 8560.0 8597.7 8910.9 8929.3 9221.5 9286.6 9472.3 8272.1 8516.2 8567.9 8600.8 9008.3 6263.9 8970.7 9103.0 9460.3 9977.8 9311.5 9465.1 9604.3 9848.4 9872.0 8278.8 8503.7 8793.3 8996.8 9018.8 8223.4 8316.9 8376.0 8384.7 8493.7 23:28:12 INFO - PROCESS | 4004 | -------- Summary: end -------- 23:28:12 INFO - PROCESS | 4004 | 23:28:13 INFO - TEST-INFO | 4004: exit 0 23:28:14 INFO - TEST-OK | dromaeo_css | took 1127838ms 23:28:14 INFO - TEST-START | kraken 23:28:14 INFO - operating with platform_type : w7_ 23:28:14 INFO - Initialising browser for kraken test... 23:28:14 INFO - TEST-INFO | started process 3644 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp5e5npv\profile http://localhost:50011/getInfo.html) 23:28:18 INFO - PROCESS | 3644 | __metrics Screen width/height:1600/1200 23:28:18 INFO - PROCESS | 3644 | colorDepth:24 23:28:18 INFO - PROCESS | 3644 | Browser inner width/height: 1010/674 23:28:18 INFO - PROCESS | 3644 | __metrics 23:28:19 INFO - PROCESS | 3644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:28:19 INFO - PROCESS | 3644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:28:19 INFO - PROCESS | 3644 | console.error: 23:28:19 INFO - PROCESS | 3644 | Message: Error: Connection closed before committing the transaction. 23:28:19 INFO - PROCESS | 3644 | Stack: 23:28:19 INFO - PROCESS | 3644 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 23:28:19 INFO - PROCESS | 3644 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 23:28:19 INFO - PROCESS | 3644 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 23:28:19 INFO - PROCESS | 3644 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 23:28:19 INFO - PROCESS | 3644 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 23:28:19 INFO - PROCESS | 3644 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 23:28:19 INFO - PROCESS | 3644 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9 23:28:19 INFO - PROCESS | 3644 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 23:28:19 INFO - PROCESS | 3644 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 23:28:19 INFO - PROCESS | 3644 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 23:28:19 INFO - PROCESS | 3644 | Barrier/this.client.addBlocker/promise13.3% 23:29:20 INFO - TinderboxPrint: I/O read bytes / time
456,716,800 / 2,281,200 23:29:20 INFO - TinderboxPrint: I/O write bytes / time
536,632,320 / 3,754,830 23:29:20 INFO - TinderboxPrint: CPU idle
8,310.9 (86.7%) 23:29:20 INFO - TinderboxPrint: CPU user
1,232.0 (12.8%) 23:29:20 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 34923008; Read time: 0; Write time: 145150 23:29:20 INFO - run-tests - Wall time: 1196s; CPU: 13.0%; Read bytes: 456716800; Write bytes: 463746048; Read time: 2281200; Write time: 3283710 23:29:21 INFO - Running post-run listener: _upload_blobber_files 23:29:21 INFO - Blob upload gear active. 23:29:21 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 23:29:21 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 23:29:21 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Firefox-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 23:29:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Firefox-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 23:29:21 INFO - (blobuploader) - INFO - Open directory for files ... 23:29:21 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 23:29:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:29:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:23 INFO - (blobuploader) - INFO - Done attempting. 23:29:23 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 23:29:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:29:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:24 INFO - (blobuploader) - INFO - Done attempting. 23:29:24 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 23:29:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:29:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:31 INFO - (blobuploader) - INFO - Done attempting. 23:29:31 INFO - (blobuploader) - INFO - Iteration through files over. 23:29:31 INFO - Return code: 0 23:29:31 INFO - rmtree: C:\slave\test\build\uploaded_files.json 23:29:31 INFO - Using _rmtree_windows ... 23:29:31 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 23:29:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4f2d12cea38fa803e0507834b668a9740739970980298da42d1585037103f2a0a663eaad707add2c71540371a5c2f34c50fc85c3cc100bba044fd9a675c824c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/479290d6782bd850c7bfcc9a072bc7c612cb9a18cac71d925670035c68da03ca97083d949fca18589062d327066c72fd61df7fbee8ae30b6cd62314fc3b0ee1e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3807e6e6a2b931e7bfc1211d4c19836eb8be6e7f6ba61f5303274fefac4024913f812f5cd538e3d5470882f2197dee2ab15af5e7cefa19b2ed6949bf1daad672"} 23:29:31 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 23:29:31 INFO - Writing to file C:\slave\test\properties\blobber_files 23:29:31 INFO - Contents: 23:29:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4f2d12cea38fa803e0507834b668a9740739970980298da42d1585037103f2a0a663eaad707add2c71540371a5c2f34c50fc85c3cc100bba044fd9a675c824c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/479290d6782bd850c7bfcc9a072bc7c612cb9a18cac71d925670035c68da03ca97083d949fca18589062d327066c72fd61df7fbee8ae30b6cd62314fc3b0ee1e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3807e6e6a2b931e7bfc1211d4c19836eb8be6e7f6ba61f5303274fefac4024913f812f5cd538e3d5470882f2197dee2ab15af5e7cefa19b2ed6949bf1daad672"} 23:29:31 INFO - Running post-run listener: copy_logs_to_upload_dir 23:29:31 INFO - Copying logs to upload dir... 23:29:31 INFO - mkdir: C:\slave\test\build\upload\logs 23:29:31 INFO - Copying logs to upload dir... 23:29:31 INFO - Using _rmtree_windows ... 23:29:31 INFO - Using _rmtree_windows ... 23:29:31 INFO - Using _rmtree_windows ... 23:29:31 INFO - Using _rmtree_windows ... 23:29:31 INFO - Using _rmtree_windows ... 23:29:31 INFO - Using _rmtree_windows ... 23:29:31 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1390.773000 ========= master_lag: -0.22 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 10 secs) (at 2016-11-25 23:29:31.517177) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 23:29:31.517866) ========= '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-101 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-101 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-101 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/Firefox-Non-PGO/sha512/4f2d12cea38fa803e0507834b668a9740739970980298da42d1585037103f2a0a663eaad707add2c71540371a5c2f34c50fc85c3cc100bba044fd9a675c824c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/479290d6782bd850c7bfcc9a072bc7c612cb9a18cac71d925670035c68da03ca97083d949fca18589062d327066c72fd61df7fbee8ae30b6cd62314fc3b0ee1e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3807e6e6a2b931e7bfc1211d4c19836eb8be6e7f6ba61f5303274fefac4024913f812f5cd538e3d5470882f2197dee2ab15af5e7cefa19b2ed6949bf1daad672"} build_url:https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/MHU67odET8mnoG4o-bqQzg/artifacts/public/build/firefox-53.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4f2d12cea38fa803e0507834b668a9740739970980298da42d1585037103f2a0a663eaad707add2c71540371a5c2f34c50fc85c3cc100bba044fd9a675c824c4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/479290d6782bd850c7bfcc9a072bc7c612cb9a18cac71d925670035c68da03ca97083d949fca18589062d327066c72fd61df7fbee8ae30b6cd62314fc3b0ee1e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3807e6e6a2b931e7bfc1211d4c19836eb8be6e7f6ba61f5303274fefac4024913f812f5cd538e3d5470882f2197dee2ab15af5e7cefa19b2ed6949bf1daad672"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-25 23:29:31.637539) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 23:29:31.638616) ========= '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-101 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-101 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-101 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-25 23:29:31.751523) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-25 23:29:31.751827) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-25 23:29:32.181894) ========= ========= Total master_lag: 1.40 =========