builder: ash_win7-ix_test-dromaeojs slave: t-w732-ix-090 starttime: 1445937172.87 results: success (0) buildid: 20151026140824 builduid: 9508afa236b34674a58373f9e108218b revision: a759c44ddb56eac3fd2e6cd23310e8f89be4ec18 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-27 02:12:52.873825) ========= master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-27 02:12:52.874837) ========= ========= Started set props: basedir (results: 0, elapsed: 8 secs) (at 2015-10-27 02:12:52.875211) ========= 'cd' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['cd'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-090 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-090 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-090 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 C:\slave\test program finished with exit code 0 elapsedTime=0.599000 basedir: 'C:\\slave\\test' ========= Finished set props: basedir (results: 0, elapsed: 8 secs) (at 2015-10-27 02:13:01.678874) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2015-10-27 02:13:01.679168) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2015-10-27 02:13:07.893366) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2015-10-27 02:13:07.893644) ========= 'rm' '-rf' 'properties' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-090 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-090 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-090 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.501000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 9 secs) (at 2015-10-27 02:13:17.190129) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-27 02:13:17.190411) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-27 02:13:17.190792) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 30 secs) (at 2015-10-27 02:13:17.191074) ========= '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-090 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-090 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-090 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 --02:13:36-- 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. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 2.45 GB/s 02:13:36 (2.45 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.706000 ========= Finished 'bash -c ...' (results: 0, elapsed: 30 secs) (at 2015-10-27 02:13:47.848139) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 19 secs) (at 2015-10-27 02:13:47.851476) ========= 'rm' '-rf' 'scripts' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-090 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-090 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-090 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=12.402000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 19 secs) (at 2015-10-27 02:14:07.266769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2015-10-27 02:14:07.267112) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo projects/ash --rev a759c44ddb56eac3fd2e6cd23310e8f89be4ec18 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/ash --rev a759c44ddb56eac3fd2e6cd23310e8f89be4ec18 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-090 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-090 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-090 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 2015-10-27 02:14:07,835 truncating revision to first 12 chars 2015-10-27 02:14:07,835 Setting DEBUG logging. 2015-10-27 02:14:07,835 attempt 1/10 2015-10-27 02:14:07,835 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/ash/a759c44ddb56?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-27 02:14:08,292 unpacking tar archive at: ash-a759c44ddb56/testing/mozharness/ program finished with exit code 0 elapsedTime=1.198000 ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2015-10-27 02:14:10.705406) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2015-10-27 02:14:10.705721) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2015-10-27 02:14:13.114313) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-27 02:14:13.114624) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-27 02:14:13.115003) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 49 secs) (at 2015-10-27 02:14:13.115291) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Ash-Non-PGO' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Ash-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', 'Ash-Non-PGO', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Ash-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-090 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-090 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-090 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 02:14:13 INFO - MultiFileLogger online at 20151027 02:14:13 in C:\slave\test 02:14:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Ash-Non-PGO --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Ash-Non-PGO 02:14:13 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 02:14:13 INFO - {'append_to_log': False, 02:14:13 INFO - 'base_work_dir': 'C:\\slave\\test', 02:14:13 INFO - 'blob_upload_branch': 'Ash-Non-PGO', 02:14:13 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 02:14:13 INFO - 'branch': 'Ash-Non-PGO', 02:14:13 INFO - 'buildbot_json_path': 'buildprops.json', 02:14:13 INFO - 'config_files': ('talos/windows_config.py',), 02:14:13 INFO - 'default_actions': ('clobber', 02:14:13 INFO - 'read-buildbot-config', 02:14:13 INFO - 'download-and-extract', 02:14:13 INFO - 'populate-webroot', 02:14:13 INFO - 'create-virtualenv', 02:14:13 INFO - 'install', 02:14:13 INFO - 'run-tests'), 02:14:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:14:13 INFO - 'download_minidump_stackwalk': True, 02:14:13 INFO - 'download_symbols': 'ondemand', 02:14:13 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 02:14:13 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 02:14:13 INFO - 'hg': 'c:/mozilla-build/hg/hg', 02:14:13 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 02:14:13 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 02:14:13 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 02:14:13 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 02:14:13 INFO - 'C:/mozilla-build/tooltool.py'), 02:14:13 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 02:14:13 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 02:14:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:14:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:14:13 INFO - 'installer_path': 'installer.exe', 02:14:13 INFO - 'log_level': 'info', 02:14:13 INFO - 'log_name': 'talos', 02:14:13 INFO - 'log_to_console': True, 02:14:13 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 02:14:13 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 02:14:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 02:14:13 INFO - 'opt_config_files': (), 02:14:13 INFO - 'pip_index': False, 02:14:13 INFO - 'sps_profile': False, 02:14:13 INFO - 'sps_profile_interval': 0, 02:14:13 INFO - 'suite': 'dromaeojs', 02:14:13 INFO - 'system_bits': '32', 02:14:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:14:13 INFO - 'title': 'T-W732-IX-090', 02:14:13 INFO - 'use_talos_json': True, 02:14:13 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 02:14:13 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 02:14:13 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 02:14:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:14:13 INFO - 'work_dir': 'build'} 02:14:13 INFO - ##### 02:14:13 INFO - ##### Running clobber step. 02:14:13 INFO - ##### 02:14:13 INFO - Running pre-action listener: _resource_record_pre_action 02:14:13 INFO - Running main action method: clobber 02:14:13 INFO - rmtree: C:\slave\test\build 02:14:13 INFO - Using _rmtree_windows ... 02:14:13 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 02:15:33 INFO - Running post-action listener: _resource_record_post_action 02:15:33 INFO - ##### 02:15:33 INFO - ##### Running read-buildbot-config step. 02:15:33 INFO - ##### 02:15:33 INFO - Running pre-action listener: _resource_record_pre_action 02:15:33 INFO - Running main action method: read_buildbot_config 02:15:33 INFO - Using buildbot properties: 02:15:33 INFO - { 02:15:33 INFO - "properties": { 02:15:33 INFO - "buildnumber": 0, 02:15:33 INFO - "product": "firefox", 02:15:33 INFO - "script_repo_revision": "production", 02:15:33 INFO - "builddir": "ash_win7-ix_test-dromaeojs", 02:15:33 INFO - "repository": "", 02:15:33 INFO - "buildername": "Windows 7 32-bit ash talos dromaeojs", 02:15:33 INFO - "buildid": "20151026140824", 02:15:33 INFO - "slavename": "t-w732-ix-090", 02:15:33 INFO - "pgo_build": "False", 02:15:33 INFO - "basedir": "C:\\slave\\test", 02:15:33 INFO - "project": "", 02:15:33 INFO - "platform": "win7-ix", 02:15:33 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/", 02:15:33 INFO - "slavebuilddir": "test", 02:15:33 INFO - "scheduler": "tests-ash-win32-talos", 02:15:33 INFO - "branch": "ash", 02:15:33 INFO - "repo_path": "projects/ash", 02:15:33 INFO - "stage_platform": "win32", 02:15:33 INFO - "builduid": "9508afa236b34674a58373f9e108218b", 02:15:33 INFO - "revision": "a759c44ddb56eac3fd2e6cd23310e8f89be4ec18" 02:15:33 INFO - }, 02:15:33 INFO - "sourcestamp": { 02:15:33 INFO - "repository": "", 02:15:33 INFO - "hasPatch": false, 02:15:33 INFO - "project": "", 02:15:33 INFO - "branch": "ash-win32-talos", 02:15:33 INFO - "changes": [ 02:15:33 INFO - { 02:15:33 INFO - "category": null, 02:15:33 INFO - "files": [ 02:15:33 INFO - { 02:15:33 INFO - "url": null, 02:15:33 INFO - "name": "https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 02:15:33 INFO - } 02:15:33 INFO - ], 02:15:33 INFO - "repository": "", 02:15:33 INFO - "rev": "a759c44ddb56eac3fd2e6cd23310e8f89be4ec18", 02:15:33 INFO - "who": "catlee@mozilla.com", 02:15:33 INFO - "when": 1445900365, 02:15:33 INFO - "number": 6576805, 02:15:33 INFO - "comments": "validate using nightly certificates", 02:15:33 INFO - "project": "", 02:15:33 INFO - "at": "Mon 26 Oct 2015 15:59:25", 02:15:33 INFO - "branch": "ash-win32-talos", 02:15:33 INFO - "revlink": "", 02:15:33 INFO - "properties": [ 02:15:33 INFO - [ 02:15:33 INFO - "buildid", 02:15:33 INFO - "20151026140824", 02:15:33 INFO - "Change" 02:15:33 INFO - ], 02:15:33 INFO - [ 02:15:33 INFO - "builduid", 02:15:33 INFO - "9508afa236b34674a58373f9e108218b", 02:15:33 INFO - "Change" 02:15:33 INFO - ], 02:15:33 INFO - [ 02:15:33 INFO - "pgo_build", 02:15:33 INFO - "False", 02:15:33 INFO - "Change" 02:15:33 INFO - ] 02:15:33 INFO - ], 02:15:33 INFO - "revision": "a759c44ddb56eac3fd2e6cd23310e8f89be4ec18" 02:15:33 INFO - } 02:15:33 INFO - ], 02:15:33 INFO - "revision": "a759c44ddb56eac3fd2e6cd23310e8f89be4ec18" 02:15:33 INFO - } 02:15:33 INFO - } 02:15:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 02:15:33 INFO - Running post-action listener: _resource_record_post_action 02:15:33 INFO - ##### 02:15:33 INFO - ##### Running download-and-extract step. 02:15:33 INFO - ##### 02:15:33 INFO - Running pre-action listener: _resource_record_pre_action 02:15:33 INFO - Running main action method: download_and_extract 02:15:33 INFO - mkdir: C:\slave\test\build\tests 02:15:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:33 INFO - https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 02:15:33 INFO - trying https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/test_packages.json 02:15:33 INFO - Downloading https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json 02:15:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1 02:15:34 INFO - Downloaded 1221 bytes. 02:15:34 INFO - Reading from file C:\slave\test\build\test_packages.json 02:15:34 INFO - Using the following test package requirements: 02:15:34 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:15:34 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:15:34 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 02:15:34 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:15:34 INFO - u'jsshell-win32.zip'], 02:15:34 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:15:34 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 02:15:34 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:15:34 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:15:34 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 02:15:34 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:15:34 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 02:15:34 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:15:34 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 02:15:34 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 02:15:34 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 02:15:34 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 02:15:34 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 02:15:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:15:34 INFO - https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 02:15:34 INFO - trying https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 02:15:34 INFO - Downloading https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:15:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'}, attempt #1 02:15:36 INFO - Downloaded 18001947 bytes. 02:15:36 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] in C:\slave\test\build\tests 02:15:36 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:15:36 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 02:16:04 INFO - Return code: 0 02:16:04 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip', u'firefox-44.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 02:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:04 INFO - https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 02:16:04 INFO - trying https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 02:16:04 INFO - Downloading https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:16:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'}, attempt #1 02:16:06 INFO - Downloaded 18001947 bytes. 02:16:06 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] in C:\slave\test\build\tests 02:16:06 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 02:16:06 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 02:16:30 INFO - Return code: 0 02:16:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:30 INFO - https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 02:16:30 INFO - trying https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 02:16:30 INFO - Downloading https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.talos.tests.zip 02:16:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'}, attempt #1 02:16:32 INFO - Downloaded 11131307 bytes. 02:16:32 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] in C:\slave\test\build\tests 02:16:32 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.talos.tests.zip 02:16:32 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 02:16:34 INFO - Return code: 0 02:16:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:34 INFO - https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 02:16:34 INFO - trying https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:16:34 INFO - Downloading https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.zip 02:16:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.zip'}, attempt #1 02:16:37 INFO - Downloaded 49708557 bytes. 02:16:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:16:37 INFO - mkdir: C:\slave\test\properties 02:16:37 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 02:16:37 INFO - Writing to file C:\slave\test\properties\build_url 02:16:37 INFO - Contents: 02:16:37 INFO - build_url:https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 02:16:37 INFO - Running post-action listener: _resource_record_post_action 02:16:37 INFO - Running post-action listener: set_extra_try_arguments 02:16:37 INFO - ##### 02:16:37 INFO - ##### Running populate-webroot step. 02:16:37 INFO - ##### 02:16:37 INFO - Running pre-action listener: _resource_record_pre_action 02:16:37 INFO - Running main action method: populate_webroot 02:16:37 INFO - Guessing talos json url... 02:16:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:37 INFO - https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 02:16:37 INFO - trying https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 02:16:37 INFO - Downloading https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 02:16:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.txt', 'file_name': 'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.txt'}, attempt #1 02:16:38 INFO - Downloaded 96 bytes. 02:16:38 INFO - Reading from file C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 02:16:38 INFO - Contents: 02:16:38 INFO - 20151026140824 02:16:38 INFO - https://hg.mozilla.org/projects/ash/rev/a759c44ddb56eac3fd2e6cd23310e8f89be4ec18 02:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:38 INFO - trying https://hg.mozilla.org/projects/ash/raw-file/a759c44ddb/testing/talos/talos.json 02:16:38 INFO - Downloading https://hg.mozilla.org/projects/ash/raw-file/a759c44ddb/testing/talos/talos.json to C:\slave\test\build\talos.json 02:16:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/projects/ash/raw-file/a759c44ddb/testing/talos/talos.json', 'file_name': 'C:\\slave\\test\\build\\talos.json'}, attempt #1 02:16:38 INFO - Downloaded 4264 bytes. 02:16:38 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 02:16:38 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 02:16:38 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 02:16:38 INFO - u'../fennec_ids.txt'], 02:16:38 INFO - u'tests': [u'tcheck2']}, 02:16:38 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 02:16:38 INFO - u'--tppagecycles', 02:16:38 INFO - u'7'], 02:16:38 INFO - u'tests': [u'tsvgm']}}, 02:16:38 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 02:16:38 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 02:16:38 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 02:16:38 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 02:16:38 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 02:16:38 INFO - u'tests': [u'dromaeo_css', 02:16:38 INFO - u'kraken', 02:16:38 INFO - u'v8_7']}, 02:16:38 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:38 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 02:16:38 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:38 INFO - u'talos_options': [u'--e10s'], 02:16:38 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 02:16:38 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:38 INFO - u'tests': [u'damp', u'tps']}, 02:16:38 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:38 INFO - u'talos_options': [u'--e10s'], 02:16:38 INFO - u'tests': [u'damp', u'tps']}, 02:16:38 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 02:16:38 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 02:16:38 INFO - u'other': {u'tests': [u'a11yr', 02:16:38 INFO - u'ts_paint', 02:16:38 INFO - u'tpaint', 02:16:38 INFO - u'sessionrestore', 02:16:38 INFO - u'sessionrestore_no_auto_restore']}, 02:16:38 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 02:16:38 INFO - u'tests': [u'a11yr', 02:16:38 INFO - u'ts_paint', 02:16:38 INFO - u'tpaint', 02:16:38 INFO - u'sessionrestore', 02:16:38 INFO - u'sessionrestore_no_auto_restore']}, 02:16:38 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 02:16:38 INFO - u'tests': [u'a11yr', 02:16:38 INFO - u'ts_paint', 02:16:38 INFO - u'tpaint', 02:16:38 INFO - u'sessionrestore', 02:16:38 INFO - u'sessionrestore_no_auto_restore']}, 02:16:38 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 02:16:38 INFO - u'tests': [u'a11yr', 02:16:38 INFO - u'ts_paint', 02:16:38 INFO - u'tpaint', 02:16:38 INFO - u'sessionrestore', 02:16:38 INFO - u'sessionrestore_no_auto_restore']}, 02:16:38 INFO - u'other_l64': {u'tests': [u'a11yr', 02:16:38 INFO - u'ts_paint', 02:16:38 INFO - u'tpaint', 02:16:38 INFO - u'sessionrestore', 02:16:38 INFO - u'sessionrestore_no_auto_restore']}, 02:16:38 INFO - u'other_nol64': {u'tests': [u'a11yr', 02:16:38 INFO - u'ts_paint', 02:16:38 INFO - u'tpaint', 02:16:38 INFO - u'sessionrestore', 02:16:38 INFO - u'sessionrestore_no_auto_restore']}, 02:16:38 INFO - u'svgr': {u'tests': [u'tsvgx', 02:16:38 INFO - u'tsvgr_opacity', 02:16:38 INFO - u'tart', 02:16:38 INFO - u'tscrollx', 02:16:38 INFO - u'cart']}, 02:16:38 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 02:16:38 INFO - u'tests': [u'tsvgx', 02:16:38 INFO - u'tsvgr_opacity', 02:16:38 INFO - u'tart', 02:16:38 INFO - u'tscrollx', 02:16:38 INFO - u'cart']}, 02:16:38 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:38 INFO - u'tests': [u'tp5o']}, 02:16:38 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:38 INFO - u'talos_options': [u'--e10s'], 02:16:38 INFO - u'tests': [u'tp5o']}, 02:16:38 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:38 INFO - u'talos_options': [u'--xperf_path', 02:16:38 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:16:38 INFO - u'tests': [u'tp5n']}, 02:16:38 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:38 INFO - u'talos_options': [u'--e10s', 02:16:38 INFO - u'--xperf_path', 02:16:38 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 02:16:38 INFO - u'C:/slave/talos-data/talos/xperf.config'], 02:16:38 INFO - u'tests': [u'tp5n']}}, 02:16:38 INFO - u'talos.zip': {u'path': u'', 02:16:38 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:16:38 INFO - Running post-action listener: _resource_record_post_action 02:16:38 INFO - ##### 02:16:38 INFO - ##### Running create-virtualenv step. 02:16:38 INFO - ##### 02:16:38 INFO - Running pre-action listener: _resource_record_pre_action 02:16:38 INFO - Running main action method: create_virtualenv 02:16:38 INFO - Creating virtualenv C:\slave\test\build/venv 02:16:38 INFO - mkdir: C:\slave\test\build\venv\Scripts 02:16:38 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 02:16:38 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'! 02:16:38 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 02:16:38 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 02:16:43 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 02:16:47 INFO - Installing distribute......................................................................................................................................................................................done. 02:16:48 INFO - Return code: 0 02:16:48 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 02:16:48 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:48 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:48 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 0x019D3B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A29EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012E8B48>, '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 0x0130A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA6660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-090', 'USERDOMAIN': 'T-W732-IX-090', '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-090', '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 02:16:48 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 02:16:48 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --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 02:16:48 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:16:48 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:16:48 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:16:48 INFO - 'COMPUTERNAME': 'T-W732-IX-090', 02:16:48 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:16:48 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:16:48 INFO - 'DCLOCATION': 'SCL3', 02:16:48 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:16:48 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:16:48 INFO - 'HOMEDRIVE': 'C:', 02:16:48 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:16:48 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:16:48 INFO - 'KTS_VERSION': '1.19c', 02:16:48 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:16:48 INFO - 'LOGONSERVER': '\\\\T-W732-IX-090', 02:16:48 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:16:48 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:48 INFO - 'MOZ_NO_REMOTE': '1', 02:16:48 INFO - 'NO_EM_RESTART': '1', 02:16:48 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:16:48 INFO - 'OS': 'Windows_NT', 02:16:48 INFO - 'OURDRIVE': 'C:', 02:16:48 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;', 02:16:48 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:16:48 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:16:48 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:16:48 INFO - 'PROCESSOR_LEVEL': '6', 02:16:48 INFO - 'PROCESSOR_REVISION': '1e05', 02:16:48 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:16:48 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:16:48 INFO - 'PROMPT': '$P$G', 02:16:48 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:16:48 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:16:48 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:16:48 INFO - 'PWD': 'C:\\slave\\test', 02:16:48 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:16:48 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:16:48 INFO - 'SYSTEMDRIVE': 'C:', 02:16:48 INFO - 'SYSTEMROOT': 'C:\\windows', 02:16:48 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:16:48 INFO - 'TEST1': 'testie', 02:16:48 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:16:48 INFO - 'USERDOMAIN': 'T-W732-IX-090', 02:16:48 INFO - 'USERNAME': 'cltbld', 02:16:48 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:16:48 INFO - 'WINDIR': 'C:\\windows', 02:16:48 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:16:48 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:16:49 INFO - Ignoring indexes: http://pypi.python.org/simple/ 02:16:49 INFO - Downloading/unpacking pip>=1.5 02:16:49 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache 02:16:53 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz 02:16:53 INFO - Running setup.py egg_info for package pip 02:16:53 INFO - warning: no files found matching 'pip\cacert.pem' 02:16:53 INFO - warning: no files found matching '*.html' under directory 'docs' 02:16:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 02:16:53 INFO - no previously-included directories found matching 'docs\_build\_sources' 02:16:53 INFO - Installing collected packages: pip 02:16:53 INFO - Found existing installation: pip 0.8.2 02:16:53 INFO - Uninstalling pip: 02:16:53 INFO - Successfully uninstalled pip 02:16:53 INFO - Running setup.py install for pip 02:16:53 INFO - warning: no files found matching 'pip\cacert.pem' 02:16:53 INFO - warning: no files found matching '*.html' under directory 'docs' 02:16:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 02:16:53 INFO - no previously-included directories found matching 'docs\_build\_sources' 02:16:53 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 02:16:53 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 02:16:53 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 02:16:53 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 02:16:53 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 02:16:53 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 02:16:53 INFO - Successfully installed pip 02:16:53 INFO - Cleaning up... 02:16:53 INFO - Return code: 0 02:16:53 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv 02:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:53 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x019D3B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A29EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012E8B48>, '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 0x0130A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA6660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-090', 'USERDOMAIN': 'T-W732-IX-090', '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-090', '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 02:16:53 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test\build 02:16:53 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:16:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:16:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:16:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:16:53 INFO - 'COMPUTERNAME': 'T-W732-IX-090', 02:16:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:16:53 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:16:53 INFO - 'DCLOCATION': 'SCL3', 02:16:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:16:53 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:16:53 INFO - 'HOMEDRIVE': 'C:', 02:16:53 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:16:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:16:53 INFO - 'KTS_VERSION': '1.19c', 02:16:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:16:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-090', 02:16:53 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:16:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:53 INFO - 'MOZ_NO_REMOTE': '1', 02:16:53 INFO - 'NO_EM_RESTART': '1', 02:16:53 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:16:53 INFO - 'OS': 'Windows_NT', 02:16:53 INFO - 'OURDRIVE': 'C:', 02:16:53 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;', 02:16:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:16:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:16:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:16:53 INFO - 'PROCESSOR_LEVEL': '6', 02:16:53 INFO - 'PROCESSOR_REVISION': '1e05', 02:16:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:16:53 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:16:53 INFO - 'PROMPT': '$P$G', 02:16:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:16:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:16:53 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:16:53 INFO - 'PWD': 'C:\\slave\\test', 02:16:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:16:53 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:16:53 INFO - 'SYSTEMDRIVE': 'C:', 02:16:53 INFO - 'SYSTEMROOT': 'C:\\windows', 02:16:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:16:53 INFO - 'TEST1': 'testie', 02:16:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:16:53 INFO - 'USERDOMAIN': 'T-W732-IX-090', 02:16:53 INFO - 'USERNAME': 'cltbld', 02:16:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:16:53 INFO - 'WINDIR': 'C:\\windows', 02:16:53 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:16:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:55 INFO - Downloading/unpacking psutil>=0.7.1 02:16:55 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 02:16:55 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 02:16:56 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpsutil-3.1.1-cp27-none-win32.whl 02:16:56 INFO - Installing collected packages: psutil 02:16:56 INFO - Successfully installed psutil 02:16:56 INFO - Cleaning up... 02:16:56 INFO - Return code: 0 02:16:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv 02:16:56 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:56 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:56 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x019D3B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A29EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012E8B48>, '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 0x0130A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA6660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-090', 'USERDOMAIN': 'T-W732-IX-090', '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-090', '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 02:16:56 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test\build 02:16:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:16:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:16:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:16:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:16:56 INFO - 'COMPUTERNAME': 'T-W732-IX-090', 02:16:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:16:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:16:56 INFO - 'DCLOCATION': 'SCL3', 02:16:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:16:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:16:56 INFO - 'HOMEDRIVE': 'C:', 02:16:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:16:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:16:56 INFO - 'KTS_VERSION': '1.19c', 02:16:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:16:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-090', 02:16:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:16:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:56 INFO - 'MOZ_NO_REMOTE': '1', 02:16:56 INFO - 'NO_EM_RESTART': '1', 02:16:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:16:56 INFO - 'OS': 'Windows_NT', 02:16:56 INFO - 'OURDRIVE': 'C:', 02:16:56 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;', 02:16:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:16:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:16:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:16:56 INFO - 'PROCESSOR_LEVEL': '6', 02:16:56 INFO - 'PROCESSOR_REVISION': '1e05', 02:16:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:16:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:16:56 INFO - 'PROMPT': '$P$G', 02:16:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:16:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:16:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:16:56 INFO - 'PWD': 'C:\\slave\\test', 02:16:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:16:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:16:56 INFO - 'SYSTEMDRIVE': 'C:', 02:16:56 INFO - 'SYSTEMROOT': 'C:\\windows', 02:16:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:16:56 INFO - 'TEST1': 'testie', 02:16:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:16:56 INFO - 'USERDOMAIN': 'T-W732-IX-090', 02:16:56 INFO - 'USERNAME': 'cltbld', 02:16:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:16:56 INFO - 'WINDIR': 'C:\\windows', 02:16:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:16:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:16:57 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 02:16:57 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 02:16:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:16:58 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozsystemmonitor-0.0.tar.gz 02:16:58 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 02:16:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 02:16:58 INFO - Installing collected packages: mozsystemmonitor 02:16:58 INFO - Running setup.py install for mozsystemmonitor 02:16:58 INFO - Successfully installed mozsystemmonitor 02:16:58 INFO - Cleaning up... 02:16:58 INFO - Return code: 0 02:16:58 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 02:16:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:58 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:58 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 0x019D3B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A29EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012E8B48>, '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 0x0130A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA6660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-090', 'USERDOMAIN': 'T-W732-IX-090', '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-090', '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 02:16:58 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 02:16:58 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --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 02:16:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:16:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:16:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:16:58 INFO - 'COMPUTERNAME': 'T-W732-IX-090', 02:16:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:16:58 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:16:58 INFO - 'DCLOCATION': 'SCL3', 02:16:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:16:58 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:16:58 INFO - 'HOMEDRIVE': 'C:', 02:16:58 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:16:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:16:58 INFO - 'KTS_VERSION': '1.19c', 02:16:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:16:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-090', 02:16:58 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:16:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:58 INFO - 'MOZ_NO_REMOTE': '1', 02:16:58 INFO - 'NO_EM_RESTART': '1', 02:16:58 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:16:58 INFO - 'OS': 'Windows_NT', 02:16:58 INFO - 'OURDRIVE': 'C:', 02:16:58 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:16:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:16:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:16:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:16:58 INFO - 'PROCESSOR_LEVEL': '6', 02:16:58 INFO - 'PROCESSOR_REVISION': '1e05', 02:16:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:16:58 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:16:58 INFO - 'PROMPT': '$P$G', 02:16:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:16:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:16:58 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:16:58 INFO - 'PWD': 'C:\\slave\\test', 02:16:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:16:58 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:16:58 INFO - 'SYSTEMDRIVE': 'C:', 02:16:58 INFO - 'SYSTEMROOT': 'C:\\windows', 02:16:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:16:58 INFO - 'TEST1': 'testie', 02:16:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:16:58 INFO - 'USERDOMAIN': 'T-W732-IX-090', 02:16:58 INFO - 'USERNAME': 'cltbld', 02:16:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:16:58 INFO - 'WINDIR': 'C:\\windows', 02:16:58 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:16:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:58 INFO - Downloading/unpacking blobuploader==1.2.4 02:16:58 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 02:16:58 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 02:16:59 INFO - Downloading blobuploader-1.2.4.tar.gz 02:16:59 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz 02:16:59 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 02:16:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:16:59 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 02:16:59 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 02:17:00 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2frequests-1.2.3.tar.gz 02:17:00 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 02:17:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:17:00 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 02:17:00 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 02:17:00 INFO - Downloading docopt-0.6.1.tar.gz 02:17:00 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fdocopt-0.6.1.tar.gz 02:17:00 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 02:17:00 INFO - Installing collected packages: blobuploader, requests, docopt 02:17:00 INFO - Running setup.py install for blobuploader 02:17:00 INFO - Running setup.py install for requests 02:17:01 INFO - Running setup.py install for docopt 02:17:01 INFO - Successfully installed blobuploader requests docopt 02:17:01 INFO - Cleaning up... 02:17:03 INFO - Return code: 0 02:17:03 INFO - Installing None into virtualenv C:\slave\test\build/venv 02:17:03 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:03 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:03 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 0x019D3B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A29EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012E8B48>, '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 0x0130A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA6660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-090', 'USERDOMAIN': 'T-W732-IX-090', '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-090', '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 02:17:03 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 02:17:03 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --download-cache C:\slave\test\build/venv\cache --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 02:17:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:17:03 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:17:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:17:03 INFO - 'COMPUTERNAME': 'T-W732-IX-090', 02:17:03 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:17:03 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:17:03 INFO - 'DCLOCATION': 'SCL3', 02:17:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:17:03 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:17:03 INFO - 'HOMEDRIVE': 'C:', 02:17:03 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:17:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:17:03 INFO - 'KTS_VERSION': '1.19c', 02:17:03 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:17:03 INFO - 'LOGONSERVER': '\\\\T-W732-IX-090', 02:17:03 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:17:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:03 INFO - 'MOZ_NO_REMOTE': '1', 02:17:03 INFO - 'NO_EM_RESTART': '1', 02:17:03 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:17:03 INFO - 'OS': 'Windows_NT', 02:17:03 INFO - 'OURDRIVE': 'C:', 02:17:03 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:17:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:17:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:17:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:17:03 INFO - 'PROCESSOR_LEVEL': '6', 02:17:03 INFO - 'PROCESSOR_REVISION': '1e05', 02:17:03 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:17:03 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:17:03 INFO - 'PROMPT': '$P$G', 02:17:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:17:03 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:17:03 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:17:03 INFO - 'PWD': 'C:\\slave\\test', 02:17:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:17:03 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:17:03 INFO - 'SYSTEMDRIVE': 'C:', 02:17:03 INFO - 'SYSTEMROOT': 'C:\\windows', 02:17:03 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:17:03 INFO - 'TEST1': 'testie', 02:17:03 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:17:03 INFO - 'USERDOMAIN': 'T-W732-IX-090', 02:17:03 INFO - 'USERNAME': 'cltbld', 02:17:03 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:17:03 INFO - 'WINDIR': 'C:\\windows', 02:17:03 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:17:03 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 02:17:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-quymgp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 02:17:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 02:17:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-koua2a-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 02:17:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 02:17:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ydbswh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 02:17:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 02:17:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aai26w-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 02:17:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 02:17:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bc6pby-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 02:17:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 02:17:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-svfcdm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 02:17:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 02:17:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rvjzvq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 02:17:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 02:17:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-2tobyn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 02:17:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 02:17:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ktpyzc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 02:17:04 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 02:17:04 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-frbjak-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 02:17:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 02:17:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-m0kivl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 02:17:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 02:17:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-u7gsqs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 02:17:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 02:17:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nzncf4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 02:17:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 02:17:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n4t5wj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 02:17:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 02:17:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-a_f4vl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 02:17:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 02:17:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6o4aqo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 02:17:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 02:17:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wkzuty-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 02:17:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:17:05 INFO - Running setup.py install for manifestparser 02:17:06 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 02:17:06 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 02:17:06 INFO - Running setup.py install for mozcrash 02:17:06 INFO - Running setup.py install for mozdebug 02:17:07 INFO - Running setup.py install for mozdevice 02:17:07 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 02:17:07 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 02:17:07 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 02:17:07 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 02:17:07 INFO - Running setup.py install for mozfile 02:17:08 INFO - Running setup.py install for mozhttpd 02:17:08 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 02:17:08 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 02:17:08 INFO - Running setup.py install for mozinfo 02:17:09 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 02:17:09 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 02:17:09 INFO - Running setup.py install for mozInstall 02:17:09 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 02:17:09 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 02:17:09 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 02:17:09 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 02:17:09 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 02:17:09 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 02:17:09 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 02:17:09 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 02:17:09 INFO - Running setup.py install for mozleak 02:17:10 INFO - Running setup.py install for mozlog 02:17:10 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 02:17:10 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 02:17:11 INFO - Running setup.py install for moznetwork 02:17:11 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 02:17:11 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 02:17:11 INFO - Running setup.py install for mozprocess 02:17:12 INFO - Running setup.py install for mozprofile 02:17:12 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 02:17:12 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 02:17:12 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 02:17:12 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 02:17:12 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 02:17:12 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 02:17:12 INFO - Running setup.py install for mozrunner 02:17:12 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 02:17:12 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 02:17:13 INFO - Running setup.py install for mozscreenshot 02:17:13 INFO - Running setup.py install for moztest 02:17:14 INFO - Running setup.py install for mozversion 02:17:14 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 02:17:14 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 02:17:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:17:14 INFO - Cleaning up... 02:17:14 INFO - Return code: 0 02:17:14 INFO - Installing None into virtualenv C:\slave\test\build/venv 02:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:14 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 0x019D3B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A29EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012E8B48>, '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 0x0130A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA6660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-090', 'USERDOMAIN': 'T-W732-IX-090', '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-090', '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 02:17:14 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 02:17:14 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --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 02:17:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:17:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:17:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:17:14 INFO - 'COMPUTERNAME': 'T-W732-IX-090', 02:17:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:17:14 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:17:14 INFO - 'DCLOCATION': 'SCL3', 02:17:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:17:14 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:17:14 INFO - 'HOMEDRIVE': 'C:', 02:17:14 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:17:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:17:14 INFO - 'KTS_VERSION': '1.19c', 02:17:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:17:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-090', 02:17:14 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:17:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:14 INFO - 'MOZ_NO_REMOTE': '1', 02:17:14 INFO - 'NO_EM_RESTART': '1', 02:17:14 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:17:14 INFO - 'OS': 'Windows_NT', 02:17:14 INFO - 'OURDRIVE': 'C:', 02:17:14 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:17:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:17:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:17:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:17:14 INFO - 'PROCESSOR_LEVEL': '6', 02:17:14 INFO - 'PROCESSOR_REVISION': '1e05', 02:17:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:17:14 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:17:14 INFO - 'PROMPT': '$P$G', 02:17:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:17:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:17:14 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:17:14 INFO - 'PWD': 'C:\\slave\\test', 02:17:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:17:14 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:17:14 INFO - 'SYSTEMDRIVE': 'C:', 02:17:14 INFO - 'SYSTEMROOT': 'C:\\windows', 02:17:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:17:14 INFO - 'TEST1': 'testie', 02:17:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:17:14 INFO - 'USERDOMAIN': 'T-W732-IX-090', 02:17:14 INFO - 'USERNAME': 'cltbld', 02:17:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:17:14 INFO - 'WINDIR': 'C:\\windows', 02:17:14 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:17:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 02:17:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4t71jz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 02:17:15 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)) 02:17:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 02:17:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kxhcoz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 02:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 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)) 02:17:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 02:17:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pnw6v_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 02:17:15 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)) 02:17:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 02:17:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-y4qggf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 02:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 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)) 02:17:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 02:17:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d27qmp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 02:17:15 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)) 02:17:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 02:17:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-reth_y-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 02:17:15 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)) 02:17:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 02:17:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xsnuue-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 02:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 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)) 02:17:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 02:17:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5hdpgy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 02:17:16 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)) 02:17:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 02:17:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-o_wnid-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 02:17:16 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)) 02:17:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 02:17:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n3ynsz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 02:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 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)) 02:17:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 02:17:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tkt7km-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 02:17:16 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)) 02:17:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 02:17:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-p6ojlr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 02:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 02:17:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 02:17:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cu1ief-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 02:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 02:17:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 02:17:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lazdqd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 02:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 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)) 02:17:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 02:17:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g96_nx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 02:17:17 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)) 02:17:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 02:17:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kdiq0n-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 02:17:17 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)) 02:17:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 02:17:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-toppok-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 02:17:17 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)) 02:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 02:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 02:17:17 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)) 02:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 02:17:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 02:17:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 02:17:17 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 02:17:17 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 02:17:18 INFO - Downloading blessings-1.5.1.tar.gz 02:17:18 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblessings-1.5.1.tar.gz 02:17:18 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 02:17:18 INFO - Installing collected packages: blessings 02:17:18 INFO - Running setup.py install for blessings 02:17:19 INFO - Successfully installed blessings 02:17:19 INFO - Cleaning up... 02:17:21 INFO - Return code: 0 02:17:21 INFO - Done creating virtualenv C:\slave\test\build/venv. 02:17:21 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 02:17:21 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 02:17:22 INFO - Reading from file tmpfile_stdout 02:17:22 INFO - Using _rmtree_windows ... 02:17:22 INFO - Using _rmtree_windows ... 02:17:22 INFO - Current package versions: 02:17:22 INFO - blessings == 1.5.1 02:17:22 INFO - blobuploader == 1.2.4 02:17:22 INFO - docopt == 0.6.1 02:17:22 INFO - manifestparser == 1.1 02:17:22 INFO - mozInstall == 1.12 02:17:22 INFO - mozcrash == 0.16 02:17:22 INFO - mozdebug == 0.1 02:17:22 INFO - mozdevice == 0.46 02:17:22 INFO - mozfile == 1.2 02:17:22 INFO - mozhttpd == 0.7 02:17:22 INFO - mozinfo == 0.8 02:17:22 INFO - mozleak == 0.1 02:17:22 INFO - mozlog == 3.0 02:17:22 INFO - moznetwork == 0.27 02:17:22 INFO - mozprocess == 0.22 02:17:22 INFO - mozprofile == 0.27 02:17:22 INFO - mozrunner == 6.10 02:17:22 INFO - mozscreenshot == 0.1 02:17:22 INFO - mozsystemmonitor == 0.0 02:17:22 INFO - moztest == 0.7 02:17:22 INFO - mozversion == 1.4 02:17:22 INFO - psutil == 3.1.1 02:17:22 INFO - requests == 1.2.3 02:17:22 INFO - Installing None into virtualenv C:\slave\test\build/venv 02:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:17:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:17:22 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 0x019D3B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01A29EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012E8B48>, '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 0x0130A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA6660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-090', 'USERDOMAIN': 'T-W732-IX-090', '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-090', '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 02:17:22 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--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 02:17:22 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --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 02:17:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:17:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:17:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:17:22 INFO - 'COMPUTERNAME': 'T-W732-IX-090', 02:17:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:17:22 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:17:22 INFO - 'DCLOCATION': 'SCL3', 02:17:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:17:22 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:17:22 INFO - 'HOMEDRIVE': 'C:', 02:17:22 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:17:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:17:22 INFO - 'KTS_VERSION': '1.19c', 02:17:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:17:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-090', 02:17:22 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:17:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:22 INFO - 'MOZ_NO_REMOTE': '1', 02:17:22 INFO - 'NO_EM_RESTART': '1', 02:17:22 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:17:22 INFO - 'OS': 'Windows_NT', 02:17:22 INFO - 'OURDRIVE': 'C:', 02:17:22 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:17:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:17:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:17:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:17:22 INFO - 'PROCESSOR_LEVEL': '6', 02:17:22 INFO - 'PROCESSOR_REVISION': '1e05', 02:17:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:17:22 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:17:22 INFO - 'PROMPT': '$P$G', 02:17:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:17:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:17:22 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:17:22 INFO - 'PWD': 'C:\\slave\\test', 02:17:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:17:22 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:17:22 INFO - 'SYSTEMDRIVE': 'C:', 02:17:22 INFO - 'SYSTEMROOT': 'C:\\windows', 02:17:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:17:22 INFO - 'TEST1': 'testie', 02:17:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:17:22 INFO - 'USERDOMAIN': 'T-W732-IX-090', 02:17:22 INFO - 'USERNAME': 'cltbld', 02:17:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:17:22 INFO - 'WINDIR': 'C:\\windows', 02:17:22 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:17:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:17:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:17:22 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 1)) 02:17:22 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 2)) 02:17:22 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 3)) 02:17:22 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 4)) 02:17:22 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 5)) 02:17:22 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 6)) 02:17:22 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 7)) 02:17:22 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 8)) 02:17:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash>=0.15->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 02:17:22 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 3)) 02:17:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.0->mozcrash>=0.15->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 02:17:22 INFO - Cleaning up... 02:17:22 INFO - Return code: 0 02:17:22 INFO - Running post-action listener: _resource_record_post_action 02:17:22 INFO - Running post-action listener: _start_resource_monitoring 02:17:22 INFO - Starting resource monitoring. 02:17:22 INFO - ##### 02:17:22 INFO - ##### Running install step. 02:17:22 INFO - ##### 02:17:22 INFO - Running pre-action listener: _resource_record_pre_action 02:17:22 INFO - Running main action method: install 02:17:22 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 02:17:22 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 02:17:23 INFO - Reading from file tmpfile_stdout 02:17:23 INFO - Using _rmtree_windows ... 02:17:23 INFO - Using _rmtree_windows ... 02:17:23 INFO - Detecting whether we're running mozinstall >=1.0... 02:17:23 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 02:17:23 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 02:17:23 INFO - Reading from file tmpfile_stdout 02:17:23 INFO - Output received: 02:17:23 INFO - Usage: mozinstall-script.py [options] installer 02:17:23 INFO - Options: 02:17:23 INFO - -h, --help show this help message and exit 02:17:23 INFO - -d DEST, --destination=DEST 02:17:23 INFO - Directory to install application into. [default: 02:17:23 INFO - "C:\slave\test"] 02:17:23 INFO - --app=APP Application being installed. [default: firefox] 02:17:23 INFO - Using _rmtree_windows ... 02:17:23 INFO - Using _rmtree_windows ... 02:17:23 INFO - mkdir: C:\slave\test\build\application 02:17:23 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-44.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 02:17:23 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-44.0a1.en-US.win32.zip --destination C:\slave\test\build\application 02:17:25 INFO - Reading from file tmpfile_stdout 02:17:25 INFO - Output received: 02:17:25 INFO - C:\slave\test\build\application\firefox\firefox.exe 02:17:25 INFO - Using _rmtree_windows ... 02:17:25 INFO - Using _rmtree_windows ... 02:17:25 INFO - Running post-action listener: _resource_record_post_action 02:17:25 INFO - ##### 02:17:25 INFO - ##### Running run-tests step. 02:17:25 INFO - ##### 02:17:25 INFO - Running pre-action listener: _resource_record_pre_action 02:17:25 INFO - Running main action method: run_tests 02:17:25 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 02:17:25 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 02:17:25 INFO - Python 2.7.3 02:17:25 INFO - Return code: 0 02:17:25 INFO - grabbing minidump binary from tooltool 02:17:25 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:17:25 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012E8B48>, '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 0x0130A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01AA6660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 02:17:25 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 02:17:25 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 02:17:25 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 02:17:27 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpokhoqn 02:17:27 INFO - INFO - File integrity verified, renaming tmpokhoqn to win32-minidump_stackwalk.exe 02:17:27 INFO - Return code: 0 02:17:27 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 02:17:27 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 02:17:27 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 02:17:27 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 02:17:27 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 02:17:27 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Ash-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-090', '--webServer', 'localhost'] in C:\slave\test\build 02:17:27 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --debug --branchName Ash-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-090 --webServer localhost 02:17:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 02:17:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 02:17:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 02:17:27 INFO - 'COMPUTERNAME': 'T-W732-IX-090', 02:17:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 02:17:27 INFO - 'CYGWINBASE': 'C:\\cygwin', 02:17:27 INFO - 'DCLOCATION': 'SCL3', 02:17:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 02:17:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 02:17:27 INFO - 'HOMEDRIVE': 'C:', 02:17:27 INFO - 'HOMEPATH': '\\Users\\cltbld', 02:17:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 02:17:27 INFO - 'KTS_VERSION': '1.19c', 02:17:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 02:17:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-090', 02:17:27 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 02:17:27 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 02:17:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 02:17:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 02:17:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:27 INFO - 'MOZ_NO_REMOTE': '1', 02:17:27 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 02:17:27 INFO - 'NO_EM_RESTART': '1', 02:17:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 02:17:27 INFO - 'OS': 'Windows_NT', 02:17:27 INFO - 'OURDRIVE': 'C:', 02:17:27 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 02:17:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 02:17:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 02:17:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 02:17:27 INFO - 'PROCESSOR_LEVEL': '6', 02:17:27 INFO - 'PROCESSOR_REVISION': '1e05', 02:17:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 02:17:27 INFO - 'PROGRAMFILES': 'C:\\Program Files', 02:17:27 INFO - 'PROMPT': '$P$G', 02:17:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 02:17:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 02:17:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 02:17:27 INFO - 'PWD': 'C:\\slave\\test', 02:17:27 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 02:17:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 02:17:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 02:17:27 INFO - 'SYSTEMDRIVE': 'C:', 02:17:27 INFO - 'SYSTEMROOT': 'C:\\windows', 02:17:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:17:27 INFO - 'TEST1': 'testie', 02:17:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 02:17:27 INFO - 'USERDOMAIN': 'T-W732-IX-090', 02:17:27 INFO - 'USERNAME': 'cltbld', 02:17:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 02:17:27 INFO - 'WINDIR': 'C:\\windows', 02:17:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 02:17:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 02:17:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 02:17:27 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Ash-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-090', '--webServer', 'localhost'] with output_timeout 3600 02:17:28 INFO - mozversion INFO | application_buildid: 20151026140824 02:17:28 INFO - mozversion INFO | application_changeset: a759c44ddb56eac3fd2e6cd23310e8f89be4ec18 02:17:28 INFO - mozversion INFO | application_display_name: Nightly 02:17:28 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:17:28 INFO - mozversion INFO | application_name: Firefox 02:17:28 INFO - mozversion INFO | application_remotingname: firefox 02:17:28 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/projects/ash 02:17:28 INFO - mozversion INFO | application_vendor: Mozilla 02:17:28 INFO - mozversion INFO | application_version: 44.0a1 02:17:28 INFO - mozversion INFO | platform_buildid: 20151026140824 02:17:28 INFO - mozversion INFO | platform_changeset: a759c44ddb56eac3fd2e6cd23310e8f89be4ec18 02:17:28 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/projects/ash 02:17:28 INFO - mozversion INFO | platform_version: 44.0a1 02:17:28 INFO - 2015-10-27 02:17:28,482 DEBUG : using testdate: 1445937448 02:17:28 INFO - 2015-10-27 02:17:28,482 DEBUG : actual date: 1445937448 02:17:28 INFO - 2015-10-27 02:17:28,578 INFO : starting webserver on 'localhost:49824' 02:17:28 INFO - 2015-10-27 02:17:28,581 INFO : Starting test suite T-W732-IX-090 02:17:28 INFO - 2015-10-27 02:17:28,581 INFO : Starting test dromaeo_css 02:17:28 INFO - 2015-10-27 02:17:28,581 DEBUG : operating with platform_type : w7_ 02:17:28 INFO - 2015-10-27 02:17:28,582 INFO : Initialising browser for dromaeo_css test... 02:17:28 INFO - 2015-10-27 02:17:28,678 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpyzoceq\profile http://localhost:49824/getInfo.html 02:17:37 INFO - 2015-10-27 02:17:37,305 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:17:37 INFO - 2015-10-27 02:17:37,305 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:17:37 INFO - 2015-10-27 02:17:37,305 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 02:17:37 INFO - 2015-10-27 02:17:37,305 DEBUG : BROWSER_OUTPUT: __metrics 02:17:37 INFO - 2015-10-27 02:17:37,428 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:17:38 INFO - 2015-10-27 02:17:38,898 INFO : Browser initialized. 02:17:38 INFO - 2015-10-27 02:17:38,898 INFO : Running cycle 1/1 for dromaeo_css test... 02:17:38 INFO - 2015-10-27 02:17:38,898 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpyzoceq\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 02:17:50 INFO - 2015-10-27 02:17:50,506 DEBUG : BROWSER_OUTPUT: RSS: Main: 149737472 02:17:50 INFO - 2015-10-27 02:17:50,506 DEBUG : BROWSER_OUTPUT: 02:18:41 INFO - 2015-10-27 02:18:41,480 DEBUG : BROWSER_OUTPUT: 1445937521484 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:20:54 INFO - 2015-10-27 02:20:54,081 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49824/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49824/tests/dromaeo/cssquery-ext.html) 02:20:55 INFO - 2015-10-27 02:20:55,161 DEBUG : BROWSER_OUTPUT: RSS: Main: 170795008 02:20:55 INFO - 2015-10-27 02:20:55,161 DEBUG : BROWSER_OUTPUT: 02:23:57 INFO - 2015-10-27 02:23:57,947 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49824/tests/dromaeo/cssquery-ext.html (next: http://localhost:49824/tests/dromaeo/cssquery-jquery.html) 02:23:58 INFO - 2015-10-27 02:23:58,510 DEBUG : BROWSER_OUTPUT: RSS: Main: 204857344 02:23:58 INFO - 2015-10-27 02:23:58,510 DEBUG : BROWSER_OUTPUT: 02:27:00 INFO - 2015-10-27 02:27:00,665 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49824/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49824/tests/dromaeo/cssquery-mootools.html) 02:27:01 INFO - 2015-10-27 02:27:01,226 DEBUG : BROWSER_OUTPUT: RSS: Main: 150364160 02:27:01 INFO - 2015-10-27 02:27:01,226 DEBUG : BROWSER_OUTPUT: 02:30:03 INFO - 2015-10-27 02:30:03,509 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49824/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49824/tests/dromaeo/cssquery-prototype.html) 02:30:04 INFO - 2015-10-27 02:30:04,084 DEBUG : BROWSER_OUTPUT: RSS: Main: 142409728 02:30:04 INFO - 2015-10-27 02:30:04,084 DEBUG : BROWSER_OUTPUT: 02:33:07 INFO - 2015-10-27 02:33:07,138 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49824/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49824/tests/dromaeo/cssquery-yui.html) 02:33:07 INFO - 2015-10-27 02:33:07,706 DEBUG : BROWSER_OUTPUT: RSS: Main: 131538944 02:33:07 INFO - 2015-10-27 02:33:07,706 DEBUG : BROWSER_OUTPUT: 02:36:10 INFO - 2015-10-27 02:36:10,581 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49824/tests/dromaeo/cssquery-yui.html (next: http://localhost:49824/tests/dromaeo/cssquery-dojo.html) 02:36:10 INFO - 2015-10-27 02:36:10,828 DEBUG : BROWSER_OUTPUT: RSS: Main: 188313600 02:36:10 INFO - 2015-10-27 02:36:10,828 DEBUG : BROWSER_OUTPUT: 02:36:10 INFO - 2015-10-27 02:36:10,828 DEBUG : BROWSER_OUTPUT: __start_tp_report 02:36:10 INFO - 2015-10-27 02:36:10,828 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 02:36:10 INFO - 2015-10-27 02:36:10,828 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 02:36:10 INFO - 2015-10-27 02:36:10,828 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 02:36:10 INFO - 2015-10-27 02:36:10,828 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2327.359976006598;2337.9181728639496;2372.2764556810166;2380.511995041016;2413.7586241375852;4099.815508302127;4128.44266024087;4148.045949431633;4205.369194620808;4212.747235165884;4194.224068547321;4215.747055176684;4258.127083947791;4261.339492378686;4267.061246525767;3568.6252943440822;3582.1940063485767;3671.1556342041345;3700.241450502654;3759.191773768639;3637.272545490899;3644.4351125575577;3650.0874781304674;3683.631636836322;3720.1257704439436;3703.759255648388;3714.0900479382453;3749.306378320006;3765.5293088363956;3781.4894989176523;3546.804925729084;3636.1455058061333;3659.1949771050326;3697.1311741740806;3768.2275133597823;3572.9285414291567;3664.743467957244;3694.2426802505693;3695.9630403696156;3707.7589956652732;4105.199486100211;4136.31750761124;4141.627253547195;4192.979035104805;4206.558311377292;4353.869383918487;4465.933011004838;4531.56950089741;4544.795484203186;4556.248219043854;4422.204003279408;4482.2156122678725;4489.371487991683;4509.6392288616835;4531.9546804531865;3969.861054863066;4122.42286079949;4132.028973191298;4235.2800023996;4244.0663054128045;4414.492333381702;4438.334249862546;4558.703684260512;4610.308453731967;4617.930731039037;4272.188284225987;4335.674824388184;4340.435743353407;4437.8224871004795;4442.355858400579;4283;4423.579759922803;4445.688801783844;4516.683832131785;4526.88682782933;4335.6965012449455;4413.271810151289;4413.470383553994;4488.079943611552;4494.438195225597;4359.825606975749;4453.888652783707;4459.241928872099;4468.03937153507;4471.150481408522;3094.7678924080333;3095.721385075354;3159.7314228290393;3161.731252843534;3163.3040731039137;2804.0606396857283;2891.320539673175;2891.3638999420095;2900.4344152890408;2902.4050069735654;4121.9587804121575;4183.728057676241;4210.6841986851105;4264.530901600821;4291.50647675515;4501.077279157765;4505.6846020778885;4509.887252818706;4585.839495617637;4609.331646911179;4070.6946978976102;4208.789560521962;4285.442892424027;4293.227219100592;4296.205202037633;3685.668289853926;3710.07248187953;3747.6814470769746;3775.3581891078034;3784.1674831537016;4579.564941330568;4588.082383523242;4613.423322084739;4667.9766601166775;4688.765561721969;4371.868843934687;4482.75344856036;4516.569095557493;4525.298578720303;4620.330052142353;4501.09978004407;4534.115847409856;4583.083383323282;4588.197065513588;4604.355390245435;4400.141972315496;4427.579379958899;4519.0058187198765;4521.932171017504;4524.457065152203;4155.355919832431;4490.977545112253;4494.483134439582;4572.748498832596;4582.977085114553;4059.289624315674;4226.42943202676;4232.746035237896;4279.400883876322;4290.356446533045;4268.061026574149;4284.4430224070675;4345.044090300129;4346.47842258931;4357.891052723708;4101.405296232089;4218.325067989107;4309.719868208682;4313.3961245425035;4315.78421078951;4241.618254357123;4357.673174511988;4376.693631445768;4384.4519435070615;4410.779461026872;4300.655947524128;4320.52474227841;4401.559844015573;4410.691251612485;4412.22786012453 02:36:10 INFO - 2015-10-27 02:36:10,828 DEBUG : BROWSER_OUTPUT: |1;ext.html;461.6722127289624;472.60065244868093;483.990320193596;484.4235359921691;505.6207844116912;25001.624975625356;34635.480467793;35685.46471802925;35729.64270357299;36126;38301;38659;39190;39225.41161882574;39287.607123928756;6182.474489668383;6533.934660653404;7881.881771773428;8128.105908350077;8145.1854814518665;9887.555060022316;10188.083072523472;10188.388696678223;10199.541020654051;10203.285769996104;9680.225581953462;10016.248781341428;10059.849102263472;10228.74428139295;10236.744081397987;6489.545731798776;6625.801225963229;9729.659461918798;9937.850932235986;9939.950300248525;9549.09283618055;9719.56261968213;9740.512974351324;9745.366551174222;9768.51157442125;33522.1619459513;34113.488297675554;34152.829235853664;34187;34236.82881585576;2564.9615255771178;2581.8967241310324;2593.8962441502317;2616.699079605851;2677.049647375173;27048.459030819275;27402.314942126406;27551;27991.160265192077;28427.85786071077;2249.921252756146;2267.5691618592414;2283.6574513822893;2307.4231442139467;2343.9296821095395;219884;220867;222814;223318;224483;40574.79712601418;41613;49336.50663493391;49980;49988;53134.46865531295;53300;53446;53485.1977220342;53599.46400535945;11243.437828108725;11280.943595281971;11282;11311.208215424842;11338.489767960462;8091.474054186459;8363.665453381918;8364.623591938378;8377.120402357665;8383.077861435237;15577.924609402342;15873.365065397486;16751.162441877856;16809.495715128567;16809.554041421477;7283.635818209174;7433.256674332523;7510.774676759706;7533.7363192288;7563.584002879785;798.2775588092785;832.6086739232551;855.0722466124324;888.3071204460531;935.7192842147329;25465.61801572978;25786.74213257881;25796.775403074615;26461.603075953877;26515.337116571853;878.4377998081233;895.7223260789176;906.9229115525254;986.413084214891;1017.353980222564;12519.561815336601;12710.809337859939;12860.228386296665;12863.935680321725;13904.721905561832;258537;258931;264690;265293;267829;41115.177696445906;43919.78040109779;44254;44336.55663443324;44473;1046.3146638951498;1075.908547773448;1077.439731339699;1098.3739268616812;1101.658485869383;20328.491787705425;24515.851885892025;25775;26301.73698262993;26979.460410792068;39791.2041759169;40218.59781402266;40269.973002700666;40291.39562906617;40322.59677403188;212729;213567.72616625714;214256;214481;215766;22383.77616223882;22683.20608778685;25567.744322556537;26288.474230515665;26422.471550568498;33427.331453371284;34314.656853431145;34384.65615343815;34416.483752743225;34840.47739283862;29247.561286580294;29773;30742.692573073982;31472;31698.207544811565;24046.51906961816;26182.86908565445;26290.21129366122;30719.2320191997;31537;28760.712392875805;30730.692693073677;31513.21216969594;31593.36813263768;31658.525122123647;27353.589696154177;28727.873308104994;28832.135035948955;28850.27874303159;28898.711012889602;22726;22857.771422285565;22910.541789164457;22958.311250662508;23008.4247893804 02:36:10 INFO - 2015-10-27 02:36:10,834 DEBUG : BROWSER_OUTPUT: |2;jquery.html;291.49134759844077;294.0003986446084;300.17601683379127;301.74351800969174;313.53127075022843;10175.643852465148;11624.709382265448;15939.681206375866;16399.999999999985;16400.917995410022;14704.485343012997;14992.400303987828;15168.9381743278;15309;15323.463678771246;4921.729304888238;5051.924221136685;5065;5097.515736005073;5122.103631864427;3491.598466176397;3614.222942067455;5052.191649336107;5743.712814359286;5977.641341519501;5830.475257226849;5881.176635271044;5916.497097746696;5916.940830591703;5931.940680593182;5441.319835020622;5865.237519122508;5878.177055212273;5887.4406931341455;5997.070454079625;4952.653314268003;4994.076095922266;5176.938727560841;5194.324737784102;5194.740262986836;20202.393928182177;20212.19151233949;20317.390478285673;20634.896825515927;20723;1706.4283465039227;1765.4085881229803;1798.9280428782833;1829.9725504117448;1880.3982725527837;12987.155834870704;13042.41309141091;13043.934780326037;13056.804147937788;13495.527656532073;1693.4411644157522;1699.7535357373235;1710.4441056656635;1766.3199668127863;1772.459399883035;57248.427515724725;57370.13944790832;59067.70466147685;59100.40899590992;59608.70195648994;9249.398789078688;9434.90565094354;9449.480278584606;9456.243500519942;9457.10157535033;11783.76432471363;11839.34883581394;11839.467223974942;11859.407029648482;11882.346470944009;6408.198975128109;6580.473562115115;6635.6350400728425;6656.866862662817;6695.598264104169;6342.936570634234;6344.984802431589;6379.075034120026;6387.329330420332;6398.168238129014;5196.506331898464;5327.866803329948;5432;5432.429594892558;5451.700156491352;1549.8992565483209;1597.8561929426176;1625.325489921693;1629.0225864481217;1633.3058450158635;342.1617681333346;353.5986655146376;362.0117080370603;380.0100737578623;381.7461388176831;10224.13094886928;10226;10557.47212639365;10617.256792024638;10796.406197659204;372.73722025971625;379.1715102500989;384.8018270590648;400.6994753934549;407.67589766135995;5623.325200975909;5859.824205273849;5892.438503796498;5933.6736479493175;5941.821745347646;283950;284159;285025;285760;286895;58213;59006.70496647489;60214.39785602176;60459.39540604626;66397.33602664009;388.59391935427544;390.0813584009617;391.72774921429334;392.7506033668641;401.87340987588817;9319.813603728024;9406.905930940877;9499;9525.714228572877;9730.902690973282;32245.838770805996;33273.66726332706;35070;35306.293874122886;35475.467867982516;44948.55051449574;45272.773636131606;45417;46048;46279.305810412196;5212.98346822366;5303.4696530346655;5373.489518495737;5382.192671099367;5391.460853914734;9940.353876997971;10187.134093602126;10352.17182625373;10366.118879894992;10474.633387831389;7944.483608565425;8003.31971782405;8688.652453901774;8810.471371717718;9086;8838.823223535623;8871.245944094566;8926.241269491906;9082.909170908206;9096.226820720312;8115.472494287852;8993.505357205417;9216.861747073934;9325.580348884181;9517.952410237904;5298.973505132604;5467.7266136692215;5522.558195344443;5734.770609175753;5779.162021506774;8469.195426434479;8750.212480876751;8872.378933474594;8956.238719708897;8973.102689730975 02:36:10 INFO - 2015-10-27 02:36:10,835 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1122.927009744366;1258.9118761686682;1304.3543445994235;1464.1654257073467;1570.5523925681184;6899.517033807636;7783.33841623462;8063.596820158983;8068.798280043002;8098.716544920929;7829.295363417291;7909.367250619951;7916.168802275764;7917.881231781528;7924.722634707787;1745.6159644878107;1778.8487978521841;1965.8132477414622;2026.1186383922966;2036.501057240978;2084.718562993999;2098.3914664747203;2109.5886302171084;2118.110393634677;2118.54451292972;2040.2349119080345;2052.5586998795256;2059.7116403703494;2065.380385884236;2066.3904148276297;2082.208760670935;2086.426232785981;2106.3365040012422;2120.586485635302;2121.8302535797175;2104.252990188492;2120.363890832744;2148.1729534129317;2151.924682636116;2153.7415510138726;7952.244536768998;8122.675092996273;8140.796480087986;8146.633401496888;8147.959260203661;4429.623482004034;4469.418975533193;4584.587387135174;4590.403247577793;4606.539346065367;2393.748656391089;2741.533939230316;2785.7214278572183;2786.178077467152;2794.832310061403;720.1538192623647;796.4385108498494;832.6877420967137;834.2241715204857;834.845553572591;10688.893111068868;10922.235443518957;10958.178136639704;10960.780784384347;10990.560377584887;3982.203559288096;3993.0815912340327;4033.1328764315813;4096.487939007624;4100.753954762664;8086.150954149847;8458.069612342637;8461.788455288666;8463.153684631488;8464.534450605275;4407.074514352022;4420.381146639473;4420.690551661352;4431.556844315607;4436.046250056189;4031.254217969685;4049.0484736086996;4049.5140583130215;4052.3111071117983;4054.5742697016976;2944.8085874418093;3080.0297906159935;3193.0740085374982;3252.902412927616;3391.559097317333;982.0670363154888;1004.5128112865254;1016.7407311135613;1029.46467836726;1030.7989941961246;879.9956000219958;880.1154839386504;881.2201201936333;882.7793051737066;944.4946953379908;6859.794206173823;7251.963740181265;7401.888971665429;7405.9631651568825;7419.183889772121;955.6273053509137;972.3728195313982;975.4488713876636;986.156835905305;986.7631768375682;5363.570914326844;5518.475744804237;5589.636673616202;5605.663660180402;5614.522765564891;9346.672866449777;9506.90493095074;9733.902660973441;9743.805123897624;9754;9488.33581649278;9665.081817227352;9680.515974201262;9685.854712179324;9693.321467497348;7138.357547820721;7178.2103968563415;7191.424686025105;7201.639918004079;7220.855582888418;7017.05269788572;7297.014902988029;7307.67115479794;7309.378702810321;7311.41508679315;7844.294013538809;8335.333173345996;8408.747737567883;8425.83148337042;8432.19894110083;8619.827603447771;8633.740987770378;8634.179752923463;8643.611037503453;8655.826883462421;6677.165354330708;6918.792436226921;6959.686814093479;6991.860162796817;6995.650217489207;6418.903716444163;6597.868042639025;7149.964250178716;7308.123025237006;7686.077670679554;6719.294474080151;6949.096617439903;6997.895031574632;7124.608146551989;7143.607101609254;6748.392644662004;6808;6921.342472465108;6936.132983377078;7023.33278338557;7102.751403700846;7116.323949224816;7130.714771409085;7131.037309963089;7135.928640713526;6073.878522429615;6187.566870319214;6261.96869015652;6284.528660350401;6290.937090629035;6089.117078023751;6233.127362169209;6245.094461302995;6266.811995640138;6276.497880169484 02:36:10 INFO - 2015-10-27 02:36:10,880 DEBUG : BROWSER_OUTPUT: |4;prototype.html;284.8063316944477;293.2785348043812;294.2628715068753;296.28151732049787;300.8465682501923;4592.931106033412;4674.602658774;4677.22825733754;4687.22660760974;4695.8356457524;4528.479224889139;4565.429321334833;4615.192341340269;4635.281531362644;4639.373684552585;3556.022093924166;3560.2523470071314;3565.6256093110237;3566.056499207938;3569.2861427714433;3422.178677117486;3446.6553344665585;3514.7188224941942;3564.0555252857885;3571.92856142877;3532.4348104303317;3535.4343305071193;3541.5395998520157;3551.804650744221;3554.3246783111126;3436.5360676308756;3522.189896323834;3528.400171970771;3532.5937517185594;3534.8586056557706;3161.0674850919036;3161.3203161320425;3164.5095010273444;3165.8891938782263;3168.825714585697;4453.4433195850515;4517.819287228507;4520.073384956077;4523.909521809578;4528.207563676377;4172.311568591209;4214.220369231702;4217.514985776645;4217.789110544491;4228.978855105735;4711.740854253015;4965.155923492979;4967.10592093423;4969.353983982095;4977.527134922177;3579.158897659048;3595.2989167112423;3617.6382361763876;3619.8009109498967;3621.7102631789394;5003.349564556621;5010.624203184776;5138.3833939927445;5159.406668233164;5162.993216322819;3551.4317709166407;3598.334308152948;3649.4708267301094;3709.9258014840093;3714.0343510687417;3748.7001039917286;3759.3797023491366;3760.9247815043545;3763.5671897732113;3770.038640146745;4190.329547272422;4208;4222.176675548239;4222.19778242133;4223.936640950388;4904.950950490449;4906.165951788204;4907.558319751168;4907.975460122677;4912.852614421501;1337.3714354253486;1339.484298545067;1359.3135466589308;1362.4413990263945;1364.0724307470816;922.0502882031518;939.1735272959752;955.5222388805597;958.836997710391;958.9041095890356;4587.197240482902;4717.764111794397;4721.669483136147;4724.126036683225;4733.5503127202865;5101.362329708786;5105.642605017687;5108.974455127701;5119.180931051014;5123.231515272739;4706.270528068155;4711.340412342275;4725.054989002145;4727.929081063787;4732.266498692708;2780.4439112177647;2799.7760179186025;2800.271929298397;2807.0596350222495;2808.817426867247;5118.232265160255;5152.871178220499;5209.973950130301;5213.348331458567;5224.085784987688;5194.4805519447755;5226.712530810766;5243.318368612132;5246.160614301693;5246.921296180561;5199.532042116228;5206.323177986837;5206.869828254324;5211.374635043819;5212.009718153538;5122.206058061007;5129.640925135204;5131.435542090292;5138.486151384832;5142.6400151989;4758.095961767253;4976.129177394014;5003.674761140514;5048.3941926969;5069.619778516552;5096.592272618256;5203.349581302337;5258.421573626974;5262.447443018581;5265.631405801711;3489.2323688789443;3531.6291789362776;3565.893023209308;3566.910827229236;3578.0697018774786;4963.602911766978;5027.2207807789855;5071.492850714899;5079.517445842639;5086.7710953007945;4791.880202994953;4840.080384726891;4841.394825646794;4851.223804191312;4858.4898585648;4670.953290467188;4834.734089625104;4843.660943734046;4844.806207751651;4857.805687772591;4751.501092385388;4774.546418090275;4783.210770222874;4783.856484305476;4784.330193772944;4952.579030782424;4954;4956.281339205867;4959.355283813081;4960.70235785854;4737.976310118427;4865.245886887538;4871;4887.047025829929;4931.408231012301 02:36:10 INFO - 2015-10-27 02:36:10,881 DEBUG : BROWSER_OUTPUT: |5;yui.html;596.4900010491031;629.6505439481087;707.1796715809659;710.9324614161653;712.9394001509871;10578.99999999999;10645.14838812895;10657.360558366503;10668.466576671164;10669.626563070284;9845.507724613759;10171.135453486444;10294.845577316328;10306;10320.432376219305;599.0684485624852;604.9697515124249;614.8217017065045;615.4307265779158;615.4645458451154;579.1486514823204;611.4008271893547;616.2204810914175;616.2327901762312;616.7872084130968;641.0672471553878;644.8452371430847;645.2031740800109;645.2740666749906;645.5803727577089;615.3753939751156;619.6189343553727;640.4588123036027;640.942315191635;641.5541198866789;458.0586893932966;468.84996801023686;468.89449873778125;470.2640367824378;470.3109943932156;9619.615215391375;9663.806723865553;9742.123208911233;9742.610295588169;9744.220462362993;7599.962000190019;7702.653380597886;7747.186545412706;7776.961115194445;8191.098979112293;7622.352100071445;7817.296443320071;8067.757967260992;8538.615762290712;8780.736577902673;2527.747225277476;2627.868606569664;2682.8926842926453;2718.6058021586955;2725.9182224533297;32799.8520051797;33106.83446582776;33108.503372449435;33212;33363.83318083418;5448.945510544923;5471.878264955674;5496.917546236809;5624.100143976944;5685.886282274414;8993.775155621031;9015.729528114167;9045.77385565366;9060.818783624423;9074.502707053261;4812.735299558488;4925.335079764258;4945.826896058621;4959.20652695567;5049.116404629175;3030.696930306996;3042.9239269018008;3081.691830816945;3124.047165614464;3149.401613693391;2017.0923084611984;2120.787921207898;2133.5412886229224;2155.181031208162;2155.342620500762;343.2431488567752;353.4786190369185;353.59689953453085;354.569198423911;354.7747180540375;540.9188621706776;577.5725962787591;578.4649199490455;587.6679675983141;617.8151628355832;6936.7572134975035;7007.4394048476;7096.787096387116;7116.217216106224;7133.429325653935;474.371457818392;532.7682458130726;565.3159676791129;610.9694515274218;616.0881894795727;3283.277678910636;3362.529245905575;3384.8476818543227;3387.187075102007;3424.178197232646;43113.13773724508;43211.56788432138;43529.564704353186;43814.78092609517;43839.12321753547;33310.8334458331;33739.15652108717;37073;37157;37214.25571488555;30768.076957690857;30862.999999999553;30884;30949.381012379625;31168.064958051295;7047.436205103477;7049.400800931979;7162.856742865218;7198.71205151788;7239.529430586995;21599.956184681643;21818.345449636538;21868;21953.67069493991;22578.645281283174;30320.84839575788;30675.2331191722;31005.0698479046;31059;31073.53389699111;9236.953815230881;9270.119338663086;9335.766605834908;9462.148406643435;9476.763080923032;9659.758506037406;9686.64095669062;10155.898441015495;10175.7456063599;10567.841482377617;9801.215902727907;9836.852447213154;10032.297739158188;10115.7471063224;10137.391756494633;9655.324127311022;9676.612935482503;9702.126808587262;9884.258680599127;9893.307468477138;8657.6969806059;9400;9407.576659050495;9440.763980900532;9602.231821454407;7593.620318984139;8311.6675332986;8375.246227839523;8607.397482176188;9081.455112692995;8243.876341854757;8589.828203436022;8690.261327786957;8820.867686984571;8877.778055548662 02:36:10 INFO - 2015-10-27 02:36:10,881 DEBUG : BROWSER_OUTPUT: __end_tp_report 02:36:10 INFO - 2015-10-27 02:36:10,881 DEBUG : BROWSER_OUTPUT: __start_cc_report 02:36:10 INFO - 2015-10-27 02:36:10,881 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,851 02:36:10 INFO - 2015-10-27 02:36:10,881 DEBUG : BROWSER_OUTPUT: __end_cc_report 02:36:10 INFO - 2015-10-27 02:36:10,881 DEBUG : BROWSER_OUTPUT: __startTimestamp1445938570850__endTimestamp 02:36:10 INFO - 2015-10-27 02:36:10,881 DEBUG : BROWSER_OUTPUT: 02:36:10 INFO - 2015-10-27 02:36:10,881 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 02:36:10 INFO - 2015-10-27 02:36:10,881 DEBUG : BROWSER_OUTPUT: Number of tests: 6 02:36:10 INFO - 2015-10-27 02:36:10,882 DEBUG : BROWSER_OUTPUT: 02:36:10 INFO - 2015-10-27 02:36:10,882 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4106.78 Median:4284.44 stddev:520.48 (12.1%) stddev-sans-first:503.01 02:36:10 INFO - 2015-10-27 02:36:10,882 DEBUG : BROWSER_OUTPUT: Values: 2327.4 2337.9 2372.3 2380.5 2413.8 4099.8 4128.4 4148.0 4205.4 4212.7 4194.2 4215.7 4258.1 4261.3 4267.1 3568.6 3582.2 3671.2 3700.2 3759.2 3637.3 3644.4 3650.1 3683.6 3720.1 3703.8 3714.1 3749.3 3765.5 3781.5 3546.8 3636.1 3659.2 3697.1 3768.2 3572.9 3664.7 3694.2 3696.0 3707.8 4105.2 4136.3 4141.6 4193.0 4206.6 4353.9 4465.9 4531.6 4544.8 4556.2 4422.2 4482.2 4489.4 4509.6 4532.0 3969.9 4122.4 4132.0 4235.3 4244.1 4414.5 4438.3 4558.7 4610.3 4617.9 4272.2 4335.7 4340.4 4437.8 4442.4 4283.0 4423.6 4445.7 4516.7 4526.9 4335.7 4413.3 4413.5 4488.1 4494.4 4359.8 4453.9 4459.2 4468.0 4471.2 3094.8 3095.7 3159.7 3161.7 3163.3 2804.1 2891.3 2891.4 2900.4 2902.4 4122.0 4183.7 4210.7 4264.5 4291.5 4501.1 4505.7 4509.9 4585.8 4609.3 4070.7 4208.8 4285.4 4293.2 4296.2 3685.7 3710.1 3747.7 3775.4 3784.2 4579.6 4588.1 4613.4 4668.0 4688.8 4371.9 4482.8 4516.6 4525.3 4620.3 4501.1 4534.1 4583.1 4588.2 4604.4 4400.1 4427.6 4519.0 4521.9 4524.5 4155.4 4491.0 4494.5 4572.7 4583.0 4059.3 4226.4 4232.7 4279.4 4290.4 4268.1 4284.4 4345.0 4346.5 4357.9 4101.4 4218.3 4309.7 4313.4 4315.8 4241.6 4357.7 4376.7 4384.5 4410.8 4300.7 4320.5 4401.6 4410.7 4412.2 02:36:10 INFO - 2015-10-27 02:36:10,882 DEBUG : BROWSER_OUTPUT: 02:36:10 INFO - 2015-10-27 02:36:10,882 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:38274.74 Median:30246.12 stddev:60969.39 (201.6%) stddev-sans-first:61074.32 02:36:10 INFO - 2015-10-27 02:36:10,882 DEBUG : BROWSER_OUTPUT: Values: 461.7 472.6 484.0 484.4 505.6 25001.6 34635.5 35685.5 35729.6 36126.0 38301.0 38659.0 39190.0 39225.4 39287.6 6182.5 6533.9 7881.9 8128.1 8145.2 9887.6 10188.1 10188.4 10199.5 10203.3 9680.2 10016.2 10059.8 10228.7 10236.7 6489.5 6625.8 9729.7 9937.9 9940.0 9549.1 9719.6 9740.5 9745.4 9768.5 33522.2 34113.5 34152.8 34187.0 34236.8 2565.0 2581.9 2593.9 2616.7 2677.0 27048.5 27402.3 27551.0 27991.2 28427.9 2249.9 2267.6 2283.7 2307.4 2343.9 219884.0 220867.0 222814.0 223318.0 224483.0 40574.8 41613.0 49336.5 49980.0 49988.0 53134.5 53300.0 53446.0 53485.2 53599.5 11243.4 11280.9 11282.0 11311.2 11338.5 8091.5 8363.7 8364.6 8377.1 8383.1 15577.9 15873.4 16751.2 16809.5 16809.6 7283.6 7433.3 7510.8 7533.7 7563.6 798.3 832.6 855.1 888.3 935.7 25465.6 25786.7 25796.8 26461.6 26515.3 878.4 895.7 906.9 986.4 1017.4 12519.6 12710.8 12860.2 12863.9 13904.7 258537.0 258931.0 264690.0 265293.0 267829.0 41115.2 43919.8 44254.0 44336.6 44473.0 1046.3 1075.9 1077.4 1098.4 1101.7 20328.5 24515.9 25775.0 26301.7 26979.5 39791.2 40218.6 40270.0 40291.4 40322.6 212729.0 213567.7 214256.0 214481.0 215766.0 22383.8 22683.2 25567.7 26288.5 26422.5 33427.3 34314.7 34384.7 34416.5 34840.5 29247.6 29773.0 30742.7 31472.0 31698.2 24046.5 26182.9 26290.2 30719.2 31537.0 28760.7 30730.7 31513.2 31593.4 31658.5 27353.6 28727.9 28832.1 28850.3 28898.7 22726.0 22857.8 22910.5 22958.3 23008.4 02:36:10 INFO - 2015-10-27 02:36:10,882 DEBUG : BROWSER_OUTPUT: 02:36:10 INFO - 2015-10-27 02:36:10,882 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:19505.86 Median:5058.60 stddev:47341.94 (935.9%) stddev-sans-first:47452.77 02:36:10 INFO - 2015-10-27 02:36:10,884 DEBUG : BROWSER_OUTPUT: Values: 291.5 294.0 300.2 301.7 313.5 10175.6 11624.7 15939.7 16400.0 16400.9 14704.5 14992.4 15168.9 15309.0 15323.5 4921.7 5051.9 5065.0 5097.5 5122.1 3491.6 3614.2 5052.2 5743.7 5977.6 5830.5 5881.2 5916.5 5916.9 5931.9 5441.3 5865.2 5878.2 5887.4 5997.1 4952.7 4994.1 5176.9 5194.3 5194.7 20202.4 20212.2 20317.4 20634.9 20723.0 1706.4 1765.4 1798.9 1830.0 1880.4 12987.2 13042.4 13043.9 13056.8 13495.5 1693.4 1699.8 1710.4 1766.3 1772.5 57248.4 57370.1 59067.7 59100.4 59608.7 9249.4 9434.9 9449.5 9456.2 9457.1 11783.8 11839.3 11839.5 11859.4 11882.3 6408.2 6580.5 6635.6 6656.9 6695.6 6342.9 6345.0 6379.1 6387.3 6398.2 5196.5 5327.9 5432.0 5432.4 5451.7 1549.9 1597.9 1625.3 1629.0 1633.3 342.2 353.6 362.0 380.0 381.7 10224.1 10226.0 10557.5 10617.3 10796.4 372.7 379.2 384.8 400.7 407.7 5623.3 5859.8 5892.4 5933.7 5941.8 283950.0 284159.0 285025.0 285760.0 286895.0 58213.0 59006.7 60214.4 60459.4 66397.3 388.6 390.1 391.7 392.8 401.9 9319.8 9406.9 9499.0 9525.7 9730.9 32245.8 33273.7 35070.0 35306.3 35475.5 44948.6 45272.8 45417.0 46048.0 46279.3 5213.0 5303.5 5373.5 5382.2 5391.5 9940.4 10187.1 10352.2 10366.1 10474.6 7944.5 8003.3 8688.7 8810.5 9086.0 8838.8 8871.2 8926.2 9082.9 9096.2 8115.5 8993.5 9216.9 9325.6 9518.0 5299.0 5467.7 5522.6 5734.8 5779.2 8469.2 8750.2 8872.4 8956.2 8973.1 02:36:10 INFO - 2015-10-27 02:36:10,885 DEBUG : BROWSER_OUTPUT: 02:36:10 INFO - 2015-10-27 02:36:10,885 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5337.37 Median:6879.66 stddev:2932.45 (42.6%) stddev-sans-first:2923.57 02:36:10 INFO - 2015-10-27 02:36:10,885 DEBUG : BROWSER_OUTPUT: Values: 1122.9 1258.9 1304.4 1464.2 1570.6 6899.5 7783.3 8063.6 8068.8 8098.7 7829.3 7909.4 7916.2 7917.9 7924.7 1745.6 1778.8 1965.8 2026.1 2036.5 2084.7 2098.4 2109.6 2118.1 2118.5 2040.2 2052.6 2059.7 2065.4 2066.4 2082.2 2086.4 2106.3 2120.6 2121.8 2104.3 2120.4 2148.2 2151.9 2153.7 7952.2 8122.7 8140.8 8146.6 8148.0 4429.6 4469.4 4584.6 4590.4 4606.5 2393.7 2741.5 2785.7 2786.2 2794.8 720.2 796.4 832.7 834.2 834.8 10688.9 10922.2 10958.2 10960.8 10990.6 3982.2 3993.1 4033.1 4096.5 4100.8 8086.2 8458.1 8461.8 8463.2 8464.5 4407.1 4420.4 4420.7 4431.6 4436.0 4031.3 4049.0 4049.5 4052.3 4054.6 2944.8 3080.0 3193.1 3252.9 3391.6 982.1 1004.5 1016.7 1029.5 1030.8 880.0 880.1 881.2 882.8 944.5 6859.8 7252.0 7401.9 7406.0 7419.2 955.6 972.4 975.4 986.2 986.8 5363.6 5518.5 5589.6 5605.7 5614.5 9346.7 9506.9 9733.9 9743.8 9754.0 9488.3 9665.1 9680.5 9685.9 9693.3 7138.4 7178.2 7191.4 7201.6 7220.9 7017.1 7297.0 7307.7 7309.4 7311.4 7844.3 8335.3 8408.7 8425.8 8432.2 8619.8 8633.7 8634.2 8643.6 8655.8 6677.2 6918.8 6959.7 6991.9 6995.7 6418.9 6597.9 7150.0 7308.1 7686.1 6719.3 6949.1 6997.9 7124.6 7143.6 6748.4 6808.0 6921.3 6936.1 7023.3 7102.8 7116.3 7130.7 7131.0 7135.9 6073.9 6187.6 6262.0 6284.5 6290.9 6089.1 6233.1 6245.1 6266.8 6276.5 02:36:10 INFO - 2015-10-27 02:36:10,887 DEBUG : BROWSER_OUTPUT: 02:36:10 INFO - 2015-10-27 02:36:10,887 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4141.90 Median:4719.72 stddev:1205.40 (25.5%) stddev-sans-first:1173.50 02:36:10 INFO - 2015-10-27 02:36:10,887 DEBUG : BROWSER_OUTPUT: Values: 284.8 293.3 294.3 296.3 300.8 4592.9 4674.6 4677.2 4687.2 4695.8 4528.5 4565.4 4615.2 4635.3 4639.4 3556.0 3560.3 3565.6 3566.1 3569.3 3422.2 3446.7 3514.7 3564.1 3571.9 3532.4 3535.4 3541.5 3551.8 3554.3 3436.5 3522.2 3528.4 3532.6 3534.9 3161.1 3161.3 3164.5 3165.9 3168.8 4453.4 4517.8 4520.1 4523.9 4528.2 4172.3 4214.2 4217.5 4217.8 4229.0 4711.7 4965.2 4967.1 4969.4 4977.5 3579.2 3595.3 3617.6 3619.8 3621.7 5003.3 5010.6 5138.4 5159.4 5163.0 3551.4 3598.3 3649.5 3709.9 3714.0 3748.7 3759.4 3760.9 3763.6 3770.0 4190.3 4208.0 4222.2 4222.2 4223.9 4905.0 4906.2 4907.6 4908.0 4912.9 1337.4 1339.5 1359.3 1362.4 1364.1 922.1 939.2 955.5 958.8 958.9 4587.2 4717.8 4721.7 4724.1 4733.6 5101.4 5105.6 5109.0 5119.2 5123.2 4706.3 4711.3 4725.1 4727.9 4732.3 2780.4 2799.8 2800.3 2807.1 2808.8 5118.2 5152.9 5210.0 5213.3 5224.1 5194.5 5226.7 5243.3 5246.2 5246.9 5199.5 5206.3 5206.9 5211.4 5212.0 5122.2 5129.6 5131.4 5138.5 5142.6 4758.1 4976.1 5003.7 5048.4 5069.6 5096.6 5203.3 5258.4 5262.4 5265.6 3489.2 3531.6 3565.9 3566.9 3578.1 4963.6 5027.2 5071.5 5079.5 5086.8 4791.9 4840.1 4841.4 4851.2 4858.5 4671.0 4834.7 4843.7 4844.8 4857.8 4751.5 4774.5 4783.2 4783.9 4784.3 4952.6 4954.0 4956.3 4959.4 4960.7 4738.0 4865.2 4871.0 4887.0 4931.4 02:36:10 INFO - 2015-10-27 02:36:10,888 DEBUG : BROWSER_OUTPUT: 02:36:10 INFO - 2015-10-27 02:36:10,888 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:9947.31 Median:578.81 stddev:11113.94 (1920.1%) stddev-sans-first:11122.93 02:36:10 INFO - 2015-10-27 02:36:10,888 DEBUG : BROWSER_OUTPUT: Values: 596.5 629.7 707.2 710.9 712.9 10579.0 10645.1 10657.4 10668.5 10669.6 9845.5 10171.1 10294.8 10306.0 10320.4 599.1 605.0 614.8 615.4 615.5 579.1 611.4 616.2 616.2 616.8 641.1 644.8 645.2 645.3 645.6 615.4 619.6 640.5 640.9 641.6 458.1 468.8 468.9 470.3 470.3 9619.6 9663.8 9742.1 9742.6 9744.2 7600.0 7702.7 7747.2 7777.0 8191.1 7622.4 7817.3 8067.8 8538.6 8780.7 2527.7 2627.9 2682.9 2718.6 2725.9 32799.9 33106.8 33108.5 33212.0 33363.8 5448.9 5471.9 5496.9 5624.1 5685.9 8993.8 9015.7 9045.8 9060.8 9074.5 4812.7 4925.3 4945.8 4959.2 5049.1 3030.7 3042.9 3081.7 3124.0 3149.4 2017.1 2120.8 2133.5 2155.2 2155.3 343.2 353.5 353.6 354.6 354.8 540.9 577.6 578.5 587.7 617.8 6936.8 7007.4 7096.8 7116.2 7133.4 474.4 532.8 565.3 611.0 616.1 3283.3 3362.5 3384.8 3387.2 3424.2 43113.1 43211.6 43529.6 43814.8 43839.1 33310.8 33739.2 37073.0 37157.0 37214.3 30768.1 30863.0 30884.0 30949.4 31168.1 7047.4 7049.4 7162.9 7198.7 7239.5 21600.0 21818.3 21868.0 21953.7 22578.6 30320.8 30675.2 31005.1 31059.0 31073.5 9237.0 9270.1 9335.8 9462.1 9476.8 9659.8 9686.6 10155.9 10175.7 10567.8 9801.2 9836.9 10032.3 10115.7 10137.4 9655.3 9676.6 9702.1 9884.3 9893.3 8657.7 9400.0 9407.6 9440.8 9602.2 7593.6 8311.7 8375.2 8607.4 9081.5 8243.9 8589.8 8690.3 8820.9 8877.8 02:36:10 INFO - 2015-10-27 02:36:10,888 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 02:36:10 INFO - 2015-10-27 02:36:10,890 DEBUG : BROWSER_OUTPUT: 02:36:11 INFO - 2015-10-27 02:36:11,394 INFO : Browser exited with error code: 0 02:36:12 INFO - 2015-10-27 02:36:12,127 INFO : Completed test dromaeo_css (00:18:43) 02:36:12 INFO - 2015-10-27 02:36:12,127 INFO : Starting test kraken 02:36:12 INFO - 2015-10-27 02:36:12,127 DEBUG : operating with platform_type : w7_ 02:36:12 INFO - 2015-10-27 02:36:12,127 INFO : Initialising browser for kraken test... 02:36:12 INFO - 2015-10-27 02:36:12,153 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpldjeny\profile http://localhost:49824/getInfo.html 02:36:16 INFO - 2015-10-27 02:36:16,575 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 02:36:16 INFO - 2015-10-27 02:36:16,575 DEBUG : BROWSER_OUTPUT: colorDepth:24 02:36:16 INFO - 2015-10-27 02:36:16,575 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 02:36:16 INFO - 2015-10-27 02:36:16,575 DEBUG : BROWSER_OUTPUT: __metrics 02:36:16 INFO - 2015-10-27 02:36:16,719 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:36:17 INFO - 2015-10-27 02:36:17,854 DEBUG : BROWSER_OUTPUT: console.error: 02:36:17 INFO - 2015-10-27 02:36:17,854 DEBUG : BROWSER_OUTPUT: Message: [Exception... "Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [mozIStorageAsyncStatement.executeAsync]" nsresult: "0x80040111 (NS_ERROR_NOT_AVAILABLE)" location: "JS frame :: resource://gre/modules/Sqlite.jsm :: ConnectionData.prototype<._executeStatement :: line 758" data: no] 02:36:17 INFO - 2015-10-27 02:36:17,854 DEBUG : BROWSER_OUTPUT: Stack: 02:36:17 INFO - 2015-10-27 02:36:17,854 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 02:36:17 INFO - 2015-10-27 02:36:17,854 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 02:36:17 INFO - 2015-10-27 02:36:17,854 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 02:36:17 INFO - 2015-10-27 02:36:17,854 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 02:36:17 INFO - 2015-10-27 02:36:17,854 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 02:36:17 INFO - 2015-10-27 02:36:17,854 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 02:36:17 INFO - 2015-10-27 02:36:17,854 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 02:36:17 INFO - 2015-10-27 02:36:17,854 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 02:36:17 INFO - 2015-10-27 02:36:17,854 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 02:36:17 INFO - 2015-10-27 02:36:17,854 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 02:36:17 INFO - 2015-10-27 02:36:17,855 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 02:36:17 INFO - 2015-10-27 02:36:17,855 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 02:36:17 INFO - 2015-10-27 02:36:17,855 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 5662.25 02:37:56 INFO - RETURN: kraken: 1647.10 02:37:56 INFO - RETURN: v8_7: 19943.32 02:37:56 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,144,25]]", "result": "19943.32"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,144,25]]", "result": "1647.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,144,25]]", "result": "5662.25"}}} 02:37:56 INFO - Return code: 0 02:37:56 INFO - # TBPL SUCCESS # 02:37:56 INFO - Running post-action listener: _resource_record_post_action 02:37:56 INFO - Running post-run listener: _resource_record_post_run 02:37:57 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 84878336; Write bytes: 465782272; Read time: 1039000; Write time: 4736290 02:37:57 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 33436672; Read time: 0; Write time: 182090 02:37:57 INFO - run-tests - Wall time: 1231s; CPU: 13.0%; Read bytes: 84861952; Write bytes: 410275328; Read time: 1037730; Write time: 4343450 02:37:57 INFO - Running post-run listener: _upload_blobber_files 02:37:57 INFO - Blob upload gear active. 02:37:57 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 02:37:57 INFO - Copying logs to upload dir... 02:37:57 INFO - mkdir: C:\slave\test\build\upload\logs program finished with exit code 0 elapsedTime=1424.724000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 49 secs) (at 2015-10-27 02:38:03.010413) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-27 02:38:03.013974) ========= '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-090 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-090 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-090 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 build_url:https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/5v9JW5nZTC2AIPfzOQlU-w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-27 02:38:03.148267) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-27 02:38:03.148597) ========= '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-090 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-090 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-090 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 ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-27 02:38:03.266059) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-27 02:38:03.266348) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-27 02:38:03.856963) =========