builder: mozilla-inbound_win7-ix_test-dromaeojs slave: t-w732-ix-077 starttime: 1445873256.62 results: success (0) buildid: 20151026064024 builduid: 8d2aa076bba649ff9a23e46722bb3cc9 revision: 18d52e7a0d80bda0507dca823806204b9595f2e9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 08:27:36.624084) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-26 08:27:36.625754) ========= ========= Started set props: basedir (results: 0, elapsed: 3 secs) (at 2015-10-26 08:27:36.626059) ========= '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-077 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-077 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-077 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.567000 basedir: 'C:\\slave\\test' ========= Finished set props: basedir (results: 0, elapsed: 3 secs) (at 2015-10-26 08:27:39.862333) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-26 08:27:39.862714) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-26 08:27:40.841979) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 08:27:40.842450) ========= '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-077 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-077 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-077 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.501000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-26 08:27:41.551063) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 08:27:41.551497) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-26 08:27:41.552238) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-26 08:27:41.552643) ========= '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-077 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-077 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-077 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:27:42-- 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% 9.34 MB/s 08:27:43 (9.34 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.481000 ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-26 08:27:43.233873) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 26 secs) (at 2015-10-26 08:27:43.234261) ========= '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-077 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-077 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-077 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=16.222000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 26 secs) (at 2015-10-26 08:28:09.757170) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 17 secs) (at 2015-10-26 08:28:09.757665) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 18d52e7a0d80bda0507dca823806204b9595f2e9 --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 18d52e7a0d80bda0507dca823806204b9595f2e9 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-077 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-077 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-077 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2015-10-26 08:28:19,170 truncating revision to first 12 chars 2015-10-26 08:28:19,170 Setting DEBUG logging. 2015-10-26 08:28:19,170 attempt 1/10 2015-10-26 08:28:19,170 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/18d52e7a0d80?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-26 08:28:22,039 unpacking tar archive at: mozilla-inbound-18d52e7a0d80/testing/mozharness/ program finished with exit code 0 elapsedTime=8.274000 ========= Finished 'bash -c ...' (results: 0, elapsed: 17 secs) (at 2015-10-26 08:28:27.143820) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-26 08:28:27.144206) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-10-26 08:28:27.891015) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 08:28:27.891401) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-26 08:28:27.891929) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 26 mins, 7 secs) (at 2015-10-26 08:28:27.892248) ========= '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-077 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-077 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-077 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:28:28 INFO - MultiFileLogger online at 20151026 08:28:28 in C:\slave\test 08:28:28 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:28:28 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 08:28:28 INFO - {'append_to_log': False, 08:28:28 INFO - 'base_work_dir': 'C:\\slave\\test', 08:28:28 INFO - 'blob_upload_branch': 'Mozilla-Inbound-Non-PGO', 08:28:28 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 08:28:28 INFO - 'branch': 'Mozilla-Inbound-Non-PGO', 08:28:28 INFO - 'buildbot_json_path': 'buildprops.json', 08:28:28 INFO - 'config_files': ('talos/windows_config.py',), 08:28:28 INFO - 'default_actions': ('clobber', 08:28:28 INFO - 'read-buildbot-config', 08:28:28 INFO - 'download-and-extract', 08:28:28 INFO - 'populate-webroot', 08:28:28 INFO - 'create-virtualenv', 08:28:28 INFO - 'install', 08:28:28 INFO - 'run-tests'), 08:28:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:28:28 INFO - 'download_minidump_stackwalk': True, 08:28:28 INFO - 'download_symbols': 'ondemand', 08:28:28 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 08:28:28 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 08:28:28 INFO - 'hg': 'c:/mozilla-build/hg/hg', 08:28:28 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 08:28:28 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 08:28:28 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 08:28:28 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 08:28:28 INFO - 'C:/mozilla-build/tooltool.py'), 08:28:28 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 08:28:28 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 08:28:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:28:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:28:28 INFO - 'installer_path': 'installer.exe', 08:28:28 INFO - 'log_level': 'info', 08:28:28 INFO - 'log_name': 'talos', 08:28:28 INFO - 'log_to_console': True, 08:28:28 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 08:28:28 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 08:28:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 08:28:28 INFO - 'opt_config_files': (), 08:28:28 INFO - 'pip_index': False, 08:28:28 INFO - 'sps_profile': False, 08:28:28 INFO - 'sps_profile_interval': 0, 08:28:28 INFO - 'suite': 'dromaeojs', 08:28:28 INFO - 'system_bits': '32', 08:28:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:28:28 INFO - 'title': 'T-W732-IX-077', 08:28:28 INFO - 'use_talos_json': True, 08:28:28 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 08:28:28 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 08:28:28 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 08:28:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:28:28 INFO - 'work_dir': 'build'} 08:28:28 INFO - ##### 08:28:28 INFO - ##### Running clobber step. 08:28:28 INFO - ##### 08:28:28 INFO - Running pre-action listener: _resource_record_pre_action 08:28:28 INFO - Running main action method: clobber 08:28:28 INFO - rmtree: C:\slave\test\build 08:28:28 INFO - Using _rmtree_windows ... 08:28:28 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 08:29:57 INFO - Running post-action listener: _resource_record_post_action 08:29:57 INFO - ##### 08:29:57 INFO - ##### Running read-buildbot-config step. 08:29:57 INFO - ##### 08:29:57 INFO - Running pre-action listener: _resource_record_pre_action 08:29:57 INFO - Running main action method: read_buildbot_config 08:29:57 INFO - Using buildbot properties: 08:29:57 INFO - { 08:29:57 INFO - "properties": { 08:29:57 INFO - "buildnumber": 2005, 08:29:57 INFO - "product": "firefox", 08:29:57 INFO - "script_repo_revision": "production", 08:29:57 INFO - "builddir": "mozilla-inbound_win7-ix_test-dromaeojs", 08:29:57 INFO - "repository": "", 08:29:57 INFO - "buildername": "Windows 7 32-bit mozilla-inbound talos dromaeojs", 08:29:57 INFO - "buildid": "20151026064024", 08:29:57 INFO - "slavename": "t-w732-ix-077", 08:29:57 INFO - "pgo_build": "False", 08:29:57 INFO - "basedir": "C:\\slave\\test", 08:29:57 INFO - "project": "", 08:29:57 INFO - "platform": "win7-ix", 08:29:57 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 08:29:57 INFO - "slavebuilddir": "test", 08:29:57 INFO - "scheduler": "tests-mozilla-inbound-win32-talos", 08:29:57 INFO - "branch": "mozilla-inbound", 08:29:57 INFO - "repo_path": "integration/mozilla-inbound", 08:29:57 INFO - "stage_platform": "win32", 08:29:57 INFO - "builduid": "8d2aa076bba649ff9a23e46722bb3cc9", 08:29:57 INFO - "revision": "18d52e7a0d80bda0507dca823806204b9595f2e9" 08:29:57 INFO - }, 08:29:57 INFO - "sourcestamp": { 08:29:57 INFO - "repository": "", 08:29:57 INFO - "hasPatch": false, 08:29:57 INFO - "project": "", 08:29:57 INFO - "branch": "mozilla-inbound-win32-talos", 08:29:57 INFO - "changes": [ 08:29:57 INFO - { 08:29:57 INFO - "category": null, 08:29:57 INFO - "files": [ 08:29:57 INFO - { 08:29:57 INFO - "url": null, 08:29:57 INFO - "name": "https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 08:29:57 INFO - } 08:29:57 INFO - ], 08:29:57 INFO - "repository": "", 08:29:57 INFO - "rev": "18d52e7a0d80bda0507dca823806204b9595f2e9", 08:29:57 INFO - "who": "jkew@mozilla.com", 08:29:57 INFO - "when": 1445872459, 08:29:57 INFO - "number": 6574009, 08:29:57 INFO - "comments": "Bug 1215428 - Update harfbuzz to release 1.0.6. r=jdaggett", 08:29:57 INFO - "project": "", 08:29:57 INFO - "at": "Mon 26 Oct 2015 08:14:19", 08:29:57 INFO - "branch": "mozilla-inbound-win32-talos", 08:29:57 INFO - "revlink": "", 08:29:57 INFO - "properties": [ 08:29:57 INFO - [ 08:29:57 INFO - "buildid", 08:29:57 INFO - "20151026064024", 08:29:57 INFO - "Change" 08:29:57 INFO - ], 08:29:57 INFO - [ 08:29:57 INFO - "builduid", 08:29:57 INFO - "8d2aa076bba649ff9a23e46722bb3cc9", 08:29:57 INFO - "Change" 08:29:57 INFO - ], 08:29:57 INFO - [ 08:29:57 INFO - "pgo_build", 08:29:57 INFO - "False", 08:29:57 INFO - "Change" 08:29:57 INFO - ] 08:29:57 INFO - ], 08:29:57 INFO - "revision": "18d52e7a0d80bda0507dca823806204b9595f2e9" 08:29:57 INFO - } 08:29:57 INFO - ], 08:29:57 INFO - "revision": "18d52e7a0d80bda0507dca823806204b9595f2e9" 08:29:57 INFO - } 08:29:57 INFO - } 08:29:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 08:29:57 INFO - Running post-action listener: _resource_record_post_action 08:29:57 INFO - ##### 08:29:57 INFO - ##### Running download-and-extract step. 08:29:57 INFO - ##### 08:29:57 INFO - Running pre-action listener: _resource_record_pre_action 08:29:57 INFO - Running main action method: download_and_extract 08:29:57 INFO - mkdir: C:\slave\test\build\tests 08:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:29:57 INFO - https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 08:29:57 INFO - trying https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/test_packages.json 08:29:57 INFO - Downloading https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json 08:29:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1 08:30:01 INFO - Downloaded 1221 bytes. 08:30:01 INFO - Reading from file C:\slave\test\build\test_packages.json 08:30:01 INFO - Using the following test package requirements: 08:30:01 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 08:30:01 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:30:01 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 08:30:01 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:30:01 INFO - u'jsshell-win32.zip'], 08:30:01 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:30:01 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 08:30:01 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 08:30:01 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:30:01 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 08:30:01 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:30:01 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 08:30:01 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:30:01 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 08:30:01 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 08:30:01 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 08:30:01 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 08:30:01 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 08:30: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:30:01 INFO - https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 08:30:01 INFO - trying https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 08:30:01 INFO - Downloading https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/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:30:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/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:30:02 INFO - Downloaded 18013447 bytes. 08:30: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:30:02 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 08:30: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:30:34 INFO - Return code: 0 08:30:34 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:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:30:34 INFO - https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 08:30:34 INFO - trying https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 08:30:34 INFO - Downloading https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/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:30:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/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:30:49 INFO - Downloaded 18013447 bytes. 08:30:49 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:30:49 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 08:30:49 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 08:31:13 INFO - Return code: 0 08:31:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:31:13 INFO - https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 08:31:13 INFO - trying https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 08:31:13 INFO - Downloading https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/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:31:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/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:31:27 INFO - Downloaded 11131307 bytes. 08:31:27 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:31:27 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.talos.tests.zip 08:31:27 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 08:31:28 INFO - Return code: 0 08:31:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:31:28 INFO - https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 08:31:28 INFO - trying https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 08:31:28 INFO - Downloading https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.zip 08:31:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/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:31:45 INFO - Downloaded 51502150 bytes. 08:31:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 08:31:45 INFO - mkdir: C:\slave\test\properties 08:31:45 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 08:31:45 INFO - Writing to file C:\slave\test\properties\build_url 08:31:45 INFO - Contents: 08:31:45 INFO - build_url:https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 08:31:45 INFO - Running post-action listener: _resource_record_post_action 08:31:45 INFO - Running post-action listener: set_extra_try_arguments 08:31:45 INFO - ##### 08:31:45 INFO - ##### Running populate-webroot step. 08:31:45 INFO - ##### 08:31:45 INFO - Running pre-action listener: _resource_record_pre_action 08:31:45 INFO - Running main action method: populate_webroot 08:31:45 INFO - Guessing talos json url... 08:31:45 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:31:45 INFO - https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 08:31:45 INFO - trying https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 08:31:45 INFO - Downloading https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 08:31:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/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:31:46 INFO - Downloaded 111 bytes. 08:31:46 INFO - Reading from file C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 08:31:46 INFO - Contents: 08:31:46 INFO - 20151026064024 08:31:46 INFO - https://hg.mozilla.org/integration/mozilla-inbound/rev/18d52e7a0d80bda0507dca823806204b9595f2e9 08:31: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:31:46 INFO - trying https://hg.mozilla.org/integration/mozilla-inbound/raw-file/18d52e7a0d/testing/talos/talos.json 08:31:46 INFO - Downloading https://hg.mozilla.org/integration/mozilla-inbound/raw-file/18d52e7a0d/testing/talos/talos.json to C:\slave\test\build\talos.json 08:31:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/mozilla-inbound/raw-file/18d52e7a0d/testing/talos/talos.json', 'file_name': 'C:\\slave\\test\\build\\talos.json'}, attempt #1 08:31:47 INFO - Downloaded 4264 bytes. 08:31:47 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 08:31:47 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 08:31:47 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 08:31:47 INFO - u'../fennec_ids.txt'], 08:31:47 INFO - u'tests': [u'tcheck2']}, 08:31:47 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 08:31:47 INFO - u'--tppagecycles', 08:31:47 INFO - u'7'], 08:31:47 INFO - u'tests': [u'tsvgm']}}, 08:31:47 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 08:31:47 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 08:31:47 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 08:31:47 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 08:31:47 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 08:31:47 INFO - u'tests': [u'dromaeo_css', 08:31:47 INFO - u'kraken', 08:31:47 INFO - u'v8_7']}, 08:31:47 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:47 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 08:31:47 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:47 INFO - u'talos_options': [u'--e10s'], 08:31:47 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 08:31:47 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:47 INFO - u'tests': [u'damp', u'tps']}, 08:31:47 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:47 INFO - u'talos_options': [u'--e10s'], 08:31:47 INFO - u'tests': [u'damp', u'tps']}, 08:31:47 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 08:31:47 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 08:31:47 INFO - u'other': {u'tests': [u'a11yr', 08:31:47 INFO - u'ts_paint', 08:31:47 INFO - u'tpaint', 08:31:47 INFO - u'sessionrestore', 08:31:47 INFO - u'sessionrestore_no_auto_restore']}, 08:31:47 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 08:31:47 INFO - u'tests': [u'a11yr', 08:31:47 INFO - u'ts_paint', 08:31:47 INFO - u'tpaint', 08:31:47 INFO - u'sessionrestore', 08:31:47 INFO - u'sessionrestore_no_auto_restore']}, 08:31:47 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 08:31:47 INFO - u'tests': [u'a11yr', 08:31:47 INFO - u'ts_paint', 08:31:47 INFO - u'tpaint', 08:31:47 INFO - u'sessionrestore', 08:31:47 INFO - u'sessionrestore_no_auto_restore']}, 08:31:47 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 08:31:47 INFO - u'tests': [u'a11yr', 08:31:47 INFO - u'ts_paint', 08:31:47 INFO - u'tpaint', 08:31:47 INFO - u'sessionrestore', 08:31:47 INFO - u'sessionrestore_no_auto_restore']}, 08:31:47 INFO - u'other_l64': {u'tests': [u'a11yr', 08:31:47 INFO - u'ts_paint', 08:31:47 INFO - u'tpaint', 08:31:47 INFO - u'sessionrestore', 08:31:47 INFO - u'sessionrestore_no_auto_restore']}, 08:31:47 INFO - u'other_nol64': {u'tests': [u'a11yr', 08:31:47 INFO - u'ts_paint', 08:31:47 INFO - u'tpaint', 08:31:47 INFO - u'sessionrestore', 08:31:47 INFO - u'sessionrestore_no_auto_restore']}, 08:31:47 INFO - u'svgr': {u'tests': [u'tsvgx', 08:31:47 INFO - u'tsvgr_opacity', 08:31:47 INFO - u'tart', 08:31:47 INFO - u'tscrollx', 08:31:47 INFO - u'cart']}, 08:31:47 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 08:31:47 INFO - u'tests': [u'tsvgx', 08:31:47 INFO - u'tsvgr_opacity', 08:31:47 INFO - u'tart', 08:31:47 INFO - u'tscrollx', 08:31:47 INFO - u'cart']}, 08:31:47 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:47 INFO - u'tests': [u'tp5o']}, 08:31:47 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:47 INFO - u'talos_options': [u'--e10s'], 08:31:47 INFO - u'tests': [u'tp5o']}, 08:31:47 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:47 INFO - u'talos_options': [u'--xperf_path', 08:31:47 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:31:47 INFO - u'tests': [u'tp5n']}, 08:31:47 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:31:47 INFO - u'talos_options': [u'--e10s', 08:31:47 INFO - u'--xperf_path', 08:31:47 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 08:31:47 INFO - u'C:/slave/talos-data/talos/xperf.config'], 08:31:47 INFO - u'tests': [u'tp5n']}}, 08:31:47 INFO - u'talos.zip': {u'path': u'', 08:31:47 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:31:47 INFO - Running post-action listener: _resource_record_post_action 08:31:47 INFO - ##### 08:31:47 INFO - ##### Running create-virtualenv step. 08:31:47 INFO - ##### 08:31:47 INFO - Running pre-action listener: _resource_record_pre_action 08:31:47 INFO - Running main action method: create_virtualenv 08:31:47 INFO - Creating virtualenv C:\slave\test\build/venv 08:31:47 INFO - mkdir: C:\slave\test\build\venv\Scripts 08:31:47 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 08:31:47 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:31:47 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:31:47 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:31:51 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 08:31:59 INFO - Installing distribute......................................................................................................................................................................................done. 08:32:03 INFO - Return code: 0 08:32:03 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 08:32:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:03 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 0x019E6B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A70660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-077', 'USERDOMAIN': 'T-W732-IX-077', '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-077', '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:32:03 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:32:03 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:32:03 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:32:03 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:32:03 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:32:03 INFO - 'COMPUTERNAME': 'T-W732-IX-077', 08:32:03 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:32:03 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:32:03 INFO - 'DCLOCATION': 'SCL3', 08:32:03 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:32:03 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:32:03 INFO - 'HOMEDRIVE': 'C:', 08:32:03 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:32:03 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:32:03 INFO - 'KTS_VERSION': '1.19c', 08:32:03 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:32:03 INFO - 'LOGONSERVER': '\\\\T-W732-IX-077', 08:32:03 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:32:03 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:32:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:03 INFO - 'MOZ_NO_REMOTE': '1', 08:32:03 INFO - 'NO_EM_RESTART': '1', 08:32:03 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:32:03 INFO - 'OS': 'Windows_NT', 08:32:03 INFO - 'OURDRIVE': 'C:', 08:32:03 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:32:03 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:32:03 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:32:03 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:32:03 INFO - 'PROCESSOR_LEVEL': '6', 08:32:03 INFO - 'PROCESSOR_REVISION': '1e05', 08:32:03 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:32:03 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:32:03 INFO - 'PROMPT': '$P$G', 08:32:03 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:32:03 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:32:03 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:32:03 INFO - 'PWD': 'C:\\slave\\test', 08:32:03 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:32:03 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:32:03 INFO - 'SYSTEMDRIVE': 'C:', 08:32:03 INFO - 'SYSTEMROOT': 'C:\\windows', 08:32:03 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:03 INFO - 'TEST1': 'testie', 08:32:03 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:03 INFO - 'USERDOMAIN': 'T-W732-IX-077', 08:32:03 INFO - 'USERNAME': 'cltbld', 08:32:03 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:32:03 INFO - 'WINDIR': 'C:\\windows', 08:32:03 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:32:03 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:32:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:32:07 INFO - Ignoring indexes: http://pypi.python.org/simple/ 08:32:07 INFO - Downloading/unpacking pip>=1.5 08:32:07 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache 08:32:16 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:32:16 INFO - Running setup.py egg_info for package pip 08:32:16 INFO - warning: no files found matching 'pip\cacert.pem' 08:32:16 INFO - warning: no files found matching '*.html' under directory 'docs' 08:32:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 08:32:16 INFO - no previously-included directories found matching 'docs\_build\_sources' 08:32:16 INFO - Installing collected packages: pip 08:32:16 INFO - Found existing installation: pip 0.8.2 08:32:16 INFO - Uninstalling pip: 08:32:16 INFO - Successfully uninstalled pip 08:32:16 INFO - Running setup.py install for pip 08:32:16 INFO - warning: no files found matching 'pip\cacert.pem' 08:32:16 INFO - warning: no files found matching '*.html' under directory 'docs' 08:32:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 08:32:16 INFO - no previously-included directories found matching 'docs\_build\_sources' 08:32:16 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 08:32:16 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 08:32:16 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 08:32:16 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 08:32:16 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 08:32:16 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 08:32:16 INFO - Successfully installed pip 08:32:16 INFO - Cleaning up... 08:32:16 INFO - Return code: 0 08:32:16 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv 08:32:16 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:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:16 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:32:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:16 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 0x019E6B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A70660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-077', 'USERDOMAIN': 'T-W732-IX-077', '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-077', '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:32:16 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:32:16 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:32:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:32:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:32:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:32:16 INFO - 'COMPUTERNAME': 'T-W732-IX-077', 08:32:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:32:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:32:16 INFO - 'DCLOCATION': 'SCL3', 08:32:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:32:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:32:16 INFO - 'HOMEDRIVE': 'C:', 08:32:16 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:32:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:32:16 INFO - 'KTS_VERSION': '1.19c', 08:32:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:32:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-077', 08:32:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:32:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:32:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:16 INFO - 'MOZ_NO_REMOTE': '1', 08:32:16 INFO - 'NO_EM_RESTART': '1', 08:32:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:32:16 INFO - 'OS': 'Windows_NT', 08:32:16 INFO - 'OURDRIVE': 'C:', 08:32:16 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:32:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:32:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:32:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:32:16 INFO - 'PROCESSOR_LEVEL': '6', 08:32:16 INFO - 'PROCESSOR_REVISION': '1e05', 08:32:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:32:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:32:16 INFO - 'PROMPT': '$P$G', 08:32:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:32:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:32:16 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:32:16 INFO - 'PWD': 'C:\\slave\\test', 08:32:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:32:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:32:16 INFO - 'SYSTEMDRIVE': 'C:', 08:32:16 INFO - 'SYSTEMROOT': 'C:\\windows', 08:32:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:16 INFO - 'TEST1': 'testie', 08:32:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:16 INFO - 'USERDOMAIN': 'T-W732-IX-077', 08:32:16 INFO - 'USERNAME': 'cltbld', 08:32:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:32:16 INFO - 'WINDIR': 'C:\\windows', 08:32:16 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:32:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:32:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:19 INFO - Downloading/unpacking psutil>=0.7.1 08:32:19 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:32:19 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:32:23 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:32:23 INFO - Installing collected packages: psutil 08:32:23 INFO - Successfully installed psutil 08:32:23 INFO - Cleaning up... 08:32:23 INFO - Return code: 0 08:32:23 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv 08:32:23 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:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:23 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:32:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:23 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 0x019E6B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A70660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-077', 'USERDOMAIN': 'T-W732-IX-077', '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-077', '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:32:23 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:32:23 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:32:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:32:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:32:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:32:23 INFO - 'COMPUTERNAME': 'T-W732-IX-077', 08:32:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:32:23 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:32:23 INFO - 'DCLOCATION': 'SCL3', 08:32:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:32:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:32:23 INFO - 'HOMEDRIVE': 'C:', 08:32:23 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:32:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:32:23 INFO - 'KTS_VERSION': '1.19c', 08:32:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:32:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-077', 08:32:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:32:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:32:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:23 INFO - 'MOZ_NO_REMOTE': '1', 08:32:23 INFO - 'NO_EM_RESTART': '1', 08:32:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:32:23 INFO - 'OS': 'Windows_NT', 08:32:23 INFO - 'OURDRIVE': 'C:', 08:32:23 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:32:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:32:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:32:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:32:23 INFO - 'PROCESSOR_LEVEL': '6', 08:32:23 INFO - 'PROCESSOR_REVISION': '1e05', 08:32:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:32:23 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:32:23 INFO - 'PROMPT': '$P$G', 08:32:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:32:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:32:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:32:23 INFO - 'PWD': 'C:\\slave\\test', 08:32:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:32:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:32:23 INFO - 'SYSTEMDRIVE': 'C:', 08:32:23 INFO - 'SYSTEMROOT': 'C:\\windows', 08:32:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:23 INFO - 'TEST1': 'testie', 08:32:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:23 INFO - 'USERDOMAIN': 'T-W732-IX-077', 08:32:23 INFO - 'USERNAME': 'cltbld', 08:32:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:32:23 INFO - 'WINDIR': 'C:\\windows', 08:32:23 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:32:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:32:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:32:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:32:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:26 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:32:26 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:32:26 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 08:32:26 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:32:26 INFO - Installing collected packages: mozsystemmonitor 08:32:26 INFO - Running setup.py install for mozsystemmonitor 08:32:26 INFO - Successfully installed mozsystemmonitor 08:32:26 INFO - Cleaning up... 08:32:26 INFO - Return code: 0 08:32:26 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 08:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:32:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:26 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 0x019E6B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A70660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-077', 'USERDOMAIN': 'T-W732-IX-077', '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-077', '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:32:26 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:32:26 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:32:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:32:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:32:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:32:26 INFO - 'COMPUTERNAME': 'T-W732-IX-077', 08:32:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:32:26 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:32:26 INFO - 'DCLOCATION': 'SCL3', 08:32:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:32:26 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:32:26 INFO - 'HOMEDRIVE': 'C:', 08:32:26 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:32:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:32:26 INFO - 'KTS_VERSION': '1.19c', 08:32:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:32:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-077', 08:32:26 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:32:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:26 INFO - 'MOZ_NO_REMOTE': '1', 08:32:26 INFO - 'NO_EM_RESTART': '1', 08:32:26 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:32:26 INFO - 'OS': 'Windows_NT', 08:32:26 INFO - 'OURDRIVE': 'C:', 08:32:26 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:32:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:32:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:32:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:32:26 INFO - 'PROCESSOR_LEVEL': '6', 08:32:26 INFO - 'PROCESSOR_REVISION': '1e05', 08:32:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:32:26 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:32:26 INFO - 'PROMPT': '$P$G', 08:32:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:32:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:32:26 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:32:26 INFO - 'PWD': 'C:\\slave\\test', 08:32:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:32:26 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:32:26 INFO - 'SYSTEMDRIVE': 'C:', 08:32:26 INFO - 'SYSTEMROOT': 'C:\\windows', 08:32:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:26 INFO - 'TEST1': 'testie', 08:32:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:26 INFO - 'USERDOMAIN': 'T-W732-IX-077', 08:32:26 INFO - 'USERNAME': 'cltbld', 08:32:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:32:26 INFO - 'WINDIR': 'C:\\windows', 08:32:26 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:32:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:32:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:27 INFO - Downloading/unpacking blobuploader==1.2.4 08:32:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:32:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:32:30 INFO - Downloading blobuploader-1.2.4.tar.gz 08:32:30 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:32:30 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 08:32:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:32:30 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:32:30 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:32:32 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:32:32 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 08:32:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:32:33 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:32:33 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:32:33 INFO - Downloading docopt-0.6.1.tar.gz 08:32:33 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:32:33 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 08:32:33 INFO - Installing collected packages: blobuploader, requests, docopt 08:32:33 INFO - Running setup.py install for blobuploader 08:32:33 INFO - Running setup.py install for requests 08:32:34 INFO - Running setup.py install for docopt 08:32:34 INFO - Successfully installed blobuploader requests docopt 08:32:34 INFO - Cleaning up... 08:32:35 INFO - Return code: 0 08:32:35 INFO - Installing None into virtualenv C:\slave\test\build/venv 08:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32:35 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 0x019E6B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A70660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-077', 'USERDOMAIN': 'T-W732-IX-077', '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-077', '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:32:35 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:32:35 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:32:35 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:32:35 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:32:35 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:32:35 INFO - 'COMPUTERNAME': 'T-W732-IX-077', 08:32:35 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:32:35 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:32:35 INFO - 'DCLOCATION': 'SCL3', 08:32:35 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:32:35 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:32:35 INFO - 'HOMEDRIVE': 'C:', 08:32:35 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:32:35 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:32:35 INFO - 'KTS_VERSION': '1.19c', 08:32:35 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:32:35 INFO - 'LOGONSERVER': '\\\\T-W732-IX-077', 08:32:35 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:32:35 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:35 INFO - 'MOZ_NO_REMOTE': '1', 08:32:35 INFO - 'NO_EM_RESTART': '1', 08:32:35 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:32:35 INFO - 'OS': 'Windows_NT', 08:32:35 INFO - 'OURDRIVE': 'C:', 08:32:35 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 08:32:35 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:32:35 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:32:35 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:32:35 INFO - 'PROCESSOR_LEVEL': '6', 08:32:35 INFO - 'PROCESSOR_REVISION': '1e05', 08:32:35 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:32:35 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:32:35 INFO - 'PROMPT': '$P$G', 08:32:35 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:32:35 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:32:35 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:32:35 INFO - 'PWD': 'C:\\slave\\test', 08:32:35 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:32:35 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:32:35 INFO - 'SYSTEMDRIVE': 'C:', 08:32:35 INFO - 'SYSTEMROOT': 'C:\\windows', 08:32:35 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:35 INFO - 'TEST1': 'testie', 08:32:35 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:35 INFO - 'USERDOMAIN': 'T-W732-IX-077', 08:32:35 INFO - 'USERNAME': 'cltbld', 08:32:35 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:32:35 INFO - 'WINDIR': 'C:\\windows', 08:32:35 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:32:35 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 08:32:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zizgd5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 08:32:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 08:32:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-odnk3o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 08:32:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 08:32:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8tm3rm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 08:32:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 08:32:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sy0xcm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 08:32:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 08:32:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-4hsuqr-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 08:32:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 08:32:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qkrvq4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 08:32:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 08:32:36 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wjwhkk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 08:32:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 08:32:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nbcw_4-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 08:32:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 08:32:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8qnytg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 08:32:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 08:32:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rqgzri-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 08:32:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 08:32:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bl7m7o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 08:32:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 08:32:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9bt8xg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 08:32:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 08:32:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0maepi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 08:32:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 08:32:37 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-veuqfk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 08:32:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 08:32:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jgu48c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 08:32:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 08:32:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-vqpr6r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 08:32:38 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 08:32:38 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wubcz7-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 08:32:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:32:38 INFO - Running setup.py install for manifestparser 08:32:38 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 08:32:38 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 08:32:39 INFO - Running setup.py install for mozcrash 08:32:39 INFO - Running setup.py install for mozdebug 08:32:39 INFO - Running setup.py install for mozdevice 08:32:40 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 08:32:40 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 08:32:40 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 08:32:40 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 08:32:40 INFO - Running setup.py install for mozfile 08:32:40 INFO - Running setup.py install for mozhttpd 08:32:41 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 08:32:41 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 08:32:41 INFO - Running setup.py install for mozinfo 08:32:41 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 08:32:41 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 08:32:41 INFO - Running setup.py install for mozInstall 08:32:41 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 08:32:41 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 08:32:41 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 08:32:41 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 08:32:41 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 08:32:41 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 08:32:41 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 08:32:41 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 08:32:41 INFO - Running setup.py install for mozleak 08:32:42 INFO - Running setup.py install for mozlog 08:32:42 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 08:32:42 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 08:32:43 INFO - Running setup.py install for moznetwork 08:32:43 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 08:32:43 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 08:32:43 INFO - Running setup.py install for mozprocess 08:32:44 INFO - Running setup.py install for mozprofile 08:32:44 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 08:32:44 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 08:32:44 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 08:32:44 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 08:32:44 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 08:32:44 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 08:32:44 INFO - Running setup.py install for mozrunner 08:32:45 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 08:32:45 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 08:32:45 INFO - Running setup.py install for mozscreenshot 08:32:45 INFO - Running setup.py install for moztest 08:32:46 INFO - Running setup.py install for mozversion 08:32:46 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 08:32:46 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 08:32:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:32:46 INFO - Cleaning up... 08:32:46 INFO - Return code: 0 08:32:46 INFO - Installing None into virtualenv C:\slave\test\build/venv 08:32: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:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32: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:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32: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', '-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 0x019E6B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A70660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-077', 'USERDOMAIN': 'T-W732-IX-077', '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-077', '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:32:46 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:32:46 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:32:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:32:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:32:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:32:46 INFO - 'COMPUTERNAME': 'T-W732-IX-077', 08:32:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:32:46 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:32:46 INFO - 'DCLOCATION': 'SCL3', 08:32:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:32:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:32:46 INFO - 'HOMEDRIVE': 'C:', 08:32:46 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:32:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:32:46 INFO - 'KTS_VERSION': '1.19c', 08:32:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:32:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-077', 08:32:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:32:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:46 INFO - 'MOZ_NO_REMOTE': '1', 08:32:46 INFO - 'NO_EM_RESTART': '1', 08:32:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:32:46 INFO - 'OS': 'Windows_NT', 08:32:46 INFO - 'OURDRIVE': 'C:', 08:32: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:32:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:32:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:32:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:32:46 INFO - 'PROCESSOR_LEVEL': '6', 08:32:46 INFO - 'PROCESSOR_REVISION': '1e05', 08:32:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:32:46 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:32:46 INFO - 'PROMPT': '$P$G', 08:32:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:32:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:32:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:32:46 INFO - 'PWD': 'C:\\slave\\test', 08:32:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:32:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:32:46 INFO - 'SYSTEMDRIVE': 'C:', 08:32:46 INFO - 'SYSTEMROOT': 'C:\\windows', 08:32:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:46 INFO - 'TEST1': 'testie', 08:32:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:46 INFO - 'USERDOMAIN': 'T-W732-IX-077', 08:32:46 INFO - 'USERNAME': 'cltbld', 08:32:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:32:46 INFO - 'WINDIR': 'C:\\windows', 08:32:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:32:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:46 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 08:32:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5vitmq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 08:32:47 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:32:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 08:32:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hpt12p-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 08:32:47 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:32:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 08:32:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rhxptw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 08:32:47 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:32:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 08:32:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nu8pxd-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 08:32:47 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:32:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 08:32:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zif2qs-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 08:32:47 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:32:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 08:32:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-i6gzgo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 08:32:47 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:32:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 08:32:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dossgm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 08:32:47 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:32:47 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 08:32:47 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fysdol-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 08:32:48 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:32:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 08:32:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-avxhtf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 08:32:48 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:32:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 08:32:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-prm6cq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 08:32:48 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:32:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 08:32:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bf1f8x-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 08:32:48 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:32:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 08:32:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-h2goph-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 08:32:48 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:32:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 08:32:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ldziq0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 08:32:48 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:32:48 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 08:32:48 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ebafhm-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 08:32:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 08:32:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 08:32:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tfrsjc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 08:32:49 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:32:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 08:32:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lipdws-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 08:32:49 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:32:49 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 08:32:49 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yhdjjn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 08:32:49 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:32:49 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:32:49 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:32:49 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:32:49 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:32:49 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:32:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 08:32:49 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:32:49 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:32:51 INFO - Downloading blessings-1.5.1.tar.gz 08:32:51 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:32:51 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 08:32:52 INFO - Installing collected packages: blessings 08:32:52 INFO - Running setup.py install for blessings 08:32:52 INFO - Successfully installed blessings 08:32:52 INFO - Cleaning up... 08:32:55 INFO - Return code: 0 08:32:55 INFO - Done creating virtualenv C:\slave\test\build/venv. 08:32:55 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 08:32:55 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 08:32:55 INFO - Reading from file tmpfile_stdout 08:32:55 INFO - Using _rmtree_windows ... 08:32:55 INFO - Using _rmtree_windows ... 08:32:55 INFO - Current package versions: 08:32:55 INFO - blessings == 1.5.1 08:32:55 INFO - blobuploader == 1.2.4 08:32:55 INFO - docopt == 0.6.1 08:32:55 INFO - manifestparser == 1.1 08:32:55 INFO - mozInstall == 1.12 08:32:55 INFO - mozcrash == 0.16 08:32:55 INFO - mozdebug == 0.1 08:32:55 INFO - mozdevice == 0.46 08:32:55 INFO - mozfile == 1.2 08:32:55 INFO - mozhttpd == 0.7 08:32:55 INFO - mozinfo == 0.8 08:32:55 INFO - mozleak == 0.1 08:32:55 INFO - mozlog == 3.0 08:32:55 INFO - moznetwork == 0.27 08:32:55 INFO - mozprocess == 0.22 08:32:55 INFO - mozprofile == 0.27 08:32:55 INFO - mozrunner == 6.11 08:32:55 INFO - mozscreenshot == 0.1 08:32:55 INFO - mozsystemmonitor == 0.0 08:32:55 INFO - moztest == 0.7 08:32:55 INFO - mozversion == 1.4 08:32:55 INFO - psutil == 3.1.1 08:32:55 INFO - requests == 1.2.3 08:32:55 INFO - Installing None into virtualenv C:\slave\test\build/venv 08:32: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:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:32: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:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:32: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', '-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 0x019E6B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x019A7EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x018EB5C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A70660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-077', 'USERDOMAIN': 'T-W732-IX-077', '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-077', '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:32:55 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:32:55 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:32:55 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:32:55 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:32:55 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:32:55 INFO - 'COMPUTERNAME': 'T-W732-IX-077', 08:32:55 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:32:55 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:32:55 INFO - 'DCLOCATION': 'SCL3', 08:32:55 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:32:55 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:32:55 INFO - 'HOMEDRIVE': 'C:', 08:32:55 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:32:55 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:32:55 INFO - 'KTS_VERSION': '1.19c', 08:32:55 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:32:55 INFO - 'LOGONSERVER': '\\\\T-W732-IX-077', 08:32:55 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:32:55 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:32:55 INFO - 'MOZ_NO_REMOTE': '1', 08:32:55 INFO - 'NO_EM_RESTART': '1', 08:32:55 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:32:55 INFO - 'OS': 'Windows_NT', 08:32:55 INFO - 'OURDRIVE': 'C:', 08:32: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:32:55 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:32:55 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:32:55 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:32:55 INFO - 'PROCESSOR_LEVEL': '6', 08:32:55 INFO - 'PROCESSOR_REVISION': '1e05', 08:32:55 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:32:55 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:32:55 INFO - 'PROMPT': '$P$G', 08:32:55 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:32:55 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:32:55 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:32:55 INFO - 'PWD': 'C:\\slave\\test', 08:32:55 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:32:55 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:32:55 INFO - 'SYSTEMDRIVE': 'C:', 08:32:55 INFO - 'SYSTEMROOT': 'C:\\windows', 08:32:55 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:55 INFO - 'TEST1': 'testie', 08:32:55 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:32:55 INFO - 'USERDOMAIN': 'T-W732-IX-077', 08:32:55 INFO - 'USERNAME': 'cltbld', 08:32:55 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:32:55 INFO - 'WINDIR': 'C:\\windows', 08:32:55 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:32:55 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:32:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:32:55 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:32:55 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:32:55 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:32:55 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:32:55 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:32:55 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:32:55 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:32:55 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:32:55 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:32:55 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:32:55 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:32:55 INFO - Cleaning up... 08:32:55 INFO - Return code: 0 08:32:55 INFO - Running post-action listener: _resource_record_post_action 08:32:55 INFO - Running post-action listener: _start_resource_monitoring 08:32:55 INFO - Starting resource monitoring. 08:32:56 INFO - ##### 08:32:56 INFO - ##### Running install step. 08:32:56 INFO - ##### 08:32:56 INFO - Running pre-action listener: _resource_record_pre_action 08:32:56 INFO - Running main action method: install 08:32:56 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 08:32:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 08:32:56 INFO - Reading from file tmpfile_stdout 08:32:56 INFO - Using _rmtree_windows ... 08:32:56 INFO - Using _rmtree_windows ... 08:32:56 INFO - Detecting whether we're running mozinstall >=1.0... 08:32:56 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 08:32:56 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 08:32:56 INFO - Reading from file tmpfile_stdout 08:32:56 INFO - Output received: 08:32:56 INFO - Usage: mozinstall-script.py [options] installer 08:32:56 INFO - Options: 08:32:56 INFO - -h, --help show this help message and exit 08:32:56 INFO - -d DEST, --destination=DEST 08:32:56 INFO - Directory to install application into. [default: 08:32:56 INFO - "C:\slave\test"] 08:32:56 INFO - --app=APP Application being installed. [default: firefox] 08:32:56 INFO - Using _rmtree_windows ... 08:32:56 INFO - Using _rmtree_windows ... 08:32:56 INFO - mkdir: C:\slave\test\build\application 08:32:56 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:32:56 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:32:57 INFO - Reading from file tmpfile_stdout 08:32:57 INFO - Output received: 08:32:57 INFO - C:\slave\test\build\application\firefox\firefox.exe 08:32:57 INFO - Using _rmtree_windows ... 08:32:57 INFO - Using _rmtree_windows ... 08:32:57 INFO - Running post-action listener: _resource_record_post_action 08:32:57 INFO - ##### 08:32:57 INFO - ##### Running run-tests step. 08:32:57 INFO - ##### 08:32:57 INFO - Running pre-action listener: _resource_record_pre_action 08:32:57 INFO - Running main action method: run_tests 08:32:57 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 08:32:57 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 08:32:57 INFO - Python 2.7.3 08:32:57 INFO - Return code: 0 08:32:57 INFO - grabbing minidump binary from tooltool 08:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:32:57 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x012B8B48>, '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 0x012DA700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A70660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 08:32:57 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build 08:32:57 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o 08:32:57 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 08:33:02 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmp34py4b 08:33:02 INFO - INFO - File integrity verified, renaming tmp34py4b to win32-minidump_stackwalk.exe 08:33:02 INFO - Return code: 0 08:33:02 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 08:33:02 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 08:33:02 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 08:33:02 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 08:33:02 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 08:33:02 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/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-077', '--webServer', 'localhost'] in C:\slave\test\build 08:33:02 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/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-077 --webServer localhost 08:33:02 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 08:33:02 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 08:33:02 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 08:33:02 INFO - 'COMPUTERNAME': 'T-W732-IX-077', 08:33:02 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 08:33:02 INFO - 'CYGWINBASE': 'C:\\cygwin', 08:33:02 INFO - 'DCLOCATION': 'SCL3', 08:33:02 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 08:33:02 INFO - 'FP_NO_HOST_CHECK': 'NO', 08:33:02 INFO - 'HOMEDRIVE': 'C:', 08:33:02 INFO - 'HOMEPATH': '\\Users\\cltbld', 08:33:02 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 08:33:02 INFO - 'KTS_VERSION': '1.19c', 08:33:02 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 08:33:02 INFO - 'LOGONSERVER': '\\\\T-W732-IX-077', 08:33:02 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 08:33:02 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 08:33:02 INFO - 'MONDIR': 'C:\\Monitor_config\\', 08:33:02 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 08:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:33:02 INFO - 'MOZ_NO_REMOTE': '1', 08:33:02 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 08:33:02 INFO - 'NO_EM_RESTART': '1', 08:33:02 INFO - 'NUMBER_OF_PROCESSORS': '8', 08:33:02 INFO - 'OS': 'Windows_NT', 08:33:02 INFO - 'OURDRIVE': 'C:', 08:33:02 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:33:02 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 08:33:02 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 08:33:02 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 08:33:02 INFO - 'PROCESSOR_LEVEL': '6', 08:33:02 INFO - 'PROCESSOR_REVISION': '1e05', 08:33:02 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 08:33:02 INFO - 'PROGRAMFILES': 'C:\\Program Files', 08:33:02 INFO - 'PROMPT': '$P$G', 08:33:02 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 08:33:02 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 08:33:02 INFO - 'PUBLIC': 'C:\\Users\\Public', 08:33:02 INFO - 'PWD': 'C:\\slave\\test', 08:33:02 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 08:33:02 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 08:33:02 INFO - 'SLAVEDIR': 'C:\\slave\\', 08:33:02 INFO - 'SYSTEMDRIVE': 'C:', 08:33:02 INFO - 'SYSTEMROOT': 'C:\\windows', 08:33:02 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:33:02 INFO - 'TEST1': 'testie', 08:33:02 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 08:33:02 INFO - 'USERDOMAIN': 'T-W732-IX-077', 08:33:02 INFO - 'USERNAME': 'cltbld', 08:33:02 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 08:33:02 INFO - 'WINDIR': 'C:\\windows', 08:33:02 INFO - 'WINDOWS_TRACING_FLAGS': '3', 08:33:02 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 08:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 08:33:02 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/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-077', '--webServer', 'localhost'] with output_timeout 3600 08:33:03 INFO - mozversion INFO | application_buildid: 20151026064024 08:33:03 INFO - mozversion INFO | application_changeset: 18d52e7a0d80bda0507dca823806204b9595f2e9 08:33:03 INFO - mozversion INFO | application_display_name: Nightly 08:33:03 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:33:03 INFO - mozversion INFO | application_name: Firefox 08:33:03 INFO - mozversion INFO | application_remotingname: firefox 08:33:03 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound 08:33:03 INFO - mozversion INFO | application_vendor: Mozilla 08:33:03 INFO - mozversion INFO | application_version: 44.0a1 08:33:03 INFO - mozversion INFO | platform_buildid: 20151026064024 08:33:03 INFO - mozversion INFO | platform_changeset: 18d52e7a0d80bda0507dca823806204b9595f2e9 08:33:03 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 08:33:03 INFO - mozversion INFO | platform_version: 44.0a1 08:33:03 INFO - 2015-10-26 08:33:03,473 DEBUG : using testdate: 1445873583 08:33:03 INFO - 2015-10-26 08:33:03,473 DEBUG : actual date: 1445873583 08:33:03 INFO - 2015-10-26 08:33:03,592 INFO : starting webserver on 'localhost:49703' 08:33:03 INFO - 2015-10-26 08:33:03,595 INFO : Starting test suite T-W732-IX-077 08:33:03 INFO - 2015-10-26 08:33:03,595 INFO : Starting test dromaeo_css 08:33:03 INFO - 2015-10-26 08:33:03,595 DEBUG : operating with platform_type : w7_ 08:33:03 INFO - 2015-10-26 08:33:03,595 INFO : Initialising browser for dromaeo_css test... 08:33:03 INFO - 2015-10-26 08:33:03,686 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpariri_\profile http://localhost:49703/getInfo.html 08:33:11 INFO - 2015-10-26 08:33:11,914 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:33:11 INFO - 2015-10-26 08:33:11,914 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:33:11 INFO - 2015-10-26 08:33:11,914 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 08:33:11 INFO - 2015-10-26 08:33:11,914 DEBUG : BROWSER_OUTPUT: __metrics 08:33:12 INFO - 2015-10-26 08:33:12,029 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:33:13 INFO - 2015-10-26 08:33:13,780 INFO : Browser initialized. 08:33:13 INFO - 2015-10-26 08:33:13,780 INFO : Running cycle 1/1 for dromaeo_css test... 08:33:13 INFO - 2015-10-26 08:33:13,780 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpariri_\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:33:25 INFO - 2015-10-26 08:33:25,424 DEBUG : BROWSER_OUTPUT: RSS: Main: 141361152 08:33:25 INFO - 2015-10-26 08:33:25,424 DEBUG : BROWSER_OUTPUT: 08:34:18 INFO - 2015-10-26 08:34:18,198 DEBUG : BROWSER_OUTPUT: 1445873658204 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:36:29 INFO - 2015-10-26 08:36:29,834 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49703/tests/dromaeo/cssquery-ext.html) 08:36:30 INFO - 2015-10-26 08:36:30,841 DEBUG : BROWSER_OUTPUT: RSS: Main: 178167808 08:36:30 INFO - 2015-10-26 08:36:30,841 DEBUG : BROWSER_OUTPUT: 08:39:33 INFO - 2015-10-26 08:39:33,655 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/dromaeo/cssquery-ext.html (next: http://localhost:49703/tests/dromaeo/cssquery-jquery.html) 08:39:34 INFO - 2015-10-26 08:39:34,223 DEBUG : BROWSER_OUTPUT: RSS: Main: 206548992 08:39:34 INFO - 2015-10-26 08:39:34,223 DEBUG : BROWSER_OUTPUT: 08:42:36 INFO - 2015-10-26 08:42:36,420 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49703/tests/dromaeo/cssquery-mootools.html) 08:42:37 INFO - 2015-10-26 08:42:37,003 DEBUG : BROWSER_OUTPUT: RSS: Main: 148017152 08:42:37 INFO - 2015-10-26 08:42:37,003 DEBUG : BROWSER_OUTPUT: 08:45:39 INFO - 2015-10-26 08:45:39,147 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49703/tests/dromaeo/cssquery-prototype.html) 08:45:39 INFO - 2015-10-26 08:45:39,730 DEBUG : BROWSER_OUTPUT: RSS: Main: 142856192 08:45:39 INFO - 2015-10-26 08:45:39,730 DEBUG : BROWSER_OUTPUT: 08:48:42 INFO - 2015-10-26 08:48:42,819 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49703/tests/dromaeo/cssquery-yui.html) 08:48:43 INFO - 2015-10-26 08:48:43,387 DEBUG : BROWSER_OUTPUT: RSS: Main: 138088448 08:48:43 INFO - 2015-10-26 08:48:43,387 DEBUG : BROWSER_OUTPUT: 08:51:46 INFO - 2015-10-26 08:51:46,257 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/dromaeo/cssquery-yui.html (next: http://localhost:49703/tests/dromaeo/cssquery-dojo.html) 08:51:46 INFO - 2015-10-26 08:51:46,505 DEBUG : BROWSER_OUTPUT: RSS: Main: 191541248 08:51:46 INFO - 2015-10-26 08:51:46,505 DEBUG : BROWSER_OUTPUT: 08:51:46 INFO - 2015-10-26 08:51:46,506 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:51:46 INFO - 2015-10-26 08:51:46,506 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:51:46 INFO - 2015-10-26 08:51:46,506 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:51:46 INFO - 2015-10-26 08:51:46,506 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:51:46 INFO - 2015-10-26 08:51:46,506 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2307.803836673883;2319.4317392238895;2390.6772585700924;2400.7119145702513;2401.4836810085826;4000.779957102362;4167.562405947374;4170.624643782059;4225.93661095083;4231.34414165804;4090.1206240658253;4145.170965806836;4232.343986682062;4244.639205667514;4250.936235956463;3581.1226249568763;3670.963290367089;3679.871204507836;3700.2599480103954;3735.1595890924596;3654.798986055766;3692.8707495237672;3720.162963333242;3721;3725.1618385863235;3706.4810926470313;3707.2214834884708;3719.1260053887313;3721.776693398405;3724.3854763963914;3652.0687224757517;3656.780593164392;3693.7968411737343;3722.7766334020043;3763.209725957552;3714.164313029574;3752.9437058444146;3756.7558108722847;3790.0714324990277;3790.3177428062936;4096.754194748325;4135.710500264982;4156.501219853607;4186.183694179636;4237.618614324695;4180.644645205161;4412.889677758049;4424.203643344197;4428.512863585003;4515.277555591089;4449.7997590108525;4459.175052615276;4479.9552004479865;4548.0903819235955;4552.590266875997;3920.010331502151;4130.56629053951;4221.366794980716;4224.408582798411;4235.724677895927;4331.047169622678;4344.370066340363;4451.10977804434;4464.687471876938;4538.977305113453;4307.65538756905;4315.503717072577;4367.995361066909;4368.191884501377;4387.166438376698;4378.7372757634;4379.386885835986;4456.509783923766;4458.197524445566;4464.285714285764;4217.536071032184;4315.050688848449;4329.372241025034;4370.759608221579;4486.102779444124;4250.277452833026;4250.511191212988;4340.8697739067875;4345.630621397154;4431.667624928144;3076.415481058592;3130.1861516005547;3133.9686603134132;3145.795523290979;3148.3231105312466;2800.6219160413084;2826.674932382761;2849.3873817129415;2876.4103358811394;2882.6540815101903;4110.958890411118;4169.666426685858;4180.414741936132;4209.621134097946;4238.19474299882;4396.472423309224;4416.094700586372;4511.368408422823;4514.706544074625;4525.072360166158;4033.415154802537;4124.8143833527565;4136.152088821845;4141.068259641556;4227.51383590891;3585.2829434113282;3773.1510410157493;3794.5256842894087;3795.9620403795607;3797.734158608871;4496.547770259882;4507;4618.053299073682;4626.421697287839;4638.582527572535;4276.722013069265;4384.188925048876;4395.824167033283;4398.252297109435;4464.799084041291;4537.2513535267635;4556.339330797082;4633.86098417048;4638.5593368629925;4638.930416043694;4117.341225403921;4243.257429949685;4376.452943382077;4443.533428989909;4471.888202794956;4059.4316795649224;4234.640055595309;4341.196878577473;4417.249067658441;4444.244478438609;4291.485021797404;4317.568243175658;4356.520782713962;4386.627136693289;4403.559644035571;4308.978455107705;4386.166628340733;4388.166248412791;4389.62688171509;4398.758068306274;4008.679305655482;4040.8383664653084;4065.7357271778424;4130.56629053945;4170.14512025028;4286.185624731291;4299.978500107604;4340.262155433647;4400.141972315496;4416.293393057095;4255.851045213402;4263.5096963849555;4342.261815491311;4351.651867850501;4354.216241076637 08:51:46 INFO - 2015-10-26 08:51:46,507 DEBUG : BROWSER_OUTPUT: |1;ext.html;484.3461327208268;485.9999999999998;490.570750593231;490.636928672782;523.9056969745444;26036.218913432604;33733.32533349335;36326.81836590817;37141;37220.62779372205;38581.527662510365;39249.21501569967;39258.60741392585;39386;39407;6235.095911092889;6354.459870910978;7909.841803163933;7926.48477848938;8092.069412017634;9996.400215987027;10303.63937262196;10332.845007324897;10353.534090965886;10366.481675916173;9467.66863159786;10041.347312424705;10167.4916254187;10188.64339748109;10201.081902628763;5842.328132264759;6160.383961603826;9937.453440060794;10172.135368493688;10505.212109091848;9629.133377995944;9786.1192492676;9876.555555000043;10015.148712359458;10058.497075146213;35887;36137.0965725859;36234.63765362339;36330.00369959302;36395.818020909726;2838.5174520331593;2875.4536638038708;2964.333025069364;3096.7677424193275;3303.3228188221356;27072.511011894338;27575.448491030267;27627.447451050866;28088.43823123526;28112.578311325346;2017.8385729141633;2050.241410678059;2099.8215151712125;2113.4927617371873;2125.606762748881;224922;230186;235519;240523.59476405362;241182.7940860302;43462.34806477906;44149.33775993362;53513.1973020405;54453.45546544484;54476.72761636166;49381;51028.74485627548;51160;51571;51653;11468.885311146949;11694.35681037534;11739.706507337214;11745.29528228309;11798.705032374259;9127.497987610745;9218.400803947721;9225.630974761069;9302.976672566012;9307.441553506811;15041.548753537412;15668.921655391878;15762.605934851492;15815.683686326209;15939.362425503083;7384.1877393486675;7469.066366704162;7473.140588832245;7511.774646760606;7606.847863042708;781.8631739445574;857.0786404615063;892.808046270055;948.6964171465203;965.32909627808;25703;26094.73905260923;27673;27895.442091158064;28386;887.6360692116331;897.7845317123844;918.1874041473211;975.426005412431;996.3822430093388;12694.873051269555;13272.203667779964;13401.463941442427;13452.1256118352;13555.796663050061;270153;271196;279998;280061.7805391295;280127;42010.369844452354;43414.131717365475;44250;44253;44309.33535996898;1068.4016950507742;1104.2766987622958;1114.0419239453902;1122.5229277557005;1125.229217985666;24843.378915527257;25535.87232063828;25598.23205303844;25687.486250275262;25696;39606.603933960294;39862;40034.199316012935;40046.59953400429;40062.599374007055;266685;268060;268850;268976;270312.6171751938;22330.665040024738;22662.546749065255;25427.87286063558;27637.44725105527;28313;36566.268674625826;37120.6287937128;37211.627883720816;37446.625533744314;37455.81272093622;30297.545536817408;31031.224219394695;31517.36965260636;32125;32900.67099328976;23078.653820192376;26114;26452.206433807016;28862.92858249742;31389.686103138676;29300.413991720474;29483.262918426353;30013.69986300109;30046.399072018;30089.84955075298;26512.204633861016;28865.134045978655;28868.979171457795;29123;29618.739295297015;22535;22976.195833146427;23051.19320823763;23067.88466057659;23166.652500212174 08:51:46 INFO - 2015-10-26 08:51:46,507 DEBUG : BROWSER_OUTPUT: |2;jquery.html;291.0250660288037;296.0467295309104;296.45156460547975;299.2802310443384;301.0726960960242;11527.19309648325;12495.750084998295;15266;15687.764683529756;15697.450589229366;14221.288935553233;14313.499027534039;14444.711105777878;14456.783148252758;14460.421583136687;5339.759710813003;5505.752241149148;5668.829935101953;5671.489565939065;5671.546276297886;3785.5835858055593;3892.2799282132755;5527.917081243764;6503.512236582252;6726.091977583037;6664.733410663568;6681.498887583426;6682.4319932805765;6692.263850976388;6707.832304192385;6021.066734656135;6193.87612247757;6217.782377616784;6219.906701399483;6651.068850360954;5742.109972954198;5755.510781583571;5861.17943487912;5866.618669786493;5889.440503152194;19447.124879380463;20238.69641955372;20683.482912927146;21174;21228.787712122834;1843.9539011524685;1960.44127423685;1994.7606287245624;2022.2113375783456;2234.0058673890126;12761.744765104646;13000.154989925624;13068.86931130686;13363.933180334134;13617.795733064011;1781.287485005995;1807.0469881725103;1844.3821319857864;1867.085128287143;1868.1313189366874;60721;61153;64008.67995659992;65222.3477765221;65425.3457465424;13632.591022269347;13728.862711372958;13782.035257532078;13809;14024.649383765487;19971.60056798856;20000;20216;20241.797582024286;20252.189912403337;7394.963025184839;7395.593242371614;7398.445116616168;7507.286807753255;7544.396448284124;6214.2657230818295;6282.026285925689;6282.18331616896;6369.936300636934;6578.5395022347975;6574.539782215199;6663.167104111986;6691.966540167366;6694.163229596301;6700.195976482756;2380.3929997850437;2753.752162305402;2801.257666718321;2825.8304501729963;2875.7124287571496;378.977261364319;384.3773087598109;421.3195688962365;426.7951383336016;431.41758625854845;10290.948545257375;10593.735156621146;10704.892951070546;10887.074598659203;11002.614908478165;391.60643553228823;395.74869957577147;401.6746435387346;417.74517544297953;424.4863714904938;5818.272715910512;5870.354261031283;5877.23595932526;5913.556483263773;5931.940680593225;276562;281576;294364;301759;308562;64494.67752661207;65925.34074659288;65929.34070659232;66232.33767662357;66503.66748166228;431.18936399569236;431.26469369724543;446.19461871322574;455.4739276136068;456.8058575105567;10319.845202321821;10479.42363170033;10546.894531054591;10570.365778053341;10697.893021069689;33449.83275083609;34682.878540930426;35393;35837;36081.639183607826;41466.37800432936;43232.567674322854;44324.77837610791;45109;45933;5633.436656334334;5848.385919478394;5850.765969361178;5897.233359663216;5899.970500147472;10943.726406839574;11165.944170279097;11359.886401136213;11381.772364552828;11450.82823757661;9450.149486546245;9469.66856160031;9700.126988571063;9763.170130538983;9786.75533111678;9323.254139668947;9332.906670933204;9346.813063738824;9428.387154834914;9529.428234305964;9639.505876589139;9841.409515429097;9957.302988790716;10018;10024.14794742455;5656.066748986371;5783.537317014545;5786.884262314646;5823.563232757476;5835.2122463466885;9312.556553734183;9498.525073746423;9722.465264410524;9859.654912078044;9877.654282100091 08:51:46 INFO - 2015-10-26 08:51:46,509 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1219.9939000304996;1222.6637674639478;1254.8142005804514;1374.2304309586632;1568.52944116765;5877.706114694264;6546.574472659274;6598.373154550322;6599.208095028591;6655.134832471784;6540.476761859052;6579.638119903403;6647.4017338439535;6712.362325579074;6733.865322693543;1897.2885168061978;1925.5186203449139;1935.4774210963033;1949.5321122930466;1957.765068191819;1935.8257756801886;1958.8432925365937;1959.2163134746072;1966.1054220329715;1973.2008536542724;1885.2364792259089;1917.980820191801;1926.489480287725;1941.3787587971854;1948.83434908033;1859.293468481975;1880.6520793653149;1905.8761180523222;1906.8474522038273;1911.7610298712661;1855.4248183063294;1891.9905400473046;1893.1575448925234;1901.9239230430767;1942.9028548572514;5837.270341207349;6013.098035294697;6650.748296601228;6821.3519715626935;6833.6241506717115;3815.9046023849346;3853.5761066282685;3858.074062225046;3955.0507878109333;3955.4066889966734;2377.9048838046456;2407.7592240775957;2428.490017096412;2438.987805060981;2439.658447817307;716.2944499667875;720.200577359131;737.3400806278404;754.6905768634888;755.8526087412956;8682.86975695365;8941.239994600466;8992.505412202327;8996.59515321812;9006.639734410615;3530.382183117944;3587.587427445877;3635.0912271932016;3673.577538583097;3747.362948298796;7698.884516732253;7765.378769698411;7780.688772449039;7781.41639377049;7798.337141342936;4012.6388625023887;4015.31739604268;4019.718619696652;4019.8744351581604;4025.496812898388;3450.189205536697;3595.352836489457;3622.728295377857;3633.745637805381;3648.416253399443;2858.213991152408;2946.3076177098364;3025.2285667155174;3056.709612586845;3058.7552995760284;991.2714155095998;991.3506653142204;999.2954966748425;999.8400255959011;1004.2668851738272;1088.8203446431407;1094.8631421072366;1097.5884043483693;1107.645553422897;1109.955601775936;6319.988801791691;6386.616802991835;6389.393007664265;6392;6393.200849893763;394.8144372145087;405.05217790370676;410.4049128763305;412.07900342733967;412.4411422522499;4958.950410495849;5064.265681476165;5074.949250507448;5075.796968121308;5082.2122571001555;7291.213652655133;7510.436717246228;7901;7975.2423519765525;7993.080795708452;7612.657430415645;7839.216078392229;7902.17027212146;7936.523808571505;7940.642671079815;6076.392360763888;6228.096925945803;6231.4391704746795;6234.345393733593;6238.095476156022;6068.150458935841;6103.481204097701;6107.4197951194565;6118.694065296629;6128.816135515942;7119.928800712134;7151.177614574391;7151.356377926011;7156.785296441115;7182.820429489305;7231.63841807897;7265.346118849329;7287.417006639562;7457.40340772726;7490.400767938443;5909.172715819703;5930.822075337746;5934.10108765191;5949.702514874152;5968.194293770285;6297.716602752796;6695.631740254333;6700.296468870698;6703.363180497845;6708.731650734111;6066.4540191382985;6097.298810636834;6120.479759220338;6187.659678717714;6192.938070619236;6045.153678484928;6077.939220607737;6167.537434692326;6216.818804427145;6230.626162430088;6026.7287972040485;6232.688365581612;6240.126382306572;6264.624122552661;6275.6548389839;5373;5477.370102438271;5478.808241711521;5502.889942201054;5641.746121424628;5393.379761327497;5423.3491980962535;5561.527270181918;5578.860528486658;5612.045952188057 08:51:46 INFO - 2015-10-26 08:51:46,526 DEBUG : BROWSER_OUTPUT: |4;prototype.html;274.69097265576227;277.4815341470159;278.54736053912393;279.50388061191387;283.4402055939521;4626.8149274029;4680.368150299709;4683.648726345525;4700.976495117528;4717.339572459854;4419.491758447772;4532.592066713995;4636.629069674426;4642.814287428499;4653.534646535345;3372.376110419568;3490.406630872745;3491.17957280039;3492.301539692059;3495.16116132129;3314.950275745866;3388.4578467445212;3460.2906404187083;3463.5670541182353;3491.720662347019;3217.083131307578;3283.425400554905;3330.900072997814;3454.4990976308422;3501.1772233525103;3243.189202699325;3434.639362866913;3478.4260597001467;3479.6868281854627;3489.842957066938;3084.568160457538;3086.8302243376606;3089.0423968569817;3092.427900838352;3093.427715872548;4415.66882483815;4429.9114017719785;4501.077279157765;4579.22153233951;4584.5873871351405;3976.80115994202;4067.877963661095;4091.529474110486;4140.8136633851245;4144.606262405066;4793.520647935214;4902.754862256873;4942.703437793743;4943.728094954776;4945.629077819178;3299.406106900757;3390.4744764561533;3394.62659107498;3403.7447191460737;3406.931861362759;4812.542808433228;4950.405951285833;5038.14351560232;5103.642745007851;5104.234364845266;3273.608716295565;3322.684344987222;3422.845971931269;3459.705924996353;3494.405950988338;3516.6659167379057;3522.295540891832;3526.770759900598;3527.470879368064;3530.2409981854025;3744.0452684565807;4028.697847661437;4064.552899181088;4067.89830254246;4067.9796601016806;4869.196582563908;4886.60907127429;4887.169181239198;4888.633352498506;4890.657653964188;1151.9862520981483;1247.357610830423;1257.226805514602;1263.823064770933;1265.6203139058246;800.083903929997;839.9496030238083;845.8158577990862;846.9703560375357;848.9405741598028;4427;4649.070185962821;4727.2436410174205;4730.834420795256;4737.763111844394;5114.181730923035;5121.8719532011255;5128.358955130609;5131.692098474104;5132.486751324837;4595.816167353335;4621.69958952667;4721.197396442544;4729.581125662287;4735.076660051257;2757.7793776497833;2773.6948935617065;2798.566222235557;2802.649668791401;2813.212300555848;5056.974715126401;5233.738313084331;5238.97380513095;5242.082635538765;5250.65870718402;5229;5237.109691352479;5244.318238628954;5245;5250.7374631268285;5043.646944713835;5177.016366890203;5227.45111763267;5231.529162375405;5249.816256431083;5119.616028797929;5125.359330083739;5126.154184559506;5140.871478212925;5142.871428214175;5014.272930425141;5015.899682006412;5019.824306149268;5023.045621331935;5032.3206367139965;5011.7494125294315;5151.330327057459;5204.973975130251;5236.947630523646;5243.082460569462;3466.410710179225;3493.7729047612843;3496.510488531557;3499.0202743231935;3501.702355299828;4850.805967761391;4945.109880221595;5046.9747651261505;5145.974270128775;5158.174692049254;4548.340490628906;4845.781939812788;4890.364252647133;4921.990991846735;4924.212126059813;4899.608031357554;4905.749033996308;4911.361523001987;4920.188168952082;4924.556789888928;4560.065186636798;4625.121226966866;4796.25658023007;4811.855644330676;4814.20565606685;4531.660125490535;4952.331435256195;4955;4964.429090654621;4965.131102057053;4461.308497182942;4488.079943611617;4756.643251756202;4785.042991401664;4812.927806082842 08:51:46 INFO - 2015-10-26 08:51:46,528 DEBUG : BROWSER_OUTPUT: |5;yui.html;628.5819929746718;632.8987362022076;718.403724908326;729.5148726097145;748.8876668499724;11561.132915031365;11687.474063667134;11736.647900562975;11760.588379406723;11826.704332391695;11699.824502632468;11755.41222938854;11756.470958806853;11791.351475668835;12513;604.6976511744128;616.8026231605888;618.8483821463748;619.6189343553727;620.2402057479596;481.2997089235154;487.34695508019246;598.1655590451318;607.6566739792025;618.9879546940739;602.2532060245286;617.1575799034331;632.7405763636909;637.0348921384107;642.5598465051431;395.37530701491573;632.4971647540217;634.4353525362408;635.2281977397438;639.5906619763355;287.06703214552704;457.7756899119441;460.5003571125333;463.66384371330855;463.9698419602749;11299;11732.472038758191;11961.342126183057;11962.581309654208;11971.64085077449;8916.420432671921;8973.32700047499;9079.81840363189;9100.089991000914;9168.083191680846;9129;9289.53552322388;9302.116298951589;9573.808523829553;9679.661211857621;2868.3116052147543;2869;2900.5939168516425;2912.8397938113394;2954.748846348063;41412.58587414148;41519;41911.580884191375;41988.16023679509;42063.57936420627;6077.9363611367835;6279.434850863444;6480.773172938924;6519.608823470607;6616.172978377703;10014.449205293633;10131.696049118538;10551.94724026375;10570.630027948984;10576.735581610521;5411.404745477994;5430.5927055471;5531.668099914018;5579.69311687861;5726.856828579319;3512.473129030666;3540.946885796715;3552.641382907343;3596.6583174598372;3683.889483315505;2229.9442513936956;2253.853499522526;2254.143425498333;2259.559385919763;2259.570681570496;343.5259342107875;349.83732564357695;352.6861093626674;354.93966025775165;355.5750877701174;545.4436474795765;561.2451253064621;573.6042130929728;576.4264556766045;606.1392822192497;7129.251428600026;7799.142094369615;7848.921510784933;7870.370370370357;7951.840963180704;423.3691799219141;495.2719502331567;579.3337661689008;582.8484594005589;601.8525461262019;3477.530533378012;3496.6328535503912;3510.420780571228;3606.8016259106003;3667.3765459871884;53886.191707124424;54000;54076.18885716717;54322;57647.42352576504;46436;47486.762566186946;48227.517724823;48519.27221091687;48870.51129488659;42593.78703106465;43646.781766090964;44508;45224.32163517484;45490.31764523535;7544.132424771222;7886.763397098096;8244.505329680238;8339.45793523419;8343;31676.683233168296;31820.363592728478;31844.885429009875;31945.840270798497;32401;41596.37605435981;42470.575294246664;44630.3305450412;44686.776566118264;44736.328955066354;6497.935020649733;6652.767153149616;6758.357955994173;6809.5573787705625;7150.499465037397;10797.266514806253;10916.78166436651;11310.377929213642;11317.490712918103;11414.94292528532;9439.622415103318;9943.403395795985;9952.800943981225;10095;10551.050405463546;10517.211209159497;10673.626423075155;10685.305455145392;10817.891821081688;11046.96208542625;10028.147607453448;10257.33327333721;10424.635137770141;10878.347299162075;10934.56261749521;9151.488227454402;9320.067993200897;9347;9402.2948278878;9528.523573821143;8611.784705382415;8716.259117974789;9022.323325750725;9097.226735727272;9352.298577606844 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,809 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: __startTimestamp1445874706500__endTimestamp 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: Number of tests: 6 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4076.04 Median:4238.19 stddev:501.09 (11.8%) stddev-sans-first:483.05 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: Values: 2307.8 2319.4 2390.7 2400.7 2401.5 4000.8 4167.6 4170.6 4225.9 4231.3 4090.1 4145.2 4232.3 4244.6 4250.9 3581.1 3671.0 3679.9 3700.3 3735.2 3654.8 3692.9 3720.2 3721.0 3725.2 3706.5 3707.2 3719.1 3721.8 3724.4 3652.1 3656.8 3693.8 3722.8 3763.2 3714.2 3752.9 3756.8 3790.1 3790.3 4096.8 4135.7 4156.5 4186.2 4237.6 4180.6 4412.9 4424.2 4428.5 4515.3 4449.8 4459.2 4480.0 4548.1 4552.6 3920.0 4130.6 4221.4 4224.4 4235.7 4331.0 4344.4 4451.1 4464.7 4539.0 4307.7 4315.5 4368.0 4368.2 4387.2 4378.7 4379.4 4456.5 4458.2 4464.3 4217.5 4315.1 4329.4 4370.8 4486.1 4250.3 4250.5 4340.9 4345.6 4431.7 3076.4 3130.2 3134.0 3145.8 3148.3 2800.6 2826.7 2849.4 2876.4 2882.7 4111.0 4169.7 4180.4 4209.6 4238.2 4396.5 4416.1 4511.4 4514.7 4525.1 4033.4 4124.8 4136.2 4141.1 4227.5 3585.3 3773.2 3794.5 3796.0 3797.7 4496.5 4507.0 4618.1 4626.4 4638.6 4276.7 4384.2 4395.8 4398.3 4464.8 4537.3 4556.3 4633.9 4638.6 4638.9 4117.3 4243.3 4376.5 4443.5 4471.9 4059.4 4234.6 4341.2 4417.2 4444.2 4291.5 4317.6 4356.5 4386.6 4403.6 4309.0 4386.2 4388.2 4389.6 4398.8 4008.7 4040.8 4065.7 4130.6 4170.1 4286.2 4300.0 4340.3 4400.1 4416.3 4255.9 4263.5 4342.3 4351.7 4354.2 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:40779.11 Median:16291.54 stddev:68013.86 (417.5%) stddev-sans-first:68137.36 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: Values: 484.3 486.0 490.6 490.6 523.9 26036.2 33733.3 36326.8 37141.0 37220.6 38581.5 39249.2 39258.6 39386.0 39407.0 6235.1 6354.5 7909.8 7926.5 8092.1 9996.4 10303.6 10332.8 10353.5 10366.5 9467.7 10041.3 10167.5 10188.6 10201.1 5842.3 6160.4 9937.5 10172.1 10505.2 9629.1 9786.1 9876.6 10015.1 10058.5 35887.0 36137.1 36234.6 36330.0 36395.8 2838.5 2875.5 2964.3 3096.8 3303.3 27072.5 27575.4 27627.4 28088.4 28112.6 2017.8 2050.2 2099.8 2113.5 2125.6 224922.0 230186.0 235519.0 240523.6 241182.8 43462.3 44149.3 53513.2 54453.5 54476.7 49381.0 51028.7 51160.0 51571.0 51653.0 11468.9 11694.4 11739.7 11745.3 11798.7 9127.5 9218.4 9225.6 9303.0 9307.4 15041.5 15668.9 15762.6 15815.7 15939.4 7384.2 7469.1 7473.1 7511.8 7606.8 781.9 857.1 892.8 948.7 965.3 25703.0 26094.7 27673.0 27895.4 28386.0 887.6 897.8 918.2 975.4 996.4 12694.9 13272.2 13401.5 13452.1 13555.8 270153.0 271196.0 279998.0 280061.8 280127.0 42010.4 43414.1 44250.0 44253.0 44309.3 1068.4 1104.3 1114.0 1122.5 1125.2 24843.4 25535.9 25598.2 25687.5 25696.0 39606.6 39862.0 40034.2 40046.6 40062.6 266685.0 268060.0 268850.0 268976.0 270312.6 22330.7 22662.5 25427.9 27637.4 28313.0 36566.3 37120.6 37211.6 37446.6 37455.8 30297.5 31031.2 31517.4 32125.0 32900.7 23078.7 26114.0 26452.2 28862.9 31389.7 29300.4 29483.3 30013.7 30046.4 30089.8 26512.2 28865.1 28869.0 29123.0 29618.7 22535.0 22976.2 23051.2 23067.9 23166.7 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: 08:51:46 INFO - 2015-10-26 08:51:46,529 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:20658.54 Median:431.34 stddev:48626.98 (11273.4%) stddev-sans-first:48739.34 08:51:46 INFO - 2015-10-26 08:51:46,530 DEBUG : BROWSER_OUTPUT: Values: 291.0 296.0 296.5 299.3 301.1 11527.2 12495.8 15266.0 15687.8 15697.5 14221.3 14313.5 14444.7 14456.8 14460.4 5339.8 5505.8 5668.8 5671.5 5671.5 3785.6 3892.3 5527.9 6503.5 6726.1 6664.7 6681.5 6682.4 6692.3 6707.8 6021.1 6193.9 6217.8 6219.9 6651.1 5742.1 5755.5 5861.2 5866.6 5889.4 19447.1 20238.7 20683.5 21174.0 21228.8 1844.0 1960.4 1994.8 2022.2 2234.0 12761.7 13000.2 13068.9 13363.9 13617.8 1781.3 1807.0 1844.4 1867.1 1868.1 60721.0 61153.0 64008.7 65222.3 65425.3 13632.6 13728.9 13782.0 13809.0 14024.6 19971.6 20000.0 20216.0 20241.8 20252.2 7395.0 7395.6 7398.4 7507.3 7544.4 6214.3 6282.0 6282.2 6369.9 6578.5 6574.5 6663.2 6692.0 6694.2 6700.2 2380.4 2753.8 2801.3 2825.8 2875.7 379.0 384.4 421.3 426.8 431.4 10290.9 10593.7 10704.9 10887.1 11002.6 391.6 395.7 401.7 417.7 424.5 5818.3 5870.4 5877.2 5913.6 5931.9 276562.0 281576.0 294364.0 301759.0 308562.0 64494.7 65925.3 65929.3 66232.3 66503.7 431.2 431.3 446.2 455.5 456.8 10319.8 10479.4 10546.9 10570.4 10697.9 33449.8 34682.9 35393.0 35837.0 36081.6 41466.4 43232.6 44324.8 45109.0 45933.0 5633.4 5848.4 5850.8 5897.2 5900.0 10943.7 11165.9 11359.9 11381.8 11450.8 9450.1 9469.7 9700.1 9763.2 9786.8 9323.3 9332.9 9346.8 9428.4 9529.4 9639.5 9841.4 9957.3 10018.0 10024.1 5656.1 5783.5 5786.9 5823.6 5835.2 9312.6 9498.5 9722.5 9859.7 9877.7 08:51:46 INFO - 2015-10-26 08:51:46,532 DEBUG : BROWSER_OUTPUT: 08:51:46 INFO - 2015-10-26 08:51:46,532 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4633.39 Median:5941.90 stddev:2433.87 (41.0%) stddev-sans-first:2427.17 08:51:46 INFO - 2015-10-26 08:51:46,532 DEBUG : BROWSER_OUTPUT: Values: 1220.0 1222.7 1254.8 1374.2 1568.5 5877.7 6546.6 6598.4 6599.2 6655.1 6540.5 6579.6 6647.4 6712.4 6733.9 1897.3 1925.5 1935.5 1949.5 1957.8 1935.8 1958.8 1959.2 1966.1 1973.2 1885.2 1918.0 1926.5 1941.4 1948.8 1859.3 1880.7 1905.9 1906.8 1911.8 1855.4 1892.0 1893.2 1901.9 1942.9 5837.3 6013.1 6650.7 6821.4 6833.6 3815.9 3853.6 3858.1 3955.1 3955.4 2377.9 2407.8 2428.5 2439.0 2439.7 716.3 720.2 737.3 754.7 755.9 8682.9 8941.2 8992.5 8996.6 9006.6 3530.4 3587.6 3635.1 3673.6 3747.4 7698.9 7765.4 7780.7 7781.4 7798.3 4012.6 4015.3 4019.7 4019.9 4025.5 3450.2 3595.4 3622.7 3633.7 3648.4 2858.2 2946.3 3025.2 3056.7 3058.8 991.3 991.4 999.3 999.8 1004.3 1088.8 1094.9 1097.6 1107.6 1110.0 6320.0 6386.6 6389.4 6392.0 6393.2 394.8 405.1 410.4 412.1 412.4 4959.0 5064.3 5074.9 5075.8 5082.2 7291.2 7510.4 7901.0 7975.2 7993.1 7612.7 7839.2 7902.2 7936.5 7940.6 6076.4 6228.1 6231.4 6234.3 6238.1 6068.2 6103.5 6107.4 6118.7 6128.8 7119.9 7151.2 7151.4 7156.8 7182.8 7231.6 7265.3 7287.4 7457.4 7490.4 5909.2 5930.8 5934.1 5949.7 5968.2 6297.7 6695.6 6700.3 6703.4 6708.7 6066.5 6097.3 6120.5 6187.7 6192.9 6045.2 6077.9 6167.5 6216.8 6230.6 6026.7 6232.7 6240.1 6264.6 6275.7 5373.0 5477.4 5478.8 5502.9 5641.7 5393.4 5423.3 5561.5 5578.9 5612.0 08:51:46 INFO - 2015-10-26 08:51:46,533 DEBUG : BROWSER_OUTPUT: 08:51:46 INFO - 2015-10-26 08:51:46,533 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4076.80 Median:4666.95 stddev:1234.89 (26.5%) stddev-sans-first:1204.93 08:51:46 INFO - 2015-10-26 08:51:46,533 DEBUG : BROWSER_OUTPUT: Values: 274.7 277.5 278.5 279.5 283.4 4626.8 4680.4 4683.6 4701.0 4717.3 4419.5 4532.6 4636.6 4642.8 4653.5 3372.4 3490.4 3491.2 3492.3 3495.2 3315.0 3388.5 3460.3 3463.6 3491.7 3217.1 3283.4 3330.9 3454.5 3501.2 3243.2 3434.6 3478.4 3479.7 3489.8 3084.6 3086.8 3089.0 3092.4 3093.4 4415.7 4429.9 4501.1 4579.2 4584.6 3976.8 4067.9 4091.5 4140.8 4144.6 4793.5 4902.8 4942.7 4943.7 4945.6 3299.4 3390.5 3394.6 3403.7 3406.9 4812.5 4950.4 5038.1 5103.6 5104.2 3273.6 3322.7 3422.8 3459.7 3494.4 3516.7 3522.3 3526.8 3527.5 3530.2 3744.0 4028.7 4064.6 4067.9 4068.0 4869.2 4886.6 4887.2 4888.6 4890.7 1152.0 1247.4 1257.2 1263.8 1265.6 800.1 839.9 845.8 847.0 848.9 4427.0 4649.1 4727.2 4730.8 4737.8 5114.2 5121.9 5128.4 5131.7 5132.5 4595.8 4621.7 4721.2 4729.6 4735.1 2757.8 2773.7 2798.6 2802.6 2813.2 5057.0 5233.7 5239.0 5242.1 5250.7 5229.0 5237.1 5244.3 5245.0 5250.7 5043.6 5177.0 5227.5 5231.5 5249.8 5119.6 5125.4 5126.2 5140.9 5142.9 5014.3 5015.9 5019.8 5023.0 5032.3 5011.7 5151.3 5205.0 5236.9 5243.1 3466.4 3493.8 3496.5 3499.0 3501.7 4850.8 4945.1 5047.0 5146.0 5158.2 4548.3 4845.8 4890.4 4922.0 4924.2 4899.6 4905.7 4911.4 4920.2 4924.6 4560.1 4625.1 4796.3 4811.9 4814.2 4531.7 4952.3 4955.0 4964.4 4965.1 4461.3 4488.1 4756.6 4785.0 4812.9 08:51:46 INFO - 2015-10-26 08:51:46,535 DEBUG : BROWSER_OUTPUT: 08:51:46 INFO - 2015-10-26 08:51:46,535 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12249.94 Median:23984.03 stddev:15068.74 (62.8%) stddev-sans-first:15085.75 08:51:46 INFO - 2015-10-26 08:51:46,536 DEBUG : BROWSER_OUTPUT: Values: 628.6 632.9 718.4 729.5 748.9 11561.1 11687.5 11736.6 11760.6 11826.7 11699.8 11755.4 11756.5 11791.4 12513.0 604.7 616.8 618.8 619.6 620.2 481.3 487.3 598.2 607.7 619.0 602.3 617.2 632.7 637.0 642.6 395.4 632.5 634.4 635.2 639.6 287.1 457.8 460.5 463.7 464.0 11299.0 11732.5 11961.3 11962.6 11971.6 8916.4 8973.3 9079.8 9100.1 9168.1 9129.0 9289.5 9302.1 9573.8 9679.7 2868.3 2869.0 2900.6 2912.8 2954.7 41412.6 41519.0 41911.6 41988.2 42063.6 6077.9 6279.4 6480.8 6519.6 6616.2 10014.4 10131.7 10551.9 10570.6 10576.7 5411.4 5430.6 5531.7 5579.7 5726.9 3512.5 3540.9 3552.6 3596.7 3683.9 2229.9 2253.9 2254.1 2259.6 2259.6 343.5 349.8 352.7 354.9 355.6 545.4 561.2 573.6 576.4 606.1 7129.3 7799.1 7848.9 7870.4 7951.8 423.4 495.3 579.3 582.8 601.9 3477.5 3496.6 3510.4 3606.8 3667.4 53886.2 54000.0 54076.2 54322.0 57647.4 46436.0 47486.8 48227.5 48519.3 48870.5 42593.8 43646.8 44508.0 45224.3 45490.3 7544.1 7886.8 8244.5 8339.5 8343.0 31676.7 31820.4 31844.9 31945.8 32401.0 41596.4 42470.6 44630.3 44686.8 44736.3 6497.9 6652.8 6758.4 6809.6 7150.5 10797.3 10916.8 11310.4 11317.5 11414.9 9439.6 9943.4 9952.8 10095.0 10551.1 10517.2 10673.6 10685.3 10817.9 11047.0 10028.1 10257.3 10424.6 10878.3 10934.6 9151.5 9320.1 9347.0 9402.3 9528.5 8611.8 8716.3 9022.3 9097.2 9352.3 08:51:46 INFO - 2015-10-26 08:51:46,536 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:51:46 INFO - 2015-10-26 08:51:46,536 DEBUG : BROWSER_OUTPUT: 08:51:47 INFO - 2015-10-26 08:51:47,092 INFO : Browser exited with error code: 0 08:51:47 INFO - 2015-10-26 08:51:47,826 INFO : Completed test dromaeo_css (00:18:44) 08:51:47 INFO - 2015-10-26 08:51:47,826 INFO : Starting test kraken 08:51:47 INFO - 2015-10-26 08:51:47,826 DEBUG : operating with platform_type : w7_ 08:51:47 INFO - 2015-10-26 08:51:47,826 INFO : Initialising browser for kraken test... 08:51:47 INFO - 2015-10-26 08:51:47,852 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp1lan_q\profile http://localhost:49703/getInfo.html 08:51:53 INFO - 2015-10-26 08:51:53,101 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:51:53 INFO - 2015-10-26 08:51:53,101 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:51:53 INFO - 2015-10-26 08:51:53,101 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 08:51:53 INFO - 2015-10-26 08:51:53,101 DEBUG : BROWSER_OUTPUT: __metrics 08:51:53 INFO - 2015-10-26 08:51:53,177 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:51:54 INFO - 2015-10-26 08:51:54,374 INFO : Browser initialized. 08:51:54 INFO - 2015-10-26 08:51:54,374 INFO : Running cycle 1/1 for kraken test... 08:51:54 INFO - 2015-10-26 08:51:54,374 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmp1lan_q\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 08:52:06 INFO - 2015-10-26 08:52:06,029 DEBUG : BROWSER_OUTPUT: RSS: Main: 141766656 08:52:06 INFO - 2015-10-26 08:52:06,029 DEBUG : BROWSER_OUTPUT: 08:52:08 INFO - 2015-10-26 08:52:08,543 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49703/tests/kraken/driver.html?testName=audio-beat-detection) 08:52:09 INFO - 2015-10-26 08:52:09,131 DEBUG : BROWSER_OUTPUT: RSS: Main: 175263744 08:52:09 INFO - 2015-10-26 08:52:09,131 DEBUG : BROWSER_OUTPUT: 08:52:11 INFO - 2015-10-26 08:52:11,966 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49703/tests/kraken/driver.html?testName=audio-dft) 08:52:12 INFO - 2015-10-26 08:52:12,540 DEBUG : BROWSER_OUTPUT: RSS: Main: 178241536 08:52:12 INFO - 2015-10-26 08:52:12,540 DEBUG : BROWSER_OUTPUT: 08:52:15 INFO - 2015-10-26 08:52:15,315 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49703/tests/kraken/driver.html?testName=audio-fft) 08:52:15 INFO - 2015-10-26 08:52:15,891 DEBUG : BROWSER_OUTPUT: RSS: Main: 171319296 08:52:15 INFO - 2015-10-26 08:52:15,891 DEBUG : BROWSER_OUTPUT: 08:52:18 INFO - 2015-10-26 08:52:18,065 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49703/tests/kraken/driver.html?testName=audio-oscillator) 08:52:18 INFO - 2015-10-26 08:52:18,632 DEBUG : BROWSER_OUTPUT: RSS: Main: 168878080 08:52:18 INFO - 2015-10-26 08:52:18,632 DEBUG : BROWSER_OUTPUT: 08:52:20 INFO - 2015-10-26 08:52:20,927 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49703/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:52:21 INFO - 2015-10-26 08:52:21,486 DEBUG : BROWSER_OUTPUT: RSS: Main: 168370176 08:52:21 INFO - 2015-10-26 08:52:21,486 DEBUG : BROWSER_OUTPUT: 08:52:24 INFO - 2015-10-26 08:52:24,667 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49703/tests/kraken/driver.html?testName=imaging-darkroom) 08:52:25 INFO - 2015-10-26 08:52:25,246 DEBUG : BROWSER_OUTPUT: RSS: Main: 162263040 08:52:25 INFO - 2015-10-26 08:52:25,246 DEBUG : BROWSER_OUTPUT: 08:52:28 INFO - 2015-10-26 08:52:28,650 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49703/tests/kraken/driver.html?testName=imaging-desaturate) 08:52:29 INFO - 2015-10-26 08:52:29,230 DEBUG : BROWSER_OUTPUT: RSS: Main: 164814848 08:52:29 INFO - 2015-10-26 08:52:29,230 DEBUG : BROWSER_OUTPUT: 08:52:32 INFO - 2015-10-26 08:52:32,096 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49703/tests/kraken/driver.html?testName=json-parse-financial) 08:52:32 INFO - 2015-10-26 08:52:32,677 DEBUG : BROWSER_OUTPUT: RSS: Main: 164495360 08:52:32 INFO - 2015-10-26 08:52:32,677 DEBUG : BROWSER_OUTPUT: 08:52:34 INFO - 2015-10-26 08:52:34,532 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49703/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:52:35 INFO - 2015-10-26 08:52:35,095 DEBUG : BROWSER_OUTPUT: RSS: Main: 194207744 08:52:35 INFO - 2015-10-26 08:52:35,095 DEBUG : BROWSER_OUTPUT: 08:52:37 INFO - 2015-10-26 08:52:37,051 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49703/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:52:37 INFO - 2015-10-26 08:52:37,625 DEBUG : BROWSER_OUTPUT: RSS: Main: 199405568 08:52:37 INFO - 2015-10-26 08:52:37,625 DEBUG : BROWSER_OUTPUT: 08:52:40 INFO - 2015-10-26 08:52:40,048 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49703/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:52:40 INFO - 2015-10-26 08:52:40,622 DEBUG : BROWSER_OUTPUT: RSS: Main: 205361152 08:52:40 INFO - 2015-10-26 08:52:40,622 DEBUG : BROWSER_OUTPUT: 08:52:43 INFO - 2015-10-26 08:52:43,298 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49703/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:52:43 INFO - 2015-10-26 08:52:43,861 DEBUG : BROWSER_OUTPUT: RSS: Main: 207413248 08:52:43 INFO - 2015-10-26 08:52:43,861 DEBUG : BROWSER_OUTPUT: 08:52:47 INFO - 2015-10-26 08:52:47,115 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49703/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:52:47 INFO - 2015-10-26 08:52:47,690 DEBUG : BROWSER_OUTPUT: RSS: Main: 206589952 08:52:47 INFO - 2015-10-26 08:52:47,690 DEBUG : BROWSER_OUTPUT: 08:52:49 INFO - 2015-10-26 08:52:49,730 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49703/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49703/tests/kraken/driver.html?testName=ai-astar) 08:52:49 INFO - 2015-10-26 08:52:49,984 DEBUG : BROWSER_OUTPUT: RSS: Main: 252329984 08:52:49 INFO - 2015-10-26 08:52:49,984 DEBUG : BROWSER_OUTPUT: 08:52:49 INFO - 2015-10-26 08:52:49,984 DEBUG : BROWSER_OUTPUT: __start_tp_report 08:52:49 INFO - 2015-10-26 08:52:49,984 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 08:52:49 INFO - 2015-10-26 08:52:49,984 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 08:52:49 INFO - 2015-10-26 08:52:49,984 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 08:52:49 INFO - 2015-10-26 08:52:49,984 DEBUG : BROWSER_OUTPUT: |0;ai-astar;94;98;98;89;97;99;98;98;97;97 08:52:49 INFO - 2015-10-26 08:52:49,984 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;149;171;177;140;146;154;146;164;147 08:52:49 INFO - 2015-10-26 08:52:49,984 DEBUG : BROWSER_OUTPUT: |2;audio-dft;156;154;149;149;148;147;147;145;145;145 08:52:49 INFO - 2015-10-26 08:52:49,984 DEBUG : BROWSER_OUTPUT: |3;audio-fft;84;105;103;122;89;80;80;81;105;81 08:52:49 INFO - 2015-10-26 08:52:49,984 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;104;100;119;101;101;100;126;101;101;124 08:52:49 INFO - 2015-10-26 08:52:49,984 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;117;116;117;117;117;117;117;116;116;117 08:52:49 INFO - 2015-10-26 08:52:49,984 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;136;137;137;145;137;137;143;137;137;136 08:52:49 INFO - 2015-10-26 08:52:49,986 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;89;89;89;90;89;90;90;89;90;89 08:52:49 INFO - 2015-10-26 08:52:49,986 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;79;80;80;81;79;79;80;80;79;89 08:52:49 INFO - 2015-10-26 08:52:49,986 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;75;74;75;75;75;74;74;75;75;75 08:52:49 INFO - 2015-10-26 08:52:49,986 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;100;100;101;102;103;102;101;103;104;106 08:52:49 INFO - 2015-10-26 08:52:49,986 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;131;138;135;136;138;131;132;130;137;141 08:52:49 INFO - 2015-10-26 08:52:49,986 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;196;207;201;205;206;198;204;205;207;196 08:52:49 INFO - 2015-10-26 08:52:49,986 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;101;95;95;96;94;92;98;93;94;94 08:52:49 INFO - 2015-10-26 08:52:49,986 DEBUG : BROWSER_OUTPUT: __end_tp_report 08:52:49 INFO - 2015-10-26 08:52:49,986 DEBUG : BROWSER_OUTPUT: __start_cc_report 08:52:49 INFO - 2015-10-26 08:52:49,986 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,951 08:52:49 INFO - 2015-10-26 08:52:49,987 DEBUG : BROWSER_OUTPUT: __end_cc_report 08:52:49 INFO - 2015-10-26 08:52:49,987 DEBUG : BROWSER_OUTPUT: __startTimestamp1445874769981__endTimestamp 08:52:49 INFO - 2015-10-26 08:52:49,987 DEBUG : BROWSER_OUTPUT: 08:52:49 INFO - 2015-10-26 08:52:49,987 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 08:52:49 INFO - 2015-10-26 08:52:49,987 DEBUG : BROWSER_OUTPUT: Number of tests: 14 08:52:49 INFO - 2015-10-26 08:52:49,987 DEBUG : BROWSER_OUTPUT: 08:52:49 INFO - 2015-10-26 08:52:49,987 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:96.50 Median:98.00 stddev:2.95 (3.0%) stddev-sans-first:2.99 08:52:49 INFO - 2015-10-26 08:52:49,989 DEBUG : BROWSER_OUTPUT: Values: 94.0 98.0 98.0 89.0 97.0 99.0 98.0 98.0 97.0 97.0 08:52:50 INFO - 2015-10-26 08:52:49,989 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:49,989 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:154.30 Median:151.50 stddev:12.20 (8.1%) stddev-sans-first:12.79 08:52:50 INFO - 2015-10-26 08:52:49,989 DEBUG : BROWSER_OUTPUT: Values: 149.0 149.0 171.0 177.0 140.0 146.0 154.0 146.0 164.0 147.0 08:52:50 INFO - 2015-10-26 08:52:49,989 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:49,989 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:148.50 Median:148.50 stddev:3.78 (2.5%) stddev-sans-first:2.87 08:52:50 INFO - 2015-10-26 08:52:49,989 DEBUG : BROWSER_OUTPUT: Values: 156.0 154.0 149.0 149.0 148.0 147.0 147.0 145.0 145.0 145.0 08:52:50 INFO - 2015-10-26 08:52:49,989 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:49,990 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:93.00 Median:80.50 stddev:14.73 (18.3%) stddev-sans-first:15.26 08:52:50 INFO - 2015-10-26 08:52:49,990 DEBUG : BROWSER_OUTPUT: Values: 84.0 105.0 103.0 122.0 89.0 80.0 80.0 81.0 105.0 81.0 08:52:50 INFO - 2015-10-26 08:52:49,990 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:49,990 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.70 Median:102.50 stddev:10.75 (10.5%) stddev-sans-first:11.32 08:52:50 INFO - 2015-10-26 08:52:49,990 DEBUG : BROWSER_OUTPUT: Values: 104.0 100.0 119.0 101.0 101.0 100.0 126.0 101.0 101.0 124.0 08:52:50 INFO - 2015-10-26 08:52:49,993 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:49,993 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 08:52:50 INFO - 2015-10-26 08:52:49,993 DEBUG : BROWSER_OUTPUT: Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 08:52:50 INFO - 2015-10-26 08:52:49,993 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:49,993 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:138.20 Median:137.00 stddev:3.12 (2.3%) stddev-sans-first:3.21 08:52:50 INFO - 2015-10-26 08:52:49,993 DEBUG : BROWSER_OUTPUT: Values: 136.0 137.0 137.0 145.0 137.0 137.0 143.0 137.0 137.0 136.0 08:52:50 INFO - 2015-10-26 08:52:49,993 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:49,994 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:89.40 Median:89.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 08:52:50 INFO - 2015-10-26 08:52:49,994 DEBUG : BROWSER_OUTPUT: Values: 89.0 89.0 89.0 90.0 89.0 90.0 90.0 89.0 90.0 89.0 08:52:50 INFO - 2015-10-26 08:52:49,994 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:49,994 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:80.60 Median:80.00 stddev:3.03 (3.8%) stddev-sans-first:3.15 08:52:50 INFO - 2015-10-26 08:52:49,996 DEBUG : BROWSER_OUTPUT: Values: 79.0 80.0 80.0 81.0 79.0 79.0 80.0 80.0 79.0 89.0 08:52:50 INFO - 2015-10-26 08:52:49,996 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:49,996 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:74.70 Median:75.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 08:52:50 INFO - 2015-10-26 08:52:49,996 DEBUG : BROWSER_OUTPUT: Values: 75.0 74.0 75.0 75.0 75.0 74.0 74.0 75.0 75.0 75.0 08:52:50 INFO - 2015-10-26 08:52:49,996 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:49,996 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:102.20 Median:102.50 stddev:1.87 (1.8%) stddev-sans-first:1.81 08:52:50 INFO - 2015-10-26 08:52:49,996 DEBUG : BROWSER_OUTPUT: Values: 100.0 100.0 101.0 102.0 103.0 102.0 101.0 103.0 104.0 106.0 08:52:50 INFO - 2015-10-26 08:52:49,996 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:49,997 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:134.90 Median:136.50 stddev:3.73 (2.7%) stddev-sans-first:3.67 08:52:50 INFO - 2015-10-26 08:52:49,997 DEBUG : BROWSER_OUTPUT: Values: 131.0 138.0 135.0 136.0 138.0 131.0 132.0 130.0 137.0 141.0 08:52:50 INFO - 2015-10-26 08:52:49,997 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:49,999 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.50 Median:205.00 stddev:4.40 (2.1%) stddev-sans-first:3.99 08:52:50 INFO - 2015-10-26 08:52:49,999 DEBUG : BROWSER_OUTPUT: Values: 196.0 207.0 201.0 205.0 206.0 198.0 204.0 205.0 207.0 196.0 08:52:50 INFO - 2015-10-26 08:52:49,999 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:49,999 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:95.20 Median:94.50 stddev:2.62 (2.8%) stddev-sans-first:1.74 08:52:50 INFO - 2015-10-26 08:52:50,000 DEBUG : BROWSER_OUTPUT: Values: 101.0 95.0 95.0 96.0 94.0 92.0 98.0 93.0 94.0 94.0 08:52:50 INFO - 2015-10-26 08:52:50,000 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 08:52:50 INFO - 2015-10-26 08:52:50,000 DEBUG : BROWSER_OUTPUT: 08:52:50 INFO - 2015-10-26 08:52:50,665 INFO : Browser exited with error code: 0 08:52:51 INFO - 2015-10-26 08:52:51,552 INFO : Completed test kraken (00:01:03) 08:52:51 INFO - 2015-10-26 08:52:51,552 INFO : Starting test v8_7 08:52:51 INFO - 2015-10-26 08:52:51,552 DEBUG : operating with platform_type : w7_ 08:52:51 INFO - 2015-10-26 08:52:51,552 INFO : Initialising browser for v8_7 test... 08:52:51 INFO - 2015-10-26 08:52:51,578 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpqnkv91\profile http://localhost:49703/getInfo.html 08:52:56 INFO - 2015-10-26 08:52:56,190 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 08:52:56 INFO - 2015-10-26 08:52:56,190 DEBUG : BROWSER_OUTPUT: colorDepth:24 08:52:56 INFO - 2015-10-26 08:52:56,190 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 08:52:56 INFO - 2015-10-26 08:52:56,190 DEBUG : BROWSER_OUTPUT: __metrics 08:52:56 INFO - 2015-10-26 08:52:56,296 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:52:57 INFO - 2015-10-26 08:52:57,104 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 08:52:57 INFO - 2015-10-26 08:52:57,346 DEBUG : BROWSER_OUTPUT: console.error: 08:52:57 INFO - 2015-10-26 08:52:57,349 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:52:57 INFO - 2015-10-26 08:52:57,349 DEBUG : BROWSER_OUTPUT: Stack: 08:52:57 INFO - 2015-10-26 08:52:57,349 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 08:52:57 INFO - 2015-10-26 08:52:57,349 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 08:52:57 INFO - 2015-10-26 08:52:57,349 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 08:52:57 INFO - 2015-10-26 08:52:57,349 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 08:52:57 INFO - 2015-10-26 08:52:57,349 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:52:57 INFO - 2015-10-26 08:52:57,349 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 08:52:57 INFO - 2015-10-26 08:52:57,349 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 08:52:57 INFO - 2015-10-26 08:52:57,349 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 08:52:57 INFO - 2015-10-26 08:52:57,349 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 08:52:57 INFO - 2015-10-26 08:52:57,349 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 08:52:57 INFO - 2015-10-26 08:52:57,349 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 08:52:57 INFO - 2015-10-26 08:52:57,349 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 08:52:57 INFO - 2015-10-26 08:52:57,351 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 5714.97 08:53:33 INFO - RETURN: kraken: 1634.40 08:53:33 INFO - RETURN: v8_7: 19953.82 08:53:33 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,131,25]]", "result": "19953.82"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,131,25]]", "result": "1634.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,131,25]]", "result": "5714.97"}}} 08:53:33 INFO - Return code: 0 08:53:33 INFO - # TBPL SUCCESS # 08:53:33 INFO - Running post-action listener: _resource_record_post_action 08:53:33 INFO - Running post-run listener: _resource_record_post_run 08:53:34 INFO - Total resource usage - Wall time: 1236s; CPU: 13.0%; Read bytes: 122892800; Write bytes: 435351552; Read time: 1297160; Write time: 4588550 08:53:34 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 16384; Write bytes: 1191936; Read time: 600; Write time: 7350 08:53:34 INFO - run-tests - Wall time: 1236s; CPU: 13.0%; Read bytes: 122876416; Write bytes: 412569600; Read time: 1296560; Write time: 4558820 08:53:34 INFO - Running post-run listener: _upload_blobber_files 08:53:34 INFO - Blob upload gear active. 08:53:34 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 08:53:34 INFO - Copying logs to upload dir... 08:53:34 INFO - mkdir: C:\slave\test\build\upload\logs program finished with exit code 0 elapsedTime=1506.622000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 26 mins, 7 secs) (at 2015-10-26 08:54:35.808747) ========= ========= Started set props: build_url (results: 0, elapsed: 22 secs) (at 2015-10-26 08:54:35.812197) ========= '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-077 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-077 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-077 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/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.101000 build_url: 'https://queue.taskcluster.net/v1/task/dGl2IixpRsumsb6ka5C7Ng/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 22 secs) (at 2015-10-26 08:54:58.730485) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2015-10-26 08:54:58.730792) ========= '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-077 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-077 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-077 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: 2 secs) (at 2015-10-26 08:55:01.317096) ========= ========= Started reboot slave lost (results: 0, elapsed: 29 secs) (at 2015-10-26 08:55:01.317408) ========= ========= Finished reboot slave lost (results: 0, elapsed: 29 secs) (at 2015-10-26 08:55:30.991042) =========