builder: autoland_win7_ix_test-dromaeojs slave: t-w732-ix-045 starttime: 1474996596.75 results: success (0) buildid: 20160927080853 builduid: 7fed7d77a37b47a78d385664f6843575 revision: bf002e13d8072fe5c81289e02c8344455d6ce406 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 10:16:36.753326) ========= master: http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 10:16:36.753864) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 10:16:36.754259) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 10:16:36.893961) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 10:16:36.894263) ========= '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-045 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-045 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-045 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 --10:16:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. WARNING: Certificate verification error for hg.mozilla.org: certificate signature failure HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 8.80 MB/s 10:16:37 (8.80 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.151000 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 10:16:38.068677) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-09-27 10:16:38.069184) ========= '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-045 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-045 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-045 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=14.807000 ========= master_lag: 0.00 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2016-09-27 10:16:52.878793) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-27 10:16:52.879460) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/autoland --rev bf002e13d8072fe5c81289e02c8344455d6ce406 --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 bf002e13d8072fe5c81289e02c8344455d6ce406 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-045 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-045 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-045 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-27 10:16:53,279 truncating revision to first 12 chars 2016-09-27 10:16:53,279 Setting DEBUG logging. 2016-09-27 10:16:53,279 attempt 1/10 2016-09-27 10:16:53,279 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/bf002e13d807?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-27 10:16:54,364 unpacking tar archive at: autoland-bf002e13d807/testing/mozharness/ 2016-09-27 10:16:54,509 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.928000 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-27 10:16:54.961793) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 10:16:54.962265) ========= script_repo_revision: bf002e13d8072fe5c81289e02c8344455d6ce406 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 10:16:54.962853) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 10:16:54.963237) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 10:16:54.974374) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 6 secs) (at 2016-09-27 10:16:54.974712) ========= '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-045 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-045 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-045 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 10:16:55 INFO - MultiFileLogger online at 20160927 10:16:55 in C:\slave\test 10:16:55 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 10:16:55 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 10:16:55 INFO - {'append_to_log': False, 10:16:55 INFO - 'base_work_dir': 'C:\\slave\\test', 10:16:55 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 10:16:55 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 10:16:55 INFO - 'branch': 'Autoland-Non-PGO', 10:16:55 INFO - 'buildbot_json_path': 'buildprops.json', 10:16:55 INFO - 'config_files': ('talos/windows_config.py',), 10:16:55 INFO - 'default_actions': ('clobber', 10:16:55 INFO - 'read-buildbot-config', 10:16:55 INFO - 'download-and-extract', 10:16:55 INFO - 'populate-webroot', 10:16:55 INFO - 'create-virtualenv', 10:16:55 INFO - 'install', 10:16:55 INFO - 'run-tests'), 10:16:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:16:55 INFO - 'download_minidump_stackwalk': True, 10:16:55 INFO - 'download_symbols': 'ondemand', 10:16:55 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 10:16:55 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 10:16:55 INFO - 'hg': 'c:/mozilla-build/hg/hg', 10:16:55 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 10:16:55 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 10:16:55 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 10:16:55 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 10:16:55 INFO - 'C:/mozilla-build/tooltool.py'), 10:16:55 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 10:16:55 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 10:16:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:16:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:16:55 INFO - 'installer_path': 'installer.exe', 10:16:55 INFO - 'log_level': 'info', 10:16:55 INFO - 'log_name': 'talos', 10:16:55 INFO - 'log_to_console': True, 10:16:55 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 10:16:55 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 10:16:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 10:16:55 INFO - 'opt_config_files': (), 10:16:55 INFO - 'pip_index': False, 10:16:55 INFO - 'sps_profile': False, 10:16:55 INFO - 'sps_profile_interval': 0, 10:16:55 INFO - 'suite': 'dromaeojs', 10:16:55 INFO - 'system_bits': '32', 10:16:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:16:55 INFO - 'title': 'T-W732-IX-045', 10:16:55 INFO - 'use_talos_json': True, 10:16:55 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 10:16:55 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 10:16:55 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 10:16:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:16:55 INFO - 'work_dir': 'build'} 10:16:55 INFO - [mozharness: 2016-09-27 17:16:55.859000Z] Running clobber step. 10:16:55 INFO - Running pre-action listener: _resource_record_pre_action 10:16:55 INFO - Running main action method: clobber 10:16:55 INFO - rmtree: C:\slave\test\build 10:16:55 INFO - Using _rmtree_windows ... 10:16:55 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 10:19:40 INFO - Running post-action listener: _resource_record_post_action 10:19:40 INFO - [mozharness: 2016-09-27 17:19:40.972000Z] Finished clobber step (success) 10:19:40 INFO - [mozharness: 2016-09-27 17:19:40.972000Z] Running read-buildbot-config step. 10:19:40 INFO - Running pre-action listener: _resource_record_pre_action 10:19:40 INFO - Running main action method: read_buildbot_config 10:19:40 INFO - Using buildbot properties: 10:19:40 INFO - { 10:19:40 INFO - "project": "", 10:19:40 INFO - "product": "firefox", 10:19:40 INFO - "script_repo_revision": "production", 10:19:40 INFO - "builddir": "autoland_win7_ix_test-dromaeojs", 10:19:40 INFO - "repository": "", 10:19:40 INFO - "buildername": "Windows 7 32-bit autoland talos dromaeojs", 10:19:40 INFO - "buildid": "20160927080853", 10:19:40 INFO - "pgo_build": "False", 10:19:40 INFO - "basedir": "C:\\slave\\test", 10:19:40 INFO - "buildnumber": 287, 10:19:40 INFO - "slavename": "t-w732-ix-045", 10:19:40 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/", 10:19:40 INFO - "platform": "win7_ix", 10:19:40 INFO - "scheduler": "tests-autoland-win32-talos", 10:19:40 INFO - "branch": "autoland", 10:19:40 INFO - "repo_path": "integration/autoland", 10:19:40 INFO - "revision": "bf002e13d8072fe5c81289e02c8344455d6ce406", 10:19:40 INFO - "stage_platform": "win32", 10:19:40 INFO - "builduid": "7fed7d77a37b47a78d385664f6843575", 10:19:40 INFO - "slavebuilddir": "test" 10:19:40 INFO - } 10:19:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 10:19:40 INFO - Running post-action listener: _resource_record_post_action 10:19:40 INFO - [mozharness: 2016-09-27 17:19:40.976000Z] Finished read-buildbot-config step (success) 10:19:40 INFO - [mozharness: 2016-09-27 17:19:40.976000Z] Running download-and-extract step. 10:19:40 INFO - Running pre-action listener: _resource_record_pre_action 10:19:40 INFO - Running main action method: download_and_extract 10:19:40 INFO - mkdir: C:\slave\test\build\tests 10:19:40 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')]} 10:19:40 INFO - trying https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 10:19:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/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 10:19:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/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 10:19:42 INFO - Downloaded 1274 bytes. 10:19:42 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 10:19:42 INFO - Using the following test package requirements: 10:19:42 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 10:19:42 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:19:42 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 10:19:42 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:19:42 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'], 10:19:42 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:19:42 INFO - u'jsshell-win32.zip'], 10:19:42 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:19:42 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 10:19:42 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 10:19:42 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:19:42 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 10:19:42 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:19:42 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 10:19:42 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:19:42 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 10:19:42 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 10:19:42 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 10:19:42 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 10:19:42 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 10:19:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 10:19:42 INFO - Fetch https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 10:19:42 INFO - Expected file size: 17322591 10:20:22 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 10:20:22 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 10:20:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 10:20:22 INFO - Fetch https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 10:20:23 INFO - Expected file size: 17322591 10:20:38 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 10:20:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 10:20:38 INFO - Fetch https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 10:20:38 INFO - Expected file size: 14414057 10:20: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')]} 10:20:46 INFO - trying https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 10:20:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 10:20:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/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 10:20:47 INFO - Downloaded 53514380 bytes. 10:20:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 10:20:47 INFO - mkdir: C:\slave\test\properties 10:20:47 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 10:20:47 INFO - Writing to file C:\slave\test\properties\build_url 10:20:47 INFO - Contents: 10:20:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 10:20:48 INFO - Running post-action listener: _resource_record_post_action 10:20:48 INFO - Running post-action listener: set_extra_try_arguments 10:20:48 INFO - [mozharness: 2016-09-27 17:20:48.254000Z] Finished download-and-extract step (success) 10:20:48 INFO - [mozharness: 2016-09-27 17:20:48.254000Z] Running populate-webroot step. 10:20:48 INFO - Running pre-action listener: _resource_record_pre_action 10:20:48 INFO - Running main action method: populate_webroot 10:20:48 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 10:20:48 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 10:20:48 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 10:20:48 INFO - u'--tppagecycles', 10:20:48 INFO - u'7'], 10:20:48 INFO - u'tests': [u'tsvgm']}}, 10:20:48 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 10:20:48 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 10:20:48 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 10:20:48 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 10:20:48 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 10:20:48 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 10:20:48 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:48 INFO - u'talos_options': [u'--disable-e10s'], 10:20:48 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 10:20:48 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:48 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 10:20:48 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:48 INFO - u'talos_options': [u'--disable-e10s'], 10:20:48 INFO - u'tests': [u'damp', u'tps']}, 10:20:48 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:48 INFO - u'tests': [u'damp', u'tps']}, 10:20:48 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 10:20:48 INFO - u'tests': [u'dromaeo_dom']}, 10:20:48 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 10:20:48 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 10:20:48 INFO - u'tests': [u'basic_compositor_video']}, 10:20:48 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 10:20:48 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 10:20:48 INFO - u'tests': [u'a11yr', 10:20:48 INFO - u'ts_paint', 10:20:48 INFO - u'tpaint', 10:20:48 INFO - u'sessionrestore', 10:20:48 INFO - u'sessionrestore_no_auto_restore', 10:20:48 INFO - u'tabpaint']}, 10:20:48 INFO - u'other-e10s': {u'tests': [u'a11yr', 10:20:48 INFO - u'ts_paint', 10:20:48 INFO - u'tpaint', 10:20:48 INFO - u'sessionrestore', 10:20:48 INFO - u'sessionrestore_no_auto_restore', 10:20:48 INFO - u'tabpaint']}, 10:20:48 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 10:20:48 INFO - u'ts_paint', 10:20:48 INFO - u'tpaint', 10:20:48 INFO - u'sessionrestore', 10:20:48 INFO - u'sessionrestore_no_auto_restore', 10:20:48 INFO - u'tabpaint']}, 10:20:48 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 10:20:48 INFO - u'ts_paint', 10:20:48 INFO - u'tpaint', 10:20:48 INFO - u'sessionrestore', 10:20:48 INFO - u'sessionrestore_no_auto_restore', 10:20:48 INFO - u'tabpaint']}, 10:20:48 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 10:20:48 INFO - u'tests': [u'a11yr', 10:20:48 INFO - u'ts_paint', 10:20:48 INFO - u'tpaint', 10:20:48 INFO - u'sessionrestore', 10:20:48 INFO - u'sessionrestore_no_auto_restore', 10:20:48 INFO - u'tabpaint']}, 10:20:48 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 10:20:48 INFO - u'tests': [u'a11yr', 10:20:48 INFO - u'ts_paint', 10:20:48 INFO - u'tpaint', 10:20:48 INFO - u'sessionrestore', 10:20:48 INFO - u'sessionrestore_no_auto_restore', 10:20:48 INFO - u'tabpaint']}, 10:20:48 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 10:20:48 INFO - u'tests': [u'tsvgx', 10:20:48 INFO - u'tsvgr_opacity', 10:20:48 INFO - u'tart', 10:20:48 INFO - u'tscrollx', 10:20:48 INFO - u'cart']}, 10:20:48 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 10:20:48 INFO - u'tsvgr_opacity', 10:20:48 INFO - u'tart', 10:20:48 INFO - u'tscrollx', 10:20:48 INFO - u'cart']}, 10:20:48 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:48 INFO - u'talos_options': [u'--disable-e10s'], 10:20:48 INFO - u'tests': [u'tp5o']}, 10:20:48 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:48 INFO - u'tests': [u'tp5o']}, 10:20:48 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:48 INFO - u'talos_options': [u'--disable-e10s', 10:20:48 INFO - u'--xperf_path', 10:20:48 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:20:48 INFO - u'tests': [u'tp5n']}, 10:20:48 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:20:48 INFO - u'talos_options': [u'--xperf_path', 10:20:48 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:20:48 INFO - u'tests': [u'tp5n']}}, 10:20:48 INFO - u'talos.zip': {u'path': u'', 10:20:48 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:20:48 INFO - Running post-action listener: _resource_record_post_action 10:20:48 INFO - [mozharness: 2016-09-27 17:20:48.294000Z] Finished populate-webroot step (success) 10:20:48 INFO - [mozharness: 2016-09-27 17:20:48.294000Z] Running create-virtualenv step. 10:20:48 INFO - Running pre-action listener: _resource_record_pre_action 10:20:48 INFO - Running main action method: create_virtualenv 10:20:48 INFO - Creating virtualenv C:\slave\test\build/venv 10:20:48 INFO - mkdir: C:\slave\test\build\venv\Scripts 10:20:48 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 10:20:48 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'! 10:20:48 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 10:20:48 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 10:21:06 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 10:21:11 INFO - Installing distribute......................................................................................................................................................................................done. 10:21:12 INFO - Return code: 0 10:21:12 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 10:21:12 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 10:21:13 INFO - Reading from file tmpfile_stdout 10:21:13 INFO - Output received: 10:21:13 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 10:21:13 INFO - Using _rmtree_windows ... 10:21:13 INFO - Using _rmtree_windows ... 10:21:13 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 10:21:13 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')]} 10:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:13 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')]} 10:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:13 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 0x01827640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01861E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0187AE90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-045', 'USERDOMAIN': 'T-W732-IX-045', '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-045', '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 10:21:13 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 10:21:13 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 10:21:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:21:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:21:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:21:13 INFO - 'COMPUTERNAME': 'T-W732-IX-045', 10:21:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:21:13 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:21:13 INFO - 'DCLOCATION': 'SCL3', 10:21:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:21:13 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:21:13 INFO - 'HOMEDRIVE': 'C:', 10:21:13 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:21:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:21:13 INFO - 'KTS_VERSION': '1.19c', 10:21:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:21:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-045', 10:21:13 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:21:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:13 INFO - 'MOZ_NO_REMOTE': '1', 10:21:13 INFO - 'NO_EM_RESTART': '1', 10:21:13 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:21:13 INFO - 'OS': 'Windows_NT', 10:21:13 INFO - 'OURDRIVE': 'C:', 10:21:13 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;', 10:21:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:21:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:21:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:21:13 INFO - 'PROCESSOR_LEVEL': '6', 10:21:13 INFO - 'PROCESSOR_REVISION': '1e05', 10:21:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:21:13 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:21:13 INFO - 'PROMPT': '$P$G', 10:21:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:21:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:21:13 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:21:13 INFO - 'PWD': 'C:\\slave\\test', 10:21:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:21:13 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:21:13 INFO - 'SYSTEMDRIVE': 'C:', 10:21:13 INFO - 'SYSTEMROOT': 'C:\\windows', 10:21:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:13 INFO - 'TEST1': 'testie', 10:21:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:13 INFO - 'USERDOMAIN': 'T-W732-IX-045', 10:21:13 INFO - 'USERNAME': 'cltbld', 10:21:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:21:13 INFO - 'WINDIR': 'C:\\windows', 10:21:13 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:21:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:21:13 INFO - Ignoring indexes: http://pypi.python.org/simple/ 10:21:13 INFO - Downloading/unpacking pip>=1.5 10:21:18 INFO - Running setup.py egg_info for package pip 10:21:18 INFO - warning: no files found matching 'pip\cacert.pem' 10:21:18 INFO - warning: no files found matching '*.html' under directory 'docs' 10:21:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 10:21:18 INFO - no previously-included directories found matching 'docs\_build\_sources' 10:21:18 INFO - Installing collected packages: pip 10:21:18 INFO - Found existing installation: pip 0.8.2 10:21:18 INFO - Uninstalling pip: 10:21:18 INFO - Successfully uninstalled pip 10:21:18 INFO - Running setup.py install for pip 10:21:18 INFO - warning: no files found matching 'pip\cacert.pem' 10:21:18 INFO - warning: no files found matching '*.html' under directory 'docs' 10:21:18 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 10:21:18 INFO - no previously-included directories found matching 'docs\_build\_sources' 10:21:18 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 10:21:18 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 10:21:18 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 10:21:18 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 10:21:18 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 10:21:18 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 10:21:18 INFO - Successfully installed pip 10:21:18 INFO - Cleaning up... 10:21:18 INFO - Return code: 0 10:21:18 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 10:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:18 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01827640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01861E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0187AE90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-045', 'USERDOMAIN': 'T-W732-IX-045', '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-045', '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 10:21:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in C:\slave\test\build 10:21:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:21:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:21:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:21:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:21:18 INFO - 'COMPUTERNAME': 'T-W732-IX-045', 10:21:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:21:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:21:18 INFO - 'DCLOCATION': 'SCL3', 10:21:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:21:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:21:18 INFO - 'HOMEDRIVE': 'C:', 10:21:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:21:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:21:18 INFO - 'KTS_VERSION': '1.19c', 10:21:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:21:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-045', 10:21:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:21:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:18 INFO - 'MOZ_NO_REMOTE': '1', 10:21:18 INFO - 'NO_EM_RESTART': '1', 10:21:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:21:18 INFO - 'OS': 'Windows_NT', 10:21:18 INFO - 'OURDRIVE': 'C:', 10:21:18 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:21:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:21:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:21:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:21:18 INFO - 'PROCESSOR_LEVEL': '6', 10:21:18 INFO - 'PROCESSOR_REVISION': '1e05', 10:21:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:21:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:21:18 INFO - 'PROMPT': '$P$G', 10:21:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:21:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:21:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:21:18 INFO - 'PWD': 'C:\\slave\\test', 10:21:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:21:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:21:18 INFO - 'SYSTEMDRIVE': 'C:', 10:21:18 INFO - 'SYSTEMROOT': 'C:\\windows', 10:21:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:18 INFO - 'TEST1': 'testie', 10:21:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:18 INFO - 'USERDOMAIN': 'T-W732-IX-045', 10:21:18 INFO - 'USERNAME': 'cltbld', 10:21:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:21:18 INFO - 'WINDIR': 'C:\\windows', 10:21:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:21:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:21:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:21 INFO - Downloading/unpacking psutil>=3.1.1 10:21: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 10:21: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 10:21:22 INFO - Installing collected packages: psutil 10:21:22 INFO - Successfully installed psutil 10:21:22 INFO - Cleaning up... 10:21:22 INFO - Return code: 0 10:21:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 10:21:22 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')]} 10:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:22 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')]} 10:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:22 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 0x01827640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01861E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0187AE90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-045', 'USERDOMAIN': 'T-W732-IX-045', '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-045', '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 10:21:22 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 10:21:22 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 10:21:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:21:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:21:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:21:22 INFO - 'COMPUTERNAME': 'T-W732-IX-045', 10:21:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:21:22 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:21:22 INFO - 'DCLOCATION': 'SCL3', 10:21:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:21:22 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:21:22 INFO - 'HOMEDRIVE': 'C:', 10:21:22 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:21:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:21:22 INFO - 'KTS_VERSION': '1.19c', 10:21:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:21:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-045', 10:21:22 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:21:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:22 INFO - 'MOZ_NO_REMOTE': '1', 10:21:22 INFO - 'NO_EM_RESTART': '1', 10:21:22 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:21:22 INFO - 'OS': 'Windows_NT', 10:21:22 INFO - 'OURDRIVE': 'C:', 10:21:22 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;', 10:21:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:21:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:21:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:21:22 INFO - 'PROCESSOR_LEVEL': '6', 10:21:22 INFO - 'PROCESSOR_REVISION': '1e05', 10:21:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:21:22 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:21:22 INFO - 'PROMPT': '$P$G', 10:21:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:21:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:21:22 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:21:22 INFO - 'PWD': 'C:\\slave\\test', 10:21:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:21:22 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:21:22 INFO - 'SYSTEMDRIVE': 'C:', 10:21:22 INFO - 'SYSTEMROOT': 'C:\\windows', 10:21:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:22 INFO - 'TEST1': 'testie', 10:21:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:22 INFO - 'USERDOMAIN': 'T-W732-IX-045', 10:21:22 INFO - 'USERNAME': 'cltbld', 10:21:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:21:22 INFO - 'WINDIR': 'C:\\windows', 10:21:22 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:21:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:21:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:21:22 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 10:21:22 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 10:21:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:21:24 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 10:21:24 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) 10:21:24 INFO - Installing collected packages: mozsystemmonitor 10:21:24 INFO - Running setup.py install for mozsystemmonitor 10:21:24 INFO - Successfully installed mozsystemmonitor 10:21:24 INFO - Cleaning up... 10:21:24 INFO - Return code: 0 10:21:24 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 10:21: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')]} 10:21:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21: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')]} 10:21:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:24 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 0x01827640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01861E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0187AE90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-045', 'USERDOMAIN': 'T-W732-IX-045', '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-045', '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 10:21:24 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 10:21:24 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 10:21:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:21:24 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:21:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:21:24 INFO - 'COMPUTERNAME': 'T-W732-IX-045', 10:21:24 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:21:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:21:24 INFO - 'DCLOCATION': 'SCL3', 10:21:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:21:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:21:24 INFO - 'HOMEDRIVE': 'C:', 10:21:24 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:21:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:21:24 INFO - 'KTS_VERSION': '1.19c', 10:21:24 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:21:24 INFO - 'LOGONSERVER': '\\\\T-W732-IX-045', 10:21:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:21:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:21:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:24 INFO - 'MOZ_NO_REMOTE': '1', 10:21:24 INFO - 'NO_EM_RESTART': '1', 10:21:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:21:24 INFO - 'OS': 'Windows_NT', 10:21:24 INFO - 'OURDRIVE': 'C:', 10:21: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;', 10:21:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:21:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:21:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:21:24 INFO - 'PROCESSOR_LEVEL': '6', 10:21:24 INFO - 'PROCESSOR_REVISION': '1e05', 10:21:24 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:21:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:21:24 INFO - 'PROMPT': '$P$G', 10:21:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:21:24 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:21:24 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:21:24 INFO - 'PWD': 'C:\\slave\\test', 10:21:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:21:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:21:24 INFO - 'SYSTEMDRIVE': 'C:', 10:21:24 INFO - 'SYSTEMROOT': 'C:\\windows', 10:21:24 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:24 INFO - 'TEST1': 'testie', 10:21:24 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:24 INFO - 'USERDOMAIN': 'T-W732-IX-045', 10:21:24 INFO - 'USERNAME': 'cltbld', 10:21:24 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:21:24 INFO - 'WINDIR': 'C:\\windows', 10:21:24 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:21:24 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:21:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:21:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:24 INFO - Downloading/unpacking jsonschema==2.5.1 10:21:24 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 10:21:24 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 10:21:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:21:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:21:25 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 10:21:25 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 10:21:26 INFO - Downloading functools32-3.2.3-2.tar.gz 10:21:26 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 10:21:26 INFO - warning: no files found matching '*.txt' 10:21:26 INFO - no previously-included directories found matching 'build' 10:21:26 INFO - no previously-included directories found matching 'dist' 10:21:26 INFO - no previously-included directories found matching '.git*' 10:21:26 INFO - Installing collected packages: jsonschema, functools32 10:21:26 INFO - Running setup.py install for functools32 10:21:26 INFO - warning: no files found matching '*.txt' 10:21:26 INFO - no previously-included directories found matching 'build' 10:21:26 INFO - no previously-included directories found matching 'dist' 10:21:26 INFO - no previously-included directories found matching '.git*' 10:21:26 INFO - Successfully installed jsonschema functools32 10:21:26 INFO - Cleaning up... 10:21:26 INFO - Return code: 0 10:21:26 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 10:21:26 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')]} 10:21:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:26 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')]} 10:21:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:26 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 0x01827640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01861E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0187AE90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-045', 'USERDOMAIN': 'T-W732-IX-045', '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-045', '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 10:21:26 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 10:21:26 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 10:21:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:21:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:21:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:21:26 INFO - 'COMPUTERNAME': 'T-W732-IX-045', 10:21:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:21:26 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:21:26 INFO - 'DCLOCATION': 'SCL3', 10:21:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:21:26 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:21:26 INFO - 'HOMEDRIVE': 'C:', 10:21:26 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:21:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:21:26 INFO - 'KTS_VERSION': '1.19c', 10:21:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:21:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-045', 10:21:26 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:21:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:21:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:26 INFO - 'MOZ_NO_REMOTE': '1', 10:21:26 INFO - 'NO_EM_RESTART': '1', 10:21:26 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:21:26 INFO - 'OS': 'Windows_NT', 10:21:26 INFO - 'OURDRIVE': 'C:', 10:21:26 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;', 10:21:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:21:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:21:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:21:26 INFO - 'PROCESSOR_LEVEL': '6', 10:21:26 INFO - 'PROCESSOR_REVISION': '1e05', 10:21:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:21:26 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:21:26 INFO - 'PROMPT': '$P$G', 10:21:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:21:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:21:26 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:21:26 INFO - 'PWD': 'C:\\slave\\test', 10:21:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:21:26 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:21:26 INFO - 'SYSTEMDRIVE': 'C:', 10:21:26 INFO - 'SYSTEMROOT': 'C:\\windows', 10:21:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:26 INFO - 'TEST1': 'testie', 10:21:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:26 INFO - 'USERDOMAIN': 'T-W732-IX-045', 10:21:26 INFO - 'USERNAME': 'cltbld', 10:21:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:21:26 INFO - 'WINDIR': 'C:\\windows', 10:21:26 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:21:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:21:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 10:21:27 INFO - Cleaning up... 10:21:27 INFO - Return code: 0 10:21:27 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 10:21:27 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')]} 10:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:27 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')]} 10:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:27 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 0x01827640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01861E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0187AE90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-045', 'USERDOMAIN': 'T-W732-IX-045', '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-045', '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 10:21:27 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 10:21:27 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 10:21:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:21:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:21:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:21:27 INFO - 'COMPUTERNAME': 'T-W732-IX-045', 10:21:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:21:27 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:21:27 INFO - 'DCLOCATION': 'SCL3', 10:21:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:21:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:21:27 INFO - 'HOMEDRIVE': 'C:', 10:21:27 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:21:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:21:27 INFO - 'KTS_VERSION': '1.19c', 10:21:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:21:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-045', 10:21:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:21:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:27 INFO - 'MOZ_NO_REMOTE': '1', 10:21:27 INFO - 'NO_EM_RESTART': '1', 10:21:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:21:27 INFO - 'OS': 'Windows_NT', 10:21:27 INFO - 'OURDRIVE': 'C:', 10:21:27 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;', 10:21:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:21:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:21:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:21:27 INFO - 'PROCESSOR_LEVEL': '6', 10:21:27 INFO - 'PROCESSOR_REVISION': '1e05', 10:21:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:21:27 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:21:27 INFO - 'PROMPT': '$P$G', 10:21:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:21:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:21:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:21:27 INFO - 'PWD': 'C:\\slave\\test', 10:21:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:21:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:21:27 INFO - 'SYSTEMDRIVE': 'C:', 10:21:27 INFO - 'SYSTEMROOT': 'C:\\windows', 10:21:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:27 INFO - 'TEST1': 'testie', 10:21:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:27 INFO - 'USERDOMAIN': 'T-W732-IX-045', 10:21:27 INFO - 'USERNAME': 'cltbld', 10:21:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:21:27 INFO - 'WINDIR': 'C:\\windows', 10:21:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:21:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:21:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:27 INFO - Downloading/unpacking blobuploader==1.2.4 10:21:27 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 10:21:27 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 10:21:28 INFO - Downloading blobuploader-1.2.4.tar.gz 10:21:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 10:21:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:28 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 10:21:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:21:29 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 10:21:29 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 10:21:29 INFO - Downloading docopt-0.6.1.tar.gz 10:21:29 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 10:21:30 INFO - Installing collected packages: blobuploader, requests, docopt 10:21:30 INFO - Running setup.py install for blobuploader 10:21:30 INFO - Running setup.py install for requests 10:21:30 INFO - Running setup.py install for docopt 10:21:30 INFO - Successfully installed blobuploader requests docopt 10:21:30 INFO - Cleaning up... 10:21:32 INFO - Return code: 0 10:21:32 INFO - Installing None into virtualenv C:\slave\test\build/venv 10:21:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:32 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 0x01827640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01861E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0187AE90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-045', 'USERDOMAIN': 'T-W732-IX-045', '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-045', '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 10:21:32 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 10:21:32 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 10:21:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:21:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:21:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:21:32 INFO - 'COMPUTERNAME': 'T-W732-IX-045', 10:21:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:21:32 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:21:32 INFO - 'DCLOCATION': 'SCL3', 10:21:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:21:32 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:21:32 INFO - 'HOMEDRIVE': 'C:', 10:21:32 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:21:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:21:32 INFO - 'KTS_VERSION': '1.19c', 10:21:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:21:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-045', 10:21:32 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:21:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:21:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:32 INFO - 'MOZ_NO_REMOTE': '1', 10:21:32 INFO - 'NO_EM_RESTART': '1', 10:21:32 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:21:32 INFO - 'OS': 'Windows_NT', 10:21:32 INFO - 'OURDRIVE': 'C:', 10:21:32 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:21:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:21:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:21:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:21:32 INFO - 'PROCESSOR_LEVEL': '6', 10:21:32 INFO - 'PROCESSOR_REVISION': '1e05', 10:21:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:21:32 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:21:32 INFO - 'PROMPT': '$P$G', 10:21:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:21:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:21:32 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:21:32 INFO - 'PWD': 'C:\\slave\\test', 10:21:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:21:32 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:21:32 INFO - 'SYSTEMDRIVE': 'C:', 10:21:32 INFO - 'SYSTEMROOT': 'C:\\windows', 10:21:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:32 INFO - 'TEST1': 'testie', 10:21:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:32 INFO - 'USERDOMAIN': 'T-W732-IX-045', 10:21:32 INFO - 'USERNAME': 'cltbld', 10:21:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:21:32 INFO - 'WINDIR': 'C:\\windows', 10:21:32 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:21:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:21:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:21:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 10:21:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tiiqvk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 10:21:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 10:21:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gtkgx8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 10:21:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 10:21:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fyh8fo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 10:21:32 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 10:21:32 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-egjdgk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 10:21:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 10:21:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ituo1g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 10:21:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 10:21:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ji3xw1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 10:21:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 10:21:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sznyzw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 10:21:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 10:21:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h8qjfz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 10:21:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 10:21:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5smcd9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 10:21:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 10:21:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_ouhrw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 10:21:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 10:21:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hxidqt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 10:21:33 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 10:21:33 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-strlvm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 10:21:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 10:21:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vf1wup-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 10:21:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 10:21:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qnjyos-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 10:21:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 10:21:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hancch-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 10:21:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 10:21:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sipi2o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 10:21:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 10:21:34 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6uy8lx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 10:21:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:21:34 INFO - Running setup.py install for manifestparser 10:21:34 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 10:21:34 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 10:21:35 INFO - Running setup.py install for mozcrash 10:21:35 INFO - Running setup.py install for mozdebug 10:21:36 INFO - Running setup.py install for mozdevice 10:21:36 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 10:21:36 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 10:21:36 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 10:21:36 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 10:21:36 INFO - Running setup.py install for mozfile 10:21:37 INFO - Running setup.py install for mozhttpd 10:21:37 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 10:21:37 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 10:21:37 INFO - Running setup.py install for mozinfo 10:21:37 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 10:21:37 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 10:21:37 INFO - Running setup.py install for mozInstall 10:21:37 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 10:21:37 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 10:21:37 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 10:21:37 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 10:21:37 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 10:21:37 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 10:21:37 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 10:21:37 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 10:21:38 INFO - Running setup.py install for mozleak 10:21:38 INFO - Running setup.py install for mozlog 10:21:38 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 10:21:38 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 10:21:39 INFO - Running setup.py install for moznetwork 10:21:39 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 10:21:39 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 10:21:39 INFO - Running setup.py install for mozprocess 10:21:40 INFO - Running setup.py install for mozprofile 10:21:40 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 10:21:40 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 10:21:40 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 10:21:40 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 10:21:40 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 10:21:40 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 10:21:40 INFO - Running setup.py install for mozrunner 10:21:41 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 10:21:41 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 10:21:41 INFO - Running setup.py install for mozscreenshot 10:21:42 INFO - Running setup.py install for moztest 10:21:42 INFO - Running setup.py install for mozversion 10:21:42 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 10:21:42 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 10:21:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:21:42 INFO - Cleaning up... 10:21:42 INFO - Return code: 0 10:21:42 INFO - Installing None into virtualenv C:\slave\test\build/venv 10:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:42 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 0x01827640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01861E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0187AE90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-045', 'USERDOMAIN': 'T-W732-IX-045', '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-045', '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 10:21:43 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 10:21:43 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 10:21:43 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:21:43 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:21:43 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:21:43 INFO - 'COMPUTERNAME': 'T-W732-IX-045', 10:21:43 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:21:43 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:21:43 INFO - 'DCLOCATION': 'SCL3', 10:21:43 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:21:43 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:21:43 INFO - 'HOMEDRIVE': 'C:', 10:21:43 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:21:43 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:21:43 INFO - 'KTS_VERSION': '1.19c', 10:21:43 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:21:43 INFO - 'LOGONSERVER': '\\\\T-W732-IX-045', 10:21:43 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:21:43 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:21:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:43 INFO - 'MOZ_NO_REMOTE': '1', 10:21:43 INFO - 'NO_EM_RESTART': '1', 10:21:43 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:21:43 INFO - 'OS': 'Windows_NT', 10:21:43 INFO - 'OURDRIVE': 'C:', 10:21: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;', 10:21:43 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:21:43 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:21:43 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:21:43 INFO - 'PROCESSOR_LEVEL': '6', 10:21:43 INFO - 'PROCESSOR_REVISION': '1e05', 10:21:43 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:21:43 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:21:43 INFO - 'PROMPT': '$P$G', 10:21:43 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:21:43 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:21:43 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:21:43 INFO - 'PWD': 'C:\\slave\\test', 10:21:43 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:21:43 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:21:43 INFO - 'SYSTEMDRIVE': 'C:', 10:21:43 INFO - 'SYSTEMROOT': 'C:\\windows', 10:21:43 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:43 INFO - 'TEST1': 'testie', 10:21:43 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:43 INFO - 'USERDOMAIN': 'T-W732-IX-045', 10:21:43 INFO - 'USERNAME': 'cltbld', 10:21:43 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:21:43 INFO - 'WINDIR': 'C:\\windows', 10:21:43 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:21:43 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:21:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:21:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 10:21:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_ohhlj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 10:21:43 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)) 10:21:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 10:21:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-b551on-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 10:21:43 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)) 10:21:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 10:21:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lyuryx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 10:21:43 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)) 10:21:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 10:21:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rs1vic-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 10:21:43 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)) 10:21:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 10:21:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hdbeaa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 10:21:44 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)) 10:21:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 10:21:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mjol_u-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 10:21:44 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)) 10:21:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 10:21:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-q15pnk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 10:21:44 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)) 10:21:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 10:21:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7lgmx0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 10:21:44 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)) 10:21:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 10:21:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nx0avc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 10:21:44 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)) 10:21:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 10:21:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ygdzag-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 10:21:44 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)) 10:21:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 10:21:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dwnq_f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 10:21:44 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)) 10:21:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 10:21:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zitsyc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 10:21:45 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)) 10:21:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 10:21:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k7qrw3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 10:21:45 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)) 10:21:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 10:21:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-x6l3nr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 10:21:45 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)) 10:21:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 10:21:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3womub-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 10:21:45 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)) 10:21:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 10:21:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-no9coo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 10:21:45 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)) 10:21:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 10:21:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-diagyg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 10:21:45 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)) 10:21:45 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)) 10:21:45 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)) 10:21:45 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)) 10:21:45 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)) 10:21:45 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)) 10:21:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 10:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:21:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:21:46 INFO - Downloading blessings-1.6.tar.gz 10:21:46 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 10:21:47 INFO - Installing collected packages: blessings 10:21:47 INFO - Running setup.py install for blessings 10:21:47 INFO - Successfully installed blessings 10:21:47 INFO - Cleaning up... 10:21:50 INFO - Return code: 0 10:21:50 INFO - Done creating virtualenv C:\slave\test\build/venv. 10:21:50 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 10:21:50 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 10:21:50 INFO - Reading from file tmpfile_stdout 10:21:50 INFO - Using _rmtree_windows ... 10:21:50 INFO - Using _rmtree_windows ... 10:21:50 INFO - Current package versions: 10:21:50 INFO - blessings == 1.6 10:21:50 INFO - blobuploader == 1.2.4 10:21:50 INFO - docopt == 0.6.1 10:21:50 INFO - functools32 == 3.2.3-2 10:21:50 INFO - jsonschema == 2.5.1 10:21:50 INFO - manifestparser == 1.1 10:21:50 INFO - mozInstall == 1.12 10:21:50 INFO - mozcrash == 0.17 10:21:50 INFO - mozdebug == 0.1 10:21:50 INFO - mozdevice == 0.48 10:21:50 INFO - mozfile == 1.2 10:21:50 INFO - mozhttpd == 0.7 10:21:50 INFO - mozinfo == 0.9 10:21:50 INFO - mozleak == 0.1 10:21:50 INFO - mozlog == 3.4 10:21:50 INFO - moznetwork == 0.27 10:21:50 INFO - mozprocess == 0.23 10:21:50 INFO - mozprofile == 0.28 10:21:50 INFO - mozrunner == 6.12 10:21:50 INFO - mozscreenshot == 0.1 10:21:50 INFO - mozsystemmonitor == 0.3 10:21:50 INFO - moztest == 0.7 10:21:50 INFO - mozversion == 1.4 10:21:50 INFO - psutil == 3.1.1 10:21:50 INFO - requests == 1.2.3 10:21:50 INFO - Installing None into virtualenv C:\slave\test\build/venv 10:21:50 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')]} 10:21:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:50 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')]} 10:21:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:50 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 0x01827640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01861E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0187AE90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-045', 'USERDOMAIN': 'T-W732-IX-045', '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-045', '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 10:21:50 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 10:21:50 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 10:21:50 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:21:50 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:21:50 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:21:50 INFO - 'COMPUTERNAME': 'T-W732-IX-045', 10:21:50 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:21:50 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:21:50 INFO - 'DCLOCATION': 'SCL3', 10:21:50 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:21:50 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:21:50 INFO - 'HOMEDRIVE': 'C:', 10:21:50 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:21:50 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:21:50 INFO - 'KTS_VERSION': '1.19c', 10:21:50 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:21:50 INFO - 'LOGONSERVER': '\\\\T-W732-IX-045', 10:21:50 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:21:50 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:21:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:50 INFO - 'MOZ_NO_REMOTE': '1', 10:21:50 INFO - 'NO_EM_RESTART': '1', 10:21:50 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:21:50 INFO - 'OS': 'Windows_NT', 10:21:50 INFO - 'OURDRIVE': 'C:', 10:21:50 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;', 10:21:50 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:21:50 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:21:50 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:21:50 INFO - 'PROCESSOR_LEVEL': '6', 10:21:50 INFO - 'PROCESSOR_REVISION': '1e05', 10:21:50 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:21:50 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:21:50 INFO - 'PROMPT': '$P$G', 10:21:50 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:21:50 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:21:50 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:21:50 INFO - 'PWD': 'C:\\slave\\test', 10:21:50 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:21:50 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:21:50 INFO - 'SYSTEMDRIVE': 'C:', 10:21:50 INFO - 'SYSTEMROOT': 'C:\\windows', 10:21:50 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:50 INFO - 'TEST1': 'testie', 10:21:50 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:50 INFO - 'USERDOMAIN': 'T-W732-IX-045', 10:21:50 INFO - 'USERNAME': 'cltbld', 10:21:50 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:21:50 INFO - 'WINDIR': 'C:\\windows', 10:21:50 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:21:50 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:21:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:51 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)) 10:21:51 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)) 10:21:51 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)) 10:21:51 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)) 10:21:51 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)) 10:21:51 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)) 10:21:51 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)) 10:21:51 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)) 10:21:51 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)) 10:21:51 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)) 10:21:51 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)) 10:21:51 INFO - Cleaning up... 10:21:51 INFO - Return code: 0 10:21:51 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 10:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:21:51 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01827640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01861E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017439B0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0187AE90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018E7520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-045', 'USERDOMAIN': 'T-W732-IX-045', '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-045', '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 10:21:51 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in C:\slave\test\build 10:21:51 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:21:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:21:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:21:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:21:51 INFO - 'COMPUTERNAME': 'T-W732-IX-045', 10:21:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:21:51 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:21:51 INFO - 'DCLOCATION': 'SCL3', 10:21:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:21:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:21:51 INFO - 'HOMEDRIVE': 'C:', 10:21:51 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:21:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:21:51 INFO - 'KTS_VERSION': '1.19c', 10:21:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:21:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-045', 10:21:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:21:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:21:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:51 INFO - 'MOZ_NO_REMOTE': '1', 10:21:51 INFO - 'NO_EM_RESTART': '1', 10:21:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:21:51 INFO - 'OS': 'Windows_NT', 10:21:51 INFO - 'OURDRIVE': 'C:', 10:21:51 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 10:21:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:21:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:21:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:21:51 INFO - 'PROCESSOR_LEVEL': '6', 10:21:51 INFO - 'PROCESSOR_REVISION': '1e05', 10:21:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:21:51 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:21:51 INFO - 'PROMPT': '$P$G', 10:21:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:21:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:21:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:21:51 INFO - 'PWD': 'C:\\slave\\test', 10:21:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:21:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:21:51 INFO - 'SYSTEMDRIVE': 'C:', 10:21:51 INFO - 'SYSTEMROOT': 'C:\\windows', 10:21:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:51 INFO - 'TEST1': 'testie', 10:21:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:51 INFO - 'USERDOMAIN': 'T-W732-IX-045', 10:21:51 INFO - 'USERNAME': 'cltbld', 10:21:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:21:51 INFO - 'WINDIR': 'C:\\windows', 10:21:51 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:21:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:21:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:21:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:21:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 10:21:51 INFO - Cleaning up... 10:21:51 INFO - Return code: 0 10:21:51 INFO - Running post-action listener: _resource_record_post_action 10:21:51 INFO - Running post-action listener: _start_resource_monitoring 10:21:51 INFO - Starting resource monitoring. 10:21:51 INFO - [mozharness: 2016-09-27 17:21:51.615000Z] Finished create-virtualenv step (success) 10:21:51 INFO - [mozharness: 2016-09-27 17:21:51.615000Z] Running install step. 10:21:51 INFO - Running pre-action listener: _resource_record_pre_action 10:21:51 INFO - Running main action method: install 10:21:51 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 10:21:51 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 10:21:51 INFO - Reading from file tmpfile_stdout 10:21:51 INFO - Using _rmtree_windows ... 10:21:51 INFO - Using _rmtree_windows ... 10:21:51 INFO - Detecting whether we're running mozinstall >=1.0... 10:21:51 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 10:21:51 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 10:21:52 INFO - Reading from file tmpfile_stdout 10:21:52 INFO - Output received: 10:21:52 INFO - Usage: mozinstall-script.py [options] installer 10:21:52 INFO - Options: 10:21:52 INFO - -h, --help show this help message and exit 10:21:52 INFO - -d DEST, --destination=DEST 10:21:52 INFO - Directory to install application into. [default: 10:21:52 INFO - "C:\slave\test"] 10:21:52 INFO - --app=APP Application being installed. [default: firefox] 10:21:52 INFO - Using _rmtree_windows ... 10:21:52 INFO - Using _rmtree_windows ... 10:21:52 INFO - mkdir: C:\slave\test\build\application 10:21:52 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'] 10:21:52 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 10:21:57 INFO - Reading from file tmpfile_stdout 10:21:57 INFO - Output received: 10:21:57 INFO - C:\slave\test\build\application\firefox\firefox.exe 10:21:57 INFO - Using _rmtree_windows ... 10:21:57 INFO - Using _rmtree_windows ... 10:21:57 INFO - Running post-action listener: _resource_record_post_action 10:21:57 INFO - [mozharness: 2016-09-27 17:21:57.290000Z] Finished install step (success) 10:21:57 INFO - [mozharness: 2016-09-27 17:21:57.291000Z] Running run-tests step. 10:21:57 INFO - Running pre-action listener: _resource_record_pre_action 10:21:57 INFO - Running main action method: run_tests 10:21:57 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 10:21:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 10:21:57 INFO - Python 2.7.3 10:21:57 INFO - Return code: 0 10:21:57 INFO - grabbing minidump binary from tooltool 10:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:21:57 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 0x0187AE90>, '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 0x0127A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x018E7520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 10:21:57 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 10:21:57 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 10:21:57 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 10:21:57 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 10:21:58 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpziju9r 10:21:58 INFO - INFO - File integrity verified, renaming tmpziju9r to win32-minidump_stackwalk.exe 10:21:58 INFO - Return code: 0 10:21:58 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 10:21:58 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 10:21:58 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 10:21:58 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 10:21:58 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 10:21:58 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/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-045', '--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 10:21:58 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/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-045 --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 10:21:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 10:21:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 10:21:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 10:21:58 INFO - 'COMPUTERNAME': 'T-W732-IX-045', 10:21:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 10:21:58 INFO - 'CYGWINBASE': 'C:\\cygwin', 10:21:58 INFO - 'DCLOCATION': 'SCL3', 10:21:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 10:21:58 INFO - 'FP_NO_HOST_CHECK': 'NO', 10:21:58 INFO - 'HOMEDRIVE': 'C:', 10:21:58 INFO - 'HOMEPATH': '\\Users\\cltbld', 10:21:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 10:21:58 INFO - 'KTS_VERSION': '1.19c', 10:21:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 10:21:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-045', 10:21:58 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 10:21:58 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 10:21:58 INFO - 'MONDIR': 'C:\\Monitor_config\\', 10:21:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 10:21:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:21:58 INFO - 'MOZ_NO_REMOTE': '1', 10:21:58 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 10:21:58 INFO - 'NO_EM_RESTART': '1', 10:21:58 INFO - 'NUMBER_OF_PROCESSORS': '8', 10:21:58 INFO - 'OS': 'Windows_NT', 10:21:58 INFO - 'OURDRIVE': 'C:', 10:21:58 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;', 10:21:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 10:21:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 10:21:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 10:21:58 INFO - 'PROCESSOR_LEVEL': '6', 10:21:58 INFO - 'PROCESSOR_REVISION': '1e05', 10:21:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 10:21:58 INFO - 'PROGRAMFILES': 'C:\\Program Files', 10:21:58 INFO - 'PROMPT': '$P$G', 10:21:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 10:21:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 10:21:58 INFO - 'PUBLIC': 'C:\\Users\\Public', 10:21:58 INFO - 'PWD': 'C:\\slave\\test', 10:21:58 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 10:21:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 10:21:58 INFO - 'SLAVEDIR': 'C:\\slave\\', 10:21:58 INFO - 'SYSTEMDRIVE': 'C:', 10:21:58 INFO - 'SYSTEMROOT': 'C:\\windows', 10:21:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:58 INFO - 'TEST1': 'testie', 10:21:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 10:21:58 INFO - 'USERDOMAIN': 'T-W732-IX-045', 10:21:58 INFO - 'USERNAME': 'cltbld', 10:21:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 10:21:58 INFO - 'WINDIR': 'C:\\windows', 10:21:58 INFO - 'WINDOWS_TRACING_FLAGS': '3', 10:21:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 10:21:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 10:21:58 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/Oo-zrII-Th6RIIYS6JNEJw/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-045', '--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 10:21:59 INFO - mozversion application_buildid: 20160927080853 10:21:59 INFO - mozversion application_changeset: bf002e13d8072fe5c81289e02c8344455d6ce406 10:21:59 INFO - mozversion application_display_name: Nightly 10:21:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:21:59 INFO - mozversion application_name: Firefox 10:21:59 INFO - mozversion application_remotingname: firefox 10:21:59 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 10:21:59 INFO - mozversion application_vendor: Mozilla 10:21:59 INFO - mozversion application_version: 52.0a1 10:21:59 INFO - mozversion platform_buildid: 20160927080853 10:21:59 INFO - mozversion platform_changeset: bf002e13d8072fe5c81289e02c8344455d6ce406 10:21:59 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 10:21:59 INFO - mozversion platform_version: 52.0a1 10:21:59 INFO - using testdate: 1474996919 10:21:59 INFO - actual date: 1474996919 10:21:59 INFO - starting webserver on 'localhost:49325' 10:21:59 INFO - SUITE-START | Running 2 tests 10:21:59 INFO - TEST-START | dromaeo_css 10:21:59 INFO - operating with platform_type : w7_ 10:21:59 INFO - Initialising browser for dromaeo_css test... 10:21:59 INFO - TEST-INFO | started process 1672 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmprvykli\profile http://localhost:49325/getInfo.html) 10:22:07 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 10:22:07 INFO - PROCESS | 1672 | colorDepth:24 10:22:07 INFO - PROCESS | 1672 | Browser inner width/height: 1010/674 10:22:07 INFO - PROCESS | 1672 | __metrics 10:22:08 INFO - PROCESS | 1672 | 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" 10:22:08 INFO - PROCESS | 1672 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:22:09 INFO - TEST-INFO | 1672: exit 0 10:22:09 INFO - Browser initialized. 10:22:09 INFO - Running cycle 1/1 for dromaeo_css test... 10:22:09 INFO - TEST-INFO | started process 3456 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmprvykli\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:22:09 INFO - PROCESS | 3456 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 10:22:20 INFO - PROCESS | 3456 | RSS: Main: 139464704 10:22:20 INFO - PROCESS | 3456 | 10:23:12 INFO - PROCESS | 3456 | 1474996992432 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] 10:25:25 INFO - PROCESS | 3456 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49325/tests/dromaeo/cssquery-ext.html) 10:25:26 INFO - PROCESS | 3456 | RSS: Main: 177311744 10:25:26 INFO - PROCESS | 3456 | 10:28:29 INFO - PROCESS | 3456 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-ext.html (next: http://localhost:49325/tests/dromaeo/cssquery-jquery.html) 10:28:29 INFO - PROCESS | 3456 | RSS: Main: 204103680 10:28:29 INFO - PROCESS | 3456 | 10:31:32 INFO - PROCESS | 3456 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49325/tests/dromaeo/cssquery-mootools.html) 10:31:32 INFO - PROCESS | 3456 | RSS: Main: 144932864 10:31:32 INFO - PROCESS | 3456 | 10:34:34 INFO - PROCESS | 3456 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49325/tests/dromaeo/cssquery-prototype.html) 10:34:35 INFO - PROCESS | 3456 | RSS: Main: 142397440 10:34:35 INFO - PROCESS | 3456 | 10:37:38 INFO - PROCESS | 3456 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49325/tests/dromaeo/cssquery-yui.html) 10:37:39 INFO - PROCESS | 3456 | RSS: Main: 140152832 10:37:39 INFO - PROCESS | 3456 | 10:40:41 INFO - PROCESS | 3456 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-yui.html (next: http://localhost:49325/tests/dromaeo/cssquery-dojo.html) 10:40:42 INFO - PROCESS | 3456 | RSS: Main: 194191360 10:40:42 INFO - PROCESS | 3456 | 10:40:42 INFO - PROCESS | 3456 | __start_tp_report 10:40:42 INFO - PROCESS | 3456 | _x_x_mozilla_page_load 10:40:42 INFO - PROCESS | 3456 | _x_x_mozilla_page_load_details 10:40:42 INFO - PROCESS | 3456 | |i|pagename|runs| 10:40:42 INFO - PROCESS | 3456 | |0;dojo.html;2343.5781559319325;2380.0598763488415;2393.0786647140844;2395.0898658509764;2397.820163487738;3911.2373087247997;3977.9005524861823;3987.1626958338747;4054.7161698681066;4079.734817236881;3890.941635875457;3896.2791883501573;4074.6740260779143;4099.446574712413;4183.686223533239;3487.808170550625;3491.9301613967705;3531.170175008871;3622.4385220290897;3635.509206257161;3547.219611685425;3584.31897939392;3615.5842078160954;3618.8781477741986;3682.963170368302;3450.1719587298994;3508.0177550285835;3554.92890142197;3591.964080359189;3592.371335016375;3499.7025252853537;3534.452159915217;3551.7158627309755;3594.5327107476123;3602.171500554861;3513.771604845677;3568.6074531801482;3603.963960360389;3614.1868079682126;3620.674139327447;3901.2782635212575;3949.2299001694682;4001.519817621875;4005.959940400588;4010.578889216649;4126.91746165078;4164.083901541656;4192.161567686475;4297.462817147856;4361.6074553290355;4154.397612346193;4242.53332133465;4274.465691788527;4289.249381358281;4326.762028088454;3971.1065010373013;3974.423708562242;3979.2240513100246;4000.5599384067727;4008.9198216036098;4096.713230073866;4196.853110141192;4240.427542281753;4323.524412314643;4335.154644844225;3990.1221731219666;4078.1435898461064;4100.405441211008;4117.07365842689;4180.331147016463;3907.238088572758;4208.894777630584;4274.166537525152;4338.436003319548;4342.5657434256955;4124.628783409507;4133.8966525836495;4176.874693759192;4197.9370309445385;4233.5131459881895;4169.145325208386;4226.323788193874;4232.449781528381;4235.30117530604;4330.480342358937;3061.1275786401216;3083.5066389377594;3084.892028778982;3095.442820292324;3109.0828205679377;2711.227300219428;2744.7529722325085;2753.821001634887;2756.9586456203174;2776.208780497589;3915.47141135943;3988.900277493086;3990.8204130814183;3993.84024639017;4021.4369988201674;4041.8989525261513;4116.9794151029055;4128.298189307765;4190.350495673176;4199.160167966419;3839.404892241707;3922.725409221327;3932.3315036443873;4020.4974378202724;4037.959620403817;3488.337215928965;3540.805255710909;3597.1007248187952;3641.217138315275;3658.743887927873;4317;4341.196878577473;4366.934495982499;4369.497507786646;4401.207782599163;3972.980135099306;4127.906104882149;4204.810783514688;4223.95776042248;4331.371951067141;4363.629091527256;4373.540778218241;4458.041521072985;4469.3966314548425;4477.619402350837;4125.876223713293;4136.234796562646;4175.5824417558;4230.365445183247;4266.786660667016;4192.790360481903;4208.621224089847;4244.087521182961;4262.786860657016;4274.187904298174;3693.5752388474593;4022.4770779798437;4138.586141385837;4147.502299723932;4152.5432202457105;3950.3284441645565;3978.224246272065;4063.187362527447;4144.943039524902;4149.854755083557;3906.570277269555;3976.3240249158293;4022.7787471688166;4048.6558642515715;4080.5307389649383;3894;4043.251998380309;4085.714000020089;4097.713160078885;4186.83252669902;4034.1326614777963;4074.8573799918045;4079.449274347925;4080.7959602020373;4122.154958233495 10:40:42 INFO - PROCESS | 3456 | |1;ext.html;529.2484671766094;535.9035373632747;541.9024575576395;549.8982688202681;557.6375356018585;25305.24084277472;36255.81872090639;36449.63550364495;36522.63477365226;40346.59653403465;38011.619883801155;38316;38391.232175356476;38393.80803095981;38431.231375372474;6422.2614399343975;7257.600831954241;7431.29402706742;7479;7518.285762852548;6409.391107844771;9322.580483878208;9511.619535218582;9569.521523923811;9919.355241909252;9904.900950990506;10052.597896084184;10143.036411540928;10156.831964324123;10158.187345012382;9603.4718090505;9644.517774111266;9651.613935442609;9669.564869580816;9920.007999200094;6213.720382582795;6428.389303016206;9219.769505762342;9410.623575057058;9589.952050239774;35028.12429689278;35038.12404689903;35041.29917401663;35211;35301.29397412037;2718;2724.7002829688718;2794.23158631378;2969.747571456429;3207.278362368472;26636;26700.332491687863;26724.46551068968;26725;26970.865145674146;3185.2833112549724;3230.2247460609283;3269.8365081746056;3322.18606441421;3349.43059679855;217496.99999999843;217596.2961481539;217669;221168;221607;44588.3311750324;44665;44677.724123451786;44714;44805;26126;26712.465750684878;46373.76813115913;46874.29688554674;46917;11159.497822598003;11508.539658413738;11552.306861588331;11704.356260405759;11724.472398742077;7226.963865180745;8515.403921725543;8524.31805455562;8546.78633034179;8550.74347769568;13132.409041593151;13563;13623.659408514866;13623.727525449436;15186.240687965557;6335.588186767845;6366.395192456709;6436.517261205428;6486.702659468031;6521.510886683518;782.5891407011364;852.4416507187802;867.4881819726392;904.2946501728662;934.4907025671025;24243.757562424504;25489.49021019569;25627;26033;26068.217953461426;923.579771204114;947.6256878533079;994.105305225303;994.8806143262855;996.1482932092956;13424.798628020588;13518.137412908067;13593.184408935496;13889.58331250044;13907.235102069484;145613;309735;309877;310738;311505;44435.55564444379;44582;44839.327410088874;45193.32210016852;45265.54734452613;1089.515165751233;1099.6371197504805;1121.876593574706;1124.1793490751634;1125.6735546691368;24548.263552093467;24782.628260575744;24971;24995.250142495755;25363.365915851515;37910.62089379071;38082.61917380901;38848.6115138845;39243.80378098205;39284.214315714096;277241;278073;278441;279145;279641;18329.3584724534;18806.905965470087;20347.898260508602;21479.140834367074;21762.782372176076;35231.82384088063;37682.62317376791;37897.81051094727;38010.42984355292;38378.232435350576;31440;32358.83820580882;32367.838160809046;32732;33809.3238135231;32293.51559726559;32828.34343313168;33114.834425827714;33141.66858331386;33233.66766332306;33735;34459.13852153716;34482.82758586191;34588;34638.82680586581;28666.713332866406;30187.396252074395;30444.543331850484;30712.232194195323;30867.382652347278;22981.195658151886;23049.308520744406;23086.42283942915;23705.64441533413;23836.16573419922 10:40:42 INFO - PROCESS | 3456 | |2;jquery.html;285.384995335053;299.97000299970006;310.7125908534605;320.5896452540747;328.56793316788435;11464.770704585902;12392.38038098096;15577.221138943065;16141.192940352994;16287.51137465878;14482.420703171887;15112.546623601282;15333.769993450107;15355.846441535581;15547.68904621907;4907.582855457291;5120.0527902338;5143.485651434864;5146.794128234867;5148.176291793314;3466.3587236361227;3820.3123437781187;4935.062338155739;5036.848894533152;5050.797968081272;3660.9450858237146;5941.28704555454;5986.580939334249;6012.308584512772;6047.123167140784;6048.576599638027;6067.089936509559;6067.696615169224;6069.939300606981;6071.301800292957;4096.201240758053;4532.138893610204;4549.818007279705;4566.634669226454;4569.9314510282375;10408.375497470177;10485.633002844865;10727.249092563523;10750.247482676215;10750.892491075145;1587.8332775058568;1604.462505060806;1636.3618188906337;1641.7291146960738;1650.9009459432473;7921.881171782428;8398.916010839874;8457.450265732768;9249.583768730423;12644.241345519205;1553.5727674889383;1707.4536148432508;1819.5542092187366;1859.460756380648;1872.9719054214197;61211.69394153001;61589;67049.3295067048;67662.66168669125;67785.66107169482;8375.45559538628;8416.873746893802;8634.568271586395;8912.197902188835;9099.636014559475;10267.845982310271;10460.843087353696;11131.888681113247;11261.155413344031;11265.324080555192;6145.938540614626;6219.191505104308;6301.684915754194;6345.460635846005;6375.776847810304;6309.716062777186;6344.397282258178;6347.079673447324;6348.682565871781;6372.4583410409705;3488.180277634754;3540.309639620301;3564.269324788412;3604.4773507841214;3627.981860090683;1516.9848301516843;1553.184578096486;1565.1939251285596;1578.6344811737993;1594.8006499187602;314.6963180530799;315;321.45031995287854;711.1750369571273;805.770355448706;8590.269827064769;8813.867791983126;8844.911550884408;8894.199522042918;8905.465672059698;340.7767912017632;343.2465737705712;348.7123123423186;349.4583395736598;350.03565177934627;4641.814327426865;4701.294805779161;4733.202714088394;4740.762961851894;4837.129316722953;275029;277457;278194;279373;280950;76406.6179669098;76488;76544.61727691325;76918;77705;342.1617038256282;346.70010440968883;348.10710527497395;348.2999171664921;348.62522788002997;8380.748577542683;8613.569321533772;8652.740417787476;8714.172153645393;8983;36312;36421.81789091037;36921.63078369182;37094.258114837015;38061.00359462217;48263.034739305716;50638.746806265735;51094.74452627713;52044;52315.47684523106;6274.372562743689;6399.424051835357;6539.247986481616;6542.640154791532;6569.080328753883;9964.352317099365;10617.256792024793;10642.787144256916;11018.228723989243;11273.436328183721;9186.632534698538;9224.861627075734;9325.766855828659;9409.341346105706;9658.130768230892;8535.743927682179;9143.314251431302;9159.542022898962;9185.311101667536;9286.582103805209;8880.378373513791;9077.636894524145;9429.52852357393;9451.621935122517;9493.718348022929;6032.3967603239325;6325.905111423241;6527.934720652733;6573.112629795108;7011.509194356346;7838.294553490213;8853.247473964786;8959.955200223958;9078.54607269647;9340.53297335144 10:40:42 INFO - PROCESS | 3456 | |3;mootools.html;1101.8732845722745;1243.3658833994664;1265.740523192745;1293.3210064716022;1485.5617592810113;5709.6574205547695;6635.170603674541;6833.282505336942;6855.21165066017;6862.279460656634;6708.798736037927;6768.999999999987;6939.514234003622;6950.7914762557075;6952.930470695292;1829.4145873320542;1835.128314050822;1861.2275905499203;1862.068965517238;1885.7077153041232;1772.6100257943233;1813.2293775145577;1817.4093419638605;1831.9816801832012;1839.3470318037105;1823.4073925974046;1830.2495976649577;1831.597048649295;1836.7704036995376;1838.9540261493437;1758.2175931710394;1760.8239176082418;1779.590694140355;1781.4121339957783;1798.7751531058618;1747.860171186302;1756.9546120058544;1758.1209395302349;1778.8310110539608;1784.125778368603;6742.2920593337485;6769.289224631441;6788.796336109925;6804.149481314836;6847.589144651337;3789.6020917803785;3830.425436184567;3864.942025869586;3925.980370098131;3935.822887970048;2416.0698131219433;2451.816113791455;2454.349597356684;2460.7785299323145;2476.5913624251975;757.8825282081286;761.3110135327537;791;795.9761207163795;797.7885860246981;8588.742337729816;8644;8655.567221638892;8809.207171354545;8915.821683566292;3325.1687078230443;3327.2014716467875;3388.3053973935284;3397.2356219850335;3401.098708842159;6981.162260528769;7000.439964802804;7075.540089894141;7076.186238582526;7152.213256541776;3613.7831730096277;3659.5608526976935;3690.704743620504;3720;3741.644543768338;3056.5109582467144;3161.0516844946424;3439.1402149462633;3455.930881382358;3456.4815277708044;2727.7817774577984;2764.419471910881;2783.832970021805;2833.150054983497;2858.1425572328217;864.2567392042838;866.9003064647646;869.5869462005497;881.2333270055093;885.0087901550305;768.4313607930092;787.4173111897269;795.3597354129981;795.5266616363255;795.7016118955391;4340.934885976713;4603.9079218415445;4611.93082103762;4627.976860115745;4697.718136911796;786.897703298579;802.827392110699;830.6345208108413;839.9832003359899;841.4362377207286;2959.9112026639236;2989.4768415527196;3031.3782126562182;3067.601211842491;3074.9692503074684;7881.881771773428;8185.959070204611;8231.09457959624;8282.296004839536;8385.371097167616;8334.958325208336;8378.581070946428;8383.371247156487;8384.203500667427;8415.032271288757;6120.540959428061;6334.334894835976;6453.451456626145;6497.577657452251;6514.771982980573;4469.195544801967;4547.499775024811;4550.544945505423;4554.453465584151;4703.082898834695;7290.599017054113;7436.851262974602;7611.619419029137;7655.311022008046;7867.881981770163;7916.564588947663;7993.440459167802;8022.83954320922;8023.117457079833;8048.235417635316;5347.438518955453;5437.619366644297;5546.362168350692;5674.319081710221;5677.886442271214;6200.3179650239335;6352.9047064295;6542.476601871934;6670.666466676744;6680.732770689118;5436.7281635918835;5506.972465137648;5640.097584386478;5729.8567535811935;5764.279465066867;5570.944290557042;5583.35791383996;5637.098064309691;5646.096624540054;5649.830505084854;5360.571154307568;5471.206675032177;5491.505764481216;5521.1442226454965;5521.668699878021;3909;3915.138669492707;3924.117073658404;3958.3072962232072;4037.37420699792;3769.8115094245727;3815.389537673959;3817.484639573733;3847.345641374124;3875.2055828555644 10:40:42 INFO - PROCESS | 3456 | |4;prototype.html;234.126707381467;235.74068524622916;244.8396300423221;245.9581871081916;247.1177894915153;4314.180305741911;4316.2878125109355;4366.956330436695;4382.649388048956;4389.9025243689075;4058.898527536813;4129.215449064675;4166.229247589191;4228.72513286637;4248.767857321373;3171.730402915755;3171.8255495947656;3178.698023687746;3188.457962146441;3246.155999440151;3230.8869189578368;3236;3245.0913744151558;3252.8210948397827;3254.8698052078007;3035.467089119991;3120.812751234922;3165.509346051366;3192.9680703192903;3204.3270913108277;3170.508571171472;3197.0408877336704;3208.769904916084;3208.887688930888;3210.197450637341;2720.469508445854;2741.409982210333;2741.9588706169425;2774.5283301838535;2799.748022677969;4132.958670413287;4253.553376895412;4269.573042695737;4274.926660569621;4283.957160428387;3688.2992231475932;3704.9255715842373;3709.9443508347395;3718.4608231806506;3754.2303827715255;4199.370094485821;4370.125974805052;4413.3159360299205;4438.378626992224;4492.910141797146;3058;3073.4325493998;3078.045805800209;3080.7227349538425;3084.5835812165296;4584.701994370355;4638.327442520849;4663.556962088596;4674.789634466423;4678.596421073698;3057.6914429605504;3112.3152906360565;3120.8127512349333;3139.9686003139677;3140.1678555182893;3095.5820964169548;3101.17818778024;3105.627324721048;3165.3036332006923;3173.7461003119697;3556.772913344892;3685.668289853926;3690.815459227028;3699.9260014800093;3714.9442758358646;4419.718281698336;4424.247877860771;4441.555844415597;4454.977725111354;4465.419495465634;1032.4528000159928;1057.6351158850184;1069;1071.12703146935;1076.617800680747;694.7776711452286;695.9942882534689;698.0576222100226;701.866485625721;705.1855107351017;4224.281872081692;4251.298535741568;4258.5954334338185;4336.154449882242;4348.956250499858;4498.730076195438;4521.276595744665;4580.656450766206;4610.216263235258;4610.677252592286;4244.87265382039;4258.510271318776;4329.134173165379;4336.76147811867;4340.370646256275;2310.688057112302;2397.6523404106306;2417.335232810956;2429.7608219808026;2458.188797696756;4709.293605959065;4730.195866702668;4801.68789028712;4822.95177048232;4826.686265392738;4661.720296782136;4692.272697731857;4705.694129881546;4755.334253204554;4761.952370478489;4701.999999999932;4729.219678753036;4736.3949827594;4829.56107165886;4847.297141914402;4437.844675436344;4594.47163576193;4693.5071817458675;4703.247480403119;4737.24204127338;4540.387047748512;4548.566516913435;4573.839915602938;4591.403117594691;4650.790714417927;4750;4759.214729569582;4838.419389673261;4864.537868902448;4874.512548745239;3108.1452600535035;3128.499440089575;3132.3265497918055;3182.777205595586;3186.2671585534995;4316.52518222989;4574.353232836243;4586.4266966629175;4590.793414296298;4682.367880336111;4416.514183439884;4417.933730994102;4432.512423633462;4443.466783985942;4462.44219472566;4400.955990440055;4462.687611867138;4474.574915383034;4513.571210734975;4516.50318464962;4346.500152482378;4355.49911760139;4361;4410;4462.107578484381;4436.689431739747;4550.476695179964;4570.748608826547;4576.4737055239075;4585.81656733727;4392.4289842320295;4400.9119817604105;4422.270325396274;4448.354988526581;4500.954990450054 10:40:42 INFO - PROCESS | 3456 | |5;yui.html;579.7565022690469;586.8146344384344;593.9109133629955;608.7869245763982;660.8017594721584;10912.672619821398;12047.036237101032;12061.396930153502;12063.638090857288;12376.381180940962;11734.178607497479;11877.930986211239;11890.94054529726;11959.402029898514;12279.324637144953;629.232336549409;648.5978693210217;657.2737125476351;659.0378048049853;659.5548005096565;503.7556784959292;512.6462740708918;632.4339715954242;632.9303776584572;635.0727937211676;662.7978466567695;679.2188982669919;691;691.2672567078887;696.5994553131944;670.9597424154542;672.9461643068543;674.5042393840523;679.3308591037824;691.312144416309;388.5609261534476;390.65231943570245;483.37161689803116;504.4929845505244;504.58371843229116;10588.417637029961;11042.227044106916;11061.778764424747;11089.33463992163;11098.44507774608;11188.776224475547;11237.719057023558;11290.604828831034;11537.826932595934;12044.156909016285;10613.099847122085;10852.60504369135;10867.155908342118;10934.617288394948;11201.159913006475;3843.423486477023;3915.843366265346;3921.901952451183;3946.940795888064;3958.6239307265764;61483.38516614866;61926.69036654788;62764.68617656928;64099.67950160266;64176.999999999534;9027.14242146995;9133.40632858862;9196.63213471467;9219;9302.302327325478;17255;17289.740653890203;17366.913165434093;17776.822231777518;17804.732929006077;6302.275238347649;6330.987042073246;6374.776882809172;6380.234371875312;6592.637404942679;3463.2808774439254;3530.5410296661785;3541.8937431877084;3584.7490675652975;3689.0777305673582;2338.088145623208;2374.1690408356935;2377.595808712523;2443.2425947955835;2444.9144279950115;414.8299197329081;420.53950923738455;420.90529630832816;420.9600087991636;430.0302817147313;501.2331133365883;520.8049704200569;541.6343967821783;544.354939396816;550.9834704958857;7003;7005.229424763374;7249.565026098346;7265.818354541178;7467.551946883204;419.6957206025607;514.7143335448861;531.1209947536832;544.0104450005468;571.5627544928134;3344.7324214062814;3378.1216883610437;3416.419208734521;3429.9142521436665;3529.1353618363664;88714;92940.53529732308;94031.52984235172;94032.54430474639;94187;63164.36835631585;64214;68391;79741.60129199317;81351.18648813554;65165;65969.67015164894;66418;66920.6653966727;68689;7235.457340699363;7272.527285726411;7341.74303899361;7466.216047314953;7589.962050189713;39607.40588891227;40732.389014165405;40989.59010409977;41426.585734143475;42110.157796844505;55770.442295576526;59091;60178.69910650419;60181;60419.39580604138;10120.696379108638;10267.897321026694;10313.22650801178;10431;10450.529726162189;11528.59649912249;11675.824862626898;11701.76596468083;11866.347350895438;11869.881301187223;10387.584496620051;10402.271840971362;10615.734606634896;10632.096271816672;10806.891931080903;7116.433166441272;9845;10299.897001029894;10386.948065259927;10755.139588833032;10543.472826358804;10715.142788576739;10978.670639880816;11101.222914395914;11261.324320540794;9390.530473476163;9392.060793920553;9543.04569543068;9569.665061722806;9634.470104144339;8986.696928945135;8987.910120898969;9090.272778177863;9093.727188184364;9199 10:40:42 INFO - PROCESS | 3456 | __end_tp_report 10:40:42 INFO - PROCESS | 3456 | __start_cc_report 10:40:42 INFO - PROCESS | 3456 | _x_x_mozilla_cycle_collect,826 10:40:42 INFO - PROCESS | 3456 | __end_cc_report 10:40:42 INFO - PROCESS | 3456 | __startTimestamp1474998042060__endTimestamp 10:40:42 INFO - PROCESS | 3456 | 10:40:42 INFO - PROCESS | 3456 | ------- Summary: start ------- 10:40:42 INFO - PROCESS | 3456 | Number of tests: 6 10:40:42 INFO - PROCESS | 3456 | 10:40:42 INFO - PROCESS | 3456 | [#0] dojo.html Cycles:165 Average:3902.07 Median:4041.90 stddev:454.15 (11.2%) stddev-sans-first:438.78 10:40:42 INFO - PROCESS | 3456 | Values: 2343.6 2380.1 2393.1 2395.1 2397.8 3911.2 3977.9 3987.2 4054.7 4079.7 3890.9 3896.3 4074.7 4099.4 4183.7 3487.8 3491.9 3531.2 3622.4 3635.5 3547.2 3584.3 3615.6 3618.9 3683.0 3450.2 3508.0 3554.9 3592.0 3592.4 3499.7 3534.5 3551.7 3594.5 3602.2 3513.8 3568.6 3604.0 3614.2 3620.7 3901.3 3949.2 4001.5 4006.0 4010.6 4126.9 4164.1 4192.2 4297.5 4361.6 4154.4 4242.5 4274.5 4289.2 4326.8 3971.1 3974.4 3979.2 4000.6 4008.9 4096.7 4196.9 4240.4 4323.5 4335.2 3990.1 4078.1 4100.4 4117.1 4180.3 3907.2 4208.9 4274.2 4338.4 4342.6 4124.6 4133.9 4176.9 4197.9 4233.5 4169.1 4226.3 4232.4 4235.3 4330.5 3061.1 3083.5 3084.9 3095.4 3109.1 2711.2 2744.8 2753.8 2757.0 2776.2 3915.5 3988.9 3990.8 3993.8 4021.4 4041.9 4117.0 4128.3 4190.4 4199.2 3839.4 3922.7 3932.3 4020.5 4038.0 3488.3 3540.8 3597.1 3641.2 3658.7 4317.0 4341.2 4366.9 4369.5 4401.2 3973.0 4127.9 4204.8 4224.0 4331.4 4363.6 4373.5 4458.0 4469.4 4477.6 4125.9 4136.2 4175.6 4230.4 4266.8 4192.8 4208.6 4244.1 4262.8 4274.2 3693.6 4022.5 4138.6 4147.5 4152.5 3950.3 3978.2 4063.2 4144.9 4149.9 3906.6 3976.3 4022.8 4048.7 4080.5 3894.0 4043.3 4085.7 4097.7 4186.8 4034.1 4074.9 4079.4 4080.8 4122.2 10:40:42 INFO - PROCESS | 3456 | 10:40:42 INFO - PROCESS | 3456 | [#1] ext.html Cycles:180 Average:40165.39 Median:18542.22 stddev:68812.91 (371.1%) stddev-sans-first:68941.60 10:40:42 INFO - PROCESS | 3456 | Values: 529.2 535.9 541.9 549.9 557.6 25305.2 36255.8 36449.6 36522.6 40346.6 38011.6 38316.0 38391.2 38393.8 38431.2 6422.3 7257.6 7431.3 7479.0 7518.3 6409.4 9322.6 9511.6 9569.5 9919.4 9904.9 10052.6 10143.0 10156.8 10158.2 9603.5 9644.5 9651.6 9669.6 9920.0 6213.7 6428.4 9219.8 9410.6 9590.0 35028.1 35038.1 35041.3 35211.0 35301.3 2718.0 2724.7 2794.2 2969.7 3207.3 26636.0 26700.3 26724.5 26725.0 26970.9 3185.3 3230.2 3269.8 3322.2 3349.4 217497.0 217596.3 217669.0 221168.0 221607.0 44588.3 44665.0 44677.7 44714.0 44805.0 26126.0 26712.5 46373.8 46874.3 46917.0 11159.5 11508.5 11552.3 11704.4 11724.5 7227.0 8515.4 8524.3 8546.8 8550.7 13132.4 13563.0 13623.7 13623.7 15186.2 6335.6 6366.4 6436.5 6486.7 6521.5 782.6 852.4 867.5 904.3 934.5 24243.8 25489.5 25627.0 26033.0 26068.2 923.6 947.6 994.1 994.9 996.1 13424.8 13518.1 13593.2 13889.6 13907.2 145613.0 309735.0 309877.0 310738.0 311505.0 44435.6 44582.0 44839.3 45193.3 45265.5 1089.5 1099.6 1121.9 1124.2 1125.7 24548.3 24782.6 24971.0 24995.3 25363.4 37910.6 38082.6 38848.6 39243.8 39284.2 277241.0 278073.0 278441.0 279145.0 279641.0 18329.4 18806.9 20347.9 21479.1 21762.8 35231.8 37682.6 37897.8 38010.4 38378.2 31440.0 32358.8 32367.8 32732.0 33809.3 32293.5 32828.3 33114.8 33141.7 33233.7 33735.0 34459.1 34482.8 34588.0 34638.8 28666.7 30187.4 30444.5 30712.2 30867.4 22981.2 23049.3 23086.4 23705.6 23836.2 10:40:42 INFO - PROCESS | 3456 | 10:40:42 INFO - PROCESS | 3456 | [#2] jquery.html Cycles:180 Average:19655.25 Median:27844.77 stddev:47085.94 (169.1%) stddev-sans-first:47195.57 10:40:42 INFO - PROCESS | 3456 | Values: 285.4 300.0 310.7 320.6 328.6 11464.8 12392.4 15577.2 16141.2 16287.5 14482.4 15112.5 15333.8 15355.8 15547.7 4907.6 5120.1 5143.5 5146.8 5148.2 3466.4 3820.3 4935.1 5036.8 5050.8 3660.9 5941.3 5986.6 6012.3 6047.1 6048.6 6067.1 6067.7 6069.9 6071.3 4096.2 4532.1 4549.8 4566.6 4569.9 10408.4 10485.6 10727.2 10750.2 10750.9 1587.8 1604.5 1636.4 1641.7 1650.9 7921.9 8398.9 8457.5 9249.6 12644.2 1553.6 1707.5 1819.6 1859.5 1873.0 61211.7 61589.0 67049.3 67662.7 67785.7 8375.5 8416.9 8634.6 8912.2 9099.6 10267.8 10460.8 11131.9 11261.2 11265.3 6145.9 6219.2 6301.7 6345.5 6375.8 6309.7 6344.4 6347.1 6348.7 6372.5 3488.2 3540.3 3564.3 3604.5 3628.0 1517.0 1553.2 1565.2 1578.6 1594.8 314.7 315.0 321.5 711.2 805.8 8590.3 8813.9 8844.9 8894.2 8905.5 340.8 343.2 348.7 349.5 350.0 4641.8 4701.3 4733.2 4740.8 4837.1 275029.0 277457.0 278194.0 279373.0 280950.0 76406.6 76488.0 76544.6 76918.0 77705.0 342.2 346.7 348.1 348.3 348.6 8380.7 8613.6 8652.7 8714.2 8983.0 36312.0 36421.8 36921.6 37094.3 38061.0 48263.0 50638.7 51094.7 52044.0 52315.5 6274.4 6399.4 6539.2 6542.6 6569.1 9964.4 10617.3 10642.8 11018.2 11273.4 9186.6 9224.9 9325.8 9409.3 9658.1 8535.7 9143.3 9159.5 9185.3 9286.6 8880.4 9077.6 9429.5 9451.6 9493.7 6032.4 6325.9 6527.9 6573.1 7011.5 7838.3 8853.2 8960.0 9078.5 9340.5 10:40:42 INFO - PROCESS | 3456 | 10:40:42 INFO - PROCESS | 3456 | [#3] mootools.html Cycles:180 Average:4341.14 Median:5499.24 stddev:2457.51 (44.7%) stddev-sans-first:2452.35 10:40:42 INFO - PROCESS | 3456 | Values: 1101.9 1243.4 1265.7 1293.3 1485.6 5709.7 6635.2 6833.3 6855.2 6862.3 6708.8 6769.0 6939.5 6950.8 6952.9 1829.4 1835.1 1861.2 1862.1 1885.7 1772.6 1813.2 1817.4 1832.0 1839.3 1823.4 1830.2 1831.6 1836.8 1839.0 1758.2 1760.8 1779.6 1781.4 1798.8 1747.9 1757.0 1758.1 1778.8 1784.1 6742.3 6769.3 6788.8 6804.1 6847.6 3789.6 3830.4 3864.9 3926.0 3935.8 2416.1 2451.8 2454.3 2460.8 2476.6 757.9 761.3 791.0 796.0 797.8 8588.7 8644.0 8655.6 8809.2 8915.8 3325.2 3327.2 3388.3 3397.2 3401.1 6981.2 7000.4 7075.5 7076.2 7152.2 3613.8 3659.6 3690.7 3720.0 3741.6 3056.5 3161.1 3439.1 3455.9 3456.5 2727.8 2764.4 2783.8 2833.2 2858.1 864.3 866.9 869.6 881.2 885.0 768.4 787.4 795.4 795.5 795.7 4340.9 4603.9 4611.9 4628.0 4697.7 786.9 802.8 830.6 840.0 841.4 2959.9 2989.5 3031.4 3067.6 3075.0 7881.9 8186.0 8231.1 8282.3 8385.4 8335.0 8378.6 8383.4 8384.2 8415.0 6120.5 6334.3 6453.5 6497.6 6514.8 4469.2 4547.5 4550.5 4554.5 4703.1 7290.6 7436.9 7611.6 7655.3 7867.9 7916.6 7993.4 8022.8 8023.1 8048.2 5347.4 5437.6 5546.4 5674.3 5677.9 6200.3 6352.9 6542.5 6670.7 6680.7 5436.7 5507.0 5640.1 5729.9 5764.3 5570.9 5583.4 5637.1 5646.1 5649.8 5360.6 5471.2 5491.5 5521.1 5521.7 3909.0 3915.1 3924.1 3958.3 4037.4 3769.8 3815.4 3817.5 3847.3 3875.2 10:40:42 INFO - PROCESS | 3456 | 10:40:42 INFO - PROCESS | 3456 | [#4] prototype.html Cycles:180 Average:3730.29 Median:4336.46 stddev:1146.67 (26.4%) stddev-sans-first:1119.46 10:40:42 INFO - PROCESS | 3456 | Values: 234.1 235.7 244.8 246.0 247.1 4314.2 4316.3 4367.0 4382.6 4389.9 4058.9 4129.2 4166.2 4228.7 4248.8 3171.7 3171.8 3178.7 3188.5 3246.2 3230.9 3236.0 3245.1 3252.8 3254.9 3035.5 3120.8 3165.5 3193.0 3204.3 3170.5 3197.0 3208.8 3208.9 3210.2 2720.5 2741.4 2742.0 2774.5 2799.7 4133.0 4253.6 4269.6 4274.9 4284.0 3688.3 3704.9 3709.9 3718.5 3754.2 4199.4 4370.1 4413.3 4438.4 4492.9 3058.0 3073.4 3078.0 3080.7 3084.6 4584.7 4638.3 4663.6 4674.8 4678.6 3057.7 3112.3 3120.8 3140.0 3140.2 3095.6 3101.2 3105.6 3165.3 3173.7 3556.8 3685.7 3690.8 3699.9 3714.9 4419.7 4424.2 4441.6 4455.0 4465.4 1032.5 1057.6 1069.0 1071.1 1076.6 694.8 696.0 698.1 701.9 705.2 4224.3 4251.3 4258.6 4336.2 4349.0 4498.7 4521.3 4580.7 4610.2 4610.7 4244.9 4258.5 4329.1 4336.8 4340.4 2310.7 2397.7 2417.3 2429.8 2458.2 4709.3 4730.2 4801.7 4823.0 4826.7 4661.7 4692.3 4705.7 4755.3 4762.0 4702.0 4729.2 4736.4 4829.6 4847.3 4437.8 4594.5 4693.5 4703.2 4737.2 4540.4 4548.6 4573.8 4591.4 4650.8 4750.0 4759.2 4838.4 4864.5 4874.5 3108.1 3128.5 3132.3 3182.8 3186.3 4316.5 4574.4 4586.4 4590.8 4682.4 4416.5 4417.9 4432.5 4443.5 4462.4 4401.0 4462.7 4474.6 4513.6 4516.5 4346.5 4355.5 4361.0 4410.0 4462.1 4436.7 4550.5 4570.7 4576.5 4585.8 4392.4 4400.9 4422.3 4448.4 4501.0 10:40:42 INFO - PROCESS | 3456 | 10:40:42 INFO - PROCESS | 3456 | [#5] yui.html Cycles:180 Average:16355.84 Median:508.61 stddev:23455.93 (4611.7%) stddev-sans-first:23491.82 10:40:42 INFO - PROCESS | 3456 | Values: 579.8 586.8 593.9 608.8 660.8 10912.7 12047.0 12061.4 12063.6 12376.4 11734.2 11877.9 11890.9 11959.4 12279.3 629.2 648.6 657.3 659.0 659.6 503.8 512.6 632.4 632.9 635.1 662.8 679.2 691.0 691.3 696.6 671.0 672.9 674.5 679.3 691.3 388.6 390.7 483.4 504.5 504.6 10588.4 11042.2 11061.8 11089.3 11098.4 11188.8 11237.7 11290.6 11537.8 12044.2 10613.1 10852.6 10867.2 10934.6 11201.2 3843.4 3915.8 3921.9 3946.9 3958.6 61483.4 61926.7 62764.7 64099.7 64177.0 9027.1 9133.4 9196.6 9219.0 9302.3 17255.0 17289.7 17366.9 17776.8 17804.7 6302.3 6331.0 6374.8 6380.2 6592.6 3463.3 3530.5 3541.9 3584.7 3689.1 2338.1 2374.2 2377.6 2443.2 2444.9 414.8 420.5 420.9 421.0 430.0 501.2 520.8 541.6 544.4 551.0 7003.0 7005.2 7249.6 7265.8 7467.6 419.7 514.7 531.1 544.0 571.6 3344.7 3378.1 3416.4 3429.9 3529.1 88714.0 92940.5 94031.5 94032.5 94187.0 63164.4 64214.0 68391.0 79741.6 81351.2 65165.0 65969.7 66418.0 66920.7 68689.0 7235.5 7272.5 7341.7 7466.2 7590.0 39607.4 40732.4 40989.6 41426.6 42110.2 55770.4 59091.0 60178.7 60181.0 60419.4 10120.7 10267.9 10313.2 10431.0 10450.5 11528.6 11675.8 11701.8 11866.3 11869.9 10387.6 10402.3 10615.7 10632.1 10806.9 7116.4 9845.0 10299.9 10386.9 10755.1 10543.5 10715.1 10978.7 11101.2 11261.3 9390.5 9392.1 9543.0 9569.7 9634.5 8986.7 8987.9 9090.3 9093.7 9199.0 10:40:42 INFO - PROCESS | 3456 | -------- Summary: end -------- 10:40:42 INFO - PROCESS | 3456 | 10:40:42 INFO - TEST-INFO | 3456: exit 0 10:40:43 INFO - TEST-OK | dromaeo_css | took 1124058ms 10:40:43 INFO - TEST-START | kraken 10:40:43 INFO - operating with platform_type : w7_ 10:40:43 INFO - Initialising browser for kraken test... 10:40:43 INFO - TEST-INFO | started process 3424 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpuacng1\profile http://localhost:49325/getInfo.html) 10:40:48 INFO - PROCESS | 3424 | __metrics Screen width/height:1600/1200 10:40:48 INFO - PROCESS | 3424 | colorDepth:24 10:40:48 INFO - PROCESS | 3424 | Browser inner width/height: 1010/674 10:40:48 INFO - PROCESS | 3424 | __metrics 10:40:48 INFO - PROCESS | 3424 | 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" 10:40:48 INFO - PROCESS | 3424 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:40:49 INFO - PROCESS | 3424 | console.error: 10:40:49 INFO - PROCESS | 3424 | Message: Error: Connection closed before committing the transaction. 10:40:49 INFO - PROCESS | 3424 | Stack: 10:40:49 INFO - PROCESS | 3424 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 10:40:49 INFO - PROCESS | 3424 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 10:40:49 INFO - PROCESS | 3424 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 10:40:49 INFO - PROCESS | 3424 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 10:40:49 INFO - PROCESS | 3424 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 10:40:49 INFO - PROCESS | 3424 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13 10:40:49 INFO - PROCESS | 3424 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 10:40:49 INFO - PROCESS | 3424 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 10:40:49 INFO - PROCESS | 3424 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 10:40:49 INFO - PROCESS | 3424 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 10:40:49 INFO - PROCESS | 3424 | Barrier/this.client.addBlocker/promise13.2% 10:41:50 INFO - TinderboxPrint: I/O read bytes / time
146,720,768 / 1,191,790 10:41:50 INFO - TinderboxPrint: I/O write bytes / time
477,868,544 / 4,963,110 10:41:50 INFO - TinderboxPrint: CPU idle
8,322.2 (86.8%) 10:41:50 INFO - TinderboxPrint: CPU user
1,222.3 (12.8%) 10:41:50 INFO - install - Wall time: 6s; CPU: 5.0%; Read bytes: 145920; Write bytes: 73360896; Read time: 9330; Write time: 1550670 10:41:50 INFO - run-tests - Wall time: 1193s; CPU: 13.0%; Read bytes: 146574848; Write bytes: 388759552; Read time: 1182460; Write time: 3253540 10:41:50 INFO - Running post-run listener: _upload_blobber_files 10:41:50 INFO - Blob upload gear active. 10:41:50 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 10:41:50 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 10:41:50 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'] 10:41:50 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 10:41:51 INFO - (blobuploader) - INFO - Open directory for files ... 10:41:51 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 10:41:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:41:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:52 INFO - (blobuploader) - INFO - Done attempting. 10:41:52 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 10:41:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:41:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:41:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:41:53 INFO - (blobuploader) - INFO - Done attempting. 10:41:53 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 10:41:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:41:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:42:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:42:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:42:01 INFO - (blobuploader) - INFO - Done attempting. 10:42:01 INFO - (blobuploader) - INFO - Iteration through files over. 10:42:01 INFO - Return code: 0 10:42:01 INFO - rmtree: C:\slave\test\build\uploaded_files.json 10:42:01 INFO - Using _rmtree_windows ... 10:42:01 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 10:42:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ced1093deec2f7ceb56ae116727630d58b0ba62916480017d258a01ada079a672f164f84e842a7adc996deb2dc6488b545ec31fe7d72e9c7ede9f69ecfb0c5e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/21bd791ad96687c3c81986c336aedad34b9239b8b8e45e9106d234458ed434c23392539da6e187d510416d928b94e0d505c88bbf1671cb69e1d7dc3d66335cd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/85706a85fb7be3bbb095d2c2dd87b227b8a9a38a45cb5318b4d362ae64343a1c4cb8c28cad10f053d19b88bec5b740c9e0675816a18b04a769eda5a777753158"} 10:42:01 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 10:42:01 INFO - Writing to file C:\slave\test\properties\blobber_files 10:42:01 INFO - Contents: 10:42:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/ced1093deec2f7ceb56ae116727630d58b0ba62916480017d258a01ada079a672f164f84e842a7adc996deb2dc6488b545ec31fe7d72e9c7ede9f69ecfb0c5e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/21bd791ad96687c3c81986c336aedad34b9239b8b8e45e9106d234458ed434c23392539da6e187d510416d928b94e0d505c88bbf1671cb69e1d7dc3d66335cd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/85706a85fb7be3bbb095d2c2dd87b227b8a9a38a45cb5318b4d362ae64343a1c4cb8c28cad10f053d19b88bec5b740c9e0675816a18b04a769eda5a777753158"} 10:42:01 INFO - Running post-run listener: copy_logs_to_upload_dir 10:42:01 INFO - Copying logs to upload dir... 10:42:01 INFO - mkdir: C:\slave\test\build\upload\logs 10:42:01 INFO - Copying logs to upload dir... 10:42:01 INFO - Using _rmtree_windows ... 10:42:01 INFO - Using _rmtree_windows ... 10:42:01 INFO - Using _rmtree_windows ... 10:42:01 INFO - Using _rmtree_windows ... 10:42:01 INFO - Using _rmtree_windows ... 10:42:01 INFO - Using _rmtree_windows ... 10:42:01 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1506.731000 ========= master_lag: -0.12 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 25 mins, 6 secs) (at 2016-09-27 10:42:01.583635) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 10:42:01.584489) ========= '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-045 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-045 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-045 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/ced1093deec2f7ceb56ae116727630d58b0ba62916480017d258a01ada079a672f164f84e842a7adc996deb2dc6488b545ec31fe7d72e9c7ede9f69ecfb0c5e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/21bd791ad96687c3c81986c336aedad34b9239b8b8e45e9106d234458ed434c23392539da6e187d510416d928b94e0d505c88bbf1671cb69e1d7dc3d66335cd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/85706a85fb7be3bbb095d2c2dd87b227b8a9a38a45cb5318b4d362ae64343a1c4cb8c28cad10f053d19b88bec5b740c9e0675816a18b04a769eda5a777753158"} build_url:https://queue.taskcluster.net/v1/task/Oo-zrII-Th6RIIYS6JNEJw/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/Oo-zrII-Th6RIIYS6JNEJw/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/ced1093deec2f7ceb56ae116727630d58b0ba62916480017d258a01ada079a672f164f84e842a7adc996deb2dc6488b545ec31fe7d72e9c7ede9f69ecfb0c5e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/21bd791ad96687c3c81986c336aedad34b9239b8b8e45e9106d234458ed434c23392539da6e187d510416d928b94e0d505c88bbf1671cb69e1d7dc3d66335cd6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/85706a85fb7be3bbb095d2c2dd87b227b8a9a38a45cb5318b4d362ae64343a1c4cb8c28cad10f053d19b88bec5b740c9e0675816a18b04a769eda5a777753158"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 10:42:01.699010) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 10:42:01.699412) ========= '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-045 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-045 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-045 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-27 10:42:01.811384) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-27 10:42:01.811710) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-27 10:42:02.338437) ========= ========= Total master_lag: 0.08 =========