builder: mozilla-central_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-146 starttime: 1478009003.25 results: success (0) buildid: 20161101030207 builduid: 41ea543753304764ae96fc9ec016de46 revision: 2c773b97167252cedcba0be0c7af9d4cab192ef5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-01 07:03:23.251018) ========= master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-01 07:03:23.251438) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-01 07:03:23.251706) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-01 07:03:23.402780) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-01 07:03:23.403139) ========= '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-146 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-146 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-146 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False --07:03:25-- 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.13 MB/s 07:03:25 (9.13 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.509000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-01 07:03:24.936082) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-11-01 07:03:24.936398) ========= '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-146 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-146 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-146 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=15.501000 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-11-01 07:03:40.469802) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-01 07:03:40.470153) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev 2c773b97167252cedcba0be0c7af9d4cab192ef5 --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 2c773b97167252cedcba0be0c7af9d4cab192ef5 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-146 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-146 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-146 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-01 07:03:41,476 truncating revision to first 12 chars 2016-11-01 07:03:41,476 Setting DEBUG logging. 2016-11-01 07:03:41,476 attempt 1/10 2016-11-01 07:03:41,476 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2c773b971672?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-01 07:03:43,071 unpacking tar archive at: mozilla-central-2c773b971672/testing/mozharness/ 2016-11-01 07:03:43,201 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=2.468000 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-01 07:03:42.984362) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-01 07:03:42.984663) ========= script_repo_revision: 2c773b97167252cedcba0be0c7af9d4cab192ef5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-01 07:03:42.985030) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-01 07:03:42.985289) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-01 07:03:42.995223) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 59 secs) (at 2016-11-01 07:03:42.995513) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Firefox' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Firefox' 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', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-146 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-146 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-146 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 07:03:44 INFO - MultiFileLogger online at 20161101 07:03:44 in C:\slave\test 07:03:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:03:44 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 07:03:44 INFO - {'append_to_log': False, 07:03:44 INFO - 'base_work_dir': 'C:\\slave\\test', 07:03:44 INFO - 'blob_upload_branch': 'Firefox', 07:03:44 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 07:03:44 INFO - 'branch': 'Firefox', 07:03:44 INFO - 'buildbot_json_path': 'buildprops.json', 07:03:44 INFO - 'config_files': ('talos/windows_config.py',), 07:03:44 INFO - 'default_actions': ('clobber', 07:03:44 INFO - 'read-buildbot-config', 07:03:44 INFO - 'download-and-extract', 07:03:44 INFO - 'populate-webroot', 07:03:44 INFO - 'create-virtualenv', 07:03:44 INFO - 'install', 07:03:44 INFO - 'run-tests'), 07:03:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:03:44 INFO - 'download_minidump_stackwalk': True, 07:03:44 INFO - 'download_symbols': 'ondemand', 07:03:44 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 07:03:44 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 07:03:44 INFO - 'hg': 'c:/mozilla-build/hg/hg', 07:03:44 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 07:03:44 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 07:03:44 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 07:03:44 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 07:03:44 INFO - 'C:/mozilla-build/tooltool.py'), 07:03:44 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 07:03:44 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 07:03:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:03:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:03:44 INFO - 'installer_path': 'installer.exe', 07:03:44 INFO - 'log_level': 'info', 07:03:44 INFO - 'log_name': 'talos', 07:03:44 INFO - 'log_to_console': True, 07:03:44 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 07:03:44 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 07:03:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 07:03:44 INFO - 'opt_config_files': (), 07:03:44 INFO - 'pip_index': False, 07:03:44 INFO - 'sps_profile': False, 07:03:44 INFO - 'sps_profile_interval': 0, 07:03:44 INFO - 'suite': 'dromaeojs', 07:03:44 INFO - 'system_bits': '32', 07:03:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:03:44 INFO - 'title': 'T-W732-IX-146', 07:03:44 INFO - 'use_talos_json': True, 07:03:44 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 07:03:44 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 07:03:44 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 07:03:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:03:44 INFO - 'work_dir': 'build'} 07:03:44 INFO - [mozharness: 2016-11-01 14:03:44.080000Z] Running clobber step. 07:03:44 INFO - Running pre-action listener: _resource_record_pre_action 07:03:44 INFO - Running main action method: clobber 07:03:44 INFO - rmtree: C:\slave\test\build 07:03:44 INFO - Using _rmtree_windows ... 07:03:44 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 07:06:53 INFO - Running post-action listener: _resource_record_post_action 07:06:53 INFO - [mozharness: 2016-11-01 14:06:53.141000Z] Finished clobber step (success) 07:06:53 INFO - [mozharness: 2016-11-01 14:06:53.141000Z] Running read-buildbot-config step. 07:06:53 INFO - Running pre-action listener: _resource_record_pre_action 07:06:53 INFO - Running main action method: read_buildbot_config 07:06:53 INFO - Using buildbot properties: 07:06:53 INFO - { 07:06:53 INFO - "project": "", 07:06:53 INFO - "product": "firefox", 07:06:53 INFO - "script_repo_revision": "production", 07:06:53 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs", 07:06:53 INFO - "repository": "", 07:06:53 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs", 07:06:53 INFO - "buildid": "20161101030207", 07:06:53 INFO - "stage_platform": "win32-pgo", 07:06:53 INFO - "basedir": "C:\\slave\\test", 07:06:53 INFO - "nightly_build": "True", 07:06:53 INFO - "buildnumber": 66, 07:06:53 INFO - "slavename": "t-w732-ix-146", 07:06:53 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/", 07:06:53 INFO - "platform": "win7_ix", 07:06:53 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos", 07:06:53 INFO - "branch": "mozilla-central", 07:06:53 INFO - "repo_path": "mozilla-central", 07:06:53 INFO - "revision": "2c773b97167252cedcba0be0c7af9d4cab192ef5", 07:06:53 INFO - "pgo_build": "True", 07:06:53 INFO - "builduid": "41ea543753304764ae96fc9ec016de46", 07:06:53 INFO - "slavebuilddir": "test" 07:06:53 INFO - } 07:06:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 07:06:53 INFO - Running post-action listener: _resource_record_post_action 07:06:53 INFO - [mozharness: 2016-11-01 14:06:53.145000Z] Finished read-buildbot-config step (success) 07:06:53 INFO - [mozharness: 2016-11-01 14:06:53.145000Z] Running download-and-extract step. 07:06:53 INFO - Running pre-action listener: _resource_record_pre_action 07:06:53 INFO - Running main action method: download_and_extract 07:06:53 INFO - mkdir: C:\slave\test\build\tests 07:06:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:06:53 INFO - trying https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 07:06:53 INFO - Downloading https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 07:06:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1 07:06:54 INFO - Downloaded 1218 bytes. 07:06:54 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 07:06:54 INFO - Using the following test package requirements: 07:06:54 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 07:06:54 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:06:54 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 07:06:54 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 07:06:54 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:06:54 INFO - u'jsshell-win32.zip'], 07:06:54 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:06:54 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 07:06:54 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 07:06:54 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:06:54 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 07:06:54 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:06:54 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 07:06:54 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:06:54 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 07:06:54 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:06:54 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 07:06:54 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 07:06:54 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 07:06:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 07:06:54 INFO - Fetch https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 07:06:55 INFO - Http code: 200 07:06:55 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 07:06:55 INFO - x-amz-cf-id: hkeIUGfRuXbq78h2fEqQgYO6xyyNGbqX4N0aYewPPSPnz22YiTINrA== 07:06:55 INFO - x-amz-version-id: MCldM8sFcdg_hAdX7NSbJpApIpRs3uO5 07:06:56 INFO - Expected file size: 17163097 07:06:56 INFO - Obtained file size: 17163097 07:07:27 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 07:07:27 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 07:07:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 07:07:27 INFO - Fetch https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 07:07:27 INFO - Http code: 200 07:07:27 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 07:07:27 INFO - x-amz-cf-id: aseAXb7EfantXM7IoR8yuPosqNnRcCeNELk7wJRO40ltw6sj2HoqQg== 07:07:27 INFO - x-amz-version-id: MCldM8sFcdg_hAdX7NSbJpApIpRs3uO5 07:07:28 INFO - Expected file size: 17163097 07:07:28 INFO - Obtained file size: 17163097 07:07:37 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 07:07:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 07:07:37 INFO - Fetch https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 07:07:38 INFO - Http code: 200 07:07:38 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 07:07:38 INFO - x-amz-cf-id: upJfF8RIaRRQzyGWfqj_ciHQowhwdVLPPk2ZL9ZzTTsb2CNCAtUEfw== 07:07:38 INFO - x-amz-version-id: F.hqFa31l9Nnv_WcPbPAFfmg2H4F.8oS 07:07:39 INFO - Expected file size: 14409038 07:07:39 INFO - Obtained file size: 14409038 07:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:44 INFO - trying https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 07:07:44 INFO - Downloading https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 07:07:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1 07:07:45 INFO - Downloaded 54442919 bytes. 07:07:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 07:07:45 INFO - mkdir: C:\slave\test\properties 07:07:45 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 07:07:45 INFO - Writing to file C:\slave\test\properties\build_url 07:07:45 INFO - Contents: 07:07:45 INFO - build_url:https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 07:07:46 INFO - Running post-action listener: _resource_record_post_action 07:07:46 INFO - Running post-action listener: set_extra_try_arguments 07:07:46 INFO - [mozharness: 2016-11-01 14:07:46.100000Z] Finished download-and-extract step (success) 07:07:46 INFO - [mozharness: 2016-11-01 14:07:46.101000Z] Running populate-webroot step. 07:07:46 INFO - Running pre-action listener: _resource_record_pre_action 07:07:46 INFO - Running main action method: populate_webroot 07:07:46 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 07:07:46 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 07:07:46 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 07:07:46 INFO - u'--tppagecycles', 07:07:46 INFO - u'7'], 07:07:46 INFO - u'tests': [u'tsvgm']}}, 07:07:46 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 07:07:46 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 07:07:46 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 07:07:46 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 07:07:46 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 07:07:46 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 07:07:46 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:46 INFO - u'talos_options': [u'--disable-e10s'], 07:07:46 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 07:07:46 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:46 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 07:07:46 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:46 INFO - u'talos_options': [u'--disable-e10s'], 07:07:46 INFO - u'tests': [u'damp', u'tps']}, 07:07:46 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:46 INFO - u'tests': [u'damp', u'tps']}, 07:07:46 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 07:07:46 INFO - u'tests': [u'dromaeo_dom']}, 07:07:46 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 07:07:46 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 07:07:46 INFO - u'tests': [u'basic_compositor_video']}, 07:07:46 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 07:07:46 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 07:07:46 INFO - u'tests': [u'a11yr', 07:07:46 INFO - u'ts_paint', 07:07:46 INFO - u'tpaint', 07:07:46 INFO - u'sessionrestore', 07:07:46 INFO - u'sessionrestore_no_auto_restore', 07:07:46 INFO - u'tabpaint']}, 07:07:46 INFO - u'other-e10s': {u'tests': [u'a11yr', 07:07:46 INFO - u'ts_paint', 07:07:46 INFO - u'tpaint', 07:07:46 INFO - u'sessionrestore', 07:07:46 INFO - u'sessionrestore_no_auto_restore', 07:07:46 INFO - u'tabpaint']}, 07:07:46 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 07:07:46 INFO - u'ts_paint', 07:07:46 INFO - u'tpaint', 07:07:46 INFO - u'sessionrestore', 07:07:46 INFO - u'sessionrestore_no_auto_restore', 07:07:46 INFO - u'tabpaint']}, 07:07:46 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 07:07:46 INFO - u'ts_paint', 07:07:46 INFO - u'tpaint', 07:07:46 INFO - u'sessionrestore', 07:07:46 INFO - u'sessionrestore_no_auto_restore', 07:07:46 INFO - u'tabpaint']}, 07:07:46 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 07:07:46 INFO - u'tests': [u'a11yr', 07:07:46 INFO - u'ts_paint', 07:07:46 INFO - u'tpaint', 07:07:46 INFO - u'sessionrestore', 07:07:46 INFO - u'sessionrestore_no_auto_restore', 07:07:46 INFO - u'tabpaint']}, 07:07:46 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 07:07:46 INFO - u'tests': [u'a11yr', 07:07:46 INFO - u'ts_paint', 07:07:46 INFO - u'tpaint', 07:07:46 INFO - u'sessionrestore', 07:07:46 INFO - u'sessionrestore_no_auto_restore', 07:07:46 INFO - u'tabpaint']}, 07:07:46 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 07:07:46 INFO - u'tests': [u'tsvgx', 07:07:46 INFO - u'tsvgr_opacity', 07:07:46 INFO - u'tart', 07:07:46 INFO - u'tscrollx', 07:07:46 INFO - u'cart']}, 07:07:46 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 07:07:46 INFO - u'tsvgr_opacity', 07:07:46 INFO - u'tart', 07:07:46 INFO - u'tscrollx', 07:07:46 INFO - u'cart']}, 07:07:46 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:46 INFO - u'talos_options': [u'--disable-e10s'], 07:07:46 INFO - u'tests': [u'tp5o']}, 07:07:46 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:46 INFO - u'tests': [u'tp5o']}, 07:07:46 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:46 INFO - u'talos_options': [u'--disable-e10s', 07:07:46 INFO - u'--xperf_path', 07:07:46 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:07:46 INFO - u'tests': [u'tp5n']}, 07:07:46 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:46 INFO - u'talos_options': [u'--xperf_path', 07:07:46 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:07:46 INFO - u'tests': [u'tp5n']}}, 07:07:46 INFO - u'talos.zip': {u'path': u'', 07:07:46 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:07:46 INFO - Running post-action listener: _resource_record_post_action 07:07:46 INFO - [mozharness: 2016-11-01 14:07:46.135000Z] Finished populate-webroot step (success) 07:07:46 INFO - [mozharness: 2016-11-01 14:07:46.135000Z] Running create-virtualenv step. 07:07:46 INFO - Running pre-action listener: _resource_record_pre_action 07:07:46 INFO - Running main action method: create_virtualenv 07:07:46 INFO - Creating virtualenv C:\slave\test\build/venv 07:07:46 INFO - mkdir: C:\slave\test\build\venv\Scripts 07:07:46 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 07:07:46 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 07:07:46 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build 07:07:46 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 07:07:58 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 07:08:02 INFO - Installing distribute......................................................................................................................................................................................done. 07:08:03 INFO - Return code: 0 07:08:03 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 07:08:03 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 07:08:04 INFO - Reading from file tmpfile_stdout 07:08:04 INFO - Output received: 07:08:04 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 07:08:04 INFO - Using _rmtree_windows ... 07:08:04 INFO - Using _rmtree_windows ... 07:08:04 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 07:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:04 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01839E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0190E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:08:04 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 07:08:04 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:08:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:08:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:08:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:08:04 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 07:08:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:08:04 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:08:04 INFO - 'DCLOCATION': 'SCL3', 07:08:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:08:04 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:08:04 INFO - 'HOMEDRIVE': 'C:', 07:08:04 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:08:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:08:04 INFO - 'KTS_VERSION': '1.19c', 07:08:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:08:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 07:08:04 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:08:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:08:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:04 INFO - 'MOZ_NO_REMOTE': '1', 07:08:04 INFO - 'NO_EM_RESTART': '1', 07:08:04 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:08:04 INFO - 'OS': 'Windows_NT', 07:08:04 INFO - 'OURDRIVE': 'C:', 07:08:04 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:08:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:08:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:08:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:08:04 INFO - 'PROCESSOR_LEVEL': '6', 07:08:04 INFO - 'PROCESSOR_REVISION': '1e05', 07:08:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:08:04 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:08:04 INFO - 'PROMPT': '$P$G', 07:08:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:08:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:08:04 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:08:04 INFO - 'PWD': 'C:\\slave\\test', 07:08:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:08:04 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:08:04 INFO - 'SYSTEMDRIVE': 'C:', 07:08:04 INFO - 'SYSTEMROOT': 'C:\\windows', 07:08:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:04 INFO - 'TEST1': 'testie', 07:08:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:04 INFO - 'USERDOMAIN': 'T-W732-IX-146', 07:08:04 INFO - 'USERNAME': 'cltbld', 07:08:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:08:04 INFO - 'WINDIR': 'C:\\windows', 07:08:04 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:08:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:08:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:08:05 INFO - Ignoring indexes: http://pypi.python.org/simple/ 07:08:05 INFO - Downloading/unpacking pip>=1.5 07:08:09 INFO - Running setup.py egg_info for package pip 07:08:09 INFO - warning: no files found matching 'pip\cacert.pem' 07:08:09 INFO - warning: no files found matching '*.html' under directory 'docs' 07:08:09 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 07:08:09 INFO - no previously-included directories found matching 'docs\_build\_sources' 07:08:09 INFO - Installing collected packages: pip 07:08:09 INFO - Found existing installation: pip 0.8.2 07:08:09 INFO - Uninstalling pip: 07:08:09 INFO - Successfully uninstalled pip 07:08:09 INFO - Running setup.py install for pip 07:08:09 INFO - warning: no files found matching 'pip\cacert.pem' 07:08:09 INFO - warning: no files found matching '*.html' under directory 'docs' 07:08:09 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 07:08:09 INFO - no previously-included directories found matching 'docs\_build\_sources' 07:08:09 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 07:08:09 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 07:08:09 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 07:08:09 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 07:08:09 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 07:08:09 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 07:08:09 INFO - Successfully installed pip 07:08:09 INFO - Cleaning up... 07:08:09 INFO - Return code: 0 07:08:09 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 07:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:09 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01839E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0190E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:08:09 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 07:08:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:08:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:08:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:08:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:08:09 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 07:08:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:08:09 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:08:09 INFO - 'DCLOCATION': 'SCL3', 07:08:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:08:09 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:08:09 INFO - 'HOMEDRIVE': 'C:', 07:08:09 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:08:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:08:09 INFO - 'KTS_VERSION': '1.19c', 07:08:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:08:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 07:08:09 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:08:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:08:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:09 INFO - 'MOZ_NO_REMOTE': '1', 07:08:09 INFO - 'NO_EM_RESTART': '1', 07:08:09 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:08:09 INFO - 'OS': 'Windows_NT', 07:08:09 INFO - 'OURDRIVE': 'C:', 07:08:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:08:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:08:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:08:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:08:09 INFO - 'PROCESSOR_LEVEL': '6', 07:08:09 INFO - 'PROCESSOR_REVISION': '1e05', 07:08:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:08:09 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:08:09 INFO - 'PROMPT': '$P$G', 07:08:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:08:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:08:09 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:08:09 INFO - 'PWD': 'C:\\slave\\test', 07:08:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:08:09 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:08:09 INFO - 'SYSTEMDRIVE': 'C:', 07:08:09 INFO - 'SYSTEMROOT': 'C:\\windows', 07:08:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:09 INFO - 'TEST1': 'testie', 07:08:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:09 INFO - 'USERDOMAIN': 'T-W732-IX-146', 07:08:09 INFO - 'USERNAME': 'cltbld', 07:08:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:08:09 INFO - 'WINDIR': 'C:\\windows', 07:08:09 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:08:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:08:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:08:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:11 INFO - Downloading/unpacking psutil>=3.1.1 07:08:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:12 INFO - Installing collected packages: psutil 07:08:12 INFO - Successfully installed psutil 07:08:12 INFO - Cleaning up... 07:08:12 INFO - Return code: 0 07:08:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 07:08: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')]} 07:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08: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')]} 07:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:12 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01839E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0190E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:08:12 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 07:08:12 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:08:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:08:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:08:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:08:12 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 07:08:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:08:12 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:08:12 INFO - 'DCLOCATION': 'SCL3', 07:08:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:08:12 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:08:12 INFO - 'HOMEDRIVE': 'C:', 07:08:12 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:08:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:08:12 INFO - 'KTS_VERSION': '1.19c', 07:08:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:08:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 07:08:12 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:08:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:12 INFO - 'MOZ_NO_REMOTE': '1', 07:08:12 INFO - 'NO_EM_RESTART': '1', 07:08:12 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:08:12 INFO - 'OS': 'Windows_NT', 07:08:12 INFO - 'OURDRIVE': 'C:', 07:08: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;', 07:08:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:08:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:08:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:08:12 INFO - 'PROCESSOR_LEVEL': '6', 07:08:12 INFO - 'PROCESSOR_REVISION': '1e05', 07:08:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:08:12 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:08:12 INFO - 'PROMPT': '$P$G', 07:08:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:08:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:08:12 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:08:12 INFO - 'PWD': 'C:\\slave\\test', 07:08:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:08:12 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:08:12 INFO - 'SYSTEMDRIVE': 'C:', 07:08:12 INFO - 'SYSTEMROOT': 'C:\\windows', 07:08:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:12 INFO - 'TEST1': 'testie', 07:08:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:12 INFO - 'USERDOMAIN': 'T-W732-IX-146', 07:08:12 INFO - 'USERNAME': 'cltbld', 07:08:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:08:12 INFO - 'WINDIR': 'C:\\windows', 07:08:12 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:08:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:08:14 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 07:08:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3) 07:08:14 INFO - Installing collected packages: mozsystemmonitor 07:08:14 INFO - Running setup.py install for mozsystemmonitor 07:08:14 INFO - Successfully installed mozsystemmonitor 07:08:14 INFO - Cleaning up... 07:08:14 INFO - Return code: 0 07:08:14 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 07:08:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:14 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01839E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0190E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:08:14 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build 07:08:14 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:08:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:08:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:08:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:08:14 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 07:08:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:08:14 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:08:14 INFO - 'DCLOCATION': 'SCL3', 07:08:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:08:14 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:08:14 INFO - 'HOMEDRIVE': 'C:', 07:08:14 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:08:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:08:14 INFO - 'KTS_VERSION': '1.19c', 07:08:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:08:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 07:08:14 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:08:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:08:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:14 INFO - 'MOZ_NO_REMOTE': '1', 07:08:14 INFO - 'NO_EM_RESTART': '1', 07:08:14 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:08:14 INFO - 'OS': 'Windows_NT', 07:08:14 INFO - 'OURDRIVE': 'C:', 07:08:14 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:08:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:08:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:08:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:08:14 INFO - 'PROCESSOR_LEVEL': '6', 07:08:14 INFO - 'PROCESSOR_REVISION': '1e05', 07:08:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:08:14 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:08:14 INFO - 'PROMPT': '$P$G', 07:08:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:08:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:08:14 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:08:14 INFO - 'PWD': 'C:\\slave\\test', 07:08:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:08:14 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:08:14 INFO - 'SYSTEMDRIVE': 'C:', 07:08:14 INFO - 'SYSTEMROOT': 'C:\\windows', 07:08:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:14 INFO - 'TEST1': 'testie', 07:08:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:14 INFO - 'USERDOMAIN': 'T-W732-IX-146', 07:08:14 INFO - 'USERNAME': 'cltbld', 07:08:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:08:14 INFO - 'WINDIR': 'C:\\windows', 07:08:14 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:08:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:08:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:08:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:15 INFO - Downloading/unpacking jsonschema==2.5.1 07:08: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 07:08: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 07:08:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:08:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:08:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:16 INFO - Downloading functools32-3.2.3-2.tar.gz 07:08:16 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 07:08:16 INFO - warning: no files found matching '*.txt' 07:08:16 INFO - no previously-included directories found matching 'build' 07:08:16 INFO - no previously-included directories found matching 'dist' 07:08:16 INFO - no previously-included directories found matching '.git*' 07:08:16 INFO - Installing collected packages: jsonschema, functools32 07:08:17 INFO - Running setup.py install for functools32 07:08:17 INFO - warning: no files found matching '*.txt' 07:08:17 INFO - no previously-included directories found matching 'build' 07:08:17 INFO - no previously-included directories found matching 'dist' 07:08:17 INFO - no previously-included directories found matching '.git*' 07:08:17 INFO - Successfully installed jsonschema functools32 07:08:17 INFO - Cleaning up... 07:08:17 INFO - Return code: 0 07:08:17 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 07:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:17 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01839E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0190E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:08:17 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build 07:08:17 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:08:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:08:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:08:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:08:17 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 07:08:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:08:17 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:08:17 INFO - 'DCLOCATION': 'SCL3', 07:08:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:08:17 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:08:17 INFO - 'HOMEDRIVE': 'C:', 07:08:17 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:08:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:08:17 INFO - 'KTS_VERSION': '1.19c', 07:08:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:08:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 07:08:17 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:08:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:17 INFO - 'MOZ_NO_REMOTE': '1', 07:08:17 INFO - 'NO_EM_RESTART': '1', 07:08:17 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:08:17 INFO - 'OS': 'Windows_NT', 07:08:17 INFO - 'OURDRIVE': 'C:', 07:08:17 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:08:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:08:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:08:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:08:17 INFO - 'PROCESSOR_LEVEL': '6', 07:08:17 INFO - 'PROCESSOR_REVISION': '1e05', 07:08:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:08:17 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:08:17 INFO - 'PROMPT': '$P$G', 07:08:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:08:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:08:17 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:08:17 INFO - 'PWD': 'C:\\slave\\test', 07:08:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:08:17 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:08:17 INFO - 'SYSTEMDRIVE': 'C:', 07:08:17 INFO - 'SYSTEMROOT': 'C:\\windows', 07:08:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:17 INFO - 'TEST1': 'testie', 07:08:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:17 INFO - 'USERDOMAIN': 'T-W732-IX-146', 07:08:17 INFO - 'USERNAME': 'cltbld', 07:08:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:08:17 INFO - 'WINDIR': 'C:\\windows', 07:08:17 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:08:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:08:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 07:08:17 INFO - Cleaning up... 07:08:18 INFO - Return code: 0 07:08:18 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 07:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:18 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01839E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0190E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:08:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build 07:08:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:08:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:08:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:08:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:08:18 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 07:08:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:08:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:08:18 INFO - 'DCLOCATION': 'SCL3', 07:08:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:08:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:08:18 INFO - 'HOMEDRIVE': 'C:', 07:08:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:08:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:08:18 INFO - 'KTS_VERSION': '1.19c', 07:08:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:08:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 07:08:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:08:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:08:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:18 INFO - 'MOZ_NO_REMOTE': '1', 07:08:18 INFO - 'NO_EM_RESTART': '1', 07:08:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:08:18 INFO - 'OS': 'Windows_NT', 07:08:18 INFO - 'OURDRIVE': 'C:', 07:08:18 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:08:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:08:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:08:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:08:18 INFO - 'PROCESSOR_LEVEL': '6', 07:08:18 INFO - 'PROCESSOR_REVISION': '1e05', 07:08:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:08:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:08:18 INFO - 'PROMPT': '$P$G', 07:08:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:08:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:08:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:08:18 INFO - 'PWD': 'C:\\slave\\test', 07:08:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:08:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:08:18 INFO - 'SYSTEMDRIVE': 'C:', 07:08:18 INFO - 'SYSTEMROOT': 'C:\\windows', 07:08:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:18 INFO - 'TEST1': 'testie', 07:08:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:18 INFO - 'USERDOMAIN': 'T-W732-IX-146', 07:08:18 INFO - 'USERNAME': 'cltbld', 07:08:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:08:18 INFO - 'WINDIR': 'C:\\windows', 07:08:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:08:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:08:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:08:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:18 INFO - Downloading/unpacking blobuploader==1.2.4 07:08:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:19 INFO - Downloading blobuploader-1.2.4.tar.gz 07:08:19 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 07:08:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:19 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 07:08:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:08:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:20 INFO - Downloading docopt-0.6.1.tar.gz 07:08:20 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 07:08:20 INFO - Installing collected packages: blobuploader, requests, docopt 07:08:20 INFO - Running setup.py install for blobuploader 07:08:20 INFO - Running setup.py install for requests 07:08:21 INFO - Running setup.py install for docopt 07:08:21 INFO - Successfully installed blobuploader requests docopt 07:08:21 INFO - Cleaning up... 07:08:23 INFO - Return code: 0 07:08:23 INFO - Installing None into virtualenv C:\slave\test\build/venv 07:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:23 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01839E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0190E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:08:23 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 07:08:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:08:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:08:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:08:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:08:23 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 07:08:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:08:23 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:08:23 INFO - 'DCLOCATION': 'SCL3', 07:08:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:08:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:08:23 INFO - 'HOMEDRIVE': 'C:', 07:08:23 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:08:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:08:23 INFO - 'KTS_VERSION': '1.19c', 07:08:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:08:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 07:08:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:08:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:08:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:23 INFO - 'MOZ_NO_REMOTE': '1', 07:08:23 INFO - 'NO_EM_RESTART': '1', 07:08:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:08:23 INFO - 'OS': 'Windows_NT', 07:08:23 INFO - 'OURDRIVE': 'C:', 07:08:23 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:08:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:08:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:08:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:08:23 INFO - 'PROCESSOR_LEVEL': '6', 07:08:23 INFO - 'PROCESSOR_REVISION': '1e05', 07:08:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:08:23 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:08:23 INFO - 'PROMPT': '$P$G', 07:08:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:08:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:08:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:08:23 INFO - 'PWD': 'C:\\slave\\test', 07:08:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:08:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:08:23 INFO - 'SYSTEMDRIVE': 'C:', 07:08:23 INFO - 'SYSTEMROOT': 'C:\\windows', 07:08:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:23 INFO - 'TEST1': 'testie', 07:08:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:23 INFO - 'USERDOMAIN': 'T-W732-IX-146', 07:08:23 INFO - 'USERNAME': 'cltbld', 07:08:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:08:23 INFO - 'WINDIR': 'C:\\windows', 07:08:23 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:08:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:08:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:08:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 07:08:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ilevxo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 07:08:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 07:08:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kojx54-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 07:08:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 07:08:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gbkurs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 07:08:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 07:08:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i4ot2a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 07:08:23 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 07:08:23 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4re_qi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 07:08:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 07:08:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xm57ig-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 07:08:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 07:08:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pgsfka-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 07:08:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 07:08:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-r9rguj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 07:08:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 07:08:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qxtubm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 07:08:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 07:08:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xx0dge-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 07:08:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 07:08:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hr49h0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 07:08:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 07:08:24 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-f0i0ak-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 07:08:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 07:08:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ixomd3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 07:08:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 07:08:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2arjed-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 07:08:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 07:08:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zvlwdy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 07:08:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 07:08:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qy6dzq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 07:08:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 07:08:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-upicux-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 07:08:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:08:25 INFO - Running setup.py install for manifestparser 07:08:25 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 07:08:25 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 07:08:26 INFO - Running setup.py install for mozcrash 07:08:26 INFO - Running setup.py install for mozdebug 07:08:26 INFO - Running setup.py install for mozdevice 07:08:27 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 07:08:27 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 07:08:27 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 07:08:27 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 07:08:27 INFO - Running setup.py install for mozfile 07:08:27 INFO - Running setup.py install for mozhttpd 07:08:28 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 07:08:28 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 07:08:28 INFO - Running setup.py install for mozinfo 07:08:28 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 07:08:28 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 07:08:28 INFO - Running setup.py install for mozInstall 07:08:28 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 07:08:28 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 07:08:28 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 07:08:28 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 07:08:28 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 07:08:28 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 07:08:28 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 07:08:28 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 07:08:28 INFO - Running setup.py install for mozleak 07:08:29 INFO - Running setup.py install for mozlog 07:08:29 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 07:08:29 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 07:08:30 INFO - Running setup.py install for moznetwork 07:08:30 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 07:08:30 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 07:08:30 INFO - Running setup.py install for mozprocess 07:08:31 INFO - Running setup.py install for mozprofile 07:08:31 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 07:08:31 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 07:08:31 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 07:08:31 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 07:08:31 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 07:08:31 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 07:08:31 INFO - Running setup.py install for mozrunner 07:08:31 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 07:08:31 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 07:08:32 INFO - Running setup.py install for mozscreenshot 07:08:32 INFO - Running setup.py install for moztest 07:08:33 INFO - Running setup.py install for mozversion 07:08:33 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 07:08:33 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 07:08:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:08:33 INFO - Cleaning up... 07:08:33 INFO - Return code: 0 07:08:33 INFO - Installing None into virtualenv C:\slave\test\build/venv 07:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:33 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01839E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0190E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:08:33 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 07:08:33 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:08:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:08:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:08:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:08:33 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 07:08:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:08:33 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:08:33 INFO - 'DCLOCATION': 'SCL3', 07:08:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:08:33 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:08:33 INFO - 'HOMEDRIVE': 'C:', 07:08:33 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:08:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:08:33 INFO - 'KTS_VERSION': '1.19c', 07:08:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:08:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 07:08:33 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:08:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:33 INFO - 'MOZ_NO_REMOTE': '1', 07:08:33 INFO - 'NO_EM_RESTART': '1', 07:08:33 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:08:33 INFO - 'OS': 'Windows_NT', 07:08:33 INFO - 'OURDRIVE': 'C:', 07:08:33 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:08:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:08:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:08:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:08:33 INFO - 'PROCESSOR_LEVEL': '6', 07:08:33 INFO - 'PROCESSOR_REVISION': '1e05', 07:08:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:08:33 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:08:33 INFO - 'PROMPT': '$P$G', 07:08:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:08:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:08:33 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:08:33 INFO - 'PWD': 'C:\\slave\\test', 07:08:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:08:33 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:08:33 INFO - 'SYSTEMDRIVE': 'C:', 07:08:33 INFO - 'SYSTEMROOT': 'C:\\windows', 07:08:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:33 INFO - 'TEST1': 'testie', 07:08:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:33 INFO - 'USERDOMAIN': 'T-W732-IX-146', 07:08:33 INFO - 'USERNAME': 'cltbld', 07:08:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:08:33 INFO - 'WINDIR': 'C:\\windows', 07:08:33 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:08:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 07:08:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rsdxry-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 07:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1)) 07:08:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 07:08:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-clskgu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 07:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 07:08:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 07:08:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-twa37b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 07:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 07:08:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 07:08:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mjsvru-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 07:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 07:08:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 07:08:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i2xqkf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 07:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5)) 07:08:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 07:08:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uzdz1f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 07:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6)) 07:08:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 07:08:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gd1vyg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 07:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7)) 07:08:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 07:08:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hq7qdv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 07:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8)) 07:08:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 07:08:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-q_umrt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 07:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9)) 07:08:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 07:08:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0bioo8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 07:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 07:08:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 07:08:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ghp1oo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 07:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11)) 07:08:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 07:08:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w0l753-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 07:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12)) 07:08:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 07:08:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-crcnkc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 07:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13)) 07:08:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 07:08:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cabliu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 07:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 07:08:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 07:08:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-azudld-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 07:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15)) 07:08:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 07:08:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ww1w8d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 07:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16)) 07:08:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 07:08:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i0vv10-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 07:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17)) 07:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 07:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 07:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 07:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 07:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 07:08:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 07:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:37 INFO - Downloading blessings-1.6.tar.gz 07:08:37 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 07:08:38 INFO - Installing collected packages: blessings 07:08:38 INFO - Running setup.py install for blessings 07:08:38 INFO - Successfully installed blessings 07:08:38 INFO - Cleaning up... 07:08:41 INFO - Return code: 0 07:08:41 INFO - Done creating virtualenv C:\slave\test\build/venv. 07:08:41 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 07:08:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 07:08:41 INFO - Reading from file tmpfile_stdout 07:08:41 INFO - Using _rmtree_windows ... 07:08:41 INFO - Using _rmtree_windows ... 07:08:41 INFO - Current package versions: 07:08:41 INFO - blessings == 1.6 07:08:41 INFO - blobuploader == 1.2.4 07:08:41 INFO - docopt == 0.6.1 07:08:41 INFO - functools32 == 3.2.3-2 07:08:41 INFO - jsonschema == 2.5.1 07:08:41 INFO - manifestparser == 1.1 07:08:41 INFO - mozInstall == 1.12 07:08:41 INFO - mozcrash == 0.17 07:08:41 INFO - mozdebug == 0.1 07:08:41 INFO - mozdevice == 0.48 07:08:41 INFO - mozfile == 1.2 07:08:41 INFO - mozhttpd == 0.7 07:08:41 INFO - mozinfo == 0.9 07:08:41 INFO - mozleak == 0.1 07:08:41 INFO - mozlog == 3.4 07:08:41 INFO - moznetwork == 0.27 07:08:41 INFO - mozprocess == 0.23 07:08:41 INFO - mozprofile == 0.28 07:08:41 INFO - mozrunner == 6.12 07:08:41 INFO - mozscreenshot == 0.1 07:08:41 INFO - mozsystemmonitor == 0.3 07:08:41 INFO - moztest == 0.7 07:08:41 INFO - mozversion == 1.4 07:08:41 INFO - psutil == 3.1.1 07:08:41 INFO - requests == 1.2.3 07:08:41 INFO - Installing None into virtualenv C:\slave\test\build/venv 07:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:41 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 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01839E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0190E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:08:41 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos 07:08:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:08:41 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:08:41 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:08:41 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:08:41 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 07:08:41 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:08:41 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:08:41 INFO - 'DCLOCATION': 'SCL3', 07:08:41 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:08:41 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:08:41 INFO - 'HOMEDRIVE': 'C:', 07:08:41 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:08:41 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:08:41 INFO - 'KTS_VERSION': '1.19c', 07:08:41 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:08:41 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 07:08:41 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:08:41 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:41 INFO - 'MOZ_NO_REMOTE': '1', 07:08:41 INFO - 'NO_EM_RESTART': '1', 07:08:41 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:08:41 INFO - 'OS': 'Windows_NT', 07:08:41 INFO - 'OURDRIVE': 'C:', 07:08:41 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:08:41 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:08:41 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:08:41 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:08:41 INFO - 'PROCESSOR_LEVEL': '6', 07:08:41 INFO - 'PROCESSOR_REVISION': '1e05', 07:08:41 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:08:41 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:08:41 INFO - 'PROMPT': '$P$G', 07:08:41 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:08:41 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:08:41 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:08:41 INFO - 'PWD': 'C:\\slave\\test', 07:08:41 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:08:41 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:08:41 INFO - 'SYSTEMDRIVE': 'C:', 07:08:41 INFO - 'SYSTEMROOT': 'C:\\windows', 07:08:41 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:41 INFO - 'TEST1': 'testie', 07:08:41 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:41 INFO - 'USERDOMAIN': 'T-W732-IX-146', 07:08:41 INFO - 'USERNAME': 'cltbld', 07:08:41 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:08:41 INFO - 'WINDIR': 'C:\\windows', 07:08:41 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:08:41 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 07:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2)) 07:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3)) 07:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 07:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5)) 07:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6)) 07:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7)) 07:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8)) 07:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9)) 07:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 07:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 07:08:42 INFO - Cleaning up... 07:08:42 INFO - Return code: 0 07:08:42 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 07: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')]} 07:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07: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')]} 07:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01791640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01839E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0173B930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01858E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0190E520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-146', 'USERDOMAIN': 'T-W732-IX-146', '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-146', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07: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', 'jsonschema'] in C:\slave\test\build 07: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 jsonschema 07:08:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:08:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:08:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:08:42 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 07:08:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:08:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:08:42 INFO - 'DCLOCATION': 'SCL3', 07:08:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:08:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:08:42 INFO - 'HOMEDRIVE': 'C:', 07:08:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:08:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:08:42 INFO - 'KTS_VERSION': '1.19c', 07:08:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:08:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 07:08:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:08:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:42 INFO - 'MOZ_NO_REMOTE': '1', 07:08:42 INFO - 'NO_EM_RESTART': '1', 07:08:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:08:42 INFO - 'OS': 'Windows_NT', 07:08:42 INFO - 'OURDRIVE': 'C:', 07: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;', 07:08:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:08:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:08:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:08:42 INFO - 'PROCESSOR_LEVEL': '6', 07:08:42 INFO - 'PROCESSOR_REVISION': '1e05', 07:08:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:08:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:08:42 INFO - 'PROMPT': '$P$G', 07:08:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:08:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:08:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:08:42 INFO - 'PWD': 'C:\\slave\\test', 07:08:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:08:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:08:42 INFO - 'SYSTEMDRIVE': 'C:', 07:08:42 INFO - 'SYSTEMROOT': 'C:\\windows', 07:08:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:42 INFO - 'TEST1': 'testie', 07:08:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:42 INFO - 'USERDOMAIN': 'T-W732-IX-146', 07:08:42 INFO - 'USERNAME': 'cltbld', 07:08:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:08:42 INFO - 'WINDIR': 'C:\\windows', 07:08:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:08:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 07:08:42 INFO - Cleaning up... 07:08:42 INFO - Return code: 0 07:08:42 INFO - Running post-action listener: _resource_record_post_action 07:08:42 INFO - Running post-action listener: _start_resource_monitoring 07:08:42 INFO - Starting resource monitoring. 07:08:42 INFO - [mozharness: 2016-11-01 14:08:42.659000Z] Finished create-virtualenv step (success) 07:08:42 INFO - [mozharness: 2016-11-01 14:08:42.660000Z] Running install step. 07:08:42 INFO - Running pre-action listener: _resource_record_pre_action 07:08:42 INFO - Running main action method: install 07:08:42 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 07:08:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 07:08:43 INFO - Reading from file tmpfile_stdout 07:08:43 INFO - Using _rmtree_windows ... 07:08:43 INFO - Using _rmtree_windows ... 07:08:43 INFO - Detecting whether we're running mozinstall >=1.0... 07:08:43 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 07:08:43 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 07:08:43 INFO - Reading from file tmpfile_stdout 07:08:43 INFO - Output received: 07:08:43 INFO - Usage: mozinstall-script.py [options] installer 07:08:43 INFO - Options: 07:08:43 INFO - -h, --help show this help message and exit 07:08:43 INFO - -d DEST, --destination=DEST 07:08:43 INFO - Directory to install application into. [default: 07:08:43 INFO - "C:\slave\test"] 07:08:43 INFO - --app=APP Application being installed. [default: firefox] 07:08:43 INFO - Using _rmtree_windows ... 07:08:43 INFO - Using _rmtree_windows ... 07:08:43 INFO - mkdir: C:\slave\test\build\application 07:08:43 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 07:08:43 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application 07:08:45 INFO - Reading from file tmpfile_stdout 07:08:45 INFO - Output received: 07:08:45 INFO - C:\slave\test\build\application\firefox\firefox.exe 07:08:45 INFO - Using _rmtree_windows ... 07:08:45 INFO - Using _rmtree_windows ... 07:08:45 INFO - Running post-action listener: _resource_record_post_action 07:08:45 INFO - [mozharness: 2016-11-01 14:08:45.573000Z] Finished install step (success) 07:08:45 INFO - [mozharness: 2016-11-01 14:08:45.573000Z] Running run-tests step. 07:08:45 INFO - Running pre-action listener: _resource_record_pre_action 07:08:45 INFO - Running main action method: run_tests 07:08:45 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 07:08:45 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 07:08:45 INFO - Python 2.7.3 07:08:45 INFO - Return code: 0 07:08:45 INFO - grabbing minidump binary from tooltool 07:08:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:45 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\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 0x01858E90>, '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 0x0124A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x0190E520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 07:08:45 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build 07:08:45 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o 07:08:45 INFO - Calling ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600 07:08:45 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 07:08:46 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpn3qzpx 07:08:46 INFO - INFO - File integrity verified, renaming tmpn3qzpx to win32-minidump_stackwalk.exe 07:08:46 INFO - Return code: 0 07:08:46 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 07:08:46 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 07:08:46 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 07:08:46 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 07:08:46 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 07:08:46 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-146', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] in C:\slave\test\build 07:08:46 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Firefox --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-146 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log 07:08:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:08:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:08:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:08:46 INFO - 'COMPUTERNAME': 'T-W732-IX-146', 07:08:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:08:46 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:08:46 INFO - 'DCLOCATION': 'SCL3', 07:08:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:08:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:08:46 INFO - 'HOMEDRIVE': 'C:', 07:08:46 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:08:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:08:46 INFO - 'KTS_VERSION': '1.19c', 07:08:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:08:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-146', 07:08:46 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 07:08:46 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 07:08:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:08:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:46 INFO - 'MOZ_NO_REMOTE': '1', 07:08:46 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 07:08:46 INFO - 'NO_EM_RESTART': '1', 07:08:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:08:46 INFO - 'OS': 'Windows_NT', 07:08:46 INFO - 'OURDRIVE': 'C:', 07:08:46 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:08:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:08:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:08:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:08:46 INFO - 'PROCESSOR_LEVEL': '6', 07:08:46 INFO - 'PROCESSOR_REVISION': '1e05', 07:08:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:08:46 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:08:46 INFO - 'PROMPT': '$P$G', 07:08:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:08:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:08:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:08:46 INFO - 'PWD': 'C:\\slave\\test', 07:08:46 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 07:08:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:08:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:08:46 INFO - 'SYSTEMDRIVE': 'C:', 07:08:46 INFO - 'SYSTEMROOT': 'C:\\windows', 07:08:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:46 INFO - 'TEST1': 'testie', 07:08:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:08:46 INFO - 'USERDOMAIN': 'T-W732-IX-146', 07:08:46 INFO - 'USERNAME': 'cltbld', 07:08:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:08:46 INFO - 'WINDIR': 'C:\\windows', 07:08:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:08:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:08:46 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-146', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] with output_timeout 3600 07:08:47 INFO - mozversion application_buildid: 20161101030207 07:08:47 INFO - mozversion application_changeset: 2c773b97167252cedcba0be0c7af9d4cab192ef5 07:08:47 INFO - mozversion application_display_name: Nightly 07:08:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:08:47 INFO - mozversion application_name: Firefox 07:08:47 INFO - mozversion application_remotingname: firefox 07:08:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:08:47 INFO - mozversion application_vendor: Mozilla 07:08:47 INFO - mozversion application_version: 52.0a1 07:08:47 INFO - mozversion platform_buildid: 20161101030207 07:08:47 INFO - mozversion platform_changeset: 2c773b97167252cedcba0be0c7af9d4cab192ef5 07:08:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:08:47 INFO - mozversion platform_version: 52.0a1 07:08:47 INFO - using testdate: 1478009327 07:08:47 INFO - actual date: 1478009327 07:08:47 INFO - starting webserver on 'localhost:49699' 07:08:47 INFO - SUITE-START | Running 2 tests 07:08:47 INFO - TEST-START | dromaeo_css 07:08:47 INFO - operating with platform_type : w7_ 07:08:47 INFO - Initialising browser for dromaeo_css test... 07:08:47 INFO - TEST-INFO | started process 4040 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmppps2pt\profile http://localhost:49699/getInfo.html) 07:08:55 INFO - PROCESS | 4040 | __metrics Screen width/height:1600/1200 07:08:55 INFO - PROCESS | 4040 | colorDepth:24 07:08:55 INFO - PROCESS | 4040 | Browser inner width/height: 1010/674 07:08:55 INFO - PROCESS | 4040 | __metrics 07:08:55 INFO - PROCESS | 4040 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:08:55 INFO - PROCESS | 4040 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:08:57 INFO - TEST-INFO | 4040: exit 0 07:08:57 INFO - Browser initialized. 07:08:57 INFO - Running cycle 1/1 for dromaeo_css test... 07:08:57 INFO - TEST-INFO | started process 304 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmppps2pt\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:08:57 INFO - PROCESS | 304 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 07:09:08 INFO - PROCESS | 304 | RSS: Main: 123457536 07:09:08 INFO - PROCESS | 304 | 07:10:00 INFO - PROCESS | 304 | 1478009400457 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:12:11 INFO - PROCESS | 304 | Cycle 1(1): loaded http://localhost:49699/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49699/tests/dromaeo/cssquery-ext.html) 07:12:12 INFO - PROCESS | 304 | RSS: Main: 199757824 07:12:12 INFO - PROCESS | 304 | 07:15:15 INFO - PROCESS | 304 | Cycle 1(1): loaded http://localhost:49699/tests/dromaeo/cssquery-ext.html (next: http://localhost:49699/tests/dromaeo/cssquery-jquery.html) 07:15:15 INFO - PROCESS | 304 | RSS: Main: 199479296 07:15:15 INFO - PROCESS | 304 | 07:18:17 INFO - PROCESS | 304 | Cycle 1(1): loaded http://localhost:49699/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49699/tests/dromaeo/cssquery-mootools.html) 07:18:18 INFO - PROCESS | 304 | RSS: Main: 145760256 07:18:18 INFO - PROCESS | 304 | 07:21:20 INFO - PROCESS | 304 | Cycle 1(1): loaded http://localhost:49699/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49699/tests/dromaeo/cssquery-prototype.html) 07:21:21 INFO - PROCESS | 304 | RSS: Main: 138133504 07:21:21 INFO - PROCESS | 304 | 07:24:23 INFO - PROCESS | 304 | Cycle 1(1): loaded http://localhost:49699/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49699/tests/dromaeo/cssquery-yui.html) 07:24:24 INFO - PROCESS | 304 | RSS: Main: 134172672 07:24:24 INFO - PROCESS | 304 | 07:27:26 INFO - PROCESS | 304 | Cycle 1(1): loaded http://localhost:49699/tests/dromaeo/cssquery-yui.html (next: http://localhost:49699/tests/dromaeo/cssquery-dojo.html) 07:27:27 INFO - PROCESS | 304 | RSS: Main: 185032704 07:27:27 INFO - PROCESS | 304 | 07:27:27 INFO - PROCESS | 304 | __start_tp_report 07:27:27 INFO - PROCESS | 304 | _x_x_mozilla_page_load 07:27:27 INFO - PROCESS | 304 | _x_x_mozilla_page_load_details 07:27:27 INFO - PROCESS | 304 | |i|pagename|runs| 07:27:27 INFO - PROCESS | 304 | |0;dojo.html;2759.765419939305;2770.3074231442138;2823.9717602823966;2827.9293017674554;2843.3033906692876;4559.17934771741;4600.37894884191;4656.765957021392;4699.530046995299;4702.5532574405415;4525.547445255473;4765.714057156573;4824.903501929959;4919.212925931843;4922.089413458512;4041.4139949707273;4277.8074986625525;4315.503717072545;4325.048489332342;4361.433013708213;4080.5919408059253;4238.809253583596;4302.698811083225;4328.63206627437;4367.868963931071;4069.776162311072;4138.689598280126;4212.4945006599255;4363.454568178978;4378.233809083397;4064.471618689581;4165.10450253194;4176.45706058212;4300.6344460720875;4368.541303163186;4067.247559201557;4078.5309689385804;4155.522114956758;4156.147989662112;4186.330187170038;4571.3828633134435;4779.115863565216;4806.0628177505405;4891.217405215184;4899.730514821684;4722.952770472286;4765.594924431428;4768.451628062782;4864.124457597631;4963.975180124113;4792.9281060784115;4811.759412029384;4823.986962737829;4839.806407743686;4866.635002374836;4437.378766972594;4475.30632751924;4482.798274077674;4497.145542346976;4524.049949510574;4913.410390753061;4919.942212424275;5043.621728370387;5117.692938423632;5232.476752324737;4833.3958255218095;5009.298698182258;5019.67372120811;5027.120253955543;5036.899262014739;5062.038212739568;5099.18413053917;5103.97448012765;5113.488651134857;5117.948820511821;4823.204171311764;5008.323876276656;5045.747712614355;5076.492350764894;5077.492250774893;4758.8885451805045;4848.418189817245;4862.270659401047;4872.707637541759;4922.556969872729;3182.872685092617;3199.77601567888;3201.695838895301;3250.658680838525;3250.967490325114;2946.646402431722;2949.9115026548807;3084.3368675734823;3169.35028319194;3187.15540381799;4422.690411671152;4511.097780443859;4511.661625378109;4520.796564154621;4521.276595744665;4920.581750551171;4938.456769755324;5022.221555658879;5024.924626130611;5251.4485978972425;4456.5766252205985;4648.767561621906;4675.298705194248;4705.388299521041;4711.528847115261;3985.2826491231276;4102.0360215349365;4262.381954616625;4264.957350426456;4275.251830929575;5222.112240919053;5238.895222095537;5284.392294886137;5312.070387682217;5329.653572517774;5123.948760512347;5126.077306084941;5129.948700513097;5134.281200631989;5137.306463627362;5259.500347466985;5355.973220133875;5366.168243922199;5367.919481207707;5370.62405631602;5021.623378246573;5053.949460505348;5064.088464076502;5064.417591976822;5075.6193285502995;5023.09584274834;5103.387593488805;5182.637215394886;5191.584673226057;5224.190250511177;4703.45910220329;4726.740029298421;4802.951970480251;4878.804847806048;4998.875028124326;4680.602148817388;4685.742284174403;4890.6332025097545;4944.505549445027;4977.900441991067;4624.884377890579;4657.487676355666;4689.202835518038;4789.784459699392;4937.7531123444405;4814.301926220608;4943.77753001123;4994;5002.449730529712;5105.953279577603;4611.723296602215;4637.443506779208;4746.193147164921;4783.636663550871;4977.726225057657 07:27:27 INFO - PROCESS | 304 | |1;ext.html;524.8320537428023;535.9308180180541;541.0963690636638;553.4520824383861;562.3308263166833;26697.466050679;35587.466188007165;36091.2781744365;36132.638673613255;37943.62056379436;34749.305013899706;36508.45237321442;36529;36566.45150322747;36908.26183476329;6271.216097987752;7164.498485106044;7435.516691415054;7621.771346859576;7836.804079897991;6201.193844800192;8739.431936924111;9174.266058715286;9218.124278193594;9662.468564228964;9661.56522956465;9734.172595329406;9754.9512252439;9792.951035244814;9800.068993445648;6340.270868850095;6589.077529145923;9663.95168024159;9701.514924253794;9703.272254580934;9234.122758337948;9347.719568412958;9536.90463095374;9743.074407931235;9760.072793084646;32053.358932821215;33244.833775831205;33874.49188262178;34060.14849628754;34240.48639270413;2652.336915771057;2703;2768.9446211075665;2987.716166964135;3185.3151572412044;26767.196984090508;27403.17790466289;27661.29757941155;27761.583576246372;28067.157985260474;3250.691184337484;3286.047046356554;3369.689725583929;3424.4863270509372;3523.735719820998;298844.99999999785;299272;299531.5726062464;299563.5021824877;302661;43332;43349.349759753626;43466;43637.345439818426;43653.88845561624;25938.40615938391;26298.21105366842;26925;28174;28367.716322836506;11886.524539018515;12647.810282845765;12689;12768.106232563818;12768.233905965672;8560.914390856136;8683.348748843862;9303.069693030779;9506.096920792514;9605.03949605034;14983.475578354706;15063.849361506464;15078.698426031637;15148.242587870562;15290.235488225544;5834.382682736646;5945.554083443672;6046.123312119806;6050.273967123973;6158.661273629993;717.7918403662879;718.6442710858054;780.1925007617089;874.9737507874773;885.5129678676702;25211.369715757253;25349.61975570368;25475.235742927372;25571.23286301414;25836.354091147496;817.1134319263665;818.5129847740585;845.9534725590152;852.2883392367443;856.9828603427896;13217.603471895858;13471.259080750653;13647.667264422911;13736.793948090786;13923.094998825043;438664;451804;466182;468794;473838;44752.3287150693;45108;45289;45323.54676453259;45327.54672453233;909.6725178935578;943.2406912435418;1010.3382284603597;1042.8696412948534;1043.1811028342715;25744.227673169837;26616.467670646092;26828.731712682624;27268.863655681595;27377.04180353678;37712;37772;38030;39080;39248.80375598104;344494;352237;365378;415133;426193;32980;33656.83171584126;33683.831580841936;35097.473537897466;35362.46956295606;36632.81683591672;37218.255634886606;37432.438513421774;37965.81017095008;38585.80707096447;31013;32403.675963240064;32767.34465310728;34899.825500872335;34971.825140874134;30872.53691194589;31541.526877096403;31570.684293156773;33668.15829604279;34755.13112172115;29244;30674.23314417068;31216.375672486876;32401.67598324081;32449.675503244664;29131.708682913748;29375;29779.404411912074;30426.39147216999;32444.35111297808;22513.662295065915;23682.171124010576;23690.28929132129;24060;24097.397565061015 07:27:27 INFO - PROCESS | 304 | |2;jquery.html;282.235142763112;299.6778463152111;300.1820040389937;313.28727145743443;320.11328619723366;11864.466099025543;12589.748205035905;15594.766078508817;16073.357065717357;16756;15820.288087036082;15996;16105.516834494954;16278.83721162788;16424.917875410607;4825.951740482594;4982.950170498285;4988.501149885001;4990.725510096943;4995.450500444948;5044.319016932704;5272.630915835893;6553.475721942257;7051.201943504399;7077.25688802676;6902.654867256642;7037.57774533527;7049.929500705004;7138.179109402433;7147.106611673541;6442.226932768074;6581.407673309402;6585;6594.340565943416;7021.368076873055;6337.904931426032;6372.203474565679;6419.197600299963;6510.381513756186;6637.46900247979;22426.88786556057;22541.323760287218;22751.772482275297;23048.193313234126;23319;1968.9409317720492;2005.9498512537157;2112.165694550644;2519.571672815626;2592.183462209398;13637.931810340984;14241.928790355982;15092.471763488225;15318.004329718644;15438.382464701399;1827.6866594836176;1947.3963071447897;1965.4398496428448;2050.63088644044;2057.506198512361;94822;102338;102977;105171.99999999923;106008.4699576505;15999.680006399807;16192.19039048043;16608.584785380463;16724.83275167257;16726.6654666906;21872.671909921362;25051.49897002086;25197.496050078895;25888;26166.476670466483;7985.281324680806;8117.634706438224;8260.967379077616;8499.702510412106;8607.311415086779;6816.454683625398;6977.89533157003;6993.2657071007825;7066.823329416806;7125.643717814089;7951.204879512003;8132.105468398471;8148.714794982147;8149.511029338259;8197.590120493951;3121.4693502104697;3187.4581321175456;3196.9360612788078;3238.6923242291946;3253.853576589012;735.8197241675716;766.0347961568464;799.9960000200078;830.5141492227115;842.616609442702;17218.569535761708;17322.26777322258;17430.564235894206;17783.46649600514;17949.371771987917;732.337234802494;733.66618188724;749.6401727170988;751.3087959077552;809.7489778168787;9071.365004449757;9220.492872892055;9556.665516706882;9602.519874006271;9689.467079310705;416024;418723;420008;437874;438506;90937.09062909287;93284;93334;94721;96122;769.7960040589248;784.7018133109382;830.7341650671848;843.1357858195399;847.6143354773563;15381.3078411474;15930.36278548845;16182.514524564282;16694.58263543421;16810.159492025596;41837.79081104575;43684.78157609192;44306.89232769077;45096.323555147355;46439.03414487719;63992;64621;64885.351146487934;64956;67446.66276668585;7536.886946695914;7594.6202689866395;7636.923630763621;7666.9616651916385;7747.496412733154;10666.679999600025;12227.633171004883;12354.938225309175;12505.687357815763;13249.072564920363;10136.949315253376;10202;10564.68305950792;10585.735356616146;10705.197110216921;10273.537690804082;10398.636047738293;10582.312149710244;10744.247902646739;10888.29226100301;10604.946975265075;10693.358398496115;10727.141828653534;10892.564297428014;10969.725756856142;6049;6396.552241343247;6485.416312531706;6649.60102393858;6699.29657385987;8562.657493700432;9258.35191536586;9425.151736343762;9540.379875308083;9729.562169702238 07:27:27 INFO - PROCESS | 304 | |3;mootools.html;962.9229661627071;1081.0702795595787;1094.879563248043;1166.3177041430763;1263.8230647709318;6100.267967843859;6748.59508429494;6855.622940738257;6936.722531098762;6940.097787287658;6629.2376376716575;6914.204866440361;6965.512414131;7013.228544860074;7048.964755176217;1833.9083045847653;1880.548668319607;1894.810518948108;1899.5441094137377;1901.819327163924;1888.424030670645;1903;1903.381401044659;1909.2076788132908;1933.6326098041327;1875.6905110656726;1889.8582606304512;1901.847852171823;1904.6762050451457;1907.0845993922926;1820.9347531693975;1842.2538871756892;1852.1850385830191;1860.22800537776;1862.8416584590327;1801.9549511262192;1811.8912865228128;1826.0778306955035;1827.9177437015232;1845.372573325076;6741.224759152662;6814.965925170392;6829.965850170767;6872.931270687279;6873.381395674413;4194.958050419487;4330.090680957002;4347.847825326098;4366.934495982563;4385.758783266919;2856.8857245710146;2974.301039255773;3016.2308611304197;3018.592490013842;3028.242939265184;924.3991405586422;926.819270242303;928.972130836076;967.0619499085875;968.1238479176286;8739.30085593151;9021.413608115452;9044.864327035033;9060.184583387461;9073.31950103745;3447.293304872495;3562.0738607962116;3592;3595.2090540081663;3600.7299452541165;6897.310268973062;6960.617166055814;6998.020277161201;7000;7002.614856182857;3937.192875460581;4095.4266402703647;4102.425660407545;4166.854160104382;4171.165766846643;3528.770629909048;3621.6921561667027;3977.085270387827;3980.8208630611693;3996.1408297215658;2860.885564577435;2902.7677785777087;2942.2350188951027;2947.263184203949;2960.985195074011;885.1325700813239;920.3833431600843;920.8480600700809;932.4265576670299;933.3373304953559;689.1110467496973;695.961722105289;727.4289682599134;731.5391303478774;763.4350580570338;4614.238650622609;4704.1767690654;4776.14029474691;4790.089882922303;4875;740.6407892172292;745.3738859358165;755.3239850333921;763.1986414265;770.0682174568834;3166.208447888028;3186.3786561620727;3206.8717251310154;3225.870965161414;3262.070309961697;8312.8337433251;8501.574921253912;8527.616257268437;8530.402871798915;8558.657653693783;8371.037330706924;8475.152484751476;8534.445261058012;8549.529775862264;8551.615177317035;6353.809385718436;6371.617702937746;6388.39310265524;6405.935940640534;6441.838954026187;4588.816447342069;4719.10337035962;4776.355192049029;4786.593139583175;4888.535589119028;7271.854562908607;7612.429067820047;7616.961915190389;7680.155182929761;7769.417293702882;7566.886496702444;7837.7648670539975;7894.250046245597;7903.9209607903185;7918.406119540942;5828.504577110993;6022.759089636541;6024.90962635552;6041.607295525777;6043.2748070231855;5995.790147344822;6131.754729810936;6605.504587155887;6795.660216989032;6807.761728339485;6187.504999599932;6271.6550589718;6350.142730731292;6504.447121994684;6517.967410162919;6067.453929146398;6160.199174107337;6251.968425209971;6284.214473190851;6300.873982520416;5673.262475878109;5745.741441635053;5754.223179870832;5757.309122905278;5788.826335209782;4345.391645169616;4379.649628029813;4390.846320378771;4430.468343798765;4549.681522293407;4241.151769646021;4313.956860431481;4332.415123958225;4375.978120109379;4468.262736648547 07:27:27 INFO - PROCESS | 304 | |4;prototype.html;239.3179438599989;247.59270999206305;253.54868334364832;253.7475212163897;256.85616055009183;4923.187674033789;5054.696718196911;5097.745112744366;5127.12838817401;5134.666246693967;4856.150173719594;4970.155073637481;5001.499850014997;5051.31807206027;5061.44324124347;3718.7210959178033;3727.9440808387762;3742.681872040866;3750.643688849555;3751.9926839141067;3564.2515071834946;3731.3954999350267;3737.7570457920147;3737.906552336186;3742.625737426249;3490.4240800267926;3635.30929123467;3701.445392934974;3712.127650002261;3713.5172427584375;3701.9629803701887;3705.7591256568235;3710.9814450927706;3712.5916149223563;3722.497462842509;3186.2512309607455;3207.550942868;3211.6948889855425;3218.8873389431255;3224.1778346521724;4895.339129217581;4914.90170196594;4966.8758281043265;4975.477574854661;4997.800087996476;4056.290149223874;4234.237837189305;4280.1653677532895;4368;4369.453818272716;5269.971061141449;5275.709835959021;5297.841064768063;5305.177697456862;5311.601629877775;3307.7849939753837;3455.87904423344;3478.913027174341;3480.582330120402;3485.8257087145794;5549.417311182308;5564.693941833198;5579.330480342344;5585.385607583163;5593.972030139864;3364.5794275715534;3554.608993010767;3555.542227686688;3556.928861422757;3562.2697347043795;3589.94615080774;3596.7122630189524;3599.7840129591787;3601.027722514959;3602.693771029492;4239.576042395797;4274.7435153890865;4279.871603851827;4281.571842815756;4293.141371725667;5348.491893270133;5352.357717073898;5376.4085950545405;5376.946230537723;5378.300820893337;1190.4880901212389;1212.4119801896074;1234.4198226833373;1245.8380410546572;1249.5501619417003;787.7597332813547;789.5381201996896;803.6584451608043;806.1173015547965;806.2260230178977;4937.481564435754;4950.009998000414;4955.35580374549;4966.90066198674;4976.706056425283;5425.457454254543;5426.213199086111;5430.647007944471;5431.158170483582;5440.612643775891;4790.185668436374;4891.902161956741;4901.436334821541;4913.287573301851;4926;2810.1429064135227;2932.6480822301032;2939.2211064068038;2946.8673909674117;2953.719396657314;5739.130434782609;5752;5754.223179870748;5797.391273916262;5818.330891947396;5592.664440133605;5630.85922851932;5636.971815140898;5641.633293835887;5649.71751412436;5615.691136987423;5723.77104915807;5748.597598168088;5765.1640512125505;5777.2200752897825;5391.16436952273;5496.6701997880255;5513.779448822002;5521.757604538947;5535.861603459945;5434.565234781129;5443.074677304789;5448.264484294569;5457.590680698884;5458.6724796512335;5560.527355174693;5718.199452076629;5729.942700573108;5749.8562535936935;5762.279715035621;3433.6738009889023;3492.89521314361;3510.26284480262;3556.110972256936;3567.714582833419;5456.563474922076;5473.372781065032;5476.7535460903555;5479.3424789023975;5479.60270131114;5308.2568440419145;5320.813771517978;5333.5199832015305;5340.118880384693;5349.170878513836;4890.450537959477;5192.714400707997;5314;5316.670832291927;5339.7063161526485;5150.48495150482;5152.098382783073;5153.639245252796;5156.303898973591;5162.767675454688;5146.9742651288;5222.3733152022005;5289.550388217045;5295;5340.0921843287515;5284.286621306074;5295.470452954673;5295.920561191662;5305.495977882095;5327.840164795062 07:27:27 INFO - PROCESS | 304 | |5;yui.html;588.8204097750187;601.6781022153149;620.6958590290759;633.7686744338316;642.582321491031;12371.628851134468;13437.932810335935;13457.394417251222;13461;13478.999999999987;11079.50142243599;11596.594119205829;11948.820767688492;12200;12268.877311226885;447.48539179943;456.8766433063071;510.9667871588335;532.1671583971082;536.6726296958852;455.68102328370105;459.20786643040657;460.56706695706094;536.5546596325061;548.454287983457;565.3978512883766;575.4245754245754;575.9769609215647;576.6050255574924;578.6701580099345;540.4325458268842;543.7906406033686;572.1617829879247;575.925129733132;576.1357963055417;339.85556138641226;346.1053177536072;346.93928562501713;348.99651003489896;418.0197437010206;11195.944020279929;11444.771104577945;11856.762864742657;11950.342731149784;11997.34014629195;12632.747345053047;12657.873421265762;12721.681957951032;12958;13250.204987700672;10752.408617526033;10814.188935829845;10865.619703310345;11023.160945494614;11265.26775759573;3315.900522984314;3386.5766779152605;3390.9491357629654;3395.898123056312;3417.8803741869165;65792.67103664498;66136.3386366135;66473.33526664719;66617;67566.3243367565;9686.854697179548;9754.170895473964;9779.853302200472;9843.60625574969;9847.359921605073;16497.91751041237;16782.160891955355;16863.747043794352;16914.74627880583;16969.83030169707;7132.072830531998;7178.246284140195;7188.676509557083;7327.340539351484;7334.376577990931;3974.066094467798;4018.7789671568344;4042.797860106983;4087.3868919662286;4137.958620413758;2673.8529380883847;2752.9724702753115;2754.807163498536;2757.8621068946472;2761.502929472714;454.4750812811208;470.20777430026726;479.0610403608965;482.56086960865446;483.19306757713997;529.8225094593278;578.1674388879999;595.9940400596025;614.3027663601755;625.1060982794647;8212.34301255898;8284.71003514874;8330;8458.915410845813;8543.316534677211;565.8443927919855;572.3703925681717;584.33677775724;587.3157771196594;604.5919004671829;4063.756174629532;4076.1236334188293;4103.076807718299;4122.031322639177;4229.323308270662;63647.68176159182;66018;66478;69134;79464.60267698625;62786.372136278966;64297.35702643007;65355;66019.66990165114;67083.32916670869;54433.72783136059;58779;59624;61201.38798612046;62680.68659656764;8632.352573556882;8795.604197811243;8810.471371717718;8893.4663920165;8931.821363572823;38985.610143899336;39136.608633913296;40404.3939340916;40761.59238407578;41703.16593668049;53377.93244135173;54453.1832022512;54767.45232547783;55737.72131139319;57194.561087781774;10510.31682940637;10901.563937442414;11043.558257669603;11192.720181995515;11243;12973.54592589255;13182;13229.140105893086;13254.734905302033;13415.798763018367;12164;12285.017198624268;12381.195222310522;12412.875871241173;12844.100912936005;12452.937735311265;12557.87442125567;12641.304728240035;12757.362131893182;12904.870951290366;12113.212641178296;12151.939240303742;12315.568955086528;12389.504419823106;12674.683132921751;10321.690349289533;10638.680839574825;10649.254552181274;11040.889591104307;11046.834297485704;10340.27618066728;10410.531526081497;10561.419121948367;10587.258891877802;10603.257771955889 07:27:27 INFO - PROCESS | 304 | __end_tp_report 07:27:27 INFO - PROCESS | 304 | __start_cc_report 07:27:27 INFO - PROCESS | 304 | _x_x_mozilla_cycle_collect,1232 07:27:27 INFO - PROCESS | 304 | __end_cc_report 07:27:27 INFO - PROCESS | 304 | __startTimestamp1478010447173__endTimestamp 07:27:27 INFO - PROCESS | 304 | 07:27:27 INFO - PROCESS | 304 | ------- Summary: start ------- 07:27:27 INFO - PROCESS | 304 | Number of tests: 6 07:27:27 INFO - PROCESS | 304 | 07:27:27 INFO - PROCESS | 304 | [#0] dojo.html Cycles:165 Average:4604.72 Median:4779.12 stddev:603.44 (12.6%) stddev-sans-first:587.68 07:27:27 INFO - PROCESS | 304 | Values: 2759.8 2770.3 2824.0 2827.9 2843.3 4559.2 4600.4 4656.8 4699.5 4702.6 4525.5 4765.7 4824.9 4919.2 4922.1 4041.4 4277.8 4315.5 4325.0 4361.4 4080.6 4238.8 4302.7 4328.6 4367.9 4069.8 4138.7 4212.5 4363.5 4378.2 4064.5 4165.1 4176.5 4300.6 4368.5 4067.2 4078.5 4155.5 4156.1 4186.3 4571.4 4779.1 4806.1 4891.2 4899.7 4723.0 4765.6 4768.5 4864.1 4964.0 4792.9 4811.8 4824.0 4839.8 4866.6 4437.4 4475.3 4482.8 4497.1 4524.0 4913.4 4919.9 5043.6 5117.7 5232.5 4833.4 5009.3 5019.7 5027.1 5036.9 5062.0 5099.2 5104.0 5113.5 5117.9 4823.2 5008.3 5045.7 5076.5 5077.5 4758.9 4848.4 4862.3 4872.7 4922.6 3182.9 3199.8 3201.7 3250.7 3251.0 2946.6 2949.9 3084.3 3169.4 3187.2 4422.7 4511.1 4511.7 4520.8 4521.3 4920.6 4938.5 5022.2 5024.9 5251.4 4456.6 4648.8 4675.3 4705.4 4711.5 3985.3 4102.0 4262.4 4265.0 4275.3 5222.1 5238.9 5284.4 5312.1 5329.7 5123.9 5126.1 5129.9 5134.3 5137.3 5259.5 5356.0 5366.2 5367.9 5370.6 5021.6 5053.9 5064.1 5064.4 5075.6 5023.1 5103.4 5182.6 5191.6 5224.2 4703.5 4726.7 4803.0 4878.8 4998.9 4680.6 4685.7 4890.6 4944.5 4977.9 4624.9 4657.5 4689.2 4789.8 4937.8 4814.3 4943.8 4994.0 5002.4 5106.0 4611.7 4637.4 4746.2 4783.6 4977.7 07:27:27 INFO - PROCESS | 304 | 07:27:27 INFO - PROCESS | 304 | [#1] ext.html Cycles:180 Average:50291.43 Median:19443.10 stddev:102593.63 (527.7%) stddev-sans-first:102813.38 07:27:27 INFO - PROCESS | 304 | Values: 524.8 535.9 541.1 553.5 562.3 26697.5 35587.5 36091.3 36132.6 37943.6 34749.3 36508.5 36529.0 36566.5 36908.3 6271.2 7164.5 7435.5 7621.8 7836.8 6201.2 8739.4 9174.3 9218.1 9662.5 9661.6 9734.2 9755.0 9793.0 9800.1 6340.3 6589.1 9664.0 9701.5 9703.3 9234.1 9347.7 9536.9 9743.1 9760.1 32053.4 33244.8 33874.5 34060.1 34240.5 2652.3 2703.0 2768.9 2987.7 3185.3 26767.2 27403.2 27661.3 27761.6 28067.2 3250.7 3286.0 3369.7 3424.5 3523.7 298845.0 299272.0 299531.6 299563.5 302661.0 43332.0 43349.3 43466.0 43637.3 43653.9 25938.4 26298.2 26925.0 28174.0 28367.7 11886.5 12647.8 12689.0 12768.1 12768.2 8560.9 8683.3 9303.1 9506.1 9605.0 14983.5 15063.8 15078.7 15148.2 15290.2 5834.4 5945.6 6046.1 6050.3 6158.7 717.8 718.6 780.2 875.0 885.5 25211.4 25349.6 25475.2 25571.2 25836.4 817.1 818.5 846.0 852.3 857.0 13217.6 13471.3 13647.7 13736.8 13923.1 438664.0 451804.0 466182.0 468794.0 473838.0 44752.3 45108.0 45289.0 45323.5 45327.5 909.7 943.2 1010.3 1042.9 1043.2 25744.2 26616.5 26828.7 27268.9 27377.0 37712.0 37772.0 38030.0 39080.0 39248.8 344494.0 352237.0 365378.0 415133.0 426193.0 32980.0 33656.8 33683.8 35097.5 35362.5 36632.8 37218.3 37432.4 37965.8 38585.8 31013.0 32403.7 32767.3 34899.8 34971.8 30872.5 31541.5 31570.7 33668.2 34755.1 29244.0 30674.2 31216.4 32401.7 32449.7 29131.7 29375.0 29779.4 30426.4 32444.4 22513.7 23682.2 23690.3 24060.0 24097.4 07:27:27 INFO - PROCESS | 304 | 07:27:27 INFO - PROCESS | 304 | [#2] jquery.html Cycles:180 Average:28236.88 Median:241406.45 stddev:71373.14 (29.6%) stddev-sans-first:71542.50 07:27:27 INFO - PROCESS | 304 | Values: 282.2 299.7 300.2 313.3 320.1 11864.5 12589.7 15594.8 16073.4 16756.0 15820.3 15996.0 16105.5 16278.8 16424.9 4826.0 4983.0 4988.5 4990.7 4995.5 5044.3 5272.6 6553.5 7051.2 7077.3 6902.7 7037.6 7049.9 7138.2 7147.1 6442.2 6581.4 6585.0 6594.3 7021.4 6337.9 6372.2 6419.2 6510.4 6637.5 22426.9 22541.3 22751.8 23048.2 23319.0 1968.9 2005.9 2112.2 2519.6 2592.2 13637.9 14241.9 15092.5 15318.0 15438.4 1827.7 1947.4 1965.4 2050.6 2057.5 94822.0 102338.0 102977.0 105172.0 106008.5 15999.7 16192.2 16608.6 16724.8 16726.7 21872.7 25051.5 25197.5 25888.0 26166.5 7985.3 8117.6 8261.0 8499.7 8607.3 6816.5 6977.9 6993.3 7066.8 7125.6 7951.2 8132.1 8148.7 8149.5 8197.6 3121.5 3187.5 3196.9 3238.7 3253.9 735.8 766.0 800.0 830.5 842.6 17218.6 17322.3 17430.6 17783.5 17949.4 732.3 733.7 749.6 751.3 809.7 9071.4 9220.5 9556.7 9602.5 9689.5 416024.0 418723.0 420008.0 437874.0 438506.0 90937.1 93284.0 93334.0 94721.0 96122.0 769.8 784.7 830.7 843.1 847.6 15381.3 15930.4 16182.5 16694.6 16810.2 41837.8 43684.8 44306.9 45096.3 46439.0 63992.0 64621.0 64885.4 64956.0 67446.7 7536.9 7594.6 7636.9 7667.0 7747.5 10666.7 12227.6 12354.9 12505.7 13249.1 10136.9 10202.0 10564.7 10585.7 10705.2 10273.5 10398.6 10582.3 10744.2 10888.3 10604.9 10693.4 10727.1 10892.6 10969.7 6049.0 6396.6 6485.4 6649.6 6699.3 8562.7 9258.4 9425.2 9540.4 9729.6 07:27:27 INFO - PROCESS | 304 | 07:27:27 INFO - PROCESS | 304 | [#3] mootools.html Cycles:180 Average:4527.13 Median:6009.27 stddev:2484.05 (41.3%) stddev-sans-first:2476.57 07:27:27 INFO - PROCESS | 304 | Values: 962.9 1081.1 1094.9 1166.3 1263.8 6100.3 6748.6 6855.6 6936.7 6940.1 6629.2 6914.2 6965.5 7013.2 7049.0 1833.9 1880.5 1894.8 1899.5 1901.8 1888.4 1903.0 1903.4 1909.2 1933.6 1875.7 1889.9 1901.8 1904.7 1907.1 1820.9 1842.3 1852.2 1860.2 1862.8 1802.0 1811.9 1826.1 1827.9 1845.4 6741.2 6815.0 6830.0 6872.9 6873.4 4195.0 4330.1 4347.8 4366.9 4385.8 2856.9 2974.3 3016.2 3018.6 3028.2 924.4 926.8 929.0 967.1 968.1 8739.3 9021.4 9044.9 9060.2 9073.3 3447.3 3562.1 3592.0 3595.2 3600.7 6897.3 6960.6 6998.0 7000.0 7002.6 3937.2 4095.4 4102.4 4166.9 4171.2 3528.8 3621.7 3977.1 3980.8 3996.1 2860.9 2902.8 2942.2 2947.3 2961.0 885.1 920.4 920.8 932.4 933.3 689.1 696.0 727.4 731.5 763.4 4614.2 4704.2 4776.1 4790.1 4875.0 740.6 745.4 755.3 763.2 770.1 3166.2 3186.4 3206.9 3225.9 3262.1 8312.8 8501.6 8527.6 8530.4 8558.7 8371.0 8475.2 8534.4 8549.5 8551.6 6353.8 6371.6 6388.4 6405.9 6441.8 4588.8 4719.1 4776.4 4786.6 4888.5 7271.9 7612.4 7617.0 7680.2 7769.4 7566.9 7837.8 7894.3 7903.9 7918.4 5828.5 6022.8 6024.9 6041.6 6043.3 5995.8 6131.8 6605.5 6795.7 6807.8 6187.5 6271.7 6350.1 6504.4 6518.0 6067.5 6160.2 6252.0 6284.2 6300.9 5673.3 5745.7 5754.2 5757.3 5788.8 4345.4 4379.6 4390.8 4430.5 4549.7 4241.2 4314.0 4332.4 4376.0 4468.3 07:27:27 INFO - PROCESS | 304 | 07:27:27 INFO - PROCESS | 304 | [#4] prototype.html Cycles:180 Average:4384.02 Median:5053.01 stddev:1386.79 (27.4%) stddev-sans-first:1355.34 07:27:27 INFO - PROCESS | 304 | Values: 239.3 247.6 253.5 253.7 256.9 4923.2 5054.7 5097.7 5127.1 5134.7 4856.2 4970.2 5001.5 5051.3 5061.4 3718.7 3727.9 3742.7 3750.6 3752.0 3564.3 3731.4 3737.8 3737.9 3742.6 3490.4 3635.3 3701.4 3712.1 3713.5 3702.0 3705.8 3711.0 3712.6 3722.5 3186.3 3207.6 3211.7 3218.9 3224.2 4895.3 4914.9 4966.9 4975.5 4997.8 4056.3 4234.2 4280.2 4368.0 4369.5 5270.0 5275.7 5297.8 5305.2 5311.6 3307.8 3455.9 3478.9 3480.6 3485.8 5549.4 5564.7 5579.3 5585.4 5594.0 3364.6 3554.6 3555.5 3556.9 3562.3 3589.9 3596.7 3599.8 3601.0 3602.7 4239.6 4274.7 4279.9 4281.6 4293.1 5348.5 5352.4 5376.4 5376.9 5378.3 1190.5 1212.4 1234.4 1245.8 1249.6 787.8 789.5 803.7 806.1 806.2 4937.5 4950.0 4955.4 4966.9 4976.7 5425.5 5426.2 5430.6 5431.2 5440.6 4790.2 4891.9 4901.4 4913.3 4926.0 2810.1 2932.6 2939.2 2946.9 2953.7 5739.1 5752.0 5754.2 5797.4 5818.3 5592.7 5630.9 5637.0 5641.6 5649.7 5615.7 5723.8 5748.6 5765.2 5777.2 5391.2 5496.7 5513.8 5521.8 5535.9 5434.6 5443.1 5448.3 5457.6 5458.7 5560.5 5718.2 5729.9 5749.9 5762.3 3433.7 3492.9 3510.3 3556.1 3567.7 5456.6 5473.4 5476.8 5479.3 5479.6 5308.3 5320.8 5333.5 5340.1 5349.2 4890.5 5192.7 5314.0 5316.7 5339.7 5150.5 5152.1 5153.6 5156.3 5162.8 5147.0 5222.4 5289.6 5295.0 5340.1 5284.3 5295.5 5295.9 5305.5 5327.8 07:27:27 INFO - PROCESS | 304 | 07:27:27 INFO - PROCESS | 304 | [#5] yui.html Cycles:180 Average:15795.49 Median:4129.99 stddev:20476.93 (495.8%) stddev-sans-first:20502.53 07:27:27 INFO - PROCESS | 304 | Values: 588.8 601.7 620.7 633.8 642.6 12371.6 13437.9 13457.4 13461.0 13479.0 11079.5 11596.6 11948.8 12200.0 12268.9 447.5 456.9 511.0 532.2 536.7 455.7 459.2 460.6 536.6 548.5 565.4 575.4 576.0 576.6 578.7 540.4 543.8 572.2 575.9 576.1 339.9 346.1 346.9 349.0 418.0 11195.9 11444.8 11856.8 11950.3 11997.3 12632.7 12657.9 12721.7 12958.0 13250.2 10752.4 10814.2 10865.6 11023.2 11265.3 3315.9 3386.6 3390.9 3395.9 3417.9 65792.7 66136.3 66473.3 66617.0 67566.3 9686.9 9754.2 9779.9 9843.6 9847.4 16497.9 16782.2 16863.7 16914.7 16969.8 7132.1 7178.2 7188.7 7327.3 7334.4 3974.1 4018.8 4042.8 4087.4 4138.0 2673.9 2753.0 2754.8 2757.9 2761.5 454.5 470.2 479.1 482.6 483.2 529.8 578.2 596.0 614.3 625.1 8212.3 8284.7 8330.0 8458.9 8543.3 565.8 572.4 584.3 587.3 604.6 4063.8 4076.1 4103.1 4122.0 4229.3 63647.7 66018.0 66478.0 69134.0 79464.6 62786.4 64297.4 65355.0 66019.7 67083.3 54433.7 58779.0 59624.0 61201.4 62680.7 8632.4 8795.6 8810.5 8893.5 8931.8 38985.6 39136.6 40404.4 40761.6 41703.2 53377.9 54453.2 54767.5 55737.7 57194.6 10510.3 10901.6 11043.6 11192.7 11243.0 12973.5 13182.0 13229.1 13254.7 13415.8 12164.0 12285.0 12381.2 12412.9 12844.1 12452.9 12557.9 12641.3 12757.4 12904.9 12113.2 12151.9 12315.6 12389.5 12674.7 10321.7 10638.7 10649.3 11040.9 11046.8 10340.3 10410.5 10561.4 10587.3 10603.3 07:27:27 INFO - PROCESS | 304 | -------- Summary: end -------- 07:27:27 INFO - PROCESS | 304 | 07:27:27 INFO - TEST-INFO | 304: exit 0 07:27:28 INFO - TEST-OK | dromaeo_css | took 1120803ms 07:27:28 INFO - TEST-START | kraken 07:27:28 INFO - operating with platform_type : w7_ 07:27:28 INFO - Initialising browser for kraken test... 07:27:28 INFO - TEST-INFO | started process 1256 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmppcrars\profile http://localhost:49699/getInfo.html) 07:27:32 INFO - PROCESS | 1256 | __metrics Screen width/height:1600/1200 07:27:32 INFO - PROCESS | 1256 | colorDepth:24 07:27:32 INFO - PROCESS | 1256 | Browser inner width/height: 1010/674 07:27:32 INFO - PROCESS | 1256 | __metrics 07:27:33 INFO - PROCESS | 1256 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:27:33 INFO - PROCESS | 1256 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:27:33 INFO - PROCESS | 1256 | console.error: 07:27:33 INFO - PROCESS | 1256 | Message: Error: Connection closed before committing the transaction. 07:27:33 INFO - PROCESS | 1256 | Stack: 07:27:33 INFO - PROCESS | 1256 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 07:27:33 INFO - PROCESS | 1256 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 07:27:33 INFO - PROCESS | 1256 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 07:27:33 INFO - PROCESS | 1256 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 07:27:33 INFO - PROCESS | 1256 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 07:27:33 INFO - PROCESS | 1256 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 07:27:33 INFO - PROCESS | 1256 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 07:27:33 INFO - PROCESS | 1256 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 07:27:33 INFO - PROCESS | 1256 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 07:27:33 INFO - PROCESS | 1256 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 07:27:33 INFO - PROCESS | 1256 | Barrier/this.client.addBlocker/promise13.2% 07:28:31 INFO - TinderboxPrint: I/O read bytes / time
86,106,112 / 1,204,480 07:28:31 INFO - TinderboxPrint: I/O write bytes / time
471,081,472 / 3,445,590 07:28:31 INFO - TinderboxPrint: CPU idle
8,254.9 (86.8%) 07:28:31 INFO - TinderboxPrint: CPU user
1,215.2 (12.8%) 07:28:31 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 35516416; Read time: 0; Write time: 303480 07:28:31 INFO - run-tests - Wall time: 1186s; CPU: 13.0%; Read bytes: 86106112; Write bytes: 399090688; Read time: 1204480; Write time: 2875270 07:28:32 INFO - Running post-run listener: _upload_blobber_files 07:28:32 INFO - Blob upload gear active. 07:28:32 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 07:28:32 INFO - Files from C:\slave\test\build\blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:28:32 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', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 07:28:32 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 -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 07:28:32 INFO - (blobuploader) - INFO - Open directory for files ... 07:28:32 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 07:28:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:28:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:28:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:28:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:28:33 INFO - (blobuploader) - INFO - Done attempting. 07:28:33 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 07:28:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:28:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:28:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:28:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:28:34 INFO - (blobuploader) - INFO - Done attempting. 07:28:34 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 07:28:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:28:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:28:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:28:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:28:42 INFO - (blobuploader) - INFO - Done attempting. 07:28:42 INFO - (blobuploader) - INFO - Iteration through files over. 07:28:42 INFO - Return code: 0 07:28:42 INFO - rmtree: C:\slave\test\build\uploaded_files.json 07:28:42 INFO - Using _rmtree_windows ... 07:28:42 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 07:28:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f40ded5a9329cd429343a9c9d313640a36d4058c283d59c0aa2c049a74859bc543c485d0922f1d89120fcc34d57bda3ef80c9c8fa6a5cb07860462b18b1d51b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2beb36989d7753a77d19169ec04dfa04e606eee38dfd2f3bde719353bd068b5c93304654fdb1ed38bdfdc1428c5a5c88cbd9f4d02682f8f1fc7677034400bf7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0118af3f71421cd38a4bd47e336ca5fb4b9ade0e25ce72298d47369a2d95c18f8a432b8a1b98152cb5958d6f3b29e2ed96a3f57f2a39dac62939f5f954a8d82"} 07:28:42 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 07:28:42 INFO - Writing to file C:\slave\test\properties\blobber_files 07:28:42 INFO - Contents: 07:28:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f40ded5a9329cd429343a9c9d313640a36d4058c283d59c0aa2c049a74859bc543c485d0922f1d89120fcc34d57bda3ef80c9c8fa6a5cb07860462b18b1d51b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2beb36989d7753a77d19169ec04dfa04e606eee38dfd2f3bde719353bd068b5c93304654fdb1ed38bdfdc1428c5a5c88cbd9f4d02682f8f1fc7677034400bf7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0118af3f71421cd38a4bd47e336ca5fb4b9ade0e25ce72298d47369a2d95c18f8a432b8a1b98152cb5958d6f3b29e2ed96a3f57f2a39dac62939f5f954a8d82"} 07:28:42 INFO - Running post-run listener: copy_logs_to_upload_dir 07:28:42 INFO - Copying logs to upload dir... 07:28:42 INFO - mkdir: C:\slave\test\build\upload\logs 07:28:42 INFO - Copying logs to upload dir... 07:28:42 INFO - Using _rmtree_windows ... 07:28:42 INFO - Using _rmtree_windows ... 07:28:42 INFO - Using _rmtree_windows ... 07:28:42 INFO - Using _rmtree_windows ... 07:28:42 INFO - Using _rmtree_windows ... 07:28:42 INFO - Using _rmtree_windows ... 07:28:42 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1498.916000 ========= master_lag: 0.58 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 59 secs) (at 2016-11-01 07:28:42.489997) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-01 07:28:42.490784) ========= '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-146 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-146 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-146 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/sha512/f40ded5a9329cd429343a9c9d313640a36d4058c283d59c0aa2c049a74859bc543c485d0922f1d89120fcc34d57bda3ef80c9c8fa6a5cb07860462b18b1d51b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2beb36989d7753a77d19169ec04dfa04e606eee38dfd2f3bde719353bd068b5c93304654fdb1ed38bdfdc1428c5a5c88cbd9f4d02682f8f1fc7677034400bf7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0118af3f71421cd38a4bd47e336ca5fb4b9ade0e25ce72298d47369a2d95c18f8a432b8a1b98152cb5958d6f3b29e2ed96a3f57f2a39dac62939f5f954a8d82"} build_url:https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/LTdoshe2Q_OEYXqXJH2UHA/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f40ded5a9329cd429343a9c9d313640a36d4058c283d59c0aa2c049a74859bc543c485d0922f1d89120fcc34d57bda3ef80c9c8fa6a5cb07860462b18b1d51b5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2beb36989d7753a77d19169ec04dfa04e606eee38dfd2f3bde719353bd068b5c93304654fdb1ed38bdfdc1428c5a5c88cbd9f4d02682f8f1fc7677034400bf7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0118af3f71421cd38a4bd47e336ca5fb4b9ade0e25ce72298d47369a2d95c18f8a432b8a1b98152cb5958d6f3b29e2ed96a3f57f2a39dac62939f5f954a8d82"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-01 07:28:42.604883) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-01 07:28:42.605215) ========= '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-146 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-146 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-146 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-01 07:28:42.717073) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-01 07:28:42.717389) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-11-01 07:28:43.231870) ========= ========= Total master_lag: 0.70 =========