builder: mozilla-inbound_win7-ix_test-dromaeojs slave: t-w732-ix-056 starttime: 1445525936.39 results: success (0) buildid: 20151022062458 builduid: 1533e9543f3340eb9f028ce5c77cc169 revision: 01c1f46069567aa2477b4fe70b24fbf14c6f3459 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-22 07:58:56.391202) ========= master: http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-22 07:58:56.392893) ========= ========= Started set props: basedir (results: 0, elapsed: 54 secs) (at 2015-10-22 07:58:56.393195) ========= '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-056 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-056 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-056 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.583000 basedir: 'C:\\slave\\test' ========= Finished set props: basedir (results: 0, elapsed: 54 secs) (at 2015-10-22 07:59:50.393256) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 47 secs) (at 2015-10-22 07:59:50.393568) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 47 secs) (at 2015-10-22 08:00:37.658286) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 18 secs) (at 2015-10-22 08:00:37.658565) ========= '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-056 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-056 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-056 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: 18 secs) (at 2015-10-22 08:00:56.566705) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-22 08:00:56.567006) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-22 08:00:56.567368) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 17 secs) (at 2015-10-22 08:00:56.567650) ========= '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-056 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-056 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-056 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 --08:01:01-- 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.23 MB/s 08:01:01 (7.23 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.822000 ========= Finished 'bash -c ...' (results: 0, elapsed: 17 secs) (at 2015-10-22 08:01:14.128990) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2015-10-22 08:01:14.129283) ========= '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-056 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-056 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-056 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=9.401000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 14 secs) (at 2015-10-22 08:01:28.150439) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 37 secs) (at 2015-10-22 08:01:28.150876) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 01c1f46069567aa2477b4fe70b24fbf14c6f3459 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 01c1f46069567aa2477b4fe70b24fbf14c6f3459 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-056 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-056 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-056 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-22 08:01:38,298 truncating revision to first 12 chars 2015-10-22 08:01:38,298 Setting DEBUG logging. 2015-10-22 08:01:38,298 attempt 1/10 2015-10-22 08:01:38,298 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/01c1f4606956?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-22 08:01:40,115 unpacking tar archive at: mozilla-inbound-01c1f4606956/testing/mozharness/ program finished with exit code 0 elapsedTime=2.474000 ========= Finished 'bash -c ...' (results: 0, elapsed: 37 secs) (at 2015-10-22 08:02:05.704985) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 2 mins, 54 secs) (at 2015-10-22 08:02:05.705292) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 2 mins, 54 secs) (at 2015-10-22 08:04:59.901894) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 08:04:59.902139) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 08:04:59.902500) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 0 secs) (at 2015-10-22 08:04:59.902779) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Mozilla-Inbound-Non-PGO' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Mozilla-Inbound-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', 'Mozilla-Inbound-Non-PGO', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-056 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-056 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-056 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 08:05:07 INFO - MultiFileLogger online at 20151022 08:05:07 in C:\slave\test 08:05:07 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound-Non-PGO --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound-Non-PGO 08:05:07 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 08:05:07 INFO - {'append_to_log': False, 08:05:07 INFO - 'base_work_dir': 'C:\\slave\\test', 08:05:07 INFO - 'blob_upload_branch': 'Mozilla-Inbound-Non-PGO', 08:05:07 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 08:05:07 INFO - 'branch': 'Mozilla-Inbound-Non-PGO', 08:05:07 INFO - 'buildbot_json_path': 'buildprops.json', 08:05:07 INFO - 'config_files': ('talos/windows_config.py',), 08:05:07 INFO - 'default_actions': ('clobber', 08:05:07 INFO - 'read-buildbot-config', 08:05:07 INFO - 'download-and-extract', 08:05:07 INFO - 'populate-webroot', 08:05:07 INFO - 'create-virtualenv', 08:05:07 INFO - 'install', 08:05:07 INFO - 'run-tests'), 08:05:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:05:07 INFO - 'download_minidump_stackwalk': True, 08:05:07 INFO - 'download_symbols': 'ondemand', 08:05:07 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 08:05:07 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 08:05:07 INFO - 'hg': 'c:/mozilla-build/hg/hg', 08:05:07 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 08:05:07 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 08:05:07 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 08:05:07 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 08:05:07 INFO - 'C:/mozilla-build/tooltool.py'), 08:05:07 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 08:05:07 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 08:05:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:05:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:05:07 INFO - 'installer_path': 'installer.exe', 08:05:07 INFO - 'log_level': 'info', 08:05:07 INFO - 'log_name': 'talos', 08:05:07 INFO - 'log_to_console': True, 08:05:07 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 08:05:07 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 08:05:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 08:05:07 INFO - 'opt_config_files': (), 08:05:07 INFO - 'pip_index': False, 08:05:07 INFO - 'sps_profile': False, 08:05:07 INFO - 'sps_profile_interval': 0, 08:05:07 INFO - 'suite': 'dromaeojs', 08:05:07 INFO - 'system_bits': '32', 08:05:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:05:07 INFO - 'title': 'T-W732-IX-056', 08:05:07 INFO - 'use_talos_json': True, 08:05:07 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 08:05:07 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 08:05:07 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 08:05:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:05:07 INFO - 'work_dir': 'build'} 08:05:07 INFO - ##### 08:05:07 INFO - ##### Running clobber step. 08:05:07 INFO - ##### 08:05:07 INFO - Running pre-action listener: _resource_record_pre_action 08:05:07 INFO - Running main action method: clobber 08:05:07 INFO - rmtree: C:\slave\test\build 08:05:07 INFO - Using _rmtree_windows ... 08:05:07 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 08:06:19 INFO - Running post-action listener: _resource_record_post_action 08:06:19 INFO - ##### 08:06:19 INFO - ##### Running read-buildbot-config step. 08:06:19 INFO - ##### 08:06:19 INFO - Running pre-action listener: _resource_record_pre_action 08:06:19 INFO - Running main action method: read_buildbot_config 08:06:19 INFO - Using buildbot properties: 08:06:19 INFO - { 08:06:19 INFO - "properties": { 08:06:19 INFO - "buildnumber": 2243, 08:06:19 INFO - "product": "firefox", 08:06:19 INFO - "script_repo_revision": "production", 08:06:19 INFO - "builddir": "mozilla-inbound_win7-ix_test-dromaeojs", 08:06:19 INFO - "repository": "", 08:06:19 INFO - "buildername": "Windows 7 32-bit mozilla-inbound talos dromaeojs", 08:06:19 INFO - "buildid": "20151022062458", 08:06:19 INFO - "slavename": "t-w732-ix-056", 08:06:19 INFO - "pgo_build": "False", 08:06:19 INFO - "basedir": "C:\\slave\\test", 08:06:19 INFO - "project": "", 08:06:19 INFO - "platform": "win7-ix", 08:06:19 INFO - "master": "http://buildbot-master109.bb.releng.scl3.mozilla.com:8201/", 08:06:19 INFO - "slavebuilddir": "test", 08:06:19 INFO - "scheduler": "tests-mozilla-inbound-win32-talos", 08:06:19 INFO - "branch": "mozilla-inbound", 08:06:19 INFO - "repo_path": "integration/mozilla-inbound", 08:06:19 INFO - "stage_platform": "win32", 08:06:19 INFO - "builduid": "1533e9543f3340eb9f028ce5c77cc169", 08:06:19 INFO - "revision": "01c1f46069567aa2477b4fe70b24fbf14c6f3459" 08:06:19 INFO - }, 08:06:19 INFO - "sourcestamp": { 08:06:19 INFO - "repository": "", 08:06:19 INFO - "hasPatch": false, 08:06:19 INFO - "project": "", 08:06:19 INFO - "branch": "mozilla-inbound-win32-talos", 08:06:19 INFO - "changes": [ 08:06:19 INFO - { 08:06:19 INFO - "category": null, 08:06:19 INFO - "files": [ 08:06:19 INFO - { 08:06:19 INFO - "url": null, 08:06:19 INFO - "name": "https://queue.taskcluster.net/v1/task/5gLvAqsnSjeOorbUX1AQUA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 08:06:19 INFO - } 08:06:19 INFO - ], 08:06:19 INFO - "repository": "", 08:06:19 INFO - "rev": "105c07e0c6ad723cf710d3bb56ede01921ad8a89", 08:06:19 INFO - "who": "npierron@mozilla.com", 08:06:19 INFO - "when": 1445525311, 08:06:19 INFO - "number": 6560070, 08:06:19 INFO - "comments": "Bug 1158632 - IonBuilder TableSwitch, fill-the-gap cases should encore the PC of the default block. r=h4writer", 08:06:19 INFO - "project": "", 08:06:19 INFO - "at": "Thu 22 Oct 2015 07:48:31", 08:06:19 INFO - "branch": "mozilla-inbound-win32-talos", 08:06:19 INFO - "revlink": "", 08:06:19 INFO - "properties": [ 08:06:19 INFO - [ 08:06:19 INFO - "buildid", 08:06:19 INFO - "20151022052503", 08:06:19 INFO - "Change" 08:06:19 INFO - ], 08:06:19 INFO - [ 08:06:19 INFO - "builduid", 08:06:19 INFO - "752cc8f5539b4911962f7b0e32fdd812", 08:06:19 INFO - "Change" 08:06:19 INFO - ], 08:06:19 INFO - [ 08:06:19 INFO - "pgo_build", 08:06:19 INFO - "False", 08:06:19 INFO - "Change" 08:06:19 INFO - ] 08:06:19 INFO - ], 08:06:19 INFO - "revision": "105c07e0c6ad723cf710d3bb56ede01921ad8a89" 08:06:19 INFO - }, 08:06:19 INFO - { 08:06:19 INFO - "category": null, 08:06:19 INFO - "files": [ 08:06:19 INFO - { 08:06:19 INFO - "url": null, 08:06:19 INFO - "name": "https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 08:06:19 INFO - } 08:06:19 INFO - ], 08:06:19 INFO - "repository": "", 08:06:19 INFO - "rev": "01c1f46069567aa2477b4fe70b24fbf14c6f3459", 08:06:19 INFO - "who": "josh@joshmatthews.net", 08:06:19 INFO - "when": 1445525627, 08:06:19 INFO - "number": 6560083, 08:06:19 INFO - "comments": "Bug 1173811 - Part 2: Propagate the response URL to intercepted channels when necessary (e10s). r=mayhemer,bkelly", 08:06:19 INFO - "project": "", 08:06:19 INFO - "at": "Thu 22 Oct 2015 07:53:47", 08:06:19 INFO - "branch": "mozilla-inbound-win32-talos", 08:06:19 INFO - "revlink": "", 08:06:19 INFO - "properties": [ 08:06:19 INFO - [ 08:06:19 INFO - "buildid", 08:06:19 INFO - "20151022062458", 08:06:19 INFO - "Change" 08:06:19 INFO - ], 08:06:19 INFO - [ 08:06:19 INFO - "builduid", 08:06:19 INFO - "1533e9543f3340eb9f028ce5c77cc169", 08:06:19 INFO - "Change" 08:06:19 INFO - ], 08:06:19 INFO - [ 08:06:19 INFO - "pgo_build", 08:06:19 INFO - "False", 08:06:19 INFO - "Change" 08:06:19 INFO - ] 08:06:19 INFO - ], 08:06:19 INFO - "revision": "01c1f46069567aa2477b4fe70b24fbf14c6f3459" 08:06:19 INFO - } 08:06:19 INFO - ], 08:06:19 INFO - "revision": "01c1f46069567aa2477b4fe70b24fbf14c6f3459" 08:06:19 INFO - } 08:06:19 INFO - } 08:06:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 08:06:19 INFO - Running post-action listener: _resource_record_post_action 08:06:19 INFO - ##### 08:06:19 INFO - ##### Running download-and-extract step. 08:06:19 INFO - ##### 08:06:19 INFO - Running pre-action listener: _resource_record_pre_action 08:06:19 INFO - Running main action method: download_and_extract 08:06:19 INFO - mkdir: C:\slave\test\build\tests 08:06:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:06:19 INFO - https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 08:06:19 INFO - trying https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/test_packages.json 08:06:19 INFO - Downloading https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json 08:06:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1 08:06:21 INFO - Downloaded 1221 bytes. 08:06:21 INFO - Reading from file C:\slave\test\build\test_packages.json 08:06:21 INFO - Using the following test package requirements: 08:06:21 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 08:06:21 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:06:21 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 08:06:21 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:06:21 INFO - u'jsshell-win32.zip'], 08:06:21 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:06:21 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 08:06:21 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 08:06:21 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:06:21 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 08:06:21 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:06:21 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 08:06:21 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:06:21 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 08:06:21 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 08:06:21 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:06:21 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 08:06:21 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 08:06:21 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')]} 08:06:21 INFO - https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 08:06:21 INFO - trying https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 08:06:21 INFO - Downloading https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/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 08:06:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/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 08:06:28 INFO - Downloaded 18005407 bytes. 08:06:28 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 08:06:28 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 08:06:28 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 08:07:01 INFO - Return code: 0 08:07:01 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 08:07: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')]} 08:07:01 INFO - https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 08:07:01 INFO - trying https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 08:07:01 INFO - Downloading https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/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 08:07:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/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 08:07:02 INFO - Downloaded 18005407 bytes. 08:07:02 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 08:07:02 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 08:07:02 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 08:07:27 INFO - Return code: 0 08:07: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')]} 08:07:27 INFO - https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 08:07:27 INFO - trying https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 08:07:27 INFO - Downloading https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/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 08:07:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/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 08:07:29 INFO - Downloaded 11131307 bytes. 08:07:29 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 08:07:29 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.talos.tests.zip 08:07:29 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 08:07:33 INFO - Return code: 0 08:07:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:07:33 INFO - https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 08:07:33 INFO - trying https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 08:07:33 INFO - Downloading https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.zip 08:07:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/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 08:07:36 INFO - Downloaded 51470673 bytes. 08:07:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 08:07:36 INFO - mkdir: C:\slave\test\properties 08:07:36 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 08:07:36 INFO - Writing to file C:\slave\test\properties\build_url 08:07:36 INFO - Contents: 08:07:36 INFO - build_url:https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 08:07:36 INFO - Running post-action listener: _resource_record_post_action 08:07:36 INFO - Running post-action listener: set_extra_try_arguments 08:07:36 INFO - ##### 08:07:36 INFO - ##### Running populate-webroot step. 08:07:36 INFO - ##### 08:07:36 INFO - Running pre-action listener: _resource_record_pre_action 08:07:36 INFO - Running main action method: populate_webroot 08:07:36 INFO - Guessing talos json url... 08:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:07:36 INFO - https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 08:07:36 INFO - trying https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 08:07:36 INFO - Downloading https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 08:07:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/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 08:07:37 INFO - Downloaded 111 bytes. 08:07:37 INFO - Reading from file C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 08:07:37 INFO - Contents: 08:07:37 INFO - 20151022062458 08:07:37 INFO - https://hg.mozilla.org/integration/mozilla-inbound/rev/01c1f46069567aa2477b4fe70b24fbf14c6f3459 08:07: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')]} 08:07:37 INFO - trying https://hg.mozilla.org/integration/mozilla-inbound/raw-file/01c1f46069/testing/talos/talos.json 08:07:37 INFO - Downloading https://hg.mozilla.org/integration/mozilla-inbound/raw-file/01c1f46069/testing/talos/talos.json to C:\slave\test\build\talos.json 08:07:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/mozilla-inbound/raw-file/01c1f46069/testing/talos/talos.json', 'file_name': 'C:\\slave\\test\\build\\talos.json'}, attempt #1 08:07:37 INFO - Downloaded 4264 bytes. 08:07:37 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 08:07:37 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 08:07:37 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 08:07:37 INFO - u'../fennec_ids.txt'], 08:07:37 INFO - u'tests': [u'tcheck2']}, 08:07:37 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 08:07:37 INFO - u'--tppagecycles', 08:07:37 INFO - u'7'], 08:07:37 INFO - u'tests': [u'tsvgm']}}, 08:07:37 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 08:07:37 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 08:07:37 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 08:07:37 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 08:07:37 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 08:07:37 INFO - u'tests': [u'dromaeo_css', 08:07:37 INFO - u'kraken', 08:07:37 INFO - u'v8_7']}, 08:07:37 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:37 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 08:07:37 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:37 INFO - u'talos_options': [u'--e10s'], 08:07:37 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 08:07:37 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:37 INFO - u'tests': [u'damp', u'tps']}, 08:07:37 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:37 INFO - u'talos_options': [u'--e10s'], 08:07:37 INFO - u'tests': [u'damp', u'tps']}, 08:07:37 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 08:07:37 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 08:07:37 INFO - u'other': {u'tests': [u'a11yr', 08:07:37 INFO - u'ts_paint', 08:07:37 INFO - u'tpaint', 08:07:37 INFO - u'sessionrestore', 08:07:37 INFO - u'sessionrestore_no_auto_restore']}, 08:07:37 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 08:07:37 INFO - u'tests': [u'a11yr', 08:07:37 INFO - u'ts_paint', 08:07:37 INFO - u'tpaint', 08:07:37 INFO - u'sessionrestore', 08:07:37 INFO - u'sessionrestore_no_auto_restore']}, 08:07:37 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 08:07:37 INFO - u'tests': [u'a11yr', 08:07:37 INFO - u'ts_paint', 08:07:37 INFO - u'tpaint', 08:07:37 INFO - u'sessionrestore', 08:07:37 INFO - u'sessionrestore_no_auto_restore']}, 08:07:37 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 08:07:37 INFO - u'tests': [u'a11yr', 08:07:37 INFO - u'ts_paint', 08:07:37 INFO - u'tpaint', 08:07:37 INFO - u'sessionrestore', 08:07:37 INFO - u'sessionrestore_no_auto_restore']}, 08:07:37 INFO - u'other_l64': {u'tests': [u'a11yr', 08:07:37 INFO - u'ts_paint', 08:07:37 INFO - u'tpaint', 08:07:37 INFO - u'sessionrestore', 08:07:37 INFO - u'sessionrestore_no_auto_restore']}, 08:07:37 INFO - u'other_nol64': {u'tests': [u'a11yr', 08:07:37 INFO - u'ts_paint', 08:07:37 INFO - u'tpaint', 08:07:37 INFO - u'sessionrestore', 08:07:37 INFO - u'sessionrestore_no_auto_restore']}, 08:07:37 INFO - u'svgr': {u'tests': [u'tsvgx', 08:07:37 INFO - u'tsvgr_opacity', 08:07:37 INFO - u'tart', 08:07:37 INFO - u'tscrollx', 08:07:37 INFO - u'cart']}, 08:07:37 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 08:07:37 INFO - u'tests': [u'tsvgx', 08:07:37 INFO - u'tsvgr_opacity', 08:07:37 INFO - u'tart', 08:07:37 INFO - u'tscrollx', 08:07:37 INFO - u'cart']}, 08:07:37 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:37 INFO - u'tests': [u'tp5o']}, 08:07:37 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:37 INFO - u'talos_options': [u'--e10s'], 08:07:37 INFO - u'tests': [u'tp5o']}, 08:07:37 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:37 INFO - u'talos_options': [u'--xperf_path', 08:07:37 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:07:37 INFO - u'tests': [u'tp5n']}, 08:07:37 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:07:37 INFO - u'talos_options': [u'--e10s', 08:07:37 INFO - u'--xperf_path', 08:07:37 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 08:07:37 INFO - u'C:/slave/talos-data/talos/xperf.config'], 08:07:37 INFO - u'tests': [u'tp5n']}}, 08:07:37 INFO - u'talos.zip': {u'path': u'', 08:07:37 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:07:37 INFO - Running post-action listener: _resource_record_post_action 08:07:37 INFO - ##### 08:07:37 INFO - ##### Running create-virtualenv step. 08:07:37 INFO - ##### 08:07:37 INFO - Running pre-action listener: _resource_record_pre_action 08:07:37 INFO - Running main action method: create_virtualenv 08:07:37 INFO - Creating virtualenv C:\slave\test\build/venv 08:07:37 INFO - mkdir: C:\slave\test\build\venv\Scripts 08:07:37 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 08:07:37 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'! 08:07:37 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 08:07:37 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 08:07:42 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 08:07:44 INFO - Installing distribute......................................................................................................................................................................................done. 08:07:46 INFO - Return code: 0 08:07:46 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 08:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:46 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 0x01976B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01937EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A00660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-056', 'USERDOMAIN': 'T-W732-IX-056', '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-056', '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 08:07:46 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 08:07:46 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 08:07:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:07:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:07:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:07:46 INFO - 'COMPUTERNAME': 'T-W732-IX-056', 08:07:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:07:46 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:07:46 INFO - 'DCLOCATION': 'SCL3', 08:07:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:07:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:07:46 INFO - 'HOMEDRIVE': 'C:', 08:07:46 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:07:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:07:46 INFO - 'KTS_VERSION': '1.19c', 08:07:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:07:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-056', 08:07:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:07:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:46 INFO - 'MOZ_NO_REMOTE': '1', 08:07:46 INFO - 'NO_EM_RESTART': '1', 08:07:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:07:46 INFO - 'OS': 'Windows_NT', 08:07:46 INFO - 'OURDRIVE': 'C:', 08:07:46 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;', 08:07:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:07:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:07:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:07:46 INFO - 'PROCESSOR_LEVEL': '6', 08:07:46 INFO - 'PROCESSOR_REVISION': '1e05', 08:07:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:07:46 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:07:46 INFO - 'PROMPT': '$P$G', 08:07:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:07:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:07:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:07:46 INFO - 'PWD': 'C:\\slave\\test', 08:07:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:07:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:07:46 INFO - 'SYSTEMDRIVE': 'C:', 08:07:46 INFO - 'SYSTEMROOT': 'C:\\windows', 08:07:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:07:46 INFO - 'TEST1': 'testie', 08:07:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:07:46 INFO - 'USERDOMAIN': 'T-W732-IX-056', 08:07:46 INFO - 'USERNAME': 'cltbld', 08:07:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:07:46 INFO - 'WINDIR': 'C:\\windows', 08:07:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:07:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:07:47 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:07:47 INFO - Downloading/unpacking pip>=1.5 08:07:47 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache 08:07:51 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 08:07:51 INFO - Running setup.py egg_info for package pip 08:07:51 INFO - warning: no files found matching 'pip\cacert.pem' 08:07:51 INFO - warning: no files found matching '*.html' under directory 'docs' 08:07:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 08:07:51 INFO - no previously-included directories found matching 'docs\_build\_sources' 08:07:51 INFO - Installing collected packages: pip 08:07:51 INFO - Found existing installation: pip 0.8.2 08:07:51 INFO - Uninstalling pip: 08:07:51 INFO - Successfully uninstalled pip 08:07:51 INFO - Running setup.py install for pip 08:07:51 INFO - warning: no files found matching 'pip\cacert.pem' 08:07:51 INFO - warning: no files found matching '*.html' under directory 'docs' 08:07:51 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 08:07:51 INFO - no previously-included directories found matching 'docs\_build\_sources' 08:07:51 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 08:07:51 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 08:07:51 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 08:07:51 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 08:07:51 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 08:07:51 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 08:07:51 INFO - Successfully installed pip 08:07:51 INFO - Cleaning up... 08:07:51 INFO - Return code: 0 08:07:51 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv 08:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:07:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:07:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:51 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 0x01976B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01937EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A00660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-056', 'USERDOMAIN': 'T-W732-IX-056', '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-056', '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 08:07:51 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 08:07:51 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 08:07:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:07:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:07:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:07:51 INFO - 'COMPUTERNAME': 'T-W732-IX-056', 08:07:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:07:51 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:07:51 INFO - 'DCLOCATION': 'SCL3', 08:07:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:07:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:07:51 INFO - 'HOMEDRIVE': 'C:', 08:07:51 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:07:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:07:51 INFO - 'KTS_VERSION': '1.19c', 08:07:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:07:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-056', 08:07:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:07:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:07:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:51 INFO - 'MOZ_NO_REMOTE': '1', 08:07:51 INFO - 'NO_EM_RESTART': '1', 08:07:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:07:51 INFO - 'OS': 'Windows_NT', 08:07:51 INFO - 'OURDRIVE': 'C:', 08:07:51 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:07:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:07:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:07:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:07:51 INFO - 'PROCESSOR_LEVEL': '6', 08:07:51 INFO - 'PROCESSOR_REVISION': '1e05', 08:07:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:07:51 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:07:51 INFO - 'PROMPT': '$P$G', 08:07:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:07:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:07:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:07:51 INFO - 'PWD': 'C:\\slave\\test', 08:07:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:07:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:07:51 INFO - 'SYSTEMDRIVE': 'C:', 08:07:51 INFO - 'SYSTEMROOT': 'C:\\windows', 08:07:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:07:51 INFO - 'TEST1': 'testie', 08:07:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:07:51 INFO - 'USERDOMAIN': 'T-W732-IX-056', 08:07:51 INFO - 'USERNAME': 'cltbld', 08:07:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:07:51 INFO - 'WINDIR': 'C:\\windows', 08:07:51 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:07:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:07:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:07:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:53 INFO - Downloading/unpacking psutil>=0.7.1 08:07:53 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 08:07:53 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 08:07:53 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 08:07:53 INFO - Installing collected packages: psutil 08:07:53 INFO - Successfully installed psutil 08:07:53 INFO - Cleaning up... 08:07:54 INFO - Return code: 0 08:07:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv 08:07: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')]} 08:07:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07: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')]} 08:07:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07: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', '--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 0x01976B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01937EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A00660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-056', 'USERDOMAIN': 'T-W732-IX-056', '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-056', '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 08:07:54 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 08:07:54 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 08:07:54 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:07:54 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:07:54 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:07:54 INFO - 'COMPUTERNAME': 'T-W732-IX-056', 08:07:54 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:07:54 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:07:54 INFO - 'DCLOCATION': 'SCL3', 08:07:54 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:07:54 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:07:54 INFO - 'HOMEDRIVE': 'C:', 08:07:54 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:07:54 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:07:54 INFO - 'KTS_VERSION': '1.19c', 08:07:54 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:07:54 INFO - 'LOGONSERVER': '\\\\T-W732-IX-056', 08:07:54 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:07:54 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:54 INFO - 'MOZ_NO_REMOTE': '1', 08:07:54 INFO - 'NO_EM_RESTART': '1', 08:07:54 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:07:54 INFO - 'OS': 'Windows_NT', 08:07:54 INFO - 'OURDRIVE': 'C:', 08:07: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;', 08:07:54 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:07:54 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:07:54 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:07:54 INFO - 'PROCESSOR_LEVEL': '6', 08:07:54 INFO - 'PROCESSOR_REVISION': '1e05', 08:07:54 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:07:54 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:07:54 INFO - 'PROMPT': '$P$G', 08:07:54 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:07:54 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:07:54 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:07:54 INFO - 'PWD': 'C:\\slave\\test', 08:07:54 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:07:54 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:07:54 INFO - 'SYSTEMDRIVE': 'C:', 08:07:54 INFO - 'SYSTEMROOT': 'C:\\windows', 08:07:54 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:07:54 INFO - 'TEST1': 'testie', 08:07:54 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:07:54 INFO - 'USERDOMAIN': 'T-W732-IX-056', 08:07:54 INFO - 'USERNAME': 'cltbld', 08:07:54 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:07:54 INFO - 'WINDIR': 'C:\\windows', 08:07:54 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:07:54 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:07:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:07:54 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 08:07:54 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 08:07:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:07:55 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 08:07:55 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 08:07:55 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) 08:07:55 INFO - Installing collected packages: mozsystemmonitor 08:07:55 INFO - Running setup.py install for mozsystemmonitor 08:07:55 INFO - Successfully installed mozsystemmonitor 08:07:55 INFO - Cleaning up... 08:07:55 INFO - Return code: 0 08:07:55 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 08:07:55 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')]} 08:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:55 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')]} 08:07:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:55 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 0x01976B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01937EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A00660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-056', 'USERDOMAIN': 'T-W732-IX-056', '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-056', '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 08:07:55 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 08:07:55 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 08:07:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:07:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:07:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:07:55 INFO - 'COMPUTERNAME': 'T-W732-IX-056', 08:07:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:07:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:07:55 INFO - 'DCLOCATION': 'SCL3', 08:07:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:07:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:07:55 INFO - 'HOMEDRIVE': 'C:', 08:07:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:07:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:07:55 INFO - 'KTS_VERSION': '1.19c', 08:07:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:07:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-056', 08:07:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:07:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:07:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:07:55 INFO - 'MOZ_NO_REMOTE': '1', 08:07:55 INFO - 'NO_EM_RESTART': '1', 08:07:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:07:55 INFO - 'OS': 'Windows_NT', 08:07:55 INFO - 'OURDRIVE': 'C:', 08:07:55 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;', 08:07:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:07:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:07:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:07:55 INFO - 'PROCESSOR_LEVEL': '6', 08:07:55 INFO - 'PROCESSOR_REVISION': '1e05', 08:07:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:07:55 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:07:55 INFO - 'PROMPT': '$P$G', 08:07:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:07:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:07:55 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:07:55 INFO - 'PWD': 'C:\\slave\\test', 08:07:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:07:55 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:07:55 INFO - 'SYSTEMDRIVE': 'C:', 08:07:55 INFO - 'SYSTEMROOT': 'C:\\windows', 08:07:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:07:55 INFO - 'TEST1': 'testie', 08:07:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:07:55 INFO - 'USERDOMAIN': 'T-W732-IX-056', 08:07:55 INFO - 'USERNAME': 'cltbld', 08:07:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:07:55 INFO - 'WINDIR': 'C:\\windows', 08:07:55 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:07:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:07:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:07:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:07:56 INFO - Downloading/unpacking blobuploader==1.2.4 08:07:56 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 08:07:56 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 08:07:57 INFO - Downloading blobuploader-1.2.4.tar.gz 08:07:57 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 08:07:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 08:07:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:07: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 08:07: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 08:07:57 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 08:07:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 08:07:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:07: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 08:07: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 08:07:57 INFO - Downloading docopt-0.6.1.tar.gz 08:07:57 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 08:07:57 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 08:07:57 INFO - Installing collected packages: blobuploader, requests, docopt 08:07:57 INFO - Running setup.py install for blobuploader 08:07:58 INFO - Running setup.py install for requests 08:07:58 INFO - Running setup.py install for docopt 08:07:58 INFO - Successfully installed blobuploader requests docopt 08:07:58 INFO - Cleaning up... 08:07:59 INFO - Return code: 0 08:07:59 INFO - Installing None into virtualenv C:\slave\test\build/venv 08:07:59 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')]} 08:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:07:59 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')]} 08:07:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:07:59 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 0x01976B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01937EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A00660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-056', 'USERDOMAIN': 'T-W732-IX-056', '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-056', '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 08:08:00 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 08:08:00 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 08:08:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:08:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:08:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:08:00 INFO - 'COMPUTERNAME': 'T-W732-IX-056', 08:08:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:08:00 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:08:00 INFO - 'DCLOCATION': 'SCL3', 08:08:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:08:00 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:08:00 INFO - 'HOMEDRIVE': 'C:', 08:08:00 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:08:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:08:00 INFO - 'KTS_VERSION': '1.19c', 08:08:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:08:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-056', 08:08:00 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:08:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:00 INFO - 'MOZ_NO_REMOTE': '1', 08:08:00 INFO - 'NO_EM_RESTART': '1', 08:08:00 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:08:00 INFO - 'OS': 'Windows_NT', 08:08:00 INFO - 'OURDRIVE': 'C:', 08:08:00 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:08:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:08:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:08:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:08:00 INFO - 'PROCESSOR_LEVEL': '6', 08:08:00 INFO - 'PROCESSOR_REVISION': '1e05', 08:08:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:08:00 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:08:00 INFO - 'PROMPT': '$P$G', 08:08:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:08:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:08:00 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:08:00 INFO - 'PWD': 'C:\\slave\\test', 08:08:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:08:00 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:08:00 INFO - 'SYSTEMDRIVE': 'C:', 08:08:00 INFO - 'SYSTEMROOT': 'C:\\windows', 08:08:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:08:00 INFO - 'TEST1': 'testie', 08:08:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:08:00 INFO - 'USERDOMAIN': 'T-W732-IX-056', 08:08:00 INFO - 'USERNAME': 'cltbld', 08:08:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:08:00 INFO - 'WINDIR': 'C:\\windows', 08:08:00 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:08:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:08:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:00 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 08:08:00 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-znrzdf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 08:08:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 08:08:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pbhjfb-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 08:08:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 08:08:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ce1k41-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 08:08:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 08:08:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-japu7x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 08:08:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 08:08:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hk0ql7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 08:08:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 08:08:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gogwhp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 08:08:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 08:08:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-g0v1r4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 08:08:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 08:08:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tqmwr8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 08:08:01 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 08:08:01 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-va4q_y-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 08:08:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 08:08:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dplzak-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 08:08:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 08:08:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i72ye1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 08:08:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 08:08:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kebrad-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 08:08:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 08:08:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h1ov4e-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 08:08:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 08:08:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xbwr0i-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 08:08:02 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 08:08:02 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8ybfyk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 08:08:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 08:08:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dy2lcl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 08:08:03 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 08:08:03 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_xmcpu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 08:08:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:08:03 INFO - Running setup.py install for manifestparser 08:08:03 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 08:08:03 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 08:08:03 INFO - Running setup.py install for mozcrash 08:08:04 INFO - Running setup.py install for mozdebug 08:08:04 INFO - Running setup.py install for mozdevice 08:08:04 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 08:08:04 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 08:08:04 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 08:08:04 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 08:08:05 INFO - Running setup.py install for mozfile 08:08:05 INFO - Running setup.py install for mozhttpd 08:08:05 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 08:08:05 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 08:08:06 INFO - Running setup.py install for mozinfo 08:08:06 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 08:08:06 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 08:08:07 INFO - Running setup.py install for mozInstall 08:08:07 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 08:08:07 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 08:08:07 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 08:08:07 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 08:08:07 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 08:08:07 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 08:08:07 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 08:08:07 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 08:08:07 INFO - Running setup.py install for mozleak 08:08:07 INFO - Running setup.py install for mozlog 08:08:08 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 08:08:08 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 08:08:08 INFO - Running setup.py install for moznetwork 08:08:08 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 08:08:08 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 08:08:09 INFO - Running setup.py install for mozprocess 08:08:09 INFO - Running setup.py install for mozprofile 08:08:09 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 08:08:09 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 08:08:09 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 08:08:09 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 08:08:09 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 08:08:09 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 08:08:10 INFO - Running setup.py install for mozrunner 08:08:10 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 08:08:10 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 08:08:10 INFO - Running setup.py install for mozscreenshot 08:08:11 INFO - Running setup.py install for moztest 08:08:11 INFO - Running setup.py install for mozversion 08:08:11 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 08:08:11 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 08:08:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:08:12 INFO - Cleaning up... 08:08:12 INFO - Return code: 0 08:08:12 INFO - Installing None into virtualenv C:\slave\test\build/venv 08:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:08:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:08:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:12 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 0x01976B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01937EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A00660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-056', 'USERDOMAIN': 'T-W732-IX-056', '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-056', '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 08:08:12 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 08:08:12 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 08:08:12 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:08:12 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:08:12 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:08:12 INFO - 'COMPUTERNAME': 'T-W732-IX-056', 08:08:12 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:08:12 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:08:12 INFO - 'DCLOCATION': 'SCL3', 08:08:12 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:08:12 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:08:12 INFO - 'HOMEDRIVE': 'C:', 08:08:12 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:08:12 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:08:12 INFO - 'KTS_VERSION': '1.19c', 08:08:12 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:08:12 INFO - 'LOGONSERVER': '\\\\T-W732-IX-056', 08:08:12 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:08:12 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:08:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:12 INFO - 'MOZ_NO_REMOTE': '1', 08:08:12 INFO - 'NO_EM_RESTART': '1', 08:08:12 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:08:12 INFO - 'OS': 'Windows_NT', 08:08:12 INFO - 'OURDRIVE': 'C:', 08:08:12 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:08:12 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:08:12 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:08:12 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:08:12 INFO - 'PROCESSOR_LEVEL': '6', 08:08:12 INFO - 'PROCESSOR_REVISION': '1e05', 08:08:12 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:08:12 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:08:12 INFO - 'PROMPT': '$P$G', 08:08:12 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:08:12 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:08:12 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:08:12 INFO - 'PWD': 'C:\\slave\\test', 08:08:12 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:08:12 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:08:12 INFO - 'SYSTEMDRIVE': 'C:', 08:08:12 INFO - 'SYSTEMROOT': 'C:\\windows', 08:08:12 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:08:12 INFO - 'TEST1': 'testie', 08:08:12 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:08:12 INFO - 'USERDOMAIN': 'T-W732-IX-056', 08:08:12 INFO - 'USERNAME': 'cltbld', 08:08:12 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:08:12 INFO - 'WINDIR': 'C:\\windows', 08:08:12 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:08:12 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:08:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:08:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 08:08:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-un7ijj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 08:08:12 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)) 08:08:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 08:08:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rswmyi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 08:08:12 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)) 08:08:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 08:08:12 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ls0qbm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 08:08:12 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)) 08:08:12 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 08:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bftk94-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 08:08:13 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)) 08:08:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 08:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wpfxrj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 08:08:13 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)) 08:08:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 08:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zqqv0p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 08:08:13 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)) 08:08:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 08:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gqzp0f-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 08:08:13 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)) 08:08:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 08:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zy4apd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 08:08:13 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)) 08:08:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 08:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4tmtkx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 08:08:13 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)) 08:08:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 08:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kihnyn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 08:08:13 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)) 08:08:13 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 08:08:13 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cw2tgg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 08:08:14 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)) 08:08:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 08:08:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5gbes1-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 08:08:14 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)) 08:08:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 08:08:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ikrfgt-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 08:08:14 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)) 08:08:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 08:08:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0jpbo_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 08:08:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 08:08:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 08:08:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rvhegx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 08:08:14 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)) 08:08:14 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 08:08:14 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-1wu5ci-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 08:08:15 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)) 08:08:15 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 08:08:15 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ez8avr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 08:08:15 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)) 08:08:15 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)) 08:08:15 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)) 08:08:15 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)) 08:08:15 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)) 08:08:15 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)) 08:08:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 08:08:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:16 INFO - Downloading blessings-1.5.1.tar.gz 08:08:16 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 08:08:16 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 08:08:16 INFO - Installing collected packages: blessings 08:08:16 INFO - Running setup.py install for blessings 08:08:16 INFO - Successfully installed blessings 08:08:16 INFO - Cleaning up... 08:08:19 INFO - Return code: 0 08:08:19 INFO - Done creating virtualenv C:\slave\test\build/venv. 08:08:19 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 08:08:19 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 08:08:19 INFO - Reading from file tmpfile_stdout 08:08:19 INFO - Using _rmtree_windows ... 08:08:19 INFO - Using _rmtree_windows ... 08:08:19 INFO - Current package versions: 08:08:19 INFO - blessings == 1.5.1 08:08:19 INFO - blobuploader == 1.2.4 08:08:19 INFO - docopt == 0.6.1 08:08:19 INFO - manifestparser == 1.1 08:08:19 INFO - mozInstall == 1.12 08:08:19 INFO - mozcrash == 0.16 08:08:19 INFO - mozdebug == 0.1 08:08:19 INFO - mozdevice == 0.46 08:08:19 INFO - mozfile == 1.2 08:08:19 INFO - mozhttpd == 0.7 08:08:19 INFO - mozinfo == 0.8 08:08:19 INFO - mozleak == 0.1 08:08:19 INFO - mozlog == 3.0 08:08:19 INFO - moznetwork == 0.27 08:08:19 INFO - mozprocess == 0.22 08:08:19 INFO - mozprofile == 0.27 08:08:19 INFO - mozrunner == 6.10 08:08:19 INFO - mozscreenshot == 0.1 08:08:19 INFO - mozsystemmonitor == 0.0 08:08:19 INFO - moztest == 0.7 08:08:19 INFO - mozversion == 1.4 08:08:19 INFO - psutil == 3.1.1 08:08:19 INFO - requests == 1.2.3 08:08:19 INFO - Installing None into virtualenv C:\slave\test\build/venv 08:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:08:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:08:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:19 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 0x01976B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01937EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0187B5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A00660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-056', 'USERDOMAIN': 'T-W732-IX-056', '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-056', '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 08:08:19 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 08:08:19 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 08:08:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:08:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:08:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:08:19 INFO - 'COMPUTERNAME': 'T-W732-IX-056', 08:08:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:08:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:08:19 INFO - 'DCLOCATION': 'SCL3', 08:08:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:08:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:08:19 INFO - 'HOMEDRIVE': 'C:', 08:08:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:08:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:08:19 INFO - 'KTS_VERSION': '1.19c', 08:08:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:08:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-056', 08:08:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:08:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:08:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:19 INFO - 'MOZ_NO_REMOTE': '1', 08:08:19 INFO - 'NO_EM_RESTART': '1', 08:08:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:08:19 INFO - 'OS': 'Windows_NT', 08:08:19 INFO - 'OURDRIVE': 'C:', 08:08:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:08:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:08:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:08:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:08:19 INFO - 'PROCESSOR_LEVEL': '6', 08:08:19 INFO - 'PROCESSOR_REVISION': '1e05', 08:08:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:08:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:08:19 INFO - 'PROMPT': '$P$G', 08:08:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:08:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:08:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:08:19 INFO - 'PWD': 'C:\\slave\\test', 08:08:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:08:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:08:19 INFO - 'SYSTEMDRIVE': 'C:', 08:08:19 INFO - 'SYSTEMROOT': 'C:\\windows', 08:08:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:08:19 INFO - 'TEST1': 'testie', 08:08:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:08:19 INFO - 'USERDOMAIN': 'T-W732-IX-056', 08:08:19 INFO - 'USERNAME': 'cltbld', 08:08:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:08:19 INFO - 'WINDIR': 'C:\\windows', 08:08:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:08:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:08:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:08:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:20 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)) 08:08:20 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)) 08:08:20 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)) 08:08:20 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)) 08:08:20 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)) 08:08:20 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)) 08:08:20 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)) 08:08:20 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)) 08:08:20 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)) 08:08:20 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)) 08:08:20 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)) 08:08:20 INFO - Cleaning up... 08:08:20 INFO - Return code: 0 08:08:20 INFO - Running post-action listener: _resource_record_post_action 08:08:20 INFO - Running post-action listener: _start_resource_monitoring 08:08:20 INFO - Starting resource monitoring. 08:08:20 INFO - ##### 08:08:20 INFO - ##### Running install step. 08:08:20 INFO - ##### 08:08:20 INFO - Running pre-action listener: _resource_record_pre_action 08:08:20 INFO - Running main action method: install 08:08:20 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 08:08:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 08:08:20 INFO - Reading from file tmpfile_stdout 08:08:20 INFO - Using _rmtree_windows ... 08:08:20 INFO - Using _rmtree_windows ... 08:08:20 INFO - Detecting whether we're running mozinstall >=1.0... 08:08:20 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 08:08:20 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 08:08:21 INFO - Reading from file tmpfile_stdout 08:08:21 INFO - Output received: 08:08:21 INFO - Usage: mozinstall-script.py [options] installer 08:08:21 INFO - Options: 08:08:21 INFO - -h, --help show this help message and exit 08:08:21 INFO - -d DEST, --destination=DEST 08:08:21 INFO - Directory to install application into. [default: 08:08:21 INFO - "C:\slave\test"] 08:08:21 INFO - --app=APP Application being installed. [default: firefox] 08:08:21 INFO - Using _rmtree_windows ... 08:08:21 INFO - Using _rmtree_windows ... 08:08:21 INFO - mkdir: C:\slave\test\build\application 08:08:21 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'] 08:08:21 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 08:08:22 INFO - Reading from file tmpfile_stdout 08:08:22 INFO - Output received: 08:08:22 INFO - C:\slave\test\build\application\firefox\firefox.exe 08:08:22 INFO - Using _rmtree_windows ... 08:08:22 INFO - Using _rmtree_windows ... 08:08:22 INFO - Running post-action listener: _resource_record_post_action 08:08:22 INFO - ##### 08:08:22 INFO - ##### Running run-tests step. 08:08:22 INFO - ##### 08:08:22 INFO - Running pre-action listener: _resource_record_pre_action 08:08:22 INFO - Running main action method: run_tests 08:08:22 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 08:08:22 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 08:08:22 INFO - Python 2.7.3 08:08:22 INFO - Return code: 0 08:08:22 INFO - grabbing minidump binary from tooltool 08:08:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:08:22 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 0x01338B48>, '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 0x0135A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A00660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 08:08:22 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 08:08:22 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 08:08:22 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 08:08:22 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp9fch1k 08:08:22 INFO - INFO - File integrity verified, renaming tmp9fch1k to win32-minidump_stackwalk.exe 08:08:22 INFO - Return code: 0 08:08:22 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 08:08:22 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 08:08:22 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 08:08:22 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 08:08:22 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 08:08:22 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-056', '--webServer', 'localhost'] in C:\slave\test\build 08:08:22 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --debug --branchName Mozilla-Inbound-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-056 --webServer localhost 08:08:22 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:08:22 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:08:22 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:08:22 INFO - 'COMPUTERNAME': 'T-W732-IX-056', 08:08:22 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:08:22 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:08:22 INFO - 'DCLOCATION': 'SCL3', 08:08:22 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:08:22 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:08:22 INFO - 'HOMEDRIVE': 'C:', 08:08:22 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:08:22 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:08:22 INFO - 'KTS_VERSION': '1.19c', 08:08:22 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:08:22 INFO - 'LOGONSERVER': '\\\\T-W732-IX-056', 08:08:22 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 08:08:22 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 08:08:22 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:08:22 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:08:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:22 INFO - 'MOZ_NO_REMOTE': '1', 08:08:22 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 08:08:22 INFO - 'NO_EM_RESTART': '1', 08:08:22 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:08:22 INFO - 'OS': 'Windows_NT', 08:08:22 INFO - 'OURDRIVE': 'C:', 08:08:22 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:08:22 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:08:22 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:08:22 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:08:22 INFO - 'PROCESSOR_LEVEL': '6', 08:08:22 INFO - 'PROCESSOR_REVISION': '1e05', 08:08:22 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:08:22 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:08:22 INFO - 'PROMPT': '$P$G', 08:08:22 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:08:22 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:08:22 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:08:22 INFO - 'PWD': 'C:\\slave\\test', 08:08:22 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 08:08:22 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:08:22 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:08:22 INFO - 'SYSTEMDRIVE': 'C:', 08:08:22 INFO - 'SYSTEMROOT': 'C:\\windows', 08:08:22 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:08:22 INFO - 'TEST1': 'testie', 08:08:22 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:08:22 INFO - 'USERDOMAIN': 'T-W732-IX-056', 08:08:22 INFO - 'USERNAME': 'cltbld', 08:08:22 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:08:22 INFO - 'WINDIR': 'C:\\windows', 08:08:22 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:08:22 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:08:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:08:22 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-056', '--webServer', 'localhost'] with output_timeout 3600 08:08:24 INFO - mozversion INFO | application_buildid: 20151022062458 08:08:24 INFO - mozversion INFO | application_changeset: 01c1f46069567aa2477b4fe70b24fbf14c6f3459 08:08:24 INFO - mozversion INFO | application_display_name: Nightly 08:08:24 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:08:24 INFO - mozversion INFO | application_name: Firefox 08:08:24 INFO - mozversion INFO | application_remotingname: firefox 08:08:24 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound 08:08:24 INFO - mozversion INFO | application_vendor: Mozilla 08:08:24 INFO - mozversion INFO | application_version: 44.0a1 08:08:24 INFO - mozversion INFO | platform_buildid: 20151022062458 08:08:24 INFO - mozversion INFO | platform_changeset: 01c1f46069567aa2477b4fe70b24fbf14c6f3459 08:08:24 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 08:08:24 INFO - mozversion INFO | platform_version: 44.0a1 08:08:24 INFO - 2015-10-22 08:08:24,286 DEBUG : using testdate: 1445526504 08:08:24 INFO - 2015-10-22 08:08:24,286 DEBUG : actual date: 1445526504 08:08:24 INFO - 2015-10-22 08:08:24,301 INFO : starting webserver on 'localhost:49891' 08:08:24 INFO - 2015-10-22 08:08:24,302 INFO : Starting test suite T-W732-IX-056 08:08:24 INFO - 2015-10-22 08:08:24,302 INFO : Starting test dromaeo_css 08:08:24 INFO - 2015-10-22 08:08:24,302 DEBUG : operating with platform_type : w7_ 08:08:24 INFO - 2015-10-22 08:08:24,303 INFO : Initialising browser for dromaeo_css test... 08:08:24 INFO - 2015-10-22 08:08:24,505 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmsljod\profile http://localhost:49891/getInfo.html 08:08:32 INFO - 2015-10-22 08:08:32,592 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:08:32 INFO - 2015-10-22 08:08:32,592 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:08:32 INFO - 2015-10-22 08:08:32,592 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 08:08:32 INFO - 2015-10-22 08:08:32,592 DEBUG : BROWSER_OUTPUT: __metrics 08:08:32 INFO - 2015-10-22 08:08:32,667 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:08:34 INFO - 2015-10-22 08:08:34,348 INFO : Browser initialized. 08:08:34 INFO - 2015-10-22 08:08:34,348 INFO : Running cycle 1/1 for dromaeo_css test... 08:08:34 INFO - 2015-10-22 08:08:34,348 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmsljod\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:08:46 INFO - 2015-10-22 08:08:46,003 DEBUG : BROWSER_OUTPUT: RSS: Main: 141725696 08:08:46 INFO - 2015-10-22 08:08:46,003 DEBUG : BROWSER_OUTPUT: 08:09:36 INFO - 2015-10-22 08:09:36,986 DEBUG : BROWSER_OUTPUT: 1445526576991 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] 08:11:50 INFO - 2015-10-22 08:11:50,233 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49891/tests/dromaeo/cssquery-ext.html) 08:11:51 INFO - 2015-10-22 08:11:51,428 DEBUG : BROWSER_OUTPUT: RSS: Main: 203898880 08:11:51 INFO - 2015-10-22 08:11:51,428 DEBUG : BROWSER_OUTPUT: 08:14:54 INFO - 2015-10-22 08:14:54,404 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-ext.html (next: http://localhost:49891/tests/dromaeo/cssquery-jquery.html) 08:14:54 INFO - 2015-10-22 08:14:54,959 DEBUG : BROWSER_OUTPUT: RSS: Main: 196820992 08:14:54 INFO - 2015-10-22 08:14:54,959 DEBUG : BROWSER_OUTPUT: 08:17:57 INFO - 2015-10-22 08:17:57,381 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49891/tests/dromaeo/cssquery-mootools.html) 08:17:57 INFO - 2015-10-22 08:17:57,961 DEBUG : BROWSER_OUTPUT: RSS: Main: 155377664 08:17:57 INFO - 2015-10-22 08:17:57,961 DEBUG : BROWSER_OUTPUT: 08:21:00 INFO - 2015-10-22 08:21:00,157 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49891/tests/dromaeo/cssquery-prototype.html) 08:21:00 INFO - 2015-10-22 08:21:00,744 DEBUG : BROWSER_OUTPUT: RSS: Main: 143208448 08:21:00 INFO - 2015-10-22 08:21:00,744 DEBUG : BROWSER_OUTPUT: 08:24:03 INFO - 2015-10-22 08:24:03,808 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49891/tests/dromaeo/cssquery-yui.html) 08:24:04 INFO - 2015-10-22 08:24:04,375 DEBUG : BROWSER_OUTPUT: RSS: Main: 139649024 08:24:04 INFO - 2015-10-22 08:24:04,375 DEBUG : BROWSER_OUTPUT: 08:27:07 INFO - 2015-10-22 08:27:07,482 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/dromaeo/cssquery-yui.html (next: http://localhost:49891/tests/dromaeo/cssquery-dojo.html) 08:27:07 INFO - 2015-10-22 08:27:07,736 DEBUG : BROWSER_OUTPUT: RSS: Main: 191221760 08:27:07 INFO - 2015-10-22 08:27:07,736 DEBUG : BROWSER_OUTPUT: 08:27:07 INFO - 2015-10-22 08:27:07,736 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:27:07 INFO - 2015-10-22 08:27:07,736 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:27:07 INFO - 2015-10-22 08:27:07,736 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:27:07 INFO - 2015-10-22 08:27:07,736 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:27:07 INFO - 2015-10-22 08:27:07,736 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2314.224734713871;2325.325655559887;2382.440126570256;2393.32986763706;2405.6151015837463;4152.79236038198;4186.958130418695;4192.119654872473;4213.6207741303315;4264.765437900915;4160.979195104021;4173.4157217989505;4199.811008504617;4210.726302790316;4214.473190851144;3568.4468907319406;3632.6912212461907;3653.506776585167;3733.88798336049;3736.906577335561;3508.6842184203415;3550.875719349823;3630.0743310455796;3721.1255354991554;3727.384981478053;3488.982555087221;3513.262214934854;3602;3636.1636823530603;3686.2258925625515;3554.893353199408;3608.224231790178;3697.8520859165737;3727.4222495512968;3765.4163604641326;3550.431930891045;3553.1117220694828;3633.5458067741533;3657.195417008254;3657.908552286214;3980.5422376426504;4118.4440100586335;4130.545639979599;4182.205380977604;4220.85227017053;4377.584129507692;4442.844500442469;4534.433195850519;4540.77296135192;4540.8864778380475;4298.054428025829;4362.389265502832;4391.516933137353;4438.112377524476;4556.840510582147;4154.626083652425;4212.978935105336;4227.217964676544;4241.830326786925;4242.130363275583;4348.2825333819565;4531;4540.841070562515;4622.59011001641;4631.490536041033;4254.191703576311;4292.399064131024;4377.037051848588;4394.8242070317465;4434.135343607965;4179.080602267496;4210.663146948237;4392.165488557164;4476.283794592915;4488.932666010012;4356.237658409766;4381.255186618282;4442.267025940683;4453.665975051884;4457.175422546839;4276.57234276576;4308.892277693083;4422.756748378806;4425.6238219751685;4432.933505997413;3000.969990300113;3085.167004908662;3112.8754849806205;3114.159177022191;3128.5932828732575;2852.914412567626;2893.5225687761704;2893.956590651142;2897.7392034716977;2905.738483536492;4081.5102187737066;4082.775447350424;4161.167766446722;4176.916461670749;4282.700210985201;4442.267025940683;4471.128130014681;4510.616597589242;4564.88587785308;4571.794269257892;4184.727992680466;4211.305134652809;4250.468691413574;4274.187904298174;4285.207236661227;3684.8157592120288;3743.1952130291575;3794.2790869734663;3844.2119365529998;3854.6530812227033;4472.306792447176;4598.21830288845;4664.556867097688;4668.5564871337165;4678.415198100238;4392.231359512137;4462.866114016455;4554.476235232858;4560.270356742905;4562.041971186088;4401.427814384109;4513.909721805479;4573.885652858572;4578.175928332932;4578.908421831611;4324.891877702957;4377.912441751084;4420;4445;4445.533219011957;4402.625776809007;4409.845655402046;4494.865154045384;4495.505494395548;4502.257127573914;4186.058136919169;4211.473565804275;4301.5698430156735;4306.698531102793;4374.21264172452;4079.7964600442347;4083.9795801020805;4280.636145927631;4360.6729495287345;4363.869083927487;4136.717617538573;4155;4180.184863951622;4272.87181384559;4277.037666525007;4227.112306415687;4275.93586096215;4323.459567554056;4332.155229730172;4401.911961760811;4284.592963668447;4317.179735850178;4336.067745434747;4371.191329604034;4391.253486907297 08:27:07 INFO - 2015-10-22 08:27:07,736 DEBUG : BROWSER_OUTPUT: |1;ext.html;475.3060531623828;482.31029627632495;489.8628384052464;513.6558505801113;513.8920826626409;25971.35071623208;36681.44977825335;36810.447843282374;37252.44121338175;37296.81351593245;39621.60378396215;39795.602043979554;40196.39705404414;40246.597534024724;40314.59685403145;6091.969540152293;6213.161223234851;7874.409419293547;7998.080220774598;8185.386096042791;10083.848742268872;10164.745881352952;10343.896561034406;10368.27422080451;10417.687469375931;9687.418754874694;9717.708468745948;9731.854022189673;9743.658971935984;9748;6376.458001069937;6581.670916454158;9893.406395616286;10034.09693127622;10069.546870390814;8978.86531702025;9243.676471323539;9467.905320946771;9636.42181469114;9644.228461723045;26579.867100664567;36679.449808252895;36889.26221475555;37307.06732331713;37338;2262.637977923541;2925.941481170386;2968.2876109733506;3096.2878537936176;3381.780184288038;26393.20820375392;26736;27086.373427836774;27158.86420567904;27282.863585681946;1996.2613832881927;2058.907349169291;2079.5736873940955;2150.7526634437086;2165.6426689596196;208996;209420;210437;210443;211241;45662.77168614202;46780;49784.75107624511;50684.49315506872;51986.22020669693;48675.26987095196;48743;48916.510834891196;48931.26603100956;48978.26532602014;11873.050155987674;12025.518979240907;12047.156699030984;12082.395880205955;12096.758064838654;8423.989121305483;8464.238218560358;8465.492070475791;8470.915290847135;8605.225529702357;16949;16992.915035424743;17086.82913170877;17094.91452542729;17142.742858857127;6797.456203503708;7018.824529386806;7026.683799229047;7028.543144695578;7085.185203701641;772.602109913395;805.2873207211543;819.4059307002376;836.188896770132;869.9304055675531;23654;24931.5013699725;24949.750502495106;25537.48925021489;25693.3576660585;920.9447433154032;923.4597760310294;937.4281688170211;1005.5525291245326;1035.052926572178;13208.933955330354;13335.93332033353;13386.598402047954;13407.463701452029;13619.659508512366;269329;280655;282106;286956;288369;46482.76758616253;46659.30011049837;46843.05502097012;46910.530894690615;46936.53063469322;1136.0343707848263;1145.4101137913815;1152.0725815718426;1156.7108222944264;1162.511745067074;24668.50662986766;24958.251252462454;25154;25365.61951570691;25810.741892580834;39497.210055799296;39537.80231098826;39979.40030899597;40041.59958400379;40354.394684079176;251153;261991;265364;265448;266636;22613.547729045655;26655;26656.200313990612;26658;26674.59988100219;34906.825465872505;35308;35615.28769424545;35651;35722.10694732652;30215;31115.377692446476;32241.677583223867;32499.837500812344;32903.17742056372;22346.77653223447;27163;31809.363812724077;31820.522692159175;32919.50620740739;31021.68978310188;31738;32443;32561.511577326834;32592.51111233286;28954.27614309659;29384.412311753218;29643;30348.696513034585;30393.696063039086;21852.672209916545;22039;22516.211932582282;23058.76941230566;23481.765182347957 08:27:07 INFO - 2015-10-22 08:27:07,740 DEBUG : BROWSER_OUTPUT: |2;jquery.html;307.3453543951383;308.69593450451316;309.0558344258291;311.0947143811509;312.4204600466135;10688.732781680463;14852.554423367315;15437.845621543795;15588.454404095859;15860;15349.462768803094;15657.608559786011;15753.369865205377;15854.20728963553;15867;5069.163588007974;5119.56483698886;5240.580753539708;5251.317328747257;5258.842234732964;3503.4394496880504;3608.1881576645305;5103.26002729604;5187.325647665798;5920.970395148018;5485;5669.546436285108;5675.978323901696;5844.473997340238;5856.209411729405;5718.313802343745;5832.795852145176;5952.464278214959;5968.1644569760265;5982.760689572434;4838.709677419366;4874.8537543873745;4910.361652985125;5111.3355263815465;5119.129747942859;12455.626331209987;19564.80435195644;19662.70505942412;19974.80025199744;20378.89810550938;1959.559099202668;1971.7338159348449;2010.4370776182693;2010.859239853211;2067.989660051705;13224.404901779442;13527.458901643922;13687.31563421825;13909.79135312971;14122.01145919786;1822.9908850455795;1856.6658001559713;1857.8978156201404;1872.6816441205028;1879.8026207248179;59947;60249.192524224345;60998.39001610016;62672.373276267565;64155.358446415405;13589.078348155193;13805.51680691171;14001.719965600834;14120;14199.36102875373;6532.8366790830605;19392.90303548473;20247;20361.694574581394;20375;4491;4569.1547063793305;4573.679842411066;4589.380433641482;4601.263797792404;4777.8327758528285;4778.829186849244;4782.234842425265;4802.951970480321;4815.22956326993;2512.8241023128207;2579.948401031969;2650.787936965038;2652.336915771057;2663.1078588672626;933.1741408853214;950.8763860698065;986.6645340584092;992.2905122837207;1007.3804610164842;386.1523954918926;395.34767633405113;395.98614048508165;409.69477739083976;423.584886810927;10148.441835699108;10191.337563058378;10248.128909042665;10368.740781480372;10391.37651740898;347.25167264544945;350.7001513705796;370.6571421435162;375.6581510825136;393.1527558112273;5456.945430545644;5483.479069488472;5587.804426845041;5688;5701.173329867146;296912;298609;302821;303982;304136;58966.70516647389;63312;66785.66607166933;67134.32865671281;68413.3158668407;409.5904095904096;420.8001199430307;421.031627257307;421.0715372603402;421.84180932150446;9967.750806229902;9997.950215227296;10057.849132262876;10211.132053775513;10236.129928956123;34502.47960326336;35482.822585886905;35903;36321.45517817288;36701.81649091737;46763.29855052245;46887.53112468968;47708;47792.76103619599;48584.271235932196;5495.890082198413;5555.416681248411;5588.692621905834;5594.272744543184;5600.243967064394;10257.9487102567;10462.947685261524;10737.677869663923;10846.945765271123;10847.511861966388;7973.601319934096;7990;8905.599248033983;9178;9591.28065395112;8846.601902914514;9244.95377523135;9264;9374.437533747998;9394.248460123037;8208.220219079178;8381.58092095405;9371.043475660243;9620;9643.9035609643;5482.91775623358;5566.192902029102;5668.433156684298;5686.2323586315315;5704.914426283526;8813.515256660945;9327;9349;9353.532323383668;9373.409065953088 08:27:07 INFO - 2015-10-22 08:27:07,740 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1052.8683914510686;1057.746140926178;1154.180531822406;1396.9383268715778;1491.8284397294315;6631.237407698116;8040.798980025503;8134.064582572991;8171.836563268738;8180.222878826502;7856.253655902694;7903.683852645887;7971.760847174594;7973.880391794113;8001.199880011995;1986.8609197356118;2044.7546294444687;2045.1410407628757;2088.56140210556;2090.4042347930767;2106.461145275936;2121.459027947879;2156.5686862627535;2163.2104281937136;2169.099823573223;2031.5327474680832;2043.2235750414823;2063.772984971652;2082.979170208301;2090.759562650292;2005.1177481907987;2058.5162486815584;2067.4831292176955;2124.437024188576;2132.1791110422446;1985.642584334819;2005.3783327168624;2041.5406533529838;2080.2927004818434;2083.5832833433224;6648.767293144717;7094.361507464353;7441.627918604048;7479.700811967515;7572.129205141424;4325.243082460557;4365.083332500179;4485.080558485535;4487.573680500347;4489.685721999462;2669.23926680895;2681.731826817322;2709.91870243893;2764.350377661268;2770.0581802187353;795.8567457857583;797.1510341486356;834.9666013359458;835.9289460395873;839.2866063845744;11143.944280278627;11242.550297988071;11280.32318060919;11446.885531144666;11465.31208127515;4057.2696914555663;4103.630673239423;4153.252414565347;4207.242696314632;4229.175310814423;9632.855507167398;9657.71026869195;9666.178374838217;9673;9673.806523869483;4343.804528796211;4467.195904737178;4536.954630453653;4539.886502837456;4548.431446069241;3898.7855667938534;4011.8796436106963;4033.830189245086;4052.371882358239;4054.9797251013556;3230.854611542486;3231.337575796956;3248.123006788201;3269.73842092632;3326.600807903067;1037.8598889150019;1051.75283808305;1070.8714954205545;1072.2869291920786;1074.1138560687464;1094.1301665176204;1129.0910816792398;1145.6391236760394;1149.7642983188425;1159.368144361325;7496.100467943882;7562.281583249583;7566.432517561205;7566.583837888859;7568.940348351235;417.95402505724957;431.88123266102076;441.50110375275733;445.29420867924705;446.3483314361004;5720.742566584514;5798.565107616945;5807.68057756819;5807.738651760681;5814.127880817911;8764.342674299452;9058.958219804676;9088.04575519574;9147.2682185425;9254.768630784285;8947.32895032875;9052.683156089637;9056.818863622691;9066.818663626691;9090.999990001095;7129.465290103263;7169.820754481076;7183.60490173035;7187.496875218789;7212.747553835696;6786.355296246848;6803.183617965876;7095.503314767917;7175.174854891754;7214.350708436266;8287.632540630728;8396.87404688918;8567.957160214159;8582.65669373218;8604.311655067455;8343.624536895975;8406.411551191359;8694.869576956477;8816.162464565856;8841.602127904389;6747.763828265987;6761.797146085625;6868.381845633917;7024.297570242935;7057.788266352018;7612.200718924433;7794.064712234568;7827.295543401121;7881.724139655084;7893.289603935444;6749.291324411062;6981.79054628362;7232.963835180791;7253.782386528413;7292.927070729224;6928.272531384132;7151.570905745464;7298.2701729826595;7301.342879140902;7302.780916572511;7367.373773229336;7407.851842963219;7420.703171873065;7426.480146389701;7426.702931882664;6540.640264785482;6582.703778330082;6608;6617.867642647217;6621.106150669598;6505.577137486048;6621.9337806621315;6665.50008749336;6668.433183179484;6674.766383176566 08:27:07 INFO - 2015-10-22 08:27:07,786 DEBUG : BROWSER_OUTPUT: |4;prototype.html;278.53623716512004;287.6375766534167;288.2721129148899;290.9447205031045;295.7855554722825;4538.773061346936;4616.076784643068;4624.699394539357;4627.375304333914;4627.8380256691025;4510.413646225994;4584.472785629654;4588.105319462706;4592.288195329721;4597.057603191346;3308.4210263203904;3411.0790086676584;3483.599386070596;3492.1793378556017;3496.30073985204;3355.899323020313;3368.1411240133853;3420.401429749796;3452.7928324300497;3498.860045598186;3256.49524323729;3360.630330663625;3392.2537041850755;3417.3848707232687;3468.722502199818;3332.0670212340583;3422.982885085571;3428.502867084284;3448.9135922184432;3469.635688252735;2959.2453924249394;2978.27032377069;2985.2536865783554;3067.757558188937;3088.274255549944;4172.958270417287;4456.710313829591;4462.330650402466;4466;4470.575295346936;3988.960110398888;4041.515018197835;4103.8974025649295;4127.731697439687;4150.709450338478;4750.904981900378;4874.366332376804;4875.098106850244;4901.632377571661;4912.754362281871;3562.412201986669;3563.055790215595;3565.4651802229614;3565.5186549815962;3568.7323450741296;5045.798168073273;5115.437301896788;5118.411382691001;5124.769385377667;5129.5383415492415;3344.347852168804;3453.430184019659;3493.982530087333;3542.1144713821545;3560.2345495718546;3415.6584341565645;3461.9653803462147;3590.3716849551224;3592.5688917329567;3604.279144171176;3905.9609403906165;4071.89820254496;4103.671706263552;4104.015036391304;4107.260693075275;4707.482176960531;4856.611471082305;4861.829835955725;4864.854054378375;4874.098291815954;1310.3710219094887;1322.3256139368991;1331.4407948661585;1339.9665008374673;1340.7385559815937;909.2998391238841;919.1406035356963;921.9400738951947;942.9575669094908;949.5774380400655;4582.610478109398;4685.554872287127;4718.575328220477;4719.834805781781;4727.905441891143;5057.6459647824295;5066.265391518209;5069.239614057921;5070.138076527;5070.670406423571;4500.98727786252;4613.76931153441;4705.764711764398;4714.834980775657;4723.055388922229;2693.4747724193594;2707.986460067687;2726.8091233613854;2760.4755096531594;2761.696213416563;4775.235962246094;5187.818426355135;5188.429272779991;5190.948090519122;5191.480851914778;5017.724025178651;5172.922406163911;5174.301469301671;5181.792728290901;5182.585393168612;5181.145111056633;5181.222816577543;5181.740912954338;5192.454792246911;5201.375834899836;4896.534829191221;5019.121653710659;5029.949700503094;5046.9747651261505;5047.722375269249;4759.881002974953;4896.436909755281;4947.802087916443;4949.158643030765;4950.430700469348;5085.516875896784;5085.821996230262;5182.222666600041;5182.222666600041;5190.143626301618;3486.5118883355844;3550.8757193498104;3566.5006899033638;3567.28654269142;3572.91067723309;4886.486918873609;4965.45380008206;4969.751512424436;4982.676126051795;5030.673006254582;4619.4687610925175;4811.735354555533;4868.294097355934;4880.975595122001;4887.926681099715;4764.880253140578;4831.178699621143;4862.270659400977;4870.439899411613;4878.512148785093;4588.174128656874;4758.095961767253;4771.355866957916;4772;4773.069251495925;4887.926681099715;4890.682105663121;4898.92651610219;4902.828401005948;4906.920477494946;4306.461692288464;4545.227311357144;4611.723296602215;4683.461401938821;4768.76156192196 08:27:07 INFO - 2015-10-22 08:27:07,788 DEBUG : BROWSER_OUTPUT: |5;yui.html;623.8908191066562;656.9704363303649;715.5849607227808;717.6089031477844;740.1007775552706;11067.778644427117;11178.385188814602;11404.894238074286;11519.25124866883;11524.193306468529;10182;10206.74483137922;10257.38455692659;10288.845667314978;10356.326838755476;595.0003993291269;597.8385835824325;598.7814447726581;610.7099127914249;623.4389049855143;617.7868635320807;620.0358442621722;621.8880601491729;626.7148447456397;627.9968600156993;640.5964242527225;645.3610925184057;645.886969780289;650.6746626686656;654.1332734127277;489.9955092061278;507.046752106042;644.8001119652876;657.2310396835666;658.6377492379172;461.0893485366408;462.7801794147787;474.5918510081327;475.32266520208907;478.6673262082854;8526.829463410759;8737.082606326308;10755.569777208902;11014.284071535325;11134.49894754738;10213;10245.514400411974;10500;10756.784864302748;11071.335719856834;8970.730878073668;9010.86483702745;9134.132257435533;9184.816303673955;9227.123423274777;3142.155016944449;3149.4488464518618;3254.886078987248;3260.4946233333635;3361.0421030006573;34181.48727769085;34797.13007174841;34817.825910870284;35169.47245791315;35261.29477410463;8287.12985136552;8416.242538171595;8518.23335899772;8668.536331438801;8733.083026282276;14549.490767823076;14587.781183282259;14672.926635366968;14681.045732027384;14750.55748327552;5461.672299661954;5640.661560306395;6293.937060629427;6580.276169621337;6589.934100658932;3489.528913596632;3491.669972233261;3502.7372947029075;3502.9824850876093;3573.6426357364576;2269.9773002269767;2272.4659704969317;2282.3267136194863;2295.0934380919775;2297.035245196988;359.0950803973971;363.2897685025768;370.34633871217324;370.5849448617566;376.9264993326275;597.1699337920294;605.6729366142322;616.2050954268943;630.4798541203526;631.955763096588;7516.285952834472;7567.432442566829;7570.8928580345855;7847.803804904809;7891.408144389079;470.88698712309485;507.1277402867063;597.6205109755336;626.8213559135625;628.2335550628226;3021.3806169735153;3491.7381196409865;3576.624454432299;3617.8733744318824;3766;41279.95040966983;42354.246973946596;42841.35737963933;43081;44142;34982;34988.12529686778;35145.82427087848;35177;35314.293714125575;31163.53254701136;31181.844090779854;31192.376152476823;31507;31941.680583194335;7124.750633727795;7440.590767507839;7630.694772209049;7702.191235059761;7768.495047821873;22337.77662223357;22636.547269054197;22784.886075569517;23338.299851004496;23481.295561133193;29995.25011874633;30675.84662076675;31494.52758208583;31501;31547.684523154476;6739.898901516383;6794.558353706993;6833.45332373399;6904.447644188554;7208.135023797178;10150.746231344276;10570.41862697559;10620;10797.190210734068;10930.289531180448;9946.254030947563;10097.798044038931;10100.090991810772;10102.024581360984;10348.482575871327;9842.60629574809;9859.408435493893;9960.203183745429;10023.949880250844;10210.285280030326;10106.343087699275;10402.219833512365;10417.270791044857;10438.78802380891;10557.208209384173;8543.359248056297;8722.433041852259;8756.562171891252;9082.772930676785;9115.863262051207;8542.18849209324;8543.743687689379;8632.870506942276;9154.633814647339;9181.265498760216 08:27:07 INFO - 2015-10-22 08:27:07,789 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:27:07 INFO - 2015-10-22 08:27:07,789 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:27:07 INFO - 2015-10-22 08:27:07,789 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,974 08:27:07 INFO - 2015-10-22 08:27:07,789 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:27:07 INFO - 2015-10-22 08:27:07,789 DEBUG : BROWSER_OUTPUT: __startTimestamp1445527627754__endTimestamp 08:27:07 INFO - 2015-10-22 08:27:07,789 DEBUG : BROWSER_OUTPUT: 08:27:07 INFO - 2015-10-22 08:27:07,789 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:27:07 INFO - 2015-10-22 08:27:07,789 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:27:07 INFO - 2015-10-22 08:27:07,789 DEBUG : BROWSER_OUTPUT: 08:27:07 INFO - 2015-10-22 08:27:07,789 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4082.92 Median:4264.77 stddev:518.22 (12.2%) stddev-sans-first:500.88 08:27:07 INFO - 2015-10-22 08:27:07,789 DEBUG : BROWSER_OUTPUT: Values: 2314.2 2325.3 2382.4 2393.3 2405.6 4152.8 4187.0 4192.1 4213.6 4264.8 4161.0 4173.4 4199.8 4210.7 4214.5 3568.4 3632.7 3653.5 3733.9 3736.9 3508.7 3550.9 3630.1 3721.1 3727.4 3489.0 3513.3 3602.0 3636.2 3686.2 3554.9 3608.2 3697.9 3727.4 3765.4 3550.4 3553.1 3633.5 3657.2 3657.9 3980.5 4118.4 4130.5 4182.2 4220.9 4377.6 4442.8 4534.4 4540.8 4540.9 4298.1 4362.4 4391.5 4438.1 4556.8 4154.6 4213.0 4227.2 4241.8 4242.1 4348.3 4531.0 4540.8 4622.6 4631.5 4254.2 4292.4 4377.0 4394.8 4434.1 4179.1 4210.7 4392.2 4476.3 4488.9 4356.2 4381.3 4442.3 4453.7 4457.2 4276.6 4308.9 4422.8 4425.6 4432.9 3001.0 3085.2 3112.9 3114.2 3128.6 2852.9 2893.5 2894.0 2897.7 2905.7 4081.5 4082.8 4161.2 4176.9 4282.7 4442.3 4471.1 4510.6 4564.9 4571.8 4184.7 4211.3 4250.5 4274.2 4285.2 3684.8 3743.2 3794.3 3844.2 3854.7 4472.3 4598.2 4664.6 4668.6 4678.4 4392.2 4462.9 4554.5 4560.3 4562.0 4401.4 4513.9 4573.9 4578.2 4578.9 4324.9 4377.9 4420.0 4445.0 4445.5 4402.6 4409.8 4494.9 4495.5 4502.3 4186.1 4211.5 4301.6 4306.7 4374.2 4079.8 4084.0 4280.6 4360.7 4363.9 4136.7 4155.0 4180.2 4272.9 4277.0 4227.1 4275.9 4323.5 4332.2 4401.9 4284.6 4317.2 4336.1 4371.2 4391.3 08:27:07 INFO - 2015-10-22 08:27:07,792 DEBUG : BROWSER_OUTPUT: 08:27:07 INFO - 2015-10-22 08:27:07,792 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:40094.93 Median:31773.68 stddev:66078.84 (208.0%) stddev-sans-first:66197.24 08:27:07 INFO - 2015-10-22 08:27:07,792 DEBUG : BROWSER_OUTPUT: Values: 475.3 482.3 489.9 513.7 513.9 25971.4 36681.4 36810.4 37252.4 37296.8 39621.6 39795.6 40196.4 40246.6 40314.6 6092.0 6213.2 7874.4 7998.1 8185.4 10083.8 10164.7 10343.9 10368.3 10417.7 9687.4 9717.7 9731.9 9743.7 9748.0 6376.5 6581.7 9893.4 10034.1 10069.5 8978.9 9243.7 9467.9 9636.4 9644.2 26579.9 36679.4 36889.3 37307.1 37338.0 2262.6 2925.9 2968.3 3096.3 3381.8 26393.2 26736.0 27086.4 27158.9 27282.9 1996.3 2058.9 2079.6 2150.8 2165.6 208996.0 209420.0 210437.0 210443.0 211241.0 45662.8 46780.0 49784.8 50684.5 51986.2 48675.3 48743.0 48916.5 48931.3 48978.3 11873.1 12025.5 12047.2 12082.4 12096.8 8424.0 8464.2 8465.5 8470.9 8605.2 16949.0 16992.9 17086.8 17094.9 17142.7 6797.5 7018.8 7026.7 7028.5 7085.2 772.6 805.3 819.4 836.2 869.9 23654.0 24931.5 24949.8 25537.5 25693.4 920.9 923.5 937.4 1005.6 1035.1 13208.9 13335.9 13386.6 13407.5 13619.7 269329.0 280655.0 282106.0 286956.0 288369.0 46482.8 46659.3 46843.1 46910.5 46936.5 1136.0 1145.4 1152.1 1156.7 1162.5 24668.5 24958.3 25154.0 25365.6 25810.7 39497.2 39537.8 39979.4 40041.6 40354.4 251153.0 261991.0 265364.0 265448.0 266636.0 22613.5 26655.0 26656.2 26658.0 26674.6 34906.8 35308.0 35615.3 35651.0 35722.1 30215.0 31115.4 32241.7 32499.8 32903.2 22346.8 27163.0 31809.4 31820.5 32919.5 31021.7 31738.0 32443.0 32561.5 32592.5 28954.3 29384.4 29643.0 30348.7 30393.7 21852.7 22039.0 22516.2 23058.8 23481.8 08:27:07 INFO - 2015-10-22 08:27:07,792 DEBUG : BROWSER_OUTPUT: 08:27:07 INFO - 2015-10-22 08:27:07,792 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:20374.64 Median:4530.08 stddev:50104.33 (1106.0%) stddev-sans-first:50222.23 08:27:07 INFO - 2015-10-22 08:27:07,792 DEBUG : BROWSER_OUTPUT: Values: 307.3 308.7 309.1 311.1 312.4 10688.7 14852.6 15437.8 15588.5 15860.0 15349.5 15657.6 15753.4 15854.2 15867.0 5069.2 5119.6 5240.6 5251.3 5258.8 3503.4 3608.2 5103.3 5187.3 5921.0 5485.0 5669.5 5676.0 5844.5 5856.2 5718.3 5832.8 5952.5 5968.2 5982.8 4838.7 4874.9 4910.4 5111.3 5119.1 12455.6 19564.8 19662.7 19974.8 20378.9 1959.6 1971.7 2010.4 2010.9 2068.0 13224.4 13527.5 13687.3 13909.8 14122.0 1823.0 1856.7 1857.9 1872.7 1879.8 59947.0 60249.2 60998.4 62672.4 64155.4 13589.1 13805.5 14001.7 14120.0 14199.4 6532.8 19392.9 20247.0 20361.7 20375.0 4491.0 4569.2 4573.7 4589.4 4601.3 4777.8 4778.8 4782.2 4803.0 4815.2 2512.8 2579.9 2650.8 2652.3 2663.1 933.2 950.9 986.7 992.3 1007.4 386.2 395.3 396.0 409.7 423.6 10148.4 10191.3 10248.1 10368.7 10391.4 347.3 350.7 370.7 375.7 393.2 5456.9 5483.5 5587.8 5688.0 5701.2 296912.0 298609.0 302821.0 303982.0 304136.0 58966.7 63312.0 66785.7 67134.3 68413.3 409.6 420.8 421.0 421.1 421.8 9967.8 9998.0 10057.8 10211.1 10236.1 34502.5 35482.8 35903.0 36321.5 36701.8 46763.3 46887.5 47708.0 47792.8 48584.3 5495.9 5555.4 5588.7 5594.3 5600.2 10257.9 10462.9 10737.7 10846.9 10847.5 7973.6 7990.0 8905.6 9178.0 9591.3 8846.6 9245.0 9264.0 9374.4 9394.2 8208.2 8381.6 9371.0 9620.0 9643.9 5482.9 5566.2 5668.4 5686.2 5704.9 8813.5 9327.0 9349.0 9353.5 9373.4 08:27:07 INFO - 2015-10-22 08:27:07,793 DEBUG : BROWSER_OUTPUT: 08:27:07 INFO - 2015-10-22 08:27:07,793 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5402.36 Median:6621.52 stddev:2990.97 (45.2%) stddev-sans-first:2981.49 08:27:07 INFO - 2015-10-22 08:27:07,793 DEBUG : BROWSER_OUTPUT: Values: 1052.9 1057.7 1154.2 1396.9 1491.8 6631.2 8040.8 8134.1 8171.8 8180.2 7856.3 7903.7 7971.8 7973.9 8001.2 1986.9 2044.8 2045.1 2088.6 2090.4 2106.5 2121.5 2156.6 2163.2 2169.1 2031.5 2043.2 2063.8 2083.0 2090.8 2005.1 2058.5 2067.5 2124.4 2132.2 1985.6 2005.4 2041.5 2080.3 2083.6 6648.8 7094.4 7441.6 7479.7 7572.1 4325.2 4365.1 4485.1 4487.6 4489.7 2669.2 2681.7 2709.9 2764.4 2770.1 795.9 797.2 835.0 835.9 839.3 11143.9 11242.6 11280.3 11446.9 11465.3 4057.3 4103.6 4153.3 4207.2 4229.2 9632.9 9657.7 9666.2 9673.0 9673.8 4343.8 4467.2 4537.0 4539.9 4548.4 3898.8 4011.9 4033.8 4052.4 4055.0 3230.9 3231.3 3248.1 3269.7 3326.6 1037.9 1051.8 1070.9 1072.3 1074.1 1094.1 1129.1 1145.6 1149.8 1159.4 7496.1 7562.3 7566.4 7566.6 7568.9 418.0 431.9 441.5 445.3 446.3 5720.7 5798.6 5807.7 5807.7 5814.1 8764.3 9059.0 9088.0 9147.3 9254.8 8947.3 9052.7 9056.8 9066.8 9091.0 7129.5 7169.8 7183.6 7187.5 7212.7 6786.4 6803.2 7095.5 7175.2 7214.4 8287.6 8396.9 8568.0 8582.7 8604.3 8343.6 8406.4 8694.9 8816.2 8841.6 6747.8 6761.8 6868.4 7024.3 7057.8 7612.2 7794.1 7827.3 7881.7 7893.3 6749.3 6981.8 7233.0 7253.8 7292.9 6928.3 7151.6 7298.3 7301.3 7302.8 7367.4 7407.9 7420.7 7426.5 7426.7 6540.6 6582.7 6608.0 6617.9 6621.1 6505.6 6621.9 6665.5 6668.4 6674.8 08:27:07 INFO - 2015-10-22 08:27:07,795 DEBUG : BROWSER_OUTPUT: 08:27:07 INFO - 2015-10-22 08:27:07,795 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4065.00 Median:4626.04 stddev:1203.34 (26.0%) stddev-sans-first:1172.68 08:27:07 INFO - 2015-10-22 08:27:07,795 DEBUG : BROWSER_OUTPUT: Values: 278.5 287.6 288.3 290.9 295.8 4538.8 4616.1 4624.7 4627.4 4627.8 4510.4 4584.5 4588.1 4592.3 4597.1 3308.4 3411.1 3483.6 3492.2 3496.3 3355.9 3368.1 3420.4 3452.8 3498.9 3256.5 3360.6 3392.3 3417.4 3468.7 3332.1 3423.0 3428.5 3448.9 3469.6 2959.2 2978.3 2985.3 3067.8 3088.3 4173.0 4456.7 4462.3 4466.0 4470.6 3989.0 4041.5 4103.9 4127.7 4150.7 4750.9 4874.4 4875.1 4901.6 4912.8 3562.4 3563.1 3565.5 3565.5 3568.7 5045.8 5115.4 5118.4 5124.8 5129.5 3344.3 3453.4 3494.0 3542.1 3560.2 3415.7 3462.0 3590.4 3592.6 3604.3 3906.0 4071.9 4103.7 4104.0 4107.3 4707.5 4856.6 4861.8 4864.9 4874.1 1310.4 1322.3 1331.4 1340.0 1340.7 909.3 919.1 921.9 943.0 949.6 4582.6 4685.6 4718.6 4719.8 4727.9 5057.6 5066.3 5069.2 5070.1 5070.7 4501.0 4613.8 4705.8 4714.8 4723.1 2693.5 2708.0 2726.8 2760.5 2761.7 4775.2 5187.8 5188.4 5190.9 5191.5 5017.7 5172.9 5174.3 5181.8 5182.6 5181.1 5181.2 5181.7 5192.5 5201.4 4896.5 5019.1 5029.9 5047.0 5047.7 4759.9 4896.4 4947.8 4949.2 4950.4 5085.5 5085.8 5182.2 5182.2 5190.1 3486.5 3550.9 3566.5 3567.3 3572.9 4886.5 4965.5 4969.8 4982.7 5030.7 4619.5 4811.7 4868.3 4881.0 4887.9 4764.9 4831.2 4862.3 4870.4 4878.5 4588.2 4758.1 4771.4 4772.0 4773.1 4887.9 4890.7 4898.9 4902.8 4906.9 4306.5 4545.2 4611.7 4683.5 4768.8 08:27:07 INFO - 2015-10-22 08:27:07,796 DEBUG : BROWSER_OUTPUT: 08:27:07 INFO - 2015-10-22 08:27:07,796 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:10477.91 Median:42597.80 stddev:11135.12 (26.1%) stddev-sans-first:11141.76 08:27:07 INFO - 2015-10-22 08:27:07,796 DEBUG : BROWSER_OUTPUT: Values: 623.9 657.0 715.6 717.6 740.1 11067.8 11178.4 11404.9 11519.3 11524.2 10182.0 10206.7 10257.4 10288.8 10356.3 595.0 597.8 598.8 610.7 623.4 617.8 620.0 621.9 626.7 628.0 640.6 645.4 645.9 650.7 654.1 490.0 507.0 644.8 657.2 658.6 461.1 462.8 474.6 475.3 478.7 8526.8 8737.1 10755.6 11014.3 11134.5 10213.0 10245.5 10500.0 10756.8 11071.3 8970.7 9010.9 9134.1 9184.8 9227.1 3142.2 3149.4 3254.9 3260.5 3361.0 34181.5 34797.1 34817.8 35169.5 35261.3 8287.1 8416.2 8518.2 8668.5 8733.1 14549.5 14587.8 14672.9 14681.0 14750.6 5461.7 5640.7 6293.9 6580.3 6589.9 3489.5 3491.7 3502.7 3503.0 3573.6 2270.0 2272.5 2282.3 2295.1 2297.0 359.1 363.3 370.3 370.6 376.9 597.2 605.7 616.2 630.5 632.0 7516.3 7567.4 7570.9 7847.8 7891.4 470.9 507.1 597.6 626.8 628.2 3021.4 3491.7 3576.6 3617.9 3766.0 41280.0 42354.2 42841.4 43081.0 44142.0 34982.0 34988.1 35145.8 35177.0 35314.3 31163.5 31181.8 31192.4 31507.0 31941.7 7124.8 7440.6 7630.7 7702.2 7768.5 22337.8 22636.5 22784.9 23338.3 23481.3 29995.3 30675.8 31494.5 31501.0 31547.7 6739.9 6794.6 6833.5 6904.4 7208.1 10150.7 10570.4 10620.0 10797.2 10930.3 9946.3 10097.8 10100.1 10102.0 10348.5 9842.6 9859.4 9960.2 10023.9 10210.3 10106.3 10402.2 10417.3 10438.8 10557.2 8543.4 8722.4 8756.6 9082.8 9115.9 8542.2 8543.7 8632.9 9154.6 9181.3 08:27:07 INFO - 2015-10-22 08:27:07,796 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:27:07 INFO - 2015-10-22 08:27:07,796 DEBUG : BROWSER_OUTPUT: 08:27:08 INFO - 2015-10-22 08:27:08,233 INFO : Browser exited with error code: 0 08:27:08 INFO - 2015-10-22 08:27:08,966 INFO : Completed test dromaeo_css (00:18:44) 08:27:08 INFO - 2015-10-22 08:27:08,966 INFO : Starting test kraken 08:27:08 INFO - 2015-10-22 08:27:08,966 DEBUG : operating with platform_type : w7_ 08:27:08 INFO - 2015-10-22 08:27:08,966 INFO : Initialising browser for kraken test... 08:27:09 INFO - 2015-10-22 08:27:08,992 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpnxw9d9\profile http://localhost:49891/getInfo.html 08:27:14 INFO - 2015-10-22 08:27:14,134 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:27:14 INFO - 2015-10-22 08:27:14,134 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:27:14 INFO - 2015-10-22 08:27:14,134 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 08:27:14 INFO - 2015-10-22 08:27:14,134 DEBUG : BROWSER_OUTPUT: __metrics 08:27:14 INFO - 2015-10-22 08:27:14,249 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:27:15 INFO - 2015-10-22 08:27:15,361 INFO : Browser initialized. 08:27:15 INFO - 2015-10-22 08:27:15,361 INFO : Running cycle 1/1 for kraken test... 08:27:15 INFO - 2015-10-22 08:27:15,361 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpnxw9d9\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:27:27 INFO - 2015-10-22 08:27:27,016 DEBUG : BROWSER_OUTPUT: RSS: Main: 141848576 08:27:27 INFO - 2015-10-22 08:27:27,016 DEBUG : BROWSER_OUTPUT: 08:27:29 INFO - 2015-10-22 08:27:29,532 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49891/tests/kraken/driver.html?testName=audio-beat-detection) 08:27:30 INFO - 2015-10-22 08:27:30,115 DEBUG : BROWSER_OUTPUT: RSS: Main: 175370240 08:27:30 INFO - 2015-10-22 08:27:30,115 DEBUG : BROWSER_OUTPUT: 08:27:32 INFO - 2015-10-22 08:27:32,930 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49891/tests/kraken/driver.html?testName=audio-dft) 08:27:33 INFO - 2015-10-22 08:27:33,505 DEBUG : BROWSER_OUTPUT: RSS: Main: 177393664 08:27:33 INFO - 2015-10-22 08:27:33,505 DEBUG : BROWSER_OUTPUT: 08:27:36 INFO - 2015-10-22 08:27:36,285 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49891/tests/kraken/driver.html?testName=audio-fft) 08:27:36 INFO - 2015-10-22 08:27:36,865 DEBUG : BROWSER_OUTPUT: RSS: Main: 169598976 08:27:36 INFO - 2015-10-22 08:27:36,865 DEBUG : BROWSER_OUTPUT: 08:27:39 INFO - 2015-10-22 08:27:39,045 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49891/tests/kraken/driver.html?testName=audio-oscillator) 08:27:39 INFO - 2015-10-22 08:27:39,619 DEBUG : BROWSER_OUTPUT: RSS: Main: 166436864 08:27:39 INFO - 2015-10-22 08:27:39,619 DEBUG : BROWSER_OUTPUT: 08:27:41 INFO - 2015-10-22 08:27:41,897 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49891/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:27:42 INFO - 2015-10-22 08:27:42,460 DEBUG : BROWSER_OUTPUT: RSS: Main: 166273024 08:27:42 INFO - 2015-10-22 08:27:42,460 DEBUG : BROWSER_OUTPUT: 08:27:45 INFO - 2015-10-22 08:27:45,700 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49891/tests/kraken/driver.html?testName=imaging-darkroom) 08:27:46 INFO - 2015-10-22 08:27:46,279 DEBUG : BROWSER_OUTPUT: RSS: Main: 163123200 08:27:46 INFO - 2015-10-22 08:27:46,279 DEBUG : BROWSER_OUTPUT: 08:27:49 INFO - 2015-10-22 08:27:49,700 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49891/tests/kraken/driver.html?testName=imaging-desaturate) 08:27:50 INFO - 2015-10-22 08:27:50,275 DEBUG : BROWSER_OUTPUT: RSS: Main: 163684352 08:27:50 INFO - 2015-10-22 08:27:50,275 DEBUG : BROWSER_OUTPUT: 08:27:53 INFO - 2015-10-22 08:27:53,150 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49891/tests/kraken/driver.html?testName=json-parse-financial) 08:27:53 INFO - 2015-10-22 08:27:53,724 DEBUG : BROWSER_OUTPUT: RSS: Main: 163557376 08:27:53 INFO - 2015-10-22 08:27:53,724 DEBUG : BROWSER_OUTPUT: 08:27:55 INFO - 2015-10-22 08:27:55,579 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49891/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:27:56 INFO - 2015-10-22 08:27:56,147 DEBUG : BROWSER_OUTPUT: RSS: Main: 193196032 08:27:56 INFO - 2015-10-22 08:27:56,148 DEBUG : BROWSER_OUTPUT: 08:27:58 INFO - 2015-10-22 08:27:58,128 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49891/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:27:58 INFO - 2015-10-22 08:27:58,703 DEBUG : BROWSER_OUTPUT: RSS: Main: 198369280 08:27:58 INFO - 2015-10-22 08:27:58,703 DEBUG : BROWSER_OUTPUT: 08:28:01 INFO - 2015-10-22 08:28:01,109 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49891/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:28:01 INFO - 2015-10-22 08:28:01,684 DEBUG : BROWSER_OUTPUT: RSS: Main: 204251136 08:28:01 INFO - 2015-10-22 08:28:01,684 DEBUG : BROWSER_OUTPUT: 08:28:04 INFO - 2015-10-22 08:28:04,321 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49891/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:28:04 INFO - 2015-10-22 08:28:04,888 DEBUG : BROWSER_OUTPUT: RSS: Main: 206458880 08:28:04 INFO - 2015-10-22 08:28:04,888 DEBUG : BROWSER_OUTPUT: 08:28:08 INFO - 2015-10-22 08:28:08,065 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49891/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:28:08 INFO - 2015-10-22 08:28:08,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 205549568 08:28:08 INFO - 2015-10-22 08:28:08,641 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,661 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49891/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49891/tests/kraken/driver.html?testName=ai-astar) 08:28:10 INFO - 2015-10-22 08:28:10,915 DEBUG : BROWSER_OUTPUT: RSS: Main: 251338752 08:28:10 INFO - 2015-10-22 08:28:10,915 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,915 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:28:10 INFO - 2015-10-22 08:28:10,915 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:28:10 INFO - 2015-10-22 08:28:10,915 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:28:10 INFO - 2015-10-22 08:28:10,915 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:28:10 INFO - 2015-10-22 08:28:10,915 DEBUG : BROWSER_OUTPUT: |0;ai-astar;93;97;97;88;96;97;97;97;98;95 08:28:10 INFO - 2015-10-22 08:28:10,915 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;148;143;159;165;144;145;155;151;162;151 08:28:10 INFO - 2015-10-22 08:28:10,915 DEBUG : BROWSER_OUTPUT: |2;audio-dft;155;151;148;146;147;147;147;146;153;146 08:28:10 INFO - 2015-10-22 08:28:10,915 DEBUG : BROWSER_OUTPUT: |3;audio-fft;85;98;100;112;80;85;80;79;105;77 08:28:10 INFO - 2015-10-22 08:28:10,915 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;100;99;117;100;100;99;124;100;100;123 08:28:10 INFO - 2015-10-22 08:28:10,915 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;117;117;117;124;125;117;116;117;118;117 08:28:10 INFO - 2015-10-22 08:28:10,915 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;138;136;138;136;137;145;137;139;146;136 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;90;93;90;90;91;90;90;90;90;91 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;77;79;78;78;78;78;78;79;83;90 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;75;75;78;80;76;75;75;76;80;76 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;101;99;98;98;105;99;99;98;101;100 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;130;134;130;128;132;135;132;128;131;153 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;192;200;200;197;199;190;201;198;199;189 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;92;92;93;91;93;93;95;91;94;92 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,942 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: __startTimestamp1445527690914__endTimestamp 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:95.50 Median:97.00 stddev:2.99 (3.1%) stddev-sans-first:3.03 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: Values: 93.0 97.0 97.0 88.0 96.0 97.0 97.0 97.0 98.0 95.0 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:152.30 Median:153.00 stddev:7.73 (5.1%) stddev-sans-first:8.04 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: Values: 148.0 143.0 159.0 165.0 144.0 145.0 155.0 151.0 162.0 151.0 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:148.60 Median:147.50 stddev:3.24 (2.2%) stddev-sans-first:2.47 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: Values: 155.0 151.0 148.0 146.0 147.0 147.0 147.0 146.0 153.0 146.0 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:90.10 Median:80.00 stddev:12.53 (15.7%) stddev-sans-first:13.15 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: Values: 85.0 98.0 100.0 112.0 80.0 85.0 80.0 79.0 105.0 77.0 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.20 Median:120.00 stddev:10.60 (8.8%) stddev-sans-first:11.01 08:28:10 INFO - 2015-10-22 08:28:10,917 DEBUG : BROWSER_OUTPUT: Values: 100.0 99.0 117.0 100.0 100.0 99.0 124.0 100.0 100.0 123.0 08:28:10 INFO - 2015-10-22 08:28:10,924 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,924 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:118.50 Median:117.00 stddev:3.21 (2.7%) stddev-sans-first:3.35 08:28:10 INFO - 2015-10-22 08:28:10,924 DEBUG : BROWSER_OUTPUT: Values: 117.0 117.0 117.0 124.0 125.0 117.0 116.0 117.0 118.0 117.0 08:28:10 INFO - 2015-10-22 08:28:10,924 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,924 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:138.80 Median:138.00 stddev:3.68 (2.7%) stddev-sans-first:3.89 08:28:10 INFO - 2015-10-22 08:28:10,924 DEBUG : BROWSER_OUTPUT: Values: 138.0 136.0 138.0 136.0 137.0 145.0 137.0 139.0 146.0 136.0 08:28:10 INFO - 2015-10-22 08:28:10,926 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,926 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:90.50 Median:90.00 stddev:0.97 (1.1%) stddev-sans-first:1.01 08:28:10 INFO - 2015-10-22 08:28:10,926 DEBUG : BROWSER_OUTPUT: Values: 90.0 93.0 90.0 90.0 91.0 90.0 90.0 90.0 90.0 91.0 08:28:10 INFO - 2015-10-22 08:28:10,926 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,926 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:79.80 Median:78.50 stddev:3.94 (5.0%) stddev-sans-first:4.04 08:28:10 INFO - 2015-10-22 08:28:10,927 DEBUG : BROWSER_OUTPUT: Values: 77.0 79.0 78.0 78.0 78.0 78.0 78.0 79.0 83.0 90.0 08:28:10 INFO - 2015-10-22 08:28:10,927 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,927 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:76.60 Median:76.00 stddev:2.01 (2.6%) stddev-sans-first:2.05 08:28:10 INFO - 2015-10-22 08:28:10,927 DEBUG : BROWSER_OUTPUT: Values: 75.0 75.0 78.0 80.0 76.0 75.0 75.0 76.0 80.0 76.0 08:28:10 INFO - 2015-10-22 08:28:10,927 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,927 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:99.80 Median:98.00 stddev:2.15 (2.2%) stddev-sans-first:2.24 08:28:10 INFO - 2015-10-22 08:28:10,927 DEBUG : BROWSER_OUTPUT: Values: 101.0 99.0 98.0 98.0 105.0 99.0 99.0 98.0 101.0 100.0 08:28:10 INFO - 2015-10-22 08:28:10,927 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,928 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:133.30 Median:132.00 stddev:7.29 (5.5%) stddev-sans-first:7.63 08:28:10 INFO - 2015-10-22 08:28:10,928 DEBUG : BROWSER_OUTPUT: Values: 130.0 134.0 130.0 128.0 132.0 135.0 132.0 128.0 131.0 153.0 08:28:10 INFO - 2015-10-22 08:28:10,928 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,930 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.50 Median:199.00 stddev:4.45 (2.2%) stddev-sans-first:4.42 08:28:10 INFO - 2015-10-22 08:28:10,930 DEBUG : BROWSER_OUTPUT: Values: 192.0 200.0 200.0 197.0 199.0 190.0 201.0 198.0 199.0 189.0 08:28:10 INFO - 2015-10-22 08:28:10,930 DEBUG : BROWSER_OUTPUT: 08:28:10 INFO - 2015-10-22 08:28:10,930 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:92.60 Median:93.00 stddev:1.26 (1.4%) stddev-sans-first:1.32 08:28:10 INFO - 2015-10-22 08:28:10,930 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 93.0 91.0 93.0 93.0 95.0 91.0 94.0 92.0 08:28:10 INFO - 2015-10-22 08:28:10,930 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:28:10 INFO - 2015-10-22 08:28:10,930 DEBUG : BROWSER_OUTPUT: 08:28:11 INFO - 2015-10-22 08:28:11,595 INFO : Browser exited with error code: 0 08:28:12 INFO - 2015-10-22 08:28:12,334 INFO : Completed test kraken (00:01:03) 08:28:12 INFO - 2015-10-22 08:28:12,334 INFO : Starting test v8_7 08:28:12 INFO - 2015-10-22 08:28:12,334 DEBUG : operating with platform_type : w7_ 08:28:12 INFO - 2015-10-22 08:28:12,334 INFO : Initialising browser for v8_7 test... 08:28:12 INFO - 2015-10-22 08:28:12,361 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmptp5jcn\profile http://localhost:49891/getInfo.html 08:28:16 INFO - 2015-10-22 08:28:16,825 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:28:16 INFO - 2015-10-22 08:28:16,825 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:28:16 INFO - 2015-10-22 08:28:16,825 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 08:28:16 INFO - 2015-10-22 08:28:16,825 DEBUG : BROWSER_OUTPUT: __metrics 08:28:16 INFO - 2015-10-22 08:28:16,911 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:28:17 INFO - 2015-10-22 08:28:17,667 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. 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: console.error: 08:28:17 INFO - 2015-10-22 08:28:17,914 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] 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: Stack: 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 08:28:17 INFO - 2015-10-22 08:28:17,914 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 5703.19 08:28:54 INFO - RETURN: kraken: 1619.10 08:28:54 INFO - RETURN: v8_7: 19553.30 08:28:54 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,131,25]]", "result": "19553.30"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,131,25]]", "result": "1619.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,131,25]]", "result": "5703.19"}}} 08:28:54 INFO - Return code: 0 08:28:54 INFO - # TBPL SUCCESS # 08:28:54 INFO - Running post-action listener: _resource_record_post_action 08:28:54 INFO - Running post-run listener: _resource_record_post_run 08:28:55 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 90383360; Write bytes: 431010304; Read time: 1078990; Write time: 4806420 08:28:55 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 16384; Read time: 0; Write time: 70 08:28:55 INFO - run-tests - Wall time: 1232s; CPU: 13.0%; Read bytes: 90366976; Write bytes: 406380544; Read time: 1066100; Write time: 4476610 08:28:55 INFO - Running post-run listener: _upload_blobber_files 08:28:55 INFO - Blob upload gear active. 08:28:55 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:28:55 INFO - Copying logs to upload dir... 08:28:55 INFO - mkdir: C:\slave\test\build\upload\logs program finished with exit code 0 elapsedTime=1428.529000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 0 secs) (at 2015-10-22 08:28:59.951490) ========= ========= Started set props: build_url (results: 0, elapsed: 3 secs) (at 2015-10-22 08:28:59.954824) ========= '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-056 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-056 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-056 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/rt6G02suTZSv-4-w4OSd_A/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/rt6G02suTZSv-4-w4OSd_A/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 3 secs) (at 2015-10-22 08:29:03.198193) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 10 secs) (at 2015-10-22 08:29:03.198576) ========= '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-056 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-056 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-056 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: 10 secs) (at 2015-10-22 08:29:13.279487) ========= ========= Started reboot slave lost (results: 0, elapsed: 1 secs) (at 2015-10-22 08:29:13.279798) ========= ========= Finished reboot slave lost (results: 0, elapsed: 1 secs) (at 2015-10-22 08:29:14.741380) =========