builder: fx-team_win7-ix_test-dromaeojs slave: t-w732-ix-169 starttime: 1445606704.29 results: success (0) buildid: 20151023052631 builduid: 221b4eab8b6048858f8de04ebaf60559 revision: 10103031391809746db0f178def31276cde2dad0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 06:25:04.290440) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-23 06:25:04.291594) ========= ========= Started set props: basedir (results: 0, elapsed: 35 secs) (at 2015-10-23 06:25:04.291893) ========= '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-169 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-169 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-169 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.701000 basedir: 'C:\\slave\\test' ========= Finished set props: basedir (results: 0, elapsed: 35 secs) (at 2015-10-23 06:25:40.012361) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2015-10-23 06:25:40.012704) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2015-10-23 06:25:41.934606) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 24 secs) (at 2015-10-23 06:25:41.934977) ========= '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-169 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-169 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-169 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.401000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 24 secs) (at 2015-10-23 06:26:06.283395) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 06:26:06.283671) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-23 06:26:06.284319) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 42 secs) (at 2015-10-23 06:26:06.284598) ========= '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-169 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-169 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-169 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 --06:26:31-- 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% 2.27 GB/s 06:26:32 (2.27 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=1.523000 ========= Finished 'bash -c ...' (results: 0, elapsed: 42 secs) (at 2015-10-23 06:26:48.532116) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 35 secs) (at 2015-10-23 06:26:48.532448) ========= '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-169 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-169 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-169 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=12.902000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 35 secs) (at 2015-10-23 06:27:23.832074) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 49 secs) (at 2015-10-23 06:27:23.832466) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 10103031391809746db0f178def31276cde2dad0 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 10103031391809746db0f178def31276cde2dad0 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-169 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-169 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-169 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-23 06:27:25,095 truncating revision to first 12 chars 2015-10-23 06:27:25,095 Setting DEBUG logging. 2015-10-23 06:27:25,095 attempt 1/10 2015-10-23 06:27:25,095 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/101030313918?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-23 06:27:26,874 unpacking tar archive at: fx-team-101030313918/testing/mozharness/ program finished with exit code 0 elapsedTime=2.393000 ========= Finished 'bash -c ...' (results: 0, elapsed: 49 secs) (at 2015-10-23 06:28:13.831756) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 mins, 14 secs) (at 2015-10-23 06:28:13.832138) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 mins, 14 secs) (at 2015-10-23 06:29:28.149264) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 06:29:28.149514) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-23 06:29:28.149875) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 26 mins, 32 secs) (at 2015-10-23 06:29:28.150164) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team-Non-PGO' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-169 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-169 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-169 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 06:29:37 INFO - MultiFileLogger online at 20151023 06:29:37 in C:\slave\test 06:29:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 06:29:37 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 06:29:37 INFO - {'append_to_log': False, 06:29:37 INFO - 'base_work_dir': 'C:\\slave\\test', 06:29:37 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 06:29:37 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 06:29:37 INFO - 'branch': 'Fx-Team-Non-PGO', 06:29:37 INFO - 'buildbot_json_path': 'buildprops.json', 06:29:37 INFO - 'config_files': ('talos/windows_config.py',), 06:29:37 INFO - 'default_actions': ('clobber', 06:29:37 INFO - 'read-buildbot-config', 06:29:37 INFO - 'download-and-extract', 06:29:37 INFO - 'populate-webroot', 06:29:37 INFO - 'create-virtualenv', 06:29:37 INFO - 'install', 06:29:37 INFO - 'run-tests'), 06:29:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:29:37 INFO - 'download_minidump_stackwalk': True, 06:29:37 INFO - 'download_symbols': 'ondemand', 06:29:37 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 06:29:37 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 06:29:37 INFO - 'hg': 'c:/mozilla-build/hg/hg', 06:29:37 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 06:29:37 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 06:29:37 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 06:29:37 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 06:29:37 INFO - 'C:/mozilla-build/tooltool.py'), 06:29:37 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 06:29:37 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 06:29:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:29:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:29:37 INFO - 'installer_path': 'installer.exe', 06:29:37 INFO - 'log_level': 'info', 06:29:37 INFO - 'log_name': 'talos', 06:29:37 INFO - 'log_to_console': True, 06:29:37 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 06:29:37 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 06:29:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 06:29:37 INFO - 'opt_config_files': (), 06:29:37 INFO - 'pip_index': False, 06:29:37 INFO - 'sps_profile': False, 06:29:37 INFO - 'sps_profile_interval': 0, 06:29:37 INFO - 'suite': 'dromaeojs', 06:29:37 INFO - 'system_bits': '32', 06:29:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:29:37 INFO - 'title': 'T-W732-IX-169', 06:29:37 INFO - 'use_talos_json': True, 06:29:37 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 06:29:37 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 06:29:37 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 06:29:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:29:37 INFO - 'work_dir': 'build'} 06:29:37 INFO - ##### 06:29:37 INFO - ##### Running clobber step. 06:29:37 INFO - ##### 06:29:37 INFO - Running pre-action listener: _resource_record_pre_action 06:29:37 INFO - Running main action method: clobber 06:29:37 INFO - rmtree: C:\slave\test\build 06:29:37 INFO - Using _rmtree_windows ... 06:29:37 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 06:32:18 INFO - Running post-action listener: _resource_record_post_action 06:32:18 INFO - ##### 06:32:18 INFO - ##### Running read-buildbot-config step. 06:32:18 INFO - ##### 06:32:18 INFO - Running pre-action listener: _resource_record_pre_action 06:32:18 INFO - Running main action method: read_buildbot_config 06:32:18 INFO - Using buildbot properties: 06:32:18 INFO - { 06:32:18 INFO - "properties": { 06:32:18 INFO - "buildnumber": 747, 06:32:18 INFO - "product": "firefox", 06:32:18 INFO - "script_repo_revision": "production", 06:32:18 INFO - "builddir": "fx-team_win7-ix_test-dromaeojs", 06:32:18 INFO - "repository": "", 06:32:18 INFO - "buildername": "Windows 7 32-bit fx-team talos dromaeojs", 06:32:18 INFO - "buildid": "20151023052631", 06:32:18 INFO - "slavename": "t-w732-ix-169", 06:32:18 INFO - "pgo_build": "False", 06:32:18 INFO - "basedir": "C:\\slave\\test", 06:32:18 INFO - "project": "", 06:32:18 INFO - "platform": "win7-ix", 06:32:18 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 06:32:18 INFO - "slavebuilddir": "test", 06:32:18 INFO - "scheduler": "tests-fx-team-win32-talos", 06:32:18 INFO - "branch": "fx-team", 06:32:18 INFO - "repo_path": "integration/fx-team", 06:32:18 INFO - "stage_platform": "win32", 06:32:18 INFO - "builduid": "221b4eab8b6048858f8de04ebaf60559", 06:32:18 INFO - "revision": "10103031391809746db0f178def31276cde2dad0" 06:32:18 INFO - }, 06:32:18 INFO - "sourcestamp": { 06:32:18 INFO - "repository": "", 06:32:18 INFO - "hasPatch": false, 06:32:18 INFO - "project": "", 06:32:18 INFO - "branch": "fx-team-win32-talos", 06:32:18 INFO - "changes": [ 06:32:18 INFO - { 06:32:18 INFO - "category": null, 06:32:18 INFO - "files": [ 06:32:18 INFO - { 06:32:18 INFO - "url": null, 06:32:18 INFO - "name": "https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 06:32:18 INFO - } 06:32:18 INFO - ], 06:32:18 INFO - "repository": "", 06:32:18 INFO - "rev": "10103031391809746db0f178def31276cde2dad0", 06:32:18 INFO - "who": "jryans@gmail.com", 06:32:18 INFO - "when": 1445605901, 06:32:18 INFO - "number": 6565984, 06:32:18 INFO - "comments": "Bug 1217687 - Appease the mozbuild reading test. r=me\n", 06:32:18 INFO - "project": "", 06:32:18 INFO - "at": "Fri 23 Oct 2015 06:11:41", 06:32:18 INFO - "branch": "fx-team-win32-talos", 06:32:18 INFO - "revlink": "", 06:32:18 INFO - "properties": [ 06:32:18 INFO - [ 06:32:18 INFO - "buildid", 06:32:18 INFO - "20151023052631", 06:32:18 INFO - "Change" 06:32:18 INFO - ], 06:32:18 INFO - [ 06:32:18 INFO - "builduid", 06:32:18 INFO - "221b4eab8b6048858f8de04ebaf60559", 06:32:18 INFO - "Change" 06:32:18 INFO - ], 06:32:18 INFO - [ 06:32:18 INFO - "pgo_build", 06:32:18 INFO - "False", 06:32:18 INFO - "Change" 06:32:18 INFO - ] 06:32:18 INFO - ], 06:32:18 INFO - "revision": "10103031391809746db0f178def31276cde2dad0" 06:32:18 INFO - } 06:32:18 INFO - ], 06:32:18 INFO - "revision": "10103031391809746db0f178def31276cde2dad0" 06:32:18 INFO - } 06:32:18 INFO - } 06:32:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 06:32:18 INFO - Running post-action listener: _resource_record_post_action 06:32:18 INFO - ##### 06:32:18 INFO - ##### Running download-and-extract step. 06:32:18 INFO - ##### 06:32:18 INFO - Running pre-action listener: _resource_record_pre_action 06:32:18 INFO - Running main action method: download_and_extract 06:32:18 INFO - mkdir: C:\slave\test\build\tests 06:32:18 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')]} 06:32:18 INFO - https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 06:32:18 INFO - trying https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/test_packages.json 06:32:18 INFO - Downloading https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json 06:32:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1 06:32:21 INFO - Downloaded 1221 bytes. 06:32:21 INFO - Reading from file C:\slave\test\build\test_packages.json 06:32:21 INFO - Using the following test package requirements: 06:32:21 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 06:32:21 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:32:21 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 06:32:21 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:32:21 INFO - u'jsshell-win32.zip'], 06:32:21 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:32:21 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 06:32:21 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 06:32:21 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:32:21 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 06:32:21 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:32:21 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 06:32:21 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:32:21 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 06:32:21 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 06:32:21 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 06:32:21 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 06:32:21 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 06:32:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:32:21 INFO - https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 06:32:21 INFO - trying https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 06:32:21 INFO - Downloading https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/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 06:32:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/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 06:32:41 INFO - Downloaded 18005235 bytes. 06:32:41 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 06:32:41 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 06:32:41 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 06:33:10 INFO - Return code: 0 06:33:10 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 06:33:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:33:10 INFO - https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 06:33:10 INFO - trying https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 06:33:10 INFO - Downloading https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/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 06:33:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/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 06:33:30 INFO - Downloaded 18005235 bytes. 06:33:30 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 06:33:30 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.common.tests.zip 06:33:30 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 06:33:52 INFO - Return code: 0 06:33:52 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')]} 06:33:52 INFO - https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 06:33:52 INFO - trying https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 06:33:52 INFO - Downloading https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/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 06:33:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/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 06:34:06 INFO - Downloaded 11131307 bytes. 06:34:06 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] in C:\slave\test\build\tests 06:34:06 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-44.0a1.en-US.win32.talos.tests.zip 06:34:06 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 06:34:06 INFO - Return code: 0 06:34:06 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')]} 06:34:06 INFO - https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 06:34:06 INFO - trying https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 06:34:06 INFO - Downloading https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test\build\firefox-44.0a1.en-US.win32.zip 06:34:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/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 06:34:27 INFO - Downloaded 51464626 bytes. 06:34:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 06:34:27 INFO - mkdir: C:\slave\test\properties 06:34:27 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 06:34:27 INFO - Writing to file C:\slave\test\properties\build_url 06:34:27 INFO - Contents: 06:34:27 INFO - build_url:https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 06:34:27 INFO - Running post-action listener: _resource_record_post_action 06:34:27 INFO - Running post-action listener: set_extra_try_arguments 06:34:27 INFO - ##### 06:34:27 INFO - ##### Running populate-webroot step. 06:34:27 INFO - ##### 06:34:27 INFO - Running pre-action listener: _resource_record_pre_action 06:34:27 INFO - Running main action method: populate_webroot 06:34:27 INFO - Guessing talos json url... 06:34:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:27 INFO - https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 06:34:27 INFO - trying https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 06:34:27 INFO - Downloading https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 06:34:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/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 06:34:29 INFO - Downloaded 103 bytes. 06:34:29 INFO - Reading from file C:\slave\test\build\firefox-44.0a1.en-US.win32.txt 06:34:29 INFO - Contents: 06:34:29 INFO - 20151023052631 06:34:29 INFO - https://hg.mozilla.org/integration/fx-team/rev/10103031391809746db0f178def31276cde2dad0 06:34:29 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')]} 06:34:29 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/1010303139/testing/talos/talos.json 06:34:29 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/1010303139/testing/talos/talos.json to C:\slave\test\build\talos.json 06:34:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/1010303139/testing/talos/talos.json', 'file_name': 'C:\\slave\\test\\build\\talos.json'}, attempt #1 06:34:30 INFO - Downloaded 4264 bytes. 06:34:30 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 06:34:30 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 06:34:30 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 06:34:30 INFO - u'../fennec_ids.txt'], 06:34:30 INFO - u'tests': [u'tcheck2']}, 06:34:30 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 06:34:30 INFO - u'--tppagecycles', 06:34:30 INFO - u'7'], 06:34:30 INFO - u'tests': [u'tsvgm']}}, 06:34:30 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 06:34:30 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 06:34:30 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 06:34:30 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 06:34:30 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 06:34:30 INFO - u'tests': [u'dromaeo_css', 06:34:30 INFO - u'kraken', 06:34:30 INFO - u'v8_7']}, 06:34:30 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:30 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:34:30 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:30 INFO - u'talos_options': [u'--e10s'], 06:34:30 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 06:34:30 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:30 INFO - u'tests': [u'damp', u'tps']}, 06:34:30 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:30 INFO - u'talos_options': [u'--e10s'], 06:34:30 INFO - u'tests': [u'damp', u'tps']}, 06:34:30 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 06:34:30 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 06:34:30 INFO - u'other': {u'tests': [u'a11yr', 06:34:30 INFO - u'ts_paint', 06:34:30 INFO - u'tpaint', 06:34:30 INFO - u'sessionrestore', 06:34:30 INFO - u'sessionrestore_no_auto_restore']}, 06:34:30 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 06:34:30 INFO - u'tests': [u'a11yr', 06:34:30 INFO - u'ts_paint', 06:34:30 INFO - u'tpaint', 06:34:30 INFO - u'sessionrestore', 06:34:30 INFO - u'sessionrestore_no_auto_restore']}, 06:34:30 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 06:34:30 INFO - u'tests': [u'a11yr', 06:34:30 INFO - u'ts_paint', 06:34:30 INFO - u'tpaint', 06:34:30 INFO - u'sessionrestore', 06:34:30 INFO - u'sessionrestore_no_auto_restore']}, 06:34:30 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 06:34:30 INFO - u'tests': [u'a11yr', 06:34:30 INFO - u'ts_paint', 06:34:30 INFO - u'tpaint', 06:34:30 INFO - u'sessionrestore', 06:34:30 INFO - u'sessionrestore_no_auto_restore']}, 06:34:30 INFO - u'other_l64': {u'tests': [u'a11yr', 06:34:30 INFO - u'ts_paint', 06:34:30 INFO - u'tpaint', 06:34:30 INFO - u'sessionrestore', 06:34:30 INFO - u'sessionrestore_no_auto_restore']}, 06:34:30 INFO - u'other_nol64': {u'tests': [u'a11yr', 06:34:30 INFO - u'ts_paint', 06:34:30 INFO - u'tpaint', 06:34:30 INFO - u'sessionrestore', 06:34:30 INFO - u'sessionrestore_no_auto_restore']}, 06:34:30 INFO - u'svgr': {u'tests': [u'tsvgx', 06:34:30 INFO - u'tsvgr_opacity', 06:34:30 INFO - u'tart', 06:34:30 INFO - u'tscrollx', 06:34:30 INFO - u'cart']}, 06:34:30 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 06:34:30 INFO - u'tests': [u'tsvgx', 06:34:30 INFO - u'tsvgr_opacity', 06:34:30 INFO - u'tart', 06:34:30 INFO - u'tscrollx', 06:34:30 INFO - u'cart']}, 06:34:30 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:30 INFO - u'tests': [u'tp5o']}, 06:34:30 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:30 INFO - u'talos_options': [u'--e10s'], 06:34:30 INFO - u'tests': [u'tp5o']}, 06:34:30 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:30 INFO - u'talos_options': [u'--xperf_path', 06:34:30 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:34:30 INFO - u'tests': [u'tp5n']}, 06:34:30 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:34:30 INFO - u'talos_options': [u'--e10s', 06:34:30 INFO - u'--xperf_path', 06:34:30 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 06:34:30 INFO - u'C:/slave/talos-data/talos/xperf.config'], 06:34:30 INFO - u'tests': [u'tp5n']}}, 06:34:30 INFO - u'talos.zip': {u'path': u'', 06:34:30 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:34:30 INFO - Running post-action listener: _resource_record_post_action 06:34:30 INFO - ##### 06:34:30 INFO - ##### Running create-virtualenv step. 06:34:30 INFO - ##### 06:34:30 INFO - Running pre-action listener: _resource_record_pre_action 06:34:30 INFO - Running main action method: create_virtualenv 06:34:30 INFO - Creating virtualenv C:\slave\test\build/venv 06:34:30 INFO - mkdir: C:\slave\test\build\venv\Scripts 06:34:30 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 06:34:30 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'! 06:34:30 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 06:34:30 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 06:34:31 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 06:34:35 INFO - Installing distribute......................................................................................................................................................................................done. 06:34:36 INFO - Return code: 0 06:34:36 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 06:34:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:36 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 0x01833B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01889EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01750680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, '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 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F3F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-169', 'USERDOMAIN': 'T-W732-IX-169', '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-169', '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 06:34:36 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 06:34:36 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 06:34:36 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:34:36 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:34:36 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:34:36 INFO - 'COMPUTERNAME': 'T-W732-IX-169', 06:34:36 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:34:36 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:34:36 INFO - 'DCLOCATION': 'SCL3', 06:34:36 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:34:36 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:34:36 INFO - 'HOMEDRIVE': 'C:', 06:34:36 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:34:36 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:34:36 INFO - 'KTS_VERSION': '1.19c', 06:34:36 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:34:36 INFO - 'LOGONSERVER': '\\\\T-W732-IX-169', 06:34:36 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:34:36 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:34:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:36 INFO - 'MOZ_NO_REMOTE': '1', 06:34:36 INFO - 'NO_EM_RESTART': '1', 06:34:36 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:34:36 INFO - 'OS': 'Windows_NT', 06:34:36 INFO - 'OURDRIVE': 'C:', 06:34:36 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:34:36 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:34:36 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:34:36 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:34:36 INFO - 'PROCESSOR_LEVEL': '6', 06:34:36 INFO - 'PROCESSOR_REVISION': '1e05', 06:34:36 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:34:36 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:34:36 INFO - 'PROMPT': '$P$G', 06:34:36 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:34:36 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:34:36 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:34:36 INFO - 'PWD': 'C:\\slave\\test', 06:34:36 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:34:36 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:34:36 INFO - 'SYSTEMDRIVE': 'C:', 06:34:36 INFO - 'SYSTEMROOT': 'C:\\windows', 06:34:36 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:34:36 INFO - 'TEST1': 'testie', 06:34:36 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:34:36 INFO - 'USERDOMAIN': 'T-W732-IX-169', 06:34:36 INFO - 'USERNAME': 'cltbld', 06:34:36 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:34:36 INFO - 'WINDIR': 'C:\\windows', 06:34:36 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:34:36 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:34:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:34:40 INFO - Ignoring indexes: http://pypi.python.org/simple/ 06:34:40 INFO - Downloading/unpacking pip>=1.5 06:34:40 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache 06:34:47 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 06:34:47 INFO - Running setup.py egg_info for package pip 06:34:47 INFO - warning: no files found matching 'pip\cacert.pem' 06:34:47 INFO - warning: no files found matching '*.html' under directory 'docs' 06:34:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 06:34:47 INFO - no previously-included directories found matching 'docs\_build\_sources' 06:34:47 INFO - Installing collected packages: pip 06:34:47 INFO - Found existing installation: pip 0.8.2 06:34:47 INFO - Uninstalling pip: 06:34:47 INFO - Successfully uninstalled pip 06:34:47 INFO - Running setup.py install for pip 06:34:47 INFO - warning: no files found matching 'pip\cacert.pem' 06:34:47 INFO - warning: no files found matching '*.html' under directory 'docs' 06:34:47 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 06:34:47 INFO - no previously-included directories found matching 'docs\_build\_sources' 06:34:47 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 06:34:47 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 06:34:47 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 06:34:47 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 06:34:47 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 06:34:47 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 06:34:47 INFO - Successfully installed pip 06:34:47 INFO - Cleaning up... 06:34:47 INFO - Return code: 0 06:34:47 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv 06:34:47 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')]} 06:34:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:47 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')]} 06:34:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:47 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 0x01833B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01889EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01750680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, '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 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F3F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-169', 'USERDOMAIN': 'T-W732-IX-169', '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-169', '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 06:34:47 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 06:34:47 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 06:34:47 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:34:47 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:34:47 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:34:47 INFO - 'COMPUTERNAME': 'T-W732-IX-169', 06:34:47 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:34:47 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:34:47 INFO - 'DCLOCATION': 'SCL3', 06:34:47 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:34:47 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:34:47 INFO - 'HOMEDRIVE': 'C:', 06:34:47 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:34:47 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:34:47 INFO - 'KTS_VERSION': '1.19c', 06:34:47 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:34:47 INFO - 'LOGONSERVER': '\\\\T-W732-IX-169', 06:34:47 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:34:47 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:34:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:47 INFO - 'MOZ_NO_REMOTE': '1', 06:34:47 INFO - 'NO_EM_RESTART': '1', 06:34:47 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:34:47 INFO - 'OS': 'Windows_NT', 06:34:47 INFO - 'OURDRIVE': 'C:', 06:34:47 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;', 06:34:47 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:34:47 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:34:47 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:34:47 INFO - 'PROCESSOR_LEVEL': '6', 06:34:47 INFO - 'PROCESSOR_REVISION': '1e05', 06:34:47 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:34:47 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:34:47 INFO - 'PROMPT': '$P$G', 06:34:47 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:34:47 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:34:47 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:34:47 INFO - 'PWD': 'C:\\slave\\test', 06:34:47 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:34:47 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:34:47 INFO - 'SYSTEMDRIVE': 'C:', 06:34:47 INFO - 'SYSTEMROOT': 'C:\\windows', 06:34:47 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:34:47 INFO - 'TEST1': 'testie', 06:34:47 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:34:47 INFO - 'USERDOMAIN': 'T-W732-IX-169', 06:34:47 INFO - 'USERNAME': 'cltbld', 06:34:47 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:34:47 INFO - 'WINDIR': 'C:\\windows', 06:34:47 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:34:47 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:34:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:34:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:49 INFO - Downloading/unpacking psutil>=0.7.1 06:34: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 06:34: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 06:34:54 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 06:34:54 INFO - Installing collected packages: psutil 06:34:54 INFO - Successfully installed psutil 06:34:54 INFO - Cleaning up... 06:34:54 INFO - Return code: 0 06:34:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv 06:34:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:54 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01833B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01889EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01750680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, '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 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F3F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-169', 'USERDOMAIN': 'T-W732-IX-169', '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-169', '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 06:34:54 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test\build 06:34:54 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 06:34:54 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:34:54 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:34:54 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:34:54 INFO - 'COMPUTERNAME': 'T-W732-IX-169', 06:34:54 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:34:54 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:34:54 INFO - 'DCLOCATION': 'SCL3', 06:34:54 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:34:54 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:34:54 INFO - 'HOMEDRIVE': 'C:', 06:34:54 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:34:54 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:34:54 INFO - 'KTS_VERSION': '1.19c', 06:34:54 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:34:54 INFO - 'LOGONSERVER': '\\\\T-W732-IX-169', 06:34:54 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:34:54 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:34:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:54 INFO - 'MOZ_NO_REMOTE': '1', 06:34:54 INFO - 'NO_EM_RESTART': '1', 06:34:54 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:34:54 INFO - 'OS': 'Windows_NT', 06:34:54 INFO - 'OURDRIVE': 'C:', 06:34:54 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:34:54 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:34:54 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:34:54 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:34:54 INFO - 'PROCESSOR_LEVEL': '6', 06:34:54 INFO - 'PROCESSOR_REVISION': '1e05', 06:34:54 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:34:54 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:34:54 INFO - 'PROMPT': '$P$G', 06:34:54 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:34:54 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:34:54 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:34:54 INFO - 'PWD': 'C:\\slave\\test', 06:34:54 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:34:54 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:34:54 INFO - 'SYSTEMDRIVE': 'C:', 06:34:54 INFO - 'SYSTEMROOT': 'C:\\windows', 06:34:54 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:34:54 INFO - 'TEST1': 'testie', 06:34:54 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:34:54 INFO - 'USERDOMAIN': 'T-W732-IX-169', 06:34:54 INFO - 'USERNAME': 'cltbld', 06:34:54 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:34:54 INFO - 'WINDIR': 'C:\\windows', 06:34:54 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:34:54 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:34:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:34:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:55 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:34:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:34:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:34:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:34:56 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 06:34:56 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 06:34:56 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) 06:34:56 INFO - Installing collected packages: mozsystemmonitor 06:34:56 INFO - Running setup.py install for mozsystemmonitor 06:34:56 INFO - Successfully installed mozsystemmonitor 06:34:56 INFO - Cleaning up... 06:34:56 INFO - Return code: 0 06:34:56 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 06:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:34:56 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x01833B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01889EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01750680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, '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 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F3F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-169', 'USERDOMAIN': 'T-W732-IX-169', '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-169', '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 06:34:56 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build 06:34:56 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:34:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:34:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:34:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:34:56 INFO - 'COMPUTERNAME': 'T-W732-IX-169', 06:34:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:34:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:34:56 INFO - 'DCLOCATION': 'SCL3', 06:34:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:34:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:34:56 INFO - 'HOMEDRIVE': 'C:', 06:34:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:34:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:34:56 INFO - 'KTS_VERSION': '1.19c', 06:34:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:34:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-169', 06:34:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:34:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:34:56 INFO - 'MOZ_NO_REMOTE': '1', 06:34:56 INFO - 'NO_EM_RESTART': '1', 06:34:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:34:56 INFO - 'OS': 'Windows_NT', 06:34:56 INFO - 'OURDRIVE': 'C:', 06:34:56 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:34:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:34:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:34:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:34:56 INFO - 'PROCESSOR_LEVEL': '6', 06:34:56 INFO - 'PROCESSOR_REVISION': '1e05', 06:34:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:34:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:34:56 INFO - 'PROMPT': '$P$G', 06:34:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:34:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:34:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:34:56 INFO - 'PWD': 'C:\\slave\\test', 06:34:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:34:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:34:56 INFO - 'SYSTEMDRIVE': 'C:', 06:34:56 INFO - 'SYSTEMROOT': 'C:\\windows', 06:34:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:34:56 INFO - 'TEST1': 'testie', 06:34:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:34:56 INFO - 'USERDOMAIN': 'T-W732-IX-169', 06:34:56 INFO - 'USERNAME': 'cltbld', 06:34:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:34:56 INFO - 'WINDIR': 'C:\\windows', 06:34:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:34:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:34:57 INFO - Downloading/unpacking blobuploader==1.2.4 06:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:34:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:34:59 INFO - Downloading blobuploader-1.2.4.tar.gz 06:34:59 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz 06:34:59 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 06:34:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:34:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:34:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:35:02 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 06:35:02 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 06:35:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:35:02 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 06:35:02 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 06:35:02 INFO - Downloading docopt-0.6.1.tar.gz 06:35:03 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 06:35:03 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 06:35:03 INFO - Installing collected packages: blobuploader, requests, docopt 06:35:03 INFO - Running setup.py install for blobuploader 06:35:03 INFO - Running setup.py install for requests 06:35:04 INFO - Running setup.py install for docopt 06:35:04 INFO - Successfully installed blobuploader requests docopt 06:35:04 INFO - Cleaning up... 06:35:05 INFO - Return code: 0 06:35:05 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 06:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:05 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 0x01833B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01889EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01750680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, '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 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F3F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-169', 'USERDOMAIN': 'T-W732-IX-169', '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-169', '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 06:35:05 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 06:35:05 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 06:35:05 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:35:05 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:35:05 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:35:05 INFO - 'COMPUTERNAME': 'T-W732-IX-169', 06:35:05 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:35:05 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:35:05 INFO - 'DCLOCATION': 'SCL3', 06:35:05 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:35:05 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:35:05 INFO - 'HOMEDRIVE': 'C:', 06:35:05 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:35:05 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:35:05 INFO - 'KTS_VERSION': '1.19c', 06:35:05 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:35:05 INFO - 'LOGONSERVER': '\\\\T-W732-IX-169', 06:35:05 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:35:05 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:05 INFO - 'MOZ_NO_REMOTE': '1', 06:35:05 INFO - 'NO_EM_RESTART': '1', 06:35:05 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:35:05 INFO - 'OS': 'Windows_NT', 06:35:05 INFO - 'OURDRIVE': 'C:', 06:35:05 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:35:05 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:35:05 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:35:05 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:35:05 INFO - 'PROCESSOR_LEVEL': '6', 06:35:05 INFO - 'PROCESSOR_REVISION': '1e05', 06:35:05 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:35:05 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:35:05 INFO - 'PROMPT': '$P$G', 06:35:05 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:35:05 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:35:05 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:35:05 INFO - 'PWD': 'C:\\slave\\test', 06:35:05 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:35:05 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:35:05 INFO - 'SYSTEMDRIVE': 'C:', 06:35:05 INFO - 'SYSTEMROOT': 'C:\\windows', 06:35:05 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:35:05 INFO - 'TEST1': 'testie', 06:35:05 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:35:05 INFO - 'USERDOMAIN': 'T-W732-IX-169', 06:35:05 INFO - 'USERNAME': 'cltbld', 06:35:05 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:35:05 INFO - 'WINDIR': 'C:\\windows', 06:35:05 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:35:05 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 06:35:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-v7xw9o-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 06:35:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 06:35:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mioe2q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 06:35:05 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 06:35:05 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-cnn6fv-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 06:35:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 06:35:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6ii8da-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 06:35:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 06:35:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ithkup-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 06:35:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 06:35:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-isys7g-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 06:35:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 06:35:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kgvjt0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 06:35:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 06:35:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mstsc2-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 06:35:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 06:35:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-n2e4yu-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 06:35:06 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 06:35:06 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lxjis0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 06:35:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 06:35:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w0r71r-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 06:35:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 06:35:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-zoa6yl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 06:35:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 06:35:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-rmltuj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 06:35:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 06:35:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fpvgjf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 06:35:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 06:35:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-crzibz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 06:35:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 06:35:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ph9hz5-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 06:35:07 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 06:35:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mt6sja-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 06:35:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:35:07 INFO - Running setup.py install for manifestparser 06:35:08 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 06:35:08 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 06:35:08 INFO - Running setup.py install for mozcrash 06:35:08 INFO - Running setup.py install for mozdebug 06:35:09 INFO - Running setup.py install for mozdevice 06:35:09 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 06:35:09 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 06:35:09 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 06:35:09 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 06:35:09 INFO - Running setup.py install for mozfile 06:35:10 INFO - Running setup.py install for mozhttpd 06:35:10 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 06:35:10 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 06:35:10 INFO - Running setup.py install for mozinfo 06:35:10 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 06:35:10 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 06:35:11 INFO - Running setup.py install for mozInstall 06:35:11 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 06:35:11 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 06:35:11 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 06:35:11 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 06:35:11 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 06:35:11 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 06:35:11 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 06:35:11 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 06:35:11 INFO - Running setup.py install for mozleak 06:35:11 INFO - Running setup.py install for mozlog 06:35:11 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 06:35:11 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 06:35:12 INFO - Running setup.py install for moznetwork 06:35:12 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 06:35:12 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 06:35:12 INFO - Running setup.py install for mozprocess 06:35:13 INFO - Running setup.py install for mozprofile 06:35:13 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 06:35:13 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 06:35:13 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 06:35:13 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 06:35:13 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 06:35:13 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 06:35:14 INFO - Running setup.py install for mozrunner 06:35:14 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 06:35:14 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 06:35:14 INFO - Running setup.py install for mozscreenshot 06:35:15 INFO - Running setup.py install for moztest 06:35:15 INFO - Running setup.py install for mozversion 06:35:15 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 06:35:15 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 06:35:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:35:15 INFO - Cleaning up... 06:35:15 INFO - Return code: 0 06:35:15 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:35:15 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')]} 06:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35:15 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')]} 06:35:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35:15 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 0x01833B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01889EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01750680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, '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 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F3F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-169', 'USERDOMAIN': 'T-W732-IX-169', '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-169', '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 06:35:16 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 06:35:16 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 06:35:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:35:16 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:35:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:35:16 INFO - 'COMPUTERNAME': 'T-W732-IX-169', 06:35:16 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:35:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:35:16 INFO - 'DCLOCATION': 'SCL3', 06:35:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:35:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:35:16 INFO - 'HOMEDRIVE': 'C:', 06:35:16 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:35:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:35:16 INFO - 'KTS_VERSION': '1.19c', 06:35:16 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:35:16 INFO - 'LOGONSERVER': '\\\\T-W732-IX-169', 06:35:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:35:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:16 INFO - 'MOZ_NO_REMOTE': '1', 06:35:16 INFO - 'NO_EM_RESTART': '1', 06:35:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:35:16 INFO - 'OS': 'Windows_NT', 06:35:16 INFO - 'OURDRIVE': 'C:', 06:35: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;', 06:35:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:35:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:35:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:35:16 INFO - 'PROCESSOR_LEVEL': '6', 06:35:16 INFO - 'PROCESSOR_REVISION': '1e05', 06:35:16 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:35:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:35:16 INFO - 'PROMPT': '$P$G', 06:35:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:35:16 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:35:16 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:35:16 INFO - 'PWD': 'C:\\slave\\test', 06:35:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:35:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:35:16 INFO - 'SYSTEMDRIVE': 'C:', 06:35:16 INFO - 'SYSTEMROOT': 'C:\\windows', 06:35:16 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:35:16 INFO - 'TEST1': 'testie', 06:35:16 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:35:16 INFO - 'USERDOMAIN': 'T-W732-IX-169', 06:35:16 INFO - 'USERNAME': 'cltbld', 06:35:16 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:35:16 INFO - 'WINDIR': 'C:\\windows', 06:35:16 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:35:16 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 06:35:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jtwwzp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 06:35:16 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)) 06:35:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 06:35:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jpeyb3-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 06:35:16 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)) 06:35:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 06:35:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-nf__jy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 06:35:16 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)) 06:35:16 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 06:35:16 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yhbsth-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 06:35:17 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)) 06:35:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 06:35:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0ixszn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 06:35:17 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)) 06:35:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 06:35:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-l3jg_y-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 06:35:17 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)) 06:35:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 06:35:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-e2butf-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 06:35:17 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)) 06:35:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 06:35:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qofhec-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 06:35:17 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)) 06:35:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 06:35:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ksjzwk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 06:35:17 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)) 06:35:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 06:35:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xt3gtk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 06:35:17 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)) 06:35:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 06:35:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aw08oo-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 06:35:17 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)) 06:35:17 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 06:35:17 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-alj5zp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 06:35:18 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)) 06:35:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 06:35:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w5kck9-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 06:35:18 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)) 06:35:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 06:35:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mgn7tc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 06:35:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 06:35:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 06:35:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-d9c5ay-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 06:35:18 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)) 06:35:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 06:35:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-bf22ks-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 06:35:18 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)) 06:35:18 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 06:35:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kc4prk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 06:35:18 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)) 06:35:18 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)) 06:35:18 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)) 06:35:18 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)) 06:35:18 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)) 06:35:18 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)) 06:35:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 06:35:18 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 06:35:18 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 06:35:19 INFO - Downloading blessings-1.5.1.tar.gz 06:35:19 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 06:35:19 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 06:35:20 INFO - Installing collected packages: blessings 06:35:20 INFO - Running setup.py install for blessings 06:35:20 INFO - Successfully installed blessings 06:35:20 INFO - Cleaning up... 06:35:23 INFO - Return code: 0 06:35:23 INFO - Done creating virtualenv C:\slave\test\build/venv. 06:35:23 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 06:35:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 06:35:23 INFO - Reading from file tmpfile_stdout 06:35:23 INFO - Using _rmtree_windows ... 06:35:23 INFO - Using _rmtree_windows ... 06:35:23 INFO - Current package versions: 06:35:23 INFO - blessings == 1.5.1 06:35:23 INFO - blobuploader == 1.2.4 06:35:23 INFO - docopt == 0.6.1 06:35:23 INFO - manifestparser == 1.1 06:35:23 INFO - mozInstall == 1.12 06:35:23 INFO - mozcrash == 0.16 06:35:23 INFO - mozdebug == 0.1 06:35:23 INFO - mozdevice == 0.46 06:35:23 INFO - mozfile == 1.2 06:35:23 INFO - mozhttpd == 0.7 06:35:23 INFO - mozinfo == 0.8 06:35:23 INFO - mozleak == 0.1 06:35:23 INFO - mozlog == 3.0 06:35:23 INFO - moznetwork == 0.27 06:35:23 INFO - mozprocess == 0.22 06:35:23 INFO - mozprofile == 0.27 06:35:23 INFO - mozrunner == 6.10 06:35:23 INFO - mozscreenshot == 0.1 06:35:23 INFO - mozsystemmonitor == 0.0 06:35:23 INFO - moztest == 0.7 06:35:23 INFO - mozversion == 1.4 06:35:23 INFO - psutil == 3.1.1 06:35:23 INFO - requests == 1.2.3 06:35:23 INFO - Installing None into virtualenv C:\slave\test\build/venv 06:35: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')]} 06:35:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:35: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')]} 06:35:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:35: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', '-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 0x01833B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01889EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x01750680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, '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 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F3F660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-169', 'USERDOMAIN': 'T-W732-IX-169', '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-169', '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 06:35:23 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 06:35:23 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 06:35:23 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:35:23 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:35:23 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:35:23 INFO - 'COMPUTERNAME': 'T-W732-IX-169', 06:35:23 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:35:23 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:35:23 INFO - 'DCLOCATION': 'SCL3', 06:35:23 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:35:23 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:35:23 INFO - 'HOMEDRIVE': 'C:', 06:35:23 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:35:23 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:35:23 INFO - 'KTS_VERSION': '1.19c', 06:35:23 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:35:23 INFO - 'LOGONSERVER': '\\\\T-W732-IX-169', 06:35:23 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:35:23 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:35:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:23 INFO - 'MOZ_NO_REMOTE': '1', 06:35:23 INFO - 'NO_EM_RESTART': '1', 06:35:23 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:35:23 INFO - 'OS': 'Windows_NT', 06:35:23 INFO - 'OURDRIVE': 'C:', 06:35: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;', 06:35:23 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:35:23 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:35:23 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:35:23 INFO - 'PROCESSOR_LEVEL': '6', 06:35:23 INFO - 'PROCESSOR_REVISION': '1e05', 06:35:23 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:35:23 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:35:23 INFO - 'PROMPT': '$P$G', 06:35:23 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:35:23 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:35:23 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:35:23 INFO - 'PWD': 'C:\\slave\\test', 06:35:23 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:35:23 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:35:23 INFO - 'SYSTEMDRIVE': 'C:', 06:35:23 INFO - 'SYSTEMROOT': 'C:\\windows', 06:35:23 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:35:23 INFO - 'TEST1': 'testie', 06:35:23 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:35:23 INFO - 'USERDOMAIN': 'T-W732-IX-169', 06:35:23 INFO - 'USERNAME': 'cltbld', 06:35:23 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:35:23 INFO - 'WINDIR': 'C:\\windows', 06:35:23 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:35:23 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:35:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:35:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 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)) 06:35:23 INFO - Cleaning up... 06:35:23 INFO - Return code: 0 06:35:23 INFO - Running post-action listener: _resource_record_post_action 06:35:23 INFO - Running post-action listener: _start_resource_monitoring 06:35:23 INFO - Starting resource monitoring. 06:35:23 INFO - ##### 06:35:23 INFO - ##### Running install step. 06:35:23 INFO - ##### 06:35:23 INFO - Running pre-action listener: _resource_record_pre_action 06:35:23 INFO - Running main action method: install 06:35:23 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 06:35:23 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 06:35:24 INFO - Reading from file tmpfile_stdout 06:35:24 INFO - Using _rmtree_windows ... 06:35:24 INFO - Using _rmtree_windows ... 06:35:24 INFO - Detecting whether we're running mozinstall >=1.0... 06:35:24 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 06:35:24 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 06:35:24 INFO - Reading from file tmpfile_stdout 06:35:24 INFO - Output received: 06:35:24 INFO - Usage: mozinstall-script.py [options] installer 06:35:24 INFO - Options: 06:35:24 INFO - -h, --help show this help message and exit 06:35:24 INFO - -d DEST, --destination=DEST 06:35:24 INFO - Directory to install application into. [default: 06:35:24 INFO - "C:\slave\test"] 06:35:24 INFO - --app=APP Application being installed. [default: firefox] 06:35:24 INFO - Using _rmtree_windows ... 06:35:24 INFO - Using _rmtree_windows ... 06:35:24 INFO - mkdir: C:\slave\test\build\application 06:35:24 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'] 06:35:24 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 06:35:25 INFO - Reading from file tmpfile_stdout 06:35:25 INFO - Output received: 06:35:25 INFO - C:\slave\test\build\application\firefox\firefox.exe 06:35:25 INFO - Using _rmtree_windows ... 06:35:25 INFO - Using _rmtree_windows ... 06:35:25 INFO - Running post-action listener: _resource_record_post_action 06:35:25 INFO - ##### 06:35:25 INFO - ##### Running run-tests step. 06:35:25 INFO - ##### 06:35:25 INFO - Running pre-action listener: _resource_record_pre_action 06:35:25 INFO - Running main action method: run_tests 06:35:25 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 06:35:25 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 06:35:25 INFO - Python 2.7.3 06:35:25 INFO - Return code: 0 06:35:25 INFO - grabbing minidump binary from tooltool 06:35:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:35:25 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, '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 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01F3F660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 06:35:25 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build 06:35:25 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o 06:35:25 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 06:35:27 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpe_2biw 06:35:27 INFO - INFO - File integrity verified, renaming tmpe_2biw to win32-minidump_stackwalk.exe 06:35:27 INFO - Return code: 0 06:35:27 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 06:35:27 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 06:35:27 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 06:35:27 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 06:35:27 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 06:35:27 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-169', '--webServer', 'localhost'] in C:\slave\test\build 06:35:27 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --debug --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-169 --webServer localhost 06:35:27 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 06:35:27 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 06:35:27 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 06:35:27 INFO - 'COMPUTERNAME': 'T-W732-IX-169', 06:35:27 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 06:35:27 INFO - 'CYGWINBASE': 'C:\\cygwin', 06:35:27 INFO - 'DCLOCATION': 'SCL3', 06:35:27 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 06:35:27 INFO - 'FP_NO_HOST_CHECK': 'NO', 06:35:27 INFO - 'HOMEDRIVE': 'C:', 06:35:27 INFO - 'HOMEPATH': '\\Users\\cltbld', 06:35:27 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 06:35:27 INFO - 'KTS_VERSION': '1.19c', 06:35:27 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 06:35:27 INFO - 'LOGONSERVER': '\\\\T-W732-IX-169', 06:35:27 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 06:35:27 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 06:35:27 INFO - 'MONDIR': 'C:\\Monitor_config\\', 06:35:27 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 06:35:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:35:27 INFO - 'MOZ_NO_REMOTE': '1', 06:35:27 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 06:35:27 INFO - 'NO_EM_RESTART': '1', 06:35:27 INFO - 'NUMBER_OF_PROCESSORS': '8', 06:35:27 INFO - 'OS': 'Windows_NT', 06:35:27 INFO - 'OURDRIVE': 'C:', 06:35:27 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 06:35:27 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 06:35:27 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 06:35:27 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 06:35:27 INFO - 'PROCESSOR_LEVEL': '6', 06:35:27 INFO - 'PROCESSOR_REVISION': '1e05', 06:35:27 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 06:35:27 INFO - 'PROGRAMFILES': 'C:\\Program Files', 06:35:27 INFO - 'PROMPT': '$P$G', 06:35:27 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 06:35:27 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 06:35:27 INFO - 'PUBLIC': 'C:\\Users\\Public', 06:35:27 INFO - 'PWD': 'C:\\slave\\test', 06:35:27 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 06:35:27 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 06:35:27 INFO - 'SLAVEDIR': 'C:\\slave\\', 06:35:27 INFO - 'SYSTEMDRIVE': 'C:', 06:35:27 INFO - 'SYSTEMROOT': 'C:\\windows', 06:35:27 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:35:27 INFO - 'TEST1': 'testie', 06:35:27 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 06:35:27 INFO - 'USERDOMAIN': 'T-W732-IX-169', 06:35:27 INFO - 'USERNAME': 'cltbld', 06:35:27 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 06:35:27 INFO - 'WINDIR': 'C:\\windows', 06:35:27 INFO - 'WINDOWS_TRACING_FLAGS': '3', 06:35:27 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 06:35:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 06:35:27 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-169', '--webServer', 'localhost'] with output_timeout 3600 06:35:28 INFO - mozversion INFO | application_buildid: 20151023052631 06:35:28 INFO - mozversion INFO | application_changeset: 10103031391809746db0f178def31276cde2dad0 06:35:28 INFO - mozversion INFO | application_display_name: Nightly 06:35:28 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:35:28 INFO - mozversion INFO | application_name: Firefox 06:35:28 INFO - mozversion INFO | application_remotingname: firefox 06:35:28 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 06:35:28 INFO - mozversion INFO | application_vendor: Mozilla 06:35:28 INFO - mozversion INFO | application_version: 44.0a1 06:35:28 INFO - mozversion INFO | platform_buildid: 20151023052631 06:35:28 INFO - mozversion INFO | platform_changeset: 10103031391809746db0f178def31276cde2dad0 06:35:28 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 06:35:28 INFO - mozversion INFO | platform_version: 44.0a1 06:35:28 INFO - 2015-10-23 06:35:28,947 DEBUG : using testdate: 1445607328 06:35:28 INFO - 2015-10-23 06:35:28,947 DEBUG : actual date: 1445607328 06:35:29 INFO - 2015-10-23 06:35:29,098 INFO : starting webserver on 'localhost:49892' 06:35:29 INFO - 2015-10-23 06:35:29,101 INFO : Starting test suite T-W732-IX-169 06:35:29 INFO - 2015-10-23 06:35:29,101 INFO : Starting test dromaeo_css 06:35:29 INFO - 2015-10-23 06:35:29,101 DEBUG : operating with platform_type : w7_ 06:35:29 INFO - 2015-10-23 06:35:29,101 INFO : Initialising browser for dromaeo_css test... 06:35:29 INFO - 2015-10-23 06:35:29,207 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpnawm15\profile http://localhost:49892/getInfo.html 06:35:37 INFO - 2015-10-23 06:35:37,424 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:35:37 INFO - 2015-10-23 06:35:37,424 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:35:37 INFO - 2015-10-23 06:35:37,424 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 06:35:37 INFO - 2015-10-23 06:35:37,424 DEBUG : BROWSER_OUTPUT: __metrics 06:35:37 INFO - 2015-10-23 06:35:37,499 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:35:39 INFO - 2015-10-23 06:35:39,046 INFO : Browser initialized. 06:35:39 INFO - 2015-10-23 06:35:39,046 INFO : Running cycle 1/1 for dromaeo_css test... 06:35:39 INFO - 2015-10-23 06:35:39,046 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpnawm15\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:35:50 INFO - 2015-10-23 06:35:50,690 DEBUG : BROWSER_OUTPUT: RSS: Main: 141684736 06:35:50 INFO - 2015-10-23 06:35:50,690 DEBUG : BROWSER_OUTPUT: 06:36:41 INFO - 2015-10-23 06:36:41,628 DEBUG : BROWSER_OUTPUT: 1445607401639 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] 06:38:54 INFO - 2015-10-23 06:38:54,388 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49892/tests/dromaeo/cssquery-ext.html) 06:38:55 INFO - 2015-10-23 06:38:55,562 DEBUG : BROWSER_OUTPUT: RSS: Main: 204427264 06:38:55 INFO - 2015-10-23 06:38:55,562 DEBUG : BROWSER_OUTPUT: 06:41:58 INFO - 2015-10-23 06:41:58,430 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-ext.html (next: http://localhost:49892/tests/dromaeo/cssquery-jquery.html) 06:41:58 INFO - 2015-10-23 06:41:58,990 DEBUG : BROWSER_OUTPUT: RSS: Main: 197132288 06:41:58 INFO - 2015-10-23 06:41:58,990 DEBUG : BROWSER_OUTPUT: 06:45:01 INFO - 2015-10-23 06:45:01,209 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49892/tests/dromaeo/cssquery-mootools.html) 06:45:01 INFO - 2015-10-23 06:45:01,769 DEBUG : BROWSER_OUTPUT: RSS: Main: 150183936 06:45:01 INFO - 2015-10-23 06:45:01,769 DEBUG : BROWSER_OUTPUT: 06:48:04 INFO - 2015-10-23 06:48:04,039 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49892/tests/dromaeo/cssquery-prototype.html) 06:48:04 INFO - 2015-10-23 06:48:04,627 DEBUG : BROWSER_OUTPUT: RSS: Main: 140222464 06:48:04 INFO - 2015-10-23 06:48:04,627 DEBUG : BROWSER_OUTPUT: 06:51:07 INFO - 2015-10-23 06:51:07,762 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49892/tests/dromaeo/cssquery-yui.html) 06:51:08 INFO - 2015-10-23 06:51:08,342 DEBUG : BROWSER_OUTPUT: RSS: Main: 141242368 06:51:08 INFO - 2015-10-23 06:51:08,342 DEBUG : BROWSER_OUTPUT: 06:54:11 INFO - 2015-10-23 06:54:11,150 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-yui.html (next: http://localhost:49892/tests/dromaeo/cssquery-dojo.html) 06:54:11 INFO - 2015-10-23 06:54:11,404 DEBUG : BROWSER_OUTPUT: RSS: Main: 191819776 06:54:11 INFO - 2015-10-23 06:54:11,404 DEBUG : BROWSER_OUTPUT: 06:54:11 INFO - 2015-10-23 06:54:11,404 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:54:11 INFO - 2015-10-23 06:54:11,404 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:54:11 INFO - 2015-10-23 06:54:11,404 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:54:11 INFO - 2015-10-23 06:54:11,404 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:54:11 INFO - 2015-10-23 06:54:11,404 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2306.4695120122374;2351.1183306260154;2374.620060790273;2395.221552995277;2407.7953373963214;4023.959760402395;4102.118044620406;4155;4210.473690788652;4212.747235165893;4030.415589739486;4080.8775736727944;4133.896652583687;4142.399352093952;4231.365295205721;3564.108972756811;3569.5181150544736;3670.6696397324235;3733.477313176151;3743.86896458624;3554.4668299754985;3578.141246100943;3673.9081522961874;3689.2437050404737;3707.8702245421414;3384.4754063120254;3644.890653280406;3645.9453108203397;3689.1699367642336;3704.018435114697;3489.057954352323;3674.081479630106;3689.335919534483;3702.37059699849;3716.9070773230887;3534.2048039190977;3574.428091505373;3615.5842078161086;3619.8733044343585;3631.564212294515;3898;4083.734557253769;4090.1819636072905;4156.833726650931;4163.604457576525;4334.804933777988;4365.519792822787;4445.911081778347;4454.420925279725;4461.709988850715;4158.9376159357635;4280.122574872144;4377.23398405281;4378.277584198603;4496.078303947683;3928.4995409713665;4058.1883623275166;4128.380742888591;4161.667066634662;4176.895577610584;4457.153140903283;4465.352523884018;4550.931736023962;4554.362389265505;4557.248054071041;4133.297339452301;4246.299360605465;4293;4294.420253265832;4388.451443569554;4393.231184542756;4444.133393988206;4451.643868490513;4462.776861156929;4480.238359478896;4322.610965013164;4340.131973605292;4405.163019026439;4409.4488188976375;4426.22541055314;4096.303628383182;4117.2588933992165;4370.562943705604;4373.759443230653;4399.956000439955;3004.2789730464533;3047.801892876956;3125.437421264194;3151.322465669846;3163.1459505933267;2858.6712528059124;2897.217751207162;2897.8551072446294;2899.8260104393803;2912.6213592232875;4049.210403971197;4069.348904175318;4107.691923105779;4143.875683729493;4214.957850421457;4414.80133393998;4460.107978404332;4493.775311234425;4540.318952157203;4548.408706868152;4137.958620413758;4138.193052354762;4218.325067989168;4223.809928553223;4239.660827133822;3663.5603727552866;3726.9627303726616;3757.0419543016355;3790.6967442604528;3798.3732684107367;4474.686771925999;4522.683412161117;4602.7008244464005;4604.516525764747;4618.699784513996;4408.713433626804;4427.80074896624;4503.977480112579;4505.594496495331;4506.864794056183;4401.559844015573;4449.51055383914;4502.707324023928;4521;4528.117017181618;4357.389965404783;4388.846390376321;4425.336199570091;4448.977755111204;4455.26488129455;4419.403380543714;4428.557144285674;4484.461864576272;4516.548345165457;4527.275635898241;4156;4185.204811085884;4251.511076226274;4253.276942919773;4369.497507786518;4075.3071977763257;4278.401023856725;4351.521332653342;4360.215161271001;4364.8472303469225;4159.625633692982;4189.811458484436;4265.0616864289805;4279.16556271524;4279.465066866642;4163.8959026024595;4181.059261666101;4250.06498570314;4291.270484017787;4374.825006999812;4142.79286035691;4251.87244382669;4264.786760662017;4355.4337936068105;4376.081022985081 06:54:11 INFO - 2015-10-23 06:54:11,404 DEBUG : BROWSER_OUTPUT: |1;ext.html;460.8225833054273;502.76370106050143;504.61901264545264;517.1931786413196;518.0080146519413;26234.606480902778;37353.62646373535;37530;37720.43419348712;37740.43389349162;37898.43152352717;39289.80355098227;40140.19719605606;40174.59825401745;40187.58874467527;6199.380061993809;6278.246610406758;7694.269044440769;8128.959355203216;8179.713710020151;9987.300888937778;10303.072723454887;10323.896761032407;10324.638637647684;10328.58685652573;9978.650747223848;10212;10222.437765922872;10236;10237.590496380137;6444.258910225337;6577.6053436793945;9771.609135634566;10139.290249682525;10356.533955972;9824.1649459796;10010.49947502622;10016.64941727036;10028.147607453375;10043.447610381427;36495;36903.26193476142;36909.81545092284;36961.81519092387;36983.26033479342;2584.806139539542;2649.1787545860843;2816.9014084506944;2833.2350265428427;3343.548620936191;26587;27365.179044628694;27489.17532474029;27759.72240277612;27777.583336249972;2217.6895234667;2260.785225403584;2272.3410211038777;2282.968457151968;2366.597678394677;233576;233605;234199;234348;234826;41785.28395654273;42552;50906.74546627317;51404;53655.29308836396;52614;52630.21054684183;52633.473665262856;52730;52888.20667689988;11983.46074426653;12106.81839772404;12125.151239413333;12135.514579416724;12142.696432589079;9074.228690561244;9098.363114581916;9125.543722813965;9131.223845973167;9136.451812891248;16198.352065917232;16482.2582983766;16494.20633254334;16512.66974660524;16664.583385415463;7390.74132405374;7614.8096297593;7632.80917977055;7653.502522336031;7713.1515533291295;781.7068599275271;798.0861913109574;826.5660528222732;852.9019162796234;925.6066171876926;23571.635368933923;23983.760162398503;24392.63313091929;26257.474850502884;26366.340841479116;879.3448880584006;899.3974037394961;900.3607438718452;901.733988473402;930.6184464369576;13241.271730054747;13336.19982801035;13457.192568445924;13583.86416135826;13702.52041178554;256963;261885;263127;263245;263594;36901.988622161705;37364.252714946095;40526;41367.379489307685;41399.7930010348;1003.7791685829106;1111.2610114273916;1134.0757282814645;1151.37825574189;1278.9959881492982;24550.50898982046;24712.50574988454;24794.25617231486;24872.626910595995;25032.499350013262;35581.288374232885;36318.81840590886;38490.422643660924;38901.41647875341;41440.585594143675;184985;185518;185761;186789;187097;25371.36571585725;25570.61644075303;25743.613845792705;25764.871175644003;25783.742162578135;29283.267918302212;29931.70068299289;37226;37291.81354093212;38010.429843551814;30078.69921300759;30246.84876575603;30273.642535067884;31130.688693113683;31228.531572025982;29378.559321609766;30408;30574.84712576423;30745.538816917317;31275.530867036556;31378.843105784326;31476.68523314829;31601;31772.841135794173;31847.840760796047;27132.593011104454;28032.71967280301;29489.11532654024;29610.703892960795;29725;22572.322830315115;22881.427964301027;22971.310860674206;23178.76821231766;23516 06:54:11 INFO - 2015-10-23 06:54:11,407 DEBUG : BROWSER_OUTPUT: |2;jquery.html;306.33219581312756;306.4636885450462;308.18331421732404;314.1737231082253;316.1842446488061;15504.534863954072;15749.842501575009;15901.602459938493;15909.204539773023;16862.747058794128;14922.552323430287;15111.924440377783;15179.77230341543;15207.46773862915;15320.387184512607;5074.644774865761;5190.558802501791;5202.713850738208;5205.583553315726;5205.895882082356;5023.0707319145895;5067.290579318898;5207.713575753332;5222.060029194743;5229.686218826882;3303.5705358303385;3391.033555436701;5171.4311425743135;5182.585393168538;5621.269234999444;5897.498712609433;5923.940760592382;5931.080682494198;5934.64392136471;5946.732397042144;3587.3542762302777;5011.323471331397;5092.9236061459105;5104.05574968632;5105.846824595231;9624.711258662252;10009.34939228955;11374.203805733601;11930.22453540517;11946.34295113777;1664.3259479910535;1713.2033604374026;1732.4456174024317;1766.8674849386348;1793.488855676139;8616.65533378664;8747.956260218722;8902.554872256425;8971.955140224323;8995.55022248885;1546.265523876161;1551.084859932634;1566.0760151510663;1572.913489758063;1590.5387437643185;52586;53486.39540813782;54676;56598.43401566014;57573;5656.8868622627315;5848.502877255396;5901.3508514063415;5930.021546444875;5984.760609575568;6166.414190651881;6698.062271282024;6807.795766127024;6857.177138743383;6868.450523958071;4552.9772351138035;4573.268277075652;4591.265397536378;4641.976790116028;4689.554492323224;4743.549362810528;4745.5966242869745;4754.762261886892;4803.18345881201;4884.902301954012;2612.1641074855866;2705;2726;2737.137801592532;2739.411026629284;957.325085814513;965.5654955270156;968.5302628225305;972.1639390124487;1030.108955753268;347.81739586717174;400.049881531363;402.44663587567095;427.00507816787433;428.07749300257996;10703.304285221435;10882.29265097766;10957.945210273898;10961.068309193808;11032.669019929415;388.53958059698783;400.10975579335746;412.08928268526296;414.0600836102092;427.0902976659757;5814.970925145347;5959.374265702126;6001.639901605918;6004.099385092271;6009.218801555857;286051;297583;299316;299863;300849;54608;59804.70097649571;60408;61736.07395889065;61760.69119654463;389.75055964182945;400.2914840731888;418.56887405971895;422.99365509517384;429.16527354295596;9625.759356016155;9771.85342219853;9886.50567471609;9994.851107012028;10103.7474063149;34205.828970854986;35139.47290790589;35326.82336588301;35477.29045419025;36424.63575364213;47449.28826067543;47844.28233576569;47910.52089479061;47983.520164797905;48245.561420345155;5549.223108764857;5709.8287051388525;5753.3958934311295;5755.366909639853;5780.450857168562;11132.443377831238;11241.66275011751;11513.827292590451;11597.536098555964;11603.245789023686;7818.530888146729;9263.861042084509;9502.85745713801;9712.368696035017;9948.900510994798;8083.9191608083165;8822.735317940473;9137.817243654958;9213.401128926598;9309.674161404319;9401.85897211556;9678.758031049;9934.900650993397;9946.30375873711;10023.448710321003;5462.207979842821;5471.151971444432;5472.398036216093;5478.479544443271;5554.638948468337;9312.44125352454;9504.144626983605;9539.618415263312;9598.66404675833;9617.278704097082 06:54:11 INFO - 2015-10-23 06:54:11,408 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1133.9829902551458;1247.6568943540528;1320.4916107298686;1466.2009204983283;1563.1637074165324;6900.27547107554;7642.923570764291;7693;7722.9613851930735;7746;7783.688652453895;7862.764117076483;7862.842743145134;7863.764087077382;7892.092409372924;1811.855051595869;1835.5961688428524;2045.1410407628757;2119;2133.7226160599103;2014.2345908554728;2085.1138266236862;2131.818795402393;2156.751973523041;2164.6861205125247;2073.0774805211686;2080.9687854682193;2090.1325949730845;2090.25795842476;2106.6418708819538;1830.5606654402914;1888.1975160556708;2055.1162999910034;2128.8190503807195;2134.1890081768984;2145.120500594764;2146.388279340396;2161.9783802161933;2163.253677481282;2166.068590506082;6890.483213758988;6974.895376569356;7914.841703165962;7976.641051152654;7985.760427187193;4385.407969924051;4518.683692141552;4528.9547104528865;4533.569310915458;4549.24937385331;2703.6350092737425;2751.614773931651;2816.605675205473;2816.6479190101236;2820.7743380529714;772.8377040821434;777.3703300326697;789.9012623422072;797.409916661672;810.5582457560585;10886.945565272203;11184.161187910939;11220.326780393121;11236.66290011301;11240.718982025433;3864.439656249884;3959.8416063357718;3960.485136932238;4165.45849039629;4226.978865105655;8848.955755221183;8909.886264216973;8944.5080520572;9167.12912273333;9473.147416732527;4507.481639611421;4525.208088584484;4529.954700452953;4537.636989040869;4552.476465206543;3786.413105968579;4054.432379466877;4064.227796718614;4067;4069.491313585802;3285.0801775502896;3296.670332966732;3386.966130338665;3402.217489977319;3411.198368383428;1044.8693913260843;1090.4493230918335;1106;1108.728361551425;1110.134095405585;900.2078171209293;919.2048877720723;920.5167287174153;1040.9063184313297;1136.5624234669713;7304.233055529199;7322.304381083789;7366.815829604196;7369.520981136317;7372.778816635509;416.74370262288477;437.4837691524032;439.25984715754055;439.3212486708044;439.8262686238918;5593.021221286259;5646.153077038395;5648.85877853057;5659.6321239119325;5660.264165658438;8977.820443591223;9160.816783664422;9481.66814161501;9682.854757178648;9703.223742100616;9529.523523823782;9532.094451027142;9535.284853636005;9589.568469418753;9593.616255349847;6734.966325168441;6895.448364130857;6926.58440493572;6990.405815505637;6994.125734283215;6650.168728908887;6894.551854129466;7121.857562848818;7156.105486814148;7157.57054576727;8248.711295104642;8253.174682531939;8271.586420679063;8296.294814940557;8296.83406331882;8043.638036288264;8262.297704695167;8263.669453222044;8267.090620031671;8274.58627068632;6944.236134025355;7007.40437062835;7031.296870312928;7041.119860017498;7063.39961103312;7405.44459165554;7581.431392645463;7615.124260710089;7725.768226953201;7766.728164514215;7068.399186069241;7185;7232.349088582053;7235.63821808918;7241.710331586678;6820.522563420513;6960.4779641526075;7099.82250443743;7123.180834204133;7125.073740413713;7143.714251429885;7183.20984691694;7183.676734546853;7190.928090719026;7191.964040179766;6091.086337049478;6127.969360153349;6239.188905442445;6424.743010279723;6479.157709497735;6296.779612713533;6482.546221764431;6519.8044058678315;6552.606843589209;6556.082148499118 06:54:11 INFO - 2015-10-23 06:54:11,430 DEBUG : BROWSER_OUTPUT: |4;prototype.html;286.188655162614;290.9403572267685;297.9553067039944;298.4582981887874;299.26828903331364;4355.629771469421;4499.347594598781;4555.74943378114;4566.977165114178;4581.862544123677;4391.297392417214;4406.272964960777;4438.689291749579;4496.662750293733;4509.323601459782;3305.8512366943423;3362.798232106069;3463.6536346365287;3468.9939917423917;3475.5308033415417;3157.6368717597425;3365.8653653853817;3430.6740859618294;3463.9480407793903;3487.773294735847;3447.1554469154967;3451.430513965193;3463.8268086595695;3464.72282217422;3470.1498132957336;3447.3967055765265;3450.3789317922765;3454.9481757773656;3455.188030812757;3459.9067564123848;2953.0550223928353;2989.9402011959855;3006.518956966897;3088.3051313454516;3094.381123775254;4214.262504061777;4376.343548467724;4376.518582955872;4381.649468042581;4391.516933137353;3981.203759248162;4017.4978127734034;4077.7553346799286;4083.7549747015273;4087.3460246360737;4645.6980296280635;4648.581627653527;4656.092062047902;4726.385569875894;4757.762111894392;3463.515107884898;3492.511048453219;3494.9825250873837;3496.318217947501;3498.6501349865066;4946.87632809179;5003.774830132653;5017.448080711119;5018.172001619729;5031.018951304533;3488.0930957951114;3545.308664810337;3558.1994051338243;3606.765560238576;3609.151849315409;3628.2562074775137;3647.6352364763843;3647.7264205184188;3649.671529562299;3653.342398368319;4055.858044968471;4084.4281800547947;4095.774732389747;4099.344104943195;4102.384642303678;4742.66801323904;4748.4539277982785;4749.073930583572;4751.881202969884;4760.190767569521;1264.1909178126007;1296.857345691973;1313.7241179352443;1314.0932756398054;1315.3291821171272;902.3908861518448;917.7292698653913;939.4691999020531;940.1961323068814;941.9952900235592;4462.732236065782;4610.815567377334;4614.284785858197;4614.353990441341;4617.307403889444;4963.181075122637;4966.354373931365;4969.950300496948;4972.0801651694555;4975.328330675313;4483.932741008887;4593.989322349078;4605.516420775837;4606;4606.654500912445;2634.0649069580404;2666.799990000719;2703.162019773876;2704.4996675615075;2706.8917243310048;5080.822171223989;5088.745562721849;5100.872478188001;5106.234064890296;5106.795728170906;4987.875303117451;5074.847754567369;5080.161773307437;5082.186850103965;5084.669496482717;4939.975300123477;4965.82619608312;5078.7206703631655;5089.2111722683785;5092.363454568179;4800.567948884617;4918.95081049185;4957.752112394293;4965.1807451770055;4986.301917731593;4722.126406614787;4849.75751212445;4850.83022094225;4866.780994855169;4874.2444921037295;4975.950240497548;5078.365204349457;5089.643724939218;5104.2343648453025;5156.845294641167;3385.8984230473125;3500.387432199406;3509;3511.4732790081694;3513.069036705326;4824.903501930012;4892.779824907817;4979.576735977483;4980.825671101494;4985.027919555652;4720.480747117883;4771.284307353924;4791.904161916812;4793.544613261734;4798.0403919217;4665.183592871302;4782.402199725035;4785.9042819144115;4787.210110331756;4787.664863459664;4554.385158003627;4664.137134630104;4676.994446194085;4683.250679891201;4685.1332503486965;4793.5685788279225;4808.422989241313;4823.614110871052;4862.854114376574;4866.124097662455;4287.528371879029;4522.502524722344;4611.823984883833;4655.092257009851;4667.906641867212 06:54:11 INFO - 2015-10-23 06:54:11,431 DEBUG : BROWSER_OUTPUT: |5;yui.html;611.446640790085;650.2262307853653;702.6416527570938;705.4497491956276;719.208870242733;11058.557657693693;11196.776064478727;11421.942890285558;11475.139364547671;11477;9382.812343753121;10105.191584673226;10454.902235265301;10508.211884108703;11469.483873225705;616.3374372549505;627.1001113402282;627.8461776864674;628.271205401734;629.109809619388;624.3725056318416;625.9467945224661;627.604609096271;628.3716283716284;628.4532456762623;644.5520363347475;649.4966401039209;651.5504302031607;656.4092316914791;656.891612883876;653.480483016001;653.8855700252436;654.0679531667355;654.231278248059;654.993450065498;477.6179056754617;477.87097483679213;478.10355583281347;478.4354461735151;479.3289394847242;10998.450077496173;11075.16936229778;11132.888671113265;11145.21983461158;11153.163512736495;8632.482051076955;9017.36878418511;9982.301238913278;10741.78516429667;10902.727431814188;8594.613242404106;8616.698415555426;8620.65517379304;8710.216080552716;8882.777930551723;2863.642044744407;2874.4825931332352;2896.782741294411;2951.940961180786;3006.9849650751826;26865.731342686715;28137.29656758577;32046.679533204602;32294.515582266285;32307.51538726921;6262.467690246289;6306.400891915261;6384.106225128486;6413.518986076063;6433.678316084177;9836.704898853046;9854.802903941882;9856.211503079736;9857.654982075735;9867.06262905023;5459.781608735686;5534.7786088556;5565.2486914266865;5633.380328163898;5634.661920284796;3439.1402149462633;3441.105312618687;3467.323871844966;3507.5440192775286;3526.3123690880543;2227.0646328542052;2230.408941630469;2247.3819699582746;2249.089118906845;2257.808086312682;381.3954881512833;425.0987905640001;438.3337327262543;441.6908164285013;441.69964424191215;522.6628824408311;525.5716590978341;552.8452033430646;596.4244504053522;602.7679343452802;6590.373914478117;7257.8911316330295;7429.62851857405;7481.512835196596;7694.846103077907;491.8204855227888;566.6062086849682;578.2800413485154;596.9731362088717;604.5979423683201;3364.293498365371;3610.548681414823;3635.4546817976984;3659.4876717259604;3708.8887333380044;42122;42383.7880810594;42441.575584243765;42557;42796;35008.29983400318;35238.295234095174;35523;35637;35647.10882227913;24999.87500062488;27265.727342726717;28601.998930037764;32332.676673232967;32631.347373052406;6583.934160658524;6728.899066513908;6929.757458488929;6950.270221626656;6994.510384273052;22990.655140172577;23056.193033243755;23085.307440776804;23304.650430243222;23398.18106366269;28406.715932841234;28708.569371459864;31420.214494637818;31618;31664.366712666077;6130.693465326805;6210.72051757681;6456.903146452901;6662.433693136138;6897.655117244218;9769.218462522838;10008.499575021366;10174.643887463903;10479.94760026195;10575.471226438802;9041.954790226007;9074.137956894085;9234.584443699916;9250.121238482334;9697.175740062174;9299.116583924517;9470;9525.714228573153;9614.90385096168;9758.316917815968;8887.15572020657;9405.811883762422;9504.42973421597;9807.117359437685;9812.558434870305;8114.310283625958;8150.307223886036;8180.713675021584;8510.361722870686;8549.145085491402;8047.557384343917;8341;8493.150684931705;8614.956925215334;8652.09153038947 06:54:11 INFO - 2015-10-23 06:54:11,433 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:54:11 INFO - 2015-10-23 06:54:11,433 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:54:11 INFO - 2015-10-23 06:54:11,433 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,960 06:54:11 INFO - 2015-10-23 06:54:11,433 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:54:11 INFO - 2015-10-23 06:54:11,433 DEBUG : BROWSER_OUTPUT: __startTimestamp1445608451425__endTimestamp 06:54:11 INFO - 2015-10-23 06:54:11,433 DEBUG : BROWSER_OUTPUT: 06:54:11 INFO - 2015-10-23 06:54:11,433 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:54:11 INFO - 2015-10-23 06:54:11,434 DEBUG : BROWSER_OUTPUT: Number of tests: 6 06:54:11 INFO - 2015-10-23 06:54:11,434 DEBUG : BROWSER_OUTPUT: 06:54:11 INFO - 2015-10-23 06:54:11,434 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4055.70 Median:4214.96 stddev:506.07 (12.0%) stddev-sans-first:488.66 06:54:11 INFO - 2015-10-23 06:54:11,434 DEBUG : BROWSER_OUTPUT: Values: 2306.5 2351.1 2374.6 2395.2 2407.8 4024.0 4102.1 4155.0 4210.5 4212.7 4030.4 4080.9 4133.9 4142.4 4231.4 3564.1 3569.5 3670.7 3733.5 3743.9 3554.5 3578.1 3673.9 3689.2 3707.9 3384.5 3644.9 3645.9 3689.2 3704.0 3489.1 3674.1 3689.3 3702.4 3716.9 3534.2 3574.4 3615.6 3619.9 3631.6 3898.0 4083.7 4090.2 4156.8 4163.6 4334.8 4365.5 4445.9 4454.4 4461.7 4158.9 4280.1 4377.2 4378.3 4496.1 3928.5 4058.2 4128.4 4161.7 4176.9 4457.2 4465.4 4550.9 4554.4 4557.2 4133.3 4246.3 4293.0 4294.4 4388.5 4393.2 4444.1 4451.6 4462.8 4480.2 4322.6 4340.1 4405.2 4409.4 4426.2 4096.3 4117.3 4370.6 4373.8 4400.0 3004.3 3047.8 3125.4 3151.3 3163.1 2858.7 2897.2 2897.9 2899.8 2912.6 4049.2 4069.3 4107.7 4143.9 4215.0 4414.8 4460.1 4493.8 4540.3 4548.4 4138.0 4138.2 4218.3 4223.8 4239.7 3663.6 3727.0 3757.0 3790.7 3798.4 4474.7 4522.7 4602.7 4604.5 4618.7 4408.7 4427.8 4504.0 4505.6 4506.9 4401.6 4449.5 4502.7 4521.0 4528.1 4357.4 4388.8 4425.3 4449.0 4455.3 4419.4 4428.6 4484.5 4516.5 4527.3 4156.0 4185.2 4251.5 4253.3 4369.5 4075.3 4278.4 4351.5 4360.2 4364.8 4159.6 4189.8 4265.1 4279.2 4279.5 4163.9 4181.1 4250.1 4291.3 4374.8 4142.8 4251.9 4264.8 4355.4 4376.1 06:54:11 INFO - 2015-10-23 06:54:11,434 DEBUG : BROWSER_OUTPUT: 06:54:11 INFO - 2015-10-23 06:54:11,434 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:37979.12 Median:29828.35 stddev:59701.97 (200.2%) stddev-sans-first:59802.98 06:54:11 INFO - 2015-10-23 06:54:11,434 DEBUG : BROWSER_OUTPUT: Values: 460.8 502.8 504.6 517.2 518.0 26234.6 37353.6 37530.0 37720.4 37740.4 37898.4 39289.8 40140.2 40174.6 40187.6 6199.4 6278.2 7694.3 8129.0 8179.7 9987.3 10303.1 10323.9 10324.6 10328.6 9978.7 10212.0 10222.4 10236.0 10237.6 6444.3 6577.6 9771.6 10139.3 10356.5 9824.2 10010.5 10016.6 10028.1 10043.4 36495.0 36903.3 36909.8 36961.8 36983.3 2584.8 2649.2 2816.9 2833.2 3343.5 26587.0 27365.2 27489.2 27759.7 27777.6 2217.7 2260.8 2272.3 2283.0 2366.6 233576.0 233605.0 234199.0 234348.0 234826.0 41785.3 42552.0 50906.7 51404.0 53655.3 52614.0 52630.2 52633.5 52730.0 52888.2 11983.5 12106.8 12125.2 12135.5 12142.7 9074.2 9098.4 9125.5 9131.2 9136.5 16198.4 16482.3 16494.2 16512.7 16664.6 7390.7 7614.8 7632.8 7653.5 7713.2 781.7 798.1 826.6 852.9 925.6 23571.6 23983.8 24392.6 26257.5 26366.3 879.3 899.4 900.4 901.7 930.6 13241.3 13336.2 13457.2 13583.9 13702.5 256963.0 261885.0 263127.0 263245.0 263594.0 36902.0 37364.3 40526.0 41367.4 41399.8 1003.8 1111.3 1134.1 1151.4 1279.0 24550.5 24712.5 24794.3 24872.6 25032.5 35581.3 36318.8 38490.4 38901.4 41440.6 184985.0 185518.0 185761.0 186789.0 187097.0 25371.4 25570.6 25743.6 25764.9 25783.7 29283.3 29931.7 37226.0 37291.8 38010.4 30078.7 30246.8 30273.6 31130.7 31228.5 29378.6 30408.0 30574.8 30745.5 31275.5 31378.8 31476.7 31601.0 31772.8 31847.8 27132.6 28032.7 29489.1 29610.7 29725.0 22572.3 22881.4 22971.3 23178.8 23516.0 06:54:11 INFO - 2015-10-23 06:54:11,434 DEBUG : BROWSER_OUTPUT: 06:54:11 INFO - 2015-10-23 06:54:11,434 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:19121.99 Median:5017.20 stddev:49241.13 (981.4%) stddev-sans-first:49359.00 06:54:11 INFO - 2015-10-23 06:54:11,434 DEBUG : BROWSER_OUTPUT: Values: 306.3 306.5 308.2 314.2 316.2 15504.5 15749.8 15901.6 15909.2 16862.7 14922.6 15111.9 15179.8 15207.5 15320.4 5074.6 5190.6 5202.7 5205.6 5205.9 5023.1 5067.3 5207.7 5222.1 5229.7 3303.6 3391.0 5171.4 5182.6 5621.3 5897.5 5923.9 5931.1 5934.6 5946.7 3587.4 5011.3 5092.9 5104.1 5105.8 9624.7 10009.3 11374.2 11930.2 11946.3 1664.3 1713.2 1732.4 1766.9 1793.5 8616.7 8748.0 8902.6 8972.0 8995.6 1546.3 1551.1 1566.1 1572.9 1590.5 52586.0 53486.4 54676.0 56598.4 57573.0 5656.9 5848.5 5901.4 5930.0 5984.8 6166.4 6698.1 6807.8 6857.2 6868.5 4553.0 4573.3 4591.3 4642.0 4689.6 4743.5 4745.6 4754.8 4803.2 4884.9 2612.2 2705.0 2726.0 2737.1 2739.4 957.3 965.6 968.5 972.2 1030.1 347.8 400.0 402.4 427.0 428.1 10703.3 10882.3 10957.9 10961.1 11032.7 388.5 400.1 412.1 414.1 427.1 5815.0 5959.4 6001.6 6004.1 6009.2 286051.0 297583.0 299316.0 299863.0 300849.0 54608.0 59804.7 60408.0 61736.1 61760.7 389.8 400.3 418.6 423.0 429.2 9625.8 9771.9 9886.5 9994.9 10103.7 34205.8 35139.5 35326.8 35477.3 36424.6 47449.3 47844.3 47910.5 47983.5 48245.6 5549.2 5709.8 5753.4 5755.4 5780.5 11132.4 11241.7 11513.8 11597.5 11603.2 7818.5 9263.9 9502.9 9712.4 9948.9 8083.9 8822.7 9137.8 9213.4 9309.7 9401.9 9678.8 9934.9 9946.3 10023.4 5462.2 5471.2 5472.4 5478.5 5554.6 9312.4 9504.1 9539.6 9598.7 9617.3 06:54:11 INFO - 2015-10-23 06:54:11,437 DEBUG : BROWSER_OUTPUT: 06:54:11 INFO - 2015-10-23 06:54:11,437 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5347.56 Median:6554.34 stddev:2949.08 (45.0%) stddev-sans-first:2940.34 06:54:11 INFO - 2015-10-23 06:54:11,437 DEBUG : BROWSER_OUTPUT: Values: 1134.0 1247.7 1320.5 1466.2 1563.2 6900.3 7642.9 7693.0 7723.0 7746.0 7783.7 7862.8 7862.8 7863.8 7892.1 1811.9 1835.6 2045.1 2119.0 2133.7 2014.2 2085.1 2131.8 2156.8 2164.7 2073.1 2081.0 2090.1 2090.3 2106.6 1830.6 1888.2 2055.1 2128.8 2134.2 2145.1 2146.4 2162.0 2163.3 2166.1 6890.5 6974.9 7914.8 7976.6 7985.8 4385.4 4518.7 4529.0 4533.6 4549.2 2703.6 2751.6 2816.6 2816.6 2820.8 772.8 777.4 789.9 797.4 810.6 10886.9 11184.2 11220.3 11236.7 11240.7 3864.4 3959.8 3960.5 4165.5 4227.0 8849.0 8909.9 8944.5 9167.1 9473.1 4507.5 4525.2 4530.0 4537.6 4552.5 3786.4 4054.4 4064.2 4067.0 4069.5 3285.1 3296.7 3387.0 3402.2 3411.2 1044.9 1090.4 1106.0 1108.7 1110.1 900.2 919.2 920.5 1040.9 1136.6 7304.2 7322.3 7366.8 7369.5 7372.8 416.7 437.5 439.3 439.3 439.8 5593.0 5646.2 5648.9 5659.6 5660.3 8977.8 9160.8 9481.7 9682.9 9703.2 9529.5 9532.1 9535.3 9589.6 9593.6 6735.0 6895.4 6926.6 6990.4 6994.1 6650.2 6894.6 7121.9 7156.1 7157.6 8248.7 8253.2 8271.6 8296.3 8296.8 8043.6 8262.3 8263.7 8267.1 8274.6 6944.2 7007.4 7031.3 7041.1 7063.4 7405.4 7581.4 7615.1 7725.8 7766.7 7068.4 7185.0 7232.3 7235.6 7241.7 6820.5 6960.5 7099.8 7123.2 7125.1 7143.7 7183.2 7183.7 7190.9 7192.0 6091.1 6128.0 6239.2 6424.7 6479.2 6296.8 6482.5 6519.8 6552.6 6556.1 06:54:11 INFO - 2015-10-23 06:54:11,437 DEBUG : BROWSER_OUTPUT: 06:54:11 INFO - 2015-10-23 06:54:11,438 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4018.75 Median:4599.75 stddev:1172.54 (25.5%) stddev-sans-first:1141.87 06:54:11 INFO - 2015-10-23 06:54:11,438 DEBUG : BROWSER_OUTPUT: Values: 286.2 290.9 298.0 298.5 299.3 4355.6 4499.3 4555.7 4567.0 4581.9 4391.3 4406.3 4438.7 4496.7 4509.3 3305.9 3362.8 3463.7 3469.0 3475.5 3157.6 3365.9 3430.7 3463.9 3487.8 3447.2 3451.4 3463.8 3464.7 3470.1 3447.4 3450.4 3454.9 3455.2 3459.9 2953.1 2989.9 3006.5 3088.3 3094.4 4214.3 4376.3 4376.5 4381.6 4391.5 3981.2 4017.5 4077.8 4083.8 4087.3 4645.7 4648.6 4656.1 4726.4 4757.8 3463.5 3492.5 3495.0 3496.3 3498.7 4946.9 5003.8 5017.4 5018.2 5031.0 3488.1 3545.3 3558.2 3606.8 3609.2 3628.3 3647.6 3647.7 3649.7 3653.3 4055.9 4084.4 4095.8 4099.3 4102.4 4742.7 4748.5 4749.1 4751.9 4760.2 1264.2 1296.9 1313.7 1314.1 1315.3 902.4 917.7 939.5 940.2 942.0 4462.7 4610.8 4614.3 4614.4 4617.3 4963.2 4966.4 4970.0 4972.1 4975.3 4483.9 4594.0 4605.5 4606.0 4606.7 2634.1 2666.8 2703.2 2704.5 2706.9 5080.8 5088.7 5100.9 5106.2 5106.8 4987.9 5074.8 5080.2 5082.2 5084.7 4940.0 4965.8 5078.7 5089.2 5092.4 4800.6 4919.0 4957.8 4965.2 4986.3 4722.1 4849.8 4850.8 4866.8 4874.2 4976.0 5078.4 5089.6 5104.2 5156.8 3385.9 3500.4 3509.0 3511.5 3513.1 4824.9 4892.8 4979.6 4980.8 4985.0 4720.5 4771.3 4791.9 4793.5 4798.0 4665.2 4782.4 4785.9 4787.2 4787.7 4554.4 4664.1 4677.0 4683.3 4685.1 4793.6 4808.4 4823.6 4862.9 4866.1 4287.5 4522.5 4611.8 4655.1 4667.9 06:54:11 INFO - 2015-10-23 06:54:11,441 DEBUG : BROWSER_OUTPUT: 06:54:11 INFO - 2015-10-23 06:54:11,441 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:9926.10 Median:587.35 stddev:10769.34 (1833.5%) stddev-sans-first:10776.83 06:54:11 INFO - 2015-10-23 06:54:11,441 DEBUG : BROWSER_OUTPUT: Values: 611.4 650.2 702.6 705.4 719.2 11058.6 11196.8 11421.9 11475.1 11477.0 9382.8 10105.2 10454.9 10508.2 11469.5 616.3 627.1 627.8 628.3 629.1 624.4 625.9 627.6 628.4 628.5 644.6 649.5 651.6 656.4 656.9 653.5 653.9 654.1 654.2 655.0 477.6 477.9 478.1 478.4 479.3 10998.5 11075.2 11132.9 11145.2 11153.2 8632.5 9017.4 9982.3 10741.8 10902.7 8594.6 8616.7 8620.7 8710.2 8882.8 2863.6 2874.5 2896.8 2951.9 3007.0 26865.7 28137.3 32046.7 32294.5 32307.5 6262.5 6306.4 6384.1 6413.5 6433.7 9836.7 9854.8 9856.2 9857.7 9867.1 5459.8 5534.8 5565.2 5633.4 5634.7 3439.1 3441.1 3467.3 3507.5 3526.3 2227.1 2230.4 2247.4 2249.1 2257.8 381.4 425.1 438.3 441.7 441.7 522.7 525.6 552.8 596.4 602.8 6590.4 7257.9 7429.6 7481.5 7694.8 491.8 566.6 578.3 597.0 604.6 3364.3 3610.5 3635.5 3659.5 3708.9 42122.0 42383.8 42441.6 42557.0 42796.0 35008.3 35238.3 35523.0 35637.0 35647.1 24999.9 27265.7 28602.0 32332.7 32631.3 6583.9 6728.9 6929.8 6950.3 6994.5 22990.7 23056.2 23085.3 23304.7 23398.2 28406.7 28708.6 31420.2 31618.0 31664.4 6130.7 6210.7 6456.9 6662.4 6897.7 9769.2 10008.5 10174.6 10479.9 10575.5 9042.0 9074.1 9234.6 9250.1 9697.2 9299.1 9470.0 9525.7 9614.9 9758.3 8887.2 9405.8 9504.4 9807.1 9812.6 8114.3 8150.3 8180.7 8510.4 8549.1 8047.6 8341.0 8493.2 8615.0 8652.1 06:54:11 INFO - 2015-10-23 06:54:11,441 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:54:11 INFO - 2015-10-23 06:54:11,441 DEBUG : BROWSER_OUTPUT: 06:54:12 INFO - 2015-10-23 06:54:12,069 INFO : Browser exited with error code: 0 06:54:12 INFO - 2015-10-23 06:54:12,803 INFO : Completed test dromaeo_css (00:18:43) 06:54:12 INFO - 2015-10-23 06:54:12,803 INFO : Starting test kraken 06:54:12 INFO - 2015-10-23 06:54:12,803 DEBUG : operating with platform_type : w7_ 06:54:12 INFO - 2015-10-23 06:54:12,803 INFO : Initialising browser for kraken test... 06:54:12 INFO - 2015-10-23 06:54:12,829 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpopmmib\profile http://localhost:49892/getInfo.html 06:54:17 INFO - 2015-10-23 06:54:17,944 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:54:17 INFO - 2015-10-23 06:54:17,944 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:54:17 INFO - 2015-10-23 06:54:17,944 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 06:54:17 INFO - 2015-10-23 06:54:17,944 DEBUG : BROWSER_OUTPUT: __metrics 06:54:18 INFO - 2015-10-23 06:54:18,019 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:54:19 INFO - 2015-10-23 06:54:19,200 INFO : Browser initialized. 06:54:19 INFO - 2015-10-23 06:54:19,200 INFO : Running cycle 1/1 for kraken test... 06:54:19 INFO - 2015-10-23 06:54:19,200 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpopmmib\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\kraken\kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:54:30 INFO - 2015-10-23 06:54:30,848 DEBUG : BROWSER_OUTPUT: RSS: Main: 141635584 06:54:30 INFO - 2015-10-23 06:54:30,848 DEBUG : BROWSER_OUTPUT: 06:54:33 INFO - 2015-10-23 06:54:33,384 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49892/tests/kraken/driver.html?testName=audio-beat-detection) 06:54:33 INFO - 2015-10-23 06:54:33,970 DEBUG : BROWSER_OUTPUT: RSS: Main: 175239168 06:54:33 INFO - 2015-10-23 06:54:33,970 DEBUG : BROWSER_OUTPUT: 06:54:36 INFO - 2015-10-23 06:54:36,746 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49892/tests/kraken/driver.html?testName=audio-dft) 06:54:37 INFO - 2015-10-23 06:54:37,322 DEBUG : BROWSER_OUTPUT: RSS: Main: 177025024 06:54:37 INFO - 2015-10-23 06:54:37,322 DEBUG : BROWSER_OUTPUT: 06:54:40 INFO - 2015-10-23 06:54:40,117 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49892/tests/kraken/driver.html?testName=audio-fft) 06:54:40 INFO - 2015-10-23 06:54:40,697 DEBUG : BROWSER_OUTPUT: RSS: Main: 168558592 06:54:40 INFO - 2015-10-23 06:54:40,697 DEBUG : BROWSER_OUTPUT: 06:54:42 INFO - 2015-10-23 06:54:42,859 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49892/tests/kraken/driver.html?testName=audio-oscillator) 06:54:43 INFO - 2015-10-23 06:54:43,434 DEBUG : BROWSER_OUTPUT: RSS: Main: 170115072 06:54:43 INFO - 2015-10-23 06:54:43,434 DEBUG : BROWSER_OUTPUT: 06:54:45 INFO - 2015-10-23 06:54:45,733 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49892/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:54:46 INFO - 2015-10-23 06:54:46,296 DEBUG : BROWSER_OUTPUT: RSS: Main: 168013824 06:54:46 INFO - 2015-10-23 06:54:46,296 DEBUG : BROWSER_OUTPUT: 06:54:49 INFO - 2015-10-23 06:54:49,471 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49892/tests/kraken/driver.html?testName=imaging-darkroom) 06:54:50 INFO - 2015-10-23 06:54:50,046 DEBUG : BROWSER_OUTPUT: RSS: Main: 162770944 06:54:50 INFO - 2015-10-23 06:54:50,046 DEBUG : BROWSER_OUTPUT: 06:54:54 INFO - 2015-10-23 06:54:54,181 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49892/tests/kraken/driver.html?testName=imaging-desaturate) 06:54:54 INFO - 2015-10-23 06:54:54,736 DEBUG : BROWSER_OUTPUT: RSS: Main: 161984512 06:54:54 INFO - 2015-10-23 06:54:54,736 DEBUG : BROWSER_OUTPUT: 06:54:57 INFO - 2015-10-23 06:54:57,638 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49892/tests/kraken/driver.html?testName=json-parse-financial) 06:54:58 INFO - 2015-10-23 06:54:58,213 DEBUG : BROWSER_OUTPUT: RSS: Main: 162697216 06:54:58 INFO - 2015-10-23 06:54:58,213 DEBUG : BROWSER_OUTPUT: 06:55:00 INFO - 2015-10-23 06:55:00,068 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49892/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:55:00 INFO - 2015-10-23 06:55:00,631 DEBUG : BROWSER_OUTPUT: RSS: Main: 194785280 06:55:00 INFO - 2015-10-23 06:55:00,631 DEBUG : BROWSER_OUTPUT: 06:55:02 INFO - 2015-10-23 06:55:02,611 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49892/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:55:03 INFO - 2015-10-23 06:55:03,190 DEBUG : BROWSER_OUTPUT: RSS: Main: 200966144 06:55:03 INFO - 2015-10-23 06:55:03,190 DEBUG : BROWSER_OUTPUT: 06:55:05 INFO - 2015-10-23 06:55:05,625 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49892/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:55:06 INFO - 2015-10-23 06:55:06,184 DEBUG : BROWSER_OUTPUT: RSS: Main: 199229440 06:55:06 INFO - 2015-10-23 06:55:06,184 DEBUG : BROWSER_OUTPUT: 06:55:08 INFO - 2015-10-23 06:55:08,864 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49892/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:55:09 INFO - 2015-10-23 06:55:09,430 DEBUG : BROWSER_OUTPUT: RSS: Main: 200929280 06:55:09 INFO - 2015-10-23 06:55:09,430 DEBUG : BROWSER_OUTPUT: 06:55:12 INFO - 2015-10-23 06:55:12,618 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49892/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:55:13 INFO - 2015-10-23 06:55:13,193 DEBUG : BROWSER_OUTPUT: RSS: Main: 198434816 06:55:13 INFO - 2015-10-23 06:55:13,193 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,213 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49892/tests/kraken/driver.html?testName=ai-astar) 06:55:15 INFO - 2015-10-23 06:55:15,467 DEBUG : BROWSER_OUTPUT: RSS: Main: 244633600 06:55:15 INFO - 2015-10-23 06:55:15,467 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,467 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:55:15 INFO - 2015-10-23 06:55:15,467 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:55:15 INFO - 2015-10-23 06:55:15,467 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:55:15 INFO - 2015-10-23 06:55:15,467 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:55:15 INFO - 2015-10-23 06:55:15,467 DEBUG : BROWSER_OUTPUT: |0;ai-astar;93;98;96;88;98;99;97;97;96;98 06:55:15 INFO - 2015-10-23 06:55:15,467 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;140;144;155;170;137;141;151;154;159;142 06:55:15 INFO - 2015-10-23 06:55:15,467 DEBUG : BROWSER_OUTPUT: |2;audio-dft;159;146;145;146;160;152;153;152;150;149 06:55:15 INFO - 2015-10-23 06:55:15,467 DEBUG : BROWSER_OUTPUT: |3;audio-fft;80;81;85;121;95;100;88;80;104;82 06:55:15 INFO - 2015-10-23 06:55:15,467 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;106;100;117;99;100;100;130;99;101;124 06:55:15 INFO - 2015-10-23 06:55:15,467 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;117;117;117;117;119;119;117;116;117;117 06:55:15 INFO - 2015-10-23 06:55:15,469 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;207;198;194;199;194;197;197;198;200;211 06:55:15 INFO - 2015-10-23 06:55:15,469 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;90;95;90;89;94;90;89;95;89;90 06:55:15 INFO - 2015-10-23 06:55:15,469 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;79;84;80;79;79;79;79;80;79;79 06:55:15 INFO - 2015-10-23 06:55:15,470 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;78;76;79;77;75;75;76;76;77;76 06:55:15 INFO - 2015-10-23 06:55:15,470 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;100;100;101;100;100;102;102;99;120;100 06:55:15 INFO - 2015-10-23 06:55:15,470 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;136;131;131;138;133;135;139;132;136;128 06:55:15 INFO - 2015-10-23 06:55:15,470 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;193;198;200;194;201;190;199;201;199;188 06:55:15 INFO - 2015-10-23 06:55:15,470 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;92;92;95;91;95;91;94;92;94;91 06:55:15 INFO - 2015-10-23 06:55:15,470 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:55:15 INFO - 2015-10-23 06:55:15,470 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:55:15 INFO - 2015-10-23 06:55:15,470 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,921 06:55:15 INFO - 2015-10-23 06:55:15,470 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:55:15 INFO - 2015-10-23 06:55:15,470 DEBUG : BROWSER_OUTPUT: __startTimestamp1445608515468__endTimestamp 06:55:15 INFO - 2015-10-23 06:55:15,470 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,470 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:55:15 INFO - 2015-10-23 06:55:15,470 DEBUG : BROWSER_OUTPUT: Number of tests: 14 06:55:15 INFO - 2015-10-23 06:55:15,471 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,471 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:96.00 Median:97.50 stddev:3.27 (3.3%) stddev-sans-first:3.28 06:55:15 INFO - 2015-10-23 06:55:15,471 DEBUG : BROWSER_OUTPUT: Values: 93.0 98.0 96.0 88.0 98.0 99.0 97.0 97.0 96.0 98.0 06:55:15 INFO - 2015-10-23 06:55:15,471 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,471 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:149.30 Median:152.50 stddev:10.37 (6.8%) stddev-sans-first:10.44 06:55:15 INFO - 2015-10-23 06:55:15,471 DEBUG : BROWSER_OUTPUT: Values: 140.0 144.0 155.0 170.0 137.0 141.0 151.0 154.0 159.0 142.0 06:55:15 INFO - 2015-10-23 06:55:15,471 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,471 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:151.20 Median:152.00 stddev:5.18 (3.4%) stddev-sans-first:4.66 06:55:15 INFO - 2015-10-23 06:55:15,473 DEBUG : BROWSER_OUTPUT: Values: 159.0 146.0 145.0 146.0 160.0 152.0 153.0 152.0 150.0 149.0 06:55:15 INFO - 2015-10-23 06:55:15,473 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,473 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:91.60 Median:81.50 stddev:13.46 (16.5%) stddev-sans-first:13.61 06:55:15 INFO - 2015-10-23 06:55:15,473 DEBUG : BROWSER_OUTPUT: Values: 80.0 81.0 85.0 121.0 95.0 100.0 88.0 80.0 104.0 82.0 06:55:15 INFO - 2015-10-23 06:55:15,473 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,473 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.60 Median:120.50 stddev:11.67 (9.7%) stddev-sans-first:12.37 06:55:15 INFO - 2015-10-23 06:55:15,473 DEBUG : BROWSER_OUTPUT: Values: 106.0 100.0 117.0 99.0 100.0 100.0 130.0 99.0 101.0 124.0 06:55:15 INFO - 2015-10-23 06:55:15,476 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,476 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.95 (0.8%) stddev-sans-first:1.00 06:55:15 INFO - 2015-10-23 06:55:15,476 DEBUG : BROWSER_OUTPUT: Values: 117.0 117.0 117.0 117.0 119.0 119.0 117.0 116.0 117.0 117.0 06:55:15 INFO - 2015-10-23 06:55:15,477 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,477 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:199.50 Median:198.50 stddev:5.44 (2.7%) stddev-sans-first:5.05 06:55:15 INFO - 2015-10-23 06:55:15,477 DEBUG : BROWSER_OUTPUT: Values: 207.0 198.0 194.0 199.0 194.0 197.0 197.0 198.0 200.0 211.0 06:55:15 INFO - 2015-10-23 06:55:15,477 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,479 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:91.10 Median:90.00 stddev:2.51 (2.8%) stddev-sans-first:2.64 06:55:15 INFO - 2015-10-23 06:55:15,479 DEBUG : BROWSER_OUTPUT: Values: 90.0 95.0 90.0 89.0 94.0 90.0 89.0 95.0 89.0 90.0 06:55:15 INFO - 2015-10-23 06:55:15,479 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,479 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:79.70 Median:79.00 stddev:1.57 (2.0%) stddev-sans-first:1.64 06:55:15 INFO - 2015-10-23 06:55:15,480 DEBUG : BROWSER_OUTPUT: Values: 79.0 84.0 80.0 79.0 79.0 79.0 79.0 80.0 79.0 79.0 06:55:15 INFO - 2015-10-23 06:55:15,480 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,480 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:76.50 Median:76.50 stddev:1.27 (1.7%) stddev-sans-first:1.22 06:55:15 INFO - 2015-10-23 06:55:15,480 DEBUG : BROWSER_OUTPUT: Values: 78.0 76.0 79.0 77.0 75.0 75.0 76.0 76.0 77.0 76.0 06:55:15 INFO - 2015-10-23 06:55:15,480 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,480 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:102.40 Median:101.50 stddev:6.26 (6.2%) stddev-sans-first:6.58 06:55:15 INFO - 2015-10-23 06:55:15,480 DEBUG : BROWSER_OUTPUT: Values: 100.0 100.0 101.0 100.0 100.0 102.0 102.0 99.0 120.0 100.0 06:55:15 INFO - 2015-10-23 06:55:15,482 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,482 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:133.90 Median:135.50 stddev:3.48 (2.6%) stddev-sans-first:3.61 06:55:15 INFO - 2015-10-23 06:55:15,482 DEBUG : BROWSER_OUTPUT: Values: 136.0 131.0 131.0 138.0 133.0 135.0 139.0 132.0 136.0 128.0 06:55:15 INFO - 2015-10-23 06:55:15,483 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,483 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.30 Median:199.00 stddev:4.72 (2.4%) stddev-sans-first:4.85 06:55:15 INFO - 2015-10-23 06:55:15,483 DEBUG : BROWSER_OUTPUT: Values: 193.0 198.0 200.0 194.0 201.0 190.0 199.0 201.0 199.0 188.0 06:55:15 INFO - 2015-10-23 06:55:15,483 DEBUG : BROWSER_OUTPUT: 06:55:15 INFO - 2015-10-23 06:55:15,483 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:92.70 Median:93.00 stddev:1.64 (1.8%) stddev-sans-first:1.72 06:55:15 INFO - 2015-10-23 06:55:15,483 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 95.0 91.0 95.0 91.0 94.0 92.0 94.0 91.0 06:55:15 INFO - 2015-10-23 06:55:15,483 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:55:15 INFO - 2015-10-23 06:55:15,483 DEBUG : BROWSER_OUTPUT: 06:55:16 INFO - 2015-10-23 06:55:16,184 INFO : Browser exited with error code: 0 06:55:16 INFO - 2015-10-23 06:55:16,976 INFO : Completed test kraken (00:01:04) 06:55:16 INFO - 2015-10-23 06:55:16,976 INFO : Starting test v8_7 06:55:16 INFO - 2015-10-23 06:55:16,976 DEBUG : operating with platform_type : w7_ 06:55:16 INFO - 2015-10-23 06:55:16,976 INFO : Initialising browser for v8_7 test... 06:55:17 INFO - 2015-10-23 06:55:17,003 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpsxffht\profile http://localhost:49892/getInfo.html 06:55:21 INFO - 2015-10-23 06:55:21,532 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:55:21 INFO - 2015-10-23 06:55:21,532 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:55:21 INFO - 2015-10-23 06:55:21,532 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 06:55:21 INFO - 2015-10-23 06:55:21,532 DEBUG : BROWSER_OUTPUT: __metrics 06:55:21 INFO - 2015-10-23 06:55:21,598 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:55:22 INFO - 2015-10-23 06:55:22,457 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 121: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 06:55:22 INFO - 2015-10-23 06:55:22,704 DEBUG : BROWSER_OUTPUT: console.error: 06:55:22 INFO - 2015-10-23 06:55:22,704 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] 06:55:22 INFO - 2015-10-23 06:55:22,704 DEBUG : BROWSER_OUTPUT: Stack: 06:55:22 INFO - 2015-10-23 06:55:22,704 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 06:55:22 INFO - 2015-10-23 06:55:22,704 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 06:55:22 INFO - 2015-10-23 06:55:22,704 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 06:55:22 INFO - 2015-10-23 06:55:22,704 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 06:55:22 INFO - 2015-10-23 06:55:22,704 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 06:55:22 INFO - 2015-10-23 06:55:22,704 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 06:55:22 INFO - 2015-10-23 06:55:22,704 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 06:55:22 INFO - 2015-10-23 06:55:22,704 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 06:55:22 INFO - 2015-10-23 06:55:22,704 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 06:55:22 INFO - 2015-10-23 06:55:22,704 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 06:55:22 INFO - 2015-10-23 06:55:22,704 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 06:55:22 INFO - 2015-10-23 06:55:22,706 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 06:55:22 INFO - 2015-10-23 06:55:22,706 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 5558.08 06:55:59 INFO - RETURN: kraken: 1685.10 06:55:59 INFO - RETURN: v8_7: 20115.35 06:55:59 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,132,25]]", "result": "20115.35"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,132,25]]", "result": "1685.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,132,25]]", "result": "5558.08"}}} 06:55:59 INFO - Return code: 0 06:55:59 INFO - # TBPL SUCCESS # 06:55:59 INFO - Running post-action listener: _resource_record_post_action 06:55:59 INFO - Running post-run listener: _resource_record_post_run 06:56:00 INFO - Total resource usage - Wall time: 1234s; CPU: 13.0%; Read bytes: 81837568; Write bytes: 414827520; Read time: 1160770; Write time: 4399780 06:56:00 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 1072128; Read time: 0; Write time: 1600 06:56:00 INFO - run-tests - Wall time: 1233s; CPU: 13.0%; Read bytes: 81743360; Write bytes: 392615936; Read time: 1158160; Write time: 4372370 06:56:00 INFO - Running post-run listener: _upload_blobber_files 06:56:00 INFO - Blob upload gear active. 06:56:00 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 06:56:00 INFO - Copying logs to upload dir... 06:56:00 INFO - mkdir: C:\slave\test\build\upload\logs program finished with exit code 0 elapsedTime=1583.393000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 26 mins, 32 secs) (at 2015-10-23 06:56:00.608579) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 06:56:00.612555) ========= '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-169 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-169 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-169 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/f3alxlgoQ_uqK-NfHBMWpA/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/f3alxlgoQ_uqK-NfHBMWpA/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-23 06:56:01.524243) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 06:56:01.524571) ========= '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-169 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-169 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-169 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-23 06:56:01.809359) ========= ========= Started reboot slave lost (results: 0, elapsed: 8 secs) (at 2015-10-23 06:56:01.809673) ========= ========= Finished reboot slave lost (results: 0, elapsed: 8 secs) (at 2015-10-23 06:56:10.692073) =========