builder: autoland_win7_ix_test-dromaeojs slave: t-w732-ix-049 starttime: 1474898330.98 results: success (0) buildid: 20160926045950 builduid: ba048a48e0a34b00bf17855d1a306d64 revision: 3c4ca5845f2c12974cad7617efe5f12137603721 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 06:58:50.981130) ========= master: http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-26 06:58:50.981638) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 06:58:50.981928) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-26 06:58:51.137377) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-26 06:58:51.137715) ========= '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-049 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-049 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-049 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:58:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 28.72 MB/s 06:58:52 (28.72 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.257000 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-26 06:58:52.423758) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-09-26 06:58:52.424104) ========= '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-049 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-049 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-049 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=15.801000 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 15 secs) (at 2016-09-26 06:59:08.256973) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-26 06:59:08.257318) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev 3c4ca5845f2c12974cad7617efe5f12137603721 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 3c4ca5845f2c12974cad7617efe5f12137603721 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-049 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-049 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-049 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2016-09-26 06:59:09,229 truncating revision to first 12 chars 2016-09-26 06:59:09,229 Setting DEBUG logging. 2016-09-26 06:59:09,229 attempt 1/10 2016-09-26 06:59:09,229 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/3c4ca5845f2c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-26 06:59:09,796 unpacking tar archive at: autoland-3c4ca5845f2c/testing/mozharness/ 2016-09-26 06:59:09,938 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.395000 ========= master_lag: 0.57 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-26 06:59:10.223626) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 06:59:10.223980) ========= script_repo_revision: 3c4ca5845f2c12974cad7617efe5f12137603721 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-26 06:59:10.224370) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 06:59:10.224637) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-26 06:59:10.288696) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 42 secs) (at 2016-09-26 06:59:10.289335) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Autoland-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Autoland-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-049 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-049 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-049 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:59:11 INFO - MultiFileLogger online at 20160926 06:59:11 in C:\slave\test 06:59:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 06:59:11 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 06:59:11 INFO - {'append_to_log': False, 06:59:11 INFO - 'base_work_dir': 'C:\\slave\\test', 06:59:11 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 06:59:11 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 06:59:11 INFO - 'branch': 'Autoland-Non-PGO', 06:59:11 INFO - 'buildbot_json_path': 'buildprops.json', 06:59:11 INFO - 'config_files': ('talos/windows_config.py',), 06:59:11 INFO - 'default_actions': ('clobber', 06:59:11 INFO - 'read-buildbot-config', 06:59:11 INFO - 'download-and-extract', 06:59:11 INFO - 'populate-webroot', 06:59:11 INFO - 'create-virtualenv', 06:59:11 INFO - 'install', 06:59:11 INFO - 'run-tests'), 06:59:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:59:11 INFO - 'download_minidump_stackwalk': True, 06:59:11 INFO - 'download_symbols': 'ondemand', 06:59:11 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 06:59:11 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 06:59:11 INFO - 'hg': 'c:/mozilla-build/hg/hg', 06:59:11 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 06:59:11 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 06:59:11 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 06:59:11 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 06:59:11 INFO - 'C:/mozilla-build/tooltool.py'), 06:59:11 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 06:59:11 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 06:59:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:59:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:59:11 INFO - 'installer_path': 'installer.exe', 06:59:11 INFO - 'log_level': 'info', 06:59:11 INFO - 'log_name': 'talos', 06:59:11 INFO - 'log_to_console': True, 06:59:11 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 06:59:11 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 06:59:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 06:59:11 INFO - 'opt_config_files': (), 06:59:11 INFO - 'pip_index': False, 06:59:11 INFO - 'sps_profile': False, 06:59:11 INFO - 'sps_profile_interval': 0, 06:59:11 INFO - 'suite': 'dromaeojs', 06:59:11 INFO - 'system_bits': '32', 06:59:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:59:11 INFO - 'title': 'T-W732-IX-049', 06:59:11 INFO - 'use_talos_json': True, 06:59:11 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 06:59:11 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 06:59:11 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 06:59:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:59:11 INFO - 'work_dir': 'build'} 06:59:11 INFO - [mozharness: 2016-09-26 13:59:11.480000Z] Running clobber step. 06:59:11 INFO - Running pre-action listener: _resource_record_pre_action 06:59:11 INFO - Running main action method: clobber 06:59:11 INFO - rmtree: C:\slave\test\build 06:59:11 INFO - Using _rmtree_windows ... 06:59:11 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 07:00:45 INFO - Running post-action listener: _resource_record_post_action 07:00:45 INFO - [mozharness: 2016-09-26 14:00:45.187000Z] Finished clobber step (success) 07:00:45 INFO - [mozharness: 2016-09-26 14:00:45.187000Z] Running read-buildbot-config step. 07:00:45 INFO - Running pre-action listener: _resource_record_pre_action 07:00:45 INFO - Running main action method: read_buildbot_config 07:00:45 INFO - Using buildbot properties: 07:00:45 INFO - { 07:00:45 INFO - "project": "", 07:00:45 INFO - "product": "firefox", 07:00:45 INFO - "script_repo_revision": "production", 07:00:45 INFO - "builddir": "autoland_win7_ix_test-dromaeojs", 07:00:45 INFO - "repository": "", 07:00:45 INFO - "buildername": "Windows 7 32-bit autoland talos dromaeojs", 07:00:45 INFO - "buildid": "20160926045950", 07:00:45 INFO - "pgo_build": "False", 07:00:45 INFO - "basedir": "C:\\slave\\test", 07:00:45 INFO - "buildnumber": 220, 07:00:45 INFO - "slavename": "t-w732-ix-049", 07:00:45 INFO - "master": "http://buildbot-master126.bb.releng.scl3.mozilla.com:8201/", 07:00:45 INFO - "platform": "win7_ix", 07:00:45 INFO - "scheduler": "tests-autoland-win32-talos-8-3600", 07:00:45 INFO - "branch": "autoland", 07:00:45 INFO - "repo_path": "integration/autoland", 07:00:45 INFO - "revision": "3c4ca5845f2c12974cad7617efe5f12137603721", 07:00:45 INFO - "stage_platform": "win32", 07:00:45 INFO - "builduid": "ba048a48e0a34b00bf17855d1a306d64", 07:00:45 INFO - "slavebuilddir": "test" 07:00:45 INFO - } 07:00:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 07:00:45 INFO - Running post-action listener: _resource_record_post_action 07:00:45 INFO - [mozharness: 2016-09-26 14:00:45.191000Z] Finished read-buildbot-config step (success) 07:00:45 INFO - [mozharness: 2016-09-26 14:00:45.191000Z] Running download-and-extract step. 07:00:45 INFO - Running pre-action listener: _resource_record_pre_action 07:00:45 INFO - Running main action method: download_and_extract 07:00:45 INFO - mkdir: C:\slave\test\build\tests 07:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:00:45 INFO - trying https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 07:00:45 INFO - Downloading https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json to C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 07:00:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.test_packages.json'}, attempt #1 07:00:46 INFO - Downloaded 1274 bytes. 07:00:46 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 07:00:46 INFO - Using the following test package requirements: 07:00:46 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 07:00:46 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:00:46 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 07:00:46 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:00:46 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'], 07:00:46 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:00:46 INFO - u'jsshell-win32.zip'], 07:00:46 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:00:46 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 07:00:46 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 07:00:46 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:00:46 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 07:00:46 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:00:46 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 07:00:46 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:00:46 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 07:00:46 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 07:00:46 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 07:00:46 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 07:00:46 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 07:00:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 07:00:46 INFO - Fetch https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 07:00:47 INFO - Expected file size: 17320961 07:01:16 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip', u'firefox-52.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 07:01:16 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 07:01:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 07:01:16 INFO - Fetch https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 07:01:17 INFO - Expected file size: 17320961 07:01:31 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 07:01:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 07:01:31 INFO - Fetch https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 07:01:31 INFO - Expected file size: 14414057 07:01:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:01:39 INFO - trying https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 07:01:39 INFO - Downloading https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 07:01:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip'}, attempt #1 07:01:41 INFO - Downloaded 53517942 bytes. 07:01:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 07:01:41 INFO - mkdir: C:\slave\test\properties 07:01:41 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 07:01:41 INFO - Writing to file C:\slave\test\properties\build_url 07:01:41 INFO - Contents: 07:01:41 INFO - build_url:https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 07:01:42 INFO - Running post-action listener: _resource_record_post_action 07:01:42 INFO - Running post-action listener: set_extra_try_arguments 07:01:42 INFO - [mozharness: 2016-09-26 14:01:42.662000Z] Finished download-and-extract step (success) 07:01:42 INFO - [mozharness: 2016-09-26 14:01:42.662000Z] Running populate-webroot step. 07:01:42 INFO - Running pre-action listener: _resource_record_pre_action 07:01:42 INFO - Running main action method: populate_webroot 07:01:42 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 07:01:42 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 07:01:42 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 07:01:42 INFO - u'--tppagecycles', 07:01:42 INFO - u'7'], 07:01:42 INFO - u'tests': [u'tsvgm']}}, 07:01:42 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 07:01:42 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 07:01:42 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 07:01:42 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 07:01:42 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 07:01:42 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 07:01:42 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:42 INFO - u'talos_options': [u'--disable-e10s'], 07:01:42 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 07:01:42 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:42 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 07:01:42 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:42 INFO - u'talos_options': [u'--disable-e10s'], 07:01:42 INFO - u'tests': [u'damp', u'tps']}, 07:01:42 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:42 INFO - u'tests': [u'damp', u'tps']}, 07:01:42 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 07:01:42 INFO - u'tests': [u'dromaeo_dom']}, 07:01:42 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 07:01:42 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 07:01:42 INFO - u'tests': [u'basic_compositor_video']}, 07:01:42 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 07:01:42 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 07:01:42 INFO - u'tests': [u'a11yr', 07:01:42 INFO - u'ts_paint', 07:01:42 INFO - u'tpaint', 07:01:42 INFO - u'sessionrestore', 07:01:42 INFO - u'sessionrestore_no_auto_restore', 07:01:42 INFO - u'tabpaint']}, 07:01:42 INFO - u'other-e10s': {u'tests': [u'a11yr', 07:01:42 INFO - u'ts_paint', 07:01:42 INFO - u'tpaint', 07:01:42 INFO - u'sessionrestore', 07:01:42 INFO - u'sessionrestore_no_auto_restore', 07:01:42 INFO - u'tabpaint']}, 07:01:42 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 07:01:42 INFO - u'ts_paint', 07:01:42 INFO - u'tpaint', 07:01:42 INFO - u'sessionrestore', 07:01:42 INFO - u'sessionrestore_no_auto_restore', 07:01:42 INFO - u'tabpaint']}, 07:01:42 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 07:01:42 INFO - u'ts_paint', 07:01:42 INFO - u'tpaint', 07:01:42 INFO - u'sessionrestore', 07:01:42 INFO - u'sessionrestore_no_auto_restore', 07:01:42 INFO - u'tabpaint']}, 07:01:42 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 07:01:42 INFO - u'tests': [u'a11yr', 07:01:42 INFO - u'ts_paint', 07:01:42 INFO - u'tpaint', 07:01:42 INFO - u'sessionrestore', 07:01:42 INFO - u'sessionrestore_no_auto_restore', 07:01:42 INFO - u'tabpaint']}, 07:01:42 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 07:01:42 INFO - u'tests': [u'a11yr', 07:01:42 INFO - u'ts_paint', 07:01:42 INFO - u'tpaint', 07:01:42 INFO - u'sessionrestore', 07:01:42 INFO - u'sessionrestore_no_auto_restore', 07:01:42 INFO - u'tabpaint']}, 07:01:42 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 07:01:42 INFO - u'tests': [u'tsvgx', 07:01:42 INFO - u'tsvgr_opacity', 07:01:42 INFO - u'tart', 07:01:42 INFO - u'tscrollx', 07:01:42 INFO - u'cart']}, 07:01:42 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 07:01:42 INFO - u'tsvgr_opacity', 07:01:42 INFO - u'tart', 07:01:42 INFO - u'tscrollx', 07:01:42 INFO - u'cart']}, 07:01:42 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:42 INFO - u'talos_options': [u'--disable-e10s'], 07:01:42 INFO - u'tests': [u'tp5o']}, 07:01:42 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:42 INFO - u'tests': [u'tp5o']}, 07:01:42 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:42 INFO - u'talos_options': [u'--disable-e10s', 07:01:42 INFO - u'--xperf_path', 07:01:42 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:01:42 INFO - u'tests': [u'tp5n']}, 07:01:42 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:01:42 INFO - u'talos_options': [u'--xperf_path', 07:01:42 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:01:42 INFO - u'tests': [u'tp5n']}}, 07:01:42 INFO - u'talos.zip': {u'path': u'', 07:01:42 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:01:42 INFO - Running post-action listener: _resource_record_post_action 07:01:42 INFO - [mozharness: 2016-09-26 14:01:42.676000Z] Finished populate-webroot step (success) 07:01:42 INFO - [mozharness: 2016-09-26 14:01:42.676000Z] Running create-virtualenv step. 07:01:42 INFO - Running pre-action listener: _resource_record_pre_action 07:01:42 INFO - Running main action method: create_virtualenv 07:01:42 INFO - Creating virtualenv C:\slave\test\build/venv 07:01:42 INFO - mkdir: C:\slave\test\build\venv\Scripts 07:01:43 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 07:01:43 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 07:01:43 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build 07:01:43 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 07:01:58 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 07:02:02 INFO - Installing distribute......................................................................................................................................................................................done. 07:02:04 INFO - Return code: 0 07:02:04 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 07:02:04 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 07:02:04 INFO - Reading from file tmpfile_stdout 07:02:04 INFO - Output received: 07:02:04 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 07:02:04 INFO - Using _rmtree_windows ... 07:02:04 INFO - Using _rmtree_windows ... 07:02:04 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 07:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:04 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FAE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-049', 'USERDOMAIN': 'T-W732-IX-049', '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-049', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:02:04 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 07:02:04 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:02:04 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:02:04 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:02:04 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:02:04 INFO - 'COMPUTERNAME': 'T-W732-IX-049', 07:02:04 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:02:04 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:02:04 INFO - 'DCLOCATION': 'SCL3', 07:02:04 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:02:04 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:02:04 INFO - 'HOMEDRIVE': 'C:', 07:02:04 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:02:04 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:02:04 INFO - 'KTS_VERSION': '1.19c', 07:02:04 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:02:04 INFO - 'LOGONSERVER': '\\\\T-W732-IX-049', 07:02:04 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:02:04 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:04 INFO - 'MOZ_NO_REMOTE': '1', 07:02:04 INFO - 'NO_EM_RESTART': '1', 07:02:04 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:02:04 INFO - 'OS': 'Windows_NT', 07:02:04 INFO - 'OURDRIVE': 'C:', 07:02:04 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:02:04 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:02:04 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:02:04 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:02:04 INFO - 'PROCESSOR_LEVEL': '6', 07:02:04 INFO - 'PROCESSOR_REVISION': '1e05', 07:02:04 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:02:04 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:02:04 INFO - 'PROMPT': '$P$G', 07:02:04 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:02:04 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:02:04 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:02:04 INFO - 'PWD': 'C:\\slave\\test', 07:02:04 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:02:04 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:02:04 INFO - 'SYSTEMDRIVE': 'C:', 07:02:04 INFO - 'SYSTEMROOT': 'C:\\windows', 07:02:04 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:04 INFO - 'TEST1': 'testie', 07:02:04 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:04 INFO - 'USERDOMAIN': 'T-W732-IX-049', 07:02:04 INFO - 'USERNAME': 'cltbld', 07:02:04 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:02:04 INFO - 'WINDIR': 'C:\\windows', 07:02:04 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:02:04 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:02:05 INFO - Ignoring indexes: http://pypi.python.org/simple/ 07:02:05 INFO - Downloading/unpacking pip>=1.5 07:02:09 INFO - Running setup.py egg_info for package pip 07:02:09 INFO - warning: no files found matching 'pip\cacert.pem' 07:02:09 INFO - warning: no files found matching '*.html' under directory 'docs' 07:02:09 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 07:02:09 INFO - no previously-included directories found matching 'docs\_build\_sources' 07:02:09 INFO - Installing collected packages: pip 07:02:09 INFO - Found existing installation: pip 0.8.2 07:02:09 INFO - Uninstalling pip: 07:02:09 INFO - Successfully uninstalled pip 07:02:09 INFO - Running setup.py install for pip 07:02:09 INFO - warning: no files found matching 'pip\cacert.pem' 07:02:09 INFO - warning: no files found matching '*.html' under directory 'docs' 07:02:09 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 07:02:09 INFO - no previously-included directories found matching 'docs\_build\_sources' 07:02:09 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 07:02:09 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 07:02:09 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 07:02:09 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 07:02:09 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 07:02:09 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 07:02:09 INFO - Successfully installed pip 07:02:09 INFO - Cleaning up... 07:02:09 INFO - Return code: 0 07:02:09 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 07:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:09 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FAE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-049', 'USERDOMAIN': 'T-W732-IX-049', '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-049', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:02:09 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 07:02:09 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:02:09 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:02:09 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:02:09 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:02:09 INFO - 'COMPUTERNAME': 'T-W732-IX-049', 07:02:09 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:02:09 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:02:09 INFO - 'DCLOCATION': 'SCL3', 07:02:09 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:02:09 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:02:09 INFO - 'HOMEDRIVE': 'C:', 07:02:09 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:02:09 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:02:09 INFO - 'KTS_VERSION': '1.19c', 07:02:09 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:02:09 INFO - 'LOGONSERVER': '\\\\T-W732-IX-049', 07:02:09 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:02:09 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:09 INFO - 'MOZ_NO_REMOTE': '1', 07:02:09 INFO - 'NO_EM_RESTART': '1', 07:02:09 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:02:09 INFO - 'OS': 'Windows_NT', 07:02:09 INFO - 'OURDRIVE': 'C:', 07:02:09 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:02:09 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:02:09 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:02:09 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:02:09 INFO - 'PROCESSOR_LEVEL': '6', 07:02:09 INFO - 'PROCESSOR_REVISION': '1e05', 07:02:09 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:02:09 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:02:09 INFO - 'PROMPT': '$P$G', 07:02:09 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:02:09 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:02:09 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:02:09 INFO - 'PWD': 'C:\\slave\\test', 07:02:09 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:02:09 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:02:09 INFO - 'SYSTEMDRIVE': 'C:', 07:02:09 INFO - 'SYSTEMROOT': 'C:\\windows', 07:02:09 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:09 INFO - 'TEST1': 'testie', 07:02:09 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:09 INFO - 'USERDOMAIN': 'T-W732-IX-049', 07:02:09 INFO - 'USERNAME': 'cltbld', 07:02:09 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:02:09 INFO - 'WINDIR': 'C:\\windows', 07:02:09 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:02:09 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:02:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:12 INFO - Downloading/unpacking psutil>=3.1.1 07:02:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:13 INFO - Installing collected packages: psutil 07:02:13 INFO - Successfully installed psutil 07:02:13 INFO - Cleaning up... 07:02:14 INFO - Return code: 0 07:02:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 07:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:14 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FAE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-049', 'USERDOMAIN': 'T-W732-IX-049', '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-049', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:02:14 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in C:\slave\test\build 07:02:14 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:02:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:02:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:02:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:02:14 INFO - 'COMPUTERNAME': 'T-W732-IX-049', 07:02:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:02:14 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:02:14 INFO - 'DCLOCATION': 'SCL3', 07:02:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:02:14 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:02:14 INFO - 'HOMEDRIVE': 'C:', 07:02:14 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:02:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:02:14 INFO - 'KTS_VERSION': '1.19c', 07:02:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:02:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-049', 07:02:14 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:02:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:02:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:14 INFO - 'MOZ_NO_REMOTE': '1', 07:02:14 INFO - 'NO_EM_RESTART': '1', 07:02:14 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:02:14 INFO - 'OS': 'Windows_NT', 07:02:14 INFO - 'OURDRIVE': 'C:', 07:02:14 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:02:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:02:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:02:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:02:14 INFO - 'PROCESSOR_LEVEL': '6', 07:02:14 INFO - 'PROCESSOR_REVISION': '1e05', 07:02:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:02:14 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:02:14 INFO - 'PROMPT': '$P$G', 07:02:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:02:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:02:14 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:02:14 INFO - 'PWD': 'C:\\slave\\test', 07:02:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:02:14 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:02:14 INFO - 'SYSTEMDRIVE': 'C:', 07:02:14 INFO - 'SYSTEMROOT': 'C:\\windows', 07:02:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:14 INFO - 'TEST1': 'testie', 07:02:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:14 INFO - 'USERDOMAIN': 'T-W732-IX-049', 07:02:14 INFO - 'USERNAME': 'cltbld', 07:02:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:02:14 INFO - 'WINDIR': 'C:\\windows', 07:02:14 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:02:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:02:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:02:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:02:15 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 07:02:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.3) 07:02:16 INFO - Installing collected packages: mozsystemmonitor 07:02:16 INFO - Running setup.py install for mozsystemmonitor 07:02:16 INFO - Successfully installed mozsystemmonitor 07:02:16 INFO - Cleaning up... 07:02:16 INFO - Return code: 0 07:02:16 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 07:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:16 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FAE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-049', 'USERDOMAIN': 'T-W732-IX-049', '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-049', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:02:16 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in C:\slave\test\build 07:02:16 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:02:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:02:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:02:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:02:16 INFO - 'COMPUTERNAME': 'T-W732-IX-049', 07:02:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:02:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:02:16 INFO - 'DCLOCATION': 'SCL3', 07:02:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:02:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:02:16 INFO - 'HOMEDRIVE': 'C:', 07:02:16 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:02:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:02:16 INFO - 'KTS_VERSION': '1.19c', 07:02:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:02:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-049', 07:02:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:02:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:16 INFO - 'MOZ_NO_REMOTE': '1', 07:02:16 INFO - 'NO_EM_RESTART': '1', 07:02:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:02:16 INFO - 'OS': 'Windows_NT', 07:02:16 INFO - 'OURDRIVE': 'C:', 07:02:16 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:02:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:02:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:02:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:02:16 INFO - 'PROCESSOR_LEVEL': '6', 07:02:16 INFO - 'PROCESSOR_REVISION': '1e05', 07:02:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:02:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:02:16 INFO - 'PROMPT': '$P$G', 07:02:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:02:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:02:16 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:02:16 INFO - 'PWD': 'C:\\slave\\test', 07:02:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:02:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:02:16 INFO - 'SYSTEMDRIVE': 'C:', 07:02:16 INFO - 'SYSTEMROOT': 'C:\\windows', 07:02:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:16 INFO - 'TEST1': 'testie', 07:02:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:16 INFO - 'USERDOMAIN': 'T-W732-IX-049', 07:02:16 INFO - 'USERNAME': 'cltbld', 07:02:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:02:16 INFO - 'WINDIR': 'C:\\windows', 07:02:16 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:02:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:16 INFO - Downloading/unpacking jsonschema==2.5.1 07:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:02:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:02:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:18 INFO - Downloading functools32-3.2.3-2.tar.gz 07:02:18 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 07:02:18 INFO - warning: no files found matching '*.txt' 07:02:18 INFO - no previously-included directories found matching 'build' 07:02:18 INFO - no previously-included directories found matching 'dist' 07:02:18 INFO - no previously-included directories found matching '.git*' 07:02:18 INFO - Installing collected packages: jsonschema, functools32 07:02:18 INFO - Running setup.py install for functools32 07:02:18 INFO - warning: no files found matching '*.txt' 07:02:18 INFO - no previously-included directories found matching 'build' 07:02:18 INFO - no previously-included directories found matching 'dist' 07:02:18 INFO - no previously-included directories found matching '.git*' 07:02:18 INFO - Successfully installed jsonschema functools32 07:02:18 INFO - Cleaning up... 07:02:19 INFO - Return code: 0 07:02:19 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 07:02:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:19 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FAE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-049', 'USERDOMAIN': 'T-W732-IX-049', '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-049', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:02:19 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build 07:02:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:02:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:02:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:02:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:02:19 INFO - 'COMPUTERNAME': 'T-W732-IX-049', 07:02:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:02:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:02:19 INFO - 'DCLOCATION': 'SCL3', 07:02:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:02:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:02:19 INFO - 'HOMEDRIVE': 'C:', 07:02:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:02:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:02:19 INFO - 'KTS_VERSION': '1.19c', 07:02:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:02:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-049', 07:02:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:02:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:19 INFO - 'MOZ_NO_REMOTE': '1', 07:02:19 INFO - 'NO_EM_RESTART': '1', 07:02:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:02:19 INFO - 'OS': 'Windows_NT', 07:02:19 INFO - 'OURDRIVE': 'C:', 07:02:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:02:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:02:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:02:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:02:19 INFO - 'PROCESSOR_LEVEL': '6', 07:02:19 INFO - 'PROCESSOR_REVISION': '1e05', 07:02:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:02:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:02:19 INFO - 'PROMPT': '$P$G', 07:02:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:02:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:02:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:02:19 INFO - 'PWD': 'C:\\slave\\test', 07:02:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:02:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:02:19 INFO - 'SYSTEMDRIVE': 'C:', 07:02:19 INFO - 'SYSTEMROOT': 'C:\\windows', 07:02:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:19 INFO - 'TEST1': 'testie', 07:02:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:19 INFO - 'USERDOMAIN': 'T-W732-IX-049', 07:02:19 INFO - 'USERNAME': 'cltbld', 07:02:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:02:19 INFO - 'WINDIR': 'C:\\windows', 07:02:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:02:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 07:02:19 INFO - Cleaning up... 07:02:19 INFO - Return code: 0 07:02:19 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 07:02:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:19 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FAE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-049', 'USERDOMAIN': 'T-W732-IX-049', '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-049', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:02:19 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build 07:02:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:02:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:02:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:02:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:02:19 INFO - 'COMPUTERNAME': 'T-W732-IX-049', 07:02:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:02:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:02:19 INFO - 'DCLOCATION': 'SCL3', 07:02:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:02:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:02:19 INFO - 'HOMEDRIVE': 'C:', 07:02:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:02:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:02:19 INFO - 'KTS_VERSION': '1.19c', 07:02:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:02:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-049', 07:02:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:02:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:19 INFO - 'MOZ_NO_REMOTE': '1', 07:02:19 INFO - 'NO_EM_RESTART': '1', 07:02:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:02:19 INFO - 'OS': 'Windows_NT', 07:02:19 INFO - 'OURDRIVE': 'C:', 07:02:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:02:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:02:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:02:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:02:19 INFO - 'PROCESSOR_LEVEL': '6', 07:02:19 INFO - 'PROCESSOR_REVISION': '1e05', 07:02:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:02:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:02:19 INFO - 'PROMPT': '$P$G', 07:02:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:02:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:02:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:02:19 INFO - 'PWD': 'C:\\slave\\test', 07:02:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:02:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:02:19 INFO - 'SYSTEMDRIVE': 'C:', 07:02:19 INFO - 'SYSTEMROOT': 'C:\\windows', 07:02:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:19 INFO - 'TEST1': 'testie', 07:02:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:19 INFO - 'USERDOMAIN': 'T-W732-IX-049', 07:02:19 INFO - 'USERNAME': 'cltbld', 07:02:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:02:19 INFO - 'WINDIR': 'C:\\windows', 07:02:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:02:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:19 INFO - Downloading/unpacking blobuploader==1.2.4 07:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:20 INFO - Downloading blobuploader-1.2.4.tar.gz 07:02:20 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 07:02:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:21 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 07:02:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:21 INFO - Downloading docopt-0.6.1.tar.gz 07:02:21 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 07:02:21 INFO - Installing collected packages: blobuploader, requests, docopt 07:02:21 INFO - Running setup.py install for blobuploader 07:02:22 INFO - Running setup.py install for requests 07:02:22 INFO - Running setup.py install for docopt 07:02:22 INFO - Successfully installed blobuploader requests docopt 07:02:22 INFO - Cleaning up... 07:02:24 INFO - Return code: 0 07:02:24 INFO - Installing None into virtualenv C:\slave\test\build/venv 07:02:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:24 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FAE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-049', 'USERDOMAIN': 'T-W732-IX-049', '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-049', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:02:24 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 07:02:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:02:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:02:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:02:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:02:24 INFO - 'COMPUTERNAME': 'T-W732-IX-049', 07:02:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:02:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:02:24 INFO - 'DCLOCATION': 'SCL3', 07:02:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:02:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:02:24 INFO - 'HOMEDRIVE': 'C:', 07:02:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:02:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:02:24 INFO - 'KTS_VERSION': '1.19c', 07:02:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:02:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-049', 07:02:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:02:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:24 INFO - 'MOZ_NO_REMOTE': '1', 07:02:24 INFO - 'NO_EM_RESTART': '1', 07:02:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:02:24 INFO - 'OS': 'Windows_NT', 07:02:24 INFO - 'OURDRIVE': 'C:', 07:02:24 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:02:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:02:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:02:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:02:24 INFO - 'PROCESSOR_LEVEL': '6', 07:02:24 INFO - 'PROCESSOR_REVISION': '1e05', 07:02:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:02:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:02:24 INFO - 'PROMPT': '$P$G', 07:02:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:02:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:02:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:02:24 INFO - 'PWD': 'C:\\slave\\test', 07:02:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:02:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:02:24 INFO - 'SYSTEMDRIVE': 'C:', 07:02:24 INFO - 'SYSTEMROOT': 'C:\\windows', 07:02:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:24 INFO - 'TEST1': 'testie', 07:02:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:24 INFO - 'USERDOMAIN': 'T-W732-IX-049', 07:02:24 INFO - 'USERNAME': 'cltbld', 07:02:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:02:24 INFO - 'WINDIR': 'C:\\windows', 07:02:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:02:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:02:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 07:02:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uum5df-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 07:02:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 07:02:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qpqhcy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 07:02:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 07:02:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5vzhnu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 07:02:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 07:02:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ilrtkx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 07:02:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 07:02:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6hzv8x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 07:02:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 07:02:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lkme_s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 07:02:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 07:02:25 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xufbjm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 07:02:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 07:02:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-svlgc_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 07:02:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 07:02:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-q9rlr5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 07:02:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 07:02:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fxyuql-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 07:02:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 07:02:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gipr84-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 07:02:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 07:02:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u2m49i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 07:02:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 07:02:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_ndb5f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 07:02:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 07:02:26 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hhlony-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 07:02:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 07:02:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cts4id-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 07:02:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 07:02:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pdqjic-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 07:02:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 07:02:27 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xrjb5s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 07:02:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:02:27 INFO - Running setup.py install for manifestparser 07:02:27 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 07:02:27 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 07:02:27 INFO - Running setup.py install for mozcrash 07:02:28 INFO - Running setup.py install for mozdebug 07:02:28 INFO - Running setup.py install for mozdevice 07:02:28 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 07:02:28 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 07:02:28 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 07:02:28 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 07:02:29 INFO - Running setup.py install for mozfile 07:02:29 INFO - Running setup.py install for mozhttpd 07:02:29 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 07:02:29 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 07:02:30 INFO - Running setup.py install for mozinfo 07:02:30 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 07:02:30 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 07:02:30 INFO - Running setup.py install for mozInstall 07:02:30 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 07:02:30 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 07:02:30 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 07:02:30 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 07:02:30 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 07:02:30 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 07:02:30 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 07:02:30 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 07:02:30 INFO - Running setup.py install for mozleak 07:02:31 INFO - Running setup.py install for mozlog 07:02:31 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 07:02:31 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 07:02:32 INFO - Running setup.py install for moznetwork 07:02:32 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 07:02:32 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 07:02:32 INFO - Running setup.py install for mozprocess 07:02:32 INFO - Running setup.py install for mozprofile 07:02:33 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 07:02:33 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 07:02:33 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 07:02:33 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 07:02:33 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 07:02:33 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 07:02:33 INFO - Running setup.py install for mozrunner 07:02:33 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 07:02:33 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 07:02:34 INFO - Running setup.py install for mozscreenshot 07:02:34 INFO - Running setup.py install for moztest 07:02:35 INFO - Running setup.py install for mozversion 07:02:35 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 07:02:35 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 07:02:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:02:35 INFO - Cleaning up... 07:02:35 INFO - Return code: 0 07:02:35 INFO - Installing None into virtualenv C:\slave\test\build/venv 07:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:35 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FAE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-049', 'USERDOMAIN': 'T-W732-IX-049', '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-049', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:02:35 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 07:02:35 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:02:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:02:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:02:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:02:35 INFO - 'COMPUTERNAME': 'T-W732-IX-049', 07:02:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:02:35 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:02:35 INFO - 'DCLOCATION': 'SCL3', 07:02:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:02:35 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:02:35 INFO - 'HOMEDRIVE': 'C:', 07:02:35 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:02:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:02:35 INFO - 'KTS_VERSION': '1.19c', 07:02:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:02:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-049', 07:02:35 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:02:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:35 INFO - 'MOZ_NO_REMOTE': '1', 07:02:35 INFO - 'NO_EM_RESTART': '1', 07:02:35 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:02:35 INFO - 'OS': 'Windows_NT', 07:02:35 INFO - 'OURDRIVE': 'C:', 07:02:35 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:02:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:02:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:02:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:02:35 INFO - 'PROCESSOR_LEVEL': '6', 07:02:35 INFO - 'PROCESSOR_REVISION': '1e05', 07:02:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:02:35 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:02:35 INFO - 'PROMPT': '$P$G', 07:02:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:02:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:02:35 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:02:35 INFO - 'PWD': 'C:\\slave\\test', 07:02:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:02:35 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:02:35 INFO - 'SYSTEMDRIVE': 'C:', 07:02:35 INFO - 'SYSTEMROOT': 'C:\\windows', 07:02:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:35 INFO - 'TEST1': 'testie', 07:02:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:35 INFO - 'USERDOMAIN': 'T-W732-IX-049', 07:02:35 INFO - 'USERNAME': 'cltbld', 07:02:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:02:35 INFO - 'WINDIR': 'C:\\windows', 07:02:35 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:02:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 07:02:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7krxeb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 07:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1)) 07:02:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 07:02:35 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cjxim8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 07:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 07:02:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 07:02:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tg4fsq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 07:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 07:02:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 07:02:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aufch0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 07:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 07:02:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 07:02:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-toydmc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 07:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5)) 07:02:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 07:02:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oz4qrn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 07:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6)) 07:02:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 07:02:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6n2i1m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 07:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7)) 07:02:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 07:02:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3pyo0u-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 07:02:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8)) 07:02:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 07:02:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8ep6_w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 07:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9)) 07:02:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 07:02:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v2i3kg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 07:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 07:02:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 07:02:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dcgahs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 07:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11)) 07:02:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 07:02:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a7p9we-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 07:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12)) 07:02:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 07:02:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8l_zay-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 07:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13)) 07:02:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 07:02:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-79dyfv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 07:02:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 07:02:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 07:02:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wjv6ru-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 07:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15)) 07:02:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 07:02:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mnfxyw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 07:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16)) 07:02:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 07:02:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wh_9l5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 07:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17)) 07:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 07:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.17->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 07:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 07:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 07:02:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.48->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 07:02:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 07:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:02:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:02:39 INFO - Downloading blessings-1.6.tar.gz 07:02:39 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 07:02:39 INFO - Installing collected packages: blessings 07:02:39 INFO - Running setup.py install for blessings 07:02:40 INFO - Successfully installed blessings 07:02:40 INFO - Cleaning up... 07:02:42 INFO - Return code: 0 07:02:42 INFO - Done creating virtualenv C:\slave\test\build/venv. 07:02:42 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 07:02:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 07:02:43 INFO - Reading from file tmpfile_stdout 07:02:43 INFO - Using _rmtree_windows ... 07:02:43 INFO - Using _rmtree_windows ... 07:02:43 INFO - Current package versions: 07:02:43 INFO - blessings == 1.6 07:02:43 INFO - blobuploader == 1.2.4 07:02:43 INFO - docopt == 0.6.1 07:02:43 INFO - functools32 == 3.2.3-2 07:02:43 INFO - jsonschema == 2.5.1 07:02:43 INFO - manifestparser == 1.1 07:02:43 INFO - mozInstall == 1.12 07:02:43 INFO - mozcrash == 0.17 07:02:43 INFO - mozdebug == 0.1 07:02:43 INFO - mozdevice == 0.48 07:02:43 INFO - mozfile == 1.2 07:02:43 INFO - mozhttpd == 0.7 07:02:43 INFO - mozinfo == 0.9 07:02:43 INFO - mozleak == 0.1 07:02:43 INFO - mozlog == 3.4 07:02:43 INFO - moznetwork == 0.27 07:02:43 INFO - mozprocess == 0.23 07:02:43 INFO - mozprofile == 0.28 07:02:43 INFO - mozrunner == 6.12 07:02:43 INFO - mozscreenshot == 0.1 07:02:43 INFO - mozsystemmonitor == 0.3 07:02:43 INFO - moztest == 0.7 07:02:43 INFO - mozversion == 1.4 07:02:43 INFO - psutil == 3.1.1 07:02:43 INFO - requests == 1.2.3 07:02:43 INFO - Installing None into virtualenv C:\slave\test\build/venv 07:02: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')]} 07:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02: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')]} 07:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02:43 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 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FAE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-049', 'USERDOMAIN': 'T-W732-IX-049', '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-049', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:02:43 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos 07:02:43 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:02:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:02:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:02:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:02:43 INFO - 'COMPUTERNAME': 'T-W732-IX-049', 07:02:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:02:43 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:02:43 INFO - 'DCLOCATION': 'SCL3', 07:02:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:02:43 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:02:43 INFO - 'HOMEDRIVE': 'C:', 07:02:43 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:02:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:02:43 INFO - 'KTS_VERSION': '1.19c', 07:02:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:02:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-049', 07:02:43 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:02:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:43 INFO - 'MOZ_NO_REMOTE': '1', 07:02:43 INFO - 'NO_EM_RESTART': '1', 07:02:43 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:02:43 INFO - 'OS': 'Windows_NT', 07:02:43 INFO - 'OURDRIVE': 'C:', 07:02: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;', 07:02:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:02:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:02:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:02:43 INFO - 'PROCESSOR_LEVEL': '6', 07:02:43 INFO - 'PROCESSOR_REVISION': '1e05', 07:02:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:02:43 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:02:43 INFO - 'PROMPT': '$P$G', 07:02:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:02:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:02:43 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:02:43 INFO - 'PWD': 'C:\\slave\\test', 07:02:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:02:43 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:02:43 INFO - 'SYSTEMDRIVE': 'C:', 07:02:43 INFO - 'SYSTEMROOT': 'C:\\windows', 07:02:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:43 INFO - 'TEST1': 'testie', 07:02:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:43 INFO - 'USERDOMAIN': 'T-W732-IX-049', 07:02:43 INFO - 'USERNAME': 'cltbld', 07:02:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:02:43 INFO - 'WINDIR': 'C:\\windows', 07:02:43 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:02:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 07:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2)) 07:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3)) 07:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 07:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5)) 07:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6)) 07:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7)) 07:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8)) 07:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 9)) 07:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.1->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 07:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 07:02:43 INFO - Cleaning up... 07:02:43 INFO - Return code: 0 07:02:43 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 07:02: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')]} 07:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:02: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')]} 07:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:02: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018A0640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01947E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0184B228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01954E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FAE520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-049', 'USERDOMAIN': 'T-W732-IX-049', '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-049', 'MOZ_NO_REMOTE': '1', 'WINDOWS_TRACING_FLAGS': '3', 'CYGWINBASE': 'C:\\cygwin', 'PROGRAMDATA': 'C:\\ProgramData', 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 'FP_NO_HOST_CHECK': 'NO', 'DCLOCATION': 'SCL3', 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 07:02: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'] in C:\slave\test\build 07:02: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 07:02:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:02:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:02:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:02:43 INFO - 'COMPUTERNAME': 'T-W732-IX-049', 07:02:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:02:43 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:02:43 INFO - 'DCLOCATION': 'SCL3', 07:02:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:02:43 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:02:43 INFO - 'HOMEDRIVE': 'C:', 07:02:43 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:02:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:02:43 INFO - 'KTS_VERSION': '1.19c', 07:02:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:02:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-049', 07:02:43 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:02:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:43 INFO - 'MOZ_NO_REMOTE': '1', 07:02:43 INFO - 'NO_EM_RESTART': '1', 07:02:43 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:02:43 INFO - 'OS': 'Windows_NT', 07:02:43 INFO - 'OURDRIVE': 'C:', 07:02: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;', 07:02:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:02:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:02:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:02:43 INFO - 'PROCESSOR_LEVEL': '6', 07:02:43 INFO - 'PROCESSOR_REVISION': '1e05', 07:02:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:02:43 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:02:43 INFO - 'PROMPT': '$P$G', 07:02:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:02:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:02:43 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:02:43 INFO - 'PWD': 'C:\\slave\\test', 07:02:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:02:43 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:02:43 INFO - 'SYSTEMDRIVE': 'C:', 07:02:43 INFO - 'SYSTEMROOT': 'C:\\windows', 07:02:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:43 INFO - 'TEST1': 'testie', 07:02:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:43 INFO - 'USERDOMAIN': 'T-W732-IX-049', 07:02:43 INFO - 'USERNAME': 'cltbld', 07:02:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:02:43 INFO - 'WINDIR': 'C:\\windows', 07:02:43 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:02:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:02:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:02:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 07:02:44 INFO - Cleaning up... 07:02:44 INFO - Return code: 0 07:02:44 INFO - Running post-action listener: _resource_record_post_action 07:02:44 INFO - Running post-action listener: _start_resource_monitoring 07:02:44 INFO - Starting resource monitoring. 07:02:44 INFO - [mozharness: 2016-09-26 14:02:44.222000Z] Finished create-virtualenv step (success) 07:02:44 INFO - [mozharness: 2016-09-26 14:02:44.222000Z] Running install step. 07:02:44 INFO - Running pre-action listener: _resource_record_pre_action 07:02:44 INFO - Running main action method: install 07:02:44 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 07:02:44 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 07:02:44 INFO - Reading from file tmpfile_stdout 07:02:44 INFO - Using _rmtree_windows ... 07:02:44 INFO - Using _rmtree_windows ... 07:02:44 INFO - Detecting whether we're running mozinstall >=1.0... 07:02:44 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 07:02:44 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 07:02:44 INFO - Reading from file tmpfile_stdout 07:02:44 INFO - Output received: 07:02:44 INFO - Usage: mozinstall-script.py [options] installer 07:02:44 INFO - Options: 07:02:44 INFO - -h, --help show this help message and exit 07:02:44 INFO - -d DEST, --destination=DEST 07:02:44 INFO - Directory to install application into. [default: 07:02:44 INFO - "C:\slave\test"] 07:02:44 INFO - --app=APP Application being installed. [default: firefox] 07:02:44 INFO - Using _rmtree_windows ... 07:02:44 INFO - Using _rmtree_windows ... 07:02:44 INFO - mkdir: C:\slave\test\build\application 07:02:44 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-52.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 07:02:44 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-52.0a1.en-US.win32.zip --destination C:\slave\test\build\application 07:02:46 INFO - Reading from file tmpfile_stdout 07:02:46 INFO - Output received: 07:02:46 INFO - C:\slave\test\build\application\firefox\firefox.exe 07:02:46 INFO - Using _rmtree_windows ... 07:02:46 INFO - Using _rmtree_windows ... 07:02:46 INFO - Running post-action listener: _resource_record_post_action 07:02:46 INFO - [mozharness: 2016-09-26 14:02:46.831000Z] Finished install step (success) 07:02:46 INFO - [mozharness: 2016-09-26 14:02:46.832000Z] Running run-tests step. 07:02:46 INFO - Running pre-action listener: _resource_record_pre_action 07:02:46 INFO - Running main action method: run_tests 07:02:46 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 07:02:46 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 07:02:46 INFO - Python 2.7.3 07:02:46 INFO - Return code: 0 07:02:46 INFO - grabbing minidump binary from tooltool 07:02: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')]} 07:02:46 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 0x01954E90>, '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 0x012DA910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01FAE520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 07:02:46 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build 07:02:46 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o 07:02:46 INFO - Calling ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] with output_timeout 600 07:02:46 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 07:02:49 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpoffyss 07:02:49 INFO - INFO - File integrity verified, renaming tmpoffyss to win32-minidump_stackwalk.exe 07:02:49 INFO - Return code: 0 07:02:49 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 07:02:49 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 07:02:49 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 07:02:49 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 07:02:49 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 07:02:49 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-049', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] in C:\slave\test\build 07:02:49 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-049 --webServer localhost --log-tbpl-level=debug --log-errorsummary=C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log --log-raw=C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log 07:02:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 07:02:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 07:02:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 07:02:49 INFO - 'COMPUTERNAME': 'T-W732-IX-049', 07:02:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 07:02:49 INFO - 'CYGWINBASE': 'C:\\cygwin', 07:02:49 INFO - 'DCLOCATION': 'SCL3', 07:02:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 07:02:49 INFO - 'FP_NO_HOST_CHECK': 'NO', 07:02:49 INFO - 'HOMEDRIVE': 'C:', 07:02:49 INFO - 'HOMEPATH': '\\Users\\cltbld', 07:02:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 07:02:49 INFO - 'KTS_VERSION': '1.19c', 07:02:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 07:02:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-049', 07:02:49 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 07:02:49 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 07:02:49 INFO - 'MONDIR': 'C:\\Monitor_config\\', 07:02:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 07:02:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:02:49 INFO - 'MOZ_NO_REMOTE': '1', 07:02:49 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 07:02:49 INFO - 'NO_EM_RESTART': '1', 07:02:49 INFO - 'NUMBER_OF_PROCESSORS': '8', 07:02:49 INFO - 'OS': 'Windows_NT', 07:02:49 INFO - 'OURDRIVE': 'C:', 07:02:49 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 07:02:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 07:02:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 07:02:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 07:02:49 INFO - 'PROCESSOR_LEVEL': '6', 07:02:49 INFO - 'PROCESSOR_REVISION': '1e05', 07:02:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 07:02:49 INFO - 'PROGRAMFILES': 'C:\\Program Files', 07:02:49 INFO - 'PROMPT': '$P$G', 07:02:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 07:02:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 07:02:49 INFO - 'PUBLIC': 'C:\\Users\\Public', 07:02:49 INFO - 'PWD': 'C:\\slave\\test', 07:02:49 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 07:02:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 07:02:49 INFO - 'SLAVEDIR': 'C:\\slave\\', 07:02:49 INFO - 'SYSTEMDRIVE': 'C:', 07:02:49 INFO - 'SYSTEMROOT': 'C:\\windows', 07:02:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:49 INFO - 'TEST1': 'testie', 07:02:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 07:02:49 INFO - 'USERDOMAIN': 'T-W732-IX-049', 07:02:49 INFO - 'USERNAME': 'cltbld', 07:02:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 07:02:49 INFO - 'WINDIR': 'C:\\windows', 07:02:49 INFO - 'WINDOWS_TRACING_FLAGS': '3', 07:02:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 07:02:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 07:02:49 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-049', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_errorsummary.log', '--log-raw=C:\\slave\\test\\build\\blobber_upload_dir\\dromaeojs_raw.log'] with output_timeout 3600 07:02:50 INFO - mozversion application_buildid: 20160926045950 07:02:50 INFO - mozversion application_changeset: 3c4ca5845f2c12974cad7617efe5f12137603721 07:02:50 INFO - mozversion application_display_name: Nightly 07:02:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:02:50 INFO - mozversion application_name: Firefox 07:02:50 INFO - mozversion application_remotingname: firefox 07:02:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 07:02:50 INFO - mozversion application_vendor: Mozilla 07:02:50 INFO - mozversion application_version: 52.0a1 07:02:50 INFO - mozversion platform_buildid: 20160926045950 07:02:50 INFO - mozversion platform_changeset: 3c4ca5845f2c12974cad7617efe5f12137603721 07:02:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 07:02:50 INFO - mozversion platform_version: 52.0a1 07:02:50 INFO - using testdate: 1474898570 07:02:50 INFO - actual date: 1474898570 07:02:50 INFO - starting webserver on 'localhost:49635' 07:02:50 INFO - SUITE-START | Running 2 tests 07:02:50 INFO - TEST-START | dromaeo_css 07:02:50 INFO - operating with platform_type : w7_ 07:02:50 INFO - Initialising browser for dromaeo_css test... 07:02:50 INFO - TEST-INFO | started process 2748 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmplnkffa\profile http://localhost:49635/getInfo.html) 07:02:58 INFO - PROCESS | 2748 | __metrics Screen width/height:1600/1200 07:02:58 INFO - PROCESS | 2748 | colorDepth:24 07:02:58 INFO - PROCESS | 2748 | Browser inner width/height: 1010/674 07:02:58 INFO - PROCESS | 2748 | __metrics 07:02:59 INFO - PROCESS | 2748 | 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:59 INFO - PROCESS | 2748 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:03:00 INFO - TEST-INFO | 2748: exit 0 07:03:00 INFO - Browser initialized. 07:03:00 INFO - Running cycle 1/1 for dromaeo_css test... 07:03:00 INFO - TEST-INFO | started process 4004 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmplnkffa\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:03:01 INFO - PROCESS | 4004 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 07:03:12 INFO - PROCESS | 4004 | RSS: Main: 139792384 07:03:12 INFO - PROCESS | 4004 | 07:04:03 INFO - PROCESS | 4004 | 1474898643507 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 07:06:16 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:49635/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49635/tests/dromaeo/cssquery-ext.html) 07:06:17 INFO - PROCESS | 4004 | RSS: Main: 181121024 07:06:17 INFO - PROCESS | 4004 | 07:09:20 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:49635/tests/dromaeo/cssquery-ext.html (next: http://localhost:49635/tests/dromaeo/cssquery-jquery.html) 07:09:20 INFO - PROCESS | 4004 | RSS: Main: 205144064 07:09:20 INFO - PROCESS | 4004 | 07:12:23 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:49635/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49635/tests/dromaeo/cssquery-mootools.html) 07:12:23 INFO - PROCESS | 4004 | RSS: Main: 151318528 07:12:23 INFO - PROCESS | 4004 | 07:15:25 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:49635/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49635/tests/dromaeo/cssquery-prototype.html) 07:15:26 INFO - PROCESS | 4004 | RSS: Main: 144515072 07:15:26 INFO - PROCESS | 4004 | 07:18:29 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:49635/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49635/tests/dromaeo/cssquery-yui.html) 07:18:30 INFO - PROCESS | 4004 | RSS: Main: 139665408 07:18:30 INFO - PROCESS | 4004 | 07:21:33 INFO - PROCESS | 4004 | Cycle 1(1): loaded http://localhost:49635/tests/dromaeo/cssquery-yui.html (next: http://localhost:49635/tests/dromaeo/cssquery-dojo.html) 07:21:33 INFO - PROCESS | 4004 | RSS: Main: 186621952 07:21:33 INFO - PROCESS | 4004 | 07:21:33 INFO - PROCESS | 4004 | __start_tp_report 07:21:33 INFO - PROCESS | 4004 | _x_x_mozilla_page_load 07:21:33 INFO - PROCESS | 4004 | _x_x_mozilla_page_load_details 07:21:33 INFO - PROCESS | 4004 | |i|pagename|runs| 07:21:33 INFO - PROCESS | 4004 | |0;dojo.html;2317.837751357405;2328.0338659456334;2379.5478859016785;2381.976180238198;2388.0805889732446;3912.276228897656;4022.255882661706;4036.253293140771;4120.15536814953;4149.813258403367;3943.7239393242485;3971.225611005855;4058.147788964314;4122.814473348699;4154.08610105777;3492.441209406489;3494.38848201565;3510.157562185083;3578.159132603836;3585.8027808470524;3414.7951122932577;3465.2722928185112;3535.3813082710553;3592.8742494012713;3623.257232267392;3512.7365447591405;3553.520274762913;3559.608443071273;3575.4458059000767;3597.1007248187952;3519.6832285094465;3522.823858807062;3578.1054736315923;3591.586967498745;3603.261331427051;3422.863085476578;3493.8427770750122;3522.577290725104;3530.593981692087;3548.308079924416;4011.6389524942897;4079.77561234132;4089;4101.015756218486;4103.8974025649295;4236.766977816219;4247.957520424787;4322.243607368698;4329.502107257674;4333.804978775963;4146.108586653884;4161.583841615814;4192.517860446027;4278.7860606969525;4307.138572285556;3996.200759848042;4055.797210139481;4060.817263223162;4061.147159096623;4062.0657248832345;4207.621314081747;4307.763073031013;4337.392765012901;4417.60241578253;4428.468583769968;3926.8821935341984;4073.9796301018305;4200.768957707355;4225.767582782976;4280.5291417944;4232.767197804151;4311.9784401077795;4326.3510473428605;4350.934735978963;4353.6081752641785;4103.117829666636;4223.049813791934;4290.742555446684;4304.225239456928;4315.8489452869;4091.3862920562156;4093.058596522757;4166.979165104155;4278.807453664593;4312.5903039211225;2999.4600971824843;3024.2741741982204;3042.9543556846224;3062.6937306269197;3086.4598695228246;2738.370174859793;2741.3968926836064;2783.5407157819136;2796.580512923078;2819.5347767618114;4028.7582745035393;4029.7582145070805;4030.4760381150263;4089.325261331847;4100.569440208795;4199.160167966419;4219.810108545123;4290.2706539888295;4306.224879521654;4371.147626212922;3978.303796835542;4073.857414990461;4074.9388759168637;4075.0627355708352;4081.9999100220634;3627.836747346376;3648.5986541480415;3694.8891533254045;3717.0893131183034;3717.498137751369;4221.873343799691;4285.528591854831;4375.912481750283;4410.33844923264;4495.955040449554;4214.283571792741;4252.447181866338;4322.178786030644;4323.200207961537;4325.113351762944;4259.87220383389;4379.562043795595;4440.822367105279;4473.686841921034;4476.597106260452;4006.539247986519;4074.9796251018556;4107;4227.97886010568;4231.87304380869;4280.614744672995;4316.719413238129;4333.306670932636;4365.148795984879;4372.0162963333;4110.568390319093;4144.896377590584;4205.411242426124;4224.4930608327195;4236.978815105905;3798.1264309208486;4037.5558688544825;4087.938680919848;4239.130978149534;4246.214450326699;3833.9808300958316;3937.370020796659;4127.731697439657;4150.398192262165;4159.8960025999595;4099.938500922429;4141.151064031927;4177.56135605757;4256.36154576816;4273.658107351466;3969.841206351829;4059.817308221078;4081.8163182657445;4128.256913755553;4219.388188712681 07:21:33 INFO - PROCESS | 4004 | |1;ext.html;545.9945400545994;547.9971651877065;549.7553588653051;559.8992181407345;569.8062658696042;25897.74102258977;35393.64606353936;37094.8145259274;37130.071748206276;39285.41071883924;36695.26609467809;37019.62980370195;37487.81256093722;37533.43699844498;37547.624523754756;6428.614283143003;7676.925230467739;7762.495437796553;7836.921630783676;7844;6473.223213214421;8864.778380540505;9847.65533206338;9866.062724041205;10193.745156371113;9962.452065136415;10120.240981926348;10183.185345172406;10193.898061019407;10196.592136314539;9645.855312170359;9783.608655653801;9838.90161098387;9846.803063938753;9853.852192217122;6329.715162817684;6496.187976502937;9403.43579385239;9887.060729230712;10031.398116113056;35119.6488035119;35127;35793;35875;35898.64101358979;2402.1472377306154;2425.3936515871032;2429.9149529766373;2485.9875700621383;2543.465872166847;26662.866685666642;26868;26906;27027.324316892038;27334.5899811503;3201.4077395681875;3310.735141188723;3313.7017668409717;3315.3037862049;3361.344537815127;217632;218687;219400;219634;220271;43388.34917476241;45941.702914854126;46042.30936535955;46086;46098.53901460942;26434.735652643227;27178.184654460398;27204.31989200286;28223.294417639725;48123;10078.294519383719;10254.948725256327;10442.112420444348;10459.320144190604;10523.368597884151;6670.132882725215;7007.789766307019;7308.926910730825;7451.105867295959;7600.961995189989;13465.798013029813;15092;15959.521214363587;16097.436589719538;16316.918415408085;6292.307846136921;6320.336364681734;6321.55749097568;6339.4611458025665;6388.84027899297;709.2623671381705;763.8052296664315;787.4055088408215;933.0576118120612;971.6939164163266;25330.620040699403;25808.225753227056;25870.353241169123;25918.611220831703;25973.480530389286;834.7871292820413;853.3812985585525;861.9957749222086;864.2178828160525;961.9951900240454;13182.934085329513;13693.520726774515;13708.3831227595;13919.791203131961;14032.298385080909;293742;304155;305849;306827;306914;42125.789371052946;43500.78249608732;44163.55836441659;44690.32964505535;44759.776201119435;908.2688435809237;916.6470908700198;1013.48818846485;1056.772793849326;1058.2645061682058;24036.759632403453;26139.215823525326;26274.599954023204;26498;26655.33361665974;37054;37697.2460550782;38182.809085954395;38193.80903095467;38321.80839095786;252804;254512;254711;254850;256371;20103.899480502503;21064.26275080365;21700.891495542422;21728.89135554312;22526.88736556372;36634.8168259157;37005.629943700216;37091.629083708816;37500.2499949994;37658.24683506369;32602.34795304128;32694.83652581722;32822.179445513095;33153.834230829656;33417.33165336631;32293.51559726559;32397.19007024749;32601.510977335834;33406.164845879044;34142.317153657285;31720.04839854808;31821.36357272888;31899.202519937186;31958.6804131965;32108.197295066875;28046.579301310903;28963.33868122862;29766.404671906872;29970.400591988473;30103.247418814706;22084.88957555202;23152.484067567486;23388.298351049496;23529.64705529453;23764.405889852893 07:21:33 INFO - PROCESS | 4004 | |2;jquery.html;297.5195059978133;321.2114259492945;323.891496348723;325.85010894988307;334.05629097798715;11656.300621962688;15415.76471411646;16132;16236.756448653265;16399.754003689923;14458.349374278156;15148;15790.289436975336;15820.525384238463;15828.446004389849;4325.394444777741;4602.171609110359;4692.976535117336;4693;4704.176769065417;3278.1804548862783;4594.310853371988;4658.25467925132;4686.554777296168;4692.953070469286;3428.8285585720737;5219.164933610623;5334.466553344673;5353.9196912046355;5461.672299662033;5154.38147422308;5374.032674118657;5422.4306447823;5439.619226654136;5446.836594902159;5101.183810590288;5305.204219367087;5308.044551980642;5309.4425085365865;5326.573874090063;19292.90353548237;19713.211471541123;19878.503037424034;20029.79970200294;20271.594568108703;1552.4256025270722;1748.5191572317588;1762.8589712823073;1938.6898096304665;2225.831438494787;8344.958275208646;8477.23704866565;8591.656333746705;8669.393142480028;8687;1541.0907564536865;1592.7053495103444;1636.6153953820842;1654.487108996215;1703.8977661340236;62963;67264.6636766813;68170.3182968169;68635;72222.27777722209;8852.159044890725;8914.955425222832;9054.909450905538;9133.17801397864;9290.396124251902;12065.758684826429;12122.878771212176;12136.08979326554;12136.45385957634;12164.939175304067;5725.5133313667975;5842.795502157404;5919.911201331984;5933.851653708605;5952.107183922446;5341.572674186055;5344.839654810362;5406.189071639207;5427.945720542744;5583.69289689071;3644.2529281497227;3825.1967086911504;3874.7093967952515;3939.546952100488;3980.422838688432;1635.6401591649817;1689.5353777710982;1696.575856035991;1698.88107832453;1711.7860267466567;325.51010728852924;329.55510061416896;343.23458687127845;363.8399104394058;731.8938753880658;15969.840301596836;17312.047837368813;17635.911820441073;17911.90022243392;18071.277148913898;736.7421402509079;774.4501404003221;775.7711475114854;781.3007358414187;781.6248200863618;9325.855053337771;9362.719118426457;9508.762280942894;9534.618615255313;9602.279829012852;302923;303426;304853.0874875616;304949;306153;73331.63334183295;74726.25273747301;76242.61878690682;76591.61704191443;77282;787.933025692811;810.3314765318636;818.4311903227319;820.0446479850907;821.3306155483263;14901.62745931317;14919.850801491846;14952.850471495145;15084.24578771079;15272.541823745305;38242;38284.2343153141;38529.614703853724;39238.607613923494;40985;46628;49018.754906225244;51523;53220;53460.465395347106;6656.800295991128;6866.725330986705;6895.310468953065;6901.965490172718;6907.447404207551;9325.347225694137;10907.509162087883;11191.72020699489;11194.160437967348;11310.830337545109;9207.585658645243;9234.815303694022;9313.39462934907;9480.336376453582;9532.761680958032;9268.907310926805;9273.3045021625;9537.37579719702;9611.855822162534;9830.508474576385;9242.953785231031;9416.670416535388;9495.905040949501;9520.333576649567;9839.606415743576;6760.729570817112;6764.62794546285;6867.175938887365;6939.271376505394;7030.789076327718;7207.387372073433;8922.955385223033;9025.593848276712;9278.489683067226;9304.348695591243 07:21:33 INFO - PROCESS | 4004 | |3;mootools.html;1108.0138676577842;1214.3624597086532;1258.9307588082652;1284.158875936262;1542.4909779772677;5770.653760774351;6908.827279318013;7196.8920466192985;7337.706491740324;7342.265773422655;7017.473689473284;7144.214136445;7174.96412517938;7217.963910180455;7233.168185658651;1910.0640686063832;1941.9029048547586;1948.2888745607893;1949.873258238217;1951.4243298227057;1857.2942281932915;1880.1163453177053;1886.650969570627;1889.508727730793;1912.7704675438897;1812.6284111757125;1871.1860340751748;1883.077292126855;1889.829915307622;1891.8864868107958;1806.8283513066237;1845.0774612693585;1859.618778150476;1860.9627807443776;1861.2741030997786;1829.423731524579;1835.880667756605;1843.9815601844077;1844.603410266799;1860.367475058473;6733.797986060426;6919.238883722786;7046.4010559102535;7059.576425414441;7063.364297213224;3823.254465379252;3911.159100793343;3946;3953.8616148434953;3956.6636835868985;2412.8914198861094;2438.7073551173794;2476.3809047738064;2488.9377765555823;2489.5643262429003;735.7093947890606;751.2937838431857;763.9953461198506;765.9617019149076;767.6929228308666;8733;8833.779155521099;8852.468851868909;8855.557222138867;8894.86657700135;3331.883384081594;3350.329934013207;3385.779924304912;3402.795832250073;3514.5079688843584;7022.40309573692;7079.610621415872;7089.22018577956;7111.253318401494;7113.82215444618;3639.5086663300644;3740.5511338638994;3804.2962052020466;3804.714646401531;3810;3131.4676504994204;3227.7417806575418;3522.0490467573613;3536.398812201932;3551.6093229744706;2896.4351951369704;2978.657454392729;2994.3412449261127;3022.3199780049754;3030.2272920405157;821.0803899632331;838.2539539809575;847.1951645936385;848.024771512753;856.4304737349593;687.5599616245606;710.7725527831147;716.4089626058523;736.5875109938345;745.9440541959375;3840.9615903841163;4228.70399072062;4263.55232700562;4318.5033721121845;4438.644908407384;743.9032925719622;769.3998681028806;783.5377127494692;809.4050872608628;812.6302532347768;2722.101706436871;2773.0987429085626;2849.4016256585933;2878.942421151607;2932.120363890824;7885.408594355446;8185.3042491387705;8189.631466584018;8227.547484888388;8238.09380968093;7689;8042.437029408003;8060.435769496079;8218.178182181735;8370.0374456937;5856.677882716406;6027.126066720301;6367.42693157618;6398.5521013528605;6407.199100112486;4165.096865023516;4232.3016702243785;4361.5202327744555;4396.230659634487;4494.25844735628;7553.7356192533;7903.881441778493;7991.2807847294025;8024.638891249791;8075.798105047421;7771.72798952034;8156.551389673625;8176.672933082848;8197.71308004217;8210.548419836967;6042.728077236447;6112.388761123852;6153.538484613761;6166;6187.319394866471;6452.387023232785;6684.4318232950745;6740.292269311848;6752.493562982698;6753.932460675331;5833.212516310244;5892.941070589411;6001.219841420588;6025.21672182631;6028.608140470856;5766.3368712598585;5771.23977186962;5883.646981181143;5887.7056147191615;5903.793367232298;5022.146235139962;5163.225516172604;5295.364556253275;5306.071437498499;5367.973160134175;3628.74598778083;3676.4117741161285;3727.813609319469;3779.3008293465796;3779.3764028934916;3726.888193354204;3772.4152756322815;3788.753731007476;3797.2974999625158;3918.4710064140977 07:21:33 INFO - PROCESS | 4004 | |4;prototype.html;223.4714899263243;241.64357572580442;241.67012028580982;254.4376926991349;257.97936165106796;4011.679065674746;4351.108022855315;4463.397441345417;4495.662825288108;4497.977510112453;4422.51845631713;4432.3573081903105;4432.689711720172;4442.933695912981;4459.50945396007;3078.415101130789;3123.1567476781256;3173.825439600821;3230.4508233600227;3257.96742032579;3073.631164260281;3074.584931034304;3081.9983505360738;3106.549550315203;3186.6654001329875;3018.9396212075744;3040.619922509686;3055.1903745507457;3063.3566950940217;3170.7780455368134;3057.31210477643;3172.460681684119;3236.3689080629288;3240.6111266648154;3241.011491495093;2741.616173735679;2821.957670634942;2827.137722994486;2831.8017738758294;2835.2911772056987;4110.794460277004;4294.420253265832;4317.525072242051;4336.8265269389185;4342.696011279212;3773.7734736505317;3797.487339209227;3811.866584669523;3816.656500914903;3818.873432337467;4248.362745588156;4503.279475283972;4549.181147393468;4590.931136032962;4598.79305431253;3247.256068622011;3247.610286765603;3251.869925202989;3252.414565378231;3258.6252580953255;4744.928826067612;4755.83354582588;4763.618910487153;4776.47458779534;4777.546133117383;3015.5627434021944;3017.9396412071637;3068.5550595164023;3152.2277042124824;3165.4935210366693;3057.2662560985204;3112.190830384071;3202.503611940153;3215.646278909318;3221.194701324669;3546.627604101532;3719.237556300952;3733.253349330145;3741.8503259869844;3749.9250015000093;4601.28680054592;4602.746848923274;4606.838760643362;4609.4699109602825;4615.584597386184;1091.170710260208;1106.5352551928208;1123.4888125902694;1132.6092498088146;1136.3466007045981;701.6702149989496;701.8034950213948;722.8518153778568;724.8840185570283;735.5880706804206;3949.3286141355466;3986.382110772835;4032.354823228269;4046.615571520701;4050.9797451012555;4223.366495025771;4225.281702110648;4256.2551553478015;4278.229918614617;4301.483821941324;3930.9975956131;3965.0571220101942;3976.980115099464;3987.5613682494904;4041.8076667383193;2211.955760884773;2247.696560964249;2248.26931247342;2279.9252680061063;2295.7933785959344;4445.599896009375;4777.259524773665;4829.444613869379;4928.383952005999;4932.7286999215385;4623;4761.452432970253;4791.544803243686;4841.3222148899185;4874.171390863562;4679.719216846932;4888;4912.484189160086;4915.033986405395;4925.75371231437;4753.952460475351;4757.666963312535;4760.78576464053;4760.857174284777;4769.141554520081;4432.867013989586;4599.44806623194;4670.743109129031;4684.5549672781035;4690.390249267573;4673.322368256651;4889.584385327287;4909.140900342497;4935.605151587936;4943.184374578154;3173.984130079335;3235.3691030249815;3240.8865689700747;3242.5136229565755;3244.253821620993;4709.058188362273;4738.241881298975;4738.36032135671;4747.074320507606;4747.216709242936;4387.188370151404;4445.444094566876;4557.293619488984;4592.5177856325945;4600.056988317454;4548.545145485425;4566.748828814447;4566.931496027496;4579.198640238012;4579.733032149107;4476.865694029184;4477.664175186809;4496.280595104768;4497.550244975476;4503;4397.978010109929;4572.4055872736335;4621.2605983043895;4630;4630.72215667061;4163.687723420816;4242.89392765171;4256.935766058486;4388.902774306423;4559.817607295671 07:21:33 INFO - PROCESS | 4004 | |5;yui.html;573.69594115119;576.3688760806916;598.7395482964911;600.2197143713173;637.9776707815228;12096.153269271144;12328.137030407875;12738.745225095492;12894.484220631175;13488.123271987313;12424.627261182179;12525.436355364007;13050.738985220314;13137.73724525509;13159.868401315984;490.36987471099644;505.4238168487927;617.4288782875833;632.5888172687744;665.7769647168204;614.081947488503;614.4746241963124;615.4153554123601;623.3672822085587;625;674.9358810912955;675.2201207605224;675.810772983563;675.9763408280711;676.1277951442634;505.24465923444683;524.4021815130754;668.7993601919429;670.4803777072808;672.8755180291637;385.36414915389554;389.08953049863453;493.888875003125;497.5174081141287;499.7151623574564;11343;11826.822597661041;12230.327331996737;12275.815862762065;12325.322107284095;10440.791184176274;11102.222844400896;11303.321800691903;11856.05151587871;11950.103742219368;11504.482298296596;11716.472758725793;11750.178722298058;11886.465109070112;11953.940230298793;3811.9618803812164;3850.9803532545334;3923.234969181011;3990.940135897964;4020.8190631421658;61873.381266187665;62105;63314.68342658303;63484;63692.68153659202;9042.909570904338;9131.81736365269;9140.862887056699;9195.586198620944;9365.217607684186;15251.161186134646;15724.68550628981;15747;15786.368545258061;15794.210289485709;6028.276606807123;6137.60105593135;6265.8120256392385;6343.746250149942;6469.159009328851;3225.645179030305;3369.25876307212;3395.5076513905838;3435.261418794921;3461.619221885641;2432.2824766693866;2451.509698060395;2532.03782562625;2533.7592928671747;2537.555927712643;423.1938157809378;437.33306707271663;446.2903982667574;460.8179768991297;460.99308510372373;452.29216276527785;496.42166875590254;518.7912164656342;566.0603398358704;576.9221155144002;6682.231543372477;6922.688479018456;6931.306869313129;7057.18842333128;7465.06686664167;436.7139523612039;486.47946697033836;546.4098773324799;546.7430307755349;562.2493970549281;3034.908952731422;3146.2134466383404;3253.755968302387;3333.949990750141;3335.5663763710563;77458.51436685363;79203.60398198088;79517.20482795214;84101.57949210214;86008.56995714981;76004;77882.22117778863;80916.59541702255;82376;82786;72000.2799972004;75987.24012759913;76025.2160915059;76167.23832761712;76539;6524.706388212637;6709.492508794881;6810.250872404131;6990.370866622028;7167.641617919187;40373.798131009156;41244.587554124075;42578.5742142587;43136.13727725365;43717.78141109274;59919.700401497714;60452.6977365128;61035.694821525605;61040.694796525735;62149.37850621617;9360.53197340144;9767.511624418892;10147.949260253652;10191.847122293024;10546.525406356579;10657.89342106569;10931;11085.445727713743;11228.4385780709;11782.29306241594;9493.905060949302;9622.278329125204;9682.370645907993;9947.403155810674;10109.195660083078;9287.62849486013;9582.952085239529;9683.41899486033;9736.464494452874;9791.608335666493;9921.503924803876;9944.105030547285;9954.651587194414;10176.389416635026;10203.38779673222;9015.729528114167;9025.277977761894;9074.954625226832;9107.635694572144;9152.771180720536;8241.423100382915;8257.21556452136;8278.296344810758;8407.2433480987;8723.171298726611 07:21:33 INFO - PROCESS | 4004 | __end_tp_report 07:21:33 INFO - PROCESS | 4004 | __start_cc_report 07:21:33 INFO - PROCESS | 4004 | _x_x_mozilla_cycle_collect,786 07:21:33 INFO - PROCESS | 4004 | __end_cc_report 07:21:33 INFO - PROCESS | 4004 | __startTimestamp1474899693281__endTimestamp 07:21:33 INFO - PROCESS | 4004 | 07:21:33 INFO - PROCESS | 4004 | ------- Summary: start ------- 07:21:33 INFO - PROCESS | 4004 | Number of tests: 6 07:21:33 INFO - PROCESS | 4004 | 07:21:33 INFO - PROCESS | 4004 | [#0] dojo.html Cycles:165 Average:3939.54 Median:4099.94 stddev:477.59 (11.6%) stddev-sans-first:461.80 07:21:33 INFO - PROCESS | 4004 | Values: 2317.8 2328.0 2379.5 2382.0 2388.1 3912.3 4022.3 4036.3 4120.2 4149.8 3943.7 3971.2 4058.1 4122.8 4154.1 3492.4 3494.4 3510.2 3578.2 3585.8 3414.8 3465.3 3535.4 3592.9 3623.3 3512.7 3553.5 3559.6 3575.4 3597.1 3519.7 3522.8 3578.1 3591.6 3603.3 3422.9 3493.8 3522.6 3530.6 3548.3 4011.6 4079.8 4089.0 4101.0 4103.9 4236.8 4248.0 4322.2 4329.5 4333.8 4146.1 4161.6 4192.5 4278.8 4307.1 3996.2 4055.8 4060.8 4061.1 4062.1 4207.6 4307.8 4337.4 4417.6 4428.5 3926.9 4074.0 4200.8 4225.8 4280.5 4232.8 4312.0 4326.4 4350.9 4353.6 4103.1 4223.0 4290.7 4304.2 4315.8 4091.4 4093.1 4167.0 4278.8 4312.6 2999.5 3024.3 3043.0 3062.7 3086.5 2738.4 2741.4 2783.5 2796.6 2819.5 4028.8 4029.8 4030.5 4089.3 4100.6 4199.2 4219.8 4290.3 4306.2 4371.1 3978.3 4073.9 4074.9 4075.1 4082.0 3627.8 3648.6 3694.9 3717.1 3717.5 4221.9 4285.5 4375.9 4410.3 4496.0 4214.3 4252.4 4322.2 4323.2 4325.1 4259.9 4379.6 4440.8 4473.7 4476.6 4006.5 4075.0 4107.0 4228.0 4231.9 4280.6 4316.7 4333.3 4365.1 4372.0 4110.6 4144.9 4205.4 4224.5 4237.0 3798.1 4037.6 4087.9 4239.1 4246.2 3834.0 3937.4 4127.7 4150.4 4159.9 4099.9 4141.2 4177.6 4256.4 4273.7 3969.8 4059.8 4081.8 4128.3 4219.4 07:21:33 INFO - PROCESS | 4004 | 07:21:33 INFO - PROCESS | 4004 | [#1] ext.html Cycles:180 Average:39951.94 Median:18066.46 stddev:68397.53 (378.6%) stddev-sans-first:68525.41 07:21:33 INFO - PROCESS | 4004 | Values: 546.0 548.0 549.8 559.9 569.8 25897.7 35393.6 37094.8 37130.1 39285.4 36695.3 37019.6 37487.8 37533.4 37547.6 6428.6 7676.9 7762.5 7836.9 7844.0 6473.2 8864.8 9847.7 9866.1 10193.7 9962.5 10120.2 10183.2 10193.9 10196.6 9645.9 9783.6 9838.9 9846.8 9853.9 6329.7 6496.2 9403.4 9887.1 10031.4 35119.6 35127.0 35793.0 35875.0 35898.6 2402.1 2425.4 2429.9 2486.0 2543.5 26662.9 26868.0 26906.0 27027.3 27334.6 3201.4 3310.7 3313.7 3315.3 3361.3 217632.0 218687.0 219400.0 219634.0 220271.0 43388.3 45941.7 46042.3 46086.0 46098.5 26434.7 27178.2 27204.3 28223.3 48123.0 10078.3 10254.9 10442.1 10459.3 10523.4 6670.1 7007.8 7308.9 7451.1 7601.0 13465.8 15092.0 15959.5 16097.4 16316.9 6292.3 6320.3 6321.6 6339.5 6388.8 709.3 763.8 787.4 933.1 971.7 25330.6 25808.2 25870.4 25918.6 25973.5 834.8 853.4 862.0 864.2 962.0 13182.9 13693.5 13708.4 13919.8 14032.3 293742.0 304155.0 305849.0 306827.0 306914.0 42125.8 43500.8 44163.6 44690.3 44759.8 908.3 916.6 1013.5 1056.8 1058.3 24036.8 26139.2 26274.6 26498.0 26655.3 37054.0 37697.2 38182.8 38193.8 38321.8 252804.0 254512.0 254711.0 254850.0 256371.0 20103.9 21064.3 21700.9 21728.9 22526.9 36634.8 37005.6 37091.6 37500.2 37658.2 32602.3 32694.8 32822.2 33153.8 33417.3 32293.5 32397.2 32601.5 33406.2 34142.3 31720.0 31821.4 31899.2 31958.7 32108.2 28046.6 28963.3 29766.4 29970.4 30103.2 22084.9 23152.5 23388.3 23529.6 23764.4 07:21:33 INFO - PROCESS | 4004 | 07:21:33 INFO - PROCESS | 4004 | [#2] jquery.html Cycles:180 Average:21350.33 Median:5306.62 stddev:51046.77 (961.9%) stddev-sans-first:51165.49 07:21:33 INFO - PROCESS | 4004 | Values: 297.5 321.2 323.9 325.9 334.1 11656.3 15415.8 16132.0 16236.8 16399.8 14458.3 15148.0 15790.3 15820.5 15828.4 4325.4 4602.2 4693.0 4693.0 4704.2 3278.2 4594.3 4658.3 4686.6 4693.0 3428.8 5219.2 5334.5 5353.9 5461.7 5154.4 5374.0 5422.4 5439.6 5446.8 5101.2 5305.2 5308.0 5309.4 5326.6 19292.9 19713.2 19878.5 20029.8 20271.6 1552.4 1748.5 1762.9 1938.7 2225.8 8345.0 8477.2 8591.7 8669.4 8687.0 1541.1 1592.7 1636.6 1654.5 1703.9 62963.0 67264.7 68170.3 68635.0 72222.3 8852.2 8915.0 9054.9 9133.2 9290.4 12065.8 12122.9 12136.1 12136.5 12164.9 5725.5 5842.8 5919.9 5933.9 5952.1 5341.6 5344.8 5406.2 5427.9 5583.7 3644.3 3825.2 3874.7 3939.5 3980.4 1635.6 1689.5 1696.6 1698.9 1711.8 325.5 329.6 343.2 363.8 731.9 15969.8 17312.0 17635.9 17911.9 18071.3 736.7 774.5 775.8 781.3 781.6 9325.9 9362.7 9508.8 9534.6 9602.3 302923.0 303426.0 304853.1 304949.0 306153.0 73331.6 74726.3 76242.6 76591.6 77282.0 787.9 810.3 818.4 820.0 821.3 14901.6 14919.9 14952.9 15084.2 15272.5 38242.0 38284.2 38529.6 39238.6 40985.0 46628.0 49018.8 51523.0 53220.0 53460.5 6656.8 6866.7 6895.3 6902.0 6907.4 9325.3 10907.5 11191.7 11194.2 11310.8 9207.6 9234.8 9313.4 9480.3 9532.8 9268.9 9273.3 9537.4 9611.9 9830.5 9243.0 9416.7 9495.9 9520.3 9839.6 6760.7 6764.6 6867.2 6939.3 7030.8 7207.4 8923.0 9025.6 9278.5 9304.3 07:21:33 INFO - PROCESS | 4004 | 07:21:33 INFO - PROCESS | 4004 | [#3] mootools.html Cycles:180 Average:4383.78 Median:5768.50 stddev:2502.29 (43.4%) stddev-sans-first:2497.20 07:21:33 INFO - PROCESS | 4004 | Values: 1108.0 1214.4 1258.9 1284.2 1542.5 5770.7 6908.8 7196.9 7337.7 7342.3 7017.5 7144.2 7175.0 7218.0 7233.2 1910.1 1941.9 1948.3 1949.9 1951.4 1857.3 1880.1 1886.7 1889.5 1912.8 1812.6 1871.2 1883.1 1889.8 1891.9 1806.8 1845.1 1859.6 1861.0 1861.3 1829.4 1835.9 1844.0 1844.6 1860.4 6733.8 6919.2 7046.4 7059.6 7063.4 3823.3 3911.2 3946.0 3953.9 3956.7 2412.9 2438.7 2476.4 2488.9 2489.6 735.7 751.3 764.0 766.0 767.7 8733.0 8833.8 8852.5 8855.6 8894.9 3331.9 3350.3 3385.8 3402.8 3514.5 7022.4 7079.6 7089.2 7111.3 7113.8 3639.5 3740.6 3804.3 3804.7 3810.0 3131.5 3227.7 3522.0 3536.4 3551.6 2896.4 2978.7 2994.3 3022.3 3030.2 821.1 838.3 847.2 848.0 856.4 687.6 710.8 716.4 736.6 745.9 3841.0 4228.7 4263.6 4318.5 4438.6 743.9 769.4 783.5 809.4 812.6 2722.1 2773.1 2849.4 2878.9 2932.1 7885.4 8185.3 8189.6 8227.5 8238.1 7689.0 8042.4 8060.4 8218.2 8370.0 5856.7 6027.1 6367.4 6398.6 6407.2 4165.1 4232.3 4361.5 4396.2 4494.3 7553.7 7903.9 7991.3 8024.6 8075.8 7771.7 8156.6 8176.7 8197.7 8210.5 6042.7 6112.4 6153.5 6166.0 6187.3 6452.4 6684.4 6740.3 6752.5 6753.9 5833.2 5892.9 6001.2 6025.2 6028.6 5766.3 5771.2 5883.6 5887.7 5903.8 5022.1 5163.2 5295.4 5306.1 5368.0 3628.7 3676.4 3727.8 3779.3 3779.4 3726.9 3772.4 3788.8 3797.3 3918.5 07:21:33 INFO - PROCESS | 4004 | 07:21:33 INFO - PROCESS | 4004 | [#4] prototype.html Cycles:180 Average:3749.67 Median:4297.95 stddev:1164.77 (27.1%) stddev-sans-first:1137.57 07:21:33 INFO - PROCESS | 4004 | Values: 223.5 241.6 241.7 254.4 258.0 4011.7 4351.1 4463.4 4495.7 4498.0 4422.5 4432.4 4432.7 4442.9 4459.5 3078.4 3123.2 3173.8 3230.5 3258.0 3073.6 3074.6 3082.0 3106.5 3186.7 3018.9 3040.6 3055.2 3063.4 3170.8 3057.3 3172.5 3236.4 3240.6 3241.0 2741.6 2822.0 2827.1 2831.8 2835.3 4110.8 4294.4 4317.5 4336.8 4342.7 3773.8 3797.5 3811.9 3816.7 3818.9 4248.4 4503.3 4549.2 4590.9 4598.8 3247.3 3247.6 3251.9 3252.4 3258.6 4744.9 4755.8 4763.6 4776.5 4777.5 3015.6 3017.9 3068.6 3152.2 3165.5 3057.3 3112.2 3202.5 3215.6 3221.2 3546.6 3719.2 3733.3 3741.9 3749.9 4601.3 4602.7 4606.8 4609.5 4615.6 1091.2 1106.5 1123.5 1132.6 1136.3 701.7 701.8 722.9 724.9 735.6 3949.3 3986.4 4032.4 4046.6 4051.0 4223.4 4225.3 4256.3 4278.2 4301.5 3931.0 3965.1 3977.0 3987.6 4041.8 2212.0 2247.7 2248.3 2279.9 2295.8 4445.6 4777.3 4829.4 4928.4 4932.7 4623.0 4761.5 4791.5 4841.3 4874.2 4679.7 4888.0 4912.5 4915.0 4925.8 4754.0 4757.7 4760.8 4760.9 4769.1 4432.9 4599.4 4670.7 4684.6 4690.4 4673.3 4889.6 4909.1 4935.6 4943.2 3174.0 3235.4 3240.9 3242.5 3244.3 4709.1 4738.2 4738.4 4747.1 4747.2 4387.2 4445.4 4557.3 4592.5 4600.1 4548.5 4566.7 4566.9 4579.2 4579.7 4476.9 4477.7 4496.3 4497.6 4503.0 4398.0 4572.4 4621.3 4630.0 4630.7 4163.7 4242.9 4256.9 4388.9 4559.8 07:21:33 INFO - PROCESS | 4004 | 07:21:33 INFO - PROCESS | 4004 | [#5] yui.html Cycles:180 Average:16507.66 Median:576.65 stddev:23786.16 (4124.9%) stddev-sans-first:23822.79 07:21:33 INFO - PROCESS | 4004 | Values: 573.7 576.4 598.7 600.2 638.0 12096.2 12328.1 12738.7 12894.5 13488.1 12424.6 12525.4 13050.7 13137.7 13159.9 490.4 505.4 617.4 632.6 665.8 614.1 614.5 615.4 623.4 625.0 674.9 675.2 675.8 676.0 676.1 505.2 524.4 668.8 670.5 672.9 385.4 389.1 493.9 497.5 499.7 11343.0 11826.8 12230.3 12275.8 12325.3 10440.8 11102.2 11303.3 11856.1 11950.1 11504.5 11716.5 11750.2 11886.5 11953.9 3812.0 3851.0 3923.2 3990.9 4020.8 61873.4 62105.0 63314.7 63484.0 63692.7 9042.9 9131.8 9140.9 9195.6 9365.2 15251.2 15724.7 15747.0 15786.4 15794.2 6028.3 6137.6 6265.8 6343.7 6469.2 3225.6 3369.3 3395.5 3435.3 3461.6 2432.3 2451.5 2532.0 2533.8 2537.6 423.2 437.3 446.3 460.8 461.0 452.3 496.4 518.8 566.1 576.9 6682.2 6922.7 6931.3 7057.2 7465.1 436.7 486.5 546.4 546.7 562.2 3034.9 3146.2 3253.8 3333.9 3335.6 77458.5 79203.6 79517.2 84101.6 86008.6 76004.0 77882.2 80916.6 82376.0 82786.0 72000.3 75987.2 76025.2 76167.2 76539.0 6524.7 6709.5 6810.3 6990.4 7167.6 40373.8 41244.6 42578.6 43136.1 43717.8 59919.7 60452.7 61035.7 61040.7 62149.4 9360.5 9767.5 10147.9 10191.8 10546.5 10657.9 10931.0 11085.4 11228.4 11782.3 9493.9 9622.3 9682.4 9947.4 10109.2 9287.6 9583.0 9683.4 9736.5 9791.6 9921.5 9944.1 9954.7 10176.4 10203.4 9015.7 9025.3 9075.0 9107.6 9152.8 8241.4 8257.2 8278.3 8407.2 8723.2 07:21:33 INFO - PROCESS | 4004 | -------- Summary: end -------- 07:21:33 INFO - PROCESS | 4004 | 07:21:33 INFO - TEST-INFO | 4004: exit 0 07:21:34 INFO - TEST-OK | dromaeo_css | took 1124392ms 07:21:34 INFO - TEST-START | kraken 07:21:34 INFO - operating with platform_type : w7_ 07:21:34 INFO - Initialising browser for kraken test... 07:21:34 INFO - TEST-INFO | started process 736 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpfjjcri\profile http://localhost:49635/getInfo.html) 07:21:39 INFO - PROCESS | 736 | __metrics Screen width/height:1600/1200 07:21:39 INFO - PROCESS | 736 | colorDepth:24 07:21:39 INFO - PROCESS | 736 | Browser inner width/height: 1010/674 07:21:39 INFO - PROCESS | 736 | __metrics 07:21:40 INFO - PROCESS | 736 | 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:21:40 INFO - PROCESS | 736 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:21:40 INFO - PROCESS | 736 | console.error: 07:21:40 INFO - PROCESS | 736 | Message: Error: Connection closed before committing the transaction. 07:21:40 INFO - PROCESS | 736 | Stack: 07:21:40 INFO - PROCESS | 736 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 07:21:40 INFO - PROCESS | 736 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 07:21:40 INFO - PROCESS | 736 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 07:21:40 INFO - PROCESS | 736 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 07:21:40 INFO - PROCESS | 736 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 07:21:40 INFO - PROCESS | 736 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13 07:21:40 INFO - PROCESS | 736 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 07:21:40 INFO - PROCESS | 736 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 07:21:40 INFO - PROCESS | 736 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 07:21:40 INFO - PROCESS | 736 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 07:21:40 INFO - PROCESS | 736 | Barrier/this.client.addBlocker/promise13.2% 07:22:41 INFO - TinderboxPrint: I/O read bytes / time
215,201,280 / 4,053,780 07:22:41 INFO - TinderboxPrint: I/O write bytes / time
504,181,248 / 3,358,660 07:22:41 INFO - TinderboxPrint: CPU idle
8,305.4 (86.8%) 07:22:41 INFO - TinderboxPrint: CPU user
1,224.1 (12.8%) 07:22:41 INFO - install - Wall time: 3s; CPU: 12.0%; Read bytes: 147456; Write bytes: 37881856; Read time: 16540; Write time: 55860 07:22:41 INFO - run-tests - Wall time: 1194s; CPU: 13.0%; Read bytes: 215053824; Write bytes: 431921664; Read time: 4037240; Write time: 3268760 07:22:41 INFO - Running post-run listener: _upload_blobber_files 07:22:41 INFO - Blob upload gear active. 07:22:41 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 07:22:41 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:22:41 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\venv\\Scripts\\blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', 'C:\\slave\\test\\oauth.txt', '-b', 'Autoland-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 07:22:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\venv\Scripts\blobberc.py -u https://blobupload.elasticbeanstalk.com -a C:\slave\test\oauth.txt -b Autoland-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 07:22:42 INFO - (blobuploader) - INFO - Open directory for files ... 07:22:42 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 07:22:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:22:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:22:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:22:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:22:44 INFO - (blobuploader) - INFO - Done attempting. 07:22:44 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 07:22:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:22:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:22:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:22:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:22:45 INFO - (blobuploader) - INFO - Done attempting. 07:22:45 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 07:22:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:22:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:22:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:22:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:22:53 INFO - (blobuploader) - INFO - Done attempting. 07:22:53 INFO - (blobuploader) - INFO - Iteration through files over. 07:22:53 INFO - Return code: 0 07:22:53 INFO - rmtree: C:\slave\test\build\uploaded_files.json 07:22:53 INFO - Using _rmtree_windows ... 07:22:53 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 07:22:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ed9135c33d3ce475734c1be1cc9bed73d8f3885ddc9f94fb0c81dbc67232c79a89b90c5214655f6b599d6db7187f4b0e856ef560cb8bf87cd74ae8062c7439ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/84d281bf823ddf0bbbfac26bb022fd2437cf621133662f61835951fbaf0e277bbbfa62fdcda13cbcc05d2248e06cb023abca7a6f720b16958912b16d6f4f988c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fbedc025ff99fa7bcb6e37823d7081d49ca2e8098a7059d7239263ef0a74db62512581341381a610a6dd72050410e0737742b90da7e9eb29a707bf3c51aae5b5"} 07:22:53 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 07:22:53 INFO - Writing to file C:\slave\test\properties\blobber_files 07:22:53 INFO - Contents: 07:22:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ed9135c33d3ce475734c1be1cc9bed73d8f3885ddc9f94fb0c81dbc67232c79a89b90c5214655f6b599d6db7187f4b0e856ef560cb8bf87cd74ae8062c7439ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/84d281bf823ddf0bbbfac26bb022fd2437cf621133662f61835951fbaf0e277bbbfa62fdcda13cbcc05d2248e06cb023abca7a6f720b16958912b16d6f4f988c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fbedc025ff99fa7bcb6e37823d7081d49ca2e8098a7059d7239263ef0a74db62512581341381a610a6dd72050410e0737742b90da7e9eb29a707bf3c51aae5b5"} 07:22:53 INFO - Running post-run listener: copy_logs_to_upload_dir 07:22:53 INFO - Copying logs to upload dir... 07:22:53 INFO - mkdir: C:\slave\test\build\upload\logs 07:22:53 INFO - Copying logs to upload dir... 07:22:53 INFO - Using _rmtree_windows ... 07:22:53 INFO - Using _rmtree_windows ... 07:22:53 INFO - Using _rmtree_windows ... 07:22:53 INFO - Using _rmtree_windows ... 07:22:53 INFO - Using _rmtree_windows ... 07:22:53 INFO - Using _rmtree_windows ... 07:22:53 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1422.400000 ========= master_lag: 0.55 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 42 secs) (at 2016-09-26 07:22:53.241063) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 07:22:53.241813) ========= '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-049 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-049 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-049 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ed9135c33d3ce475734c1be1cc9bed73d8f3885ddc9f94fb0c81dbc67232c79a89b90c5214655f6b599d6db7187f4b0e856ef560cb8bf87cd74ae8062c7439ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/84d281bf823ddf0bbbfac26bb022fd2437cf621133662f61835951fbaf0e277bbbfa62fdcda13cbcc05d2248e06cb023abca7a6f720b16958912b16d6f4f988c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fbedc025ff99fa7bcb6e37823d7081d49ca2e8098a7059d7239263ef0a74db62512581341381a610a6dd72050410e0737742b90da7e9eb29a707bf3c51aae5b5"} build_url:https://queue.taskcluster.net/v1/task/P6abG55ySYis9Y4R5bPYFg/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/P6abG55ySYis9Y4R5bPYFg/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ed9135c33d3ce475734c1be1cc9bed73d8f3885ddc9f94fb0c81dbc67232c79a89b90c5214655f6b599d6db7187f4b0e856ef560cb8bf87cd74ae8062c7439ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/84d281bf823ddf0bbbfac26bb022fd2437cf621133662f61835951fbaf0e277bbbfa62fdcda13cbcc05d2248e06cb023abca7a6f720b16958912b16d6f4f988c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/fbedc025ff99fa7bcb6e37823d7081d49ca2e8098a7059d7239263ef0a74db62512581341381a610a6dd72050410e0737742b90da7e9eb29a707bf3c51aae5b5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-26 07:22:53.355595) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-26 07:22:53.355932) ========= '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-049 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-049 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-049 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-09-26 07:22:53.469051) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-26 07:22:53.469504) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-26 07:22:53.915052) ========= ========= Total master_lag: 1.21 =========