builder: fx-team_win7-ix_test-dromaeojs slave: t-w732-ix-098 starttime: 1445856536.2 results: success (0) buildid: 20151026030933 builduid: 3123e42579314a7e94b13ee96afbf98a revision: 60d33952d4cad718c786bea4d229cb137d5b2317 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 03:48:56.200795) ========= master: http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 03:48:56.214660) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-26 03:48:56.215112) ========= '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-098 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-098 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-098 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.653000 basedir: 'C:\\slave\\test' ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-26 03:48:56.997996) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-26 03:48:56.998472) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-26 03:48:57.316267) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 03:48:57.316608) ========= '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-098 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-098 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-098 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: 0 secs) (at 2015-10-26 03:48:57.890050) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 03:48:57.890397) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 03:48:57.890827) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 03:48:57.891181) ========= '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-098 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-098 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-098 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 --03:48:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 7.42 MB/s 03:48:58 (7.42 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.752000 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 03:48:58.708384) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 4 secs) (at 2015-10-26 03:48:58.708772) ========= '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-098 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-098 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-098 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=4.001000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 4 secs) (at 2015-10-26 03:49:02.744122) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-26 03:49:02.745370) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 60d33952d4cad718c786bea4d229cb137d5b2317 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 60d33952d4cad718c786bea4d229cb137d5b2317 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-098 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-098 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-098 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-26 03:49:02,951 truncating revision to first 12 chars 2015-10-26 03:49:02,951 Setting DEBUG logging. 2015-10-26 03:49:02,951 attempt 1/10 2015-10-26 03:49:02,951 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/60d33952d4ca?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-26 03:49:03,556 unpacking tar archive at: fx-team-60d33952d4ca/testing/mozharness/ program finished with exit code 0 elapsedTime=1.193000 ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-26 03:49:03.982498) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-26 03:49:03.982959) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-26 03:49:04.035578) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 03:49:04.036142) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 03:49:04.036730) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 59 secs) (at 2015-10-26 03:49:04.037092) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team-Non-PGO' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-098 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-098 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-098 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 03:49:04 INFO - MultiFileLogger online at 20151026 03:49:04 in C:\slave\test 03:49:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 03:49:04 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 03:49:04 INFO - {'append_to_log': False, 03:49:04 INFO - 'base_work_dir': 'C:\\slave\\test', 03:49:04 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 03:49:04 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 03:49:04 INFO - 'branch': 'Fx-Team-Non-PGO', 03:49:04 INFO - 'buildbot_json_path': 'buildprops.json', 03:49:04 INFO - 'config_files': ('talos/windows_config.py',), 03:49:04 INFO - 'default_actions': ('clobber', 03:49:04 INFO - 'read-buildbot-config', 03:49:04 INFO - 'download-and-extract', 03:49:04 INFO - 'populate-webroot', 03:49:04 INFO - 'create-virtualenv', 03:49:04 INFO - 'install', 03:49:04 INFO - 'run-tests'), 03:49:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:49:04 INFO - 'download_minidump_stackwalk': True, 03:49:04 INFO - 'download_symbols': 'ondemand', 03:49:04 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 03:49:04 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 03:49:04 INFO - 'hg': 'c:/mozilla-build/hg/hg', 03:49:04 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 03:49:04 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 03:49:04 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 03:49:04 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 03:49:04 INFO - 'C:/mozilla-build/tooltool.py'), 03:49:04 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 03:49:04 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 03:49:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:49:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:49:04 INFO - 'installer_path': 'installer.exe', 03:49:04 INFO - 'log_level': 'info', 03:49:04 INFO - 'log_name': 'talos', 03:49:04 INFO - 'log_to_console': True, 03:49:04 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 03:49:04 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 03:49:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 03:49:04 INFO - 'opt_config_files': (), 03:49:04 INFO - 'pip_index': False, 03:49:04 INFO - 'sps_profile': False, 03:49:04 INFO - 'sps_profile_interval': 0, 03:49:04 INFO - 'suite': 'dromaeojs', 03:49:04 INFO - 'system_bits': '32', 03:49:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:49:04 INFO - 'title': 'T-W732-IX-098', 03:49:04 INFO - 'use_talos_json': True, 03:49:04 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 03:49:04 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 03:49:04 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 03:49:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:49:04 INFO - 'work_dir': 'build'} 03:49:04 INFO - ##### 03:49:04 INFO - ##### Running clobber step. 03:49:04 INFO - ##### 03:49:04 INFO - Running pre-action listener: _resource_record_pre_action 03:49:04 INFO - Running main action method: clobber 03:49:04 INFO - rmtree: C:\slave\test\build 03:49:04 INFO - Using _rmtree_windows ... 03:49:04 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 03:50:02 INFO - Running post-action listener: _resource_record_post_action 03:50:02 INFO - ##### 03:50:02 INFO - ##### Running read-buildbot-config step. 03:50:02 INFO - ##### 03:50:02 INFO - Running pre-action listener: _resource_record_pre_action 03:50:02 INFO - Running main action method: read_buildbot_config 03:50:02 INFO - Using buildbot properties: 03:50:02 INFO - { 03:50:02 INFO - "properties": { 03:50:02 INFO - "buildnumber": 721, 03:50:02 INFO - "product": "firefox", 03:50:02 INFO - "script_repo_revision": "production", 03:50:02 INFO - "builddir": "fx-team_win7-ix_test-dromaeojs", 03:50:02 INFO - "repository": "", 03:50:02 INFO - "buildername": "Windows 7 32-bit fx-team talos dromaeojs", 03:50:02 INFO - "buildid": "20151026030933", 03:50:02 INFO - "slavename": "t-w732-ix-098", 03:50:02 INFO - "pgo_build": "False", 03:50:02 INFO - "basedir": "C:\\slave\\test", 03:50:02 INFO - "project": "", 03:50:02 INFO - "platform": "win7-ix", 03:50:02 INFO - "master": "http://buildbot-master110.bb.releng.scl3.mozilla.com:8201/", 03:50:02 INFO - "slavebuilddir": "test", 03:50:02 INFO - "scheduler": "tests-fx-team-win32-talos", 03:50:02 INFO - "branch": "fx-team", 03:50:02 INFO - "repo_path": "integration/fx-team", 03:50:02 INFO - "stage_platform": "win32", 03:50:02 INFO - "builduid": "3123e42579314a7e94b13ee96afbf98a", 03:50:02 INFO - "revision": "60d33952d4cad718c786bea4d229cb137d5b2317" 03:50:02 INFO - }, 03:50:02 INFO - "sourcestamp": { 03:50:02 INFO - "repository": "", 03:50:02 INFO - "hasPatch": false, 03:50:02 INFO - "project": "", 03:50:02 INFO - "branch": "fx-team-win32-talos", 03:50:02 INFO - "changes": [ 03:50:02 INFO - { 03:50:02 INFO - "category": null, 03:50:02 INFO - "files": [ 03:50:02 INFO - { 03:50:02 INFO - "url": null, 03:50:02 INFO - "name": "https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 03:50:02 INFO - } 03:50:02 INFO - ], 03:50:02 INFO - "repository": "", 03:50:02 INFO - "rev": "60d33952d4cad718c786bea4d229cb137d5b2317", 03:50:02 INFO - "who": "cbook@mozilla.com", 03:50:02 INFO - "when": 1445856519, 03:50:02 INFO - "number": 6572709, 03:50:02 INFO - "comments": "Bug 1217232 - Hide placeholder svg path in context menu when unblocking image r=mfinkle", 03:50:02 INFO - "project": "", 03:50:02 INFO - "at": "Mon 26 Oct 2015 03:48:39", 03:50:02 INFO - "branch": "fx-team-win32-talos", 03:50:02 INFO - "revlink": "", 03:50:02 INFO - "properties": [ 03:50:02 INFO - [ 03:50:02 INFO - "buildid", 03:50:02 INFO - "20151026030933", 03:50:02 INFO - "Change" 03:50:02 INFO - ], 03:50:02 INFO - [ 03:50:02 INFO - "builduid", 03:50:02 INFO - "3123e42579314a7e94b13ee96afbf98a", 03:50:02 INFO - "Change" 03:50:02 INFO - ], 03:50:02 INFO - [ 03:50:02 INFO - "pgo_build", 03:50:02 INFO - "False", 03:50:02 INFO - "Change" 03:50:02 INFO - ] 03:50:02 INFO - ], 03:50:02 INFO - "revision": "60d33952d4cad718c786bea4d229cb137d5b2317" 03:50:02 INFO - } 03:50:02 INFO - ], 03:50:02 INFO - "revision": "60d33952d4cad718c786bea4d229cb137d5b2317" 03:50:02 INFO - } 03:50:02 INFO - } 03:50:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 03:50:02 INFO - Running post-action listener: _resource_record_post_action 03:50:02 INFO - ##### 03:50:02 INFO - ##### Running download-and-extract step. 03:50:02 INFO - ##### 03:50:02 INFO - Running pre-action listener: _resource_record_pre_action 03:50:02 INFO - Running main action method: download_and_extract 03:50:02 INFO - mkdir: C:\slave\test\build\tests 03:50:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:50:02 INFO - https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 03:50:02 INFO - trying https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/test_packages.json 03:50:02 INFO - Downloading https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json 03:50:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1 03:50:03 INFO - Downloaded 1221 bytes. 03:50:03 INFO - Reading from file C:\slave\test\build\test_packages.json 03:50:03 INFO - Using the following test package requirements: 03:50:03 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 03:50:03 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 03:50:03 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 03:50:03 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 03:50:03 INFO - u'jsshell-win32.zip'], 03:50:03 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 03:50:03 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 03:50:03 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 03:50:03 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 03:50:03 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 03:50:03 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 03:50:03 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 03:50:03 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 03:50:03 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 03:50:03 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 03:50:03 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 03:50:03 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 03:50:03 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 03:50: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')]} 03:50:03 INFO - https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 03:50:03 INFO - trying https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 03:50:03 INFO - Downloading https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/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 03:50:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/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 03:50:05 INFO - Downloaded 18007322 bytes. 03:50:05 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 03:50:05 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 03:50:05 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 03:50:38 INFO - Return code: 0 03:50:38 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 03:50: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')]} 03:50:38 INFO - https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 03:50:38 INFO - trying https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 03:50:38 INFO - Downloading https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/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 03:50:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/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 03:50:40 INFO - Downloaded 18007322 bytes. 03:50:40 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 03:50:40 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 03:50:40 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 03:51:04 INFO - Return code: 0 03:51: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')]} 03:51:04 INFO - https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 03:51:04 INFO - trying https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 03:51:04 INFO - Downloading https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/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 03:51:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/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 03:51:06 INFO - Downloaded 11131307 bytes. 03:51:06 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 03:51:06 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.talos.tests.zip 03:51:06 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 03:51:13 INFO - Return code: 0 03:51:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:51:13 INFO - https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 03:51:13 INFO - trying https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 03:51:13 INFO - Downloading https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.zip 03:51:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/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 03:51:17 INFO - Downloaded 51470164 bytes. 03:51:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 03:51:17 INFO - mkdir: C:\slave\test\properties 03:51:17 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 03:51:17 INFO - Writing to file C:\slave\test\properties\build_url 03:51:17 INFO - Contents: 03:51:17 INFO - build_url:https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 03:51:17 INFO - Running post-action listener: _resource_record_post_action 03:51:17 INFO - Running post-action listener: set_extra_try_arguments 03:51:17 INFO - ##### 03:51:17 INFO - ##### Running populate-webroot step. 03:51:17 INFO - ##### 03:51:17 INFO - Running pre-action listener: _resource_record_pre_action 03:51:17 INFO - Running main action method: populate_webroot 03:51:17 INFO - Guessing talos json url... 03:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:51:17 INFO - https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 03:51:17 INFO - trying https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 03:51:17 INFO - Downloading https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 03:51:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/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 03:51:17 INFO - Downloaded 103 bytes. 03:51:17 INFO - Reading from file C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 03:51:17 INFO - Contents: 03:51:17 INFO - 20151026030933 03:51:17 INFO - https://hg.mozilla.org/integration/fx-team/rev/60d33952d4cad718c786bea4d229cb137d5b2317 03:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:51:17 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/60d33952d4/testing/talos/talos.json 03:51:17 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/60d33952d4/testing/talos/talos.json to C:\slave\test\build\talos.json 03:51:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/60d33952d4/testing/talos/talos.json', 'file_name': 'C:\\slave\\test\\build\\talos.json'}, attempt #1 03:51:18 INFO - Downloaded 4264 bytes. 03:51:18 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 03:51:18 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 03:51:18 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 03:51:18 INFO - u'../fennec_ids.txt'], 03:51:18 INFO - u'tests': [u'tcheck2']}, 03:51:18 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 03:51:18 INFO - u'--tppagecycles', 03:51:18 INFO - u'7'], 03:51:18 INFO - u'tests': [u'tsvgm']}}, 03:51:18 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 03:51:18 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 03:51:18 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 03:51:18 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 03:51:18 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 03:51:18 INFO - u'tests': [u'dromaeo_css', 03:51:18 INFO - u'kraken', 03:51:18 INFO - u'v8_7']}, 03:51:18 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:18 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 03:51:18 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:18 INFO - u'talos_options': [u'--e10s'], 03:51:18 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 03:51:18 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:18 INFO - u'tests': [u'damp', u'tps']}, 03:51:18 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:18 INFO - u'talos_options': [u'--e10s'], 03:51:18 INFO - u'tests': [u'damp', u'tps']}, 03:51:18 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 03:51:18 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 03:51:18 INFO - u'other': {u'tests': [u'a11yr', 03:51:18 INFO - u'ts_paint', 03:51:18 INFO - u'tpaint', 03:51:18 INFO - u'sessionrestore', 03:51:18 INFO - u'sessionrestore_no_auto_restore']}, 03:51:18 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 03:51:18 INFO - u'tests': [u'a11yr', 03:51:18 INFO - u'ts_paint', 03:51:18 INFO - u'tpaint', 03:51:18 INFO - u'sessionrestore', 03:51:18 INFO - u'sessionrestore_no_auto_restore']}, 03:51:18 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 03:51:18 INFO - u'tests': [u'a11yr', 03:51:18 INFO - u'ts_paint', 03:51:18 INFO - u'tpaint', 03:51:18 INFO - u'sessionrestore', 03:51:18 INFO - u'sessionrestore_no_auto_restore']}, 03:51:18 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 03:51:18 INFO - u'tests': [u'a11yr', 03:51:18 INFO - u'ts_paint', 03:51:18 INFO - u'tpaint', 03:51:18 INFO - u'sessionrestore', 03:51:18 INFO - u'sessionrestore_no_auto_restore']}, 03:51:18 INFO - u'other_l64': {u'tests': [u'a11yr', 03:51:18 INFO - u'ts_paint', 03:51:18 INFO - u'tpaint', 03:51:18 INFO - u'sessionrestore', 03:51:18 INFO - u'sessionrestore_no_auto_restore']}, 03:51:18 INFO - u'other_nol64': {u'tests': [u'a11yr', 03:51:18 INFO - u'ts_paint', 03:51:18 INFO - u'tpaint', 03:51:18 INFO - u'sessionrestore', 03:51:18 INFO - u'sessionrestore_no_auto_restore']}, 03:51:18 INFO - u'svgr': {u'tests': [u'tsvgx', 03:51:18 INFO - u'tsvgr_opacity', 03:51:18 INFO - u'tart', 03:51:18 INFO - u'tscrollx', 03:51:18 INFO - u'cart']}, 03:51:18 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 03:51:18 INFO - u'tests': [u'tsvgx', 03:51:18 INFO - u'tsvgr_opacity', 03:51:18 INFO - u'tart', 03:51:18 INFO - u'tscrollx', 03:51:18 INFO - u'cart']}, 03:51:18 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:18 INFO - u'tests': [u'tp5o']}, 03:51:18 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:18 INFO - u'talos_options': [u'--e10s'], 03:51:18 INFO - u'tests': [u'tp5o']}, 03:51:18 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:18 INFO - u'talos_options': [u'--xperf_path', 03:51:18 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:51:18 INFO - u'tests': [u'tp5n']}, 03:51:18 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:51:18 INFO - u'talos_options': [u'--e10s', 03:51:18 INFO - u'--xperf_path', 03:51:18 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 03:51:18 INFO - u'C:/slave/talos-data/talos/xperf.config'], 03:51:18 INFO - u'tests': [u'tp5n']}}, 03:51:18 INFO - u'talos.zip': {u'path': u'', 03:51:18 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:51:18 INFO - Running post-action listener: _resource_record_post_action 03:51:18 INFO - ##### 03:51:18 INFO - ##### Running create-virtualenv step. 03:51:18 INFO - ##### 03:51:18 INFO - Running pre-action listener: _resource_record_pre_action 03:51:18 INFO - Running main action method: create_virtualenv 03:51:18 INFO - Creating virtualenv C:\slave\test\build/venv 03:51:18 INFO - mkdir: C:\slave\test\build\venv\Scripts 03:51:18 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 03:51:18 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'! 03:51:18 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 03:51:18 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 03:51:22 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 03:51:26 INFO - Installing distribute......................................................................................................................................................................................done. 03:51:27 INFO - Return code: 0 03:51:27 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 03:51:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:51:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:51:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:27 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 0x01A10B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0191B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013D8B48>, '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 0x013FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-098', 'USERDOMAIN': 'T-W732-IX-098', '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-098', '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 03:51:27 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 03:51:27 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 03:51:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:51:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 03:51:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 03:51:27 INFO - 'COMPUTERNAME': 'T-W732-IX-098', 03:51:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:51:27 INFO - 'CYGWINBASE': 'C:\\cygwin', 03:51:27 INFO - 'DCLOCATION': 'SCL3', 03:51:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:51:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:51:27 INFO - 'HOMEDRIVE': 'C:', 03:51:27 INFO - 'HOMEPATH': '\\Users\\cltbld', 03:51:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:51:27 INFO - 'KTS_VERSION': '1.19c', 03:51:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 03:51:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-098', 03:51:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:51:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:51:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:27 INFO - 'MOZ_NO_REMOTE': '1', 03:51:27 INFO - 'NO_EM_RESTART': '1', 03:51:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:51:27 INFO - 'OS': 'Windows_NT', 03:51:27 INFO - 'OURDRIVE': 'C:', 03:51: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;', 03:51:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:51:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:51:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 03:51:27 INFO - 'PROCESSOR_LEVEL': '6', 03:51:27 INFO - 'PROCESSOR_REVISION': '1e05', 03:51:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:51:27 INFO - 'PROGRAMFILES': 'C:\\Program Files', 03:51:27 INFO - 'PROMPT': '$P$G', 03:51:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 03:51:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:51:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:51:27 INFO - 'PWD': 'C:\\slave\\test', 03:51:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:51:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:51:27 INFO - 'SYSTEMDRIVE': 'C:', 03:51:27 INFO - 'SYSTEMROOT': 'C:\\windows', 03:51:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:51:27 INFO - 'TEST1': 'testie', 03:51:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:51:27 INFO - 'USERDOMAIN': 'T-W732-IX-098', 03:51:27 INFO - 'USERNAME': 'cltbld', 03:51:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 03:51:27 INFO - 'WINDIR': 'C:\\windows', 03:51:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 03:51:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 03:51:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:51:28 INFO - Ignoring indexes: http://pypi.python.org/simple/ 03:51:28 INFO - Downloading/unpacking pip>=1.5 03:51:28 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache 03:51:32 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 03:51:32 INFO - Running setup.py egg_info for package pip 03:51:32 INFO - warning: no files found matching 'pip\cacert.pem' 03:51:32 INFO - warning: no files found matching '*.html' under directory 'docs' 03:51:32 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 03:51:32 INFO - no previously-included directories found matching 'docs\_build\_sources' 03:51:32 INFO - Installing collected packages: pip 03:51:32 INFO - Found existing installation: pip 0.8.2 03:51:32 INFO - Uninstalling pip: 03:51:32 INFO - Successfully uninstalled pip 03:51:32 INFO - Running setup.py install for pip 03:51:32 INFO - warning: no files found matching 'pip\cacert.pem' 03:51:32 INFO - warning: no files found matching '*.html' under directory 'docs' 03:51:32 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 03:51:32 INFO - no previously-included directories found matching 'docs\_build\_sources' 03:51:32 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 03:51:32 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 03:51:32 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 03:51:32 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 03:51:32 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 03:51:32 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 03:51:32 INFO - Successfully installed pip 03:51:32 INFO - Cleaning up... 03:51:32 INFO - Return code: 0 03:51:32 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv 03:51:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:51:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:32 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 0x01A10B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0191B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013D8B48>, '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 0x013FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-098', 'USERDOMAIN': 'T-W732-IX-098', '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-098', '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 03:51:32 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 03:51:32 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 03:51:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:51:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 03:51:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 03:51:32 INFO - 'COMPUTERNAME': 'T-W732-IX-098', 03:51:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:51:32 INFO - 'CYGWINBASE': 'C:\\cygwin', 03:51:32 INFO - 'DCLOCATION': 'SCL3', 03:51:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:51:32 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:51:32 INFO - 'HOMEDRIVE': 'C:', 03:51:32 INFO - 'HOMEPATH': '\\Users\\cltbld', 03:51:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:51:32 INFO - 'KTS_VERSION': '1.19c', 03:51:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 03:51:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-098', 03:51:32 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:51:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:32 INFO - 'MOZ_NO_REMOTE': '1', 03:51:32 INFO - 'NO_EM_RESTART': '1', 03:51:32 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:51:32 INFO - 'OS': 'Windows_NT', 03:51:32 INFO - 'OURDRIVE': 'C:', 03:51:32 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 03:51:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:51:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:51:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 03:51:32 INFO - 'PROCESSOR_LEVEL': '6', 03:51:32 INFO - 'PROCESSOR_REVISION': '1e05', 03:51:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:51:32 INFO - 'PROGRAMFILES': 'C:\\Program Files', 03:51:32 INFO - 'PROMPT': '$P$G', 03:51:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 03:51:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:51:32 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:51:32 INFO - 'PWD': 'C:\\slave\\test', 03:51:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:51:32 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:51:32 INFO - 'SYSTEMDRIVE': 'C:', 03:51:32 INFO - 'SYSTEMROOT': 'C:\\windows', 03:51:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:51:32 INFO - 'TEST1': 'testie', 03:51:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:51:32 INFO - 'USERDOMAIN': 'T-W732-IX-098', 03:51:32 INFO - 'USERNAME': 'cltbld', 03:51:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 03:51:32 INFO - 'WINDIR': 'C:\\windows', 03:51:32 INFO - 'WINDOWS_TRACING_FLAGS': '3', 03:51:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 03:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:51:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:34 INFO - Downloading/unpacking psutil>=0.7.1 03:51:34 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 03:51:34 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 03:51:35 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 03:51:35 INFO - Installing collected packages: psutil 03:51:35 INFO - Successfully installed psutil 03:51:35 INFO - Cleaning up... 03:51:35 INFO - Return code: 0 03:51:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv 03:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:51:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:35 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 0x01A10B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0191B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013D8B48>, '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 0x013FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-098', 'USERDOMAIN': 'T-W732-IX-098', '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-098', '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 03:51:35 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 03:51:35 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 03:51:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:51:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 03:51:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 03:51:35 INFO - 'COMPUTERNAME': 'T-W732-IX-098', 03:51:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:51:35 INFO - 'CYGWINBASE': 'C:\\cygwin', 03:51:35 INFO - 'DCLOCATION': 'SCL3', 03:51:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:51:35 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:51:35 INFO - 'HOMEDRIVE': 'C:', 03:51:35 INFO - 'HOMEPATH': '\\Users\\cltbld', 03:51:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:51:35 INFO - 'KTS_VERSION': '1.19c', 03:51:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 03:51:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-098', 03:51:35 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:51:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:51:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:35 INFO - 'MOZ_NO_REMOTE': '1', 03:51:35 INFO - 'NO_EM_RESTART': '1', 03:51:35 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:51:35 INFO - 'OS': 'Windows_NT', 03:51:35 INFO - 'OURDRIVE': 'C:', 03:51:35 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 03:51:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:51:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:51:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 03:51:35 INFO - 'PROCESSOR_LEVEL': '6', 03:51:35 INFO - 'PROCESSOR_REVISION': '1e05', 03:51:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:51:35 INFO - 'PROGRAMFILES': 'C:\\Program Files', 03:51:35 INFO - 'PROMPT': '$P$G', 03:51:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 03:51:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:51:35 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:51:35 INFO - 'PWD': 'C:\\slave\\test', 03:51:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:51:35 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:51:35 INFO - 'SYSTEMDRIVE': 'C:', 03:51:35 INFO - 'SYSTEMROOT': 'C:\\windows', 03:51:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:51:35 INFO - 'TEST1': 'testie', 03:51:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:51:35 INFO - 'USERDOMAIN': 'T-W732-IX-098', 03:51:35 INFO - 'USERNAME': 'cltbld', 03:51:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 03:51:35 INFO - 'WINDIR': 'C:\\windows', 03:51:35 INFO - 'WINDOWS_TRACING_FLAGS': '3', 03:51:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 03:51:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:51:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:51:35 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 03:51:35 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 03:51:36 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:51:36 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 03:51:36 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 03:51:36 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) 03:51:36 INFO - Installing collected packages: mozsystemmonitor 03:51:36 INFO - Running setup.py install for mozsystemmonitor 03:51:37 INFO - Successfully installed mozsystemmonitor 03:51:37 INFO - Cleaning up... 03:51:37 INFO - Return code: 0 03:51:37 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 03:51: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')]} 03:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51: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')]} 03:51:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:37 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 0x01A10B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0191B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013D8B48>, '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 0x013FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-098', 'USERDOMAIN': 'T-W732-IX-098', '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-098', '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 03:51:37 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 03:51:37 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 03:51:37 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:51:37 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 03:51:37 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 03:51:37 INFO - 'COMPUTERNAME': 'T-W732-IX-098', 03:51:37 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:51:37 INFO - 'CYGWINBASE': 'C:\\cygwin', 03:51:37 INFO - 'DCLOCATION': 'SCL3', 03:51:37 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:51:37 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:51:37 INFO - 'HOMEDRIVE': 'C:', 03:51:37 INFO - 'HOMEPATH': '\\Users\\cltbld', 03:51:37 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:51:37 INFO - 'KTS_VERSION': '1.19c', 03:51:37 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 03:51:37 INFO - 'LOGONSERVER': '\\\\T-W732-IX-098', 03:51:37 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:51:37 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:51:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:37 INFO - 'MOZ_NO_REMOTE': '1', 03:51:37 INFO - 'NO_EM_RESTART': '1', 03:51:37 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:51:37 INFO - 'OS': 'Windows_NT', 03:51:37 INFO - 'OURDRIVE': 'C:', 03:51:37 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;', 03:51:37 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:51:37 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:51:37 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 03:51:37 INFO - 'PROCESSOR_LEVEL': '6', 03:51:37 INFO - 'PROCESSOR_REVISION': '1e05', 03:51:37 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:51:37 INFO - 'PROGRAMFILES': 'C:\\Program Files', 03:51:37 INFO - 'PROMPT': '$P$G', 03:51:37 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 03:51:37 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:51:37 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:51:37 INFO - 'PWD': 'C:\\slave\\test', 03:51:37 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:51:37 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:51:37 INFO - 'SYSTEMDRIVE': 'C:', 03:51:37 INFO - 'SYSTEMROOT': 'C:\\windows', 03:51:37 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:51:37 INFO - 'TEST1': 'testie', 03:51:37 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:51:37 INFO - 'USERDOMAIN': 'T-W732-IX-098', 03:51:37 INFO - 'USERNAME': 'cltbld', 03:51:37 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 03:51:37 INFO - 'WINDIR': 'C:\\windows', 03:51:37 INFO - 'WINDOWS_TRACING_FLAGS': '3', 03:51:37 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 03:51:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:51:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:37 INFO - Downloading/unpacking blobuploader==1.2.4 03:51:37 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 03:51:37 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 03:51:38 INFO - Downloading blobuploader-1.2.4.tar.gz 03:51:38 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 03:51:38 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 03:51:38 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:51:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:51:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:51:39 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 03:51:39 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 03:51:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:51:39 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 03:51:39 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 03:51:39 INFO - Downloading docopt-0.6.1.tar.gz 03:51:40 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 03:51:40 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 03:51:40 INFO - Installing collected packages: blobuploader, requests, docopt 03:51:40 INFO - Running setup.py install for blobuploader 03:51:40 INFO - Running setup.py install for requests 03:51:41 INFO - Running setup.py install for docopt 03:51:41 INFO - Successfully installed blobuploader requests docopt 03:51:41 INFO - Cleaning up... 03:51:42 INFO - Return code: 0 03:51:42 INFO - Installing None into virtualenv C:\slave\test\build/venv 03:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:51:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:51:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:42 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 0x01A10B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0191B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013D8B48>, '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 0x013FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-098', 'USERDOMAIN': 'T-W732-IX-098', '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-098', '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 03:51:42 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 03:51:42 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 03:51:42 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:51:42 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 03:51:42 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 03:51:42 INFO - 'COMPUTERNAME': 'T-W732-IX-098', 03:51:42 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:51:42 INFO - 'CYGWINBASE': 'C:\\cygwin', 03:51:42 INFO - 'DCLOCATION': 'SCL3', 03:51:42 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:51:42 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:51:42 INFO - 'HOMEDRIVE': 'C:', 03:51:42 INFO - 'HOMEPATH': '\\Users\\cltbld', 03:51:42 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:51:42 INFO - 'KTS_VERSION': '1.19c', 03:51:42 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 03:51:42 INFO - 'LOGONSERVER': '\\\\T-W732-IX-098', 03:51:42 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:51:42 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:51:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:42 INFO - 'MOZ_NO_REMOTE': '1', 03:51:42 INFO - 'NO_EM_RESTART': '1', 03:51:42 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:51:42 INFO - 'OS': 'Windows_NT', 03:51:42 INFO - 'OURDRIVE': 'C:', 03:51:42 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 03:51:42 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:51:42 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:51:42 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 03:51:42 INFO - 'PROCESSOR_LEVEL': '6', 03:51:42 INFO - 'PROCESSOR_REVISION': '1e05', 03:51:42 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:51:42 INFO - 'PROGRAMFILES': 'C:\\Program Files', 03:51:42 INFO - 'PROMPT': '$P$G', 03:51:42 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 03:51:42 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:51:42 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:51:42 INFO - 'PWD': 'C:\\slave\\test', 03:51:42 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:51:42 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:51:42 INFO - 'SYSTEMDRIVE': 'C:', 03:51:42 INFO - 'SYSTEMROOT': 'C:\\windows', 03:51:42 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:51:42 INFO - 'TEST1': 'testie', 03:51:42 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:51:42 INFO - 'USERDOMAIN': 'T-W732-IX-098', 03:51:42 INFO - 'USERNAME': 'cltbld', 03:51:42 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 03:51:42 INFO - 'WINDIR': 'C:\\windows', 03:51:42 INFO - 'WINDOWS_TRACING_FLAGS': '3', 03:51:42 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 03:51:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:51:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 03:51:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-imnik5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 03:51:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 03:51:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-behuwu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 03:51:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 03:51:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e5iyoc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 03:51:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 03:51:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-26fav9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 03:51:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 03:51:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g05szh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 03:51:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 03:51:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_ah1v3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 03:51:43 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 03:51:43 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5oq3kw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 03:51:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 03:51:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ak7osw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 03:51:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 03:51:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vggssj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 03:51:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 03:51:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-r6pm7b-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 03:51:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 03:51:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gk4jue-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 03:51:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 03:51:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qao_g5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 03:51:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 03:51:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pwcsko-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 03:51:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 03:51:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aqwh2m-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 03:51:44 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 03:51:44 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qbdhhg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 03:51:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 03:51:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xch8ih-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 03:51:45 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 03:51:45 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mxscwa-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 03:51:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:51:45 INFO - Running setup.py install for manifestparser 03:51:45 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 03:51:45 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 03:51:46 INFO - Running setup.py install for mozcrash 03:51:46 INFO - Running setup.py install for mozdebug 03:51:47 INFO - Running setup.py install for mozdevice 03:51:47 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 03:51:47 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 03:51:47 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 03:51:47 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 03:51:47 INFO - Running setup.py install for mozfile 03:51:48 INFO - Running setup.py install for mozhttpd 03:51:48 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 03:51:48 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 03:51:48 INFO - Running setup.py install for mozinfo 03:51:48 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 03:51:48 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 03:51:49 INFO - Running setup.py install for mozInstall 03:51:49 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 03:51:49 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 03:51:49 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 03:51:49 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 03:51:49 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 03:51:49 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 03:51:49 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 03:51:49 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 03:51:49 INFO - Running setup.py install for mozleak 03:51:49 INFO - Running setup.py install for mozlog 03:51:49 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 03:51:49 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 03:51:50 INFO - Running setup.py install for moznetwork 03:51:50 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 03:51:50 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 03:51:50 INFO - Running setup.py install for mozprocess 03:51:51 INFO - Running setup.py install for mozprofile 03:51:51 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 03:51:51 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 03:51:51 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 03:51:51 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 03:51:51 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 03:51:51 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 03:51:52 INFO - Running setup.py install for mozrunner 03:51:52 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 03:51:52 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 03:51:52 INFO - Running setup.py install for mozscreenshot 03:51:53 INFO - Running setup.py install for moztest 03:51:53 INFO - Running setup.py install for mozversion 03:51:53 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 03:51:53 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 03:51:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:51:54 INFO - Cleaning up... 03:51:54 INFO - Return code: 0 03:51:54 INFO - Installing None into virtualenv C:\slave\test\build/venv 03:51:54 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')]} 03:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:54 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')]} 03:51:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:54 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 0x01A10B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0191B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013D8B48>, '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 0x013FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-098', 'USERDOMAIN': 'T-W732-IX-098', '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-098', '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 03:51:54 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 03:51:54 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 03:51:54 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:51:54 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 03:51:54 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 03:51:54 INFO - 'COMPUTERNAME': 'T-W732-IX-098', 03:51:54 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:51:54 INFO - 'CYGWINBASE': 'C:\\cygwin', 03:51:54 INFO - 'DCLOCATION': 'SCL3', 03:51:54 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:51:54 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:51:54 INFO - 'HOMEDRIVE': 'C:', 03:51:54 INFO - 'HOMEPATH': '\\Users\\cltbld', 03:51:54 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:51:54 INFO - 'KTS_VERSION': '1.19c', 03:51:54 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 03:51:54 INFO - 'LOGONSERVER': '\\\\T-W732-IX-098', 03:51:54 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:51:54 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:51:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:54 INFO - 'MOZ_NO_REMOTE': '1', 03:51:54 INFO - 'NO_EM_RESTART': '1', 03:51:54 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:51:54 INFO - 'OS': 'Windows_NT', 03:51:54 INFO - 'OURDRIVE': 'C:', 03:51:54 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;', 03:51:54 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:51:54 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:51:54 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 03:51:54 INFO - 'PROCESSOR_LEVEL': '6', 03:51:54 INFO - 'PROCESSOR_REVISION': '1e05', 03:51:54 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:51:54 INFO - 'PROGRAMFILES': 'C:\\Program Files', 03:51:54 INFO - 'PROMPT': '$P$G', 03:51:54 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 03:51:54 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:51:54 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:51:54 INFO - 'PWD': 'C:\\slave\\test', 03:51:54 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:51:54 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:51:54 INFO - 'SYSTEMDRIVE': 'C:', 03:51:54 INFO - 'SYSTEMROOT': 'C:\\windows', 03:51:54 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:51:54 INFO - 'TEST1': 'testie', 03:51:54 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:51:54 INFO - 'USERDOMAIN': 'T-W732-IX-098', 03:51:54 INFO - 'USERNAME': 'cltbld', 03:51:54 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 03:51:54 INFO - 'WINDIR': 'C:\\windows', 03:51:54 INFO - 'WINDOWS_TRACING_FLAGS': '3', 03:51:54 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 03:51:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:51:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 03:51:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sapdtl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 03:51:54 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)) 03:51:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 03:51:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pvzci5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 03:51:54 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)) 03:51:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 03:51:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l1_g5p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 03:51:54 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)) 03:51:54 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 03:51:54 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-7cfrk5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 03:51:55 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)) 03:51:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 03:51:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-z6vjmk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 03:51:55 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)) 03:51:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 03:51:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gopla1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 03:51:55 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)) 03:51:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 03:51:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nx3nbx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 03:51:55 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)) 03:51:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 03:51:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ssddvn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 03:51:55 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)) 03:51:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 03:51:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qiipls-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 03:51:55 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)) 03:51:55 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 03:51:55 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-m6ohgi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 03:51:56 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)) 03:51:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 03:51:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uafzwc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 03:51:56 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)) 03:51:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 03:51:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gg4yhh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 03:51:56 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)) 03:51:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 03:51:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ljliox-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 03:51:56 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)) 03:51:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 03:51:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9ws7px-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 03:51:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 03:51:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 03:51:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3wqaae-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 03:51:56 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)) 03:51:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 03:51:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hle2i2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 03:51:56 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)) 03:51:56 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 03:51:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vwlkgw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 03:51:57 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)) 03:51:57 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)) 03:51:57 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)) 03:51:57 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)) 03:51:57 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)) 03:51:57 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)) 03:51:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 03:51: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 03:51: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 03:51:57 INFO - Downloading blessings-1.5.1.tar.gz 03:51:57 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 03:51:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 03:51:58 INFO - Installing collected packages: blessings 03:51:58 INFO - Running setup.py install for blessings 03:51:58 INFO - Successfully installed blessings 03:51:58 INFO - Cleaning up... 03:52:01 INFO - Return code: 0 03:52:01 INFO - Done creating virtualenv C:\slave\test\build/venv. 03:52:01 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 03:52:01 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 03:52:01 INFO - Reading from file tmpfile_stdout 03:52:01 INFO - Using _rmtree_windows ... 03:52:01 INFO - Using _rmtree_windows ... 03:52:01 INFO - Current package versions: 03:52:01 INFO - blessings == 1.5.1 03:52:01 INFO - blobuploader == 1.2.4 03:52:01 INFO - docopt == 0.6.1 03:52:01 INFO - manifestparser == 1.1 03:52:01 INFO - mozInstall == 1.12 03:52:01 INFO - mozcrash == 0.16 03:52:01 INFO - mozdebug == 0.1 03:52:01 INFO - mozdevice == 0.46 03:52:01 INFO - mozfile == 1.2 03:52:01 INFO - mozhttpd == 0.7 03:52:01 INFO - mozinfo == 0.8 03:52:01 INFO - mozleak == 0.1 03:52:01 INFO - mozlog == 3.0 03:52:01 INFO - moznetwork == 0.27 03:52:01 INFO - mozprocess == 0.22 03:52:01 INFO - mozprofile == 0.27 03:52:01 INFO - mozrunner == 6.11 03:52:01 INFO - mozscreenshot == 0.1 03:52:01 INFO - mozsystemmonitor == 0.0 03:52:01 INFO - moztest == 0.7 03:52:01 INFO - mozversion == 1.4 03:52:01 INFO - psutil == 3.1.1 03:52:01 INFO - requests == 1.2.3 03:52:01 INFO - Installing None into virtualenv C:\slave\test\build/venv 03:52:01 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')]} 03:52:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:52:01 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')]} 03:52:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:52:01 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 0x01A10B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019D7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0191B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x013D8B48>, '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 0x013FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-098', 'USERDOMAIN': 'T-W732-IX-098', '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-098', '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 03:52:01 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 03:52:01 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 03:52:01 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:52:01 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 03:52:01 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 03:52:01 INFO - 'COMPUTERNAME': 'T-W732-IX-098', 03:52:01 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:52:01 INFO - 'CYGWINBASE': 'C:\\cygwin', 03:52:01 INFO - 'DCLOCATION': 'SCL3', 03:52:01 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:52:01 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:52:01 INFO - 'HOMEDRIVE': 'C:', 03:52:01 INFO - 'HOMEPATH': '\\Users\\cltbld', 03:52:01 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:52:01 INFO - 'KTS_VERSION': '1.19c', 03:52:01 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 03:52:01 INFO - 'LOGONSERVER': '\\\\T-W732-IX-098', 03:52:01 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:52:01 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:52:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:01 INFO - 'MOZ_NO_REMOTE': '1', 03:52:01 INFO - 'NO_EM_RESTART': '1', 03:52:01 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:52:01 INFO - 'OS': 'Windows_NT', 03:52:01 INFO - 'OURDRIVE': 'C:', 03:52:01 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;', 03:52:01 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:52:01 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:52:01 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 03:52:01 INFO - 'PROCESSOR_LEVEL': '6', 03:52:01 INFO - 'PROCESSOR_REVISION': '1e05', 03:52:01 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:52:01 INFO - 'PROGRAMFILES': 'C:\\Program Files', 03:52:01 INFO - 'PROMPT': '$P$G', 03:52:01 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 03:52:01 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:52:01 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:52:01 INFO - 'PWD': 'C:\\slave\\test', 03:52:01 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:52:01 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:52:01 INFO - 'SYSTEMDRIVE': 'C:', 03:52:01 INFO - 'SYSTEMROOT': 'C:\\windows', 03:52:01 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:52:01 INFO - 'TEST1': 'testie', 03:52:01 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:52:01 INFO - 'USERDOMAIN': 'T-W732-IX-098', 03:52:01 INFO - 'USERNAME': 'cltbld', 03:52:01 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 03:52:01 INFO - 'WINDIR': 'C:\\windows', 03:52:01 INFO - 'WINDOWS_TRACING_FLAGS': '3', 03:52:01 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 03:52:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:52:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:52:02 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)) 03:52:02 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)) 03:52:02 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)) 03:52:02 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)) 03:52:02 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)) 03:52:02 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)) 03:52:02 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)) 03:52:02 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)) 03:52:02 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)) 03:52:02 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)) 03:52:02 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)) 03:52:02 INFO - Cleaning up... 03:52:02 INFO - Return code: 0 03:52:02 INFO - Running post-action listener: _resource_record_post_action 03:52:02 INFO - Running post-action listener: _start_resource_monitoring 03:52:02 INFO - Starting resource monitoring. 03:52:02 INFO - ##### 03:52:02 INFO - ##### Running install step. 03:52:02 INFO - ##### 03:52:02 INFO - Running pre-action listener: _resource_record_pre_action 03:52:02 INFO - Running main action method: install 03:52:02 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 03:52:02 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 03:52:02 INFO - Reading from file tmpfile_stdout 03:52:02 INFO - Using _rmtree_windows ... 03:52:02 INFO - Using _rmtree_windows ... 03:52:02 INFO - Detecting whether we're running mozinstall >=1.0... 03:52:02 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 03:52:02 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 03:52:02 INFO - Reading from file tmpfile_stdout 03:52:02 INFO - Output received: 03:52:02 INFO - Usage: mozinstall-script.py [options] installer 03:52:02 INFO - Options: 03:52:02 INFO - -h, --help show this help message and exit 03:52:02 INFO - -d DEST, --destination=DEST 03:52:02 INFO - Directory to install application into. [default: 03:52:02 INFO - "C:\slave\test"] 03:52:02 INFO - --app=APP Application being installed. [default: firefox] 03:52:02 INFO - Using _rmtree_windows ... 03:52:02 INFO - Using _rmtree_windows ... 03:52:02 INFO - mkdir: C:\slave\test\build\application 03:52:02 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'] 03:52:02 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 03:52:04 INFO - Reading from file tmpfile_stdout 03:52:04 INFO - Output received: 03:52:04 INFO - C:\slave\test\build\application\firefox\firefox.exe 03:52:04 INFO - Using _rmtree_windows ... 03:52:04 INFO - Using _rmtree_windows ... 03:52:04 INFO - Running post-action listener: _resource_record_post_action 03:52:04 INFO - ##### 03:52:04 INFO - ##### Running run-tests step. 03:52:04 INFO - ##### 03:52:04 INFO - Running pre-action listener: _resource_record_pre_action 03:52:04 INFO - Running main action method: run_tests 03:52:04 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 03:52:04 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 03:52:04 INFO - Python 2.7.3 03:52:04 INFO - Return code: 0 03:52:04 INFO - grabbing minidump binary from tooltool 03:52: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')]} 03:52:04 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 0x013D8B48>, '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 0x013FA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F4F660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 03:52:04 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 03:52:04 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 03:52:04 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 03:52:06 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp41xvek 03:52:06 INFO - INFO - File integrity verified, renaming tmp41xvek to win32-minidump_stackwalk.exe 03:52:06 INFO - Return code: 0 03:52:06 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 03:52:06 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 03:52:06 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 03:52:06 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 03:52:06 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 03:52:06 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-098', '--webServer', 'localhost'] in C:\slave\test\build 03:52:06 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-098 --webServer localhost 03:52:06 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 03:52:06 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 03:52:06 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 03:52:06 INFO - 'COMPUTERNAME': 'T-W732-IX-098', 03:52:06 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 03:52:06 INFO - 'CYGWINBASE': 'C:\\cygwin', 03:52:06 INFO - 'DCLOCATION': 'SCL3', 03:52:06 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 03:52:06 INFO - 'FP_NO_HOST_CHECK': 'NO', 03:52:06 INFO - 'HOMEDRIVE': 'C:', 03:52:06 INFO - 'HOMEPATH': '\\Users\\cltbld', 03:52:06 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 03:52:06 INFO - 'KTS_VERSION': '1.19c', 03:52:06 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 03:52:06 INFO - 'LOGONSERVER': '\\\\T-W732-IX-098', 03:52:06 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 03:52:06 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 03:52:06 INFO - 'MONDIR': 'C:\\Monitor_config\\', 03:52:06 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 03:52:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:52:06 INFO - 'MOZ_NO_REMOTE': '1', 03:52:06 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 03:52:06 INFO - 'NO_EM_RESTART': '1', 03:52:06 INFO - 'NUMBER_OF_PROCESSORS': '8', 03:52:06 INFO - 'OS': 'Windows_NT', 03:52:06 INFO - 'OURDRIVE': 'C:', 03:52:06 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;', 03:52:06 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 03:52:06 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 03:52:06 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 03:52:06 INFO - 'PROCESSOR_LEVEL': '6', 03:52:06 INFO - 'PROCESSOR_REVISION': '1e05', 03:52:06 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 03:52:06 INFO - 'PROGRAMFILES': 'C:\\Program Files', 03:52:06 INFO - 'PROMPT': '$P$G', 03:52:06 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 03:52:06 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 03:52:06 INFO - 'PUBLIC': 'C:\\Users\\Public', 03:52:06 INFO - 'PWD': 'C:\\slave\\test', 03:52:06 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 03:52:06 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 03:52:06 INFO - 'SLAVEDIR': 'C:\\slave\\', 03:52:06 INFO - 'SYSTEMDRIVE': 'C:', 03:52:06 INFO - 'SYSTEMROOT': 'C:\\windows', 03:52:06 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:52:06 INFO - 'TEST1': 'testie', 03:52:06 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 03:52:06 INFO - 'USERDOMAIN': 'T-W732-IX-098', 03:52:06 INFO - 'USERNAME': 'cltbld', 03:52:06 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 03:52:06 INFO - 'WINDIR': 'C:\\windows', 03:52:06 INFO - 'WINDOWS_TRACING_FLAGS': '3', 03:52:06 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 03:52:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 03:52:06 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-098', '--webServer', 'localhost'] with output_timeout 3600 03:52:07 INFO - mozversion INFO | application_buildid: 20151026030933 03:52:07 INFO - mozversion INFO | application_changeset: 60d33952d4cad718c786bea4d229cb137d5b2317 03:52:07 INFO - mozversion INFO | application_display_name: Nightly 03:52:07 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:52:07 INFO - mozversion INFO | application_name: Firefox 03:52:07 INFO - mozversion INFO | application_remotingname: firefox 03:52:07 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 03:52:07 INFO - mozversion INFO | application_vendor: Mozilla 03:52:07 INFO - mozversion INFO | application_version: 44.0a1 03:52:07 INFO - mozversion INFO | platform_buildid: 20151026030933 03:52:07 INFO - mozversion INFO | platform_changeset: 60d33952d4cad718c786bea4d229cb137d5b2317 03:52:07 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 03:52:07 INFO - mozversion INFO | platform_version: 44.0a1 03:52:07 INFO - 2015-10-26 03:52:07,336 DEBUG : using testdate: 1445856727 03:52:07 INFO - 2015-10-26 03:52:07,336 DEBUG : actual date: 1445856727 03:52:07 INFO - 2015-10-26 03:52:07,413 INFO : starting webserver on 'localhost:49896' 03:52:07 INFO - 2015-10-26 03:52:07,414 INFO : Starting test suite T-W732-IX-098 03:52:07 INFO - 2015-10-26 03:52:07,414 INFO : Starting test dromaeo_css 03:52:07 INFO - 2015-10-26 03:52:07,414 DEBUG : operating with platform_type : w7_ 03:52:07 INFO - 2015-10-26 03:52:07,415 INFO : Initialising browser for dromaeo_css test... 03:52:07 INFO - 2015-10-26 03:52:07,519 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmplhfdbi\profile http://localhost:49896/getInfo.html 03:52:16 INFO - 2015-10-26 03:52:16,016 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 03:52:16 INFO - 2015-10-26 03:52:16,016 DEBUG : BROWSER_OUTPUT: colorDepth:24 03:52:16 INFO - 2015-10-26 03:52:16,016 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 03:52:16 INFO - 2015-10-26 03:52:16,016 DEBUG : BROWSER_OUTPUT: __metrics 03:52:16 INFO - 2015-10-26 03:52:16,082 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:52:17 INFO - 2015-10-26 03:52:17,513 INFO : Browser initialized. 03:52:17 INFO - 2015-10-26 03:52:17,513 INFO : Running cycle 1/1 for dromaeo_css test... 03:52:17 INFO - 2015-10-26 03:52:17,513 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmplhfdbi\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 03:52:29 INFO - 2015-10-26 03:52:29,164 DEBUG : BROWSER_OUTPUT: RSS: Main: 141418496 03:52:29 INFO - 2015-10-26 03:52:29,164 DEBUG : BROWSER_OUTPUT: 03:53:24 INFO - 2015-10-26 03:53:24,207 DEBUG : BROWSER_OUTPUT: 1445856804212 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] 03:55:35 INFO - 2015-10-26 03:55:35,622 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49896/tests/dromaeo/cssquery-ext.html) 03:55:36 INFO - 2015-10-26 03:55:36,598 DEBUG : BROWSER_OUTPUT: RSS: Main: 175513600 03:55:36 INFO - 2015-10-26 03:55:36,598 DEBUG : BROWSER_OUTPUT: 03:58:39 INFO - 2015-10-26 03:58:39,244 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-ext.html (next: http://localhost:49896/tests/dromaeo/cssquery-jquery.html) 03:58:39 INFO - 2015-10-26 03:58:39,812 DEBUG : BROWSER_OUTPUT: RSS: Main: 205418496 03:58:39 INFO - 2015-10-26 03:58:39,812 DEBUG : BROWSER_OUTPUT: 04:01:42 INFO - 2015-10-26 04:01:42,286 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49896/tests/dromaeo/cssquery-mootools.html) 04:01:42 INFO - 2015-10-26 04:01:42,846 DEBUG : BROWSER_OUTPUT: RSS: Main: 152641536 04:01:42 INFO - 2015-10-26 04:01:42,846 DEBUG : BROWSER_OUTPUT: 04:04:45 INFO - 2015-10-26 04:04:45,094 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49896/tests/dromaeo/cssquery-prototype.html) 04:04:45 INFO - 2015-10-26 04:04:45,709 DEBUG : BROWSER_OUTPUT: RSS: Main: 139300864 04:04:45 INFO - 2015-10-26 04:04:45,710 DEBUG : BROWSER_OUTPUT: 04:07:48 INFO - 2015-10-26 04:07:48,710 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49896/tests/dromaeo/cssquery-yui.html) 04:07:49 INFO - 2015-10-26 04:07:49,289 DEBUG : BROWSER_OUTPUT: RSS: Main: 138907648 04:07:49 INFO - 2015-10-26 04:07:49,289 DEBUG : BROWSER_OUTPUT: 04:10:52 INFO - 2015-10-26 04:10:52,176 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/dromaeo/cssquery-yui.html (next: http://localhost:49896/tests/dromaeo/cssquery-dojo.html) 04:10:52 INFO - 2015-10-26 04:10:52,423 DEBUG : BROWSER_OUTPUT: RSS: Main: 190226432 04:10:52 INFO - 2015-10-26 04:10:52,423 DEBUG : BROWSER_OUTPUT: 04:10:52 INFO - 2015-10-26 04:10:52,423 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:10:52 INFO - 2015-10-26 04:10:52,423 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:10:52 INFO - 2015-10-26 04:10:52,424 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:10:52 INFO - 2015-10-26 04:10:52,424 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:10:52 INFO - 2015-10-26 04:10:52,424 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2353.517528906574;2377.4294169399345;2437.463757973246;2458.877056147192;2462.4459496613254;4253.553376895424;4272.957270427295;4278.743275403478;4346.717463364879;4356.215881141393;4315.5900189481945;4402.581754733304;4404.097160082176;4407.228734971375;4417.204903117437;3697.3159965406353;3752.155764952891;3781.1681430085405;3810.5236845394325;3869.593692662272;3550.218951830593;3553.3959226931356;3570.4822800693883;3723.2739615774935;3781.357169281215;3724.14344700719;3772.77363358198;3780.9810950945207;3792.412176112693;3792.696584273265;3767.8116094195457;3782.2246439479977;3789.0716774390175;3803.7337386382965;3887.6306750858907;3623.474596183539;3710.05393624624;3733.9626603733886;3759.7180211484247;3789.2232092420986;4237.7922292146895;4274.209271284792;4286.42133312002;4312.137572485484;4326.069894972565;4430.003249268921;4525.185466616008;4535.931961020588;4576.977115114403;4657.790399432034;4314.158739045887;4393.9121217575785;4429.202743506167;4545.068261006486;4553.863384098482;4184.5187803403;4309.181255561464;4378.430803995492;4379.43067401239;4385.166818304512;4518.751468669255;4542.1597004553605;4563.315502674559;4707.552782485658;4785.856424307276;4452.376667266585;4472.888177795581;4598.126355992351;4607.539246075366;4612.74629895359;4528;4538.069695712371;4662.930056049162;4666.789994450258;4684.273937539687;4375.978120109379;4495.505494395614;4497.730136191839;4530;4624.352590637246;4225.619694227473;4423.336499525032;4519.864404067883;4633.235516139866;4651.37206477128;3073.4006868660845;3124.7968882022597;3132.1543183340827;3207.246297120175;3215.7909735867092;2737.5346191147155;2817.5210214263625;2900.1444573850763;2902.201894478993;2930.3113768264443;4269.87190384289;4293.2272191005295;4351.042770590465;4380.189664912002;4384.210842048462;4617.145828021827;4641.512641172696;4644.790984405709;4689.6248300136585;4734.455537613218;4203.831846726097;4294.484661840599;4305.612494875476;4312.245357062564;4412.7793610319995;3867.1105645701646;3888.650021498079;3989.3816458448987;3999.980000100039;4003.599640035973;4770.522947705202;4773.665843391068;4787.497312782108;4859.271109333628;4879.365682461257;4494.752788596527;4552.089582083663;4612.654050946126;4621.79201935907;4726.881827954328;4622.913615300335;4632.5135860734135;4682.555157260055;4787.3776409068;4801.5438533339275;4348.695591308578;4540.568645978627;4548.158590660738;4611.169989401806;4650.604698600521;4459.397981272617;4539.319102134707;4566.200914839823;4610.6542009350105;4700.8119675213975;4372.978135109304;4386.64906807461;4440.578145076212;4467.2182368086105;4503.324501324828;4460.821567137278;4466.798994045211;4525.479569849378;4551.5676010778925;4557.908841823211;4241.681873859535;4346.521882592975;4348.543402942645;4363.650907927295;4456.974895774055;4221.957780422156;4443.488998765183;4444.666650001327;4540.909181816412;4546.7953942073345;4312.460942382202;4401.07577408735;4493.0339976905125;4494.5730155635165;4516.683832131719 04:10:52 INFO - 2015-10-26 04:10:52,424 DEBUG : BROWSER_OUTPUT: |1;ext.html;486.10313970724025;502.56277038976094;505.9696418214907;510.1429598274897;514.8815772372354;26108.347291317703;26461.73538264619;36771.26457470849;37125.44311835318;37149.81425092877;38426.4236036459;38849.223015539676;38943.41584876229;38958;39020;6232.1898153240245;6332.461740752042;7526.322630963212;7944.245296696805;8090.352771778244;9917.90082099177;10145.695629131138;10148.0866721995;10160.491975401237;10172.64395746149;9927;10018.348807327537;10049.095581397673;10056.597736090584;10056.949715251414;6164.938350616526;6323.083152942821;9704.51477426133;9835.704928852147;10246.385216886936;9787.119159275699;9906;9928.70213893577;9950.30347875649;9954.303198776088;36169.81915090408;36518.45222321667;36543.4518482223;36557.81721091404;36591.817040914626;2650.4301575161435;2656.9867150664318;2679.7454241846995;2953.719396657314;3412.692857642824;26665.733342666517;26912.192634221006;26923.32691682704;27564;27575.448491030067;2301.4821665125382;2301.7583153768946;2307.526956973816;2355.9646605300936;2359.2214569192297;229936;230037;230181;230514;231409;40157.39763903544;40906.386404203964;49042.50957490451;49431.50568494269;51098.48901510937;50414.495855041714;50924;50943.74528127335;50992;51027.74486127545;10474.738131546772;10630.149588032938;10653.254272200871;10658.094062004662;10674.199435042248;8595.871061934076;8605.182507661762;8663.523506207219;8684.913150868537;8686;15768.68462630741;15869.603259918595;16141.56107070565;16154.434594789125;16240.51278461648;7057.50597458173;7308.30571095745;7333.156686981066;7339.926600733925;7399.963000184965;765.6516285090271;824.3364091906074;835.3233880556769;886.2865393358279;977.3677957810473;24911.62632560513;25524;25884;26005.349866253116;26145.82343794534;895.5387975192781;939.8778158839444;978.9818588667848;990.9058639429243;1000.4397537379091;13222.338883055809;13365.599032029055;13432.932835335761;13579.321033948263;13632.182069075887;275525;281111;281525;284446;284802;43098.569014309454;44941.32588011182;45216.77391613021;45219.773901130284;45425.545744542134;1101.1961268274204;1120;1120.8374785656035;1121.7924683933336;1124.201816710145;18582.44252672422;25902.631264678294;27725;27750.306242344104;27835.44329113447;41931.371029433976;42139.7893010533;42272;42310.153796923274;42376.57623423726;226725;227428;231001;237422;239360;20217.89891050594;25697.486050279265;26367.868160659073;26469.205923822316;26912.730872691023;33096.71782856432;33226.169345765586;34180.14549636279;34835.303293934485;34839;28347.291317717223;29142.27144321409;29626.259343516584;30455.543166852956;32484;30642.540361894142;30969.380612387176;32300.515492267165;32448.83775581107;32712.672873271913;29875.21996480553;31644.367112658078;31845;32016.67983320137;32065.6793432072;28817.85591072031;28893.422131557672;29633.851830741572;29636.703632964258;29755.256118597208;20866.26968056111;21838.454038648524;22058.44853878666;22273.443163920383;22369.552608947404 04:10:52 INFO - 2015-10-26 04:10:52,434 DEBUG : BROWSER_OUTPUT: |2;jquery.html;295.6910029019674;295.9008732074755;298.9402119576085;302.94849875521174;303.1028156656298;14934.402623895045;15157.166355850424;15213.771793423093;15413.528212439476;16555.66888662226;13592.932035339834;13630.522931697393;13807;13928.721425571483;14004.29978501076;5091.643584949055;5199.752059505729;5208.635395522315;5220.451852555484;5222.556082732972;4519.638428925695;4935.235038569028;5186.481351864821;5228.764705588258;5264.8683782905355;5039.647224694273;5171.715555644438;5180.559652429548;5186.870328241786;5196.662216955905;4777.450593181777;5059.671121377099;5169.715665638389;5183.585313174955;5193.922091168635;3391.372596069735;3619.8914032579064;3686.5760437549757;3701.3152566774966;4553.544645535453;11596.884031159749;11604.187706860523;11722.179447438682;11745.354005529693;11769.41152942358;1522.2693107308553;1624.951251462458;1642.3676884399472;1732.644807814395;1771.1055916762082;8234.505929644181;8448.197421244971;8588.312934965188;8607.053224145338;8715.433496822687;1521.9315130819139;1605.9759103613455;1622.4159302651037;1696.211261763286;1710.3928105522484;48969.51030489685;49169;52588;55219.171712424344;55391;5288.233206185082;5585.692786896679;5595.63628364155;5615.4665306796605;5762.88474230513;6168.845778855475;6455.419012288917;6616.503762217853;6622.403983641495;6650.634215118118;4258.212230737324;4368.650507959355;4381.277089280233;4447.666425018137;4524.914020635089;4426;4484.775761211927;4509;4540.500544940054;4545.863624091283;2625.540530407171;2625.658664373619;2632.934176645599;2636.7231440698833;2645.285772841322;939.5343264507391;950.8621249918862;961.4471678784587;1002.2082554781787;1028.6605420211313;369.0478565301516;384.06480220662775;399.3689969847634;399.76613680996945;414.3742948148318;9946.154576861049;10287.897121028844;10301.698301698301;10472.78244421495;10498.685039448828;355.8452073348102;360.83581970203494;367.02005644928107;373.8448543854324;413.64013308421863;5406;5493.23094766733;5514.09017512114;5551.195076713854;5624.943750562524;295247;299364;303117;303948;307221;55653.72173139108;57138.7143064282;57272.42727572754;60996;62309.68845155745;413.55129684292876;422.01038564566045;422.15147553417853;422.7907185942978;425.35346273664476;9211.861822072808;9401.670941517288;9521.857172142285;9585.952070239604;9615.90384096178;31490.268035257362;32715.34569308648;33082;33134.83432582822;33308.333833322715;45810.31284530801;46568.767156164;48121;48177.277340840614;48730.75634621804;5328.413874473727;5389.083855744454;5427.937383794133;5516.9448305516435;5561.193626924154;10271.845922311022;10326.948365258126;10584.8941510587;10628;10638.202134839763;9086.772830679285;9089.909100909172;9188.95405522968;9235.492047937429;9307.255419566283;8559.144085591392;8864.246539044254;8910.688125915562;9050.366474346733;9166.587503562223;9042.819143617222;9225.21585665226;9489.525523723923;9500.619975200914;9669.226461883172;5631.070873306023;5632.380438151718;5687.744051517609;5690.748035432198;5716.9142462862255;8999.145081217273;9035.141661542142;9270.629174832931;9390.67132650354;9396.906030939603 04:10:52 INFO - 2015-10-26 04:10:52,434 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1141.5262665993614;1271.9427625756837;1352.8241328627278;1470.2722152534495;1647.7610746441767;7183.353498185163;8167.754967350981;8355.164483551642;8376.413651044422;8403.36974726895;8022.558759268238;8131.552764597945;8242.25819676229;8415.326773858076;8415.326773858093;1843.6865732825386;1874.3533480949118;2102.6530622447276;2123.044629916536;2154.795294447025;2167.8157356624706;2193.9780602193932;2200.0869639099756;2203.724534433196;2203.9971812825124;2139.1764170794277;2162.4593851537115;2164.3182397544724;2170.8806015669134;2170.902309396081;1790.7313902914536;1813.6010077782933;2100.519134010519;2199.692043113965;2202.3723238877005;2097.0877668214225;2173.1416090644107;2194.3965409512352;2202.273249827553;2205.4596623827106;7865.8033549161155;7895.8026049348655;7904.0119985001875;7915.485493442965;7922.168172341936;4528.56978587037;4595.425571803525;4609.746463944482;4613.884652883671;4614.353990441341;2659.1490722968656;2712.9457410851674;2715.2804506805714;2731.2625591090296;2849.216465471991;783.0759703549809;798.9161138080476;805.8106345008977;815.2255357410484;823.1315961660446;11377.772444551063;11821.94089029558;11890.821637675443;11899.643010709693;11900.940495297555;4218.409422680827;4286.099919017041;4308.9353659695125;4333.393324934512;4334.4798624165305;9731.9026809731;9792.755181120387;9804.509774511245;9854.211663066937;9854.901450985542;4485.349624304523;4519.141363140992;4590.036092420629;4598.448186217608;4601.079784043138;4023.7988100594853;4055.3916912463364;4143.854965076268;4146.896327591834;4150.937735933963;3569.286742363628;3571.321448924696;3578.4453409721536;3608.8195590220907;3613.2050948791225;1073.120041565924;1080.2114456446682;1101.8732845722845;1111.216592302425;1142.9199956003167;846.3905987688854;1136.2273653915418;1136.4374634555948;1181.3325471108797;1182.4974385886137;6534.575252608565;6917.377436030782;8104.189581041966;8131.14622964592;8151.347892168612;581.9592628516048;587.9382664820217;589.9911501327483;597.1311741416229;599.8890205311944;5837.299524057055;5840.970795145997;5890.9410905891245;5906.291245050621;5909.881802363929;9885.357451765612;10096.646617368357;10201.234907381977;10227.181825453947;10272.383656980604;9712.271579631555;10089.545970431349;10091.899081009244;10121.746956326151;10137.493125343703;6155.076738489173;6195.783147589903;7267.745628903069;7335.413166946738;7337.229590892986;6651.46788256948;7280.3083707047745;7374.33630973215;7465.253474652709;7511.136219334628;7705.22947705225;8139.715109971123;8146.185381461806;8153.510789352657;8237.341012719087;8197.918020819716;8497.532635705096;8540.91459085426;8541.701040463555;8553.31573474133;6808.863822723617;7267.527610705499;7270.200277969313;7349.669264882986;7353.558786472829;7910.446268761361;7945.48354356965;7969.163237859942;8023.839523209386;8037.115917249215;7297.562146271241;7339.816504587215;7386.113666359856;7510.211427800003;7530.359919406693;7163.677634506564;7205.675744591401;7315.48791584584;7332.240262337107;7428.814279643052;7125;7302.488825782144;7412.332890039922;7419.554826710415;7432.851342973218;6630.403263706289;6650.90023649636;6695.799126026227;6698.263191049078;6707.195136583641;6718.630475323708;6776.728930842908;6805.421539169226;6807.149106361705;6807.489438292247 04:10:52 INFO - 2015-10-26 04:10:52,448 DEBUG : BROWSER_OUTPUT: |4;prototype.html;271.65499815234665;273.41216384772736;277.0952838980728;280.5077089707563;280.5833337493821;4687.953120468795;4700.858974230774;4702.294655801631;4705.341252224691;4718.528147185284;4547.363369128325;4619.7921093550785;4626.838060667869;4627.907441851161;4640.883977900554;3429.6227414984332;3503.3693935091737;3505.298940211968;3510.0873772818995;3512.701420379271;3503.4569641705575;3509.2805974775238;3510.4032314506467;3512.051746028571;3520.137566296261;3342.114339699969;3392.2197894484284;3407.6421975692565;3468.826558672069;3487.145649315921;3365.057783820534;3435.381631306364;3447.3105378924065;3451.188970591909;3456.4988076728987;3038.8328641924686;3042.7413669838093;3044.8477576121104;3045.056032629892;3045.6954304569585;4413.823447062114;4479.10417916418;4485.2599321111975;4542.295944128666;4548.522405147445;4026.617471340218;4074.694397920168;4102.425660407545;4104.938425923614;4106.404571337169;4602.401687780601;4714.882127946795;4888.828890988834;4892.9510704892855;4909.263610458424;3441.6042155152227;3444.569428821397;3445.913852153691;3448.4654878493625;3450.516927630134;5080.847574572768;5085.79656813727;5086.949130508721;5088.516590923857;5090.9236361454605;3359.4960755886323;3410.6418826023405;3522.577290725078;3526.664966828147;3531.399662057456;3440.449528075496;3445.500402441682;3445.844936977842;3447.6035255945385;3448.6723761242642;3997.1006523531973;4001.7198796084576;4009.599040095967;4012.297847876668;4013.2174226026227;4898.338724272177;4904.632152588499;4907.386576677915;4909.4845041270155;4910.238912968495;1223.4372188793077;1236.7402845402387;1237.5730373021474;1240.2000709542294;1241.3979220078254;823.8558252305822;833.6207025803245;853.6841368693623;855.4525103933488;859.8134574287568;4643.512431194744;4704.223803072455;4706.576408123216;4710.3405523226775;4712.952870471251;4935.333729946411;5047.545720885138;5094.592432605383;5103.336566246364;5106.923396149061;4649.093426781745;4702.27114797207;4704.129735998851;4714.292856071548;4715.93891374438;2765.0737003104186;2791.1486996466274;2791.2184588315304;2792.176307989148;2794.3433293176085;5096.439391666913;5269.420363759983;5275.4460781618145;5282.656627319288;5285.286486324374;5224.869378265574;5229.529342359206;5230.895382092413;5235.424103348705;5236.397814251333;5150.768215430389;5220.530152286313;5284.445133261029;5290.127129023745;5290.391604965402;5034.270030845433;5093.821716240062;5113.284140220298;5120;5121.462246464068;5003.924941125813;5004;5005.374328208974;5007.974960125176;5010.198368261061;5121.769520371667;5185.781341384696;5263.710495922608;5265.131253343156;5274.472552744694;3460.307938412277;3484.1115515543374;3486.9825650871585;3487.1630808605246;3487.4071407860215;5017.37282839645;5076.593872490266;5128.12821820284;5144.845654630367;5150.690958542499;4903.387076615423;4923.975380123076;4924;4924.4090709115135;4931.3835770528685;4894.877628059327;4907.55831975124;4908.533689299511;4912.164931961646;4915.926261106158;4788.16207163738;4793.976030119967;4798.136335459651;4804.927926081182;4808.134535783593;4854.490278520846;4935.580475659609;4939.802407903644;4941.75291235444;4950.356453661001;4787.329773831597;4885.780139893643;4888.169011268022;4888.7311197884455;4939.950600493949 04:10:52 INFO - 2015-10-26 04:10:52,450 DEBUG : BROWSER_OUTPUT: |5;yui.html;633.2243002322156;670.6780745242282;694.2432748304348;700.8318003679119;700.8843540815766;11618;11651.533938642444;11685.883141168586;11731.53073877046;11800.291982481058;10886.40124793136;10905.563777448893;10914.23600347976;10993.285436446626;11001.339919604809;483.57445448005706;494.8218641289133;618.8824123416559;630.1209812311822;630.2972186012609;598.7604958016784;600.0728873889828;613.6042252746976;614.68958176121;616.3589866538795;646.6249575246346;654.2116749317086;663.0716996205302;663.8506336074392;664.4717449627558;513.4223998002248;521.7052365413567;642.2164958750317;658.8715200535897;660.5904339309635;473.46734923211386;473.5335694341071;481.59545981375464;482.3319702212461;482.9034193161382;11085.113190944705;11193.16051296156;11228.55085796567;11235.101191904629;11237.26957747752;9808.852867206997;10031.59873605055;10106.191504679608;10345.793084138275;10729.08802751767;8655.567221638892;8924.821503569892;8945.329100317502;8965.417247878868;9043.412178208395;3170.920726981844;3205.4917176540766;3221.8066915985114;3242.4487837067754;3289.3256882339065;32156.196095097577;33268;33662.49506257408;33670.98987030368;34215.14462138442;8250.716346681838;8655.870161947576;8664.220220180214;8672.56637168139;8908;14762.557123286104;14770.113793172444;14842.03526770778;14878.404863805543;14888.851111488962;6314.905276420857;6333.809985700436;6349.49204063683;6526.836829079311;6542.869142617121;3591.892243232707;3594.2982890901994;3630.7821530708243;3634.854605815791;3669.0093674707678;2374.240243122219;2380.5000949800387;2398.0407836865043;2438.2319569335605;2464.5070985802913;379.4725331788816;379.51611695088985;384.65957627499864;387.1212347970092;396.1285172620247;564.7882044233412;566.6571724106939;577.5004621002803;586.6802592587051;616.6978180691518;6850.794476165723;6946.51374403797;7410.814729631802;7423.4061275097865;7841.372690184771;388.8722704157908;581.892349915267;583.9708014599253;604.6221111805122;616.0666590115978;3217.581714377116;3562.839672214757;3591.4074177760376;3593.730470214692;3596.118950857057;41398.58601414008;43488.347674784905;43686.781566091966;44090.779546102065;44147.558524414984;35415;35415.29169416649;35678.643213567535;35869.46195807065;36511.4523282151;29358.26604334866;29765.255868603457;30533.541996870066;30538.69461305403;30590.84704576463;6791.422729068085;6791.490638202055;7057.435405167677;7383.741569045058;7394.926050739424;21705.891470542545;22245.22141725032;22508.21221257248;22528.32415027552;22564;30071.699283007765;30127.698723013367;30358.54462183025;30403.695963040085;30568.388632227674;6186.505079593711;6360.554761166674;6451.903221451776;6529.7388104475285;6743.9325606743305;9437.245020398213;9598.66404675833;10075.848862266925;10146;10179.592816287266;9269.953650231706;9580.473073980998;9625;9717.951410243186;10058.446785426871;9075.183233509017;9130.452172869649;9306.441613503212;9348.95325523368;9693;9338.439693618404;9361.541667493686;9526.333156679244;9735.464549449849;9816.55825487869;8373.790655233424;8422.387791383024;8449.23956843887;8525.573721314033;9182.816343673223;8113.634886430007;8566.571671416528;8593.742187734379;8731;8770.342224333328 04:10:52 INFO - 2015-10-26 04:10:52,450 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:10:52 INFO - 2015-10-26 04:10:52,450 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:10:52 INFO - 2015-10-26 04:10:52,450 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,782 04:10:52 INFO - 2015-10-26 04:10:52,450 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:10:52 INFO - 2015-10-26 04:10:52,450 DEBUG : BROWSER_OUTPUT: __startTimestamp1445857852421__endTimestamp 04:10:52 INFO - 2015-10-26 04:10:52,450 DEBUG : BROWSER_OUTPUT: 04:10:52 INFO - 2015-10-26 04:10:52,450 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:10:52 INFO - 2015-10-26 04:10:52,451 DEBUG : BROWSER_OUTPUT: Number of tests: 6 04:10:52 INFO - 2015-10-26 04:10:52,451 DEBUG : BROWSER_OUTPUT: 04:10:52 INFO - 2015-10-26 04:10:52,451 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4209.15 Median:4393.91 stddev:548.33 (12.5%) stddev-sans-first:530.33 04:10:52 INFO - 2015-10-26 04:10:52,451 DEBUG : BROWSER_OUTPUT: Values: 2353.5 2377.4 2437.5 2458.9 2462.4 4253.6 4273.0 4278.7 4346.7 4356.2 4315.6 4402.6 4404.1 4407.2 4417.2 3697.3 3752.2 3781.2 3810.5 3869.6 3550.2 3553.4 3570.5 3723.3 3781.4 3724.1 3772.8 3781.0 3792.4 3792.7 3767.8 3782.2 3789.1 3803.7 3887.6 3623.5 3710.1 3734.0 3759.7 3789.2 4237.8 4274.2 4286.4 4312.1 4326.1 4430.0 4525.2 4535.9 4577.0 4657.8 4314.2 4393.9 4429.2 4545.1 4553.9 4184.5 4309.2 4378.4 4379.4 4385.2 4518.8 4542.2 4563.3 4707.6 4785.9 4452.4 4472.9 4598.1 4607.5 4612.7 4528.0 4538.1 4662.9 4666.8 4684.3 4376.0 4495.5 4497.7 4530.0 4624.4 4225.6 4423.3 4519.9 4633.2 4651.4 3073.4 3124.8 3132.2 3207.2 3215.8 2737.5 2817.5 2900.1 2902.2 2930.3 4269.9 4293.2 4351.0 4380.2 4384.2 4617.1 4641.5 4644.8 4689.6 4734.5 4203.8 4294.5 4305.6 4312.2 4412.8 3867.1 3888.7 3989.4 4000.0 4003.6 4770.5 4773.7 4787.5 4859.3 4879.4 4494.8 4552.1 4612.7 4621.8 4726.9 4622.9 4632.5 4682.6 4787.4 4801.5 4348.7 4540.6 4548.2 4611.2 4650.6 4459.4 4539.3 4566.2 4610.7 4700.8 4373.0 4386.6 4440.6 4467.2 4503.3 4460.8 4466.8 4525.5 4551.6 4557.9 4241.7 4346.5 4348.5 4363.7 4457.0 4222.0 4443.5 4444.7 4540.9 4546.8 4312.5 4401.1 4493.0 4494.6 4516.7 04:10:52 INFO - 2015-10-26 04:10:52,451 DEBUG : BROWSER_OUTPUT: 04:10:52 INFO - 2015-10-26 04:10:52,451 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:39688.28 Median:30549.04 stddev:65020.76 (212.8%) stddev-sans-first:65136.53 04:10:52 INFO - 2015-10-26 04:10:52,451 DEBUG : BROWSER_OUTPUT: Values: 486.1 502.6 506.0 510.1 514.9 26108.3 26461.7 36771.3 37125.4 37149.8 38426.4 38849.2 38943.4 38958.0 39020.0 6232.2 6332.5 7526.3 7944.2 8090.4 9917.9 10145.7 10148.1 10160.5 10172.6 9927.0 10018.3 10049.1 10056.6 10056.9 6164.9 6323.1 9704.5 9835.7 10246.4 9787.1 9906.0 9928.7 9950.3 9954.3 36169.8 36518.5 36543.5 36557.8 36591.8 2650.4 2657.0 2679.7 2953.7 3412.7 26665.7 26912.2 26923.3 27564.0 27575.4 2301.5 2301.8 2307.5 2356.0 2359.2 229936.0 230037.0 230181.0 230514.0 231409.0 40157.4 40906.4 49042.5 49431.5 51098.5 50414.5 50924.0 50943.7 50992.0 51027.7 10474.7 10630.1 10653.3 10658.1 10674.2 8595.9 8605.2 8663.5 8684.9 8686.0 15768.7 15869.6 16141.6 16154.4 16240.5 7057.5 7308.3 7333.2 7339.9 7400.0 765.7 824.3 835.3 886.3 977.4 24911.6 25524.0 25884.0 26005.3 26145.8 895.5 939.9 979.0 990.9 1000.4 13222.3 13365.6 13432.9 13579.3 13632.2 275525.0 281111.0 281525.0 284446.0 284802.0 43098.6 44941.3 45216.8 45219.8 45425.5 1101.2 1120.0 1120.8 1121.8 1124.2 18582.4 25902.6 27725.0 27750.3 27835.4 41931.4 42139.8 42272.0 42310.2 42376.6 226725.0 227428.0 231001.0 237422.0 239360.0 20217.9 25697.5 26367.9 26469.2 26912.7 33096.7 33226.2 34180.1 34835.3 34839.0 28347.3 29142.3 29626.3 30455.5 32484.0 30642.5 30969.4 32300.5 32448.8 32712.7 29875.2 31644.4 31845.0 32016.7 32065.7 28817.9 28893.4 29633.9 29636.7 29755.3 20866.3 21838.5 22058.4 22273.4 22369.6 04:10:52 INFO - 2015-10-26 04:10:52,451 DEBUG : BROWSER_OUTPUT: 04:10:52 INFO - 2015-10-26 04:10:52,453 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:18877.43 Median:48149.14 stddev:49970.88 (103.8%) stddev-sans-first:50091.58 04:10:52 INFO - 2015-10-26 04:10:52,453 DEBUG : BROWSER_OUTPUT: Values: 295.7 295.9 298.9 302.9 303.1 14934.4 15157.2 15213.8 15413.5 16555.7 13592.9 13630.5 13807.0 13928.7 14004.3 5091.6 5199.8 5208.6 5220.5 5222.6 4519.6 4935.2 5186.5 5228.8 5264.9 5039.6 5171.7 5180.6 5186.9 5196.7 4777.5 5059.7 5169.7 5183.6 5193.9 3391.4 3619.9 3686.6 3701.3 4553.5 11596.9 11604.2 11722.2 11745.4 11769.4 1522.3 1625.0 1642.4 1732.6 1771.1 8234.5 8448.2 8588.3 8607.1 8715.4 1521.9 1606.0 1622.4 1696.2 1710.4 48969.5 49169.0 52588.0 55219.2 55391.0 5288.2 5585.7 5595.6 5615.5 5762.9 6168.8 6455.4 6616.5 6622.4 6650.6 4258.2 4368.7 4381.3 4447.7 4524.9 4426.0 4484.8 4509.0 4540.5 4545.9 2625.5 2625.7 2632.9 2636.7 2645.3 939.5 950.9 961.4 1002.2 1028.7 369.0 384.1 399.4 399.8 414.4 9946.2 10287.9 10301.7 10472.8 10498.7 355.8 360.8 367.0 373.8 413.6 5406.0 5493.2 5514.1 5551.2 5624.9 295247.0 299364.0 303117.0 303948.0 307221.0 55653.7 57138.7 57272.4 60996.0 62309.7 413.6 422.0 422.2 422.8 425.4 9211.9 9401.7 9521.9 9586.0 9615.9 31490.3 32715.3 33082.0 33134.8 33308.3 45810.3 46568.8 48121.0 48177.3 48730.8 5328.4 5389.1 5427.9 5516.9 5561.2 10271.8 10326.9 10584.9 10628.0 10638.2 9086.8 9089.9 9189.0 9235.5 9307.3 8559.1 8864.2 8910.7 9050.4 9166.6 9042.8 9225.2 9489.5 9500.6 9669.2 5631.1 5632.4 5687.7 5690.7 5716.9 8999.1 9035.1 9270.6 9390.7 9396.9 04:10:52 INFO - 2015-10-26 04:10:52,454 DEBUG : BROWSER_OUTPUT: 04:10:52 INFO - 2015-10-26 04:10:52,454 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5561.17 Median:598.51 stddev:3100.38 (518.0%) stddev-sans-first:3091.28 04:10:52 INFO - 2015-10-26 04:10:52,454 DEBUG : BROWSER_OUTPUT: Values: 1141.5 1271.9 1352.8 1470.3 1647.8 7183.4 8167.8 8355.2 8376.4 8403.4 8022.6 8131.6 8242.3 8415.3 8415.3 1843.7 1874.4 2102.7 2123.0 2154.8 2167.8 2194.0 2200.1 2203.7 2204.0 2139.2 2162.5 2164.3 2170.9 2170.9 1790.7 1813.6 2100.5 2199.7 2202.4 2097.1 2173.1 2194.4 2202.3 2205.5 7865.8 7895.8 7904.0 7915.5 7922.2 4528.6 4595.4 4609.7 4613.9 4614.4 2659.1 2712.9 2715.3 2731.3 2849.2 783.1 798.9 805.8 815.2 823.1 11377.8 11821.9 11890.8 11899.6 11900.9 4218.4 4286.1 4308.9 4333.4 4334.5 9731.9 9792.8 9804.5 9854.2 9854.9 4485.3 4519.1 4590.0 4598.4 4601.1 4023.8 4055.4 4143.9 4146.9 4150.9 3569.3 3571.3 3578.4 3608.8 3613.2 1073.1 1080.2 1101.9 1111.2 1142.9 846.4 1136.2 1136.4 1181.3 1182.5 6534.6 6917.4 8104.2 8131.1 8151.3 582.0 587.9 590.0 597.1 599.9 5837.3 5841.0 5890.9 5906.3 5909.9 9885.4 10096.6 10201.2 10227.2 10272.4 9712.3 10089.5 10091.9 10121.7 10137.5 6155.1 6195.8 7267.7 7335.4 7337.2 6651.5 7280.3 7374.3 7465.3 7511.1 7705.2 8139.7 8146.2 8153.5 8237.3 8197.9 8497.5 8540.9 8541.7 8553.3 6808.9 7267.5 7270.2 7349.7 7353.6 7910.4 7945.5 7969.2 8023.8 8037.1 7297.6 7339.8 7386.1 7510.2 7530.4 7163.7 7205.7 7315.5 7332.2 7428.8 7125.0 7302.5 7412.3 7419.6 7432.9 6630.4 6650.9 6695.8 6698.3 6707.2 6718.6 6776.7 6805.4 6807.1 6807.5 04:10:52 INFO - 2015-10-26 04:10:52,456 DEBUG : BROWSER_OUTPUT: 04:10:52 INFO - 2015-10-26 04:10:52,456 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4100.23 Median:4708.46 stddev:1241.59 (26.4%) stddev-sans-first:1211.36 04:10:52 INFO - 2015-10-26 04:10:52,456 DEBUG : BROWSER_OUTPUT: Values: 271.7 273.4 277.1 280.5 280.6 4688.0 4700.9 4702.3 4705.3 4718.5 4547.4 4619.8 4626.8 4627.9 4640.9 3429.6 3503.4 3505.3 3510.1 3512.7 3503.5 3509.3 3510.4 3512.1 3520.1 3342.1 3392.2 3407.6 3468.8 3487.1 3365.1 3435.4 3447.3 3451.2 3456.5 3038.8 3042.7 3044.8 3045.1 3045.7 4413.8 4479.1 4485.3 4542.3 4548.5 4026.6 4074.7 4102.4 4104.9 4106.4 4602.4 4714.9 4888.8 4893.0 4909.3 3441.6 3444.6 3445.9 3448.5 3450.5 5080.8 5085.8 5086.9 5088.5 5090.9 3359.5 3410.6 3522.6 3526.7 3531.4 3440.4 3445.5 3445.8 3447.6 3448.7 3997.1 4001.7 4009.6 4012.3 4013.2 4898.3 4904.6 4907.4 4909.5 4910.2 1223.4 1236.7 1237.6 1240.2 1241.4 823.9 833.6 853.7 855.5 859.8 4643.5 4704.2 4706.6 4710.3 4713.0 4935.3 5047.5 5094.6 5103.3 5106.9 4649.1 4702.3 4704.1 4714.3 4715.9 2765.1 2791.1 2791.2 2792.2 2794.3 5096.4 5269.4 5275.4 5282.7 5285.3 5224.9 5229.5 5230.9 5235.4 5236.4 5150.8 5220.5 5284.4 5290.1 5290.4 5034.3 5093.8 5113.3 5120.0 5121.5 5003.9 5004.0 5005.4 5008.0 5010.2 5121.8 5185.8 5263.7 5265.1 5274.5 3460.3 3484.1 3487.0 3487.2 3487.4 5017.4 5076.6 5128.1 5144.8 5150.7 4903.4 4924.0 4924.0 4924.4 4931.4 4894.9 4907.6 4908.5 4912.2 4915.9 4788.2 4794.0 4798.1 4804.9 4808.1 4854.5 4935.6 4939.8 4941.8 4950.4 4787.3 4885.8 4888.2 4888.7 4940.0 04:10:52 INFO - 2015-10-26 04:10:52,457 DEBUG : BROWSER_OUTPUT: 04:10:52 INFO - 2015-10-26 04:10:52,457 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:10344.50 Median:504.12 stddev:11033.53 (2188.7%) stddev-sans-first:11040.38 04:10:52 INFO - 2015-10-26 04:10:52,457 DEBUG : BROWSER_OUTPUT: Values: 633.2 670.7 694.2 700.8 700.9 11618.0 11651.5 11685.9 11731.5 11800.3 10886.4 10905.6 10914.2 10993.3 11001.3 483.6 494.8 618.9 630.1 630.3 598.8 600.1 613.6 614.7 616.4 646.6 654.2 663.1 663.9 664.5 513.4 521.7 642.2 658.9 660.6 473.5 473.5 481.6 482.3 482.9 11085.1 11193.2 11228.6 11235.1 11237.3 9808.9 10031.6 10106.2 10345.8 10729.1 8655.6 8924.8 8945.3 8965.4 9043.4 3170.9 3205.5 3221.8 3242.4 3289.3 32156.2 33268.0 33662.5 33671.0 34215.1 8250.7 8655.9 8664.2 8672.6 8908.0 14762.6 14770.1 14842.0 14878.4 14888.9 6314.9 6333.8 6349.5 6526.8 6542.9 3591.9 3594.3 3630.8 3634.9 3669.0 2374.2 2380.5 2398.0 2438.2 2464.5 379.5 379.5 384.7 387.1 396.1 564.8 566.7 577.5 586.7 616.7 6850.8 6946.5 7410.8 7423.4 7841.4 388.9 581.9 584.0 604.6 616.1 3217.6 3562.8 3591.4 3593.7 3596.1 41398.6 43488.3 43686.8 44090.8 44147.6 35415.0 35415.3 35678.6 35869.5 36511.5 29358.3 29765.3 30533.5 30538.7 30590.8 6791.4 6791.5 7057.4 7383.7 7394.9 21705.9 22245.2 22508.2 22528.3 22564.0 30071.7 30127.7 30358.5 30403.7 30568.4 6186.5 6360.6 6451.9 6529.7 6743.9 9437.2 9598.7 10075.8 10146.0 10179.6 9270.0 9580.5 9625.0 9718.0 10058.4 9075.2 9130.5 9306.4 9349.0 9693.0 9338.4 9361.5 9526.3 9735.5 9816.6 8373.8 8422.4 8449.2 8525.6 9182.8 8113.6 8566.6 8593.7 8731.0 8770.3 04:10:52 INFO - 2015-10-26 04:10:52,459 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:10:52 INFO - 2015-10-26 04:10:52,459 DEBUG : BROWSER_OUTPUT: 04:10:53 INFO - 2015-10-26 04:10:52,999 INFO : Browser exited with error code: 0 04:10:53 INFO - 2015-10-26 04:10:53,757 INFO : Completed test dromaeo_css (00:18:46) 04:10:53 INFO - 2015-10-26 04:10:53,757 INFO : Starting test kraken 04:10:53 INFO - 2015-10-26 04:10:53,757 DEBUG : operating with platform_type : w7_ 04:10:53 INFO - 2015-10-26 04:10:53,757 INFO : Initialising browser for kraken test... 04:10:53 INFO - 2015-10-26 04:10:53,799 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp2urogz\profile http://localhost:49896/getInfo.html 04:10:59 INFO - 2015-10-26 04:10:59,187 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:10:59 INFO - 2015-10-26 04:10:59,187 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:10:59 INFO - 2015-10-26 04:10:59,187 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 04:10:59 INFO - 2015-10-26 04:10:59,187 DEBUG : BROWSER_OUTPUT: __metrics 04:10:59 INFO - 2015-10-26 04:10:59,290 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:11:00 INFO - 2015-10-26 04:11:00,421 INFO : Browser initialized. 04:11:00 INFO - 2015-10-26 04:11:00,421 INFO : Running cycle 1/1 for kraken test... 04:11:00 INFO - 2015-10-26 04:11:00,421 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp2urogz\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 04:11:12 INFO - 2015-10-26 04:11:12,069 DEBUG : BROWSER_OUTPUT: RSS: Main: 141578240 04:11:12 INFO - 2015-10-26 04:11:12,069 DEBUG : BROWSER_OUTPUT: 04:11:14 INFO - 2015-10-26 04:11:14,608 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49896/tests/kraken/driver.html?testName=audio-beat-detection) 04:11:15 INFO - 2015-10-26 04:11:15,190 DEBUG : BROWSER_OUTPUT: RSS: Main: 175308800 04:11:15 INFO - 2015-10-26 04:11:15,190 DEBUG : BROWSER_OUTPUT: 04:11:18 INFO - 2015-10-26 04:11:18,145 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49896/tests/kraken/driver.html?testName=audio-dft) 04:11:18 INFO - 2015-10-26 04:11:18,720 DEBUG : BROWSER_OUTPUT: RSS: Main: 181805056 04:11:18 INFO - 2015-10-26 04:11:18,720 DEBUG : BROWSER_OUTPUT: 04:11:21 INFO - 2015-10-26 04:11:21,536 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49896/tests/kraken/driver.html?testName=audio-fft) 04:11:22 INFO - 2015-10-26 04:11:22,111 DEBUG : BROWSER_OUTPUT: RSS: Main: 171028480 04:11:22 INFO - 2015-10-26 04:11:22,111 DEBUG : BROWSER_OUTPUT: 04:11:24 INFO - 2015-10-26 04:11:24,210 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49896/tests/kraken/driver.html?testName=audio-oscillator) 04:11:24 INFO - 2015-10-26 04:11:24,786 DEBUG : BROWSER_OUTPUT: RSS: Main: 167501824 04:11:24 INFO - 2015-10-26 04:11:24,786 DEBUG : BROWSER_OUTPUT: 04:11:27 INFO - 2015-10-26 04:11:27,062 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49896/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:11:27 INFO - 2015-10-26 04:11:27,621 DEBUG : BROWSER_OUTPUT: RSS: Main: 168214528 04:11:27 INFO - 2015-10-26 04:11:27,621 DEBUG : BROWSER_OUTPUT: 04:11:30 INFO - 2015-10-26 04:11:30,779 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49896/tests/kraken/driver.html?testName=imaging-darkroom) 04:11:31 INFO - 2015-10-26 04:11:31,358 DEBUG : BROWSER_OUTPUT: RSS: Main: 164950016 04:11:31 INFO - 2015-10-26 04:11:31,358 DEBUG : BROWSER_OUTPUT: 04:11:34 INFO - 2015-10-26 04:11:34,714 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49896/tests/kraken/driver.html?testName=imaging-desaturate) 04:11:35 INFO - 2015-10-26 04:11:35,289 DEBUG : BROWSER_OUTPUT: RSS: Main: 165564416 04:11:35 INFO - 2015-10-26 04:11:35,289 DEBUG : BROWSER_OUTPUT: 04:11:38 INFO - 2015-10-26 04:11:38,210 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49896/tests/kraken/driver.html?testName=json-parse-financial) 04:11:38 INFO - 2015-10-26 04:11:38,789 DEBUG : BROWSER_OUTPUT: RSS: Main: 165179392 04:11:38 INFO - 2015-10-26 04:11:38,789 DEBUG : BROWSER_OUTPUT: 04:11:40 INFO - 2015-10-26 04:11:40,648 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49896/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:11:41 INFO - 2015-10-26 04:11:41,209 DEBUG : BROWSER_OUTPUT: RSS: Main: 195215360 04:11:41 INFO - 2015-10-26 04:11:41,209 DEBUG : BROWSER_OUTPUT: 04:11:43 INFO - 2015-10-26 04:11:43,164 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:11:43 INFO - 2015-10-26 04:11:43,743 DEBUG : BROWSER_OUTPUT: RSS: Main: 200433664 04:11:43 INFO - 2015-10-26 04:11:43,743 DEBUG : BROWSER_OUTPUT: 04:11:46 INFO - 2015-10-26 04:11:46,144 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:11:46 INFO - 2015-10-26 04:11:46,719 DEBUG : BROWSER_OUTPUT: RSS: Main: 206413824 04:11:46 INFO - 2015-10-26 04:11:46,719 DEBUG : BROWSER_OUTPUT: 04:11:49 INFO - 2015-10-26 04:11:49,341 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:11:49 INFO - 2015-10-26 04:11:49,915 DEBUG : BROWSER_OUTPUT: RSS: Main: 208089088 04:11:49 INFO - 2015-10-26 04:11:49,915 DEBUG : BROWSER_OUTPUT: 04:11:53 INFO - 2015-10-26 04:11:53,056 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:11:53 INFO - 2015-10-26 04:11:53,631 DEBUG : BROWSER_OUTPUT: RSS: Main: 207478784 04:11:53 INFO - 2015-10-26 04:11:53,631 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,638 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49896/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49896/tests/kraken/driver.html?testName=ai-astar) 04:11:55 INFO - 2015-10-26 04:11:55,894 DEBUG : BROWSER_OUTPUT: RSS: Main: 252989440 04:11:55 INFO - 2015-10-26 04:11:55,894 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,894 DEBUG : BROWSER_OUTPUT: __start_tp_report 04:11:55 INFO - 2015-10-26 04:11:55,894 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 04:11:55 INFO - 2015-10-26 04:11:55,894 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 04:11:55 INFO - 2015-10-26 04:11:55,894 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 04:11:55 INFO - 2015-10-26 04:11:55,894 DEBUG : BROWSER_OUTPUT: |0;ai-astar;91;97;96;89;104;98;98;98;96;97 04:11:55 INFO - 2015-10-26 04:11:55,895 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;173;154;156;172;153;143;158;159;205;174 04:11:55 INFO - 2015-10-26 04:11:55,895 DEBUG : BROWSER_OUTPUT: |2;audio-dft;156;148;160;152;155;150;150;156;151;154 04:11:55 INFO - 2015-10-26 04:11:55,895 DEBUG : BROWSER_OUTPUT: |3;audio-fft;83;84;81;103;76;79;82;79;103;81 04:11:55 INFO - 2015-10-26 04:11:55,895 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;100;99;116;100;99;101;125;100;100;123 04:11:55 INFO - 2015-10-26 04:11:55,897 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;117;117;117;117;117;116;116;116;116;117 04:11:55 INFO - 2015-10-26 04:11:55,897 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;136;136;136;136;136;137;138;136;137;136 04:11:55 INFO - 2015-10-26 04:11:55,897 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;94;93;93;94;96;95;94;94;93;94 04:11:55 INFO - 2015-10-26 04:11:55,897 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;79;79;79;80;79;78;78;78;79;90 04:11:55 INFO - 2015-10-26 04:11:55,897 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;84;75;75;79;75;75;75;74;75;75 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;101;102;99;100;101;99;100;100;100;99 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;135;127;129;127;130;131;134;133;127;132 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;187;195;194;200;197;187;195;196;196;187 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;92;94;90;90;91;90;92;90;92;90 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: __end_tp_report 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: __start_cc_report 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,954 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: __end_cc_report 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: __startTimestamp1445857915888__endTimestamp 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: Number of tests: 14 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:96.40 Median:97.00 stddev:4.09 (4.2%) stddev-sans-first:3.84 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: Values: 91.0 97.0 96.0 89.0 104.0 98.0 98.0 98.0 96.0 97.0 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:164.70 Median:165.50 stddev:17.35 (10.5%) stddev-sans-first:18.14 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: Values: 173.0 154.0 156.0 172.0 153.0 143.0 158.0 159.0 205.0 174.0 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:153.20 Median:154.50 stddev:3.65 (2.4%) stddev-sans-first:3.72 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: Values: 156.0 148.0 160.0 152.0 155.0 150.0 150.0 156.0 151.0 154.0 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,898 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:85.10 Median:81.00 stddev:9.70 (12.0%) stddev-sans-first:10.26 04:11:55 INFO - 2015-10-26 04:11:55,900 DEBUG : BROWSER_OUTPUT: Values: 83.0 84.0 81.0 103.0 76.0 79.0 82.0 79.0 103.0 81.0 04:11:55 INFO - 2015-10-26 04:11:55,900 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,900 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.30 Median:119.50 stddev:10.63 (8.9%) stddev-sans-first:11.02 04:11:55 INFO - 2015-10-26 04:11:55,900 DEBUG : BROWSER_OUTPUT: Values: 100.0 99.0 116.0 100.0 99.0 101.0 125.0 100.0 100.0 123.0 04:11:55 INFO - 2015-10-26 04:11:55,901 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,901 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 04:11:55 INFO - 2015-10-26 04:11:55,901 DEBUG : BROWSER_OUTPUT: Values: 117.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 116.0 117.0 04:11:55 INFO - 2015-10-26 04:11:55,901 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,901 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:136.40 Median:136.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 04:11:55 INFO - 2015-10-26 04:11:55,901 DEBUG : BROWSER_OUTPUT: Values: 136.0 136.0 136.0 136.0 136.0 137.0 138.0 136.0 137.0 136.0 04:11:55 INFO - 2015-10-26 04:11:55,901 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,903 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:94.00 Median:94.00 stddev:0.94 (1.0%) stddev-sans-first:1.00 04:11:55 INFO - 2015-10-26 04:11:55,903 DEBUG : BROWSER_OUTPUT: Values: 94.0 93.0 93.0 94.0 96.0 95.0 94.0 94.0 93.0 94.0 04:11:55 INFO - 2015-10-26 04:11:55,903 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,903 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:79.90 Median:79.00 stddev:3.60 (4.6%) stddev-sans-first:3.81 04:11:55 INFO - 2015-10-26 04:11:55,904 DEBUG : BROWSER_OUTPUT: Values: 79.0 79.0 79.0 80.0 79.0 78.0 78.0 78.0 79.0 90.0 04:11:55 INFO - 2015-10-26 04:11:55,904 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,904 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:76.20 Median:75.00 stddev:3.05 (4.1%) stddev-sans-first:1.41 04:11:55 INFO - 2015-10-26 04:11:55,904 DEBUG : BROWSER_OUTPUT: Values: 84.0 75.0 75.0 79.0 75.0 75.0 75.0 74.0 75.0 75.0 04:11:55 INFO - 2015-10-26 04:11:55,904 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,904 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:100.10 Median:101.50 stddev:0.99 (1.0%) stddev-sans-first:1.00 04:11:55 INFO - 2015-10-26 04:11:55,904 DEBUG : BROWSER_OUTPUT: Values: 101.0 102.0 99.0 100.0 101.0 99.0 100.0 100.0 100.0 99.0 04:11:55 INFO - 2015-10-26 04:11:55,904 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,905 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:130.50 Median:131.50 stddev:2.99 (2.3%) stddev-sans-first:2.69 04:11:55 INFO - 2015-10-26 04:11:55,905 DEBUG : BROWSER_OUTPUT: Values: 135.0 127.0 129.0 127.0 130.0 131.0 134.0 133.0 127.0 132.0 04:11:55 INFO - 2015-10-26 04:11:55,905 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,905 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.40 Median:195.50 stddev:4.70 (2.4%) stddev-sans-first:4.37 04:11:55 INFO - 2015-10-26 04:11:55,907 DEBUG : BROWSER_OUTPUT: Values: 187.0 195.0 194.0 200.0 197.0 187.0 195.0 196.0 196.0 187.0 04:11:55 INFO - 2015-10-26 04:11:55,907 DEBUG : BROWSER_OUTPUT: 04:11:55 INFO - 2015-10-26 04:11:55,907 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:91.10 Median:91.50 stddev:1.37 (1.5%) stddev-sans-first:1.41 04:11:55 INFO - 2015-10-26 04:11:55,907 DEBUG : BROWSER_OUTPUT: Values: 92.0 94.0 90.0 90.0 91.0 90.0 92.0 90.0 92.0 90.0 04:11:55 INFO - 2015-10-26 04:11:55,907 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 04:11:55 INFO - 2015-10-26 04:11:55,907 DEBUG : BROWSER_OUTPUT: 04:11:56 INFO - 2015-10-26 04:11:56,614 INFO : Browser exited with error code: 0 04:11:57 INFO - 2015-10-26 04:11:57,523 INFO : Completed test kraken (00:01:03) 04:11:57 INFO - 2015-10-26 04:11:57,523 INFO : Starting test v8_7 04:11:57 INFO - 2015-10-26 04:11:57,523 DEBUG : operating with platform_type : w7_ 04:11:57 INFO - 2015-10-26 04:11:57,525 INFO : Initialising browser for v8_7 test... 04:11:57 INFO - 2015-10-26 04:11:57,551 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp2xorpw\profile http://localhost:49896/getInfo.html 04:12:02 INFO - 2015-10-26 04:12:02,085 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 04:12:02 INFO - 2015-10-26 04:12:02,085 DEBUG : BROWSER_OUTPUT: colorDepth:24 04:12:02 INFO - 2015-10-26 04:12:02,085 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 04:12:02 INFO - 2015-10-26 04:12:02,085 DEBUG : BROWSER_OUTPUT: __metrics 04:12:02 INFO - 2015-10-26 04:12:02,211 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:12:03 INFO - 2015-10-26 04:12:02,999 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 121: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 04:12:03 INFO - 2015-10-26 04:12:03,201 DEBUG : BROWSER_OUTPUT: console.error: 04:12:03 INFO - 2015-10-26 04:12:03,203 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] 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: Stack: 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 04:12:03 INFO - 2015-10-26 04:12:03,203 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 5643.40 04:12:38 INFO - RETURN: kraken: 1623.90 04:12:38 INFO - RETURN: v8_7: 20214.71 04:12:38 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,25]]", "result": "20214.71"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,25]]", "result": "1623.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,25]]", "result": "5643.40"}}} 04:12:38 INFO - Return code: 0 04:12:38 INFO - # TBPL SUCCESS # 04:12:38 INFO - Running post-action listener: _resource_record_post_action 04:12:38 INFO - Running post-run listener: _resource_record_post_run 04:12:40 INFO - Total resource usage - Wall time: 1236s; CPU: 13.0%; Read bytes: 144674816; Write bytes: 487267840; Read time: 3325110; Write time: 5151680 04:12:40 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 16384; Read time: 0; Write time: 70 04:12:40 INFO - run-tests - Wall time: 1235s; CPU: 13.0%; Read bytes: 144642048; Write bytes: 462989824; Read time: 3323260; Write time: 4940120 04:12:40 INFO - Running post-run listener: _upload_blobber_files 04:12:40 INFO - Blob upload gear active. 04:12:40 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 04:12:40 INFO - Copying logs to upload dir... 04:12:40 INFO - mkdir: C:\slave\test\build\upload\logs program finished with exit code 0 elapsedTime=1416.032000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 59 secs) (at 2015-10-26 04:13:03.251352) ========= ========= Started set props: build_url (results: 0, elapsed: 15 secs) (at 2015-10-26 04:13:03.257523) ========= '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-098 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-098 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-098 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/-JxgCBkKS5WxhrHCyY4e0w/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/-JxgCBkKS5WxhrHCyY4e0w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 15 secs) (at 2015-10-26 04:13:18.381791) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2015-10-26 04:13:18.382141) ========= '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-098 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-098 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-098 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: 1 secs) (at 2015-10-26 04:13:19.434510) ========= ========= Started reboot slave lost (results: 0, elapsed: 27 secs) (at 2015-10-26 04:13:19.434921) ========= ========= Finished reboot slave lost (results: 0, elapsed: 27 secs) (at 2015-10-26 04:13:47.025887) =========