builder: fx-team_win7_ix_test-dromaeojs slave: t-w732-ix-023 starttime: 1474966485.07 results: success (0) buildid: 20160926233117 builduid: b84b9620f44b4c6b82a9168e022eaed6 revision: df63260c1c90ea8ff0a5a314fbcae9365854abb0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 01:54:45.071191) ========= master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-27 01:54:45.071581) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 01:54:45.071849) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-27 01:54:45.296315) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 01:54:45.296810) ========= '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-023 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-023 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-023 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 --01:54:46-- 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% 14.01 MB/s 01:54:46 (14.01 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.011000 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 01:54:46.334414) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 8 secs) (at 2016-09-27 01:54:46.334699) ========= '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-023 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-023 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-023 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=8.701000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 8 secs) (at 2016-09-27 01:54:55.050284) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 01:54:55.050657) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev df63260c1c90ea8ff0a5a314fbcae9365854abb0 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev df63260c1c90ea8ff0a5a314fbcae9365854abb0 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-023 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-023 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-023 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 01:54:55,415 truncating revision to first 12 chars 2016-09-27 01:54:55,415 Setting DEBUG logging. 2016-09-27 01:54:55,415 attempt 1/10 2016-09-27 01:54:55,415 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/df63260c1c90?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-27 01:54:55,901 unpacking tar archive at: fx-team-df63260c1c90/testing/mozharness/ 2016-09-27 01:54:56,042 skipping symlink on windows: configs/single_locale/linux32.py program finished with exit code 0 elapsedTime=1.197000 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-27 01:54:56.365479) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 01:54:56.365801) ========= script_repo_revision: df63260c1c90ea8ff0a5a314fbcae9365854abb0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-27 01:54:56.366253) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 01:54:56.366526) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-27 01:54:56.405374) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 51 secs) (at 2016-09-27 01:54:56.405752) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team-Non-PGO' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team-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', 'Fx-Team-Non-PGO', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-023 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-023 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-023 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 01:54:56 INFO - MultiFileLogger online at 20160927 01:54:56 in C:\slave\test 01:54:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 01:54:56 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 01:54:56 INFO - {'append_to_log': False, 01:54:56 INFO - 'base_work_dir': 'C:\\slave\\test', 01:54:56 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 01:54:56 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 01:54:56 INFO - 'branch': 'Fx-Team-Non-PGO', 01:54:56 INFO - 'buildbot_json_path': 'buildprops.json', 01:54:56 INFO - 'config_files': ('talos/windows_config.py',), 01:54:56 INFO - 'default_actions': ('clobber', 01:54:56 INFO - 'read-buildbot-config', 01:54:56 INFO - 'download-and-extract', 01:54:56 INFO - 'populate-webroot', 01:54:56 INFO - 'create-virtualenv', 01:54:56 INFO - 'install', 01:54:56 INFO - 'run-tests'), 01:54:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:54:56 INFO - 'download_minidump_stackwalk': True, 01:54:56 INFO - 'download_symbols': 'ondemand', 01:54:56 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 01:54:56 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 01:54:56 INFO - 'hg': 'c:/mozilla-build/hg/hg', 01:54:56 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 01:54:56 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 01:54:56 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 01:54:56 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 01:54:56 INFO - 'C:/mozilla-build/tooltool.py'), 01:54:56 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 01:54:56 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 01:54:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:54:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:54:56 INFO - 'installer_path': 'installer.exe', 01:54:56 INFO - 'log_level': 'info', 01:54:56 INFO - 'log_name': 'talos', 01:54:56 INFO - 'log_to_console': True, 01:54:56 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 01:54:56 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 01:54:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 01:54:56 INFO - 'opt_config_files': (), 01:54:56 INFO - 'pip_index': False, 01:54:56 INFO - 'sps_profile': False, 01:54:56 INFO - 'sps_profile_interval': 0, 01:54:56 INFO - 'suite': 'dromaeojs', 01:54:56 INFO - 'system_bits': '32', 01:54:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:54:56 INFO - 'title': 'T-W732-IX-023', 01:54:56 INFO - 'use_talos_json': True, 01:54:56 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 01:54:56 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 01:54:56 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 01:54:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:54:56 INFO - 'work_dir': 'build'} 01:54:56 INFO - [mozharness: 2016-09-27 08:54:56.938000Z] Running clobber step. 01:54:56 INFO - Running pre-action listener: _resource_record_pre_action 01:54:56 INFO - Running main action method: clobber 01:54:56 INFO - rmtree: C:\slave\test\build 01:54:56 INFO - Using _rmtree_windows ... 01:54:56 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 01:56:40 INFO - Running post-action listener: _resource_record_post_action 01:56:40 INFO - [mozharness: 2016-09-27 08:56:40.852000Z] Finished clobber step (success) 01:56:40 INFO - [mozharness: 2016-09-27 08:56:40.852000Z] Running read-buildbot-config step. 01:56:40 INFO - Running pre-action listener: _resource_record_pre_action 01:56:40 INFO - Running main action method: read_buildbot_config 01:56:40 INFO - Using buildbot properties: 01:56:40 INFO - { 01:56:40 INFO - "project": "", 01:56:40 INFO - "product": "firefox", 01:56:40 INFO - "script_repo_revision": "production", 01:56:40 INFO - "builddir": "fx-team_win7_ix_test-dromaeojs", 01:56:40 INFO - "repository": "", 01:56:40 INFO - "buildername": "Windows 7 32-bit fx-team talos dromaeojs", 01:56:40 INFO - "buildid": "20160926233117", 01:56:40 INFO - "pgo_build": "False", 01:56:40 INFO - "basedir": "C:\\slave\\test", 01:56:40 INFO - "buildnumber": 221, 01:56:40 INFO - "slavename": "t-w732-ix-023", 01:56:40 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/", 01:56:40 INFO - "platform": "win7_ix", 01:56:40 INFO - "scheduler": "tests-fx-team-win32-talos", 01:56:40 INFO - "branch": "fx-team", 01:56:40 INFO - "repo_path": "integration/fx-team", 01:56:40 INFO - "revision": "df63260c1c90ea8ff0a5a314fbcae9365854abb0", 01:56:40 INFO - "stage_platform": "win32", 01:56:40 INFO - "builduid": "b84b9620f44b4c6b82a9168e022eaed6", 01:56:40 INFO - "slavebuilddir": "test" 01:56:40 INFO - } 01:56:40 INFO - Found installer url https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip. 01:56:40 INFO - Running post-action listener: _resource_record_post_action 01:56:40 INFO - [mozharness: 2016-09-27 08:56:40.856000Z] Finished read-buildbot-config step (success) 01:56:40 INFO - [mozharness: 2016-09-27 08:56:40.856000Z] Running download-and-extract step. 01:56:40 INFO - Running pre-action listener: _resource_record_pre_action 01:56:40 INFO - Running main action method: download_and_extract 01:56:40 INFO - mkdir: C:\slave\test\build\tests 01:56: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')]} 01:56:40 INFO - trying https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.test_packages.json 01:56:40 INFO - Downloading https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/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 01:56:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/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 01:56:41 INFO - Downloaded 1274 bytes. 01:56:41 INFO - Reading from file C:\slave\test\build\firefox-52.0a1.en-US.win32.test_packages.json 01:56:41 INFO - Using the following test package requirements: 01:56:41 INFO - {u'common': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 01:56:41 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:56:41 INFO - u'firefox-52.0a1.en-US.win32.cppunittest.tests.zip'], 01:56:41 INFO - u'gtest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:56:41 INFO - u'firefox-52.0a1.en-US.win32.gtest.tests.zip'], 01:56:41 INFO - u'jittest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:56:41 INFO - u'jsshell-win32.zip'], 01:56:41 INFO - u'mochitest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:56:41 INFO - u'firefox-52.0a1.en-US.win32.mochitest.tests.zip'], 01:56:41 INFO - u'mozbase': [u'firefox-52.0a1.en-US.win32.common.tests.zip'], 01:56:41 INFO - u'reftest': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:56:41 INFO - u'firefox-52.0a1.en-US.win32.reftest.tests.zip'], 01:56:41 INFO - u'talos': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:56:41 INFO - u'firefox-52.0a1.en-US.win32.talos.tests.zip'], 01:56:41 INFO - u'web-platform': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:56:41 INFO - u'firefox-52.0a1.en-US.win32.web-platform.tests.zip'], 01:56:41 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.win32.common.tests.zip', 01:56:41 INFO - u'firefox-52.0a1.en-US.win32.xpcshell.tests.zip']} 01:56:41 INFO - Downloading packages: [u'firefox-52.0a1.en-US.win32.common.tests.zip'] for test suite category: common 01:56:41 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 01:56:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 01:56:41 INFO - Fetch https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 01:56:42 INFO - Expected file size: 17320963 01:57:11 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 01:57:11 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip 01:57:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip'}, attempt #1 01:57:11 INFO - Fetch https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.common.tests.zip into memory 01:57:11 INFO - Expected file size: 17320963 01:57:27 INFO - Downloading and extracting to C:\slave\test\build\tests these dirs * from https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip 01:57:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip'}, attempt #1 01:57:27 INFO - Fetch https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.talos.tests.zip into memory 01:57:28 INFO - Expected file size: 14414057 01:57: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')]} 01:57:40 INFO - trying https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 01:57:40 INFO - Downloading https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip to C:\slave\test\build\firefox-52.0a1.en-US.win32.zip 01:57:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/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 01:57:43 INFO - Downloaded 53501050 bytes. 01:57:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 01:57:43 INFO - mkdir: C:\slave\test\properties 01:57:43 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 01:57:43 INFO - Writing to file C:\slave\test\properties\build_url 01:57:43 INFO - Contents: 01:57:43 INFO - build_url:https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip 01:57:44 INFO - Running post-action listener: _resource_record_post_action 01:57:44 INFO - Running post-action listener: set_extra_try_arguments 01:57:44 INFO - [mozharness: 2016-09-27 08:57:44.473000Z] Finished download-and-extract step (success) 01:57:44 INFO - [mozharness: 2016-09-27 08:57:44.473000Z] Running populate-webroot step. 01:57:44 INFO - Running pre-action listener: _resource_record_pre_action 01:57:44 INFO - Running main action method: populate_webroot 01:57:44 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 01:57:44 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 01:57:44 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 01:57:44 INFO - u'--tppagecycles', 01:57:44 INFO - u'7'], 01:57:44 INFO - u'tests': [u'tsvgm']}}, 01:57:44 INFO - u'suites': {u'chromez': {u'talos_options': [u'--disable-e10s'], 01:57:44 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 01:57:44 INFO - u'chromez-e10s': {u'tests': [u'tresize', u'tcanvasmark']}, 01:57:44 INFO - u'dromaeojs': {u'talos_options': [u'--disable-e10s'], 01:57:44 INFO - u'tests': [u'dromaeo_css', u'kraken']}, 01:57:44 INFO - u'dromaeojs-e10s': {u'tests': [u'dromaeo_css', u'kraken']}, 01:57:44 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:44 INFO - u'talos_options': [u'--disable-e10s'], 01:57:44 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 01:57:44 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:44 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 01:57:44 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:44 INFO - u'talos_options': [u'--disable-e10s'], 01:57:44 INFO - u'tests': [u'damp', u'tps']}, 01:57:44 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:44 INFO - u'tests': [u'damp', u'tps']}, 01:57:44 INFO - u'g3': {u'talos_options': [u'--disable-e10s'], 01:57:44 INFO - u'tests': [u'dromaeo_dom']}, 01:57:44 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 01:57:44 INFO - u'g4': {u'talos_options': [u'--disable-e10s'], 01:57:44 INFO - u'tests': [u'basic_compositor_video']}, 01:57:44 INFO - u'g4-e10s': {u'tests': [u'basic_compositor_video']}, 01:57:44 INFO - u'other': {u'talos_options': [u'--disable-e10s'], 01:57:44 INFO - u'tests': [u'a11yr', 01:57:44 INFO - u'ts_paint', 01:57:44 INFO - u'tpaint', 01:57:44 INFO - u'sessionrestore', 01:57:44 INFO - u'sessionrestore_no_auto_restore', 01:57:44 INFO - u'tabpaint']}, 01:57:44 INFO - u'other-e10s': {u'tests': [u'a11yr', 01:57:44 INFO - u'ts_paint', 01:57:44 INFO - u'tpaint', 01:57:44 INFO - u'sessionrestore', 01:57:44 INFO - u'sessionrestore_no_auto_restore', 01:57:44 INFO - u'tabpaint']}, 01:57:44 INFO - u'other-e10s_l64': {u'tests': [u'a11yr', 01:57:44 INFO - u'ts_paint', 01:57:44 INFO - u'tpaint', 01:57:44 INFO - u'sessionrestore', 01:57:44 INFO - u'sessionrestore_no_auto_restore', 01:57:44 INFO - u'tabpaint']}, 01:57:44 INFO - u'other-e10s_nol64': {u'tests': [u'a11yr', 01:57:44 INFO - u'ts_paint', 01:57:44 INFO - u'tpaint', 01:57:44 INFO - u'sessionrestore', 01:57:44 INFO - u'sessionrestore_no_auto_restore', 01:57:44 INFO - u'tabpaint']}, 01:57:44 INFO - u'other_l64': {u'talos_options': [u'--disable-e10s'], 01:57:44 INFO - u'tests': [u'a11yr', 01:57:44 INFO - u'ts_paint', 01:57:44 INFO - u'tpaint', 01:57:44 INFO - u'sessionrestore', 01:57:44 INFO - u'sessionrestore_no_auto_restore', 01:57:44 INFO - u'tabpaint']}, 01:57:44 INFO - u'other_nol64': {u'talos_options': [u'--disable-e10s'], 01:57:44 INFO - u'tests': [u'a11yr', 01:57:44 INFO - u'ts_paint', 01:57:44 INFO - u'tpaint', 01:57:44 INFO - u'sessionrestore', 01:57:44 INFO - u'sessionrestore_no_auto_restore', 01:57:44 INFO - u'tabpaint']}, 01:57:44 INFO - u'svgr': {u'talos_options': [u'--disable-e10s'], 01:57:44 INFO - u'tests': [u'tsvgx', 01:57:44 INFO - u'tsvgr_opacity', 01:57:44 INFO - u'tart', 01:57:44 INFO - u'tscrollx', 01:57:44 INFO - u'cart']}, 01:57:44 INFO - u'svgr-e10s': {u'tests': [u'tsvgx', 01:57:44 INFO - u'tsvgr_opacity', 01:57:44 INFO - u'tart', 01:57:44 INFO - u'tscrollx', 01:57:44 INFO - u'cart']}, 01:57:44 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:44 INFO - u'talos_options': [u'--disable-e10s'], 01:57:44 INFO - u'tests': [u'tp5o']}, 01:57:44 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:44 INFO - u'tests': [u'tp5o']}, 01:57:44 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:44 INFO - u'talos_options': [u'--disable-e10s', 01:57:44 INFO - u'--xperf_path', 01:57:44 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:57:44 INFO - u'tests': [u'tp5n']}, 01:57:44 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:57:44 INFO - u'talos_options': [u'--xperf_path', 01:57:44 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:57:44 INFO - u'tests': [u'tp5n']}}, 01:57:44 INFO - u'talos.zip': {u'path': u'', 01:57:44 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:57:44 INFO - Running post-action listener: _resource_record_post_action 01:57:44 INFO - [mozharness: 2016-09-27 08:57:44.532000Z] Finished populate-webroot step (success) 01:57:44 INFO - [mozharness: 2016-09-27 08:57:44.532000Z] Running create-virtualenv step. 01:57:44 INFO - Running pre-action listener: _resource_record_pre_action 01:57:44 INFO - Running main action method: create_virtualenv 01:57:44 INFO - Creating virtualenv C:\slave\test\build/venv 01:57:44 INFO - mkdir: C:\slave\test\build\venv\Scripts 01:57:44 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 01:57:44 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'! 01:57:44 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 01:57:44 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 01:57:54 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 01:57:58 INFO - Installing distribute......................................................................................................................................................................................done. 01:57:59 INFO - Return code: 0 01:57:59 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', '--version'] 01:57:59 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip --version 01:58:00 INFO - Reading from file tmpfile_stdout 01:58:00 INFO - Output received: 01:58:00 INFO - pip 0.8.2 from c:\slave\test\build\venv\lib\site-packages\pip-0.8.2-py2.7.egg (python 2.7) 01:58:00 INFO - Using _rmtree_windows ... 01:58:00 INFO - Using _rmtree_windows ... 01:58:00 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 01:58:00 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')]} 01:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:00 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')]} 01:58:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:00 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-023', 'USERDOMAIN': 'T-W732-IX-023', '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-023', '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 01:58:00 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 01:58:00 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 01:58:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:58:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:58:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:58:00 INFO - 'COMPUTERNAME': 'T-W732-IX-023', 01:58:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:58:00 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:58:00 INFO - 'DCLOCATION': 'SCL3', 01:58:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:58:00 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:58:00 INFO - 'HOMEDRIVE': 'C:', 01:58:00 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:58:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:58:00 INFO - 'KTS_VERSION': '1.19c', 01:58:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:58:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-023', 01:58:00 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:58:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:58:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:00 INFO - 'MOZ_NO_REMOTE': '1', 01:58:00 INFO - 'NO_EM_RESTART': '1', 01:58:00 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:58:00 INFO - 'OS': 'Windows_NT', 01:58:00 INFO - 'OURDRIVE': 'C:', 01:58:00 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;', 01:58:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:58:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:58:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:58:00 INFO - 'PROCESSOR_LEVEL': '6', 01:58:00 INFO - 'PROCESSOR_REVISION': '1e05', 01:58:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:58:00 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:58:00 INFO - 'PROMPT': '$P$G', 01:58:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:58:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:58:00 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:58:00 INFO - 'PWD': 'C:\\slave\\test', 01:58:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:58:00 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:58:00 INFO - 'SYSTEMDRIVE': 'C:', 01:58:00 INFO - 'SYSTEMROOT': 'C:\\windows', 01:58:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:00 INFO - 'TEST1': 'testie', 01:58:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:00 INFO - 'USERDOMAIN': 'T-W732-IX-023', 01:58:00 INFO - 'USERNAME': 'cltbld', 01:58:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:58:00 INFO - 'WINDIR': 'C:\\windows', 01:58:00 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:58:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:58:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:58:00 INFO - Ignoring indexes: http://pypi.python.org/simple/ 01:58:00 INFO - Downloading/unpacking pip>=1.5 01:58:05 INFO - Running setup.py egg_info for package pip 01:58:05 INFO - warning: no files found matching 'pip\cacert.pem' 01:58:05 INFO - warning: no files found matching '*.html' under directory 'docs' 01:58:05 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 01:58:05 INFO - no previously-included directories found matching 'docs\_build\_sources' 01:58:05 INFO - Installing collected packages: pip 01:58:05 INFO - Found existing installation: pip 0.8.2 01:58:05 INFO - Uninstalling pip: 01:58:05 INFO - Successfully uninstalled pip 01:58:05 INFO - Running setup.py install for pip 01:58:05 INFO - warning: no files found matching 'pip\cacert.pem' 01:58:05 INFO - warning: no files found matching '*.html' under directory 'docs' 01:58:05 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 01:58:05 INFO - no previously-included directories found matching 'docs\_build\_sources' 01:58:05 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 01:58:05 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 01:58:05 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 01:58:05 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 01:58:05 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 01:58:05 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 01:58:05 INFO - Successfully installed pip 01:58:05 INFO - Cleaning up... 01:58:05 INFO - Return code: 0 01:58:05 INFO - Installing psutil>=3.1.1 into virtualenv C:\slave\test\build/venv 01:58:05 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')]} 01:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:05 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')]} 01:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:05 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-023', 'USERDOMAIN': 'T-W732-IX-023', '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-023', '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 01:58:05 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 01:58:05 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 01:58:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:58:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:58:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:58:05 INFO - 'COMPUTERNAME': 'T-W732-IX-023', 01:58:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:58:05 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:58:05 INFO - 'DCLOCATION': 'SCL3', 01:58:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:58:05 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:58:05 INFO - 'HOMEDRIVE': 'C:', 01:58:05 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:58:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:58:05 INFO - 'KTS_VERSION': '1.19c', 01:58:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:58:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-023', 01:58:05 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:58:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:05 INFO - 'MOZ_NO_REMOTE': '1', 01:58:05 INFO - 'NO_EM_RESTART': '1', 01:58:05 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:58:05 INFO - 'OS': 'Windows_NT', 01:58:05 INFO - 'OURDRIVE': 'C:', 01:58:05 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;', 01:58:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:58:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:58:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:58:05 INFO - 'PROCESSOR_LEVEL': '6', 01:58:05 INFO - 'PROCESSOR_REVISION': '1e05', 01:58:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:58:05 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:58:05 INFO - 'PROMPT': '$P$G', 01:58:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:58:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:58:05 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:58:05 INFO - 'PWD': 'C:\\slave\\test', 01:58:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:58:05 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:58:05 INFO - 'SYSTEMDRIVE': 'C:', 01:58:05 INFO - 'SYSTEMROOT': 'C:\\windows', 01:58:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:05 INFO - 'TEST1': 'testie', 01:58:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:05 INFO - 'USERDOMAIN': 'T-W732-IX-023', 01:58:05 INFO - 'USERNAME': 'cltbld', 01:58:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:58:05 INFO - 'WINDIR': 'C:\\windows', 01:58:05 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:58:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:58:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:07 INFO - Downloading/unpacking psutil>=3.1.1 01:58:07 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 01:58:07 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 01:58:08 INFO - Installing collected packages: psutil 01:58:08 INFO - Successfully installed psutil 01:58:08 INFO - Cleaning up... 01:58:08 INFO - Return code: 0 01:58:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv C:\slave\test\build/venv 01:58:08 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')]} 01:58:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:08 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')]} 01:58:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:08 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-023', 'USERDOMAIN': 'T-W732-IX-023', '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-023', '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 01:58:08 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 01:58:08 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 01:58:08 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:58:08 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:58:08 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:58:08 INFO - 'COMPUTERNAME': 'T-W732-IX-023', 01:58:08 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:58:08 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:58:08 INFO - 'DCLOCATION': 'SCL3', 01:58:08 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:58:08 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:58:08 INFO - 'HOMEDRIVE': 'C:', 01:58:08 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:58:08 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:58:08 INFO - 'KTS_VERSION': '1.19c', 01:58:08 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:58:08 INFO - 'LOGONSERVER': '\\\\T-W732-IX-023', 01:58:08 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:58:08 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:58:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:08 INFO - 'MOZ_NO_REMOTE': '1', 01:58:08 INFO - 'NO_EM_RESTART': '1', 01:58:08 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:58:08 INFO - 'OS': 'Windows_NT', 01:58:08 INFO - 'OURDRIVE': 'C:', 01:58:08 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;', 01:58:08 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:58:08 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:58:08 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:58:08 INFO - 'PROCESSOR_LEVEL': '6', 01:58:08 INFO - 'PROCESSOR_REVISION': '1e05', 01:58:08 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:58:08 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:58:08 INFO - 'PROMPT': '$P$G', 01:58:08 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:58:08 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:58:08 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:58:08 INFO - 'PWD': 'C:\\slave\\test', 01:58:08 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:58:08 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:58:08 INFO - 'SYSTEMDRIVE': 'C:', 01:58:08 INFO - 'SYSTEMROOT': 'C:\\windows', 01:58:08 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:08 INFO - 'TEST1': 'testie', 01:58:08 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:08 INFO - 'USERDOMAIN': 'T-W732-IX-023', 01:58:08 INFO - 'USERNAME': 'cltbld', 01:58:08 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:58:08 INFO - 'WINDIR': 'C:\\windows', 01:58:08 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:58:08 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:58:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:58:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:58:08 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 01:58:08 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 01:58:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:58:09 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 01:58:09 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) 01:58:09 INFO - Installing collected packages: mozsystemmonitor 01:58:09 INFO - Running setup.py install for mozsystemmonitor 01:58:09 INFO - Successfully installed mozsystemmonitor 01:58:09 INFO - Cleaning up... 01:58:10 INFO - Return code: 0 01:58:10 INFO - Installing jsonschema==2.5.1 into virtualenv C:\slave\test\build/venv 01:58:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:58:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:10 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-023', 'USERDOMAIN': 'T-W732-IX-023', '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-023', '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 01:58:10 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 01:58:10 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 01:58:10 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:58:10 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:58:10 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:58:10 INFO - 'COMPUTERNAME': 'T-W732-IX-023', 01:58:10 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:58:10 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:58:10 INFO - 'DCLOCATION': 'SCL3', 01:58:10 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:58:10 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:58:10 INFO - 'HOMEDRIVE': 'C:', 01:58:10 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:58:10 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:58:10 INFO - 'KTS_VERSION': '1.19c', 01:58:10 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:58:10 INFO - 'LOGONSERVER': '\\\\T-W732-IX-023', 01:58:10 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:58:10 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:58:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:10 INFO - 'MOZ_NO_REMOTE': '1', 01:58:10 INFO - 'NO_EM_RESTART': '1', 01:58:10 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:58:10 INFO - 'OS': 'Windows_NT', 01:58:10 INFO - 'OURDRIVE': 'C:', 01:58:10 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 01:58:10 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:58:10 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:58:10 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:58:10 INFO - 'PROCESSOR_LEVEL': '6', 01:58:10 INFO - 'PROCESSOR_REVISION': '1e05', 01:58:10 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:58:10 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:58:10 INFO - 'PROMPT': '$P$G', 01:58:10 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:58:10 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:58:10 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:58:10 INFO - 'PWD': 'C:\\slave\\test', 01:58:10 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:58:10 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:58:10 INFO - 'SYSTEMDRIVE': 'C:', 01:58:10 INFO - 'SYSTEMROOT': 'C:\\windows', 01:58:10 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:10 INFO - 'TEST1': 'testie', 01:58:10 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:10 INFO - 'USERDOMAIN': 'T-W732-IX-023', 01:58:10 INFO - 'USERNAME': 'cltbld', 01:58:10 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:58:10 INFO - 'WINDIR': 'C:\\windows', 01:58:10 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:58:10 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:58:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:58:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:10 INFO - Downloading/unpacking jsonschema==2.5.1 01:58:10 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 01:58:10 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 01:58:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:58:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:11 INFO - Downloading functools32-3.2.3-2.tar.gz 01:58:11 INFO - Running setup.py (path:C:\slave\test\build\venv\build\functools32\setup.py) egg_info for package functools32 01:58:11 INFO - warning: no files found matching '*.txt' 01:58:11 INFO - no previously-included directories found matching 'build' 01:58:11 INFO - no previously-included directories found matching 'dist' 01:58:11 INFO - no previously-included directories found matching '.git*' 01:58:11 INFO - Installing collected packages: jsonschema, functools32 01:58:12 INFO - Running setup.py install for functools32 01:58:12 INFO - warning: no files found matching '*.txt' 01:58:12 INFO - no previously-included directories found matching 'build' 01:58:12 INFO - no previously-included directories found matching 'dist' 01:58:12 INFO - no previously-included directories found matching '.git*' 01:58:12 INFO - Successfully installed jsonschema functools32 01:58:12 INFO - Cleaning up... 01:58:12 INFO - Return code: 0 01:58:12 INFO - Installing functools32==3.2.3-2 into virtualenv C:\slave\test\build/venv 01:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:58:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:58:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:12 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-023', 'USERDOMAIN': 'T-W732-IX-023', '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-023', '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 01:58:12 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in C:\slave\test\build 01:58:12 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:58:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:58:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:58:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:58:12 INFO - 'COMPUTERNAME': 'T-W732-IX-023', 01:58:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:58:12 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:58:12 INFO - 'DCLOCATION': 'SCL3', 01:58:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:58:12 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:58:12 INFO - 'HOMEDRIVE': 'C:', 01:58:12 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:58:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:58:12 INFO - 'KTS_VERSION': '1.19c', 01:58:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:58:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-023', 01:58:12 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:58:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:58:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:12 INFO - 'MOZ_NO_REMOTE': '1', 01:58:12 INFO - 'NO_EM_RESTART': '1', 01:58:12 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:58:12 INFO - 'OS': 'Windows_NT', 01:58:12 INFO - 'OURDRIVE': 'C:', 01:58:12 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 01:58:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:58:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:58:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:58:12 INFO - 'PROCESSOR_LEVEL': '6', 01:58:12 INFO - 'PROCESSOR_REVISION': '1e05', 01:58:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:58:12 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:58:12 INFO - 'PROMPT': '$P$G', 01:58:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:58:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:58:12 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:58:12 INFO - 'PWD': 'C:\\slave\\test', 01:58:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:58:12 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:58:12 INFO - 'SYSTEMDRIVE': 'C:', 01:58:12 INFO - 'SYSTEMROOT': 'C:\\windows', 01:58:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:12 INFO - 'TEST1': 'testie', 01:58:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:12 INFO - 'USERDOMAIN': 'T-W732-IX-023', 01:58:12 INFO - 'USERNAME': 'cltbld', 01:58:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:58:12 INFO - 'WINDIR': 'C:\\windows', 01:58:12 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:58:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:58:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in c:\slave\test\build\venv\lib\site-packages 01:58:13 INFO - Cleaning up... 01:58:13 INFO - Return code: 0 01:58:13 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 01:58: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')]} 01:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58: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')]} 01:58:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58: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', '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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-023', 'USERDOMAIN': 'T-W732-IX-023', '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-023', '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 01:58: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', 'blobuploader==1.2.4'] in C:\slave\test\build 01:58: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 blobuploader==1.2.4 01:58:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:58:13 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:58:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:58:13 INFO - 'COMPUTERNAME': 'T-W732-IX-023', 01:58:13 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:58:13 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:58:13 INFO - 'DCLOCATION': 'SCL3', 01:58:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:58:13 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:58:13 INFO - 'HOMEDRIVE': 'C:', 01:58:13 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:58:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:58:13 INFO - 'KTS_VERSION': '1.19c', 01:58:13 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:58:13 INFO - 'LOGONSERVER': '\\\\T-W732-IX-023', 01:58:13 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:58:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:58:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:13 INFO - 'MOZ_NO_REMOTE': '1', 01:58:13 INFO - 'NO_EM_RESTART': '1', 01:58:13 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:58:13 INFO - 'OS': 'Windows_NT', 01:58:13 INFO - 'OURDRIVE': 'C:', 01:58: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;', 01:58:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:58:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:58:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:58:13 INFO - 'PROCESSOR_LEVEL': '6', 01:58:13 INFO - 'PROCESSOR_REVISION': '1e05', 01:58:13 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:58:13 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:58:13 INFO - 'PROMPT': '$P$G', 01:58:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:58:13 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:58:13 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:58:13 INFO - 'PWD': 'C:\\slave\\test', 01:58:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:58:13 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:58:13 INFO - 'SYSTEMDRIVE': 'C:', 01:58:13 INFO - 'SYSTEMROOT': 'C:\\windows', 01:58:13 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:13 INFO - 'TEST1': 'testie', 01:58:13 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:13 INFO - 'USERDOMAIN': 'T-W732-IX-023', 01:58:13 INFO - 'USERNAME': 'cltbld', 01:58:13 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:58:13 INFO - 'WINDIR': 'C:\\windows', 01:58:13 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:58:13 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:58:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:13 INFO - Downloading/unpacking blobuploader==1.2.4 01:58:13 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 01:58:13 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 01:58:14 INFO - Downloading blobuploader-1.2.4.tar.gz 01:58:14 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 01:58:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:58:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:14 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 01:58:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:58:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:15 INFO - Downloading docopt-0.6.1.tar.gz 01:58:15 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 01:58:15 INFO - Installing collected packages: blobuploader, requests, docopt 01:58:15 INFO - Running setup.py install for blobuploader 01:58:15 INFO - Running setup.py install for requests 01:58:16 INFO - Running setup.py install for docopt 01:58:16 INFO - Successfully installed blobuploader requests docopt 01:58:16 INFO - Cleaning up... 01:58:17 INFO - Return code: 0 01:58:17 INFO - Installing None into virtualenv C:\slave\test\build/venv 01:58:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:58:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:58:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:17 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-023', 'USERDOMAIN': 'T-W732-IX-023', '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-023', '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 01:58:17 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 01:58:17 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 01:58:17 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:58:17 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:58:17 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:58:17 INFO - 'COMPUTERNAME': 'T-W732-IX-023', 01:58:17 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:58:17 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:58:17 INFO - 'DCLOCATION': 'SCL3', 01:58:17 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:58:17 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:58:17 INFO - 'HOMEDRIVE': 'C:', 01:58:17 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:58:17 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:58:17 INFO - 'KTS_VERSION': '1.19c', 01:58:17 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:58:17 INFO - 'LOGONSERVER': '\\\\T-W732-IX-023', 01:58:17 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:58:17 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:58:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:17 INFO - 'MOZ_NO_REMOTE': '1', 01:58:17 INFO - 'NO_EM_RESTART': '1', 01:58:17 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:58:17 INFO - 'OS': 'Windows_NT', 01:58:17 INFO - 'OURDRIVE': 'C:', 01:58:17 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 01:58:17 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:58:17 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:58:17 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:58:17 INFO - 'PROCESSOR_LEVEL': '6', 01:58:17 INFO - 'PROCESSOR_REVISION': '1e05', 01:58:17 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:58:17 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:58:17 INFO - 'PROMPT': '$P$G', 01:58:17 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:58:17 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:58:17 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:58:17 INFO - 'PWD': 'C:\\slave\\test', 01:58:17 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:58:17 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:58:17 INFO - 'SYSTEMDRIVE': 'C:', 01:58:17 INFO - 'SYSTEMROOT': 'C:\\windows', 01:58:17 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:17 INFO - 'TEST1': 'testie', 01:58:17 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:17 INFO - 'USERDOMAIN': 'T-W732-IX-023', 01:58:17 INFO - 'USERNAME': 'cltbld', 01:58:17 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:58:17 INFO - 'WINDIR': 'C:\\windows', 01:58:17 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:58:17 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:58:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:58:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 01:58:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-isrjhy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 01:58:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 01:58:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cqxkxv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 01:58:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 01:58:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aexkk1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 01:58:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 01:58:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5lq3yk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 01:58:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 01:58:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tgox_m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 01:58:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 01:58:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xpqqxg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 01:58:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 01:58:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mzifi1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 01:58:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 01:58:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dkhhoc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 01:58:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 01:58:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3hd6w3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 01:58:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 01:58:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-caytms-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 01:58:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 01:58:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-41utph-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 01:58:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 01:58:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a_nip0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 01:58:19 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 01:58:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bylhnf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 01:58:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 01:58:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7rbe5s-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 01:58:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 01:58:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sunle3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 01:58:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 01:58:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ppongi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 01:58:20 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 01:58:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6euk1j-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 01:58:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:58:20 INFO - Running setup.py install for manifestparser 01:58:20 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 01:58:20 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 01:58:21 INFO - Running setup.py install for mozcrash 01:58:21 INFO - Running setup.py install for mozdebug 01:58:21 INFO - Running setup.py install for mozdevice 01:58:22 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 01:58:22 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 01:58:22 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 01:58:22 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 01:58:22 INFO - Running setup.py install for mozfile 01:58:22 INFO - Running setup.py install for mozhttpd 01:58:23 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 01:58:23 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 01:58:23 INFO - Running setup.py install for mozinfo 01:58:23 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 01:58:23 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 01:58:23 INFO - Running setup.py install for mozInstall 01:58:23 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 01:58:23 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 01:58:23 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 01:58:23 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 01:58:23 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 01:58:23 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 01:58:23 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 01:58:23 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 01:58:24 INFO - Running setup.py install for mozleak 01:58:24 INFO - Running setup.py install for mozlog 01:58:24 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 01:58:24 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 01:58:25 INFO - Running setup.py install for moznetwork 01:58:25 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 01:58:25 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 01:58:25 INFO - Running setup.py install for mozprocess 01:58:26 INFO - Running setup.py install for mozprofile 01:58:26 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 01:58:26 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 01:58:26 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 01:58:26 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 01:58:26 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 01:58:26 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 01:58:26 INFO - Running setup.py install for mozrunner 01:58:27 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 01:58:27 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 01:58:27 INFO - Running setup.py install for mozscreenshot 01:58:27 INFO - Running setup.py install for moztest 01:58:28 INFO - Running setup.py install for mozversion 01:58:28 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 01:58:28 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 01:58:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:58:28 INFO - Cleaning up... 01:58:28 INFO - Return code: 0 01:58:28 INFO - Installing None into virtualenv C:\slave\test\build/venv 01:58:28 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')]} 01:58:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:28 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')]} 01:58:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:28 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-023', 'USERDOMAIN': 'T-W732-IX-023', '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-023', '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 01:58:28 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 01:58:28 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 01:58:28 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:58:28 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:58:28 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:58:28 INFO - 'COMPUTERNAME': 'T-W732-IX-023', 01:58:28 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:58:28 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:58:28 INFO - 'DCLOCATION': 'SCL3', 01:58:28 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:58:28 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:58:28 INFO - 'HOMEDRIVE': 'C:', 01:58:28 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:58:28 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:58:28 INFO - 'KTS_VERSION': '1.19c', 01:58:28 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:58:28 INFO - 'LOGONSERVER': '\\\\T-W732-IX-023', 01:58:28 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:58:28 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:58:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:28 INFO - 'MOZ_NO_REMOTE': '1', 01:58:28 INFO - 'NO_EM_RESTART': '1', 01:58:28 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:58:28 INFO - 'OS': 'Windows_NT', 01:58:28 INFO - 'OURDRIVE': 'C:', 01:58:28 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;', 01:58:28 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:58:28 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:58:28 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:58:28 INFO - 'PROCESSOR_LEVEL': '6', 01:58:28 INFO - 'PROCESSOR_REVISION': '1e05', 01:58:28 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:58:28 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:58:28 INFO - 'PROMPT': '$P$G', 01:58:28 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:58:28 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:58:28 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:58:28 INFO - 'PWD': 'C:\\slave\\test', 01:58:28 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:58:28 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:58:28 INFO - 'SYSTEMDRIVE': 'C:', 01:58:28 INFO - 'SYSTEMROOT': 'C:\\windows', 01:58:28 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:28 INFO - 'TEST1': 'testie', 01:58:28 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:28 INFO - 'USERDOMAIN': 'T-W732-IX-023', 01:58:28 INFO - 'USERNAME': 'cltbld', 01:58:28 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:58:28 INFO - 'WINDIR': 'C:\\windows', 01:58:28 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:58:28 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:58:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:58:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 01:58:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sjy340-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 01:58:29 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)) 01:58:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 01:58:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vky1xh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 01:58:29 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)) 01:58:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 01:58:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rwa24t-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 01:58:29 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)) 01:58:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 01:58:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d2xtvy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 01:58:29 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)) 01:58:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 01:58:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vgoxdx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 01:58:29 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)) 01:58:29 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 01:58:29 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1yr9zj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 01:58:30 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)) 01:58:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 01:58:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-oo4m5o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 01:58:30 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)) 01:58:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 01:58:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vnqebe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 01:58:30 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)) 01:58:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 01:58:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fqvp3a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 01:58:30 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)) 01:58:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 01:58:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lcrvvg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 01:58:30 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)) 01:58:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 01:58:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jtsagm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 01:58:30 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)) 01:58:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 01:58:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nvc99d-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 01:58:30 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)) 01:58:30 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 01:58:30 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_1mcul-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 01:58:31 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)) 01:58:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 01:58:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pftn5c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 01:58:31 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)) 01:58:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 01:58:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mynvwe-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 01:58:31 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)) 01:58:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 01:58:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lnzpo1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 01:58:31 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)) 01:58:31 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 01:58:31 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hlwckh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 01:58:31 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)) 01:58:31 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)) 01:58:31 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)) 01:58:31 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)) 01:58:31 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)) 01:58:31 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)) 01:58:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 01:58:31 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 01:58:31 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 01:58:32 INFO - Downloading blessings-1.6.tar.gz 01:58:32 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 01:58:33 INFO - Installing collected packages: blessings 01:58:33 INFO - Running setup.py install for blessings 01:58:33 INFO - Successfully installed blessings 01:58:33 INFO - Cleaning up... 01:58:36 INFO - Return code: 0 01:58:36 INFO - Done creating virtualenv C:\slave\test\build/venv. 01:58:36 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 01:58:36 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 01:58:36 INFO - Reading from file tmpfile_stdout 01:58:36 INFO - Using _rmtree_windows ... 01:58:36 INFO - Using _rmtree_windows ... 01:58:36 INFO - Current package versions: 01:58:36 INFO - blessings == 1.6 01:58:36 INFO - blobuploader == 1.2.4 01:58:36 INFO - docopt == 0.6.1 01:58:36 INFO - functools32 == 3.2.3-2 01:58:36 INFO - jsonschema == 2.5.1 01:58:36 INFO - manifestparser == 1.1 01:58:36 INFO - mozInstall == 1.12 01:58:36 INFO - mozcrash == 0.17 01:58:36 INFO - mozdebug == 0.1 01:58:36 INFO - mozdevice == 0.48 01:58:36 INFO - mozfile == 1.2 01:58:36 INFO - mozhttpd == 0.7 01:58:36 INFO - mozinfo == 0.9 01:58:36 INFO - mozleak == 0.1 01:58:36 INFO - mozlog == 3.4 01:58:36 INFO - moznetwork == 0.27 01:58:36 INFO - mozprocess == 0.23 01:58:36 INFO - mozprofile == 0.28 01:58:36 INFO - mozrunner == 6.12 01:58:36 INFO - mozscreenshot == 0.1 01:58:36 INFO - mozsystemmonitor == 0.3 01:58:36 INFO - moztest == 0.7 01:58:36 INFO - mozversion == 1.4 01:58:36 INFO - psutil == 3.1.1 01:58:36 INFO - requests == 1.2.3 01:58:36 INFO - Installing None into virtualenv C:\slave\test\build/venv 01:58:36 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')]} 01:58:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:36 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')]} 01:58:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:36 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-023', 'USERDOMAIN': 'T-W732-IX-023', '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-023', '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 01:58:36 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 01:58:36 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 01:58:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:58:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:58:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:58:36 INFO - 'COMPUTERNAME': 'T-W732-IX-023', 01:58:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:58:36 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:58:36 INFO - 'DCLOCATION': 'SCL3', 01:58:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:58:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:58:36 INFO - 'HOMEDRIVE': 'C:', 01:58:36 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:58:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:58:36 INFO - 'KTS_VERSION': '1.19c', 01:58:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:58:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-023', 01:58:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:58:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:58:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:36 INFO - 'MOZ_NO_REMOTE': '1', 01:58:36 INFO - 'NO_EM_RESTART': '1', 01:58:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:58:36 INFO - 'OS': 'Windows_NT', 01:58:36 INFO - 'OURDRIVE': 'C:', 01:58:36 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;', 01:58:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:58:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:58:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:58:36 INFO - 'PROCESSOR_LEVEL': '6', 01:58:36 INFO - 'PROCESSOR_REVISION': '1e05', 01:58:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:58:36 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:58:36 INFO - 'PROMPT': '$P$G', 01:58:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:58:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:58:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:58:36 INFO - 'PWD': 'C:\\slave\\test', 01:58:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:58:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:58:36 INFO - 'SYSTEMDRIVE': 'C:', 01:58:36 INFO - 'SYSTEMROOT': 'C:\\windows', 01:58:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:36 INFO - 'TEST1': 'testie', 01:58:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:36 INFO - 'USERDOMAIN': 'T-W732-IX-023', 01:58:36 INFO - 'USERNAME': 'cltbld', 01:58:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:58:36 INFO - 'WINDIR': 'C:\\windows', 01:58:36 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:58:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:58:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:58:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:36 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)) 01:58:36 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)) 01:58:36 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)) 01:58:36 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)) 01:58:36 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)) 01:58:36 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)) 01:58:36 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)) 01:58:36 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)) 01:58:36 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)) 01:58:36 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)) 01:58:36 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)) 01:58:36 INFO - Cleaning up... 01:58:36 INFO - Return code: 0 01:58:36 INFO - Installing jsonschema into virtualenv C:\slave\test\build/venv 01:58:36 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')]} 01:58:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:36 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')]} 01:58:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:36 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 0x01800640>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018ABE30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x017AB228>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018C8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F78520>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-023', 'USERDOMAIN': 'T-W732-IX-023', '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-023', '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 01:58:36 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 01:58:36 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 01:58:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:58:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:58:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:58:36 INFO - 'COMPUTERNAME': 'T-W732-IX-023', 01:58:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:58:36 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:58:36 INFO - 'DCLOCATION': 'SCL3', 01:58:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:58:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:58:36 INFO - 'HOMEDRIVE': 'C:', 01:58:36 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:58:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:58:36 INFO - 'KTS_VERSION': '1.19c', 01:58:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:58:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-023', 01:58:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:58:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:58:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:36 INFO - 'MOZ_NO_REMOTE': '1', 01:58:36 INFO - 'NO_EM_RESTART': '1', 01:58:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:58:36 INFO - 'OS': 'Windows_NT', 01:58:36 INFO - 'OURDRIVE': 'C:', 01:58:36 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;', 01:58:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:58:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:58:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:58:36 INFO - 'PROCESSOR_LEVEL': '6', 01:58:36 INFO - 'PROCESSOR_REVISION': '1e05', 01:58:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:58:36 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:58:36 INFO - 'PROMPT': '$P$G', 01:58:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:58:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:58:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:58:36 INFO - 'PWD': 'C:\\slave\\test', 01:58:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:58:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:58:36 INFO - 'SYSTEMDRIVE': 'C:', 01:58:36 INFO - 'SYSTEMROOT': 'C:\\windows', 01:58:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:36 INFO - 'TEST1': 'testie', 01:58:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:36 INFO - 'USERDOMAIN': 'T-W732-IX-023', 01:58:36 INFO - 'USERNAME': 'cltbld', 01:58:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:58:36 INFO - 'WINDIR': 'C:\\windows', 01:58:36 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:58:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:58:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:58:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in c:\slave\test\build\venv\lib\site-packages 01:58:37 INFO - Cleaning up... 01:58:37 INFO - Return code: 0 01:58:37 INFO - Running post-action listener: _resource_record_post_action 01:58:37 INFO - Running post-action listener: _start_resource_monitoring 01:58:37 INFO - Starting resource monitoring. 01:58:37 INFO - [mozharness: 2016-09-27 08:58:37.288000Z] Finished create-virtualenv step (success) 01:58:37 INFO - [mozharness: 2016-09-27 08:58:37.288000Z] Running install step. 01:58:37 INFO - Running pre-action listener: _resource_record_pre_action 01:58:37 INFO - Running main action method: install 01:58:37 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 01:58:37 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 01:58:37 INFO - Reading from file tmpfile_stdout 01:58:37 INFO - Using _rmtree_windows ... 01:58:37 INFO - Using _rmtree_windows ... 01:58:37 INFO - Detecting whether we're running mozinstall >=1.0... 01:58:37 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 01:58:37 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 01:58:37 INFO - Reading from file tmpfile_stdout 01:58:37 INFO - Output received: 01:58:37 INFO - Usage: mozinstall-script.py [options] installer 01:58:37 INFO - Options: 01:58:37 INFO - -h, --help show this help message and exit 01:58:37 INFO - -d DEST, --destination=DEST 01:58:37 INFO - Directory to install application into. [default: 01:58:37 INFO - "C:\slave\test"] 01:58:37 INFO - --app=APP Application being installed. [default: firefox] 01:58:37 INFO - Using _rmtree_windows ... 01:58:37 INFO - Using _rmtree_windows ... 01:58:37 INFO - mkdir: C:\slave\test\build\application 01:58:37 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'] 01:58:37 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 01:58:41 INFO - Reading from file tmpfile_stdout 01:58:41 INFO - Output received: 01:58:41 INFO - C:\slave\test\build\application\firefox\firefox.exe 01:58:41 INFO - Using _rmtree_windows ... 01:58:41 INFO - Using _rmtree_windows ... 01:58:41 INFO - Running post-action listener: _resource_record_post_action 01:58:41 INFO - [mozharness: 2016-09-27 08:58:41.317000Z] Finished install step (success) 01:58:41 INFO - [mozharness: 2016-09-27 08:58:41.317000Z] Running run-tests step. 01:58:41 INFO - Running pre-action listener: _resource_record_pre_action 01:58:41 INFO - Running main action method: run_tests 01:58:41 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 01:58:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 01:58:41 INFO - Python 2.7.3 01:58:41 INFO - Return code: 0 01:58:41 INFO - grabbing minidump binary from tooltool 01:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:58:41 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 0x018C8E90>, '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 0x0125A910>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F78520>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 01:58:41 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 01:58:41 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 01:58:41 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 01:58:41 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 01:58:42 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpz73nog 01:58:42 INFO - INFO - File integrity verified, renaming tmpz73nog to win32-minidump_stackwalk.exe 01:58:42 INFO - Return code: 0 01:58:42 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 01:58:42 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 01:58:42 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 01:58:42 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 01:58:42 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 01:58:42 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-023', '--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 01:58:42 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-023 --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 01:58:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 01:58:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 01:58:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 01:58:42 INFO - 'COMPUTERNAME': 'T-W732-IX-023', 01:58:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 01:58:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 01:58:42 INFO - 'DCLOCATION': 'SCL3', 01:58:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 01:58:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 01:58:42 INFO - 'HOMEDRIVE': 'C:', 01:58:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 01:58:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 01:58:42 INFO - 'KTS_VERSION': '1.19c', 01:58:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 01:58:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-023', 01:58:42 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 01:58:42 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 01:58:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 01:58:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 01:58:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:42 INFO - 'MOZ_NO_REMOTE': '1', 01:58:42 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 01:58:42 INFO - 'NO_EM_RESTART': '1', 01:58:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 01:58:42 INFO - 'OS': 'Windows_NT', 01:58:42 INFO - 'OURDRIVE': 'C:', 01:58:42 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 01:58:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 01:58:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 01:58:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 01:58:42 INFO - 'PROCESSOR_LEVEL': '6', 01:58:42 INFO - 'PROCESSOR_REVISION': '1e05', 01:58:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 01:58:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 01:58:42 INFO - 'PROMPT': '$P$G', 01:58:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 01:58:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 01:58:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 01:58:42 INFO - 'PWD': 'C:\\slave\\test', 01:58:42 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 01:58:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 01:58:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 01:58:42 INFO - 'SYSTEMDRIVE': 'C:', 01:58:42 INFO - 'SYSTEMROOT': 'C:\\windows', 01:58:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:42 INFO - 'TEST1': 'testie', 01:58:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 01:58:42 INFO - 'USERDOMAIN': 'T-W732-IX-023', 01:58:42 INFO - 'USERNAME': 'cltbld', 01:58:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 01:58:42 INFO - 'WINDIR': 'C:\\windows', 01:58:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 01:58:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 01:58:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 01:58:42 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-023', '--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 01:58:43 INFO - mozversion application_buildid: 20160926233117 01:58:43 INFO - mozversion application_changeset: df63260c1c90ea8ff0a5a314fbcae9365854abb0 01:58:43 INFO - mozversion application_display_name: Nightly 01:58:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:58:43 INFO - mozversion application_name: Firefox 01:58:43 INFO - mozversion application_remotingname: firefox 01:58:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:58:43 INFO - mozversion application_vendor: Mozilla 01:58:43 INFO - mozversion application_version: 52.0a1 01:58:43 INFO - mozversion platform_buildid: 20160926233117 01:58:43 INFO - mozversion platform_changeset: df63260c1c90ea8ff0a5a314fbcae9365854abb0 01:58:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:58:43 INFO - mozversion platform_version: 52.0a1 01:58:43 INFO - using testdate: 1474966723 01:58:43 INFO - actual date: 1474966723 01:58:43 INFO - starting webserver on 'localhost:49893' 01:58:43 INFO - SUITE-START | Running 2 tests 01:58:43 INFO - TEST-START | dromaeo_css 01:58:43 INFO - operating with platform_type : w7_ 01:58:43 INFO - Initialising browser for dromaeo_css test... 01:58:43 INFO - TEST-INFO | started process 3380 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp03wd6w\profile http://localhost:49893/getInfo.html) 01:58:51 INFO - PROCESS | 3380 | __metrics Screen width/height:1600/1200 01:58:51 INFO - PROCESS | 3380 | colorDepth:24 01:58:51 INFO - PROCESS | 3380 | Browser inner width/height: 1010/674 01:58:51 INFO - PROCESS | 3380 | __metrics 01:58:51 INFO - PROCESS | 3380 | 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" 01:58:51 INFO - PROCESS | 3380 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:58:52 INFO - TEST-INFO | 3380: exit 0 01:58:52 INFO - Browser initialized. 01:58:52 INFO - Running cycle 1/1 for dromaeo_css test... 01:58:52 INFO - TEST-INFO | started process 1892 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp03wd6w\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:58:53 INFO - PROCESS | 1892 | [GFX1-]: Invalid size in UpdateRenderTarget Size(116,0), 0 01:59:04 INFO - PROCESS | 1892 | RSS: Main: 139272192 01:59:04 INFO - PROCESS | 1892 | 01:59:56 INFO - PROCESS | 1892 | 1474966796128 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] 02:02:09 INFO - PROCESS | 1892 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49893/tests/dromaeo/cssquery-ext.html) 02:02:10 INFO - PROCESS | 1892 | RSS: Main: 146284544 02:02:10 INFO - PROCESS | 1892 | 02:05:13 INFO - PROCESS | 1892 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-ext.html (next: http://localhost:49893/tests/dromaeo/cssquery-jquery.html) 02:05:13 INFO - PROCESS | 1892 | RSS: Main: 204390400 02:05:13 INFO - PROCESS | 1892 | 02:08:16 INFO - PROCESS | 1892 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49893/tests/dromaeo/cssquery-mootools.html) 02:08:16 INFO - PROCESS | 1892 | RSS: Main: 144035840 02:08:16 INFO - PROCESS | 1892 | 02:11:18 INFO - PROCESS | 1892 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49893/tests/dromaeo/cssquery-prototype.html) 02:11:19 INFO - PROCESS | 1892 | RSS: Main: 146788352 02:11:19 INFO - PROCESS | 1892 | 02:14:22 INFO - PROCESS | 1892 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49893/tests/dromaeo/cssquery-yui.html) 02:14:23 INFO - PROCESS | 1892 | RSS: Main: 138760192 02:14:23 INFO - PROCESS | 1892 | 02:17:26 INFO - PROCESS | 1892 | Cycle 1(1): loaded http://localhost:49893/tests/dromaeo/cssquery-yui.html (next: http://localhost:49893/tests/dromaeo/cssquery-dojo.html) 02:17:26 INFO - PROCESS | 1892 | RSS: Main: 190664704 02:17:26 INFO - PROCESS | 1892 | 02:17:26 INFO - PROCESS | 1892 | __start_tp_report 02:17:26 INFO - PROCESS | 1892 | _x_x_mozilla_page_load 02:17:26 INFO - PROCESS | 1892 | _x_x_mozilla_page_load_details 02:17:26 INFO - PROCESS | 1892 | |i|pagename|runs| 02:17:26 INFO - PROCESS | 1892 | |0;dojo.html;2369.6327069304257;2372.727136379317;2401.759824017598;2415.7342692303832;2426.3812727754407;4059.0867054912705;4070.857519986801;4147.108371700087;4153.584641535848;4163.250614889319;4036.3743619738916;4108.897277568055;4131.731437456563;4180.853670121539;4217.8312867485265;3536.23970846268;3624.9456258156147;3629.727770417216;3633.6729694327505;3677.4667673187373;3593.119685677;3634.345817752803;3650.653187947141;3654.689351405134;3709.9072523187;3615.493830863681;3666.0101772521402;3672.981635091821;3682.263547290539;3696.131409118855;3460.9271125951036;3527.488514165457;3606.170580766412;3607.098225443639;3675.191457879262;3507.8596856125723;3569.214772750017;3586.318599466093;3589.6589823966683;3658.2500587379523;4073.409355643415;4134.276501612206;4143.7306575072525;4149.91700165995;4158.4178215049615;4137.793110344501;4192.224438478891;4291.249031419489;4295.183915056129;4390.758508282043;4280.293751530994;4304.461942257218;4384.079343337903;4411.205982923073;4411.338299255105;4005.0588111793704;4028.8992775180855;4091.85678501254;4098.549159592442;4105.876823695294;4381.9342709859375;4383.232934236496;4390.626796722315;4470.731756094645;4473.731576105444;4007;4090.9181816363507;4098.241825262336;4154.459920210354;4171.91656166875;4247.723897946624;4267.317229243306;4317.870463886088;4348.956510434855;4358.956410435855;4232.597903199191;4242.087951090531;4327.350897365357;4331.913361732748;4332.566743325705;4151.398047283187;4182.435371224907;4194.0563373240775;4247.51153617338;4314.762688052125;3019.4866872631706;3033.1507177990197;3039.6656367799524;3065.6474505431715;3076.4769989101906;2758.3517873299757;2833.688294287627;2839.3185635447344;2841.1618572521156;2848.3875966667265;3953.347697629916;4056.716029877939;4102.774347410921;4108.465899433055;4131.318332475168;4177.185448837508;4279.379489973936;4298.290782020994;4350.934735978963;4441.822327106945;4058.0463591056077;4085.142120154801;4138.13099249155;4142.171565686815;4143.047099167148;3694.0949467380665;3747.0632341913974;3755.9858838113555;3768.039150016783;3770.2647983643474;4350.608445239944;4374.387585737935;4442.978115033625;4460.8661740147845;4483.327500874895;4241.7030807844385;4354.760488173181;4418.315161150027;4429.844955426544;4431.578999994996;4378.890527736832;4495.685302028957;4527.63778897694;4529.41117654716;4531.592156705912;4145.606167414091;4177.582241775798;4208.768517731554;4256.170046840836;4349.891252718708;4208.38978348144;4272.166927449118;4334.9133017338845;4358.49877264106;4359.564043595615;3998.1803730235815;4095.2833254180996;4164.646005089601;4172.749635021908;4175.895602609838;4042.575529569353;4057.6753859691744;4073.7759423232005;4147.3778933160265;4165.625093741578;4025.718199726108;4047.736897101679;4136.855210067633;4177.352510360899;4179.477565304337;4204.453421055243;4268.039691069484;4282.229198744256;4287.442632457761;4293.012607100322;4037.7577345359373;4069.5320038195987;4071.3689378146437;4152.148809494107;4169.187008533307 02:17:26 INFO - PROCESS | 1892 | |1;ext.html;542.8208691131925;548.1558400063899;554.9972250138749;562.4881357964251;589.7228302697731;25313;36833.63166368336;37947.810260948725;38021.42967855481;40567.39148912769;37780.43329350055;37966.8101659492;38245.426318605176;38246.235075298544;38251;6522.347765223463;7708.614569271542;7799.454038217327;7846.450748447611;7861.135275119761;6594.4065034146915;9745.512724363789;9977.900220997806;10010.59957601695;10334.32826866255;10057.245706572;10111.090001899827;10112.646057387992;10115.342502737298;10127;9850.556724947395;10021.849672254923;10046.19630429564;10109.797804043877;10134.89865101347;6395.328490508523;6611.008348747679;9487;9841.90158098417;9873.308868379216;35677.46483802745;35771;35954.64045359565;35969.46045809315;36007.81996090029;2732.79504037198;2893.6382952130984;2922.649282086163;3214.260720034382;3300.455424854896;27106.864465677743;27141.592876106875;27547.586786198222;27646.170614881587;28083.719162808316;3302.669733026678;3344.5484859544135;3363.1423986883433;3391.643877392863;3391.8982430526876;212016;219912;226541;226985.86507067524;227124;46802.043652579145;46850.76574617105;46877.76561117172;46890.5310946893;46917;27304.863475682494;27922;28018.439631207668;28166.732497037683;48727.512724873006;9865.260105492116;9903.60385584583;10046.14607758334;10097.24270679702;10129.848052279222;6261.686915654199;7030.472714546429;7035.753748618876;7060.117485314336;7064.788056358318;13689.452421903212;15843.920780396023;16287.126980397168;16335.428260010842;16801.747973780402;6422.903656445157;6479.306795126827;6493.805185844338;6502.869942601122;6511.544191906616;774.2064384006326;780.2041917244493;868.68727258187;940.722486866376;947.6398968391967;24418.87790561036;25902.963881444906;25997.350066248495;26073.478530429285;26250.474990500083;929.5445231836352;955.7658373698349;963.816874793787;995.9592226123718;1000.7998400319965;13603;13796.241206733726;13986.86013139876;14110.50613228532;14164.929175354264;328900;330124;330304;331778;332841;45128;45421.545784542395;45570.77214613972;45659;45681;960.4525420510242;982.0228872272135;1068.348307532389;1093.557109370706;1105.657246253664;24735.38161545976;26471.470570588866;26628.46743065089;26760.33099172536;26831.463370732865;38491.80754096324;38687.61312386952;38804.223915522096;38868.80565597267;38877;262802;262877;263742;263759;264433;21036.894815525826;22000.66998994984;22012.889935550218;22864.771352286265;22893.427664308525;37972.430413544374;38653.420198696476;38836;38878.805605971786;39002.804985974886;33089.172770679965;33350;34089.48865767065;34841.59955206947;35814.820925895205;33050.33899321952;33194.336113278085;33569.83215083909;33726;33931.49102763411;32144.678553215104;32598;32629.510557342135;32682.673173267962;32706.182345441553;28850.17908971417;29840.403191936475;30671.539926900667;31018;31120.688793112684;22477.21329753451;23853.165139220044;23989.400264993514;24040.39899002539;24236.51526969486 02:17:26 INFO - PROCESS | 1892 | |2;jquery.html;294.392080354069;316.318333990251;316.45569620253156;323.6617734467481;325.5247338885226;11314.094872410207;12460.314682692471;12460.626181214555;12464.688382790435;12513.749725005506;11417.60038398654;11420.200585958986;11930.701732456693;12877.806832897491;15417.3833046678;4793.113274044295;4994.451719966822;4998.975005124969;5001.249812528114;5005.749712514378;4156.1687662467475;4236.703430759848;4862.197737373329;4872.610191184696;4913.975430122862;4917.557419832215;4934.654574179809;4950.257461380786;4955.851324460272;4961.05739909416;4909.68087074339;4936.358273424432;4941.061198372297;4948.233023881304;4949.381327334083;4196.643285320721;4268.167707297078;4355.194289056535;4356.346548017791;4357.172137293904;19874.403767886986;19927.30254441102;20049;20123.79876201234;20144.697829532568;1617.7168995425754;1618.8381161883835;1622.9675406491922;1623.4886010906528;1698.312183565658;11955.52177912891;12249.938750306192;12648.241105533698;13057.151285166434;13209.537666181637;1525.908445493274;1636.9345226190937;1657.78448801655;1663.2432243329256;1668.349343755924;51188.488115118744;57405;64219.3578064218;64433.677831611014;64713.67643161801;8799.47203167812;8805.207531322212;8858.955705221562;8913.465192088366;8929.374943754005;9913.454759988126;10401.479926003669;10661.786764264827;10713.196510261761;10722.142228621691;5731.340895797037;5821.15593238978;5885.764569417175;5949.196858424144;5958.672273025025;5450.291462109901;5510.118381059011;5532.30846144232;5540.445955404508;5545.445455454503;5513.228148059275;5625.578081643893;5640.661560306395;5654.5193658538665;5724.1413787931315;1480.0823489436618;1515.6968606278567;1517.770605809298;1525.7101150781316;1532.8773698104126;638.7445021991241;852.6333676519156;852.7356519478982;873.5195642396657;958.0898146760605;16547.338106475847;16921.238544265536;16926.661466770594;16965.660686786196;17512.474625761246;794.1780257433528;813.1421350475306;862.2412277196028;900.4912224593083;911.977200569991;9387.34288599805;9740.610375585038;9778.75553111178;9828.557714902847;10033.147182489423;283499;284344;285749;287045;287438;64501.67749161318;65317.67341163264;66015.66992165105;66278;67373.66313168402;765.223298352173;805.6575955219125;833.2917020532499;861.4960248254841;863.9265662418765;15273.77089343683;15318.846811531743;15498.84501154974;15501.689966200838;15569;39010.609893901834;39071.41392879052;39235.41146882742;39244.60755392409;40037.19925601414;52529.21206181834;52619.47380526299;52793.47206527885;53599;54042.729786350814;6755.93244067553;6943.166819981635;6979.790606281819;6983.720651173896;6992.79021629352;8500.532470713923;10849.294795838245;11019.669409917715;11041.83437248458;11308.83036754433;7321.524100933422;7526.5107767994905;8999.145081217273;9071.183593476619;9182.219511341611;9073.954630226806;9141.542922853698;9435.669751558662;9454;9733.124018838338;8932.642694292155;9037.09629037091;9384.624615015597;9463.574139163891;9624.470654113811;6975.197852247056;7087.043249161504;7122.109736282965;7131.607761573163;7136.357727804522;7449.702011919462;8754.21212090915;8837.91162088371;8868.423552469068;9163.49600771964 02:17:26 INFO - PROCESS | 1892 | |3;mootools.html;1206.3606288667006;1229.7724920889636;1268.3087717194128;1551.8836087293455;1552.0144708110347;6694.7322107115715;7116.964415177923;7172.21105678375;7173.533720308189;7197.2442893496145;5993;6182.289036760774;6789.185297764275;7000.859982800341;7129.5365801223015;1878.6806242938699;1892.0350621183227;1897.7532920720287;1903.3528600275904;1904.266857259958;1827.4791684369957;1835.1466568045855;1857.3963461874878;1859.888406695596;1890.5746207103361;1605.5825485373828;1657.9751303730454;1663.2681620087183;1885.6982882738764;1885.70771530413;1537.423466200175;1553.5494706535035;1708.555775498367;1765.2762367429293;1767.938122165718;1803.467976946804;1804.0348413598663;1812.9093545322812;1815.6005678750653;1816.2644129127725;6978.965105174492;7009.509334346598;7041;7048.612326322051;7049.436045116378;3850.4994350734223;3987.983064318609;3989.860354887565;4000.3399439092514;4008.498937632796;2517.093846215361;2576.9484610307877;2579.7678208961283;2583.057184127799;2604.9348766280805;830.256920056546;848.758103940374;854.7093988044095;868.1188593577524;885.9202671759573;8810.38327317088;8831.911680883173;8835.204831565126;8841.16008979146;8851.867221991675;3479.739019573542;3480.686738193575;3537.6108628050893;3538.9469157962653;3595.280943811196;7647.235276472308;7663.157052724196;7681.270279323456;7686.6164090463135;7688.846223075507;3891.6497515223764;3975.185087057146;3983.24318379507;4006.2988976928923;4008.6993475489453;3569;3608.6210947850623;3618.0773902654655;3637.399829028234;3651.470536772153;3098.3028818515654;3130.6869313068964;3188.2507610711464;3235.8058516489086;3236.530703048045;1044.5247412427327;1044.7649278912183;1048.8374301983206;1054.641421916552;1055.5091882274778;730.8063363208754;748.3003391828657;751.2187325181859;758.1811643425087;779.9025121859768;4296.205202037633;4306.181825453216;4333.393324934512;4376.671749618791;4467.977660111678;325.49223211789683;332.1778597970005;334.9950149551346;337.87836378903575;338.4365032221324;2984.4777163996214;3013.3671928894737;3047.369657233414;3082.1832214463184;3152.8423578821426;8178.345732341518;8208.835823283502;8322.126176751353;8503.532305723245;8510.106438823957;8337.499750014897;8345.66617335312;8393.706220282262;8470.407071504935;8476;6366.331535188831;6393.840153996187;6425.453836423862;6493.188351456068;6502.967485162544;4258.361245813153;4335.436393268855;4361.607455328909;4376.584224498668;4410.911781764283;7806.57063861493;7834.647440865061;7843.862639917066;7874.291313781787;7878.25156610144;7716.266954639525;7716.5755883426955;7790.961045194965;7809.687612495437;7924.20757924203;5836.824895253149;6081.665508397081;6089.5737298388685;6117.1130186123655;6119.143319935301;6597.3072827352435;6619.304972977768;6690.765823196165;6698.39714425704;6715.328467153246;5687.260656114679;5776.682282474504;5830.941690583039;5860.912086318624;5906.557008224315;5884.499817515559;5902.61632993854;5947.821565353046;5969.432903874125;5984.341722410444;5155.252488389087;5398;5470.015397228537;5476.698781567052;5485.4788795064405;3852.4799152114106;3854.3640299350295;3907.824147913294;3952.5256969163884;4038.353863381845;4009.498812648419;4037.0916543777416;4055.5538890721405;4073.103917138225;4133.400656904671 02:17:26 INFO - PROCESS | 1892 | |4;prototype.html;216.20436792603215;238.5658102253898;244.22580420068383;244.32078820877953;248.423657115492;4191.916161676764;4340.674449416295;4433.445819272595;4462.910741785162;4463.933041004388;4266.210751011065;4359.149965756678;4363.956360436395;4372;4378.6497080233585;3183.904482865518;3211.325621619451;3280.589346580969;3283.983580082096;3289.111939776259;3267.9183020424443;3278.8851790391263;3282.7045565899066;3292.1440425489304;3293.357795229931;3242.254281515241;3256.5115232715166;3256.8208748518823;3257.299680568677;3261.315123824;3193.744500439959;3242.691944265291;3245.513173024042;3259.364423937321;3260.10347154532;2858.442603692281;2858.9285267868263;2860.2266594711205;2866.1688110447944;2867.211516832867;4158.771267580282;4265.722728022669;4266.360045993095;4267.253230684618;4270.402143699885;3796.9430458543143;3806.2387522495333;3807.8857634271017;3808.0289526170927;3813.161104556993;4154.21368811411;4479.283314669669;4484.1031793641405;4529.932051019237;4538.638408477444;3187.681231876817;3193.3554219576936;3195.4407978603886;3197.952030719541;3199.5520627112223;4531.796069176928;4646.53534646536;4691.929621055687;4695.5539223773685;4698.295255711637;3113.2683819302447;3115.735162511212;3126.656067832537;3131.765117616188;3132.561441398206;3149.5590617313596;3153.6373317068374;3163.588733464681;3166.1134882233014;3167.0973772474736;3666.9633303666624;3680.723945704083;3685.8525658973876;3692.3538380783257;3692.8707495237536;4535.886602834889;4538.546145385435;4543.341215523797;4544.182047231464;4548.795304211319;1136.5624234669547;1137.6530158301632;1149.5401839264362;1152.5562658376584;1153.331067980586;691.9757808476679;725.8620862036302;728.8615163118991;733.7431898835472;745.4930647159983;4269.274223382033;4286.064046128412;4298.054428025829;4302.548232435612;4303.913921721548;4600.010997635458;4645.279981602857;4646.512116227815;4653.208954477747;4655.138799322136;4296.3125899856495;4298.828046878152;4299.914001719948;4299.97850010748;4305.332673435622;2362.68103805984;2363.7281712602926;2430.1130087517927;2443.9389015274405;2465.3097132802845;4231.682623803227;4771.117343291433;4794;4798.66409351349;4802.183628783116;4666.879948812329;4730.645201609893;4732.881677958078;4780;4784.067106914118;4595.540445955377;4629.421322334708;4711.976440117777;4815.374001379798;4826.927596086061;4668.159731248408;4682.648801339845;4690.343351930801;4695.671303008757;4697.177993851131;4525.343825145402;4549.727016379028;4575.885602859955;4590.931136032896;4606.585407313358;4756.6908150970075;4816.373871396696;4817.555447854801;4817.975910120427;4823.782929768098;2956.4530561846127;3150.3069324748517;3180.3162320101287;3263.543103965494;3267.7549183811784;4524.796384162655;4588;4593.954060459487;4614.73094898892;4618.930716039329;4393.362962370502;4437.511873693956;4440.111977604427;4456.551620723213;4458.910821783481;4411.117776444659;4432.138501829175;4439.4228750262255;4440.600345968878;4441.689081764245;4165.8541951032785;4287.164003019381;4375.124975005076;4383.846565370197;4384.298512238027;4487.820487180476;4517.977410112929;4519.977400112978;4530.478994915627;4540.296254080623;4466.307722303049;4474.955250447454;4475.977620111878;4478.238699421171;4487.865364039084 02:17:26 INFO - PROCESS | 1892 | |5;yui.html;567.2512283785403;590.8257064166072;629.7134803664334;641.1312671330344;679.497172092651;12591.496340146394;13754;13790;13795.448182072718;13808.171509709424;12555.37223138844;12580.622581322552;12581.811272830915;12714.1735787174;12734.426950787214;509.1853035143759;522.1150150494913;601.8946684330247;646.3181343682402;659.7723785294066;643.9517036222279;658.5818005566451;658.887989041864;664.5613894829413;665.268204974529;704.6054209642591;708.5748550869489;708.8617719544691;708.9929100709004;709.4537206351107;524.8845254044143;550.7108767896846;681.5535824035264;700.5656492974363;702.7645738677523;498.6215750868338;506.09914352452694;511.0137434750929;511.1132185657918;511.4757373691995;12683.619491415273;12689.111762176652;12691.301978391277;12702;12729.299888506128;11302.491387887565;11901.761964760657;12001.159918805688;12017.51929922802;12434.253944763343;9851.064148905842;10665.360078395244;11354.26197297173;11731.589394371242;11854.525818967317;3985.103351745863;4057.3102572562734;4137.338025915839;4187.769672668002;4224.746515209066;61436.69281653563;63172.36827631711;63376;63578.68210658964;63731.68134159346;9386.90613093874;9461.668841590512;9465.47939863314;9581.089796469325;9596.472194029257;16440.177991100398;16862.915685421496;16874.662506749795;16958.745618815727;16976;5827.038538641162;6131.294901086343;6163.7842675506145;6173.166622505994;6335.144755458046;3330.450475671487;3371.7976921384306;3381.6618338165986;3386.3227354529195;3476.1830969722096;2360.091364824555;2435.8782060896883;2446.5963116086004;2467.161165203839;2475.3687809608796;453.8411555955389;456.4682145300754;456.7647661454354;460.19236240397885;469.3382330913396;503.1170296130341;510.8186593759162;513.4685600403565;543.7580276776796;561.9409961954018;7117.7152913882865;7406.296401841816;7602.315791578784;7640.350570201484;7819.687212511436;409.4513352108189;438.1324976546456;455.41479199229497;497.25411882176735;538.321714639557;3800.3349413853016;3941.842326306973;3990.521137463465;4014.277430062617;4023.3964905264443;86205;88045.55977220072;89149.10850891538;92551;92581;46895.06209875851;52110.73944630328;73639.63180184065;78568.60715696384;79932;48199.75900120477;49147;69090.30909690839;71458.9978701486;72297;7397.630118494161;7478.551286922802;7592.392608591189;7823.687052517836;7914.802129946797;42391.3641295387;42444.78777606092;43522.12955740931;43619.34570981501;44070.11859762851;58625.70687146537;61201;61359;61373;61832.69083654553;8923.910760892568;9144.908550914406;9200.769980750267;10057.547410366697;10181.185505159421;11933.880661193278;12763.936180319039;12791;12900.96792256636;13087.738245235232;11251.943740281573;11426.371549564854;11513.942430287794;11877.287362758263;11925.105617078927;11680.591179308683;11944.283342999448;11946.940265298617;11982.760344792881;12386.690332741753;10936.23446358779;11374.94312528432;11575.710607234887;11582.536698531965;11834.349110798988;10284.177265818567;10363.689089327332;10555.788884222426;10565.366078035342;10596.417197054236;9639.9036009639;9986;10010.04904534068;10049.949750251202;10123.392596444237 02:17:26 INFO - PROCESS | 1892 | __end_tp_report 02:17:26 INFO - PROCESS | 1892 | __start_cc_report 02:17:26 INFO - PROCESS | 1892 | _x_x_mozilla_cycle_collect,1228 02:17:26 INFO - PROCESS | 1892 | __end_cc_report 02:17:26 INFO - PROCESS | 1892 | __startTimestamp1474967846559__endTimestamp 02:17:26 INFO - PROCESS | 1892 | 02:17:26 INFO - PROCESS | 1892 | ------- Summary: start ------- 02:17:26 INFO - PROCESS | 1892 | Number of tests: 6 02:17:26 INFO - PROCESS | 1892 | 02:17:26 INFO - PROCESS | 1892 | [#0] dojo.html Cycles:165 Average:3986.10 Median:4143.73 stddev:473.44 (11.4%) stddev-sans-first:457.59 02:17:26 INFO - PROCESS | 1892 | Values: 2369.6 2372.7 2401.8 2415.7 2426.4 4059.1 4070.9 4147.1 4153.6 4163.3 4036.4 4108.9 4131.7 4180.9 4217.8 3536.2 3624.9 3629.7 3633.7 3677.5 3593.1 3634.3 3650.7 3654.7 3709.9 3615.5 3666.0 3673.0 3682.3 3696.1 3460.9 3527.5 3606.2 3607.1 3675.2 3507.9 3569.2 3586.3 3589.7 3658.3 4073.4 4134.3 4143.7 4149.9 4158.4 4137.8 4192.2 4291.2 4295.2 4390.8 4280.3 4304.5 4384.1 4411.2 4411.3 4005.1 4028.9 4091.9 4098.5 4105.9 4381.9 4383.2 4390.6 4470.7 4473.7 4007.0 4090.9 4098.2 4154.5 4171.9 4247.7 4267.3 4317.9 4349.0 4359.0 4232.6 4242.1 4327.4 4331.9 4332.6 4151.4 4182.4 4194.1 4247.5 4314.8 3019.5 3033.2 3039.7 3065.6 3076.5 2758.4 2833.7 2839.3 2841.2 2848.4 3953.3 4056.7 4102.8 4108.5 4131.3 4177.2 4279.4 4298.3 4350.9 4441.8 4058.0 4085.1 4138.1 4142.2 4143.0 3694.1 3747.1 3756.0 3768.0 3770.3 4350.6 4374.4 4443.0 4460.9 4483.3 4241.7 4354.8 4418.3 4429.8 4431.6 4378.9 4495.7 4527.6 4529.4 4531.6 4145.6 4177.6 4208.8 4256.2 4349.9 4208.4 4272.2 4334.9 4358.5 4359.6 3998.2 4095.3 4164.6 4172.7 4175.9 4042.6 4057.7 4073.8 4147.4 4165.6 4025.7 4047.7 4136.9 4177.4 4179.5 4204.5 4268.0 4282.2 4287.4 4293.0 4037.8 4069.5 4071.4 4152.1 4169.2 02:17:26 INFO - PROCESS | 1892 | 02:17:26 INFO - PROCESS | 1892 | [#1] ext.html Cycles:180 Average:41463.71 Median:183017.67 stddev:72359.68 (39.5%) stddev-sans-first:72497.44 02:17:26 INFO - PROCESS | 1892 | Values: 542.8 548.2 555.0 562.5 589.7 25313.0 36833.6 37947.8 38021.4 40567.4 37780.4 37966.8 38245.4 38246.2 38251.0 6522.3 7708.6 7799.5 7846.5 7861.1 6594.4 9745.5 9977.9 10010.6 10334.3 10057.2 10111.1 10112.6 10115.3 10127.0 9850.6 10021.8 10046.2 10109.8 10134.9 6395.3 6611.0 9487.0 9841.9 9873.3 35677.5 35771.0 35954.6 35969.5 36007.8 2732.8 2893.6 2922.6 3214.3 3300.5 27106.9 27141.6 27547.6 27646.2 28083.7 3302.7 3344.5 3363.1 3391.6 3391.9 212016.0 219912.0 226541.0 226985.9 227124.0 46802.0 46850.8 46877.8 46890.5 46917.0 27304.9 27922.0 28018.4 28166.7 48727.5 9865.3 9903.6 10046.1 10097.2 10129.8 6261.7 7030.5 7035.8 7060.1 7064.8 13689.5 15843.9 16287.1 16335.4 16801.7 6422.9 6479.3 6493.8 6502.9 6511.5 774.2 780.2 868.7 940.7 947.6 24418.9 25903.0 25997.4 26073.5 26250.5 929.5 955.8 963.8 996.0 1000.8 13603.0 13796.2 13986.9 14110.5 14164.9 328900.0 330124.0 330304.0 331778.0 332841.0 45128.0 45421.5 45570.8 45659.0 45681.0 960.5 982.0 1068.3 1093.6 1105.7 24735.4 26471.5 26628.5 26760.3 26831.5 38491.8 38687.6 38804.2 38868.8 38877.0 262802.0 262877.0 263742.0 263759.0 264433.0 21036.9 22000.7 22012.9 22864.8 22893.4 37972.4 38653.4 38836.0 38878.8 39002.8 33089.2 33350.0 34089.5 34841.6 35814.8 33050.3 33194.3 33569.8 33726.0 33931.5 32144.7 32598.0 32629.5 32682.7 32706.2 28850.2 29840.4 30671.5 31018.0 31120.7 22477.2 23853.2 23989.4 24040.4 24236.5 02:17:26 INFO - PROCESS | 1892 | 02:17:26 INFO - PROCESS | 1892 | [#2] jquery.html Cycles:180 Average:20333.07 Median:4996.71 stddev:47717.31 (955.0%) stddev-sans-first:47827.45 02:17:26 INFO - PROCESS | 1892 | Values: 294.4 316.3 316.5 323.7 325.5 11314.1 12460.3 12460.6 12464.7 12513.7 11417.6 11420.2 11930.7 12877.8 15417.4 4793.1 4994.5 4999.0 5001.2 5005.7 4156.2 4236.7 4862.2 4872.6 4914.0 4917.6 4934.7 4950.3 4955.9 4961.1 4909.7 4936.4 4941.1 4948.2 4949.4 4196.6 4268.2 4355.2 4356.3 4357.2 19874.4 19927.3 20049.0 20123.8 20144.7 1617.7 1618.8 1623.0 1623.5 1698.3 11955.5 12249.9 12648.2 13057.2 13209.5 1525.9 1636.9 1657.8 1663.2 1668.3 51188.5 57405.0 64219.4 64433.7 64713.7 8799.5 8805.2 8859.0 8913.5 8929.4 9913.5 10401.5 10661.8 10713.2 10722.1 5731.3 5821.2 5885.8 5949.2 5958.7 5450.3 5510.1 5532.3 5540.4 5545.4 5513.2 5625.6 5640.7 5654.5 5724.1 1480.1 1515.7 1517.8 1525.7 1532.9 638.7 852.6 852.7 873.5 958.1 16547.3 16921.2 16926.7 16965.7 17512.5 794.2 813.1 862.2 900.5 912.0 9387.3 9740.6 9778.8 9828.6 10033.1 283499.0 284344.0 285749.0 287045.0 287438.0 64501.7 65317.7 66015.7 66278.0 67373.7 765.2 805.7 833.3 861.5 863.9 15273.8 15318.8 15498.8 15501.7 15569.0 39010.6 39071.4 39235.4 39244.6 40037.2 52529.2 52619.5 52793.5 53599.0 54042.7 6755.9 6943.2 6979.8 6983.7 6992.8 8500.5 10849.3 11019.7 11041.8 11308.8 7321.5 7526.5 8999.1 9071.2 9182.2 9074.0 9141.5 9435.7 9454.0 9733.1 8932.6 9037.1 9384.6 9463.6 9624.5 6975.2 7087.0 7122.1 7131.6 7136.4 7449.7 8754.2 8837.9 8868.4 9163.5 02:17:26 INFO - PROCESS | 1892 | 02:17:26 INFO - PROCESS | 1892 | [#3] mootools.html Cycles:180 Average:4439.11 Median:4369.10 stddev:2521.26 (57.7%) stddev-sans-first:2516.63 02:17:26 INFO - PROCESS | 1892 | Values: 1206.4 1229.8 1268.3 1551.9 1552.0 6694.7 7117.0 7172.2 7173.5 7197.2 5993.0 6182.3 6789.2 7000.9 7129.5 1878.7 1892.0 1897.8 1903.4 1904.3 1827.5 1835.1 1857.4 1859.9 1890.6 1605.6 1658.0 1663.3 1885.7 1885.7 1537.4 1553.5 1708.6 1765.3 1767.9 1803.5 1804.0 1812.9 1815.6 1816.3 6979.0 7009.5 7041.0 7048.6 7049.4 3850.5 3988.0 3989.9 4000.3 4008.5 2517.1 2576.9 2579.8 2583.1 2604.9 830.3 848.8 854.7 868.1 885.9 8810.4 8831.9 8835.2 8841.2 8851.9 3479.7 3480.7 3537.6 3538.9 3595.3 7647.2 7663.2 7681.3 7686.6 7688.8 3891.6 3975.2 3983.2 4006.3 4008.7 3569.0 3608.6 3618.1 3637.4 3651.5 3098.3 3130.7 3188.3 3235.8 3236.5 1044.5 1044.8 1048.8 1054.6 1055.5 730.8 748.3 751.2 758.2 779.9 4296.2 4306.2 4333.4 4376.7 4468.0 325.5 332.2 335.0 337.9 338.4 2984.5 3013.4 3047.4 3082.2 3152.8 8178.3 8208.8 8322.1 8503.5 8510.1 8337.5 8345.7 8393.7 8470.4 8476.0 6366.3 6393.8 6425.5 6493.2 6503.0 4258.4 4335.4 4361.6 4376.6 4410.9 7806.6 7834.6 7843.9 7874.3 7878.3 7716.3 7716.6 7791.0 7809.7 7924.2 5836.8 6081.7 6089.6 6117.1 6119.1 6597.3 6619.3 6690.8 6698.4 6715.3 5687.3 5776.7 5830.9 5860.9 5906.6 5884.5 5902.6 5947.8 5969.4 5984.3 5155.3 5398.0 5470.0 5476.7 5485.5 3852.5 3854.4 3907.8 3952.5 4038.4 4009.5 4037.1 4055.6 4073.1 4133.4 02:17:26 INFO - PROCESS | 1892 | 02:17:26 INFO - PROCESS | 1892 | [#4] prototype.html Cycles:180 Average:3759.80 Median:4323.00 stddev:1135.56 (26.3%) stddev-sans-first:1107.16 02:17:26 INFO - PROCESS | 1892 | Values: 216.2 238.6 244.2 244.3 248.4 4191.9 4340.7 4433.4 4462.9 4463.9 4266.2 4359.1 4364.0 4372.0 4378.6 3183.9 3211.3 3280.6 3284.0 3289.1 3267.9 3278.9 3282.7 3292.1 3293.4 3242.3 3256.5 3256.8 3257.3 3261.3 3193.7 3242.7 3245.5 3259.4 3260.1 2858.4 2858.9 2860.2 2866.2 2867.2 4158.8 4265.7 4266.4 4267.3 4270.4 3796.9 3806.2 3807.9 3808.0 3813.2 4154.2 4479.3 4484.1 4529.9 4538.6 3187.7 3193.4 3195.4 3198.0 3199.6 4531.8 4646.5 4691.9 4695.6 4698.3 3113.3 3115.7 3126.7 3131.8 3132.6 3149.6 3153.6 3163.6 3166.1 3167.1 3667.0 3680.7 3685.9 3692.4 3692.9 4535.9 4538.5 4543.3 4544.2 4548.8 1136.6 1137.7 1149.5 1152.6 1153.3 692.0 725.9 728.9 733.7 745.5 4269.3 4286.1 4298.1 4302.5 4303.9 4600.0 4645.3 4646.5 4653.2 4655.1 4296.3 4298.8 4299.9 4300.0 4305.3 2362.7 2363.7 2430.1 2443.9 2465.3 4231.7 4771.1 4794.0 4798.7 4802.2 4666.9 4730.6 4732.9 4780.0 4784.1 4595.5 4629.4 4712.0 4815.4 4826.9 4668.2 4682.6 4690.3 4695.7 4697.2 4525.3 4549.7 4575.9 4590.9 4606.6 4756.7 4816.4 4817.6 4818.0 4823.8 2956.5 3150.3 3180.3 3263.5 3267.8 4524.8 4588.0 4594.0 4614.7 4618.9 4393.4 4437.5 4440.1 4456.6 4458.9 4411.1 4432.1 4439.4 4440.6 4441.7 4165.9 4287.2 4375.1 4383.8 4384.3 4487.8 4518.0 4520.0 4530.5 4540.3 4466.3 4475.0 4476.0 4478.2 4487.9 02:17:26 INFO - PROCESS | 1892 | 02:17:26 INFO - PROCESS | 1892 | [#5] yui.html Cycles:180 Average:16427.16 Median:23677.63 stddev:22830.63 (96.4%) stddev-sans-first:22863.61 02:17:26 INFO - PROCESS | 1892 | Values: 567.3 590.8 629.7 641.1 679.5 12591.5 13754.0 13790.0 13795.4 13808.2 12555.4 12580.6 12581.8 12714.2 12734.4 509.2 522.1 601.9 646.3 659.8 644.0 658.6 658.9 664.6 665.3 704.6 708.6 708.9 709.0 709.5 524.9 550.7 681.6 700.6 702.8 498.6 506.1 511.0 511.1 511.5 12683.6 12689.1 12691.3 12702.0 12729.3 11302.5 11901.8 12001.2 12017.5 12434.3 9851.1 10665.4 11354.3 11731.6 11854.5 3985.1 4057.3 4137.3 4187.8 4224.7 61436.7 63172.4 63376.0 63578.7 63731.7 9386.9 9461.7 9465.5 9581.1 9596.5 16440.2 16862.9 16874.7 16958.7 16976.0 5827.0 6131.3 6163.8 6173.2 6335.1 3330.5 3371.8 3381.7 3386.3 3476.2 2360.1 2435.9 2446.6 2467.2 2475.4 453.8 456.5 456.8 460.2 469.3 503.1 510.8 513.5 543.8 561.9 7117.7 7406.3 7602.3 7640.4 7819.7 409.5 438.1 455.4 497.3 538.3 3800.3 3941.8 3990.5 4014.3 4023.4 86205.0 88045.6 89149.1 92551.0 92581.0 46895.1 52110.7 73639.6 78568.6 79932.0 48199.8 49147.0 69090.3 71459.0 72297.0 7397.6 7478.6 7592.4 7823.7 7914.8 42391.4 42444.8 43522.1 43619.3 44070.1 58625.7 61201.0 61359.0 61373.0 61832.7 8923.9 9144.9 9200.8 10057.5 10181.2 11933.9 12763.9 12791.0 12901.0 13087.7 11251.9 11426.4 11513.9 11877.3 11925.1 11680.6 11944.3 11946.9 11982.8 12386.7 10936.2 11374.9 11575.7 11582.5 11834.3 10284.2 10363.7 10555.8 10565.4 10596.4 9639.9 9986.0 10010.0 10049.9 10123.4 02:17:26 INFO - PROCESS | 1892 | -------- Summary: end -------- 02:17:26 INFO - PROCESS | 1892 | 02:17:27 INFO - TEST-INFO | 1892: exit 0 02:17:27 INFO - TEST-OK | dromaeo_css | took 1124560ms 02:17:27 INFO - TEST-START | kraken 02:17:27 INFO - operating with platform_type : w7_ 02:17:27 INFO - Initialising browser for kraken test... 02:17:27 INFO - TEST-INFO | started process 3348 (C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpr1rs4_\profile http://localhost:49893/getInfo.html) 02:17:32 INFO - PROCESS | 3348 | __metrics Screen width/height:1600/1200 02:17:32 INFO - PROCESS | 3348 | colorDepth:24 02:17:32 INFO - PROCESS | 3348 | Browser inner width/height: 1010/674 02:17:32 INFO - PROCESS | 3348 | __metrics 02:17:33 INFO - PROCESS | 3348 | 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" 02:17:33 INFO - PROCESS | 3348 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:17:33 INFO - PROCESS | 3348 | console.error: 02:17:33 INFO - PROCESS | 3348 | Message: Error: Connection closed before committing the transaction. 02:17:33 INFO - PROCESS | 3348 | Stack: 02:17:33 INFO - PROCESS | 3348 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 02:17:33 INFO - PROCESS | 3348 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 02:17:33 INFO - PROCESS | 3348 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 02:17:33 INFO - PROCESS | 3348 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 02:17:33 INFO - PROCESS | 3348 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 02:17:33 INFO - PROCESS | 3348 | TaskImpl_run@resource://gre/modules/Task.jsm:324:13 02:17:33 INFO - PROCESS | 3348 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 02:17:33 INFO - PROCESS | 3348 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 02:17:33 INFO - PROCESS | 3348 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 02:17:33 INFO - PROCESS | 3348 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 02:17:33 INFO - PROCESS | 3348 | Barrier/this.client.addBlocker/promise13.1% 02:18:34 INFO - TinderboxPrint: I/O read bytes / time
67,184,128 / 760,590 02:18:34 INFO - TinderboxPrint: I/O write bytes / time
473,063,936 / 3,663,130 02:18:34 INFO - TinderboxPrint: CPU idle
8,314.2 (86.9%) 02:18:34 INFO - TinderboxPrint: CPU user
1,220.7 (12.8%) 02:18:34 INFO - install - Wall time: 4s; CPU: 7.0%; Read bytes: 0; Write bytes: 43664896; Read time: 0; Write time: 716630 02:18:34 INFO - run-tests - Wall time: 1193s; CPU: 13.0%; Read bytes: 67184128; Write bytes: 397015552; Read time: 760590; Write time: 2742700 02:18:34 INFO - Running post-run listener: _upload_blobber_files 02:18:34 INFO - Blob upload gear active. 02:18:34 INFO - Preparing to upload files from C:\slave\test\build\blobber_upload_dir. 02:18:34 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 02:18:34 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', 'Fx-Team-Non-PGO', '-d', 'C:\\slave\\test\\build\\blobber_upload_dir', '--output-manifest', 'C:\\slave\\test\\build\\uploaded_files.json'] 02:18:34 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 Fx-Team-Non-PGO -d C:\slave\test\build\blobber_upload_dir --output-manifest C:\slave\test\build\uploaded_files.json 02:18:35 INFO - (blobuploader) - INFO - Open directory for files ... 02:18:35 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_errorsummary.log ... 02:18:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:18:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:18:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:18:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:18:36 INFO - (blobuploader) - INFO - Done attempting. 02:18:36 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\dromaeojs_raw.log ... 02:18:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:18:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:18:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:18:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:18:37 INFO - (blobuploader) - INFO - Done attempting. 02:18:37 INFO - (blobuploader) - INFO - Uploading C:\slave\test\build\blobber_upload_dir\resource-usage.json ... 02:18:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:18:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:18:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:18:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:18:47 INFO - (blobuploader) - INFO - Done attempting. 02:18:47 INFO - (blobuploader) - INFO - Iteration through files over. 02:18:47 INFO - Return code: 0 02:18:47 INFO - rmtree: C:\slave\test\build\uploaded_files.json 02:18:47 INFO - Using _rmtree_windows ... 02:18:47 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build\\uploaded_files.json',), kwargs: {}, attempt #1 02:18:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/23e1afb726bb6e29c016cb65356e0d5963aa1a0138737f00adf72e90f40a07d5a343e870287e6889d12e28899275730143742025983837b5195121325c2169c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9615f9c4b6642794193d6baef58b254b3ec991d4d836926cb1351d7019e6c9dcb25b5f79d6df28f568c1847d21fcdd47de6372a4c1bdc9b6339efbd069aeb7cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4bb671164c7404642085b828b6bb4ddb44ee165ccc4d9581f54acc6fbdb0ea15bd79cafeb64e881f9745b12644ce216392e9dc5a47046857cc1760d67f743fa7"} 02:18:47 INFO - Writing buildbot properties ['blobber_files'] to C:\slave\test\properties\blobber_files 02:18:47 INFO - Writing to file C:\slave\test\properties\blobber_files 02:18:47 INFO - Contents: 02:18:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/23e1afb726bb6e29c016cb65356e0d5963aa1a0138737f00adf72e90f40a07d5a343e870287e6889d12e28899275730143742025983837b5195121325c2169c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9615f9c4b6642794193d6baef58b254b3ec991d4d836926cb1351d7019e6c9dcb25b5f79d6df28f568c1847d21fcdd47de6372a4c1bdc9b6339efbd069aeb7cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4bb671164c7404642085b828b6bb4ddb44ee165ccc4d9581f54acc6fbdb0ea15bd79cafeb64e881f9745b12644ce216392e9dc5a47046857cc1760d67f743fa7"} 02:18:47 INFO - Running post-run listener: copy_logs_to_upload_dir 02:18:47 INFO - Copying logs to upload dir... 02:18:47 INFO - mkdir: C:\slave\test\build\upload\logs 02:18:47 INFO - Copying logs to upload dir... 02:18:47 INFO - Using _rmtree_windows ... 02:18:47 INFO - Using _rmtree_windows ... 02:18:47 INFO - Using _rmtree_windows ... 02:18:47 INFO - Using _rmtree_windows ... 02:18:47 INFO - Using _rmtree_windows ... 02:18:47 INFO - Using _rmtree_windows ... 02:18:47 INFO - Using _rmtree_windows ... program finished with exit code 0 elapsedTime=1431.060000 ========= master_lag: 0.03 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 51 secs) (at 2016-09-27 02:18:47.496979) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 02:18:47.507459) ========= '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-023 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-023 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-023 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/Fx-Team-Non-PGO/sha512/23e1afb726bb6e29c016cb65356e0d5963aa1a0138737f00adf72e90f40a07d5a343e870287e6889d12e28899275730143742025983837b5195121325c2169c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9615f9c4b6642794193d6baef58b254b3ec991d4d836926cb1351d7019e6c9dcb25b5f79d6df28f568c1847d21fcdd47de6372a4c1bdc9b6339efbd069aeb7cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4bb671164c7404642085b828b6bb4ddb44ee165ccc4d9581f54acc6fbdb0ea15bd79cafeb64e881f9745b12644ce216392e9dc5a47046857cc1760d67f743fa7"} build_url:https://queue.taskcluster.net/v1/task/ecc9NlZwR6aZkmem8San0g/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/ecc9NlZwR6aZkmem8San0g/artifacts/public/build/firefox-52.0a1.en-US.win32.zip' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/23e1afb726bb6e29c016cb65356e0d5963aa1a0138737f00adf72e90f40a07d5a343e870287e6889d12e28899275730143742025983837b5195121325c2169c7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9615f9c4b6642794193d6baef58b254b3ec991d4d836926cb1351d7019e6c9dcb25b5f79d6df28f568c1847d21fcdd47de6372a4c1bdc9b6339efbd069aeb7cd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4bb671164c7404642085b828b6bb4ddb44ee165ccc4d9581f54acc6fbdb0ea15bd79cafeb64e881f9745b12644ce216392e9dc5a47046857cc1760d67f743fa7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-27 02:18:47.624233) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 02:18:47.624573) ========= '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-023 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-023 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-023 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.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-27 02:18:47.741232) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-27 02:18:47.741600) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2016-09-27 02:18:48.177226) ========= ========= Total master_lag: 0.22 =========