builder: b2g-inbound_win7-ix_test-dromaeojs-pgo slave: t-w732-ix-047 starttime: 1445481187.81 results: success (0) buildid: 20151021143022 builduid: 11a6a161479c48078fef47a7c3880f13 revision: 0f821c314396155126f562b7bf7c8f89b62ab4ef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-21 19:33:07.808292) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-21 19:33:07.809599) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-21 19:33:07.809901) ========= 'cd' in dir C:\slave\test-pgo\. (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-047 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-047 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-pgo 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-047 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-pgo program finished with exit code 0 elapsedTime=0.664000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-21 19:33:08.720244) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-21 19:33:08.720551) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-21 19:33:08.910818) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 7 secs) (at 2015-10-21 19:33:08.911105) ========= 'rm' '-rf' 'properties' in dir C:\slave\test-pgo\. (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-047 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-047 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-pgo 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-047 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.501000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 7 secs) (at 2015-10-21 19:33:16.397315) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-21 19:33:16.397605) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-21 19:33:16.398572) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-21 19:33:16.398854) ========= '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-pgo\. (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-047 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-047 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-pgo 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-047 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 --19:33:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 15.57 MB/s 19:33:17 (15.57 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.790000 ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-10-21 19:33:18.389578) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 19 secs) (at 2015-10-21 19:33:18.389858) ========= 'rm' '-rf' 'scripts' in dir C:\slave\test-pgo\. (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-047 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-047 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-pgo 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-047 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=13.601000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 19 secs) (at 2015-10-21 19:33:37.691950) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 15 secs) (at 2015-10-21 19:33:37.692334) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 0f821c314396155126f562b7bf7c8f89b62ab4ef --destination scripts --debug' in dir C:\slave\test-pgo\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/b2g-inbound --rev 0f821c314396155126f562b7bf7c8f89b62ab4ef --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-047 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-047 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-pgo 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-047 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-21 19:33:38,410 truncating revision to first 12 chars 2015-10-21 19:33:38,410 Setting DEBUG logging. 2015-10-21 19:33:38,410 attempt 1/10 2015-10-21 19:33:38,410 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/b2g-inbound/0f821c314396?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-21 19:33:38,903 unpacking tar archive at: b2g-inbound-0f821c314396/testing/mozharness/ program finished with exit code 0 elapsedTime=1.284000 ========= Finished 'bash -c ...' (results: 0, elapsed: 15 secs) (at 2015-10-21 19:33:52.915262) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2015-10-21 19:33:52.915561) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2015-10-21 19:33:53.976769) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-21 19:33:53.977062) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-21 19:33:53.977491) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 47 secs) (at 2015-10-21 19:33:53.977847) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'B2G-Inbound' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'B2G-Inbound' in dir C:\slave\test-pgo\. (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', 'B2G-Inbound', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'B2G-Inbound'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-047 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-047 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-pgo/buildprops.json PSMODULEPATH=C:\windows\system32\WindowsPowerShell\v1.0\Modules\ PUBLIC=C:\Users\Public PWD=C:\slave\test-pgo 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-047 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 19:33:54 INFO - MultiFileLogger online at 20151021 19:33:54 in C:\slave\test-pgo 19:33:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name B2G-Inbound --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch B2G-Inbound 19:33:54 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 19:33:54 INFO - {'append_to_log': False, 19:33:54 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 19:33:54 INFO - 'blob_upload_branch': 'B2G-Inbound', 19:33:54 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 19:33:54 INFO - 'branch': 'B2G-Inbound', 19:33:54 INFO - 'buildbot_json_path': 'buildprops.json', 19:33:54 INFO - 'config_files': ('talos/windows_config.py',), 19:33:54 INFO - 'default_actions': ('clobber', 19:33:54 INFO - 'read-buildbot-config', 19:33:54 INFO - 'download-and-extract', 19:33:54 INFO - 'populate-webroot', 19:33:54 INFO - 'create-virtualenv', 19:33:54 INFO - 'install', 19:33:54 INFO - 'run-tests'), 19:33:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:33:54 INFO - 'download_minidump_stackwalk': True, 19:33:54 INFO - 'download_symbols': 'ondemand', 19:33:54 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 19:33:54 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 19:33:54 INFO - 'hg': 'c:/mozilla-build/hg/hg', 19:33:54 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 19:33:54 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 19:33:54 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 19:33:54 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 19:33:54 INFO - 'C:/mozilla-build/tooltool.py'), 19:33:54 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 19:33:54 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 19:33:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:33:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:33:54 INFO - 'installer_path': 'installer.exe', 19:33:54 INFO - 'log_level': 'info', 19:33:54 INFO - 'log_name': 'talos', 19:33:54 INFO - 'log_to_console': True, 19:33:54 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 19:33:54 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 19:33:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 19:33:54 INFO - 'opt_config_files': (), 19:33:54 INFO - 'pip_index': False, 19:33:54 INFO - 'sps_profile': False, 19:33:54 INFO - 'sps_profile_interval': 0, 19:33:54 INFO - 'suite': 'dromaeojs', 19:33:54 INFO - 'system_bits': '32', 19:33:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:33:54 INFO - 'title': 'T-W732-IX-047', 19:33:54 INFO - 'use_talos_json': True, 19:33:54 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 19:33:54 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 19:33:54 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 19:33:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:33:54 INFO - 'work_dir': 'build'} 19:33:54 INFO - ##### 19:33:54 INFO - ##### Running clobber step. 19:33:54 INFO - ##### 19:33:54 INFO - Running pre-action listener: _resource_record_pre_action 19:33:54 INFO - Running main action method: clobber 19:33:54 INFO - rmtree: C:\slave\test-pgo\build 19:33:54 INFO - Using _rmtree_windows ... 19:33:54 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 19:35:16 INFO - Running post-action listener: _resource_record_post_action 19:35:16 INFO - ##### 19:35:16 INFO - ##### Running read-buildbot-config step. 19:35:16 INFO - ##### 19:35:16 INFO - Running pre-action listener: _resource_record_pre_action 19:35:16 INFO - Running main action method: read_buildbot_config 19:35:16 INFO - Using buildbot properties: 19:35:16 INFO - { 19:35:16 INFO - "properties": { 19:35:16 INFO - "buildnumber": 63, 19:35:16 INFO - "product": "firefox", 19:35:16 INFO - "script_repo_revision": "production", 19:35:16 INFO - "builddir": "b2g-inbound_win7-ix_test-dromaeojs", 19:35:16 INFO - "repository": "", 19:35:16 INFO - "buildername": "Windows 7 32-bit b2g-inbound pgo talos dromaeojs", 19:35:16 INFO - "buildid": "20151021143022", 19:35:16 INFO - "slavename": "t-w732-ix-047", 19:35:16 INFO - "pgo_build": "True", 19:35:16 INFO - "basedir": "C:\\slave\\test-pgo", 19:35:16 INFO - "project": "", 19:35:16 INFO - "platform": "win7-ix", 19:35:16 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 19:35:16 INFO - "slavebuilddir": "test", 19:35:16 INFO - "scheduler": "tests-b2g-inbound-win32-pgo-talos", 19:35:16 INFO - "branch": "b2g-inbound", 19:35:16 INFO - "repo_path": "integration/b2g-inbound", 19:35:16 INFO - "stage_platform": "win32-pgo", 19:35:16 INFO - "builduid": "11a6a161479c48078fef47a7c3880f13", 19:35:16 INFO - "revision": "0f821c314396155126f562b7bf7c8f89b62ab4ef" 19:35:16 INFO - }, 19:35:16 INFO - "sourcestamp": { 19:35:16 INFO - "repository": "", 19:35:16 INFO - "hasPatch": false, 19:35:16 INFO - "project": "", 19:35:16 INFO - "branch": "b2g-inbound-win32-pgo-talos", 19:35:16 INFO - "changes": [ 19:35:16 INFO - { 19:35:16 INFO - "category": null, 19:35:16 INFO - "files": [ 19:35:16 INFO - { 19:35:16 INFO - "url": null, 19:35:16 INFO - "name": "https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 19:35:16 INFO - } 19:35:16 INFO - ], 19:35:16 INFO - "repository": "", 19:35:16 INFO - "rev": "0f821c314396155126f562b7bf7c8f89b62ab4ef", 19:35:16 INFO - "who": "sendchange", 19:35:16 INFO - "when": 1445480668, 19:35:16 INFO - "number": 6556960, 19:35:16 INFO - "comments": "", 19:35:16 INFO - "project": "", 19:35:16 INFO - "at": "Wed 21 Oct 2015 19:24:28", 19:35:16 INFO - "branch": "b2g-inbound-win32-pgo-talos", 19:35:16 INFO - "revlink": "", 19:35:16 INFO - "properties": [ 19:35:16 INFO - [ 19:35:16 INFO - "buildid", 19:35:16 INFO - "20151021143022", 19:35:16 INFO - "Change" 19:35:16 INFO - ], 19:35:16 INFO - [ 19:35:16 INFO - "builduid", 19:35:16 INFO - "11a6a161479c48078fef47a7c3880f13", 19:35:16 INFO - "Change" 19:35:16 INFO - ], 19:35:16 INFO - [ 19:35:16 INFO - "pgo_build", 19:35:16 INFO - "True", 19:35:16 INFO - "Change" 19:35:16 INFO - ] 19:35:16 INFO - ], 19:35:16 INFO - "revision": "0f821c314396155126f562b7bf7c8f89b62ab4ef" 19:35:16 INFO - } 19:35:16 INFO - ], 19:35:16 INFO - "revision": "0f821c314396155126f562b7bf7c8f89b62ab4ef" 19:35:16 INFO - } 19:35:16 INFO - } 19:35:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 19:35:16 INFO - Running post-action listener: _resource_record_post_action 19:35:16 INFO - ##### 19:35:16 INFO - ##### Running download-and-extract step. 19:35:16 INFO - ##### 19:35:16 INFO - Running pre-action listener: _resource_record_pre_action 19:35:16 INFO - Running main action method: download_and_extract 19:35:16 INFO - mkdir: C:\slave\test-pgo\build\tests 19:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:16 INFO - https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 19:35:16 INFO - trying https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/test_packages.json 19:35:16 INFO - Downloading https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 19:35:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 19:35:17 INFO - Downloaded 1221 bytes. 19:35:17 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 19:35:17 INFO - Using the following test package requirements: 19:35:17 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 19:35:17 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 19:35:17 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 19:35:17 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 19:35:17 INFO - u'jsshell-win32.zip'], 19:35:17 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 19:35:17 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 19:35:17 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 19:35:17 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 19:35:17 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 19:35:17 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 19:35:17 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 19:35:17 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 19:35:17 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 19:35:17 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 19:35:17 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 19:35:17 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 19:35:17 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 19:35:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:35:17 INFO - https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 19:35:17 INFO - trying https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 19:35:17 INFO - Downloading https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 19:35:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'}, attempt #1 19:35:19 INFO - Downloaded 18002686 bytes. 19:35:19 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests 19:35:19 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 19:35:19 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 19:35:52 INFO - Return code: 0 19:35:52 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 19:35: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')]} 19:35:52 INFO - https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 19:35:52 INFO - trying https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 19:35:52 INFO - Downloading https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 19:35:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'}, attempt #1 19:35:54 INFO - Downloaded 18002686 bytes. 19:35:54 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] in C:\slave\test-pgo\build\tests 19:35:54 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 19:35:54 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 19:36:17 INFO - Return code: 0 19:36:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:17 INFO - https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 19:36:17 INFO - trying https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 19:36:17 INFO - Downloading https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 19:36:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip', 'file_name': u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'}, attempt #1 19:36:18 INFO - Downloaded 11128541 bytes. 19:36:18 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] in C:\slave\test-pgo\build\tests 19:36:18 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 19:36:18 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 19:36:20 INFO - Return code: 0 19:36:20 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')]} 19:36:20 INFO - https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 19:36:20 INFO - trying https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 19:36:20 INFO - Downloading https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip 19:36:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.zip'}, attempt #1 19:36:23 INFO - Downloaded 55020552 bytes. 19:36:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 19:36:23 INFO - mkdir: C:\slave\test-pgo\properties 19:36:23 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 19:36:23 INFO - Writing to file C:\slave\test-pgo\properties\build_url 19:36:23 INFO - Contents: 19:36:23 INFO - build_url:https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 19:36:23 INFO - Running post-action listener: _resource_record_post_action 19:36:23 INFO - Running post-action listener: set_extra_try_arguments 19:36:23 INFO - ##### 19:36:23 INFO - ##### Running populate-webroot step. 19:36:23 INFO - ##### 19:36:23 INFO - Running pre-action listener: _resource_record_pre_action 19:36:23 INFO - Running main action method: populate_webroot 19:36:23 INFO - Guessing talos json url... 19:36: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')]} 19:36:23 INFO - https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 19:36:23 INFO - trying https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 19:36:23 INFO - Downloading https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 19:36:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.txt', 'file_name': 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.txt'}, attempt #1 19:36:24 INFO - Downloaded 107 bytes. 19:36:24 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 19:36:24 INFO - Contents: 19:36:24 INFO - 20151021143022 19:36:24 INFO - https://hg.mozilla.org/integration/b2g-inbound/rev/0f821c314396155126f562b7bf7c8f89b62ab4ef 19:36:24 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')]} 19:36:24 INFO - trying https://hg.mozilla.org/integration/b2g-inbound/raw-file/0f821c3143/testing/talos/talos.json 19:36:24 INFO - Downloading https://hg.mozilla.org/integration/b2g-inbound/raw-file/0f821c3143/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 19:36:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/b2g-inbound/raw-file/0f821c3143/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 19:36:24 INFO - Downloaded 4264 bytes. 19:36:24 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 19:36:24 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 19:36:24 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 19:36:24 INFO - u'../fennec_ids.txt'], 19:36:24 INFO - u'tests': [u'tcheck2']}, 19:36:24 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 19:36:24 INFO - u'--tppagecycles', 19:36:24 INFO - u'7'], 19:36:24 INFO - u'tests': [u'tsvgm']}}, 19:36:24 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 19:36:24 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 19:36:24 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 19:36:24 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 19:36:24 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 19:36:24 INFO - u'tests': [u'dromaeo_css', 19:36:24 INFO - u'kraken', 19:36:24 INFO - u'v8_7']}, 19:36:24 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:24 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 19:36:24 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:24 INFO - u'talos_options': [u'--e10s'], 19:36:24 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 19:36:24 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:24 INFO - u'tests': [u'damp', u'tps']}, 19:36:24 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:24 INFO - u'talos_options': [u'--e10s'], 19:36:24 INFO - u'tests': [u'damp', u'tps']}, 19:36:24 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 19:36:24 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 19:36:24 INFO - u'other': {u'tests': [u'a11yr', 19:36:24 INFO - u'ts_paint', 19:36:24 INFO - u'tpaint', 19:36:24 INFO - u'sessionrestore', 19:36:24 INFO - u'sessionrestore_no_auto_restore']}, 19:36:24 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 19:36:24 INFO - u'tests': [u'a11yr', 19:36:24 INFO - u'ts_paint', 19:36:24 INFO - u'tpaint', 19:36:24 INFO - u'sessionrestore', 19:36:24 INFO - u'sessionrestore_no_auto_restore']}, 19:36:24 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 19:36:24 INFO - u'tests': [u'a11yr', 19:36:24 INFO - u'ts_paint', 19:36:24 INFO - u'tpaint', 19:36:24 INFO - u'sessionrestore', 19:36:24 INFO - u'sessionrestore_no_auto_restore']}, 19:36:24 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 19:36:25 INFO - u'tests': [u'a11yr', 19:36:25 INFO - u'ts_paint', 19:36:25 INFO - u'tpaint', 19:36:25 INFO - u'sessionrestore', 19:36:25 INFO - u'sessionrestore_no_auto_restore']}, 19:36:25 INFO - u'other_l64': {u'tests': [u'a11yr', 19:36:25 INFO - u'ts_paint', 19:36:25 INFO - u'tpaint', 19:36:25 INFO - u'sessionrestore', 19:36:25 INFO - u'sessionrestore_no_auto_restore']}, 19:36:25 INFO - u'other_nol64': {u'tests': [u'a11yr', 19:36:25 INFO - u'ts_paint', 19:36:25 INFO - u'tpaint', 19:36:25 INFO - u'sessionrestore', 19:36:25 INFO - u'sessionrestore_no_auto_restore']}, 19:36:25 INFO - u'svgr': {u'tests': [u'tsvgx', 19:36:25 INFO - u'tsvgr_opacity', 19:36:25 INFO - u'tart', 19:36:25 INFO - u'tscrollx', 19:36:25 INFO - u'cart']}, 19:36:25 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 19:36:25 INFO - u'tests': [u'tsvgx', 19:36:25 INFO - u'tsvgr_opacity', 19:36:25 INFO - u'tart', 19:36:25 INFO - u'tscrollx', 19:36:25 INFO - u'cart']}, 19:36:25 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:25 INFO - u'tests': [u'tp5o']}, 19:36:25 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:25 INFO - u'talos_options': [u'--e10s'], 19:36:25 INFO - u'tests': [u'tp5o']}, 19:36:25 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:25 INFO - u'talos_options': [u'--xperf_path', 19:36:25 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:36:25 INFO - u'tests': [u'tp5n']}, 19:36:25 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:36:25 INFO - u'talos_options': [u'--e10s', 19:36:25 INFO - u'--xperf_path', 19:36:25 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 19:36:25 INFO - u'C:/slave/talos-data/talos/xperf.config'], 19:36:25 INFO - u'tests': [u'tp5n']}}, 19:36:25 INFO - u'talos.zip': {u'path': u'', 19:36:25 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:36:25 INFO - Running post-action listener: _resource_record_post_action 19:36:25 INFO - ##### 19:36:25 INFO - ##### Running create-virtualenv step. 19:36:25 INFO - ##### 19:36:25 INFO - Running pre-action listener: _resource_record_pre_action 19:36:25 INFO - Running main action method: create_virtualenv 19:36:25 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 19:36:25 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 19:36:25 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 19:36:25 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 19:36:25 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test-pgo\\build/venv'] in C:\slave\test-pgo\build 19:36:25 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test-pgo\build/venv 19:36:34 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 19:36:38 INFO - Installing distribute......................................................................................................................................................................................done. 19:36:40 INFO - Return code: 0 19:36:40 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 19:36:40 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')]} 19:36:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:40 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')]} 19:36:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:40 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\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 0x0198FB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01955EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0143E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A15660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-047', 'USERDOMAIN': 'T-W732-IX-047', '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-047', '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-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 19:36:40 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\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-pgo\build 19:36:40 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\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 19:36:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:36:40 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:36:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:36:40 INFO - 'COMPUTERNAME': 'T-W732-IX-047', 19:36:40 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:36:40 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:36:40 INFO - 'DCLOCATION': 'SCL3', 19:36:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:36:40 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:36:40 INFO - 'HOMEDRIVE': 'C:', 19:36:40 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:36:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:36:40 INFO - 'KTS_VERSION': '1.19c', 19:36:40 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:36:40 INFO - 'LOGONSERVER': '\\\\T-W732-IX-047', 19:36:40 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:36:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:36:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:40 INFO - 'MOZ_NO_REMOTE': '1', 19:36:40 INFO - 'NO_EM_RESTART': '1', 19:36:40 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:36:40 INFO - 'OS': 'Windows_NT', 19:36:40 INFO - 'OURDRIVE': 'C:', 19:36:40 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;', 19:36:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:36:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:36:40 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:36:40 INFO - 'PROCESSOR_LEVEL': '6', 19:36:40 INFO - 'PROCESSOR_REVISION': '1e05', 19:36:40 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:36:40 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:36:40 INFO - 'PROMPT': '$P$G', 19:36:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 19:36:40 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:36:40 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:36:40 INFO - 'PWD': 'C:\\slave\\test-pgo', 19:36:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:36:40 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:36:40 INFO - 'SYSTEMDRIVE': 'C:', 19:36:40 INFO - 'SYSTEMROOT': 'C:\\windows', 19:36:40 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:36:40 INFO - 'TEST1': 'testie', 19:36:40 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:36:40 INFO - 'USERDOMAIN': 'T-W732-IX-047', 19:36:40 INFO - 'USERNAME': 'cltbld', 19:36:40 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:36:40 INFO - 'WINDIR': 'C:\\windows', 19:36:40 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:36:40 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:36:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:36:41 INFO - Ignoring indexes: http://pypi.python.org/simple/ 19:36:41 INFO - Downloading/unpacking pip>=1.5 19:36:41 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 19:36:46 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz 19:36:46 INFO - Running setup.py egg_info for package pip 19:36:46 INFO - warning: no files found matching 'pip\cacert.pem' 19:36:46 INFO - warning: no files found matching '*.html' under directory 'docs' 19:36:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 19:36:46 INFO - no previously-included directories found matching 'docs\_build\_sources' 19:36:46 INFO - Installing collected packages: pip 19:36:46 INFO - Found existing installation: pip 0.8.2 19:36:46 INFO - Uninstalling pip: 19:36:46 INFO - Successfully uninstalled pip 19:36:46 INFO - Running setup.py install for pip 19:36:46 INFO - warning: no files found matching 'pip\cacert.pem' 19:36:46 INFO - warning: no files found matching '*.html' under directory 'docs' 19:36:46 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 19:36:46 INFO - no previously-included directories found matching 'docs\_build\_sources' 19:36:46 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:36:46 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 19:36:46 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:36:46 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 19:36:46 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:36:46 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 19:36:46 INFO - Successfully installed pip 19:36:46 INFO - Cleaning up... 19:36:46 INFO - Return code: 0 19:36:46 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 19:36:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:46 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\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 0x0198FB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01955EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0143E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A15660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-047', 'USERDOMAIN': 'T-W732-IX-047', '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-047', '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-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 19:36:46 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\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-pgo\build 19:36:46 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\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 19:36:46 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:36:46 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:36:46 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:36:46 INFO - 'COMPUTERNAME': 'T-W732-IX-047', 19:36:46 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:36:46 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:36:46 INFO - 'DCLOCATION': 'SCL3', 19:36:46 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:36:46 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:36:46 INFO - 'HOMEDRIVE': 'C:', 19:36:46 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:36:46 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:36:46 INFO - 'KTS_VERSION': '1.19c', 19:36:46 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:36:46 INFO - 'LOGONSERVER': '\\\\T-W732-IX-047', 19:36:46 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:36:46 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:36:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:46 INFO - 'MOZ_NO_REMOTE': '1', 19:36:46 INFO - 'NO_EM_RESTART': '1', 19:36:46 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:36:46 INFO - 'OS': 'Windows_NT', 19:36:46 INFO - 'OURDRIVE': 'C:', 19:36:46 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 19:36:46 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:36:46 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:36:46 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:36:46 INFO - 'PROCESSOR_LEVEL': '6', 19:36:46 INFO - 'PROCESSOR_REVISION': '1e05', 19:36:46 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:36:46 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:36:46 INFO - 'PROMPT': '$P$G', 19:36:46 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 19:36:46 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:36:46 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:36:46 INFO - 'PWD': 'C:\\slave\\test-pgo', 19:36:46 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:36:46 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:36:46 INFO - 'SYSTEMDRIVE': 'C:', 19:36:46 INFO - 'SYSTEMROOT': 'C:\\windows', 19:36:46 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:36:46 INFO - 'TEST1': 'testie', 19:36:46 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:36:46 INFO - 'USERDOMAIN': 'T-W732-IX-047', 19:36:46 INFO - 'USERNAME': 'cltbld', 19:36:46 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:36:46 INFO - 'WINDIR': 'C:\\windows', 19:36:46 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:36:46 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:36:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:36:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:48 INFO - Downloading/unpacking psutil>=0.7.1 19:36:48 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 19:36:48 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 19:36:49 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpsutil-3.1.1-cp27-none-win32.whl 19:36:49 INFO - Installing collected packages: psutil 19:36:49 INFO - Successfully installed psutil 19:36:49 INFO - Cleaning up... 19:36:49 INFO - Return code: 0 19:36:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 19:36:49 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')]} 19:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:49 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')]} 19:36:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:49 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\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 0x0198FB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01955EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0143E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A15660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-047', 'USERDOMAIN': 'T-W732-IX-047', '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-047', '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-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 19:36:49 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\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-pgo\build 19:36:49 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\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 19:36:49 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:36:49 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:36:49 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:36:49 INFO - 'COMPUTERNAME': 'T-W732-IX-047', 19:36:49 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:36:49 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:36:49 INFO - 'DCLOCATION': 'SCL3', 19:36:49 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:36:49 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:36:49 INFO - 'HOMEDRIVE': 'C:', 19:36:49 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:36:49 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:36:49 INFO - 'KTS_VERSION': '1.19c', 19:36:49 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:36:49 INFO - 'LOGONSERVER': '\\\\T-W732-IX-047', 19:36:49 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:36:49 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:49 INFO - 'MOZ_NO_REMOTE': '1', 19:36:49 INFO - 'NO_EM_RESTART': '1', 19:36:49 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:36:49 INFO - 'OS': 'Windows_NT', 19:36:49 INFO - 'OURDRIVE': 'C:', 19:36:49 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;', 19:36:49 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:36:49 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:36:49 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:36:49 INFO - 'PROCESSOR_LEVEL': '6', 19:36:49 INFO - 'PROCESSOR_REVISION': '1e05', 19:36:49 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:36:49 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:36:49 INFO - 'PROMPT': '$P$G', 19:36:49 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 19:36:49 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:36:49 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:36:49 INFO - 'PWD': 'C:\\slave\\test-pgo', 19:36:49 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:36:49 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:36:49 INFO - 'SYSTEMDRIVE': 'C:', 19:36:49 INFO - 'SYSTEMROOT': 'C:\\windows', 19:36:49 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:36:49 INFO - 'TEST1': 'testie', 19:36:49 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:36:49 INFO - 'USERDOMAIN': 'T-W732-IX-047', 19:36:49 INFO - 'USERNAME': 'cltbld', 19:36:49 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:36:49 INFO - 'WINDIR': 'C:\\windows', 19:36:49 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:36:49 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:36:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:36:50 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 19:36:50 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 19:36:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:36:51 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozsystemmonitor-0.0.tar.gz 19:36:51 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 19:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 19:36:51 INFO - Installing collected packages: mozsystemmonitor 19:36:51 INFO - Running setup.py install for mozsystemmonitor 19:36:51 INFO - Successfully installed mozsystemmonitor 19:36:51 INFO - Cleaning up... 19:36:51 INFO - Return code: 0 19:36:51 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 19:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:51 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\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 0x0198FB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01955EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0143E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A15660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-047', 'USERDOMAIN': 'T-W732-IX-047', '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-047', '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-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 19:36:51 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\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-pgo\build 19:36:51 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\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 19:36:51 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:36:51 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:36:51 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:36:51 INFO - 'COMPUTERNAME': 'T-W732-IX-047', 19:36:51 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:36:51 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:36:51 INFO - 'DCLOCATION': 'SCL3', 19:36:51 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:36:51 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:36:51 INFO - 'HOMEDRIVE': 'C:', 19:36:51 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:36:51 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:36:51 INFO - 'KTS_VERSION': '1.19c', 19:36:51 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:36:51 INFO - 'LOGONSERVER': '\\\\T-W732-IX-047', 19:36:51 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:36:51 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:51 INFO - 'MOZ_NO_REMOTE': '1', 19:36:51 INFO - 'NO_EM_RESTART': '1', 19:36:51 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:36:51 INFO - 'OS': 'Windows_NT', 19:36:51 INFO - 'OURDRIVE': 'C:', 19:36:51 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 19:36:51 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:36:51 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:36:51 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:36:51 INFO - 'PROCESSOR_LEVEL': '6', 19:36:51 INFO - 'PROCESSOR_REVISION': '1e05', 19:36:51 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:36:51 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:36:51 INFO - 'PROMPT': '$P$G', 19:36:51 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 19:36:51 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:36:51 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:36:51 INFO - 'PWD': 'C:\\slave\\test-pgo', 19:36:51 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:36:51 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:36:51 INFO - 'SYSTEMDRIVE': 'C:', 19:36:51 INFO - 'SYSTEMROOT': 'C:\\windows', 19:36:51 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:36:51 INFO - 'TEST1': 'testie', 19:36:51 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:36:51 INFO - 'USERDOMAIN': 'T-W732-IX-047', 19:36:51 INFO - 'USERNAME': 'cltbld', 19:36:51 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:36:51 INFO - 'WINDIR': 'C:\\windows', 19:36:51 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:36:51 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:36:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:51 INFO - Downloading/unpacking blobuploader==1.2.4 19:36:51 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 19:36:51 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 19:36:52 INFO - Downloading blobuploader-1.2.4.tar.gz 19:36:52 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz 19:36:52 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 19:36:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:36:52 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 19:36:52 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 19:36:53 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2frequests-1.2.3.tar.gz 19:36:53 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 19:36:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:36:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:36:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:36:53 INFO - Downloading docopt-0.6.1.tar.gz 19:36:53 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fdocopt-0.6.1.tar.gz 19:36:53 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 19:36:53 INFO - Installing collected packages: blobuploader, requests, docopt 19:36:53 INFO - Running setup.py install for blobuploader 19:36:53 INFO - Running setup.py install for requests 19:36:55 INFO - Running setup.py install for docopt 19:36:55 INFO - Successfully installed blobuploader requests docopt 19:36:55 INFO - Cleaning up... 19:36:56 INFO - Return code: 0 19:36:56 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 19:36: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')]} 19:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:36: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')]} 19:36:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:36:56 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\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 0x0198FB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01955EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0143E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A15660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-047', 'USERDOMAIN': 'T-W732-IX-047', '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-047', '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-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 19:36:56 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\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-pgo\build\tests\config 19:36:56 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --no-deps --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\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 19:36:56 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:36:56 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:36:56 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:36:56 INFO - 'COMPUTERNAME': 'T-W732-IX-047', 19:36:56 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:36:56 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:36:56 INFO - 'DCLOCATION': 'SCL3', 19:36:56 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:36:56 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:36:56 INFO - 'HOMEDRIVE': 'C:', 19:36:56 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:36:56 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:36:56 INFO - 'KTS_VERSION': '1.19c', 19:36:56 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:36:56 INFO - 'LOGONSERVER': '\\\\T-W732-IX-047', 19:36:56 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:36:56 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:36:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:36:56 INFO - 'MOZ_NO_REMOTE': '1', 19:36:56 INFO - 'NO_EM_RESTART': '1', 19:36:56 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:36:56 INFO - 'OS': 'Windows_NT', 19:36:56 INFO - 'OURDRIVE': 'C:', 19:36: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;', 19:36:56 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:36:56 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:36:56 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:36:56 INFO - 'PROCESSOR_LEVEL': '6', 19:36:56 INFO - 'PROCESSOR_REVISION': '1e05', 19:36:56 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:36:56 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:36:56 INFO - 'PROMPT': '$P$G', 19:36:56 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 19:36:56 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:36:56 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:36:56 INFO - 'PWD': 'C:\\slave\\test-pgo', 19:36:56 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:36:56 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:36:56 INFO - 'SYSTEMDRIVE': 'C:', 19:36:56 INFO - 'SYSTEMROOT': 'C:\\windows', 19:36:56 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:36:56 INFO - 'TEST1': 'testie', 19:36:56 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:36:56 INFO - 'USERDOMAIN': 'T-W732-IX-047', 19:36:56 INFO - 'USERNAME': 'cltbld', 19:36:56 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:36:56 INFO - 'WINDIR': 'C:\\windows', 19:36:56 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:36:56 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:36:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:36:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:36:56 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 19:36:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-okikkz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 19:36:56 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 19:36:56 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lirvdn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 19:36:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 19:36:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3k15fi-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 19:36:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 19:36:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tuyaqq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 19:36:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 19:36:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-tqxiea-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 19:36:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 19:36:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gsfwov-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 19:36:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 19:36:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j1tjrz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 19:36:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 19:36:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-0jsqtk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 19:36:57 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 19:36:57 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-surhwo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 19:36:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 19:36:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_ctqxy-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 19:36:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 19:36:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gtm9mv-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 19:36:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 19:36:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-enid8r-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 19:36:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 19:36:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-td0ol_-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 19:36:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 19:36:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-aliznd-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 19:36:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 19:36:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-k1rw4a-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:36:58 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 19:36:58 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-j_gzpa-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 19:36:59 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 19:36:59 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hpamge-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 19:36:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:36:59 INFO - Running setup.py install for manifestparser 19:36:59 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:36:59 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 19:36:59 INFO - Running setup.py install for mozcrash 19:37:00 INFO - Running setup.py install for mozdebug 19:37:00 INFO - Running setup.py install for mozdevice 19:37:00 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:00 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:00 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:00 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:01 INFO - Running setup.py install for mozfile 19:37:01 INFO - Running setup.py install for mozhttpd 19:37:01 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:01 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:02 INFO - Running setup.py install for mozinfo 19:37:02 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:02 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:02 INFO - Running setup.py install for mozInstall 19:37:02 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:02 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:02 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:02 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:02 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:02 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:02 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:02 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:02 INFO - Running setup.py install for mozleak 19:37:03 INFO - Running setup.py install for mozlog 19:37:03 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:03 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:04 INFO - Running setup.py install for moznetwork 19:37:04 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:04 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:04 INFO - Running setup.py install for mozprocess 19:37:05 INFO - Running setup.py install for mozprofile 19:37:05 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:05 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:05 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:05 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:05 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:05 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:05 INFO - Running setup.py install for mozrunner 19:37:05 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:05 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:06 INFO - Running setup.py install for mozscreenshot 19:37:06 INFO - Running setup.py install for moztest 19:37:07 INFO - Running setup.py install for mozversion 19:37:07 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 19:37:07 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 19:37:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:37:07 INFO - Cleaning up... 19:37:07 INFO - Return code: 0 19:37:07 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 19:37:07 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')]} 19:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:07 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')]} 19:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:07 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\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 0x0198FB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01955EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0143E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A15660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-047', 'USERDOMAIN': 'T-W732-IX-047', '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-047', '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-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 19:37:07 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\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-pgo\build\tests\config 19:37:07 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\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 19:37:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:37:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:37:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:37:07 INFO - 'COMPUTERNAME': 'T-W732-IX-047', 19:37:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:37:07 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:37:07 INFO - 'DCLOCATION': 'SCL3', 19:37:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:37:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:37:07 INFO - 'HOMEDRIVE': 'C:', 19:37:07 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:37:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:37:07 INFO - 'KTS_VERSION': '1.19c', 19:37:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:37:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-047', 19:37:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:37:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:07 INFO - 'MOZ_NO_REMOTE': '1', 19:37:07 INFO - 'NO_EM_RESTART': '1', 19:37:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:37:07 INFO - 'OS': 'Windows_NT', 19:37:07 INFO - 'OURDRIVE': 'C:', 19:37:07 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;', 19:37:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:37:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:37:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:37:07 INFO - 'PROCESSOR_LEVEL': '6', 19:37:07 INFO - 'PROCESSOR_REVISION': '1e05', 19:37:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:37:07 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:37:07 INFO - 'PROMPT': '$P$G', 19:37:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 19:37:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:37:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:37:07 INFO - 'PWD': 'C:\\slave\\test-pgo', 19:37:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:37:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:37:07 INFO - 'SYSTEMDRIVE': 'C:', 19:37:07 INFO - 'SYSTEMROOT': 'C:\\windows', 19:37:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:37:07 INFO - 'TEST1': 'testie', 19:37:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:37:07 INFO - 'USERDOMAIN': 'T-W732-IX-047', 19:37:07 INFO - 'USERNAME': 'cltbld', 19:37:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:37:07 INFO - 'WINDIR': 'C:\\windows', 19:37:07 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:37:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:37:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:07 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 19:37:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-_hek0j-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 19:37:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 1)) 19:37:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 19:37:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ilglqx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 19:37:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 19:37:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 19:37:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-uvwino-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 19:37:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 19:37:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 19:37:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-mg3rf1-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 19:37:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 19:37:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 19:37:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6fkgze-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 19:37:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 5)) 19:37:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 19:37:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-lqgq9k-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 19:37:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 6)) 19:37:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 19:37:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gtjguk-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 19:37:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 7)) 19:37:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 19:37:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8kh79o-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 19:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 8)) 19:37:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 19:37:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-quasbu-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 19:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 9)) 19:37:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 19:37:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hwqha0-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 19:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 19:37:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 19:37:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-waez94-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 19:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 11)) 19:37:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 19:37:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jx4xto-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 19:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 12)) 19:37:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 19:37:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dpuwes-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 19:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 13)) 19:37:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 19:37:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-96gijx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 19:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 14)) 19:37:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 19:37:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wxqvyd-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:37:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 15)) 19:37:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 19:37:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pobsgn-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 19:37:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test-pgo/build/tests/mozbase/moztest in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 16)) 19:37:10 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 19:37:10 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-sa6ckp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 19:37:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 17)) 19:37:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 19:37:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 2)) 19:37:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 3)) 19:37:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 19:37:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozdevice==0.46->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 4)) 19:37:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 19:37:10 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 19:37:10 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 19:37:11 INFO - Downloading blessings-1.5.1.tar.gz 19:37:11 INFO - Storing download in cache at c:\slave\test-pgo\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblessings-1.5.1.tar.gz 19:37:11 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 19:37:11 INFO - Installing collected packages: blessings 19:37:11 INFO - Running setup.py install for blessings 19:37:11 INFO - Successfully installed blessings 19:37:11 INFO - Cleaning up... 19:37:14 INFO - Return code: 0 19:37:14 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 19:37:14 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 19:37:14 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 19:37:14 INFO - Reading from file tmpfile_stdout 19:37:14 INFO - Using _rmtree_windows ... 19:37:14 INFO - Using _rmtree_windows ... 19:37:14 INFO - Current package versions: 19:37:14 INFO - blessings == 1.5.1 19:37:14 INFO - blobuploader == 1.2.4 19:37:14 INFO - docopt == 0.6.1 19:37:14 INFO - manifestparser == 1.1 19:37:14 INFO - mozInstall == 1.12 19:37:14 INFO - mozcrash == 0.16 19:37:14 INFO - mozdebug == 0.1 19:37:14 INFO - mozdevice == 0.46 19:37:14 INFO - mozfile == 1.2 19:37:14 INFO - mozhttpd == 0.7 19:37:14 INFO - mozinfo == 0.8 19:37:14 INFO - mozleak == 0.1 19:37:14 INFO - mozlog == 3.0 19:37:14 INFO - moznetwork == 0.27 19:37:14 INFO - mozprocess == 0.22 19:37:14 INFO - mozprofile == 0.27 19:37:14 INFO - mozrunner == 6.10 19:37:14 INFO - mozscreenshot == 0.1 19:37:14 INFO - mozsystemmonitor == 0.0 19:37:14 INFO - moztest == 0.7 19:37:14 INFO - mozversion == 1.4 19:37:14 INFO - psutil == 3.1.1 19:37:14 INFO - requests == 1.2.3 19:37:14 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 19:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:37:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:37:14 INFO - retry: Calling run_command with args: [['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\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 0x0198FB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01955EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0143E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A15660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-047', 'USERDOMAIN': 'T-W732-IX-047', '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-047', '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-pgo', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'USERPROFILE': 'C:\\Users\\cltbld', 'OS': 'Windows_NT', 'PUBLIC': 'C:\\Users\\Public', 'WINDIR': 'C:\\windows'}}, attempt #1 19:37:14 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test-pgo\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test-pgo\\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-pgo\build\tests\talos 19:37:14 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip install --download-cache C:\slave\test-pgo\build/venv\cache --timeout 120 -r C:\slave\test-pgo\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 19:37:14 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:37:14 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:37:14 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:37:14 INFO - 'COMPUTERNAME': 'T-W732-IX-047', 19:37:14 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:37:14 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:37:14 INFO - 'DCLOCATION': 'SCL3', 19:37:14 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:37:14 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:37:14 INFO - 'HOMEDRIVE': 'C:', 19:37:14 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:37:14 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:37:14 INFO - 'KTS_VERSION': '1.19c', 19:37:14 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:37:14 INFO - 'LOGONSERVER': '\\\\T-W732-IX-047', 19:37:14 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:37:14 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:37:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:14 INFO - 'MOZ_NO_REMOTE': '1', 19:37:14 INFO - 'NO_EM_RESTART': '1', 19:37:14 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:37:14 INFO - 'OS': 'Windows_NT', 19:37:14 INFO - 'OURDRIVE': 'C:', 19:37:14 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 19:37:14 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:37:14 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:37:14 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:37:14 INFO - 'PROCESSOR_LEVEL': '6', 19:37:14 INFO - 'PROCESSOR_REVISION': '1e05', 19:37:14 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:37:14 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:37:14 INFO - 'PROMPT': '$P$G', 19:37:14 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 19:37:14 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:37:14 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:37:14 INFO - 'PWD': 'C:\\slave\\test-pgo', 19:37:14 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:37:14 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:37:14 INFO - 'SYSTEMDRIVE': 'C:', 19:37:14 INFO - 'SYSTEMROOT': 'C:\\windows', 19:37:14 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:37:14 INFO - 'TEST1': 'testie', 19:37:14 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:37:14 INFO - 'USERDOMAIN': 'T-W732-IX-047', 19:37:14 INFO - 'USERNAME': 'cltbld', 19:37:14 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:37:14 INFO - 'WINDIR': 'C:\\windows', 19:37:14 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:37:14 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:37:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:37:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 19:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 2)) 19:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3)) 19:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 4)) 19:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 5)) 19:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 6)) 19:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 7)) 19:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test-pgo\build\venv\lib\site-packages (from -r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 8)) 19:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 19:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 3)) 19:37:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test-pgo\build\venv\lib\site-packages (from mozlog>=3.0->mozcrash>=0.15->-r C:\slave\test-pgo\build\tests\talos\requirements.txt (line 1)) 19:37:15 INFO - Cleaning up... 19:37:15 INFO - Return code: 0 19:37:15 INFO - Running post-action listener: _resource_record_post_action 19:37:15 INFO - Running post-action listener: _start_resource_monitoring 19:37:15 INFO - Starting resource monitoring. 19:37:15 INFO - ##### 19:37:15 INFO - ##### Running install step. 19:37:15 INFO - ##### 19:37:15 INFO - Running pre-action listener: _resource_record_pre_action 19:37:15 INFO - Running main action method: install 19:37:15 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 19:37:15 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 19:37:15 INFO - Reading from file tmpfile_stdout 19:37:15 INFO - Using _rmtree_windows ... 19:37:15 INFO - Using _rmtree_windows ... 19:37:15 INFO - Detecting whether we're running mozinstall >=1.0... 19:37:15 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 19:37:15 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 19:37:15 INFO - Reading from file tmpfile_stdout 19:37:15 INFO - Output received: 19:37:15 INFO - Usage: mozinstall-script.py [options] installer 19:37:15 INFO - Options: 19:37:15 INFO - -h, --help show this help message and exit 19:37:15 INFO - -d DEST, --destination=DEST 19:37:15 INFO - Directory to install application into. [default: 19:37:15 INFO - "C:\slave\test-pgo"] 19:37:15 INFO - --app=APP Application being installed. [default: firefox] 19:37:15 INFO - Using _rmtree_windows ... 19:37:15 INFO - Using _rmtree_windows ... 19:37:15 INFO - mkdir: C:\slave\test-pgo\build\application 19:37:15 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test-pgo\\build\\application'] 19:37:15 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip --destination C:\slave\test-pgo\build\application 19:37:17 INFO - Reading from file tmpfile_stdout 19:37:17 INFO - Output received: 19:37:17 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 19:37:17 INFO - Using _rmtree_windows ... 19:37:17 INFO - Using _rmtree_windows ... 19:37:17 INFO - Running post-action listener: _resource_record_post_action 19:37:17 INFO - ##### 19:37:17 INFO - ##### Running run-tests step. 19:37:17 INFO - ##### 19:37:17 INFO - Running pre-action listener: _resource_record_pre_action 19:37:17 INFO - Running main action method: run_tests 19:37:17 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 19:37:17 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 19:37:17 INFO - Python 2.7.3 19:37:17 INFO - Return code: 0 19:37:17 INFO - grabbing minidump binary from tooltool 19:37:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:37:17 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-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01358B48>, '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 0x0137A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01A15660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 19:37:17 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-pgo\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test-pgo\build 19:37:17 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-pgo\build\tests\config/tooltool-manifests/win32/releng.manifest -o 19:37:17 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 19:37:19 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpue_93e 19:37:19 INFO - INFO - File integrity verified, renaming tmpue_93e to win32-minidump_stackwalk.exe 19:37:19 INFO - Return code: 0 19:37:19 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 19:37:19 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 19:37:19 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 19:37:19 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 19:37:19 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 19:37:19 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-047', '--webServer', 'localhost'] in C:\slave\test-pgo\build 19:37:19 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName B2G-Inbound --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-047 --webServer localhost 19:37:19 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 19:37:19 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 19:37:19 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 19:37:19 INFO - 'COMPUTERNAME': 'T-W732-IX-047', 19:37:19 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 19:37:19 INFO - 'CYGWINBASE': 'C:\\cygwin', 19:37:19 INFO - 'DCLOCATION': 'SCL3', 19:37:19 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 19:37:19 INFO - 'FP_NO_HOST_CHECK': 'NO', 19:37:19 INFO - 'HOMEDRIVE': 'C:', 19:37:19 INFO - 'HOMEPATH': '\\Users\\cltbld', 19:37:19 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 19:37:19 INFO - 'KTS_VERSION': '1.19c', 19:37:19 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 19:37:19 INFO - 'LOGONSERVER': '\\\\T-W732-IX-047', 19:37:19 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 19:37:19 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 19:37:19 INFO - 'MONDIR': 'C:\\Monitor_config\\', 19:37:19 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 19:37:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:37:19 INFO - 'MOZ_NO_REMOTE': '1', 19:37:19 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 19:37:19 INFO - 'NO_EM_RESTART': '1', 19:37:19 INFO - 'NUMBER_OF_PROCESSORS': '8', 19:37:19 INFO - 'OS': 'Windows_NT', 19:37:19 INFO - 'OURDRIVE': 'C:', 19:37:19 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 19:37:19 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 19:37:19 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 19:37:19 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 19:37:19 INFO - 'PROCESSOR_LEVEL': '6', 19:37:19 INFO - 'PROCESSOR_REVISION': '1e05', 19:37:19 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 19:37:19 INFO - 'PROGRAMFILES': 'C:\\Program Files', 19:37:19 INFO - 'PROMPT': '$P$G', 19:37:19 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 19:37:19 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 19:37:19 INFO - 'PUBLIC': 'C:\\Users\\Public', 19:37:19 INFO - 'PWD': 'C:\\slave\\test-pgo', 19:37:19 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 19:37:19 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 19:37:19 INFO - 'SLAVEDIR': 'C:\\slave\\', 19:37:19 INFO - 'SYSTEMDRIVE': 'C:', 19:37:19 INFO - 'SYSTEMROOT': 'C:\\windows', 19:37:19 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:37:19 INFO - 'TEST1': 'testie', 19:37:19 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 19:37:19 INFO - 'USERDOMAIN': 'T-W732-IX-047', 19:37:19 INFO - 'USERNAME': 'cltbld', 19:37:19 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 19:37:19 INFO - 'WINDIR': 'C:\\windows', 19:37:19 INFO - 'WINDOWS_TRACING_FLAGS': '3', 19:37:19 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 19:37:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 19:37:19 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'B2G-Inbound', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-047', '--webServer', 'localhost'] with output_timeout 3600 19:37:20 INFO - mozversion INFO | application_buildid: 20151021143022 19:37:20 INFO - mozversion INFO | application_changeset: 0f821c314396155126f562b7bf7c8f89b62ab4ef 19:37:20 INFO - mozversion INFO | application_display_name: Nightly 19:37:20 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:37:20 INFO - mozversion INFO | application_name: Firefox 19:37:20 INFO - mozversion INFO | application_remotingname: firefox 19:37:20 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/b2g-inbound 19:37:20 INFO - mozversion INFO | application_vendor: Mozilla 19:37:20 INFO - mozversion INFO | application_version: 44.0a1 19:37:20 INFO - mozversion INFO | platform_buildid: 20151021143022 19:37:20 INFO - mozversion INFO | platform_changeset: 0f821c314396155126f562b7bf7c8f89b62ab4ef 19:37:20 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/b2g-inbound 19:37:20 INFO - mozversion INFO | platform_version: 44.0a1 19:37:20 INFO - 2015-10-21 19:37:20,315 DEBUG : using testdate: 1445481440 19:37:20 INFO - 2015-10-21 19:37:20,315 DEBUG : actual date: 1445481440 19:37:20 INFO - 2015-10-21 19:37:20,325 INFO : starting webserver on 'localhost:49684' 19:37:20 INFO - 2015-10-21 19:37:20,326 INFO : Starting test suite T-W732-IX-047 19:37:20 INFO - 2015-10-21 19:37:20,328 INFO : Starting test dromaeo_css 19:37:20 INFO - 2015-10-21 19:37:20,328 DEBUG : operating with platform_type : w7_ 19:37:20 INFO - 2015-10-21 19:37:20,328 INFO : Initialising browser for dromaeo_css test... 19:37:20 INFO - 2015-10-21 19:37:20,427 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmyjvhm\profile http://localhost:49684/getInfo.html 19:37:28 INFO - 2015-10-21 19:37:28,528 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:37:28 INFO - 2015-10-21 19:37:28,528 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:37:28 INFO - 2015-10-21 19:37:28,528 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 19:37:28 INFO - 2015-10-21 19:37:28,528 DEBUG : BROWSER_OUTPUT: __metrics 19:37:28 INFO - 2015-10-21 19:37:28,582 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:37:30 INFO - 2015-10-21 19:37:30,486 INFO : Browser initialized. 19:37:30 INFO - 2015-10-21 19:37:30,486 INFO : Running cycle 1/1 for dromaeo_css test... 19:37:30 INFO - 2015-10-21 19:37:30,486 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmyjvhm\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:37:41 INFO - 2015-10-21 19:37:41,953 DEBUG : BROWSER_OUTPUT: RSS: Main: 130158592 19:37:41 INFO - 2015-10-21 19:37:41,953 DEBUG : BROWSER_OUTPUT: 19:38:33 INFO - 2015-10-21 19:38:33,023 DEBUG : BROWSER_OUTPUT: 1445481513024 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] 19:40:42 INFO - 2015-10-21 19:40:42,358 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49684/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49684/tests/dromaeo/cssquery-ext.html) 19:40:43 INFO - 2015-10-21 19:40:43,446 DEBUG : BROWSER_OUTPUT: RSS: Main: 166363136 19:40:43 INFO - 2015-10-21 19:40:43,446 DEBUG : BROWSER_OUTPUT: 19:43:45 INFO - 2015-10-21 19:43:45,762 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49684/tests/dromaeo/cssquery-ext.html (next: http://localhost:49684/tests/dromaeo/cssquery-jquery.html) 19:43:46 INFO - 2015-10-21 19:43:46,316 DEBUG : BROWSER_OUTPUT: RSS: Main: 197779456 19:43:46 INFO - 2015-10-21 19:43:46,316 DEBUG : BROWSER_OUTPUT: 19:46:48 INFO - 2015-10-21 19:46:48,217 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49684/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49684/tests/dromaeo/cssquery-mootools.html) 19:46:48 INFO - 2015-10-21 19:46:48,780 DEBUG : BROWSER_OUTPUT: RSS: Main: 144248832 19:46:48 INFO - 2015-10-21 19:46:48,780 DEBUG : BROWSER_OUTPUT: 19:49:50 INFO - 2015-10-21 19:49:50,717 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49684/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49684/tests/dromaeo/cssquery-prototype.html) 19:49:51 INFO - 2015-10-21 19:49:51,315 DEBUG : BROWSER_OUTPUT: RSS: Main: 140816384 19:49:51 INFO - 2015-10-21 19:49:51,315 DEBUG : BROWSER_OUTPUT: 19:52:53 INFO - 2015-10-21 19:52:53,851 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49684/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49684/tests/dromaeo/cssquery-yui.html) 19:52:54 INFO - 2015-10-21 19:52:54,430 DEBUG : BROWSER_OUTPUT: RSS: Main: 130793472 19:52:54 INFO - 2015-10-21 19:52:54,430 DEBUG : BROWSER_OUTPUT: 19:55:56 INFO - 2015-10-21 19:55:56,848 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49684/tests/dromaeo/cssquery-yui.html (next: http://localhost:49684/tests/dromaeo/cssquery-dojo.html) 19:55:57 INFO - 2015-10-21 19:55:57,095 DEBUG : BROWSER_OUTPUT: RSS: Main: 192598016 19:55:57 INFO - 2015-10-21 19:55:57,095 DEBUG : BROWSER_OUTPUT: 19:55:57 INFO - 2015-10-21 19:55:57,095 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:55:57 INFO - 2015-10-21 19:55:57,095 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:55:57 INFO - 2015-10-21 19:55:57,095 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:55:57 INFO - 2015-10-21 19:55:57,096 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:55:57 INFO - 2015-10-21 19:55:57,096 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2785.039161489286;2805.635267415236;2828.6181365515663;2842.2041828288075;2869.167941297024;4966;4979.502049795019;5028.673136246146;5184;5196.610254230934;5207.140821764414;5212.973935130329;5218.21726740989;5248.448912864152;5280.419153893069;4464.330350447426;4465;4496.325551167335;4556.9088618227615;4590.862274131781;4527.705699129546;4540.023894862604;4545.636349092081;4577.427821522309;4605.286180642006;4319.006628475452;4444.866654000369;4523.796429160696;4527.388802511652;4527.981204229039;4550.567696068884;4563.885902852455;4570.840020599263;4618.053299073682;4653.557911998389;4412.073464572443;4489.236829738919;4499.280115181556;4499.325101234808;4548.181327361073;5119.872003199913;5131.461196574381;5197.688138711652;5201.531862132426;5224.686518808883;5455.372632147314;5458.399576046631;5536.058869992071;5552.833414997556;5559.861003474905;5387.191921211849;5410.5401040911565;5448.264484294648;5473.781048758045;5479.671219726829;5051.949480505222;5070.366204224472;5143.254228136899;5143.794248230066;5180.611454140954;5559.833205003856;5561.138023606348;5564.721763911788;5654.208410822488;5671.461211184931;5265.315508983807;5300.628955973122;5347.41178470368;5348.411674715856;5378.865528361822;5394;5453.836384908459;5522.530584900329;5540.861478463071;5547.417521160301;5397.9460205397445;5447.237386765856;5541.251930989265;5545.916811247835;5548.195511650788;5371.865703357369;5388.595855310868;5466.070767969455;5472.151816468454;5474.233607294982;3465.9826700866333;3486.982565087209;3493.5807703075793;3495.2310491691787;3519.894403167909;3157.5105858591955;3161.2729072313496;3215.437298472758;3216.4049650814673;3223.403670320998;5242.554382877422;5261.394939581921;5314.176302673014;5324.547413469899;5348.3849357323625;5528.889422211533;5544.833654990276;5581.413951535112;5597.972010139923;5655.208270842086;5334.093204155303;5334.626576139711;5346.304980352529;5440.619156659075;5450.182472629058;4229.555896630871;4316.395704601358;4374.059577190919;4374.737515749065;4389.561043895585;5836.445537673914;5837.211976383134;5936.317323507766;5938.792142275;5950.107483877453;5494.395616482264;5525.613207075467;5529.723513824373;5642.88714225705;5649.406812284816;5693.886122277614;5694.515966142759;5730;5777.8555536111935;5860.648361098177;5318.095923692904;5384.9730751346;5473.480019398151;5478.643888147258;5488.341399031982;5396.379416367168;5473.863153421196;5486.231927530069;5618.690971996416;5623.465770751772;5202.167653175474;5314.787408503771;5376.919346209888;5392.919106213331;5485.232067510631;5350.170723537858;5369.114096174244;5423.430539793265;5492.258545096361;5499.752511136929;5149;5243.187305967581;5370.9731451342495;5393.6763794172475;5397.001554712391;5308.309919710413;5381.3004309441085;5403.675779453245;5407.080796264723;5486.067368547277;5309.840704778863;5316.6544174628525;5382.219578161223;5414.593905457185;5417.702026388586 19:55:57 INFO - 2015-10-21 19:55:57,096 DEBUG : BROWSER_OUTPUT: |1;ext.html;512.2648998686883;512.5361547799241;514.0541403816976;514.1798830864772;530.2523441946851;29310.706892931063;31502.369952600962;38880.222395552075;39055;39336;39659.40510892332;40171.59828401715;40196.59803401973;40217.195656086864;40309.39535906957;7764.922350776476;8126.146754590757;8279.50322980623;8282.83434331313;8286.254237118657;7794.922050779476;10120.190384769237;10314.278000539965;10328.845067323997;10332.741681457948;9992.600295988152;10056.949715251414;10147.594096236142;10154.796904061914;10166.69499915;8002.239787220205;8155.836883262361;10105.191584673245;10170.847437288372;10221.437820919846;9781.217502599775;9910.454924979123;9973.559122102863;10002.199824014062;10014.54934527948;36871.815640921894;37414.62585374139;37444.251114977546;37458.25083498315;37475.81262093672;3264.8530816113093;3332.2502436951736;3465.8267086645565;3809.580946095927;3909.2376986487648;28692;28699.713002869914;29419;30069.398612027857;30231.848840755876;2653.3764565327133;2670.719574444675;2709.864506774673;2761.875715592783;2786.3869948611273;335483;335578;335688;336661;337709;45354;46080;50992.74503627458;51551.484485155415;51700;49253.261201082016;49355.50644493581;49475.752621237385;49498;49521.25718114231;14118.788218176736;14154.292285385689;14208.2239720035;14214;14255.572332830032;10822.891771082188;10823.458827058615;10837;10837.078848297826;10863.782724345469;17082.316707331567;17090.57273568146;17199.1400429978;17208.741868871977;17224.138793060298;7195.316444937723;7390.113186417664;7397.297256760599;7398.593077380796;7424.071991001125;882.896379525593;915.5102020419043;964.4743614729989;974.3033730882312;992.593036854886;27774.861125694646;28295.15114546567;28567.714322856922;28679.426411471653;28920;981.6894445914642;987.6098940918297;989.715692503176;991.9305648604528;1035.7514196592915;14789.852101478848;14828.481003164838;15051.698966020836;15150.31823567942;15676.764848527282;381557;384179;384749;386708;386987;47884.5211547887;47899.281510777364;48008.759956199996;48111.518884811405;48164.759176203894;1175.1538891997748;1181.285322379965;1189.7323102301914;1194.5281613762688;1195.2171327780318;28436.57345139783;28673.254657329715;28763;29222.707772922848;29304.413911721218;41570.1685966285;41665;41746.22374616309;41790.79104604579;41837.58162418337;374116;375695;376373;376950;377647;24290.271291861274;27070.1878943632;27545.862270688518;27563.586546201423;27598.996291075364;37748.811255944645;38712.80643596764;38775.2244955105;38809.223815522964;40462.9884252896;32809.671903280665;34330.65669343275;34478.48282275818;34756.47865282073;35338.1165470855;31622.209444764067;33086.17284567905;34720;34881.65118348886;35170.64829351776;33182.879221837575;33226.169345765586;33302.66697332996;33403.498947516295;34956.65043349534;29370.559441607966;30036.399272014;30069.69930300669;30802.69197308088;32712.672873271913;24897.634724701125;25014;25035;25521.36196595026;25562 19:55:57 INFO - 2015-10-21 19:55:57,105 DEBUG : BROWSER_OUTPUT: |2;jquery.html;294.86878339139065;297.4007375139095;303.82530045223996;306.2328866190194;306.74080402060264;9740.902590974089;13797.24115173665;16379.344826206952;17173.313067477302;17285.30858765648;10489.213309001818;10769.461526923642;17457.73813392797;17544.12279386032;17766.758806152186;3782.2813665403623;4297.312430011199;4298.849540266091;4304.806283717225;4307.978460107703;3650.5619325680823;3704.111013356802;4300.548442413533;4597.287420449836;4684.929726054112;4608.838690645828;4628.305754136873;4630.536946305376;4634.258518637019;4635.814567417299;4473.083017981306;4616.2613981762925;4620.491745907947;4624.606908412789;4624.930626040612;3846.865359712425;3869.090763670535;3871.4192871069286;3900.395438707005;3902.180542086165;12143.757124857542;12424.937875310565;12602.117851750381;12647.683807904876;12770;2039.5315372931466;2068.172730907634;2068.524239424926;2103.8632488888175;2153.160267495678;9192;9295.628174872996;9336.859947100798;9348.532573371373;9512;1475.2538861135922;1541.1908747907326;1554.3316373959194;1582.9366825326972;1639.147643225516;68231;68793.65603172002;68925.31074689238;72169.27830721678;75346;5787.652740835563;5790.044642634002;5818.7672493100645;5850.9414905851245;5867.237259156283;6476.417122458907;6653.900191497131;6663;6668;6669.1996960364095;4955.281484184845;4977.626677999165;5020.849374518771;5033.7734801934;5040.924386134211;5499.890002199933;5501.724913754297;5538.003159431341;5546.251256080458;5595.524380427628;2623.776978956772;2629.1455277034656;2639.8680065996623;2652.0982865825326;2655.7742591879905;932.0399988012358;934.8410770169088;938.0572524612857;938.6620816506053;1002.819492491344;360.93683605368955;366.5619584596435;406.8901396622895;412.26410856621385;435.2209544914567;10967.670969870916;11422.771544569228;11472.024877885306;11588.47851846652;11683.941580292214;412.3134980257837;413.9855105071308;419.32279368819746;426.3242760225016;456.9337446070301;5981.0729336952845;6062.605930614496;6068.787592434332;6335.398137177053;6352.555321127476;437484;438241;438781;440323;443037;67022;69412.3058769416;70869;73098.63450682712;84701.15298847055;455.4853016091795;466.2353739866621;467.0775218942561;467.1731036066144;467.49510528628997;10388.948055259676;10484.318519296221;10524.421156836448;10553.41956192386;10840.186985975926;41695.374569380874;41778.79110604428;42031.7898410506;42903.356449653904;43366.34950475682;52477.21284180817;52808.47191528035;54517;60553;61662.69168654128;5714.485696287354;5767.106098554731;5827.8834423310445;5855.238818953509;5857.47282744555;11507.769844603228;12063.69840753953;12124.515019399125;12129.514819407124;12162.452689628812;7495.250474952461;9189.586468608795;9901.455419951972;10047.346922450017;10303.3817970922;9687.806243874942;9825.361351512129;9938.850917236392;10031.448270345201;10224.28430009922;10195;10312.381257124596;10402.739931501774;10481.266311358131;10490.685279441324;5690.2887139107615;5766.163906233656;5782.508486778671;5869.618474799125;5871.853203669943;9604.95197524008;9941.403515789076;10131.797364052825;10203.489825508843;10256.692299231036 19:55:57 INFO - 2015-10-21 19:55:57,107 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1202.9338386388745;1216.0575553945691;1256.9560065397707;1442.76915693489;1443.155753883978;7792.649330780115;8355.248027677508;8590.2698270647;8769.079246679103;8789.340799440044;8327.292180164679;8525.275351595106;8528.403011789178;8562.486250824939;8734.868976965336;1974.427416049344;2007.8594498385046;2192.9890350548267;2200.4058904095887;2213.4687674957977;2136.9849321572206;2197.121151539381;2226.7327920649464;2232.966505502419;2245.8091062259728;2162.610730068587;2212.8672279663274;2218.6671999200166;2219.04581030157;2223.666450032492;1968.0750047477734;1998.8201061904354;2157.8813165275906;2167.9024443899903;2198.1756841184556;2213.2143089203264;2229.665550167472;2233.1848875160613;2249.4713742270555;2255.4135924659545;8380.413371064027;8598.656053757843;8610.827783444358;8629.913700862973;8640.39517233794;4783.234682450826;4827.275908613701;4835.661503694742;4836.226203807374;4839.854804355875;3224.983875080633;3294.67053294671;3308.2391050058623;3315.8839440619704;3316.950245746316;960.5629438605417;977.8044391121733;1014.1481155829065;1023.9232057595635;1025.4770067265674;11936.164468487123;11939.94030029853;11981.580644677482;11985.640430787003;12039.976601988754;4076.3885417187657;4121.793910304473;4141.275276826544;4167.291560434673;4174.895627609334;10051.24615653829;10059.346142500714;10063.396196228103;10065.647702330383;10066.295359324924;4735.976320118446;4744.59670927975;4745.074710431432;4746.38297021385;4748.240281554935;4262.808173632194;4297.613214810682;4299.398084268205;4306.0957198987935;4308.870733877926;3434.6221915589267;3449.724022078228;3476.8700172444055;3495.9861640124077;3499.825008749552;1020.5254556631202;1030.1552726763982;1032.3650954662828;1036.2746077745608;1038.309524166421;907.5054095517959;1106.623747925709;1121.9382933938712;1123.3596849795538;1125.7129431994788;7234.74678386254;7707.229277072248;7981.880271795928;8137.145599712064;8156.1028286888395;579.826052184343;594.6759016336107;601.1373678770977;606.1967892542311;606.8998615228526;5903.645781253139;5940.910886336708;5947.791827286111;5949.405059494103;5957.910631340534;10650.094741946867;10703.304285221591;10840.295380800222;10870.18473614482;10928;10576.20678449104;10594.735131621617;10616.893831061745;10627.09669678086;10683.03852653265;7834.0599128105;7866.842663146705;7876.960615197002;7878.290953814069;7923.049234091945;7343.48595598303;7344.045274114331;7346.742863999734;7549.773506794805;7810.297073263434;9208.907910920805;9233.261339092722;9467.857982130412;9541.618335266512;9546.809063818546;9559.61761529531;9837.9016209837;9925.305228633926;9939.353941993748;9949.850752238577;7772.300492955661;7792.259735325135;7844.6077696113825;7865.3314468268545;7871.212878712083;8129.79675508117;8309.169083091643;8310.584470776557;8324.292435143081;8333.791655208668;7819.178986206594;7919.881201782093;7992.280694737501;8022.9197708022175;8045.9597702011115;6749.763758268437;7298.598577078312;7702.383809295355;7832.21677832235;7874.803129921798;7297.452691048299;7614;7640.312371886557;7645.847083058196;7665.923340766744;6724.495662825406;6745.865082698417;6752.561083529751;6777.2883847195335;6780.898286525805;6818.147731533559;6825;6827.761028363983;6837.89743153863;6838.7264509419065 19:55:57 INFO - 2015-10-21 19:55:57,181 DEBUG : BROWSER_OUTPUT: |4;prototype.html;285.98713057912397;297.40815776604563;302.9151837485505;304.3699541948167;305.03912675073514;5894.351621321662;5894.852628684285;5896.469317761401;5901.262342207229;5902.704864756755;5562.9443705562935;5827.300723913137;5845.824625261249;5854.385289544601;5855.385184555625;4256.170046840867;4256.87229383119;4259.297215959363;4260.36094585813;4260.744355338682;4000.479937608107;4147.398627199054;4177.540470548237;4235.788210589458;4243.151369726067;4177.33162693969;4188.141431006637;4190.895227619303;4195.160967806436;4195.28680124378;4091.8363265469347;4193.469383674952;4198.496180458334;4198.790060496963;4200.243956087903;3464.8787292444895;3506.3169678554436;3550.946735798965;3589.5154154189113;3621.27574485104;5690.459406356389;5712.65724056558;5719.170720245583;5722.113072473773;5728.112142617901;4652.930206046912;4820.879478013043;4871.634627402959;4958.00839832035;4966.826161084345;5923.763049478016;6077.544184186204;6085.5435842312;6087.573869829113;6089.969550152221;4017.27689015977;4025.818838152261;4033.6773058155277;4037.9703175690306;4046.817893194813;6185;6287.49700023997;6339.651319177444;6348.650824204667;6355.936440635581;4104.774237416971;4106.67146628269;4108.77401742901;4110.23960567296;4122.793860307032;3956.8219430125714;4044.5348784889525;4099.590040995877;4126.277901367249;4177.791110444466;4908.067467181224;4944.307796908436;4949.257611358325;4954.232094025432;4961.5286547777905;6025.578209525379;6045.546584006129;6058.394160583995;6109.2974307955155;6119.418655227746;1478.7042591481747;1497.857703518164;1510.94988982656;1511.2217208137818;1515.053091817614;953.1610693275719;961.8076384723083;980.7793246519476;994.5325696922434;1001;6021.879562408727;6120.969395152996;6145.84635384119;6146.985747351637;6150;6376.681165941684;6380.138681278061;6396.264429590564;6396.584222025554;6400.775972841021;6121.571489995747;6132.233470816148;6145.0782382642965;6145.20112385396;6150.292716337589;3388.728901687859;3419.059758566414;3434.6565343465454;3453.4647129694936;3489.8255087246043;6751.291114432914;6762.628055456997;6772.390484856288;6789.762358317534;6796.898046529306;6447.967760161169;6462.773802916875;6467.967660161763;6468.579542329733;6469.870602587922;6484.254310754323;6740.561863478956;6743.35938085881;6763.222229443579;6772.762953296611;6155.599886007395;6310;6345.4923606112325;6377.362263773771;6402.071699603624;6080.087986802015;6126.23422072241;6175.166352542349;6247.9062814056915;6269.62382257066;6671.366220209073;6749.628770417674;6750.32496750321;6767.154105736783;6774.42417394527;4155.8337666493;4158.708890377645;4158.875233742992;4161.604647558477;4164.229617520768;6216.316205217513;6379.776707815204;6459.773907913201;6460.612363258219;6477.384648458389;6296.212973378328;6298.937010629835;6302.149209856794;6310.05349197624;6313.463355614824;6169.2288463942;6239.812805615838;6250.593711408744;6251.34360892118;6253.155823963707;5956.642601443928;6053;6098.634081955097;6101.481374083252;6109.961306577804;5871.236739223874;6186;6283.622982621057;6286.497080233479;6293.307736149111;6177.6911154441195;6179.907301390573;6180.3201647817805;6180.443760061616;6181.96909015452 19:55:57 INFO - 2015-10-21 19:55:57,183 DEBUG : BROWSER_OUTPUT: |5;yui.html;700.0584214231857;718.3140101203351;722.6531264992803;729.0522320982722;739.2828955912763;12627.305498197595;12976.935115324422;12979.805302920464;12988.220706757578;12994.28531430771;11769.117316201298;11955;11960.820587691192;11976.940115299432;11995.520179192843;585.5871610514598;593.6289818863211;594.2542109652402;594.8037147741235;594.9524038076966;490.7325507598367;506.5921932844059;582.0774073094151;591.3908674065719;596.3350863786868;594.1503649780811;620.9875802483948;625.1217040058725;625.1466747889144;626.2985456288962;620.3765215957978;620.5128973755603;625.0405627362001;625.43710660406;625.6340041075953;382.0582264717457;387.9612038796126;445.39203986558437;449.29685042908073;449.7503885343633;12207.572734954323;12289.262644241375;12298.385080746017;12302.138850280484;12326.50693972249;11309.604163854308;11437.142214333875;11547.364894930775;11658.511473082839;12183;9991.550380232908;10009.29934904557;10383.636572719919;10700.946495267552;10846.186536009753;3392.1180493071734;3541.698955588778;3652.525171727685;3705.703543716523;3734.5705243897028;44825.32762008572;46789.76605116986;47444.762776186246;47665.04669906617;47879;9663.632410059581;9725.756856078655;9731.610735570497;9813;9965.651202207888;16049.358025678843;16825.495235142964;16923.91538042302;16974.40589579359;17106.914465427843;7277.2722727726805;7331.74338898136;7434.405247580181;7488.2174926824;7631.771046868492;4227.66178705709;4240.151969606091;4249.128928569698;4251.213525497793;4295.334223195409;2919.8540072996266;2928.838913859758;2938.4416960777385;2939.0741916296297;2940.8970686025887;469.6829639993059;492.8743170491502;493.85925011371586;494.29069285575315;495.86363749968325;587.7032098790083;658.7463826426861;670.969806358715;671;696.4428457234193;8130.674773009132;8285.171482851787;8300.204013427898;8404.11756765543;8619.396642234984;570.6889745088937;632.4023797511308;636.9745210191633;687.5393486364148;722.6711846110007;3744.756590821588;3994.141259629194;3995.9400608990886;4076.449679293317;4159.7920103994675;52062;52436.47563524316;52487.737561312715;52588.21117683238;52800;44519.55480445154;47042.76478617585;47219.5278047222;47388.52611473841;47398.28902566464;39314.80342598269;39545.40681889774;42251.57748422476;43206.351904720825;43218.56781432209;8954.194122529003;9216.493092879688;9223.76940576464;9253.213476854542;9647.60283036295;28381.574276385458;29273.121806345844;30546.541801873434;30799.384012320075;30951.380972379975;40482;41351;41391.37912931248;41537.1692566153;41597.58402415937;8007.799805004922;8087.919120808717;8232.50604963704;8255.793605159919;8342.457740246864;13137.408816603467;13356.933215333862;13371.933140334237;13533.729325413633;13885.791713124108;12383.69040773988;12512.43694033789;12723.55467558631;12731.80902286448;12903.935480322538;12050.397480126134;12155;12198.024158067155;12326.630201093982;12808.231506109663;12235.571754988532;12239.32683702405;12343;12363.62909112728;12518.374081295717;10026.498675066363;10222.59109635636;10313.22650801208;10569.365838049742;10741.892581074402;9826.803463930824;10233.48832558384;10360.171186304926;10408.219383546113;10791.838122428013 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,849 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: __startTimestamp1445482557093__endTimestamp 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: Number of tests: 6 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5050.39 Median:5347.41 stddev:719.71 (13.5%) stddev-sans-first:699.63 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: Values: 2785.0 2805.6 2828.6 2842.2 2869.2 4966.0 4979.5 5028.7 5184.0 5196.6 5207.1 5213.0 5218.2 5248.4 5280.4 4464.3 4465.0 4496.3 4556.9 4590.9 4527.7 4540.0 4545.6 4577.4 4605.3 4319.0 4444.9 4523.8 4527.4 4528.0 4550.6 4563.9 4570.8 4618.1 4653.6 4412.1 4489.2 4499.3 4499.3 4548.2 5119.9 5131.5 5197.7 5201.5 5224.7 5455.4 5458.4 5536.1 5552.8 5559.9 5387.2 5410.5 5448.3 5473.8 5479.7 5051.9 5070.4 5143.3 5143.8 5180.6 5559.8 5561.1 5564.7 5654.2 5671.5 5265.3 5300.6 5347.4 5348.4 5378.9 5394.0 5453.8 5522.5 5540.9 5547.4 5397.9 5447.2 5541.3 5545.9 5548.2 5371.9 5388.6 5466.1 5472.2 5474.2 3466.0 3487.0 3493.6 3495.2 3519.9 3157.5 3161.3 3215.4 3216.4 3223.4 5242.6 5261.4 5314.2 5324.5 5348.4 5528.9 5544.8 5581.4 5598.0 5655.2 5334.1 5334.6 5346.3 5440.6 5450.2 4229.6 4316.4 4374.1 4374.7 4389.6 5836.4 5837.2 5936.3 5938.8 5950.1 5494.4 5525.6 5529.7 5642.9 5649.4 5693.9 5694.5 5730.0 5777.9 5860.6 5318.1 5385.0 5473.5 5478.6 5488.3 5396.4 5473.9 5486.2 5618.7 5623.5 5202.2 5314.8 5376.9 5392.9 5485.2 5350.2 5369.1 5423.4 5492.3 5499.8 5149.0 5243.2 5371.0 5393.7 5397.0 5308.3 5381.3 5403.7 5407.1 5486.1 5309.8 5316.7 5382.2 5414.6 5417.7 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:50848.82 Median:34404.57 stddev:96577.40 (280.7%) stddev-sans-first:96774.38 19:55:57 INFO - 2015-10-21 19:55:57,184 DEBUG : BROWSER_OUTPUT: Values: 512.3 512.5 514.1 514.2 530.3 29310.7 31502.4 38880.2 39055.0 39336.0 39659.4 40171.6 40196.6 40217.2 40309.4 7764.9 8126.1 8279.5 8282.8 8286.3 7794.9 10120.2 10314.3 10328.8 10332.7 9992.6 10056.9 10147.6 10154.8 10166.7 8002.2 8155.8 10105.2 10170.8 10221.4 9781.2 9910.5 9973.6 10002.2 10014.5 36871.8 37414.6 37444.3 37458.3 37475.8 3264.9 3332.3 3465.8 3809.6 3909.2 28692.0 28699.7 29419.0 30069.4 30231.8 2653.4 2670.7 2709.9 2761.9 2786.4 335483.0 335578.0 335688.0 336661.0 337709.0 45354.0 46080.0 50992.7 51551.5 51700.0 49253.3 49355.5 49475.8 49498.0 49521.3 14118.8 14154.3 14208.2 14214.0 14255.6 10822.9 10823.5 10837.0 10837.1 10863.8 17082.3 17090.6 17199.1 17208.7 17224.1 7195.3 7390.1 7397.3 7398.6 7424.1 882.9 915.5 964.5 974.3 992.6 27774.9 28295.2 28567.7 28679.4 28920.0 981.7 987.6 989.7 991.9 1035.8 14789.9 14828.5 15051.7 15150.3 15676.8 381557.0 384179.0 384749.0 386708.0 386987.0 47884.5 47899.3 48008.8 48111.5 48164.8 1175.2 1181.3 1189.7 1194.5 1195.2 28436.6 28673.3 28763.0 29222.7 29304.4 41570.2 41665.0 41746.2 41790.8 41837.6 374116.0 375695.0 376373.0 376950.0 377647.0 24290.3 27070.2 27545.9 27563.6 27599.0 37748.8 38712.8 38775.2 38809.2 40463.0 32809.7 34330.7 34478.5 34756.5 35338.1 31622.2 33086.2 34720.0 34881.7 35170.6 33182.9 33226.2 33302.7 33403.5 34956.7 29370.6 30036.4 30069.7 30802.7 32712.7 24897.6 25014.0 25035.0 25521.4 25562.0 19:55:57 INFO - 2015-10-21 19:55:57,186 DEBUG : BROWSER_OUTPUT: 19:55:57 INFO - 2015-10-21 19:55:57,186 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:24398.75 Median:438511.00 stddev:72658.50 (16.6%) stddev-sans-first:72839.78 19:55:57 INFO - 2015-10-21 19:55:57,186 DEBUG : BROWSER_OUTPUT: Values: 294.9 297.4 303.8 306.2 306.7 9740.9 13797.2 16379.3 17173.3 17285.3 10489.2 10769.5 17457.7 17544.1 17766.8 3782.3 4297.3 4298.8 4304.8 4308.0 3650.6 3704.1 4300.5 4597.3 4684.9 4608.8 4628.3 4630.5 4634.3 4635.8 4473.1 4616.3 4620.5 4624.6 4624.9 3846.9 3869.1 3871.4 3900.4 3902.2 12143.8 12424.9 12602.1 12647.7 12770.0 2039.5 2068.2 2068.5 2103.9 2153.2 9192.0 9295.6 9336.9 9348.5 9512.0 1475.3 1541.2 1554.3 1582.9 1639.1 68231.0 68793.7 68925.3 72169.3 75346.0 5787.7 5790.0 5818.8 5850.9 5867.2 6476.4 6653.9 6663.0 6668.0 6669.2 4955.3 4977.6 5020.8 5033.8 5040.9 5499.9 5501.7 5538.0 5546.3 5595.5 2623.8 2629.1 2639.9 2652.1 2655.8 932.0 934.8 938.1 938.7 1002.8 360.9 366.6 406.9 412.3 435.2 10967.7 11422.8 11472.0 11588.5 11683.9 412.3 414.0 419.3 426.3 456.9 5981.1 6062.6 6068.8 6335.4 6352.6 437484.0 438241.0 438781.0 440323.0 443037.0 67022.0 69412.3 70869.0 73098.6 84701.2 455.5 466.2 467.1 467.2 467.5 10388.9 10484.3 10524.4 10553.4 10840.2 41695.4 41778.8 42031.8 42903.4 43366.3 52477.2 52808.5 54517.0 60553.0 61662.7 5714.5 5767.1 5827.9 5855.2 5857.5 11507.8 12063.7 12124.5 12129.5 12162.5 7495.3 9189.6 9901.5 10047.3 10303.4 9687.8 9825.4 9938.9 10031.4 10224.3 10195.0 10312.4 10402.7 10481.3 10490.7 5690.3 5766.2 5782.5 5869.6 5871.9 9605.0 9941.4 10131.8 10203.5 10256.7 19:55:57 INFO - 2015-10-21 19:55:57,187 DEBUG : BROWSER_OUTPUT: 19:55:57 INFO - 2015-10-21 19:55:57,187 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5854.82 Median:4838.04 stddev:3303.51 (68.3%) stddev-sans-first:3294.28 19:55:57 INFO - 2015-10-21 19:55:57,188 DEBUG : BROWSER_OUTPUT: Values: 1202.9 1216.1 1257.0 1442.8 1443.2 7792.6 8355.2 8590.3 8769.1 8789.3 8327.3 8525.3 8528.4 8562.5 8734.9 1974.4 2007.9 2193.0 2200.4 2213.5 2137.0 2197.1 2226.7 2233.0 2245.8 2162.6 2212.9 2218.7 2219.0 2223.7 1968.1 1998.8 2157.9 2167.9 2198.2 2213.2 2229.7 2233.2 2249.5 2255.4 8380.4 8598.7 8610.8 8629.9 8640.4 4783.2 4827.3 4835.7 4836.2 4839.9 3225.0 3294.7 3308.2 3315.9 3317.0 960.6 977.8 1014.1 1023.9 1025.5 11936.2 11939.9 11981.6 11985.6 12040.0 4076.4 4121.8 4141.3 4167.3 4174.9 10051.2 10059.3 10063.4 10065.6 10066.3 4736.0 4744.6 4745.1 4746.4 4748.2 4262.8 4297.6 4299.4 4306.1 4308.9 3434.6 3449.7 3476.9 3496.0 3499.8 1020.5 1030.2 1032.4 1036.3 1038.3 907.5 1106.6 1121.9 1123.4 1125.7 7234.7 7707.2 7981.9 8137.1 8156.1 579.8 594.7 601.1 606.2 606.9 5903.6 5940.9 5947.8 5949.4 5957.9 10650.1 10703.3 10840.3 10870.2 10928.0 10576.2 10594.7 10616.9 10627.1 10683.0 7834.1 7866.8 7877.0 7878.3 7923.0 7343.5 7344.0 7346.7 7549.8 7810.3 9208.9 9233.3 9467.9 9541.6 9546.8 9559.6 9837.9 9925.3 9939.4 9949.9 7772.3 7792.3 7844.6 7865.3 7871.2 8129.8 8309.2 8310.6 8324.3 8333.8 7819.2 7919.9 7992.3 8022.9 8046.0 6749.8 7298.6 7702.4 7832.2 7874.8 7297.5 7614.0 7640.3 7645.8 7665.9 6724.5 6745.9 6752.6 6777.3 6780.9 6818.1 6825.0 6827.8 6837.9 6838.7 19:55:57 INFO - 2015-10-21 19:55:57,190 DEBUG : BROWSER_OUTPUT: 19:55:57 INFO - 2015-10-21 19:55:57,190 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5104.15 Median:6035.56 stddev:1624.79 (26.9%) stddev-sans-first:1588.59 19:55:57 INFO - 2015-10-21 19:55:57,190 DEBUG : BROWSER_OUTPUT: Values: 286.0 297.4 302.9 304.4 305.0 5894.4 5894.9 5896.5 5901.3 5902.7 5562.9 5827.3 5845.8 5854.4 5855.4 4256.2 4256.9 4259.3 4260.4 4260.7 4000.5 4147.4 4177.5 4235.8 4243.2 4177.3 4188.1 4190.9 4195.2 4195.3 4091.8 4193.5 4198.5 4198.8 4200.2 3464.9 3506.3 3550.9 3589.5 3621.3 5690.5 5712.7 5719.2 5722.1 5728.1 4652.9 4820.9 4871.6 4958.0 4966.8 5923.8 6077.5 6085.5 6087.6 6090.0 4017.3 4025.8 4033.7 4038.0 4046.8 6185.0 6287.5 6339.7 6348.7 6355.9 4104.8 4106.7 4108.8 4110.2 4122.8 3956.8 4044.5 4099.6 4126.3 4177.8 4908.1 4944.3 4949.3 4954.2 4961.5 6025.6 6045.5 6058.4 6109.3 6119.4 1478.7 1497.9 1510.9 1511.2 1515.1 953.2 961.8 980.8 994.5 1001.0 6021.9 6121.0 6145.8 6147.0 6150.0 6376.7 6380.1 6396.3 6396.6 6400.8 6121.6 6132.2 6145.1 6145.2 6150.3 3388.7 3419.1 3434.7 3453.5 3489.8 6751.3 6762.6 6772.4 6789.8 6796.9 6448.0 6462.8 6468.0 6468.6 6469.9 6484.3 6740.6 6743.4 6763.2 6772.8 6155.6 6310.0 6345.5 6377.4 6402.1 6080.1 6126.2 6175.2 6247.9 6269.6 6671.4 6749.6 6750.3 6767.2 6774.4 4155.8 4158.7 4158.9 4161.6 4164.2 6216.3 6379.8 6459.8 6460.6 6477.4 6296.2 6298.9 6302.1 6310.1 6313.5 6169.2 6239.8 6250.6 6251.3 6253.2 5956.6 6053.0 6098.6 6101.5 6110.0 5871.2 6186.0 6283.6 6286.5 6293.3 6177.7 6179.9 6180.3 6180.4 6182.0 19:55:57 INFO - 2015-10-21 19:55:57,190 DEBUG : BROWSER_OUTPUT: 19:55:57 INFO - 2015-10-21 19:55:57,190 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13038.12 Median:4250.17 stddev:14595.71 (343.4%) stddev-sans-first:14607.24 19:55:57 INFO - 2015-10-21 19:55:57,190 DEBUG : BROWSER_OUTPUT: Values: 700.1 718.3 722.7 729.1 739.3 12627.3 12976.9 12979.8 12988.2 12994.3 11769.1 11955.0 11960.8 11976.9 11995.5 585.6 593.6 594.3 594.8 595.0 490.7 506.6 582.1 591.4 596.3 594.2 621.0 625.1 625.1 626.3 620.4 620.5 625.0 625.4 625.6 382.1 388.0 445.4 449.3 449.8 12207.6 12289.3 12298.4 12302.1 12326.5 11309.6 11437.1 11547.4 11658.5 12183.0 9991.6 10009.3 10383.6 10700.9 10846.2 3392.1 3541.7 3652.5 3705.7 3734.6 44825.3 46789.8 47444.8 47665.0 47879.0 9663.6 9725.8 9731.6 9813.0 9965.7 16049.4 16825.5 16923.9 16974.4 17106.9 7277.3 7331.7 7434.4 7488.2 7631.8 4227.7 4240.2 4249.1 4251.2 4295.3 2919.9 2928.8 2938.4 2939.1 2940.9 469.7 492.9 493.9 494.3 495.9 587.7 658.7 671.0 671.0 696.4 8130.7 8285.2 8300.2 8404.1 8619.4 570.7 632.4 637.0 687.5 722.7 3744.8 3994.1 3995.9 4076.4 4159.8 52062.0 52436.5 52487.7 52588.2 52800.0 44519.6 47042.8 47219.5 47388.5 47398.3 39314.8 39545.4 42251.6 43206.4 43218.6 8954.2 9216.5 9223.8 9253.2 9647.6 28381.6 29273.1 30546.5 30799.4 30951.4 40482.0 41351.0 41391.4 41537.2 41597.6 8007.8 8087.9 8232.5 8255.8 8342.5 13137.4 13356.9 13371.9 13533.7 13885.8 12383.7 12512.4 12723.6 12731.8 12903.9 12050.4 12155.0 12198.0 12326.6 12808.2 12235.6 12239.3 12343.0 12363.6 12518.4 10026.5 10222.6 10313.2 10569.4 10741.9 9826.8 10233.5 10360.2 10408.2 10791.8 19:55:57 INFO - 2015-10-21 19:55:57,191 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:55:57 INFO - 2015-10-21 19:55:57,191 DEBUG : BROWSER_OUTPUT: 19:55:57 INFO - 2015-10-21 19:55:57,565 INFO : Browser exited with error code: 0 19:55:58 INFO - 2015-10-21 19:55:58,377 INFO : Completed test dromaeo_css (00:18:38) 19:55:58 INFO - 2015-10-21 19:55:58,377 INFO : Starting test kraken 19:55:58 INFO - 2015-10-21 19:55:58,377 DEBUG : operating with platform_type : w7_ 19:55:58 INFO - 2015-10-21 19:55:58,377 INFO : Initialising browser for kraken test... 19:55:58 INFO - 2015-10-21 19:55:58,403 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpmqtr4g\profile http://localhost:49684/getInfo.html 19:56:03 INFO - 2015-10-21 19:56:03,109 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:56:03 INFO - 2015-10-21 19:56:03,109 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:56:03 INFO - 2015-10-21 19:56:03,109 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 19:56:03 INFO - 2015-10-21 19:56:03,109 DEBUG : BROWSER_OUTPUT: __metrics 19:56:03 INFO - 2015-10-21 19:56:03,177 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: console.error: 19:56:03 INFO - 2015-10-21 19:56:03,825 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] 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: Stack: 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 19:56:03 INFO - 2015-10-21 19:56:03,825 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 19:56:03 INFO - 2015-10-21 19:56:03,826 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6419.35 19:57:39 INFO - RETURN: kraken: 1578.70 19:57:39 INFO - RETURN: v8_7: 20481.77 19:57:39 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,201,25]]", "result": "20481.77"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,201,25]]", "result": "1578.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,201,25]]", "result": "6419.35"}}} 19:57:39 INFO - Return code: 0 19:57:39 INFO - # TBPL SUCCESS # 19:57:39 INFO - Running post-action listener: _resource_record_post_action 19:57:39 INFO - Running post-run listener: _resource_record_post_run 19:57:40 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 94415360; Write bytes: 455600128; Read time: 1229670; Write time: 5546410 19:57:40 INFO - install - Wall time: 2s; CPU: 13.0%; Read bytes: 0; Write bytes: 371712; Read time: 0; Write time: 12890 19:57:40 INFO - run-tests - Wall time: 1223s; CPU: 13.0%; Read bytes: 94415360; Write bytes: 432667648; Read time: 1229670; Write time: 5512410 19:57:40 INFO - Running post-run listener: _upload_blobber_files 19:57:40 INFO - Blob upload gear active. 19:57:40 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 19:57:40 INFO - Copying logs to upload dir... 19:57:40 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1426.770000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 23 mins, 47 secs) (at 2015-10-21 19:57:41.058079) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-21 19:57:41.061581) ========= 'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f' in dir C:\slave\test-pgo\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-047 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-047 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-pgo\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-047 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/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.102000 build_url: 'https://queue.taskcluster.net/v1/task/A71G7BveQg2wwUNmbOkkTw/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-10-21 19:57:41.179821) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-21 19:57:41.180194) ========= 'rm' '-f' 'oauth.txt' in dir C:\slave\test-pgo\. (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-047 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-047 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-pgo 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-047 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.100000 ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-21 19:57:41.307336) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-21 19:57:41.307651) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-10-21 19:57:41.831517) =========