builder: mozilla-central_win7_ix_test-dromaeojs-pgo slave: t-w732-ix-105 starttime: 1476884332.2 results: success (0) buildid: 20161019030208 builduid: 6be7e2a95936440a94c0d0edce1810db revision: 90d8afaddf9150853b0b68b35b30c1e54a8683e7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 06:38:52.197277) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 06:38:52.197704) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 06:38:52.198000) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 06:38:52.301298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 06:38:52.301572) ========= '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-105 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-105 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-105 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 --06:38:53-- 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% 41.66 MB/s 06:38:53 (41.66 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.129000 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 06:38:53.504046) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2016-10-19 06:38:53.504336) ========= '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-105 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-105 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-105 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=12.201000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 12 secs) (at 2016-10-19 06:39:05.716480) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 06:39:05.716946) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo mozilla-central --rev 90d8afaddf9150853b0b68b35b30c1e54a8683e7 --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 90d8afaddf9150853b0b68b35b30c1e54a8683e7 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-105 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-105 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-105 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-10-19 06:39:06,121 truncating revision to first 12 chars 2016-10-19 06:39:06,121 Setting DEBUG logging. 2016-10-19 06:39:06,121 attempt 1/10 2016-10-19 06:39:06,121 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/90d8afaddf91?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-19 06:39:06,615 unpacking tar archive at: mozilla-central-90d8afaddf91/testing/mozharness/ 2016-10-19 06:39:06,737 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.168000 ========= master_lag: 0.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 06:39:06.992387) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 06:39:06.992703) ========= script_repo_revision: 90d8afaddf9150853b0b68b35b30c1e54a8683e7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 06:39:06.993115) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 06:39:06.993400) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 06:39:07.014127) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 4 secs) (at 2016-10-19 06:39:07.014470) ========= '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-105 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-105 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-105 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 06:39:07 INFO - MultiFileLogger online at 20161019 06:39:07 in C:\slave\test 06:39:07 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 06:39:07 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 06:39:07 INFO - {'append_to_log': False, 06:39:07 INFO - 'base_work_dir': 'C:\\slave\\test', 06:39:07 INFO - 'blob_upload_branch': 'Firefox', 06:39:07 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 06:39:07 INFO - 'branch': 'Firefox', 06:39:07 INFO - 'buildbot_json_path': 'buildprops.json', 06:39:07 INFO - 'config_files': ('talos/windows_config.py',), 06:39:07 INFO - 'default_actions': ('clobber', 06:39:07 INFO - 'read-buildbot-config', 06:39:07 INFO - 'download-and-extract', 06:39:07 INFO - 'populate-webroot', 06:39:07 INFO - 'create-virtualenv', 06:39:07 INFO - 'install', 06:39:07 INFO - 'run-tests'), 06:39:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:39:07 INFO - 'download_minidump_stackwalk': True, 06:39:07 INFO - 'download_symbols': 'ondemand', 06:39:07 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 06:39:07 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 06:39:07 INFO - 'hg': 'c:/mozilla-build/hg/hg', 06:39:07 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 06:39:07 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 06:39:07 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 06:39:07 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 06:39:07 INFO - 'C:/mozilla-build/tooltool.py'), 06:39:07 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 06:39:07 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 06:39:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:39:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:39:07 INFO - 'installer_path': 'installer.exe', 06:39:07 INFO - 'log_level': 'info', 06:39:07 INFO - 'log_name': 'talos', 06:39:07 INFO - 'log_to_console': True, 06:39:07 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 06:39:07 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 06:39:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 06:39:07 INFO - 'opt_config_files': (), 06:39:07 INFO - 'pip_index': False, 06:39:07 INFO - 'sps_profile': False, 06:39:07 INFO - 'sps_profile_interval': 0, 06:39:07 INFO - 'suite': 'dromaeojs', 06:39:07 INFO - 'system_bits': '32', 06:39:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:39:07 INFO - 'title': 'T-W732-IX-105', 06:39:07 INFO - 'use_talos_json': True, 06:39:07 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 06:39:07 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 06:39:07 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 06:39:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:39:07 INFO - 'work_dir': 'build'} 06:39:07 INFO - [mozharness: 2016-10-19 13:39:07.581000Z] Running clobber step. 06:39:07 INFO - Running pre-action listener: _resource_record_pre_action 06:39:07 INFO - Running main action method: clobber 06:39:07 INFO - rmtree: C:\slave\test\build 06:39:07 INFO - Using _rmtree_windows ... 06:39:07 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 06:41:31 INFO - Running post-action listener: _resource_record_post_action 06:41:31 INFO - [mozharness: 2016-10-19 13:41:31.019000Z] Finished clobber step (success) 06:41:31 INFO - [mozharness: 2016-10-19 13:41:31.019000Z] Running read-buildbot-config step. 06:41:31 INFO - Running pre-action listener: _resource_record_pre_action 06:41:31 INFO - Running main action method: read_buildbot_config 06:41:31 INFO - Using buildbot properties: 06:41:31 INFO - { 06:41:31 INFO - "project": "", 06:41:31 INFO - "product": "firefox", 06:41:31 INFO - "script_repo_revision": "production", 06:41:31 INFO - "builddir": "mozilla-central_win7_ix_test-dromaeojs", 06:41:31 INFO - "repository": "", 06:41:31 INFO - "buildername": "Windows 7 32-bit mozilla-central pgo talos dromaeojs", 06:41:31 INFO - "buildid": "20161019030208", 06:41:31 INFO - "stage_platform": "win32-pgo", 06:41:31 INFO - "basedir": "C:\\slave\\test", 06:41:31 INFO - "nightly_build": "True", 06:41:31 INFO - "buildnumber": 74, 06:41:31 INFO - "slavename": "t-w732-ix-105", 06:41:31 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 06:41:31 INFO - "platform": "win7_ix", 06:41:31 INFO - "scheduler": "tests-mozilla-central-win32-pgo-talos", 06:41:31 INFO - "branch": "mozilla-central", 06:41:31 INFO - "repo_path": "mozilla-central", 06:41:31 INFO - "revision": "90d8afaddf9150853b0b68b35b30c1e54a8683e7", 06:41:31 INFO - "pgo_build": "True", 06:41:31 INFO - "builduid": "6be7e2a95936440a94c0d0edce1810db", 06:41:31 INFO - "slavebuilddir": "test" 06:41:31 INFO - } 06:41:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 06:41:31 INFO - Running post-action listener: _resource_record_post_action 06:41:31 INFO - [mozharness: 2016-10-19 13:41:31.023000Z] Finished read-buildbot-config step (success) 06:41:31 INFO - [mozharness: 2016-10-19 13:41:31.023000Z] Running download-and-extract step. 06:41:31 INFO - Running pre-action listener: _resource_record_pre_action 06:41:31 INFO - Running main action method: download_and_extract 06:41:31 INFO - mkdir: C:\slave\test\build\tests 06:41:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:41:31 INFO - trying https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 06:41:31 INFO - Downloading https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/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 06:41:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/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 06:41:32 INFO - Downloaded 1218 bytes. 06:41:32 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 06:41:32 INFO - Using the following test package requirements: 06:41:32 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 06:41:32 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:41:32 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 06:41:32 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 06:41:32 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:41:32 INFO - u'jsshell-win32.zip'], 06:41:32 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:41:32 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 06:41:32 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 06:41:32 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:41:32 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 06:41:32 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:41:32 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 06:41:32 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:41:32 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 06:41:32 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 06:41:32 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 06:41:32 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 06:41:32 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 06:41:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 06:41:32 INFO - Fetch https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 06:41:32 INFO - Http code: 200 06:41:32 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 06:41:32 INFO - x-amz-cf-id: -QJBx6B_s4qE6wnfpDMm_TnhrS4qhpIR0Ou84LxhApdoHDQ2kFsEVw== 06:41:32 INFO - x-amz-version-id: ht68K9bcZ0Nxufk_RHSgAyt2Wh3ZX5Bu 06:41:33 INFO - Expected file size: 17258154 06:41:33 INFO - Obtained file size: 17258154 06:42:02 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 06:42:02 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 06:42:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 06:42:02 INFO - Fetch https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 06:42:03 INFO - Http code: 200 06:42:03 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 06:42:03 INFO - x-amz-cf-id: Drda54aHuHBeWNF_JYj8yZRUhhn8pbtFhDt6_8fJrN3ql1TQ1qZOdg== 06:42:03 INFO - x-amz-version-id: ht68K9bcZ0Nxufk_RHSgAyt2Wh3ZX5Bu 06:42:03 INFO - Expected file size: 17258154 06:42:03 INFO - Obtained file size: 17258154 06:42:14 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 06:42:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 06:42:14 INFO - Fetch https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 06:42:15 INFO - Http code: 200 06:42:15 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 06:42:15 INFO - x-amz-cf-id: V_mOcxbukQ9aDugtnutQDPItPhyeOVWDxnxqcmywMFFrP35RTY4AFQ== 06:42:15 INFO - x-amz-version-id: IqJwhSnEOfgX6FiG0BCa4XIvTAAQu50N 06:42:15 INFO - Expected file size: 14409028 06:42:15 INFO - Obtained file size: 14409028 06:42: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')]} 06:42:18 INFO - trying https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 06:42:18 INFO - Downloading https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 06:42:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/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 06:42:21 INFO - Downloaded 54440823 bytes. 06:42:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 06:42:21 INFO - mkdir: C:\slave\test\properties 06:42:21 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 06:42:21 INFO - Writing to file C:\slave\test\properties\build_url 06:42:21 INFO - Contents: 06:42:21 INFO - build_url:https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 06:42:22 INFO - Running post-action listener: _resource_record_post_action 06:42:22 INFO - Running post-action listener: set_extra_try_arguments 06:42:22 INFO - [mozharness: 2016-10-19 13:42:22.455000Z] Finished download-and-extract step (success) 06:42:22 INFO - [mozharness: 2016-10-19 13:42:22.455000Z] Running populate-webroot step. 06:42:22 INFO - Running pre-action listener: _resource_record_pre_action 06:42:22 INFO - Running main action method: populate_webroot 06:42:22 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 06:42:22 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 06:42:22 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 06:42:22 INFO - u'--tppagecycles', 06:42:22 INFO - u'7'], 06:42:22 INFO - u'tests': [u'tsvgm']}}, 06:42:22 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 06:42:22 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 06:42:22 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 06:42:22 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 06:42:22 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 06:42:22 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 06:42:22 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:42:22 INFO - u'talos_options': [u'--disable-e10s'], 06:42:22 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:42:22 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:42:22 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:42:22 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:42:22 INFO - u'talos_options': [u'--disable-e10s'], 06:42:22 INFO - u'tests': [u'damp', u'tps']}, 06:42:22 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:42:22 INFO - u'tests': [u'damp', u'tps']}, 06:42:22 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 06:42:22 INFO - u'tests': [u'dromaeo_dom']}, 06:42:22 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 06:42:22 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 06:42:22 INFO - u'tests': [u'basic_compositor_video']}, 06:42:22 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 06:42:22 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 06:42:22 INFO - u'tests': [u'a11yr', 06:42:22 INFO - u'ts_paint', 06:42:22 INFO - u'tpaint', 06:42:22 INFO - u'sessionrestore', 06:42:22 INFO - u'sessionrestore_no_auto_restore', 06:42:22 INFO - u'tabpaint']}, 06:42:22 INFO - u'other-e10s': {u'tests': [u'a11yr', 06:42:22 INFO - u'ts_paint', 06:42:22 INFO - u'tpaint', 06:42:22 INFO - u'sessionrestore', 06:42:22 INFO - u'sessionrestore_no_auto_restore', 06:42:22 INFO - u'tabpaint']}, 06:42:22 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 06:42:22 INFO - u'ts_paint', 06:42:22 INFO - u'tpaint', 06:42:22 INFO - u'sessionrestore', 06:42:22 INFO - u'sessionrestore_no_auto_restore', 06:42:22 INFO - u'tabpaint']}, 06:42:22 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 06:42:22 INFO - u'ts_paint', 06:42:22 INFO - u'tpaint', 06:42:22 INFO - u'sessionrestore', 06:42:22 INFO - u'sessionrestore_no_auto_restore', 06:42:22 INFO - u'tabpaint']}, 06:42:22 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 06:42:22 INFO - u'tests': [u'a11yr', 06:42:22 INFO - u'ts_paint', 06:42:22 INFO - u'tpaint', 06:42:22 INFO - u'sessionrestore', 06:42:22 INFO - u'sessionrestore_no_auto_restore', 06:42:22 INFO - u'tabpaint']}, 06:42:22 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 06:42:22 INFO - u'tests': [u'a11yr', 06:42:22 INFO - u'ts_paint', 06:42:22 INFO - u'tpaint', 06:42:22 INFO - u'sessionrestore', 06:42:22 INFO - u'sessionrestore_no_auto_restore', 06:42:22 INFO - u'tabpaint']}, 06:42:22 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 06:42:22 INFO - u'tests': [u'tsvgx', 06:42:22 INFO - u'tsvgr_opacity', 06:42:22 INFO - u'tart', 06:42:22 INFO - u'tscrollx', 06:42:22 INFO - u'cart']}, 06:42:22 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 06:42:22 INFO - u'tsvgr_opacity', 06:42:22 INFO - u'tart', 06:42:22 INFO - u'tscrollx', 06:42:22 INFO - u'cart']}, 06:42:22 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:42:22 INFO - u'talos_options': [u'--disable-e10s'], 06:42:22 INFO - u'tests': [u'tp5o']}, 06:42:22 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:42:22 INFO - u'tests': [u'tp5o']}, 06:42:22 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:42:22 INFO - u'talos_options': [u'--disable-e10s', 06:42:22 INFO - u'--xperf_path', 06:42:22 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:42:22 INFO - u'tests': [u'tp5n']}, 06:42:22 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:42:22 INFO - u'talos_options': [u'--xperf_path', 06:42:22 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:42:22 INFO - u'tests': [u'tp5n']}}, 06:42:22 INFO - u'talos.zip': {u'path': u'', 06:42:22 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:42:22 INFO - Running post-action listener: _resource_record_post_action 06:42:22 INFO - [mozharness: 2016-10-19 13:42:22.475000Z] Finished populate-webroot step (success) 06:42:22 INFO - [mozharness: 2016-10-19 13:42:22.475000Z] Running create-virtualenv step. 06:42:22 INFO - Running pre-action listener: _resource_record_pre_action 06:42:22 INFO - Running main action method: create_virtualenv 06:42:22 INFO - Creating virtualenv C:\slave\test\build/venv 06:42:22 INFO - mkdir: C:\slave\test\build\venv\Scripts 06:42:22 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 06:42:22 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'! 06:42:22 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 06:42:22 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 06:42:24 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 06:42:31 INFO - Installing distribute......................................................................................................................................................................................done. 06:42:33 INFO - Return code: 0 06:42:33 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 06:42:33 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 06:42:33 INFO - Reading from file tmpfile_stdout 06:42:33 INFO - Output received: 06:42:33 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 06:42:33 INFO - Using _rmtree_windows ... 06:42:33 INFO - Using _rmtree_windows ... 06:42:33 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 06:42: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')]} 06:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:42: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')]} 06:42:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:42:33 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 0x01908640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01882B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01959E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C6520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-105', 'USERDOMAIN': 'T-W732-IX-105', '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-105', '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 06:42:33 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 06:42:33 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:42:33 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:42:33 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:42:33 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:42:33 INFO - 'COMPUTERNAME': 'T-W732-IX-105', 06:42:33 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:42:33 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:42:33 INFO - 'DCLOCATION': 'SCL3', 06:42:33 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:42:33 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:42:33 INFO - 'HOMEDRIVE': 'C:', 06:42:33 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:42:33 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:42:33 INFO - 'KTS_VERSION': '1.19c', 06:42:33 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:42:33 INFO - 'LOGONSERVER': '\\\\T-W732-IX-105', 06:42:33 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:42:33 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:42:33 INFO - 'MOZ_NO_REMOTE': '1', 06:42:33 INFO - 'NO_EM_RESTART': '1', 06:42:33 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:42:33 INFO - 'OS': 'Windows_NT', 06:42:33 INFO - 'OURDRIVE': 'C:', 06:42: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;', 06:42:33 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:42:33 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:42:33 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:42:33 INFO - 'PROCESSOR_LEVEL': '6', 06:42:33 INFO - 'PROCESSOR_REVISION': '1e05', 06:42:33 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:42:33 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:42:33 INFO - 'PROMPT': '$P$G', 06:42:33 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:42:33 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:42:33 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:42:33 INFO - 'PWD': 'C:\\slave\\test', 06:42:33 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:42:33 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:42:33 INFO - 'SYSTEMDRIVE': 'C:', 06:42:33 INFO - 'SYSTEMROOT': 'C:\\windows', 06:42:33 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:33 INFO - 'TEST1': 'testie', 06:42:33 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:33 INFO - 'USERDOMAIN': 'T-W732-IX-105', 06:42:33 INFO - 'USERNAME': 'cltbld', 06:42:33 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:42:33 INFO - 'WINDIR': 'C:\\windows', 06:42:33 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:42:33 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:42:34 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:42:34 INFO - Downloading/unpacking pip>=1.5 06:42:38 INFO - Running setup.py egg_info for package pip 06:42:38 INFO - warning: no files found matching 'pip\cacert.pem' 06:42:38 INFO - warning: no files found matching '*.html' under directory 'docs' 06:42:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 06:42:38 INFO - no previously-included directories found matching 'docs\_build\_sources' 06:42:38 INFO - Installing collected packages: pip 06:42:38 INFO - Found existing installation: pip 0.8.2 06:42:38 INFO - Uninstalling pip: 06:42:38 INFO - Successfully uninstalled pip 06:42:38 INFO - Running setup.py install for pip 06:42:38 INFO - warning: no files found matching 'pip\cacert.pem' 06:42:38 INFO - warning: no files found matching '*.html' under directory 'docs' 06:42:38 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 06:42:38 INFO - no previously-included directories found matching 'docs\_build\_sources' 06:42:38 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 06:42:38 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 06:42:38 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 06:42:38 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 06:42:38 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 06:42:38 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 06:42:38 INFO - Successfully installed pip 06:42:38 INFO - Cleaning up... 06:42:38 INFO - Return code: 0 06:42:38 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 06:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:42:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:42:38 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 0x01908640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01882B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01959E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C6520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-105', 'USERDOMAIN': 'T-W732-IX-105', '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-105', '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 06:42:38 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 06:42:38 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 06:42:38 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:42:38 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:42:38 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:42:38 INFO - 'COMPUTERNAME': 'T-W732-IX-105', 06:42:38 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:42:38 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:42:38 INFO - 'DCLOCATION': 'SCL3', 06:42:38 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:42:38 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:42:38 INFO - 'HOMEDRIVE': 'C:', 06:42:38 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:42:38 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:42:38 INFO - 'KTS_VERSION': '1.19c', 06:42:38 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:42:38 INFO - 'LOGONSERVER': '\\\\T-W732-IX-105', 06:42:38 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:42:38 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:42:38 INFO - 'MOZ_NO_REMOTE': '1', 06:42:38 INFO - 'NO_EM_RESTART': '1', 06:42:38 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:42:38 INFO - 'OS': 'Windows_NT', 06:42:38 INFO - 'OURDRIVE': 'C:', 06:42:38 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:42:38 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:42:38 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:42:38 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:42:38 INFO - 'PROCESSOR_LEVEL': '6', 06:42:38 INFO - 'PROCESSOR_REVISION': '1e05', 06:42:38 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:42:38 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:42:38 INFO - 'PROMPT': '$P$G', 06:42:38 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:42:38 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:42:38 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:42:38 INFO - 'PWD': 'C:\\slave\\test', 06:42:38 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:42:38 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:42:38 INFO - 'SYSTEMDRIVE': 'C:', 06:42:38 INFO - 'SYSTEMROOT': 'C:\\windows', 06:42:38 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:38 INFO - 'TEST1': 'testie', 06:42:38 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:38 INFO - 'USERDOMAIN': 'T-W732-IX-105', 06:42:38 INFO - 'USERNAME': 'cltbld', 06:42:38 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:42:38 INFO - 'WINDIR': 'C:\\windows', 06:42:38 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:42:38 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:42:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:42:40 INFO - Downloading/unpacking psutil>=3.1.1 06:42:40 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 06:42:40 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 06:42:41 INFO - Installing collected packages: psutil 06:42:41 INFO - Successfully installed psutil 06:42:41 INFO - Cleaning up... 06:42:42 INFO - Return code: 0 06:42:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 06:42: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')]} 06:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:42: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')]} 06:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:42: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01908640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01882B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01959E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C6520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-105', 'USERDOMAIN': 'T-W732-IX-105', '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-105', '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 06:42: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', 'mozsystemmonitor==0.3'] in C:\slave\test\build 06:42: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 mozsystemmonitor==0.3 06:42:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:42:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:42:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:42:42 INFO - 'COMPUTERNAME': 'T-W732-IX-105', 06:42:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:42:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:42:42 INFO - 'DCLOCATION': 'SCL3', 06:42:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:42:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:42:42 INFO - 'HOMEDRIVE': 'C:', 06:42:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:42:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:42:42 INFO - 'KTS_VERSION': '1.19c', 06:42:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:42:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-105', 06:42:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:42:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:42:42 INFO - 'MOZ_NO_REMOTE': '1', 06:42:42 INFO - 'NO_EM_RESTART': '1', 06:42:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:42:42 INFO - 'OS': 'Windows_NT', 06:42:42 INFO - 'OURDRIVE': 'C:', 06:42: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;', 06:42:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:42:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:42:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:42:42 INFO - 'PROCESSOR_LEVEL': '6', 06:42:42 INFO - 'PROCESSOR_REVISION': '1e05', 06:42:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:42:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:42:42 INFO - 'PROMPT': '$P$G', 06:42:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:42:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:42:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:42:42 INFO - 'PWD': 'C:\\slave\\test', 06:42:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:42:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:42:42 INFO - 'SYSTEMDRIVE': 'C:', 06:42:42 INFO - 'SYSTEMROOT': 'C:\\windows', 06:42:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:42 INFO - 'TEST1': 'testie', 06:42:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:42 INFO - 'USERDOMAIN': 'T-W732-IX-105', 06:42:42 INFO - 'USERNAME': 'cltbld', 06:42:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:42:42 INFO - 'WINDIR': 'C:\\windows', 06:42:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:42:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:42:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:42:42 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 06:42:42 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 06:42:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:42:43 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 06:42:43 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) 06:42:43 INFO - Installing collected packages: mozsystemmonitor 06:42:43 INFO - Running setup.py install for mozsystemmonitor 06:42:43 INFO - Successfully installed mozsystemmonitor 06:42:43 INFO - Cleaning up... 06:42:43 INFO - Return code: 0 06:42:43 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 06:42:43 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')]} 06:42:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:42:43 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')]} 06:42:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:42:43 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 0x01908640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01882B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01959E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C6520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-105', 'USERDOMAIN': 'T-W732-IX-105', '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-105', '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 06:42:43 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 06:42:43 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 06:42:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:42:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:42:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:42:43 INFO - 'COMPUTERNAME': 'T-W732-IX-105', 06:42:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:42:43 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:42:43 INFO - 'DCLOCATION': 'SCL3', 06:42:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:42:43 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:42:43 INFO - 'HOMEDRIVE': 'C:', 06:42:43 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:42:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:42:43 INFO - 'KTS_VERSION': '1.19c', 06:42:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:42:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-105', 06:42:43 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:42:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:42:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:42:43 INFO - 'MOZ_NO_REMOTE': '1', 06:42:43 INFO - 'NO_EM_RESTART': '1', 06:42:43 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:42:43 INFO - 'OS': 'Windows_NT', 06:42:43 INFO - 'OURDRIVE': 'C:', 06:42:43 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;', 06:42:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:42:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:42:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:42:43 INFO - 'PROCESSOR_LEVEL': '6', 06:42:43 INFO - 'PROCESSOR_REVISION': '1e05', 06:42:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:42:43 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:42:43 INFO - 'PROMPT': '$P$G', 06:42:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:42:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:42:43 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:42:43 INFO - 'PWD': 'C:\\slave\\test', 06:42:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:42:43 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:42:43 INFO - 'SYSTEMDRIVE': 'C:', 06:42:43 INFO - 'SYSTEMROOT': 'C:\\windows', 06:42:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:43 INFO - 'TEST1': 'testie', 06:42:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:43 INFO - 'USERDOMAIN': 'T-W732-IX-105', 06:42:43 INFO - 'USERNAME': 'cltbld', 06:42:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:42:43 INFO - 'WINDIR': 'C:\\windows', 06:42:43 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:42:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:42:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:42:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:42:44 INFO - Downloading/unpacking jsonschema==2.5.1 06:42:44 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 06:42:44 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 06:42:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:42:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:42:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:42:45 INFO - Downloading functools32-3.2.3-2.tar.gz 06:42:45 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 06:42:45 INFO - warning: no files found matching '*.txt' 06:42:45 INFO - no previously-included directories found matching 'build' 06:42:45 INFO - no previously-included directories found matching 'dist' 06:42:45 INFO - no previously-included directories found matching '.git*' 06:42:45 INFO - Installing collected packages: jsonschema, functools32 06:42:45 INFO - Running setup.py install for functools32 06:42:45 INFO - warning: no files found matching '*.txt' 06:42:45 INFO - no previously-included directories found matching 'build' 06:42:45 INFO - no previously-included directories found matching 'dist' 06:42:45 INFO - no previously-included directories found matching '.git*' 06:42:45 INFO - Successfully installed jsonschema functools32 06:42:45 INFO - Cleaning up... 06:42:46 INFO - Return code: 0 06:42:46 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 06:42:46 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')]} 06:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:42:46 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')]} 06:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:42:46 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 0x01908640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01882B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01959E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C6520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-105', 'USERDOMAIN': 'T-W732-IX-105', '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-105', '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 06:42:46 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 06:42:46 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 06:42:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:42:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:42:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:42:46 INFO - 'COMPUTERNAME': 'T-W732-IX-105', 06:42:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:42:46 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:42:46 INFO - 'DCLOCATION': 'SCL3', 06:42:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:42:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:42:46 INFO - 'HOMEDRIVE': 'C:', 06:42:46 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:42:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:42:46 INFO - 'KTS_VERSION': '1.19c', 06:42:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:42:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-105', 06:42:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:42:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:42:46 INFO - 'MOZ_NO_REMOTE': '1', 06:42:46 INFO - 'NO_EM_RESTART': '1', 06:42:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:42:46 INFO - 'OS': 'Windows_NT', 06:42:46 INFO - 'OURDRIVE': 'C:', 06:42: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;', 06:42:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:42:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:42:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:42:46 INFO - 'PROCESSOR_LEVEL': '6', 06:42:46 INFO - 'PROCESSOR_REVISION': '1e05', 06:42:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:42:46 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:42:46 INFO - 'PROMPT': '$P$G', 06:42:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:42:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:42:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:42:46 INFO - 'PWD': 'C:\\slave\\test', 06:42:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:42:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:42:46 INFO - 'SYSTEMDRIVE': 'C:', 06:42:46 INFO - 'SYSTEMROOT': 'C:\\windows', 06:42:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:46 INFO - 'TEST1': 'testie', 06:42:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:46 INFO - 'USERDOMAIN': 'T-W732-IX-105', 06:42:46 INFO - 'USERNAME': 'cltbld', 06:42:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:42:46 INFO - 'WINDIR': 'C:\\windows', 06:42:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:42:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:42:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 06:42:46 INFO - Cleaning up... 06:42:46 INFO - Return code: 0 06:42:46 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 06:42:46 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')]} 06:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:42:46 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')]} 06:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:42:46 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 0x01908640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01882B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01959E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C6520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-105', 'USERDOMAIN': 'T-W732-IX-105', '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-105', '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 06:42:46 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 06:42:46 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 06:42:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:42:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:42:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:42:46 INFO - 'COMPUTERNAME': 'T-W732-IX-105', 06:42:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:42:46 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:42:46 INFO - 'DCLOCATION': 'SCL3', 06:42:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:42:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:42:46 INFO - 'HOMEDRIVE': 'C:', 06:42:46 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:42:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:42:46 INFO - 'KTS_VERSION': '1.19c', 06:42:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:42:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-105', 06:42:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:42:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:42:46 INFO - 'MOZ_NO_REMOTE': '1', 06:42:46 INFO - 'NO_EM_RESTART': '1', 06:42:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:42:46 INFO - 'OS': 'Windows_NT', 06:42:46 INFO - 'OURDRIVE': 'C:', 06:42: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;', 06:42:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:42:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:42:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:42:46 INFO - 'PROCESSOR_LEVEL': '6', 06:42:46 INFO - 'PROCESSOR_REVISION': '1e05', 06:42:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:42:46 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:42:46 INFO - 'PROMPT': '$P$G', 06:42:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:42:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:42:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:42:46 INFO - 'PWD': 'C:\\slave\\test', 06:42:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:42:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:42:46 INFO - 'SYSTEMDRIVE': 'C:', 06:42:46 INFO - 'SYSTEMROOT': 'C:\\windows', 06:42:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:46 INFO - 'TEST1': 'testie', 06:42:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:46 INFO - 'USERDOMAIN': 'T-W732-IX-105', 06:42:46 INFO - 'USERNAME': 'cltbld', 06:42:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:42:46 INFO - 'WINDIR': 'C:\\windows', 06:42:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:42:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:42:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:42:47 INFO - Downloading/unpacking blobuploader==1.2.4 06:42:47 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 06:42:47 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 06:42:48 INFO - Downloading blobuploader-1.2.4.tar.gz 06:42:48 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 06:42:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:42:48 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 06:42:48 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 06:42:48 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 06:42:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:42:48 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 06:42:48 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 06:42:48 INFO - Downloading docopt-0.6.1.tar.gz 06:42:48 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 06:42:48 INFO - Installing collected packages: blobuploader, requests, docopt 06:42:48 INFO - Running setup.py install for blobuploader 06:42:49 INFO - Running setup.py install for requests 06:42:50 INFO - Running setup.py install for docopt 06:42:50 INFO - Successfully installed blobuploader requests docopt 06:42:50 INFO - Cleaning up... 06:42:51 INFO - Return code: 0 06:42:51 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:42:51 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 0x01908640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01882B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01959E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C6520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-105', 'USERDOMAIN': 'T-W732-IX-105', '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-105', '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 06:42:51 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 06:42:51 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 06:42:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:42:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:42:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:42:51 INFO - 'COMPUTERNAME': 'T-W732-IX-105', 06:42:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:42:51 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:42:51 INFO - 'DCLOCATION': 'SCL3', 06:42:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:42:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:42:51 INFO - 'HOMEDRIVE': 'C:', 06:42:51 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:42:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:42:51 INFO - 'KTS_VERSION': '1.19c', 06:42:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:42:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-105', 06:42:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:42:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:42:51 INFO - 'MOZ_NO_REMOTE': '1', 06:42:51 INFO - 'NO_EM_RESTART': '1', 06:42:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:42:51 INFO - 'OS': 'Windows_NT', 06:42:51 INFO - 'OURDRIVE': 'C:', 06:42:51 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:42:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:42:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:42:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:42:51 INFO - 'PROCESSOR_LEVEL': '6', 06:42:51 INFO - 'PROCESSOR_REVISION': '1e05', 06:42:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:42:51 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:42:51 INFO - 'PROMPT': '$P$G', 06:42:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:42:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:42:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:42:51 INFO - 'PWD': 'C:\\slave\\test', 06:42:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:42:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:42:51 INFO - 'SYSTEMDRIVE': 'C:', 06:42:51 INFO - 'SYSTEMROOT': 'C:\\windows', 06:42:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:51 INFO - 'TEST1': 'testie', 06:42:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:42:51 INFO - 'USERDOMAIN': 'T-W732-IX-105', 06:42:51 INFO - 'USERNAME': 'cltbld', 06:42:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:42:51 INFO - 'WINDIR': 'C:\\windows', 06:42:51 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:42:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:42:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:42:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 06:42:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-daqjal-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 06:42:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 06:42:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eyy5ir-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 06:42:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 06:42:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rszweg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 06:42:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 06:42:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tojong-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 06:42:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 06:42:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vvfnul-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 06:42:52 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 06:42:52 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-73xjh6-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 06:42:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 06:42:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zu4z00-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 06:42:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 06:42:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wbmbzc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 06:42:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 06:42:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_zofj4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 06:42:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 06:42:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fjxwwm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 06:42:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 06:42:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lgqlou-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 06:42:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 06:42:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qulore-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 06:42:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 06:42:53 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g2dovw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 06:42:53 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 06:42:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7awxvd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 06:42:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 06:42:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-okals3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 06:42:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 06:42:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qhxx0z-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 06:42:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 06:42:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3e7eis-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 06:42:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:42:54 INFO - Running setup.py install for manifestparser 06:42:54 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 06:42:54 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 06:42:55 INFO - Running setup.py install for mozcrash 06:42:56 INFO - Running setup.py install for mozdebug 06:42:56 INFO - Running setup.py install for mozdevice 06:42:56 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 06:42:56 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 06:42:56 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 06:42:56 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 06:42:56 INFO - Running setup.py install for mozfile 06:42:57 INFO - Running setup.py install for mozhttpd 06:42:57 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 06:42:57 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 06:42:57 INFO - Running setup.py install for mozinfo 06:42:57 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 06:42:57 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 06:42:58 INFO - Running setup.py install for mozInstall 06:42:58 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 06:42:58 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 06:42:58 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 06:42:58 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 06:42:58 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 06:42:58 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 06:42:58 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 06:42:58 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 06:42:58 INFO - Running setup.py install for mozleak 06:42:58 INFO - Running setup.py install for mozlog 06:42:59 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 06:42:59 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 06:42:59 INFO - Running setup.py install for moznetwork 06:42:59 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 06:42:59 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 06:42:59 INFO - Running setup.py install for mozprocess 06:43:00 INFO - Running setup.py install for mozprofile 06:43:00 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 06:43:00 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 06:43:00 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 06:43:00 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 06:43:00 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 06:43:00 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 06:43:01 INFO - Running setup.py install for mozrunner 06:43:01 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 06:43:01 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 06:43:01 INFO - Running setup.py install for mozscreenshot 06:43:02 INFO - Running setup.py install for moztest 06:43:02 INFO - Running setup.py install for mozversion 06:43:02 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 06:43:02 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 06:43:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:43:02 INFO - Cleaning up... 06:43:02 INFO - Return code: 0 06:43:02 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:43:02 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')]} 06:43:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:43:02 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')]} 06:43:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:43:02 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 0x01908640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01882B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01959E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C6520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-105', 'USERDOMAIN': 'T-W732-IX-105', '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-105', '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 06:43:03 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 06:43:03 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 06:43:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:43:03 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:43:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:43:03 INFO - 'COMPUTERNAME': 'T-W732-IX-105', 06:43:03 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:43:03 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:43:03 INFO - 'DCLOCATION': 'SCL3', 06:43:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:43:03 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:43:03 INFO - 'HOMEDRIVE': 'C:', 06:43:03 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:43:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:43:03 INFO - 'KTS_VERSION': '1.19c', 06:43:03 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:43:03 INFO - 'LOGONSERVER': '\\\\T-W732-IX-105', 06:43:03 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:43:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:43:03 INFO - 'MOZ_NO_REMOTE': '1', 06:43:03 INFO - 'NO_EM_RESTART': '1', 06:43:03 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:43:03 INFO - 'OS': 'Windows_NT', 06:43:03 INFO - 'OURDRIVE': 'C:', 06:43:03 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:43:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:43:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:43:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:43:03 INFO - 'PROCESSOR_LEVEL': '6', 06:43:03 INFO - 'PROCESSOR_REVISION': '1e05', 06:43:03 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:43:03 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:43:03 INFO - 'PROMPT': '$P$G', 06:43:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:43:03 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:43:03 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:43:03 INFO - 'PWD': 'C:\\slave\\test', 06:43:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:43:03 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:43:03 INFO - 'SYSTEMDRIVE': 'C:', 06:43:03 INFO - 'SYSTEMROOT': 'C:\\windows', 06:43:03 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:43:03 INFO - 'TEST1': 'testie', 06:43:03 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:43:03 INFO - 'USERDOMAIN': 'T-W732-IX-105', 06:43:03 INFO - 'USERNAME': 'cltbld', 06:43:03 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:43:03 INFO - 'WINDIR': 'C:\\windows', 06:43:03 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:43:03 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:43:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:43:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 06:43:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-43r_sz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 06:43:03 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)) 06:43:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 06:43:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dnmcww-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 06:43:03 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)) 06:43:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 06:43:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vjkz9k-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 06:43:03 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)) 06:43:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 06:43:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0y32zt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 06:43:03 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)) 06:43:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 06:43:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dad6cs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 06:43:04 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)) 06:43:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 06:43:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_eme8r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 06:43:04 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)) 06:43:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 06:43:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-myek5d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 06:43:04 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)) 06:43:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 06:43:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rhkate-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 06:43:04 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)) 06:43:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 06:43:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1nn3gn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 06:43:04 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)) 06:43:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 06:43:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hfnjlw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 06:43:04 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)) 06:43:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 06:43:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qlfac4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 06:43:04 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)) 06:43:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 06:43:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bsskpn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 06:43:05 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)) 06:43:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 06:43:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ulsnen-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 06:43:05 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)) 06:43:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 06:43:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rmj1ju-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 06:43:05 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)) 06:43:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 06:43:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8gxkjw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 06:43:05 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)) 06:43:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 06:43:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ydvxa4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 06:43:05 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)) 06:43:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 06:43:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x3tk41-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 06:43:05 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)) 06:43:05 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)) 06:43:05 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)) 06:43:05 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)) 06:43:05 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)) 06:43:05 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)) 06:43:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 06:43:05 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 06:43:05 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 06:43:06 INFO - Downloading blessings-1.6.tar.gz 06:43:06 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 06:43:07 INFO - Installing collected packages: blessings 06:43:07 INFO - Running setup.py install for blessings 06:43:07 INFO - Successfully installed blessings 06:43:07 INFO - Cleaning up... 06:43:10 INFO - Return code: 0 06:43:10 INFO - Done creating virtualenv C:\slave\test\build/venv. 06:43:10 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 06:43:10 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 06:43:10 INFO - Reading from file tmpfile_stdout 06:43:10 INFO - Using _rmtree_windows ... 06:43:10 INFO - Using _rmtree_windows ... 06:43:10 INFO - Current package versions: 06:43:10 INFO - blessings == 1.6 06:43:10 INFO - blobuploader == 1.2.4 06:43:10 INFO - docopt == 0.6.1 06:43:10 INFO - functools32 == 3.2.3-2 06:43:10 INFO - jsonschema == 2.5.1 06:43:10 INFO - manifestparser == 1.1 06:43:10 INFO - mozInstall == 1.12 06:43:10 INFO - mozcrash == 0.17 06:43:10 INFO - mozdebug == 0.1 06:43:10 INFO - mozdevice == 0.48 06:43:10 INFO - mozfile == 1.2 06:43:10 INFO - mozhttpd == 0.7 06:43:10 INFO - mozinfo == 0.9 06:43:10 INFO - mozleak == 0.1 06:43:10 INFO - mozlog == 3.4 06:43:10 INFO - moznetwork == 0.27 06:43:10 INFO - mozprocess == 0.23 06:43:10 INFO - mozprofile == 0.28 06:43:10 INFO - mozrunner == 6.12 06:43:10 INFO - mozscreenshot == 0.1 06:43:10 INFO - mozsystemmonitor == 0.3 06:43:10 INFO - moztest == 0.7 06:43:10 INFO - mozversion == 1.4 06:43:10 INFO - psutil == 3.1.1 06:43:10 INFO - requests == 1.2.3 06:43:10 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:43:10 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')]} 06:43:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:43:10 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')]} 06:43:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:43:10 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 0x01908640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01882B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01959E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C6520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-105', 'USERDOMAIN': 'T-W732-IX-105', '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-105', '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 06:43:10 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 06:43:10 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 06:43:10 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:43:10 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:43:10 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:43:10 INFO - 'COMPUTERNAME': 'T-W732-IX-105', 06:43:10 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:43:10 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:43:10 INFO - 'DCLOCATION': 'SCL3', 06:43:10 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:43:10 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:43:10 INFO - 'HOMEDRIVE': 'C:', 06:43:10 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:43:10 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:43:10 INFO - 'KTS_VERSION': '1.19c', 06:43:10 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:43:10 INFO - 'LOGONSERVER': '\\\\T-W732-IX-105', 06:43:10 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:43:10 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:43:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:43:10 INFO - 'MOZ_NO_REMOTE': '1', 06:43:10 INFO - 'NO_EM_RESTART': '1', 06:43:10 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:43:10 INFO - 'OS': 'Windows_NT', 06:43:10 INFO - 'OURDRIVE': 'C:', 06:43:10 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;', 06:43:10 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:43:10 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:43:10 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:43:10 INFO - 'PROCESSOR_LEVEL': '6', 06:43:10 INFO - 'PROCESSOR_REVISION': '1e05', 06:43:10 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:43:10 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:43:10 INFO - 'PROMPT': '$P$G', 06:43:10 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:43:10 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:43:10 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:43:10 INFO - 'PWD': 'C:\\slave\\test', 06:43:10 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:43:10 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:43:10 INFO - 'SYSTEMDRIVE': 'C:', 06:43:10 INFO - 'SYSTEMROOT': 'C:\\windows', 06:43:10 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:43:10 INFO - 'TEST1': 'testie', 06:43:10 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:43:10 INFO - 'USERDOMAIN': 'T-W732-IX-105', 06:43:10 INFO - 'USERNAME': 'cltbld', 06:43:10 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:43:10 INFO - 'WINDIR': 'C:\\windows', 06:43:10 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:43:10 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:43:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:43:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:43:11 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)) 06:43:11 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)) 06:43:11 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)) 06:43:11 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)) 06:43:11 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)) 06:43:11 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)) 06:43:11 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)) 06:43:11 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)) 06:43:11 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)) 06:43:11 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)) 06:43:11 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)) 06:43:11 INFO - Cleaning up... 06:43:11 INFO - Return code: 0 06:43:11 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 06:43:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:43:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:43:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:43:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:43:11 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 0x01908640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0193FE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01882B28>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01959E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C6520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-105', 'USERDOMAIN': 'T-W732-IX-105', '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-105', '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 06:43:11 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 06:43:11 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 06:43:11 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:43:11 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:43:11 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:43:11 INFO - 'COMPUTERNAME': 'T-W732-IX-105', 06:43:11 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:43:11 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:43:11 INFO - 'DCLOCATION': 'SCL3', 06:43:11 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:43:11 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:43:11 INFO - 'HOMEDRIVE': 'C:', 06:43:11 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:43:11 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:43:11 INFO - 'KTS_VERSION': '1.19c', 06:43:11 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:43:11 INFO - 'LOGONSERVER': '\\\\T-W732-IX-105', 06:43:11 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:43:11 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:43:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:43:11 INFO - 'MOZ_NO_REMOTE': '1', 06:43:11 INFO - 'NO_EM_RESTART': '1', 06:43:11 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:43:11 INFO - 'OS': 'Windows_NT', 06:43:11 INFO - 'OURDRIVE': 'C:', 06:43:11 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;', 06:43:11 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:43:11 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:43:11 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:43:11 INFO - 'PROCESSOR_LEVEL': '6', 06:43:11 INFO - 'PROCESSOR_REVISION': '1e05', 06:43:11 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:43:11 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:43:11 INFO - 'PROMPT': '$P$G', 06:43:11 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:43:11 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:43:11 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:43:11 INFO - 'PWD': 'C:\\slave\\test', 06:43:11 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:43:11 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:43:11 INFO - 'SYSTEMDRIVE': 'C:', 06:43:11 INFO - 'SYSTEMROOT': 'C:\\windows', 06:43:11 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:43:11 INFO - 'TEST1': 'testie', 06:43:11 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:43:11 INFO - 'USERDOMAIN': 'T-W732-IX-105', 06:43:11 INFO - 'USERNAME': 'cltbld', 06:43:11 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:43:11 INFO - 'WINDIR': 'C:\\windows', 06:43:11 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:43:11 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:43:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:43:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:43:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 06:43:11 INFO - Cleaning up... 06:43:11 INFO - Return code: 0 06:43:11 INFO - Running post-action listener: _resource_record_post_action 06:43:11 INFO - Running post-action listener: _start_resource_monitoring 06:43:11 INFO - Starting resource monitoring. 06:43:11 INFO - [mozharness: 2016-10-19 13:43:11.577000Z] Finished create-virtualenv step (success) 06:43:11 INFO - [mozharness: 2016-10-19 13:43:11.578000Z] Running install step. 06:43:11 INFO - Running pre-action listener: _resource_record_pre_action 06:43:11 INFO - Running main action method: install 06:43:11 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 06:43:11 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 06:43:11 INFO - Reading from file tmpfile_stdout 06:43:11 INFO - Using _rmtree_windows ... 06:43:11 INFO - Using _rmtree_windows ... 06:43:11 INFO - Detecting whether we're running mozinstall >=1.0... 06:43:11 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 06:43:11 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 06:43:12 INFO - Reading from file tmpfile_stdout 06:43:12 INFO - Output received: 06:43:12 INFO - Usage: mozinstall-script.py [options] installer 06:43:12 INFO - Options: 06:43:12 INFO - -h, --help show this help message and exit 06:43:12 INFO - -d DEST, --destination=DEST 06:43:12 INFO - Directory to install application into. [default: 06:43:12 INFO - "C:\slave\test"] 06:43:12 INFO - --app=APP Application being installed. [default: firefox] 06:43:12 INFO - Using _rmtree_windows ... 06:43:12 INFO - Using _rmtree_windows ... 06:43:12 INFO - mkdir: C:\slave\test\build\application 06:43:12 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'] 06:43:12 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 06:43:14 INFO - Reading from file tmpfile_stdout 06:43:14 INFO - Output received: 06:43:14 INFO - C:\slave\test\build\application\firefox\firefox.exe 06:43:14 INFO - Using _rmtree_windows ... 06:43:14 INFO - Using _rmtree_windows ... 06:43:14 INFO - Running post-action listener: _resource_record_post_action 06:43:14 INFO - [mozharness: 2016-10-19 13:43:14.450000Z] Finished install step (success) 06:43:14 INFO - [mozharness: 2016-10-19 13:43:14.451000Z] Running run-tests step. 06:43:14 INFO - Running pre-action listener: _resource_record_pre_action 06:43:14 INFO - Running main action method: run_tests 06:43:14 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 06:43:14 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 06:43:14 INFO - Python 2.7.3 06:43:14 INFO - Return code: 0 06:43:14 INFO - grabbing minidump binary from tooltool 06:43: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')]} 06:43:14 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 0x01959E90>, '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 0x012CA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x019C6520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 06:43:14 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 06:43:14 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 06:43:14 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 06:43:14 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 06:43:15 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpski_du 06:43:15 INFO - INFO - File integrity verified, renaming tmpski_du to win32-minidump_stackwalk.exe 06:43:15 INFO - Return code: 0 06:43:15 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 06:43:15 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 06:43:15 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 06:43:15 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 06:43:15 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 06:43:15 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/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-105', '--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 06:43:15 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/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-105 --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 06:43:15 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:43:15 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:43:15 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:43:15 INFO - 'COMPUTERNAME': 'T-W732-IX-105', 06:43:15 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:43:15 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:43:15 INFO - 'DCLOCATION': 'SCL3', 06:43:15 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:43:15 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:43:15 INFO - 'HOMEDRIVE': 'C:', 06:43:15 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:43:15 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:43:15 INFO - 'KTS_VERSION': '1.19c', 06:43:15 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:43:15 INFO - 'LOGONSERVER': '\\\\T-W732-IX-105', 06:43:15 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 06:43:15 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 06:43:15 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:43:15 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:43:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:43:15 INFO - 'MOZ_NO_REMOTE': '1', 06:43:15 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 06:43:15 INFO - 'NO_EM_RESTART': '1', 06:43:15 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:43:15 INFO - 'OS': 'Windows_NT', 06:43:15 INFO - 'OURDRIVE': 'C:', 06:43:15 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;', 06:43:15 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:43:15 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:43:15 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:43:15 INFO - 'PROCESSOR_LEVEL': '6', 06:43:15 INFO - 'PROCESSOR_REVISION': '1e05', 06:43:15 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:43:15 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:43:15 INFO - 'PROMPT': '$P$G', 06:43:15 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:43:15 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:43:15 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:43:15 INFO - 'PWD': 'C:\\slave\\test', 06:43:15 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 06:43:15 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:43:15 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:43:15 INFO - 'SYSTEMDRIVE': 'C:', 06:43:15 INFO - 'SYSTEMROOT': 'C:\\windows', 06:43:15 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:43:15 INFO - 'TEST1': 'testie', 06:43:15 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:43:15 INFO - 'USERDOMAIN': 'T-W732-IX-105', 06:43:15 INFO - 'USERNAME': 'cltbld', 06:43:15 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:43:15 INFO - 'WINDIR': 'C:\\windows', 06:43:15 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:43:15 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:43:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:43:15 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/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-105', '--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 06:43:16 INFO - mozversion application_buildid: 20161019030208 06:43:16 INFO - mozversion application_changeset: 90d8afaddf9150853b0b68b35b30c1e54a8683e7 06:43:16 INFO - mozversion application_display_name: Nightly 06:43:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:43:16 INFO - mozversion application_name: Firefox 06:43:16 INFO - mozversion application_remotingname: firefox 06:43:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:43:16 INFO - mozversion application_vendor: Mozilla 06:43:16 INFO - mozversion application_version: 52.0a1 06:43:16 INFO - mozversion platform_buildid: 20161019030208 06:43:16 INFO - mozversion platform_changeset: 90d8afaddf9150853b0b68b35b30c1e54a8683e7 06:43:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:43:16 INFO - mozversion platform_version: 52.0a1 06:43:16 INFO - using testdate: 1476884596 06:43:16 INFO - actual date: 1476884596 06:43:16 INFO - starting webserver on 'localhost:49901' 06:43:16 INFO - SUITE-START | Running 2 tests 06:43:16 INFO - TEST-START | dromaeo_css 06:43:16 INFO - operating with platform_type : w7_ 06:43:16 INFO - Initialising browser for dromaeo_css test... 06:43:16 INFO - TEST-INFO | started process 2168 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpku2x0z\profile http://localhost:49901/getInfo.html) 06:43:24 INFO - PROCESS | 2168 | __metrics Screen width/height:1600/1200 06:43:24 INFO - PROCESS | 2168 | colorDepth:24 06:43:24 INFO - PROCESS | 2168 | Browser inner width/height: 1010/674 06:43:24 INFO - PROCESS | 2168 | __metrics 06:43:25 INFO - PROCESS | 2168 | 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" 06:43:25 INFO - PROCESS | 2168 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:43:26 INFO - TEST-INFO | 2168: exit 0 06:43:26 INFO - Browser initialized. 06:43:26 INFO - Running cycle 1/1 for dromaeo_css test... 06:43:26 INFO - TEST-INFO | started process 2556 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpku2x0z\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:43:26 INFO - PROCESS | 2556 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 06:43:37 INFO - PROCESS | 2556 | RSS: Main: 123424768 06:43:37 INFO - PROCESS | 2556 | 06:44:28 INFO - PROCESS | 2556 | 1476884668669 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] 06:46:39 INFO - PROCESS | 2556 | Cycle 1(1): loaded http://localhost:49901/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49901/tests/dromaeo/cssquery-ext.html) 06:46:41 INFO - PROCESS | 2556 | RSS: Main: 240529408 06:46:41 INFO - PROCESS | 2556 | 06:49:43 INFO - PROCESS | 2556 | Cycle 1(1): loaded http://localhost:49901/tests/dromaeo/cssquery-ext.html (next: http://localhost:49901/tests/dromaeo/cssquery-jquery.html) 06:49:44 INFO - PROCESS | 2556 | RSS: Main: 199102464 06:49:44 INFO - PROCESS | 2556 | 06:52:46 INFO - PROCESS | 2556 | Cycle 1(1): loaded http://localhost:49901/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49901/tests/dromaeo/cssquery-mootools.html) 06:52:47 INFO - PROCESS | 2556 | RSS: Main: 144781312 06:52:47 INFO - PROCESS | 2556 | 06:55:49 INFO - PROCESS | 2556 | Cycle 1(1): loaded http://localhost:49901/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49901/tests/dromaeo/cssquery-prototype.html) 06:55:49 INFO - PROCESS | 2556 | RSS: Main: 143908864 06:55:49 INFO - PROCESS | 2556 | 06:58:52 INFO - PROCESS | 2556 | Cycle 1(1): loaded http://localhost:49901/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49901/tests/dromaeo/cssquery-yui.html) 06:58:53 INFO - PROCESS | 2556 | RSS: Main: 133173248 06:58:53 INFO - PROCESS | 2556 | 07:01:55 INFO - PROCESS | 2556 | Cycle 1(1): loaded http://localhost:49901/tests/dromaeo/cssquery-yui.html (next: http://localhost:49901/tests/dromaeo/cssquery-dojo.html) 07:01:55 INFO - PROCESS | 2556 | RSS: Main: 265871360 07:01:55 INFO - PROCESS | 2556 | 07:01:55 INFO - PROCESS | 2556 | __start_tp_report 07:01:55 INFO - PROCESS | 2556 | _x_x_mozilla_page_load 07:01:55 INFO - PROCESS | 2556 | _x_x_mozilla_page_load_details 07:01:55 INFO - PROCESS | 2556 | |i|pagename|runs| 07:01:55 INFO - PROCESS | 2556 | |0;dojo.html;2707.634469346638;2748.2854457840954;2758.379364642956;2760.5997130416076;2766.142495826293;4585.564371384718;4657.371254880594;4713.882152946174;4746.6440016998695;4750.311204875291;4627.467841198263;4633.837815676452;4698.295255711645;4755.667103302771;4770.4275486941615;4138.0689344897455;4148.12889293248;4169.708120431571;4179.2059508693255;4190.937135942963;4050.3127116280816;4127.071408932996;4153.813078411464;4208.831646734127;4209.284421648327;4135.29699951009;4139.151473947849;4142.109446469009;4223.472065991751;4225.176090662322;3986.4817573715522;4012.177503611782;4127.071408932996;4158.106007208435;4197.370394440828;4065.2072845795083;4069.3285607874664;4134.91730165398;4160.022394737234;4171.91656166878;4571.679982401233;4692.6245900327895;4709.693869898446;4710.128626204163;4722.126406614753;4866.172750632401;4875.463698993108;4968.87577810554;4978.775955081994;4981.551660350549;4806.759662016885;4889.046635906;4899.779509922062;4905.852824415273;4935.777889994959;4603.286490593963;4621.2837010263465;4636.698614590041;4682.882927926829;4686.297055441711;4975.4278258000795;5026.572741316956;5121.411037730634;5127.589792816566;5141.126008578551;4820.132376172252;4824.831130910401;4846.39420072491;4919.803207871645;4922.261660750844;4840.661153719206;4872.512748725099;4897.412310522689;4947.777350019258;4998.2002879539095;4897.1674815281485;4911.778969946361;4914.778834952435;4917.87705307363;4922.778474968635;4905.705657660552;4922.064807686528;4924.285978533093;4925.753712314442;4929.556339929351;3324.0027991602424;3329.450640644315;3330.317284956578;3337.349216902729;3340.799552026886;2929.5312749959903;2996.8201907885596;3025.3797971415806;3027.7577793776445;3075.2004478835665;4747.2879068140055;4754.096721622881;4759.857204283877;4762.52374762521;4775.450823155312;4891.73095479752;4909.533594308535;4957;4963.677360971597;4968;4495.100979804052;4658.277966915133;4816.277558366273;4817.373741413594;4824.155772739757;4041.009952561641;4069.5116586009867;4085.0604360997136;4188.560201178832;4217.114405974779;5214.113600687892;5232.162853943427;5233.39815921174;5287.973560132252;5296.258523806747;4884.682495637772;5020.347354843993;5025.974870125626;5028.170351891897;5038.798448062036;4994.275830004702;5148.639595228298;5277.577793776565;5281.709505977208;5314.335708036496;4777.665563410667;4783.306420568929;4800.66395352322;4857.441394239708;4982.327385802871;4896.6572339935865;4953.083679519301;4992.450830408579;5009.574186194214;5059.569936555286;4767.976160119316;4789.090072886141;4819.325294458708;4835;4883.877903052452;4730.550597693214;4751.334813126096;4773.594244489257;4794.041191761731;4881.292212629078;4692.202325604587;4712.552307530779;4755.048990202043;4758.214894542361;4760.333553302609;4864.294677271847;4885.462599114024;4886.242632391985;4888.90222195547;4892.339534162842;4769.57073863354;4820.590249828663;4822.927656085231;4824.445188803333;4829.903401932012 07:01:55 INFO - PROCESS | 2556 | |1;ext.html;516.4499807704794;538.7037129578731;550.9641873278238;557.4509108528097;571.9828405147847;27737;37686;38601.806990965044;39701.80149099258;40528.59471405285;38019.61980380195;39520.80239598798;39576.604233957725;39865;39866.40200396996;6670.566413183152;7674.347680447168;7888.317971889571;8086.878696819553;8101.392395570327;6601.075849381091;6633.502487313447;6787.4570034397375;9474.431534107942;9994.250431217652;10063.245256605784;10081.44552049637;10091.444970526618;10100.141487973531;10117.140043096346;9813.705588832276;10036.247281453883;10077.14344280737;10102.898971010343;10111.44387058715;6677.3656502632175;6879.174499060095;9565.521723913846;9896.307258491908;9912.603495860158;36698.08254793625;36821.63178368209;36871.631283687355;36937.44593831095;37008;2894.45005448964;2901.3326934805114;3263.7225835803984;3478.634743351937;3516.033090899997;28940.98706545261;28942.71057289421;29451;29531.55702664462;29619.85190074036;3331.900042998714;3361.0757041813695;3388.203772113551;3390.9660903391145;3517.964820351815;300932.4953375241;302911;303375;304822;305159;49146.26280605794;49276.26085608719;49543;49588.50411495839;49611;28172.43655126927;28215.15354539367;28243.57634635482;29847.850760746493;51247.48752512502;13416.664583385493;13465.596032119052;13828.93085534566;13911.860881391056;13924.443022278996;9251.658509516083;10288.948555257175;10545.47272636365;10584.57661693539;10619;14190.29048547589;16120.435784747477;16174.75737863933;16300.347986080662;16847.83152168487;6510.25132109804;6527.836804079841;6562.737490500326;6589.604623722592;6633.336666333328;774.6165648004161;911.9088091190828;931.38993958957;934.7195841247599;956.7034219392011;26458.17153553587;26558.203253902026;26595.867020664773;26769;27091;892.1970226795806;897.4570384917031;910.4749395504333;932.715521765868;987.3878195518824;13936.58190254294;14019.228942408265;14293.142411455137;14349.85650143506;14477.348519316445;323405;396087;403562;414288;414796;46212.7689361551;46525;46761;46963.765181173876;47012.5298747015;1000.5541991821893;1029.130384824822;1032.83990981398;1074.1245884604023;1097.1113398147527;25590.360240994123;27413.725862741117;27503.86248068747;27870.721292786813;28020.299492512037;39099.413508796824;40183.59816401799;40453.79773101116;40757.59242407538;40951.7952410236;337111;337901;346618;347384;349485;22911.770882291632;38322.61677383302;38334;38426.61573384231;38705.806470967465;39623.40564891471;40099.398509021805;41102.17795644011;41729;42180.36729449122;35470.46794298036;35765.28469430545;37182.8140859294;37305.44041839429;38022;36325.636743632225;37241.81379093087;37303.81348093242;37326;37909.24181516299;32377.51433728543;33459.330813384084;33929.66070339364;34740.47889281713;34783;32400;32475.675243247264;32805.50791738078;33000;33325.50011749874;24361.39096522601;24758.25725228246;24905.87547062253;25150.371240718396;25333 07:01:55 INFO - PROCESS | 2556 | |2;jquery.html;269.023444895031;295.97780166487513;297.250927662291;302.99091027269174;303.05598062036756;12726.363681815907;16783.91608041961;17045;17166.39917602884;17789.46631601051;16810.495685129434;16967.15164241789;17158;17279.395221167262;17317.91341043293;5003.3245511855885;5224.529792318691;5242.501962313574;5250.396204436482;5252.133397989346;5022.347094877661;5156.793728250866;5231.973840130794;5282.154855223165;5292.841214763564;5278.076336641092;5285.048691235576;5285.920711189336;5305.814296499623;5310.389305229909;5104.795808167669;5221.086309895791;5249.947500524984;5303.124984377592;5305.283786688806;5107.693538387709;5124.692518448867;5132.794688212467;5133.871653208662;5144.0740666679985;10689.572417103307;10771.461426928623;11000.504977276041;11127.944360278228;11291.604793832177;1555.4083071358448;1601.5035339044932;1708;1726.4906852478548;1851.6204178143582;8544.829103417896;8641.354913501826;8874.86687699685;8974.50640214788;8984.10158984103;1689.8648108151442;1706.2663054886393;1758.8856724313007;1760.3222759237785;1763.4621440460655;79580.20419795786;82018;82941.17058829394;83654.49036528914;84935.575322123;8800.47197168172;8887.644494220289;8936.955315223382;9088.681896133603;9127.543622818832;10668.253222274367;11166;25470.235892923243;25585;26009.739902600733;8655.78360540979;8789.516576588348;9012.233960113464;9113.544322783835;9156.816863662689;7646.4265180111715;7722.11195712489;7735.729249476241;7754.612269386508;7902.170272121344;7691.961540192263;7846.254605812553;7888.250616191567;7997.60041992649;8074.636641351153;3161.462551366273;3166.60417447819;3192.74458043356;3223.8722443187385;3276.361109583608;763.7632333976485;782.4366456151562;792.7265093542718;845.2941793602411;868.600443795861;16668.916655416644;17576.121193940253;17895.28418863257;18125.731198815956;18360.08199590042;785.9331956783623;797.8962734844549;806.6087947345533;814.3607268294361;919.9402038867452;9246.768830779285;9398.662150971728;9776.706698799047;9787.608495660237;9889.45607991553;380304;405274;422257;422575;437953;92770.53614731884;92974.53512732394;93073.53463232641;94440.05559944312;96238.51880740552;862.1507814802414;889.1330952321434;891.8350105230553;894.6600291889041;894.7360528644066;15810.604734881259;15980.600484987968;16161.515154545383;16294.755578666567;16385.69324469573;40213.798931005156;40578;40832.3875141879;40897;41434.79282603568;55628;57825;58276.70861645665;58590.70704646449;60605.69697151486;6804.387605115563;6805.3875151236625;6824.2152152503095;6903.723851045902;7033.753818616324;8710.346723995599;11599.942000289944;11614.477348519506;11771.234869733782;11935.641930742091;9510.904890951002;9619.134277915022;9735.561899714388;10071.848922266025;10149.797004060025;9399.859002114837;9446.39411300067;9700.660476883275;9782.364146330467;10317.123044541298;9809.509524523888;10003.499825008867;10019.699409017741;10051.69844904654;10251.641192558225;6601.669916504059;6780.898286525805;6819.897701534382;6824.761133360308;6899.241083480913;6968.128983877015;9740.805183896424;9919.45443000613;9951.303408761318;10035.996400359905 07:01:55 INFO - PROCESS | 2556 | |3;mootools.html;1088.080571916731;1148.1044785067647;1157.2940506291166;1233.062872217115;1442.5888621742806;6122.142899994004;6863.897041544375;7027.5080744347915;7030.753923612675;7053.823654408642;7108.360247577717;7117.928820711792;7122.501424900259;7137.85724285514;7154.213036565987;1828.9176987035582;1837.577357207843;1841.7789865216193;1844.7878493973164;1851.1022154255174;1805.972910406345;1819.20864423976;1822.6445843060608;1823.4711933539259;1825.762650855387;1837.5498002989286;1856.4894653970132;1856.7957524672274;1858.9721154182698;1860.0327829528637;1752.6669932712744;1753.4914874686262;1768.2308195934809;1784.9553761155946;1793.3454289184353;1765.5762616971963;1787.499500139963;1787.6156626325403;1790.8209179081987;1791.5252458098614;6716.294789047129;6873.931260687379;6982.895256571155;6996.300369962963;7016.193137789169;3875.4380614810693;4123.958760412387;4126.257273690734;4128.896777580554;4129.421880936671;2566.9970311948277;2626.277773612253;2662.6405435266183;2668.132856821541;2668.3729323608936;728.6757392960135;761.1209053543166;767.6353731977322;768.4774353439659;769.6959700918106;8961.462312261285;9002.189802917703;9025.368224224307;9039.909600903973;9045.231155351803;3371.7471189660873;3372.915677108092;3392.168918614955;3434.227298857787;3444.4488881778793;7167.390771784352;7203.31568501002;7204.639768011579;7207.603581802946;7209.026781384446;3985.442038114666;3994.4208089827393;4034.8587799427464;4036.2129384770274;4043.332850079704;3394.13449570358;3487.7907325560545;3737.8131093445218;3749.8312575934146;3834.539855217336;2830.26413132587;2902.883884644633;2904.419116176773;2918.5038543447663;2950.9262268443463;835.0480452284279;835.6657337065101;843.3590471241778;844.9873251901226;845.4420082745359;745.642091795941;767.5816679909462;770.8650986077414;777.0752804163141;792.2592376128339;4394.582514661102;4544.772761361919;4575.862724118282;4596.3105534170145;4709.364235828188;769.1078349114999;787.1027029186731;787.3504358903928;798.6565776716068;812.6586833529932;3095.3190298134373;3104.8447577621027;3148.795328303653;3149.1497295730023;3149.5433162191352;8190.50856948585;8322;8375.8324833503;8439.451435656663;8450.154984501502;8398.496090234605;8405.327573794082;8408.033076196316;8416.915830841614;8422.199891010345;6469.51478639104;6573.967130164223;6612.570182938093;6663.766768163091;6677.899831502531;4599.770011499345;4622.6764126510825;4661.510541393241;4671.696339737906;4784.712917224978;7820.5307681537015;7912.446128770931;7945.523268603903;7957.204279571996;7959.761207163794;7902.960485197767;7921.881171782313;8012.679492820455;8018.198180181935;8027.638756256099;5637.830865074055;6443.194600674916;6578.506612004023;6621;6716.630585317855;6058.0912863069125;6229;6531;6794.592324460548;6814.488913331619;6003.969980150071;6126.724297406538;6179.84550386244;6205.348438413902;6226.346233645402;5965.164876917148;6018.849528761816;6031.969840150771;6061.484773794277;6109.7861574844665;5533.474319939599;5550.722463876871;5594.692291923983;5600.467955544217;5602.971985140048;3954.5452272988973;4047.2310261050307;4057.5333836609166;4088.6933479988525;4147.460830092069;3902.8829135125993;3986.800659966932;4027.8993025174605;4036.979815100906;4065.10567675111 07:01:55 INFO - PROCESS | 2556 | |4;prototype.html;245.8463460337289;248.41126530123603;249.2423033976711;254.28799361786997;255.67401563007175;4963.652544321899;5137.6403651744395;5142.357205349326;5152.613553983448;5152.639315247934;4912.926306105412;4959.727215003173;5016.398032236128;5022.648414610979;5060.671056381333;3699.8160588611654;3705.0181701848896;3716.349638813217;3721.6092310307436;3721.981390093046;3681.141023782999;3687.59436461989;3700.1674623209833;3721.0139313082054;3725.590185079639;3492.0920560654286;3540.716742660581;3545.86532309661;3571.5535558055244;3691.169486865461;3626.5285512883156;3652.4886515887797;3656.744027918047;3657.195417008241;3667.6149004354425;3098.47325954586;3146.181992681896;3147.2446612812987;3148.889788857402;3149.3543823516125;4951.108800415924;4957.058158949824;4962.1812400953795;4977.253411988231;4981.501849815026;4013.779242141681;4198.160367926396;4276.871693849158;4277.251480990814;4278.401023856632;5218.895622087537;5241.764120614582;5250;5255.553277971377;5257.447967963347;3439.208981934169;3439.329330780499;3442.569678790151;3444.2594842109065;3449.8275086245585;5530.391656917735;5536.944630553683;5545.556355491551;5551;5554.222408862763;3462.8441720122655;3464.72282217422;3468.254325320018;3470.670286322795;3475.374432602105;3365.1480861950117;3382.8139452329865;3531.646835316448;3536.3457760314423;3537.1157210697324;4212.7682977435925;4218.704690671623;4228.556001619847;4228.788560571959;4230.15396920611;5214.478552144755;5253;5322.574194064466;5333.040052790458;5350.689081175137;1202.1584890576457;1210.3887536793864;1216.2702378572963;1217.8903898649164;1218.4090716002731;742.8848528478095;756.228646780281;768.1013214539003;786.5241528875063;789.3487872505207;5103.719295438712;5110.97444512775;5111.495367270011;5133.768980395891;5138.486151384906;5439.292891924025;5446.210299506629;5454.645448045864;5455.454454554592;5465.371482279509;5128.435872054071;5147.768350424165;5148.845534633967;5157.613179011589;5165.1219292720325;2810.6065150878894;2849.886004559836;2884.3798583304692;2901.8403987780875;2908.3020075182226;5731.742071606787;5734.2258795062135;5766.904288185231;5768.509676677446;5774.556360909773;5523.226748255248;5530.944690553043;5531.1426728857095;5543.1408131738835;5543.1408131739645;5588.580856435784;5691.715414229272;5760.465395226087;5882.970585147047;5895.528357731457;5535.7785688572;5556.333240011225;5566.805161819317;5576.191452239322;5579.860503487445;5482.698451585201;5494.450554944474;5495.313085864267;5499.50504454601;5508.44915508446;5729.255196824386;5786.537077033911;5894.233749612523;5895.734691938787;5898.587098903207;3459.0141809584147;3526.2368815592204;3526.8412921418085;3528.3769466045906;3529.464682862963;5552.500274975271;5563.58273129525;5563.970665426909;5578.30271216098;5582.078956972054;5325.893482130258;5333.280007198979;5335.199720042025;5368.785248590013;5395.919061214164;5266.473352664703;5301.151815709468;5392.191171324333;5394.865128371665;5397.70312632809;5149.639525233198;5240.2663627093;5243.973780131075;5245.160774276097;5258.447862974333;5176.352955880515;5264.499872512105;5369.865753356197;5388.676679399092;5416.458354164552;5101.438841727481;5240.829298274642;5311.973440132929;5312.946870531246;5322.935412917354 07:01:55 INFO - PROCESS | 2556 | |5;yui.html;609.9268087829461;613.9201903752512;627.6296984778979;651.3226244705505;691.8996745471907;13998.090124141936;14061.50784722535;14514;14734.484293049745;14805.777913331296;12358.876411235886;12607.306598137098;12832.93583532081;13116.606501804938;13207;499.4306490600718;597.7399831073486;615.2432508015144;619.096119665287;619.2166908860295;509.40654137929454;609.3632154398664;619.427029997253;622.6419808610044;626.3861415812484;646.9256035555901;647.3623480871338;649.3636236488244;651.9511036672245;651.9902201466982;515.4767910570736;637.8054693318536;642.425029598509;649.3798422506495;650.4016304999388;382.42826973674255;461.5407669368966;465.4623909384632;468.59466561424125;469.0993292877683;12585.993120550425;12789.48842046317;12799.552015679406;12830.935845320713;12841.550545730855;12391.938040309831;12480.126391152622;12483.625491235278;12641;12840.550580729723;12439.93780031103;12660.683482913;12662.49350026007;12837;13058.085933984625;4192.224438478861;4217.557156498554;4225.577442255781;4288.635465985395;4404.031113155101;80558;84397;84696.57651711702;87253.56373218093;88254.55872720659;9065.728028159167;9412.623495060257;9862.605495780232;10062.949685251528;10427.582896684047;18635.90682046581;18649.813501864806;18682.53293667642;18690.532736681693;18770.718439223423;6588.637624930579;6849.1438570178725;6967.372936435745;7082.645867706594;7183.138023437221;3474.0967348489576;3542.6457354264885;3594.8741794037082;3639.3449179148006;3667.101560117735;2720.088770261945;2736.9726302737117;2808.705085965985;2818.760405365526;2822.223888430698;513.117438006628;514.7580637100557;518.984430467079;519.158962480776;521.817363922624;509.98980020399387;584.9736761845727;589.9381113994792;594.521410264741;645.6255371884296;8653.221210091122;8707.956460217658;8857.158569935846;9040.231580315747;9133.45199288045;582.2837909371497;602.6594973839766;617.3363634093367;632.5129650169343;649.1852724830308;3895.1235971906094;3914.745541539791;3979.3434083376496;4099.098198396348;4187.539370669224;113520.43239783749;115830;116201;116323.09192270091;116980.58504788646;99278.5036074815;101609.49195254124;103506;103663.48168259111;104704.38239043932;73935;85360;90477.54761226283;92227;93317;8713.651453941771;8833;9090.863637045582;9264.351495395258;9329.813403731752;47557.52442475531;47765;48314;48981;49357.25964110469;63699.6815015922;70360.29639703743;70961.29038709546;71051.28948710447;72239.27760722325;11541.596044138414;11810.76378472443;11977.700557486132;12006;12091.697707557381;14165.645858853612;14311.785323220369;14713.190774507504;14746;14972.1016738996;12651.177673451197;13666.385012674653;13726.65683357924;14018.088824226392;14297.966002547926;12216.328101954477;12388.442520086797;12469.563565275535;12552.93723531413;12858.485660573473;13226;13366.665833354244;13464.25946572948;13496.392662330023;13638.727225455237;11248.381339026104;11351.59603838264;11374.758193914695;11487.8276825846;11743.412829358327;10673.199510036624;10861.131109511378;10944.562217511211;11180.944095279472;11185.776284474103 07:01:55 INFO - PROCESS | 2556 | __end_tp_report 07:01:55 INFO - PROCESS | 2556 | __start_cc_report 07:01:55 INFO - PROCESS | 2556 | _x_x_mozilla_cycle_collect,1779 07:01:55 INFO - PROCESS | 2556 | __end_cc_report 07:01:55 INFO - PROCESS | 2556 | __startTimestamp1476885715823__endTimestamp 07:01:55 INFO - PROCESS | 2556 | 07:01:55 INFO - PROCESS | 2556 | ------- Summary: start ------- 07:01:55 INFO - PROCESS | 2556 | Number of tests: 6 07:01:55 INFO - PROCESS | 2556 | 07:01:55 INFO - PROCESS | 2556 | [#0] dojo.html Cycles:165 Average:4572.13 Median:4777.67 stddev:586.34 (12.3%) stddev-sans-first:569.60 07:01:55 INFO - PROCESS | 2556 | Values: 2707.6 2748.3 2758.4 2760.6 2766.1 4585.6 4657.4 4713.9 4746.6 4750.3 4627.5 4633.8 4698.3 4755.7 4770.4 4138.1 4148.1 4169.7 4179.2 4190.9 4050.3 4127.1 4153.8 4208.8 4209.3 4135.3 4139.2 4142.1 4223.5 4225.2 3986.5 4012.2 4127.1 4158.1 4197.4 4065.2 4069.3 4134.9 4160.0 4171.9 4571.7 4692.6 4709.7 4710.1 4722.1 4866.2 4875.5 4968.9 4978.8 4981.6 4806.8 4889.0 4899.8 4905.9 4935.8 4603.3 4621.3 4636.7 4682.9 4686.3 4975.4 5026.6 5121.4 5127.6 5141.1 4820.1 4824.8 4846.4 4919.8 4922.3 4840.7 4872.5 4897.4 4947.8 4998.2 4897.2 4911.8 4914.8 4917.9 4922.8 4905.7 4922.1 4924.3 4925.8 4929.6 3324.0 3329.5 3330.3 3337.3 3340.8 2929.5 2996.8 3025.4 3027.8 3075.2 4747.3 4754.1 4759.9 4762.5 4775.5 4891.7 4909.5 4957.0 4963.7 4968.0 4495.1 4658.3 4816.3 4817.4 4824.2 4041.0 4069.5 4085.1 4188.6 4217.1 5214.1 5232.2 5233.4 5288.0 5296.3 4884.7 5020.3 5026.0 5028.2 5038.8 4994.3 5148.6 5277.6 5281.7 5314.3 4777.7 4783.3 4800.7 4857.4 4982.3 4896.7 4953.1 4992.5 5009.6 5059.6 4768.0 4789.1 4819.3 4835.0 4883.9 4730.6 4751.3 4773.6 4794.0 4881.3 4692.2 4712.6 4755.0 4758.2 4760.3 4864.3 4885.5 4886.2 4888.9 4892.3 4769.6 4820.6 4822.9 4824.4 4829.9 07:01:55 INFO - PROCESS | 2556 | 07:01:55 INFO - PROCESS | 2556 | [#1] ext.html Cycles:180 Average:48897.18 Median:35617.88 stddev:91780.41 (257.7%) stddev-sans-first:91966.00 07:01:55 INFO - PROCESS | 2556 | Values: 516.4 538.7 551.0 557.5 572.0 27737.0 37686.0 38601.8 39701.8 40528.6 38019.6 39520.8 39576.6 39865.0 39866.4 6670.6 7674.3 7888.3 8086.9 8101.4 6601.1 6633.5 6787.5 9474.4 9994.3 10063.2 10081.4 10091.4 10100.1 10117.1 9813.7 10036.2 10077.1 10102.9 10111.4 6677.4 6879.2 9565.5 9896.3 9912.6 36698.1 36821.6 36871.6 36937.4 37008.0 2894.5 2901.3 3263.7 3478.6 3516.0 28941.0 28942.7 29451.0 29531.6 29619.9 3331.9 3361.1 3388.2 3391.0 3518.0 300932.5 302911.0 303375.0 304822.0 305159.0 49146.3 49276.3 49543.0 49588.5 49611.0 28172.4 28215.2 28243.6 29847.9 51247.5 13416.7 13465.6 13828.9 13911.9 13924.4 9251.7 10288.9 10545.5 10584.6 10619.0 14190.3 16120.4 16174.8 16300.3 16847.8 6510.3 6527.8 6562.7 6589.6 6633.3 774.6 911.9 931.4 934.7 956.7 26458.2 26558.2 26595.9 26769.0 27091.0 892.2 897.5 910.5 932.7 987.4 13936.6 14019.2 14293.1 14349.9 14477.3 323405.0 396087.0 403562.0 414288.0 414796.0 46212.8 46525.0 46761.0 46963.8 47012.5 1000.6 1029.1 1032.8 1074.1 1097.1 25590.4 27413.7 27503.9 27870.7 28020.3 39099.4 40183.6 40453.8 40757.6 40951.8 337111.0 337901.0 346618.0 347384.0 349485.0 22911.8 38322.6 38334.0 38426.6 38705.8 39623.4 40099.4 41102.2 41729.0 42180.4 35470.5 35765.3 37182.8 37305.4 38022.0 36325.6 37241.8 37303.8 37326.0 37909.2 32377.5 33459.3 33929.7 34740.5 34783.0 32400.0 32475.7 32805.5 33000.0 33325.5 24361.4 24758.3 24905.9 25150.4 25333.0 07:01:55 INFO - PROCESS | 2556 | 07:01:55 INFO - PROCESS | 2556 | [#2] jquery.html Cycles:180 Average:26065.51 Median:5285.48 stddev:69043.61 (1306.3%) stddev-sans-first:69210.13 07:01:55 INFO - PROCESS | 2556 | Values: 269.0 296.0 297.3 303.0 303.1 12726.4 16783.9 17045.0 17166.4 17789.5 16810.5 16967.2 17158.0 17279.4 17317.9 5003.3 5224.5 5242.5 5250.4 5252.1 5022.3 5156.8 5232.0 5282.2 5292.8 5278.1 5285.0 5285.9 5305.8 5310.4 5104.8 5221.1 5249.9 5303.1 5305.3 5107.7 5124.7 5132.8 5133.9 5144.1 10689.6 10771.5 11000.5 11127.9 11291.6 1555.4 1601.5 1708.0 1726.5 1851.6 8544.8 8641.4 8874.9 8974.5 8984.1 1689.9 1706.3 1758.9 1760.3 1763.5 79580.2 82018.0 82941.2 83654.5 84935.6 8800.5 8887.6 8937.0 9088.7 9127.5 10668.3 11166.0 25470.2 25585.0 26009.7 8655.8 8789.5 9012.2 9113.5 9156.8 7646.4 7722.1 7735.7 7754.6 7902.2 7692.0 7846.3 7888.3 7997.6 8074.6 3161.5 3166.6 3192.7 3223.9 3276.4 763.8 782.4 792.7 845.3 868.6 16668.9 17576.1 17895.3 18125.7 18360.1 785.9 797.9 806.6 814.4 919.9 9246.8 9398.7 9776.7 9787.6 9889.5 380304.0 405274.0 422257.0 422575.0 437953.0 92770.5 92974.5 93073.5 94440.1 96238.5 862.2 889.1 891.8 894.7 894.7 15810.6 15980.6 16161.5 16294.8 16385.7 40213.8 40578.0 40832.4 40897.0 41434.8 55628.0 57825.0 58276.7 58590.7 60605.7 6804.4 6805.4 6824.2 6903.7 7033.8 8710.3 11599.9 11614.5 11771.2 11935.6 9510.9 9619.1 9735.6 10071.8 10149.8 9399.9 9446.4 9700.7 9782.4 10317.1 9809.5 10003.5 10019.7 10051.7 10251.6 6601.7 6780.9 6819.9 6824.8 6899.2 6968.1 9740.8 9919.5 9951.3 10036.0 07:01:55 INFO - PROCESS | 2556 | 07:01:55 INFO - PROCESS | 2556 | [#3] mootools.html Cycles:180 Average:4487.10 Median:5801.50 stddev:2540.55 (43.8%) stddev-sans-first:2534.83 07:01:55 INFO - PROCESS | 2556 | Values: 1088.1 1148.1 1157.3 1233.1 1442.6 6122.1 6863.9 7027.5 7030.8 7053.8 7108.4 7117.9 7122.5 7137.9 7154.2 1828.9 1837.6 1841.8 1844.8 1851.1 1806.0 1819.2 1822.6 1823.5 1825.8 1837.5 1856.5 1856.8 1859.0 1860.0 1752.7 1753.5 1768.2 1785.0 1793.3 1765.6 1787.5 1787.6 1790.8 1791.5 6716.3 6873.9 6982.9 6996.3 7016.2 3875.4 4124.0 4126.3 4128.9 4129.4 2567.0 2626.3 2662.6 2668.1 2668.4 728.7 761.1 767.6 768.5 769.7 8961.5 9002.2 9025.4 9039.9 9045.2 3371.7 3372.9 3392.2 3434.2 3444.4 7167.4 7203.3 7204.6 7207.6 7209.0 3985.4 3994.4 4034.9 4036.2 4043.3 3394.1 3487.8 3737.8 3749.8 3834.5 2830.3 2902.9 2904.4 2918.5 2950.9 835.0 835.7 843.4 845.0 845.4 745.6 767.6 770.9 777.1 792.3 4394.6 4544.8 4575.9 4596.3 4709.4 769.1 787.1 787.4 798.7 812.7 3095.3 3104.8 3148.8 3149.1 3149.5 8190.5 8322.0 8375.8 8439.5 8450.2 8398.5 8405.3 8408.0 8416.9 8422.2 6469.5 6574.0 6612.6 6663.8 6677.9 4599.8 4622.7 4661.5 4671.7 4784.7 7820.5 7912.4 7945.5 7957.2 7959.8 7903.0 7921.9 8012.7 8018.2 8027.6 5637.8 6443.2 6578.5 6621.0 6716.6 6058.1 6229.0 6531.0 6794.6 6814.5 6004.0 6126.7 6179.8 6205.3 6226.3 5965.2 6018.8 6032.0 6061.5 6109.8 5533.5 5550.7 5594.7 5600.5 5603.0 3954.5 4047.2 4057.5 4088.7 4147.5 3902.9 3986.8 4027.9 4037.0 4065.1 07:01:55 INFO - PROCESS | 2556 | 07:01:55 INFO - PROCESS | 2556 | [#4] prototype.html Cycles:180 Average:4392.04 Median:5148.31 stddev:1413.42 (27.5%) stddev-sans-first:1382.70 07:01:55 INFO - PROCESS | 2556 | Values: 245.8 248.4 249.2 254.3 255.7 4963.7 5137.6 5142.4 5152.6 5152.6 4912.9 4959.7 5016.4 5022.6 5060.7 3699.8 3705.0 3716.3 3721.6 3722.0 3681.1 3687.6 3700.2 3721.0 3725.6 3492.1 3540.7 3545.9 3571.6 3691.2 3626.5 3652.5 3656.7 3657.2 3667.6 3098.5 3146.2 3147.2 3148.9 3149.4 4951.1 4957.1 4962.2 4977.3 4981.5 4013.8 4198.2 4276.9 4277.3 4278.4 5218.9 5241.8 5250.0 5255.6 5257.4 3439.2 3439.3 3442.6 3444.3 3449.8 5530.4 5536.9 5545.6 5551.0 5554.2 3462.8 3464.7 3468.3 3470.7 3475.4 3365.1 3382.8 3531.6 3536.3 3537.1 4212.8 4218.7 4228.6 4228.8 4230.2 5214.5 5253.0 5322.6 5333.0 5350.7 1202.2 1210.4 1216.3 1217.9 1218.4 742.9 756.2 768.1 786.5 789.3 5103.7 5111.0 5111.5 5133.8 5138.5 5439.3 5446.2 5454.6 5455.5 5465.4 5128.4 5147.8 5148.8 5157.6 5165.1 2810.6 2849.9 2884.4 2901.8 2908.3 5731.7 5734.2 5766.9 5768.5 5774.6 5523.2 5530.9 5531.1 5543.1 5543.1 5588.6 5691.7 5760.5 5883.0 5895.5 5535.8 5556.3 5566.8 5576.2 5579.9 5482.7 5494.5 5495.3 5499.5 5508.4 5729.3 5786.5 5894.2 5895.7 5898.6 3459.0 3526.2 3526.8 3528.4 3529.5 5552.5 5563.6 5564.0 5578.3 5582.1 5325.9 5333.3 5335.2 5368.8 5395.9 5266.5 5301.2 5392.2 5394.9 5397.7 5149.6 5240.3 5244.0 5245.2 5258.4 5176.4 5264.5 5369.9 5388.7 5416.5 5101.4 5240.8 5312.0 5312.9 5322.9 07:01:55 INFO - PROCESS | 2556 | 07:01:55 INFO - PROCESS | 2556 | [#5] yui.html Cycles:180 Average:20430.50 Median:4143.32 stddev:30666.78 (740.2%) stddev-sans-first:30716.70 07:01:55 INFO - PROCESS | 2556 | Values: 609.9 613.9 627.6 651.3 691.9 13998.1 14061.5 14514.0 14734.5 14805.8 12358.9 12607.3 12832.9 13116.6 13207.0 499.4 597.7 615.2 619.1 619.2 509.4 609.4 619.4 622.6 626.4 646.9 647.4 649.4 652.0 652.0 515.5 637.8 642.4 649.4 650.4 382.4 461.5 465.5 468.6 469.1 12586.0 12789.5 12799.6 12830.9 12841.6 12391.9 12480.1 12483.6 12641.0 12840.6 12439.9 12660.7 12662.5 12837.0 13058.1 4192.2 4217.6 4225.6 4288.6 4404.0 80558.0 84397.0 84696.6 87253.6 88254.6 9065.7 9412.6 9862.6 10062.9 10427.6 18635.9 18649.8 18682.5 18690.5 18770.7 6588.6 6849.1 6967.4 7082.6 7183.1 3474.1 3542.6 3594.9 3639.3 3667.1 2720.1 2737.0 2808.7 2818.8 2822.2 513.1 514.8 519.0 519.2 521.8 510.0 585.0 589.9 594.5 645.6 8653.2 8708.0 8857.2 9040.2 9133.5 582.3 602.7 617.3 632.5 649.2 3895.1 3914.7 3979.3 4099.1 4187.5 113520.4 115830.0 116201.0 116323.1 116980.6 99278.5 101609.5 103506.0 103663.5 104704.4 73935.0 85360.0 90477.5 92227.0 93317.0 8713.7 8833.0 9090.9 9264.4 9329.8 47557.5 47765.0 48314.0 48981.0 49357.3 63699.7 70360.3 70961.3 71051.3 72239.3 11541.6 11810.8 11977.7 12006.0 12091.7 14165.6 14311.8 14713.2 14746.0 14972.1 12651.2 13666.4 13726.7 14018.1 14298.0 12216.3 12388.4 12469.6 12552.9 12858.5 13226.0 13366.7 13464.3 13496.4 13638.7 11248.4 11351.6 11374.8 11487.8 11743.4 10673.2 10861.1 10944.6 11180.9 11185.8 07:01:55 INFO - PROCESS | 2556 | -------- Summary: end -------- 07:01:55 INFO - PROCESS | 2556 | 07:01:56 INFO - TEST-INFO | 2556: exit 0 07:01:57 INFO - TEST-OK | dromaeo_css | took 1120519ms 07:01:57 INFO - TEST-START | kraken 07:01:57 INFO - operating with platform_type : w7_ 07:01:57 INFO - Initialising browser for kraken test... 07:01:57 INFO - TEST-INFO | started process 1848 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmppss7mw\profile http://localhost:49901/getInfo.html) 07:02:01 INFO - PROCESS | 1848 | __metrics Screen width/height:1600/1200 07:02:01 INFO - PROCESS | 1848 | colorDepth:24 07:02:01 INFO - PROCESS | 1848 | Browser inner width/height: 1010/674 07:02:01 INFO - PROCESS | 1848 | __metrics 07:02:02 INFO - PROCESS | 1848 | 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:02:02 INFO - PROCESS | 1848 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:02:02 INFO - PROCESS | 1848 | console.error: 07:02:02 INFO - PROCESS | 1848 | Message: Error: Connection closed before committing the transaction. 07:02:02 INFO - PROCESS | 1848 | Stack: 07:02:02 INFO - PROCESS | 1848 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 07:02:02 INFO - PROCESS | 1848 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 07:02:02 INFO - PROCESS | 1848 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 07:02:02 INFO - PROCESS | 1848 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 07:02:02 INFO - PROCESS | 1848 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 07:02:02 INFO - PROCESS | 1848 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 07:02:02 INFO - PROCESS | 1848 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 07:02:02 INFO - PROCESS | 1848 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 07:02:02 INFO - PROCESS | 1848 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 07:02:02 INFO - PROCESS | 1848 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 07:02:02 INFO - PROCESS | 1848 | Barrier/this.client.addBlocker/promise13.1% 07:02:59 INFO - TinderboxPrint: I/O read bytes / time
73,828,864 / 1,018,270 07:02:59 INFO - TinderboxPrint: I/O write bytes / time
483,218,432 / 3,428,320 07:02:59 INFO - TinderboxPrint: CPU idle
8,253.8 (86.9%) 07:02:59 INFO - TinderboxPrint: CPU user
1,210.6 (12.7%) 07:02:59 INFO - install - Wall time: 3s; CPU: 9.0%; Read bytes: 0; Write bytes: 34911744; Read time: 0; Write time: 224440 07:02:59 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 73828864; Write bytes: 412245504; Read time: 1018270; Write time: 3177900 07:03:00 INFO - Running post-run listener: _upload_blobber_files 07:03:00 INFO - Blob upload gear active. 07:03:00 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 07:03:00 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:03:00 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:03:00 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:03:00 INFO - (blobuploader) - INFO - Open directory for files ... 07:03:00 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 07:03:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:03:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:03:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:03:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:03:01 INFO - (blobuploader) - INFO - Done attempting. 07:03:01 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 07:03:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:03:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:03:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:03:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:03:03 INFO - (blobuploader) - INFO - Done attempting. 07:03:03 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 07:03:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:03:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:03:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:03:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:03:11 INFO - (blobuploader) - INFO - Done attempting. 07:03:11 INFO - (blobuploader) - INFO - Iteration through files over. 07:03:11 INFO - Return code: 0 07:03:11 INFO - rmtree: C:\slave\test\build\uploaded_files.json 07:03:11 INFO - Using _rmtree_windows ... 07:03:11 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 07:03:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4770d031aa25e1fcdf0a93b1b786b70d7749f357a8066cc30525fc7d5722945ecc72c248049a66d1f7daf0217cc59218832837a75a6f2bd2df346e0dbf35b7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/837ace1503dac1db3d63b40eda5f421e3bd0c917837f9f04f378da80b4890f3ad7de7ac0c80dc1de4f748f4bb05fb97eae85e8a94d9495eb0d180d25a8a78d75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a30bd4ef0f85c4be0f01f4f05beed6d0511ff480249cc869187c90cfa1d093af85cfc1a1eee54e9d35bf59f46c405455ec2904c77add4c662546efd14b8b89bc"} 07:03:11 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 07:03:11 INFO - Writing to file C:\slave\test\properties\blobber_files 07:03:11 INFO - Contents: 07:03:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4770d031aa25e1fcdf0a93b1b786b70d7749f357a8066cc30525fc7d5722945ecc72c248049a66d1f7daf0217cc59218832837a75a6f2bd2df346e0dbf35b7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/837ace1503dac1db3d63b40eda5f421e3bd0c917837f9f04f378da80b4890f3ad7de7ac0c80dc1de4f748f4bb05fb97eae85e8a94d9495eb0d180d25a8a78d75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a30bd4ef0f85c4be0f01f4f05beed6d0511ff480249cc869187c90cfa1d093af85cfc1a1eee54e9d35bf59f46c405455ec2904c77add4c662546efd14b8b89bc"} 07:03:11 INFO - Running post-run listener: copy_logs_to_upload_dir 07:03:11 INFO - Copying logs to upload dir... 07:03:11 INFO - mkdir: C:\slave\test\build\upload\logs 07:03:11 INFO - Copying logs to upload dir... 07:03:11 INFO - Using _rmtree_windows ... 07:03:11 INFO - Using _rmtree_windows ... 07:03:11 INFO - Using _rmtree_windows ... 07:03:11 INFO - Using _rmtree_windows ... 07:03:11 INFO - Using _rmtree_windows ... 07:03:11 INFO - Using _rmtree_windows ... 07:03:11 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1443.983000 ========= master_lag: 0.10 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 4 secs) (at 2016-10-19 07:03:11.100332) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 07:03:11.100961) ========= '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-105 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-105 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-105 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/b4770d031aa25e1fcdf0a93b1b786b70d7749f357a8066cc30525fc7d5722945ecc72c248049a66d1f7daf0217cc59218832837a75a6f2bd2df346e0dbf35b7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/837ace1503dac1db3d63b40eda5f421e3bd0c917837f9f04f378da80b4890f3ad7de7ac0c80dc1de4f748f4bb05fb97eae85e8a94d9495eb0d180d25a8a78d75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a30bd4ef0f85c4be0f01f4f05beed6d0511ff480249cc869187c90cfa1d093af85cfc1a1eee54e9d35bf59f46c405455ec2904c77add4c662546efd14b8b89bc"} build_url:https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/artifacts/public/build/firefox-52.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/AfTnqGCVQHGjGU-AqGRn0A/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/b4770d031aa25e1fcdf0a93b1b786b70d7749f357a8066cc30525fc7d5722945ecc72c248049a66d1f7daf0217cc59218832837a75a6f2bd2df346e0dbf35b7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/837ace1503dac1db3d63b40eda5f421e3bd0c917837f9f04f378da80b4890f3ad7de7ac0c80dc1de4f748f4bb05fb97eae85e8a94d9495eb0d180d25a8a78d75", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a30bd4ef0f85c4be0f01f4f05beed6d0511ff480249cc869187c90cfa1d093af85cfc1a1eee54e9d35bf59f46c405455ec2904c77add4c662546efd14b8b89bc"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 07:03:11.215056) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 07:03:11.215385) ========= '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-105 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-105 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-105 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 07:03:11.326333) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-19 07:03:11.326638) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-10-19 07:03:11.661520) ========= ========= Total master_lag: 0.32 =========