builder: fx-team_win7-ix_test-dromaeojs-pgo slave: t-w732-ix-092 starttime: 1445570056.17 results: success (0) buildid: 20151022143005 builduid: 648e4069183c4ba1b22cef6cea117dda revision: 85bd262e7094cfed408b3d36d2f9818355628fd2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-22 20:14:16.174334) ========= master: http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-22 20:14:16.176258) ========= ========= Started set props: basedir (results: 0, elapsed: 20 secs) (at 2015-10-22 20:14:16.176556) ========= '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-092 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-092 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-092 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.540000 basedir: 'C:\\slave\\test-pgo' ========= Finished set props: basedir (results: 0, elapsed: 20 secs) (at 2015-10-22 20:14:36.233450) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 58 secs) (at 2015-10-22 20:14:36.233789) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 58 secs) (at 2015-10-22 20:15:34.597695) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 11 secs) (at 2015-10-22 20:15:34.597978) ========= '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-092 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-092 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-092 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: 11 secs) (at 2015-10-22 20:15:45.863243) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-22 20:15:45.863514) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-22 20:15:45.865264) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2015-10-22 20:15:45.865547) ========= '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-092 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-092 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-092 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 --20:15:47-- 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% 2.27 GB/s 20:15:48 (2.27 GB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.849000 ========= Finished 'bash -c ...' (results: 0, elapsed: 11 secs) (at 2015-10-22 20:15:57.130443) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 26 secs) (at 2015-10-22 20:15:57.130799) ========= '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-092 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-092 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-092 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=7.501000 ========= Finished 'rm -rf ...' (results: 0, elapsed: 26 secs) (at 2015-10-22 20:16:23.181386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 8 secs) (at 2015-10-22 20:16:23.181700) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev 85bd262e7094cfed408b3d36d2f9818355628fd2 --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/fx-team --rev 85bd262e7094cfed408b3d36d2f9818355628fd2 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-092 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-092 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-092 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False 2015-10-22 20:16:24,589 truncating revision to first 12 chars 2015-10-22 20:16:24,589 Setting DEBUG logging. 2015-10-22 20:16:24,589 attempt 1/10 2015-10-22 20:16:24,589 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/85bd262e7094?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-22 20:16:26,632 unpacking tar archive at: fx-team-85bd262e7094/testing/mozharness/ program finished with exit code 0 elapsedTime=3.693000 ========= Finished 'bash -c ...' (results: 0, elapsed: 8 secs) (at 2015-10-22 20:16:31.428463) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 7 secs) (at 2015-10-22 20:16:31.428737) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 7 secs) (at 2015-10-22 20:16:38.719338) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 20:16:38.719588) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-22 20:16:38.726994) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 54 secs) (at 2015-10-22 20:16:38.727390) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'dromaeojs' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team' 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', 'Fx-Team', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: ALLUSERSPROFILE=C:\ProgramData APPDATA=C:\Users\cltbld\AppData\Roaming COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-W732-IX-092 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-092 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-092 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 20:16:39 INFO - MultiFileLogger online at 20151022 20:16:39 in C:\slave\test-pgo 20:16:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 20:16:39 INFO - Dumping config to C:\slave\test-pgo\logs\localconfig.json. 20:16:39 INFO - {'append_to_log': False, 20:16:39 INFO - 'base_work_dir': 'C:\\slave\\test-pgo', 20:16:39 INFO - 'blob_upload_branch': 'Fx-Team', 20:16:39 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test-pgo\\oauth.txt', 20:16:39 INFO - 'branch': 'Fx-Team', 20:16:39 INFO - 'buildbot_json_path': 'buildprops.json', 20:16:39 INFO - 'config_files': ('talos/windows_config.py',), 20:16:39 INFO - 'default_actions': ('clobber', 20:16:39 INFO - 'read-buildbot-config', 20:16:39 INFO - 'download-and-extract', 20:16:39 INFO - 'populate-webroot', 20:16:39 INFO - 'create-virtualenv', 20:16:39 INFO - 'install', 20:16:39 INFO - 'run-tests'), 20:16:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:16:39 INFO - 'download_minidump_stackwalk': True, 20:16:39 INFO - 'download_symbols': 'ondemand', 20:16:39 INFO - 'exes': {'easy_install': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 20:16:39 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/easy_install-2.7-script.py'), 20:16:39 INFO - 'hg': 'c:/mozilla-build/hg/hg', 20:16:39 INFO - 'mozinstall': ('C:\\slave\\test-pgo\\build/venv/scripts/python', 20:16:39 INFO - 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py'), 20:16:39 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 20:16:39 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 20:16:39 INFO - 'C:/mozilla-build/tooltool.py'), 20:16:39 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 20:16:39 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 20:16:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:16:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:16:39 INFO - 'installer_path': 'installer.exe', 20:16:39 INFO - 'log_level': 'info', 20:16:39 INFO - 'log_name': 'talos', 20:16:39 INFO - 'log_to_console': True, 20:16:39 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 20:16:39 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 20:16:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 20:16:39 INFO - 'opt_config_files': (), 20:16:39 INFO - 'pip_index': False, 20:16:39 INFO - 'sps_profile': False, 20:16:39 INFO - 'sps_profile_interval': 0, 20:16:39 INFO - 'suite': 'dromaeojs', 20:16:39 INFO - 'system_bits': '32', 20:16:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:16:39 INFO - 'title': 'T-W732-IX-092', 20:16:39 INFO - 'use_talos_json': True, 20:16:39 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 20:16:39 INFO - 'virtualenv_path': 'C:\\slave\\test-pgo\\build/venv', 20:16:39 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 20:16:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:16:39 INFO - 'work_dir': 'build'} 20:16:39 INFO - ##### 20:16:39 INFO - ##### Running clobber step. 20:16:39 INFO - ##### 20:16:39 INFO - Running pre-action listener: _resource_record_pre_action 20:16:39 INFO - Running main action method: clobber 20:16:39 INFO - rmtree: C:\slave\test-pgo\build 20:16:39 INFO - Using _rmtree_windows ... 20:16:39 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test-pgo\\build',), kwargs: {}, attempt #1 20:17:56 INFO - Running post-action listener: _resource_record_post_action 20:17:56 INFO - ##### 20:17:56 INFO - ##### Running read-buildbot-config step. 20:17:56 INFO - ##### 20:17:56 INFO - Running pre-action listener: _resource_record_pre_action 20:17:56 INFO - Running main action method: read_buildbot_config 20:17:56 INFO - Using buildbot properties: 20:17:56 INFO - { 20:17:56 INFO - "properties": { 20:17:56 INFO - "buildnumber": 335, 20:17:56 INFO - "product": "firefox", 20:17:56 INFO - "script_repo_revision": "production", 20:17:56 INFO - "builddir": "fx-team_win7-ix_test-dromaeojs", 20:17:56 INFO - "repository": "", 20:17:56 INFO - "buildername": "Windows 7 32-bit fx-team pgo talos dromaeojs", 20:17:56 INFO - "buildid": "20151022143005", 20:17:56 INFO - "slavename": "t-w732-ix-092", 20:17:56 INFO - "pgo_build": "True", 20:17:56 INFO - "basedir": "C:\\slave\\test-pgo", 20:17:56 INFO - "project": "", 20:17:56 INFO - "platform": "win7-ix", 20:17:56 INFO - "master": "http://buildbot-master112.bb.releng.scl3.mozilla.com:8201/", 20:17:56 INFO - "slavebuilddir": "test", 20:17:56 INFO - "scheduler": "tests-fx-team-win32-pgo-talos", 20:17:56 INFO - "branch": "fx-team", 20:17:56 INFO - "repo_path": "integration/fx-team", 20:17:56 INFO - "stage_platform": "win32-pgo", 20:17:56 INFO - "builduid": "648e4069183c4ba1b22cef6cea117dda", 20:17:56 INFO - "revision": "85bd262e7094cfed408b3d36d2f9818355628fd2" 20:17:56 INFO - }, 20:17:56 INFO - "sourcestamp": { 20:17:56 INFO - "repository": "", 20:17:56 INFO - "hasPatch": false, 20:17:56 INFO - "project": "", 20:17:56 INFO - "branch": "fx-team-win32-pgo-talos", 20:17:56 INFO - "changes": [ 20:17:56 INFO - { 20:17:56 INFO - "category": null, 20:17:56 INFO - "files": [ 20:17:56 INFO - { 20:17:56 INFO - "url": null, 20:17:56 INFO - "name": "https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip" 20:17:56 INFO - } 20:17:56 INFO - ], 20:17:56 INFO - "repository": "", 20:17:56 INFO - "rev": "85bd262e7094cfed408b3d36d2f9818355628fd2", 20:17:56 INFO - "who": "sendchange", 20:17:56 INFO - "when": 1445567569, 20:17:56 INFO - "number": 6563219, 20:17:56 INFO - "comments": "", 20:17:56 INFO - "project": "", 20:17:56 INFO - "at": "Thu 22 Oct 2015 19:32:49", 20:17:56 INFO - "branch": "fx-team-win32-pgo-talos", 20:17:56 INFO - "revlink": "", 20:17:56 INFO - "properties": [ 20:17:56 INFO - [ 20:17:56 INFO - "buildid", 20:17:56 INFO - "20151022143005", 20:17:56 INFO - "Change" 20:17:56 INFO - ], 20:17:56 INFO - [ 20:17:56 INFO - "builduid", 20:17:56 INFO - "648e4069183c4ba1b22cef6cea117dda", 20:17:56 INFO - "Change" 20:17:56 INFO - ], 20:17:56 INFO - [ 20:17:56 INFO - "pgo_build", 20:17:56 INFO - "True", 20:17:56 INFO - "Change" 20:17:56 INFO - ] 20:17:56 INFO - ], 20:17:56 INFO - "revision": "85bd262e7094cfed408b3d36d2f9818355628fd2" 20:17:57 INFO - } 20:17:57 INFO - ], 20:17:57 INFO - "revision": "85bd262e7094cfed408b3d36d2f9818355628fd2" 20:17:57 INFO - } 20:17:57 INFO - } 20:17:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip. 20:17:57 INFO - Running post-action listener: _resource_record_post_action 20:17:57 INFO - ##### 20:17:57 INFO - ##### Running download-and-extract step. 20:17:57 INFO - ##### 20:17:57 INFO - Running pre-action listener: _resource_record_pre_action 20:17:57 INFO - Running main action method: download_and_extract 20:17:57 INFO - mkdir: C:\slave\test-pgo\build\tests 20:17:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:17:57 INFO - https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 20:17:57 INFO - trying https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/test_packages.json 20:17:57 INFO - Downloading https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/test_packages.json to C:\slave\test-pgo\build\test_packages.json 20:17:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test-pgo\\build\\test_packages.json'}, attempt #1 20:17:58 INFO - Downloaded 1221 bytes. 20:17:58 INFO - Reading from file C:\slave\test-pgo\build\test_packages.json 20:17:58 INFO - Using the following test package requirements: 20:17:58 INFO - {u'common': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 20:17:58 INFO - u'cppunittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:17:58 INFO - u'firefox-44.0a1.en-US.win32.cppunittest.tests.zip'], 20:17:58 INFO - u'jittest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:17:58 INFO - u'jsshell-win32.zip'], 20:17:58 INFO - u'mochitest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:17:58 INFO - u'firefox-44.0a1.en-US.win32.mochitest.tests.zip'], 20:17:58 INFO - u'mozbase': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 20:17:58 INFO - u'reftest': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:17:58 INFO - u'firefox-44.0a1.en-US.win32.reftest.tests.zip'], 20:17:58 INFO - u'talos': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:17:58 INFO - u'firefox-44.0a1.en-US.win32.talos.tests.zip'], 20:17:58 INFO - u'web-platform': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:17:58 INFO - u'firefox-44.0a1.en-US.win32.web-platform.tests.zip'], 20:17:58 INFO - u'webapprt': [u'firefox-44.0a1.en-US.win32.common.tests.zip'], 20:17:58 INFO - u'xpcshell': [u'firefox-44.0a1.en-US.win32.common.tests.zip', 20:17:58 INFO - u'firefox-44.0a1.en-US.win32.xpcshell.tests.zip']} 20:17:58 INFO - Downloading packages: [u'firefox-44.0a1.en-US.win32.common.tests.zip'] for test suite category: common 20:17:58 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')]} 20:17:58 INFO - https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 20:17:58 INFO - trying https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 20:17:58 INFO - Downloading https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/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 20:17:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/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 20:18:00 INFO - Downloaded 18005575 bytes. 20:18:00 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 20:18:00 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 20:18:00 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 20:18:30 INFO - Return code: 0 20:18:30 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 20:18:30 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')]} 20:18:30 INFO - https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 20:18:30 INFO - trying https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.common.tests.zip 20:18:30 INFO - Downloading https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/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 20:18:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/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 20:18:43 INFO - Downloaded 18005575 bytes. 20:18:43 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 20:18:43 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.common.tests.zip 20:18:43 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 20:19:05 INFO - Return code: 0 20:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:05 INFO - https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 20:19:05 INFO - trying https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.talos.tests.zip 20:19:05 INFO - Downloading https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/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 20:19:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/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 20:19:15 INFO - Downloaded 11131307 bytes. 20:19:15 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 20:19:15 INFO - Copy/paste: unzip -q -o C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.talos.tests.zip 20:19:15 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test-pgo\\build\\firefox-44.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 20:19:16 INFO - Return code: 0 20:19: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')]} 20:19:16 INFO - https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip matches https://queue.taskcluster.net 20:19:16 INFO - trying https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 20:19:16 INFO - Downloading https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.zip 20:19:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/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 20:19:37 INFO - Downloaded 53528772 bytes. 20:19:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 20:19:37 INFO - mkdir: C:\slave\test-pgo\properties 20:19:37 INFO - Writing buildbot properties ['build_url'] to C:\slave\test-pgo\properties\build_url 20:19:37 INFO - Writing to file C:\slave\test-pgo\properties\build_url 20:19:37 INFO - Contents: 20:19:37 INFO - build_url:https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip 20:19:37 INFO - Running post-action listener: _resource_record_post_action 20:19:37 INFO - Running post-action listener: set_extra_try_arguments 20:19:37 INFO - ##### 20:19:37 INFO - ##### Running populate-webroot step. 20:19:37 INFO - ##### 20:19:37 INFO - Running pre-action listener: _resource_record_pre_action 20:19:37 INFO - Running main action method: populate_webroot 20:19:37 INFO - Guessing talos json url... 20:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:19:37 INFO - https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.txt matches https://queue.taskcluster.net 20:19:37 INFO - trying https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.txt 20:19:37 INFO - Downloading https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.txt to C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 20:19:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/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 20:19:39 INFO - Downloaded 103 bytes. 20:19:39 INFO - Reading from file C:\slave\test-pgo\build\firefox-44.0a1.en-US.win32.txt 20:19:39 INFO - Contents: 20:19:39 INFO - 20151022143005 20:19:39 INFO - https://hg.mozilla.org/integration/fx-team/rev/85bd262e7094cfed408b3d36d2f9818355628fd2 20:19:39 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')]} 20:19:39 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/85bd262e70/testing/talos/talos.json 20:19:39 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/85bd262e70/testing/talos/talos.json to C:\slave\test-pgo\build\talos.json 20:19:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/85bd262e70/testing/talos/talos.json', 'file_name': 'C:\\slave\\test-pgo\\build\\talos.json'}, attempt #1 20:19:40 INFO - Downloaded 4264 bytes. 20:19:40 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 20:19:40 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 20:19:40 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 20:19:40 INFO - u'../fennec_ids.txt'], 20:19:40 INFO - u'tests': [u'tcheck2']}, 20:19:40 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 20:19:40 INFO - u'--tppagecycles', 20:19:40 INFO - u'7'], 20:19:40 INFO - u'tests': [u'tsvgm']}}, 20:19:40 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 20:19:40 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 20:19:40 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 20:19:40 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 20:19:40 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 20:19:40 INFO - u'tests': [u'dromaeo_css', 20:19:40 INFO - u'kraken', 20:19:40 INFO - u'v8_7']}, 20:19:40 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:40 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 20:19:40 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:40 INFO - u'talos_options': [u'--e10s'], 20:19:40 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 20:19:40 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:40 INFO - u'tests': [u'damp', u'tps']}, 20:19:40 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:40 INFO - u'talos_options': [u'--e10s'], 20:19:40 INFO - u'tests': [u'damp', u'tps']}, 20:19:40 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 20:19:40 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 20:19:40 INFO - u'other': {u'tests': [u'a11yr', 20:19:40 INFO - u'ts_paint', 20:19:40 INFO - u'tpaint', 20:19:40 INFO - u'sessionrestore', 20:19:40 INFO - u'sessionrestore_no_auto_restore']}, 20:19:40 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 20:19:40 INFO - u'tests': [u'a11yr', 20:19:40 INFO - u'ts_paint', 20:19:40 INFO - u'tpaint', 20:19:40 INFO - u'sessionrestore', 20:19:40 INFO - u'sessionrestore_no_auto_restore']}, 20:19:40 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 20:19:40 INFO - u'tests': [u'a11yr', 20:19:40 INFO - u'ts_paint', 20:19:40 INFO - u'tpaint', 20:19:40 INFO - u'sessionrestore', 20:19:40 INFO - u'sessionrestore_no_auto_restore']}, 20:19:40 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 20:19:40 INFO - u'tests': [u'a11yr', 20:19:40 INFO - u'ts_paint', 20:19:40 INFO - u'tpaint', 20:19:40 INFO - u'sessionrestore', 20:19:40 INFO - u'sessionrestore_no_auto_restore']}, 20:19:40 INFO - u'other_l64': {u'tests': [u'a11yr', 20:19:40 INFO - u'ts_paint', 20:19:40 INFO - u'tpaint', 20:19:40 INFO - u'sessionrestore', 20:19:40 INFO - u'sessionrestore_no_auto_restore']}, 20:19:40 INFO - u'other_nol64': {u'tests': [u'a11yr', 20:19:40 INFO - u'ts_paint', 20:19:40 INFO - u'tpaint', 20:19:40 INFO - u'sessionrestore', 20:19:40 INFO - u'sessionrestore_no_auto_restore']}, 20:19:40 INFO - u'svgr': {u'tests': [u'tsvgx', 20:19:40 INFO - u'tsvgr_opacity', 20:19:40 INFO - u'tart', 20:19:40 INFO - u'tscrollx', 20:19:40 INFO - u'cart']}, 20:19:40 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 20:19:40 INFO - u'tests': [u'tsvgx', 20:19:40 INFO - u'tsvgr_opacity', 20:19:40 INFO - u'tart', 20:19:40 INFO - u'tscrollx', 20:19:40 INFO - u'cart']}, 20:19:40 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:40 INFO - u'tests': [u'tp5o']}, 20:19:40 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:40 INFO - u'talos_options': [u'--e10s'], 20:19:40 INFO - u'tests': [u'tp5o']}, 20:19:40 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:40 INFO - u'talos_options': [u'--xperf_path', 20:19:40 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:19:40 INFO - u'tests': [u'tp5n']}, 20:19:40 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:19:40 INFO - u'talos_options': [u'--e10s', 20:19:40 INFO - u'--xperf_path', 20:19:40 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 20:19:40 INFO - u'C:/slave/talos-data/talos/xperf.config'], 20:19:40 INFO - u'tests': [u'tp5n']}}, 20:19:40 INFO - u'talos.zip': {u'path': u'', 20:19:40 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:19:40 INFO - Running post-action listener: _resource_record_post_action 20:19:40 INFO - ##### 20:19:40 INFO - ##### Running create-virtualenv step. 20:19:40 INFO - ##### 20:19:40 INFO - Running pre-action listener: _resource_record_pre_action 20:19:40 INFO - Running main action method: create_virtualenv 20:19:40 INFO - Creating virtualenv C:\slave\test-pgo\build/venv 20:19:40 INFO - mkdir: C:\slave\test-pgo\build\venv\Scripts 20:19:40 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test-pgo\build\venv\Scripts\python27.dll 20:19:40 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'! 20:19:40 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 20:19:40 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 20:19:41 INFO - New python executable in C:\slave\test-pgo\build/venv\Scripts\python.exe 20:19:43 INFO - Installing distribute......................................................................................................................................................................................done. 20:19:44 INFO - Return code: 0 20:19:44 INFO - Installing pip>=1.5 into virtualenv C:\slave\test-pgo\build/venv 20:19:44 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')]} 20:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:44 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')]} 20:19:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:44 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 0x018F8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0130E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01979660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 20:19:44 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 20:19:44 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 20:19:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:44 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:44 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 20:19:44 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:44 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:44 INFO - 'DCLOCATION': 'SCL3', 20:19:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:44 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:44 INFO - 'HOMEDRIVE': 'C:', 20:19:44 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:44 INFO - 'KTS_VERSION': '1.19c', 20:19:44 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:44 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 20:19:44 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:44 INFO - 'MOZ_NO_REMOTE': '1', 20:19:44 INFO - 'NO_EM_RESTART': '1', 20:19:44 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:44 INFO - 'OS': 'Windows_NT', 20:19:44 INFO - 'OURDRIVE': 'C:', 20:19:44 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;', 20:19:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:44 INFO - 'PROCESSOR_LEVEL': '6', 20:19:44 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:44 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:44 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:44 INFO - 'PROMPT': '$P$G', 20:19:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:19:44 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:44 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:44 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:19:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:44 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:44 INFO - 'SYSTEMDRIVE': 'C:', 20:19:44 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:44 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:44 INFO - 'TEST1': 'testie', 20:19:44 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:44 INFO - 'USERDOMAIN': 'T-W732-IX-092', 20:19:44 INFO - 'USERNAME': 'cltbld', 20:19:44 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:44 INFO - 'WINDIR': 'C:\\windows', 20:19:44 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:44 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:47 INFO - Ignoring indexes: http://pypi.python.org/simple/ 20:19:47 INFO - Downloading/unpacking pip>=1.5 20:19:47 INFO - Creating supposed download cache at C:\slave\test-pgo\build/venv\cache 20:19:53 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 20:19:53 INFO - Running setup.py egg_info for package pip 20:19:53 INFO - warning: no files found matching 'pip\cacert.pem' 20:19:53 INFO - warning: no files found matching '*.html' under directory 'docs' 20:19:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 20:19:53 INFO - no previously-included directories found matching 'docs\_build\_sources' 20:19:53 INFO - Installing collected packages: pip 20:19:53 INFO - Found existing installation: pip 0.8.2 20:19:53 INFO - Uninstalling pip: 20:19:53 INFO - Successfully uninstalled pip 20:19:53 INFO - Running setup.py install for pip 20:19:53 INFO - warning: no files found matching 'pip\cacert.pem' 20:19:53 INFO - warning: no files found matching '*.html' under directory 'docs' 20:19:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 20:19:53 INFO - no previously-included directories found matching 'docs\_build\_sources' 20:19:53 INFO - Installing pip-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:53 INFO - Installing pip.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:53 INFO - Installing pip2.7-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:53 INFO - Installing pip2.7.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:53 INFO - Installing pip2-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:19:53 INFO - Installing pip2.exe script to C:\slave\test-pgo\build\venv\Scripts 20:19:53 INFO - Successfully installed pip 20:19:53 INFO - Cleaning up... 20:19:53 INFO - Return code: 0 20:19:53 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test-pgo\build/venv 20:19:53 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')]} 20:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:53 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')]} 20:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:53 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 0x018F8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0130E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01979660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 20:19:53 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 20:19:53 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 20:19:53 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:53 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:53 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:53 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 20:19:53 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:53 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:53 INFO - 'DCLOCATION': 'SCL3', 20:19:53 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:53 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:53 INFO - 'HOMEDRIVE': 'C:', 20:19:53 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:53 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:53 INFO - 'KTS_VERSION': '1.19c', 20:19:53 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:53 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 20:19:53 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:53 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:53 INFO - 'MOZ_NO_REMOTE': '1', 20:19:53 INFO - 'NO_EM_RESTART': '1', 20:19:53 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:53 INFO - 'OS': 'Windows_NT', 20:19:53 INFO - 'OURDRIVE': 'C:', 20:19:53 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;', 20:19:53 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:53 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:53 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:53 INFO - 'PROCESSOR_LEVEL': '6', 20:19:53 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:53 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:53 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:53 INFO - 'PROMPT': '$P$G', 20:19:53 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:19:53 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:53 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:53 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:19:53 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:53 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:53 INFO - 'SYSTEMDRIVE': 'C:', 20:19:53 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:53 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:53 INFO - 'TEST1': 'testie', 20:19:53 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:53 INFO - 'USERDOMAIN': 'T-W732-IX-092', 20:19:53 INFO - 'USERNAME': 'cltbld', 20:19:53 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:53 INFO - 'WINDIR': 'C:\\windows', 20:19:53 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:53 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:55 INFO - Downloading/unpacking psutil>=0.7.1 20:19:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:19:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:19:58 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 20:19:58 INFO - Installing collected packages: psutil 20:19:58 INFO - Successfully installed psutil 20:19:58 INFO - Cleaning up... 20:19:58 INFO - Return code: 0 20:19:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test-pgo\build/venv 20:19:58 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')]} 20:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:19:58 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')]} 20:19:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:19:58 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 0x018F8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0130E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01979660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 20:19:58 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 20:19:58 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 20:19:58 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:19:58 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:19:58 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:19:58 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 20:19:58 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:19:58 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:19:58 INFO - 'DCLOCATION': 'SCL3', 20:19:58 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:19:58 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:19:58 INFO - 'HOMEDRIVE': 'C:', 20:19:58 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:19:58 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:19:58 INFO - 'KTS_VERSION': '1.19c', 20:19:58 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:19:58 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 20:19:58 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:19:58 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:19:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:19:58 INFO - 'MOZ_NO_REMOTE': '1', 20:19:58 INFO - 'NO_EM_RESTART': '1', 20:19:58 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:19:58 INFO - 'OS': 'Windows_NT', 20:19:58 INFO - 'OURDRIVE': 'C:', 20:19:58 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;', 20:19:58 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:19:58 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:19:58 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:19:58 INFO - 'PROCESSOR_LEVEL': '6', 20:19:58 INFO - 'PROCESSOR_REVISION': '1e05', 20:19:58 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:19:58 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:19:58 INFO - 'PROMPT': '$P$G', 20:19:58 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:19:58 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:19:58 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:19:58 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:19:58 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:19:58 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:19:58 INFO - 'SYSTEMDRIVE': 'C:', 20:19:58 INFO - 'SYSTEMROOT': 'C:\\windows', 20:19:58 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:58 INFO - 'TEST1': 'testie', 20:19:58 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:19:58 INFO - 'USERDOMAIN': 'T-W732-IX-092', 20:19:58 INFO - 'USERNAME': 'cltbld', 20:19:58 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:19:58 INFO - 'WINDIR': 'C:\\windows', 20:19:58 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:19:58 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:19:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:19:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:19:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:19:58 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 20:19:58 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 20:19:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:19:59 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 20:19:59 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 20:19:59 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) 20:19:59 INFO - Installing collected packages: mozsystemmonitor 20:19:59 INFO - Running setup.py install for mozsystemmonitor 20:19:59 INFO - Successfully installed mozsystemmonitor 20:19:59 INFO - Cleaning up... 20:20:00 INFO - Return code: 0 20:20:00 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test-pgo\build/venv 20:20:00 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')]} 20:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:00 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')]} 20:20:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:00 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 0x018F8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0130E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01979660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 20:20:00 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 20:20:00 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 20:20:00 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:20:00 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:20:00 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:20:00 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 20:20:00 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:20:00 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:20:00 INFO - 'DCLOCATION': 'SCL3', 20:20:00 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:20:00 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:20:00 INFO - 'HOMEDRIVE': 'C:', 20:20:00 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:20:00 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:20:00 INFO - 'KTS_VERSION': '1.19c', 20:20:00 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:20:00 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 20:20:00 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:20:00 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:00 INFO - 'MOZ_NO_REMOTE': '1', 20:20:00 INFO - 'NO_EM_RESTART': '1', 20:20:00 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:20:00 INFO - 'OS': 'Windows_NT', 20:20:00 INFO - 'OURDRIVE': 'C:', 20:20:00 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:20:00 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:20:00 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:20:00 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:20:00 INFO - 'PROCESSOR_LEVEL': '6', 20:20:00 INFO - 'PROCESSOR_REVISION': '1e05', 20:20:00 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:20:00 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:20:00 INFO - 'PROMPT': '$P$G', 20:20:00 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:20:00 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:20:00 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:20:00 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:20:00 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:20:00 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:20:00 INFO - 'SYSTEMDRIVE': 'C:', 20:20:00 INFO - 'SYSTEMROOT': 'C:\\windows', 20:20:00 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:20:00 INFO - 'TEST1': 'testie', 20:20:00 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:20:00 INFO - 'USERDOMAIN': 'T-W732-IX-092', 20:20:00 INFO - 'USERNAME': 'cltbld', 20:20:00 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:20:00 INFO - 'WINDIR': 'C:\\windows', 20:20:00 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:20:00 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:20:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:00 INFO - Downloading/unpacking blobuploader==1.2.4 20:20:00 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 20:20:00 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 20:20:03 INFO - Downloading blobuploader-1.2.4.tar.gz 20:20:03 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 20:20:03 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 20:20:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:20:03 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 20:20:03 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 20:20:04 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 20:20:04 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\requests\setup.py) egg_info for package requests 20:20:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:20:04 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 20:20:04 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 20:20:05 INFO - Downloading docopt-0.6.1.tar.gz 20:20:05 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 20:20:05 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\docopt\setup.py) egg_info for package docopt 20:20:05 INFO - Installing collected packages: blobuploader, requests, docopt 20:20:05 INFO - Running setup.py install for blobuploader 20:20:05 INFO - Running setup.py install for requests 20:20:06 INFO - Running setup.py install for docopt 20:20:06 INFO - Successfully installed blobuploader requests docopt 20:20:06 INFO - Cleaning up... 20:20:07 INFO - Return code: 0 20:20:07 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 20:20: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')]} 20:20:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20: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')]} 20:20:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:07 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 0x018F8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0130E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01979660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 20:20:07 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 20:20:07 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 20:20:07 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:20:07 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:20:07 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:20:07 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 20:20:07 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:20:07 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:20:07 INFO - 'DCLOCATION': 'SCL3', 20:20:07 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:20:07 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:20:07 INFO - 'HOMEDRIVE': 'C:', 20:20:07 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:20:07 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:20:07 INFO - 'KTS_VERSION': '1.19c', 20:20:07 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:20:07 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 20:20:07 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:20:07 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:20:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:07 INFO - 'MOZ_NO_REMOTE': '1', 20:20:07 INFO - 'NO_EM_RESTART': '1', 20:20:07 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:20:07 INFO - 'OS': 'Windows_NT', 20:20:07 INFO - 'OURDRIVE': 'C:', 20:20: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;', 20:20:07 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:20:07 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:20:07 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:20:07 INFO - 'PROCESSOR_LEVEL': '6', 20:20:07 INFO - 'PROCESSOR_REVISION': '1e05', 20:20:07 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:20:07 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:20:07 INFO - 'PROMPT': '$P$G', 20:20:07 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:20:07 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:20:07 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:20:07 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:20:07 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:20:07 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:20:07 INFO - 'SYSTEMDRIVE': 'C:', 20:20:07 INFO - 'SYSTEMROOT': 'C:\\windows', 20:20:07 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:20:07 INFO - 'TEST1': 'testie', 20:20:07 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:20:07 INFO - 'USERDOMAIN': 'T-W732-IX-092', 20:20:07 INFO - 'USERNAME': 'cltbld', 20:20:07 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:20:07 INFO - 'WINDIR': 'C:\\windows', 20:20:07 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:20:07 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:20:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:20:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:07 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 20:20:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9kiet7-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 20:20:07 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 20:20:07 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6x7hxm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 20:20:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 20:20:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-yydmot-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 20:20:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 20:20:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-3cecuy-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 20:20:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 20:20:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-hpurfo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 20:20:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 20:20:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dgwczh-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 20:20:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 20:20:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ya4yb3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 20:20:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 20:20:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-trhmsx-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 20:20:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 20:20:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kvifmo-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 20:20:08 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 20:20:08 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-6nszb9-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 20:20:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 20:20:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wwnb7y-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 20:20:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 20:20:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-qrgeu2-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 20:20:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 20:20:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-eues4y-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 20:20:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 20:20:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jc7ct3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 20:20:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 20:20:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-os_lqt-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:20:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 20:20:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kxganl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 20:20:09 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 20:20:09 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9r4khz-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 20:20:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:20:10 INFO - Running setup.py install for manifestparser 20:20:10 INFO - Installing manifestparser-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:10 INFO - Installing manifestparser.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:10 INFO - Running setup.py install for mozcrash 20:20:10 INFO - Running setup.py install for mozdebug 20:20:11 INFO - Running setup.py install for mozdevice 20:20:11 INFO - Installing sutini-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:11 INFO - Installing sutini.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:11 INFO - Installing dm-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:11 INFO - Installing dm.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:11 INFO - Running setup.py install for mozfile 20:20:12 INFO - Running setup.py install for mozhttpd 20:20:12 INFO - Installing mozhttpd-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:12 INFO - Installing mozhttpd.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:12 INFO - Running setup.py install for mozinfo 20:20:12 INFO - Installing mozinfo-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:12 INFO - Installing mozinfo.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:13 INFO - Running setup.py install for mozInstall 20:20:13 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:13 INFO - Installing moz_remove_from_system.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:13 INFO - Installing mozuninstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:13 INFO - Installing mozuninstall.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:13 INFO - Installing mozinstall-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:13 INFO - Installing mozinstall.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:13 INFO - Installing moz_add_to_system-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:13 INFO - Installing moz_add_to_system.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:13 INFO - Running setup.py install for mozleak 20:20:13 INFO - Running setup.py install for mozlog 20:20:14 INFO - Installing structlog-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:14 INFO - Installing structlog.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:14 INFO - Running setup.py install for moznetwork 20:20:14 INFO - Installing moznetwork-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:14 INFO - Installing moznetwork.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:14 INFO - Running setup.py install for mozprocess 20:20:15 INFO - Running setup.py install for mozprofile 20:20:15 INFO - Installing mozprofile-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:15 INFO - Installing mozprofile.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:15 INFO - Installing diff-profiles-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:15 INFO - Installing diff-profiles.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:15 INFO - Installing view-profile-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:15 INFO - Installing view-profile.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:16 INFO - Running setup.py install for mozrunner 20:20:16 INFO - Installing mozrunner-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:16 INFO - Installing mozrunner.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:16 INFO - Running setup.py install for mozscreenshot 20:20:17 INFO - Running setup.py install for moztest 20:20:17 INFO - Running setup.py install for mozversion 20:20:17 INFO - Installing mozversion-script.py script to C:\slave\test-pgo\build\venv\Scripts 20:20:17 INFO - Installing mozversion.exe script to C:\slave\test-pgo\build\venv\Scripts 20:20:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:20:18 INFO - Cleaning up... 20:20:18 INFO - Return code: 0 20:20:18 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 20:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:18 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 0x018F8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0130E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01979660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\config', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 20:20:18 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 20:20:18 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 20:20:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:20:18 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:20:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:20:18 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 20:20:18 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:20:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:20:18 INFO - 'DCLOCATION': 'SCL3', 20:20:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:20:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:20:18 INFO - 'HOMEDRIVE': 'C:', 20:20:18 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:20:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:20:18 INFO - 'KTS_VERSION': '1.19c', 20:20:18 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:20:18 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 20:20:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:20:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:20:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:18 INFO - 'MOZ_NO_REMOTE': '1', 20:20:18 INFO - 'NO_EM_RESTART': '1', 20:20:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:20:18 INFO - 'OS': 'Windows_NT', 20:20:18 INFO - 'OURDRIVE': 'C:', 20:20:18 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;', 20:20:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:20:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:20:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:20:18 INFO - 'PROCESSOR_LEVEL': '6', 20:20:18 INFO - 'PROCESSOR_REVISION': '1e05', 20:20:18 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:20:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:20:18 INFO - 'PROMPT': '$P$G', 20:20:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:20:18 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:20:18 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:20:18 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:20:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:20:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:20:18 INFO - 'SYSTEMDRIVE': 'C:', 20:20:18 INFO - 'SYSTEMROOT': 'C:\\windows', 20:20:18 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:20:18 INFO - 'TEST1': 'testie', 20:20:18 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:20:18 INFO - 'USERDOMAIN': 'T-W732-IX-092', 20:20:18 INFO - 'USERNAME': 'cltbld', 20:20:18 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:20:18 INFO - 'WINDIR': 'C:\\windows', 20:20:18 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:20:18 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:20:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:20:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\manifestparser 20:20:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-ijbmbp-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/manifestparser 20:20:18 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)) 20:20:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozcrash 20:20:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-8qpe6l-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozcrash 20:20:18 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)) 20:20:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdebug 20:20:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-42t215-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdebug 20:20:18 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)) 20:20:18 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozdevice 20:20:18 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-gvkkoc-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozdevice 20:20:19 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)) 20:20:19 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozfile 20:20:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-xbsmhq-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozfile 20:20:19 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)) 20:20:19 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozhttpd 20:20:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wdpfms-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozhttpd 20:20:19 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)) 20:20:19 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinfo 20:20:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jg1p7z-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinfo 20:20:19 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)) 20:20:19 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozinstall 20:20:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-5cyqkm-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozinstall 20:20:19 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)) 20:20:19 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozleak 20:20:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-jukid3-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozleak 20:20:19 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)) 20:20:19 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozlog 20:20:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dbosmf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozlog 20:20:19 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)) 20:20:19 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moznetwork 20:20:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-w6wcsf-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moznetwork 20:20:19 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)) 20:20:19 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprocess 20:20:19 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-fzdlxw-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprocess 20:20:20 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)) 20:20:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozprofile 20:20:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-9vdnqb-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozprofile 20:20:20 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)) 20:20:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozrunner 20:20:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-dup3w6-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozrunner 20:20:20 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)) 20:20:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozscreenshot 20:20:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-wbyepe-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:20:20 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)) 20:20:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\moztest 20:20:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-pdnj6b-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/moztest 20:20:20 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)) 20:20:20 INFO - Unpacking c:\slave\test-pgo\build\tests\mozbase\mozversion 20:20:20 INFO - Running setup.py (path:c:\users\cltbld\appdata\local\temp\pip-kvjykl-build\setup.py) egg_info for package from file:///C:/slave/test-pgo/build/tests/mozbase/mozversion 20:20:20 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)) 20:20:20 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)) 20:20:20 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)) 20:20:20 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)) 20:20:20 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)) 20:20:20 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)) 20:20:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test-pgo\build\tests\config\mozbase_requirements.txt (line 10)) 20:20:20 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 20:20:20 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 20:20:22 INFO - Downloading blessings-1.5.1.tar.gz 20:20:22 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 20:20:22 INFO - Running setup.py (path:C:\slave\test-pgo\build\venv\build\blessings\setup.py) egg_info for package blessings 20:20:22 INFO - Installing collected packages: blessings 20:20:22 INFO - Running setup.py install for blessings 20:20:23 INFO - Successfully installed blessings 20:20:23 INFO - Cleaning up... 20:20:25 INFO - Return code: 0 20:20:25 INFO - Done creating virtualenv C:\slave\test-pgo\build/venv. 20:20:25 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 20:20:25 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 20:20:26 INFO - Reading from file tmpfile_stdout 20:20:26 INFO - Using _rmtree_windows ... 20:20:26 INFO - Using _rmtree_windows ... 20:20:26 INFO - Current package versions: 20:20:26 INFO - blessings == 1.5.1 20:20:26 INFO - blobuploader == 1.2.4 20:20:26 INFO - docopt == 0.6.1 20:20:26 INFO - manifestparser == 1.1 20:20:26 INFO - mozInstall == 1.12 20:20:26 INFO - mozcrash == 0.16 20:20:26 INFO - mozdebug == 0.1 20:20:26 INFO - mozdevice == 0.46 20:20:26 INFO - mozfile == 1.2 20:20:26 INFO - mozhttpd == 0.7 20:20:26 INFO - mozinfo == 0.8 20:20:26 INFO - mozleak == 0.1 20:20:26 INFO - mozlog == 3.0 20:20:26 INFO - moznetwork == 0.27 20:20:26 INFO - mozprocess == 0.22 20:20:26 INFO - mozprofile == 0.27 20:20:26 INFO - mozrunner == 6.10 20:20:26 INFO - mozscreenshot == 0.1 20:20:26 INFO - mozsystemmonitor == 0.0 20:20:26 INFO - moztest == 0.7 20:20:26 INFO - mozversion == 1.4 20:20:26 INFO - psutil == 3.1.1 20:20:26 INFO - requests == 1.2.3 20:20:26 INFO - Installing None into virtualenv C:\slave\test-pgo\build/venv 20:20:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:20:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:20:26 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 0x018F8B10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x018B8EA8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x0130E8C8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x01228B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01979660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test-pgo\\build\\tests\\talos', 'env': {'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 'TEST1': 'testie', 'COMPUTERNAME': 'T-W732-IX-092', 'USERDOMAIN': 'T-W732-IX-092', '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-092', '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 20:20:26 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 20:20:26 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 20:20:26 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:20:26 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:20:26 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:20:26 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 20:20:26 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:20:26 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:20:26 INFO - 'DCLOCATION': 'SCL3', 20:20:26 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:20:26 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:20:26 INFO - 'HOMEDRIVE': 'C:', 20:20:26 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:20:26 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:20:26 INFO - 'KTS_VERSION': '1.19c', 20:20:26 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:20:26 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 20:20:26 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:20:26 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:20:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:26 INFO - 'MOZ_NO_REMOTE': '1', 20:20:26 INFO - 'NO_EM_RESTART': '1', 20:20:26 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:20:26 INFO - 'OS': 'Windows_NT', 20:20:26 INFO - 'OURDRIVE': 'C:', 20:20:26 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:20:26 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:20:26 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:20:26 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:20:26 INFO - 'PROCESSOR_LEVEL': '6', 20:20:26 INFO - 'PROCESSOR_REVISION': '1e05', 20:20:26 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:20:26 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:20:26 INFO - 'PROMPT': '$P$G', 20:20:26 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:20:26 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:20:26 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:20:26 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:20:26 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:20:26 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:20:26 INFO - 'SYSTEMDRIVE': 'C:', 20:20:26 INFO - 'SYSTEMROOT': 'C:\\windows', 20:20:26 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:20:26 INFO - 'TEST1': 'testie', 20:20:26 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:20:26 INFO - 'USERDOMAIN': 'T-W732-IX-092', 20:20:26 INFO - 'USERNAME': 'cltbld', 20:20:26 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:20:26 INFO - 'WINDIR': 'C:\\windows', 20:20:26 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:20:26 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:20:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:20:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:20:26 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)) 20:20:26 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)) 20:20:26 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)) 20:20:26 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)) 20:20:26 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)) 20:20:26 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)) 20:20:26 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)) 20:20:26 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)) 20:20:26 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)) 20:20:26 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)) 20:20:26 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)) 20:20:26 INFO - Cleaning up... 20:20:26 INFO - Return code: 0 20:20:26 INFO - Running post-action listener: _resource_record_post_action 20:20:26 INFO - Running post-action listener: _start_resource_monitoring 20:20:26 INFO - Starting resource monitoring. 20:20:26 INFO - ##### 20:20:26 INFO - ##### Running install step. 20:20:26 INFO - ##### 20:20:26 INFO - Running pre-action listener: _resource_record_pre_action 20:20:26 INFO - Running main action method: install 20:20:26 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\pip', 'freeze'] 20:20:26 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\pip freeze 20:20:27 INFO - Reading from file tmpfile_stdout 20:20:27 INFO - Using _rmtree_windows ... 20:20:27 INFO - Using _rmtree_windows ... 20:20:27 INFO - Detecting whether we're running mozinstall >=1.0... 20:20:27 INFO - Getting output from command: ['C:\\slave\\test-pgo\\build/venv/scripts/python', 'C:\\slave\\test-pgo\\build/venv/scripts/mozinstall-script.py', '-h'] 20:20:27 INFO - Copy/paste: C:\slave\test-pgo\build/venv/scripts/python C:\slave\test-pgo\build/venv/scripts/mozinstall-script.py -h 20:20:27 INFO - Reading from file tmpfile_stdout 20:20:27 INFO - Output received: 20:20:27 INFO - Usage: mozinstall-script.py [options] installer 20:20:27 INFO - Options: 20:20:27 INFO - -h, --help show this help message and exit 20:20:27 INFO - -d DEST, --destination=DEST 20:20:27 INFO - Directory to install application into. [default: 20:20:27 INFO - "C:\slave\test-pgo"] 20:20:27 INFO - --app=APP Application being installed. [default: firefox] 20:20:27 INFO - Using _rmtree_windows ... 20:20:27 INFO - Using _rmtree_windows ... 20:20:27 INFO - mkdir: C:\slave\test-pgo\build\application 20:20:27 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'] 20:20:27 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 20:20:29 INFO - Reading from file tmpfile_stdout 20:20:29 INFO - Output received: 20:20:29 INFO - C:\slave\test-pgo\build\application\firefox\firefox.exe 20:20:29 INFO - Using _rmtree_windows ... 20:20:29 INFO - Using _rmtree_windows ... 20:20:29 INFO - Running post-action listener: _resource_record_post_action 20:20:29 INFO - ##### 20:20:29 INFO - ##### Running run-tests step. 20:20:29 INFO - ##### 20:20:29 INFO - Running pre-action listener: _resource_record_pre_action 20:20:29 INFO - Running main action method: run_tests 20:20:29 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', '--version'] 20:20:29 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python --version 20:20:29 INFO - Python 2.7.3 20:20:29 INFO - Return code: 0 20:20:29 INFO - grabbing minidump binary from tooltool 20:20:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:20:29 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 0x01228B48>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x0124A700>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x01979660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test-pgo\\build', 'privileged': False}, attempt #1 20:20:29 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 20:20:29 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 20:20:29 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 20:20:32 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test-pgo\build\tmpe4bd1r 20:20:32 INFO - INFO - File integrity verified, renaming tmpe4bd1r to win32-minidump_stackwalk.exe 20:20:32 INFO - Return code: 0 20:20:32 INFO - Chmoding C:\slave\test-pgo\build\win32-minidump_stackwalk.exe to 0755 20:20:32 INFO - mkdir: C:\slave\test-pgo\build\blobber_upload_dir 20:20:32 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test-pgo\build\blobber_upload_dir 20:20:32 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test-pgo\build\win32-minidump_stackwalk.exe 20:20:32 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test-pgo\build\blobber_upload_dir 20:20:32 INFO - Running command: ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-092', '--webServer', 'localhost'] in C:\slave\test-pgo\build 20:20:32 INFO - Copy/paste: C:\slave\test-pgo\build\venv\Scripts\python C:\slave\test-pgo\build\tests\talos\talos\run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath C:\slave\test-pgo\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip --title T-W732-IX-092 --webServer localhost 20:20:32 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\ProgramData', 20:20:32 INFO - 'APPDATA': 'C:\\Users\\cltbld\\AppData\\Roaming', 20:20:32 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 20:20:32 INFO - 'COMPUTERNAME': 'T-W732-IX-092', 20:20:32 INFO - 'COMSPEC': 'C:\\windows\\system32\\cmd.exe', 20:20:32 INFO - 'CYGWINBASE': 'C:\\cygwin', 20:20:32 INFO - 'DCLOCATION': 'SCL3', 20:20:32 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 20:20:32 INFO - 'FP_NO_HOST_CHECK': 'NO', 20:20:32 INFO - 'HOMEDRIVE': 'C:', 20:20:32 INFO - 'HOMEPATH': '\\Users\\cltbld', 20:20:32 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 20:20:32 INFO - 'KTS_VERSION': '1.19c', 20:20:32 INFO - 'LOCALAPPDATA': 'C:\\Users\\cltbld\\AppData\\Local', 20:20:32 INFO - 'LOGONSERVER': '\\\\T-W732-IX-092', 20:20:32 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 20:20:32 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test-pgo\\build\\win32-minidump_stackwalk.exe', 20:20:32 INFO - 'MONDIR': 'C:\\Monitor_config\\', 20:20:32 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 20:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:20:32 INFO - 'MOZ_NO_REMOTE': '1', 20:20:32 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test-pgo\\build\\blobber_upload_dir', 20:20:32 INFO - 'NO_EM_RESTART': '1', 20:20:32 INFO - 'NUMBER_OF_PROCESSORS': '8', 20:20:32 INFO - 'OS': 'Windows_NT', 20:20:32 INFO - 'OURDRIVE': 'C:', 20:20:32 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 20:20:32 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC', 20:20:32 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 20:20:32 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 20:20:32 INFO - 'PROCESSOR_LEVEL': '6', 20:20:32 INFO - 'PROCESSOR_REVISION': '1e05', 20:20:32 INFO - 'PROGRAMDATA': 'C:\\ProgramData', 20:20:32 INFO - 'PROGRAMFILES': 'C:\\Program Files', 20:20:32 INFO - 'PROMPT': '$P$G', 20:20:32 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test-pgo/buildprops.json', 20:20:32 INFO - 'PSMODULEPATH': 'C:\\windows\\system32\\WindowsPowerShell\\v1.0\\Modules\\', 20:20:32 INFO - 'PUBLIC': 'C:\\Users\\Public', 20:20:32 INFO - 'PWD': 'C:\\slave\\test-pgo', 20:20:32 INFO - 'PYTHONPATH': 'C:\\slave\\test-pgo\\build\\tests\\talos', 20:20:32 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 20:20:32 INFO - 'SLAVEDIR': 'C:\\slave\\', 20:20:32 INFO - 'SYSTEMDRIVE': 'C:', 20:20:32 INFO - 'SYSTEMROOT': 'C:\\windows', 20:20:32 INFO - 'TEMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:20:32 INFO - 'TEST1': 'testie', 20:20:32 INFO - 'TMP': 'C:\\Users\\cltbld\\AppData\\Local\\Temp', 20:20:32 INFO - 'USERDOMAIN': 'T-W732-IX-092', 20:20:32 INFO - 'USERNAME': 'cltbld', 20:20:32 INFO - 'USERPROFILE': 'C:\\Users\\cltbld', 20:20:32 INFO - 'WINDIR': 'C:\\windows', 20:20:32 INFO - 'WINDOWS_TRACING_FLAGS': '3', 20:20:32 INFO - 'WINDOWS_TRACING_LOGFILE': 'C:\\BVTBin\\Tests\\installpackage\\csilogfile.log', 20:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 20:20:32 INFO - Calling ['C:\\slave\\test-pgo\\build\\venv\\Scripts\\python', 'C:\\slave\\test-pgo\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', 'C:\\slave\\test-pgo\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-W732-IX-092', '--webServer', 'localhost'] with output_timeout 3600 20:20:33 INFO - mozversion INFO | application_buildid: 20151022143005 20:20:33 INFO - mozversion INFO | application_changeset: 85bd262e7094cfed408b3d36d2f9818355628fd2 20:20:33 INFO - mozversion INFO | application_display_name: Nightly 20:20:33 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:20:33 INFO - mozversion INFO | application_name: Firefox 20:20:33 INFO - mozversion INFO | application_remotingname: firefox 20:20:33 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 20:20:33 INFO - mozversion INFO | application_vendor: Mozilla 20:20:33 INFO - mozversion INFO | application_version: 44.0a1 20:20:33 INFO - mozversion INFO | platform_buildid: 20151022143005 20:20:33 INFO - mozversion INFO | platform_changeset: 85bd262e7094cfed408b3d36d2f9818355628fd2 20:20:33 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 20:20:33 INFO - mozversion INFO | platform_version: 44.0a1 20:20:33 INFO - 2015-10-22 20:20:33,141 DEBUG : using testdate: 1445570433 20:20:33 INFO - 2015-10-22 20:20:33,141 DEBUG : actual date: 1445570433 20:20:33 INFO - 2015-10-22 20:20:33,319 INFO : starting webserver on 'localhost:49892' 20:20:33 INFO - 2015-10-22 20:20:33,321 INFO : Starting test suite T-W732-IX-092 20:20:33 INFO - 2015-10-22 20:20:33,321 INFO : Starting test dromaeo_css 20:20:33 INFO - 2015-10-22 20:20:33,321 DEBUG : operating with platform_type : w7_ 20:20:33 INFO - 2015-10-22 20:20:33,322 INFO : Initialising browser for dromaeo_css test... 20:20:33 INFO - 2015-10-22 20:20:33,408 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpbbknwj\profile http://localhost:49892/getInfo.html 20:20:41 INFO - 2015-10-22 20:20:41,318 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:20:41 INFO - 2015-10-22 20:20:41,318 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:20:41 INFO - 2015-10-22 20:20:41,318 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 20:20:41 INFO - 2015-10-22 20:20:41,318 DEBUG : BROWSER_OUTPUT: __metrics 20:20:41 INFO - 2015-10-22 20:20:41,392 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:20:42 INFO - 2015-10-22 20:20:42,808 DEBUG : BROWSER_OUTPUT: console.error: 20:20:42 INFO - 2015-10-22 20:20:42,808 DEBUG : BROWSER_OUTPUT: Message: Error: Transaction canceled due to a closed connection. 20:20:42 INFO - 2015-10-22 20:20:42,808 DEBUG : BROWSER_OUTPUT: Stack: 20:20:42 INFO - 2015-10-22 20:20:42,808 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise<@resource://gre/modules/Sqlite.jsm:562:1 20:20:42 INFO - 2015-10-22 20:20:42,808 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 20:20:42 INFO - 2015-10-22 20:20:42,808 DEBUG : BROWSER_OUTPUT: 20:20:43 INFO - 2015-10-22 20:20:43,482 INFO : Browser initialized. 20:20:43 INFO - 2015-10-22 20:20:43,482 INFO : Running cycle 1/1 for dromaeo_css test... 20:20:43 INFO - 2015-10-22 20:20:43,482 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpbbknwj\profile -tp file:\C:\slave\test-pgo\build\tests\talos\talos\tests\dromaeo\css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 20:20:54 INFO - 2015-10-22 20:20:54,888 DEBUG : BROWSER_OUTPUT: RSS: Main: 127373312 20:20:54 INFO - 2015-10-22 20:20:54,888 DEBUG : BROWSER_OUTPUT: 20:21:45 INFO - 2015-10-22 20:21:45,947 DEBUG : BROWSER_OUTPUT: 1445570505958 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] 20:23:55 INFO - 2015-10-22 20:23:55,506 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49892/tests/dromaeo/cssquery-ext.html) 20:23:56 INFO - 2015-10-22 20:23:56,461 DEBUG : BROWSER_OUTPUT: RSS: Main: 178778112 20:23:56 INFO - 2015-10-22 20:23:56,461 DEBUG : BROWSER_OUTPUT: 20:26:58 INFO - 2015-10-22 20:26:58,621 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-ext.html (next: http://localhost:49892/tests/dromaeo/cssquery-jquery.html) 20:26:59 INFO - 2015-10-22 20:26:59,176 DEBUG : BROWSER_OUTPUT: RSS: Main: 189771776 20:26:59 INFO - 2015-10-22 20:26:59,176 DEBUG : BROWSER_OUTPUT: 20:30:01 INFO - 2015-10-22 20:30:00,996 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49892/tests/dromaeo/cssquery-mootools.html) 20:30:01 INFO - 2015-10-22 20:30:01,555 DEBUG : BROWSER_OUTPUT: RSS: Main: 148254720 20:30:01 INFO - 2015-10-22 20:30:01,555 DEBUG : BROWSER_OUTPUT: 20:33:03 INFO - 2015-10-22 20:33:03,457 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49892/tests/dromaeo/cssquery-prototype.html) 20:33:04 INFO - 2015-10-22 20:33:04,036 DEBUG : BROWSER_OUTPUT: RSS: Main: 138194944 20:33:04 INFO - 2015-10-22 20:33:04,036 DEBUG : BROWSER_OUTPUT: 20:36:06 INFO - 2015-10-22 20:36:06,710 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49892/tests/dromaeo/cssquery-yui.html) 20:36:07 INFO - 2015-10-22 20:36:07,305 DEBUG : BROWSER_OUTPUT: RSS: Main: 134664192 20:36:07 INFO - 2015-10-22 20:36:07,306 DEBUG : BROWSER_OUTPUT: 20:39:09 INFO - 2015-10-22 20:39:09,529 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:49892/tests/dromaeo/cssquery-yui.html (next: http://localhost:49892/tests/dromaeo/cssquery-dojo.html) 20:39:09 INFO - 2015-10-22 20:39:09,785 DEBUG : BROWSER_OUTPUT: RSS: Main: 196673536 20:39:09 INFO - 2015-10-22 20:39:09,785 DEBUG : BROWSER_OUTPUT: 20:39:09 INFO - 2015-10-22 20:39:09,785 DEBUG : BROWSER_OUTPUT: __start_tp_report 20:39:09 INFO - 2015-10-22 20:39:09,785 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 20:39:09 INFO - 2015-10-22 20:39:09,785 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 20:39:09 INFO - 2015-10-22 20:39:09,785 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 20:39:09 INFO - 2015-10-22 20:39:09,785 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2727.754502094811;2753.8623068846555;2800.747932686058;2809.9999999999986;2819.9989003903606;4973.801047958082;4992.725400102993;5151.768170432335;5259.316288882449;5264.868378290545;5203.68777873327;5318.228856815759;5326.3075800145925;5368.543673787723;5385.2999110115725;4441.578050085237;4541.682082254243;4556.749378784174;4599.517050709677;4609.3777340059;4198.895027624303;4537.636989040869;4565.520620334867;4568.474625418086;4595.931061034087;4419.668524860632;4439.644828413735;4595.655325850558;4612.192866248393;4619.422572178478;4339.631131353823;4510.278355463143;4523.7285762854335;4613.192691279013;4618.584327410549;4138.399932009872;4245.3844192591905;4412.75729834859;4460.397846290742;4506.481754598198;5157.252198431206;5160.406553246387;5176.715280659562;5194.558462530689;5249.2388603652635;5285.814996475105;5466.125419932791;5480.095784195603;5537.861553461155;5567.192757050246;5149.2276158576515;5503.394626591072;5508.531774799147;5584.6090773645865;5619.129034999541;5099.719515426687;5114.207297868837;5137.768800403954;5199.48005199477;5200.869978250536;5446.809361672323;5646.971765141148;5674.716264186774;5774.076147816413;5775.249217601685;4962.429320628174;5239.240310155006;5302.416734159317;5387.434319396485;5420.322459692538;5523.530499907472;5561.360443548962;5613.859653508695;5628.296462942132;5649.858753531194;5437.809676661298;5464.316960379952;5568.860778480489;5592.916106258409;5597.160425936142;5386.407495175527;5401.108817045222;5496.477834605706;5563.860903477445;5587.273654424899;3400.795952242873;3422.178677117524;3481.147118955872;3488.302339532104;3489.4416893297457;3110.720035196843;3120.3135310231714;3140.544621029984;3188.314512379803;3197.0728488738005;5162.664426812245;5185.351831021122;5275.868103297372;5279.47205279469;5283.973580132151;5532.1425179097305;5532.585056120807;5576.052071147915;5611.354694210137;5633.436656334334;5107.61692873036;5200.2719619252575;5238.109521381374;5299.735013249322;5304.867378315573;4242.681798865097;4275.102228532043;4360.607545320873;4370.344448332776;4371.256886329332;5803.100519419412;5845.473907348359;5941.970290148522;5944.821655350346;5953.077273022688;5436.401995780382;5527.50252477279;5537.723113844372;5575;5677.034904066236;5841.474267315962;5878.529717622666;5950.880982380241;5962.582619216613;5972.820815375546;5312.893742125058;5412.161115027177;5449.782008719607;5545.140503222007;5556.472135147154;5407.972960135174;5490.91763623538;5553.416891226363;5584.972075139598;5685.630434021776;5175.870603234949;5213.81751638706;5226.790928362823;5563.054280772198;5564.610477266552;5226.63413561047;5292.02097611943;5457.69982650958;5518.2826232589505;5536.833894983157;5229.084910140634;5278.129108697022;5293.9205911910585;5352.678839269656;5416.864578385572;5229.294045303987;5241.921371179512;5372.8448383597715;5376.892462150813;5516.751746171351;5200.2199670048285;5203.791848326176;5321.5476684482255;5477.123660214346;5488.313960754906 20:39:09 INFO - 2015-10-22 20:39:09,785 DEBUG : BROWSER_OUTPUT: |1;ext.html;479.6714250738245;490.24991762603213;500.7596353750201;515.2657463115063;519.6933809052658;30091.54862677056;30373.544396834066;30457.086287411385;30819.22951926203;30953.69046309536;31053.689463105362;37652.24695506095;38573.807130964306;38915.80542097286;39141.41287880684;8091.919080809175;8252.628631711557;8452.873206901886;8572.699955501559;8588.055313915464;7229.530080544774;7608.657610407545;8346.666133354658;8434.156584341548;8444.831103377928;10470.371777693363;10595.099416549603;10711.143108551298;10747.408892510908;10751.408672523008;10054.79890402195;10305.948470257676;10423.27037107399;10425.270231083827;10427.895721042769;7472.103347598269;7671.884921726123;9840.753981150528;10236.334638248565;10599.0990765785;37123;37552.43671344932;37741.622583774086;37851.621483785086;37892.242155156746;3133.420317241295;3218.0185043561705;3228.1445416964525;3484.703800176988;3507.473878918157;30123.60476440196;30413.695863041306;30551;30885.845570772002;30947.22631934197;2658.428437885864;2684.0874102804946;2727.6590426196726;2731;2774.1261502626608;318370;319175;319519;319784;320973;43299.78350108292;51207.710149564584;51542.48457515452;51578.48421515737;51942;46527.76736116298;51368;53843;53909;53946.46053539493;12903.161294515827;12964.286964217059;13347.531771505126;13689.247091409869;13814.171149731048;10459.633912813015;10543.84184237237;10560;10565.788684226272;10570.577176912991;16310.918445407697;16509.257083431276;16733.497995060166;16745.581360466087;16853.241604127845;7665.3101220890385;7680.577568233801;7760.728374506865;7846.882296765553;7854.410919181084;862.0732712334141;894.9190252236072;907.8320510705408;931.403901503025;1062.6865074802952;29113.56329655057;29129.56305655417;29150;29173.270668233465;29290.26774330616;968.6222373274429;970.3547141151056;971.5919313888184;984.7587341101475;992.4194346307347;14901.254937253094;15158.807025251383;15164.393424263126;15189.468368607046;15255.847441525664;474685;476035;477921;484372;484678;46437.30344044842;47914;48378.516214838106;48428.75785621049;48660.75669621629;1155.2952698853692;1161.5760247509597;1166.2128063557066;1175.400545721671;1183.946722397494;27965;28420;28680.856595716887;28870;28947.855260724406;41028.589714102476;41590.37614435725;43787.78106109449;44340.33489497725;44812;277895;278684;278946.394916941;279828;280435;24750.25749227526;24897.62653560162;26342.73657263403;27607;28262.43475130445;36711.63288367189;38627.80686096552;39115.608843912334;39359.2128157441;39458.80270598744;32361.676383235867;33562.49656255207;34049.48925766165;34429.48355774715;34519.65480345265;33296.567832349276;34141;34400.48399273963;34526;35517.64482355143;34807.30385392329;35268.823655881555;35283.64716352804;35342.82328588341;35376.29247414986;29234.41531169322;32595;33045.66954330426;33316.33367332688;33808.3238335227;24945.875270623532;25179.622305665063;25404.745952540976;25410;25918.35204119912 20:39:09 INFO - 2015-10-22 20:39:09,793 DEBUG : BROWSER_OUTPUT: |2;jquery.html;323.2016918212017;324.26068563674824;325.34767790579883;327.3910526420857;331.0614408152886;11301.717457063578;12122.151449398545;16683.666326673458;16749.1625418729;17148.399806006793;17407.651846963086;17510;17739.37912173074;17753.556161095978;17783.199756010978;5445.428230035848;5597.860053498654;5613.775448982046;5613.80351687691;5615.634983726065;5431.967926094049;5491.121420572709;5581.358143813472;5588.776448942058;5601.243832082678;4176.352665336863;4439.778011099448;5529.751161197756;5534.0592099343;5541.0580201365665;6069.817905462844;6123.418275263843;6204.363145663076;6224.8132556023165;6227.221597300338;3906.746061505993;5311.468853114697;5358.624896257264;5404.51359377654;5422.484863937959;13080.41138148776;13134.211947283193;13135.34323283832;13169.670758231025;13211.801822972662;1990.4327266729058;2034.2879992002681;2039.3270220827092;2082.822960048398;2085.124247815921;9230.400023998418;9601.087896649877;9905.405675659484;10017.749556261078;10021;1573.5358069369563;1591.7612358146255;1592.6416556274746;1651.9091449970247;1666.5333706562183;66983.33016669868;70529;70863.29136708619;71294.28705712929;71859;6195.752169913243;6277.623342599459;6365.36346365369;6409.839754006187;6478.060681201199;7375.262473752582;7387.741429049957;7391.113066432063;7398.4081273497995;7411.481196316314;5298.97350513245;5317.8404647860625;5323.946760532423;5325.4142044375085;5367.651102678314;5566.248556444909;5661.46216109469;5708.543316534668;5714.257146570919;5716.942830571641;2816.7042460541347;2851.5580085086845;2862.4418238443304;2864.6705628852533;2883.2503549076973;992.156666833186;1028.4497793680496;1040.8802987656516;1045.9267851250493;1086.0334302470808;371.8958691566366;394.70199999000573;399.27132982306824;406.938959156123;409.6395172248462;11193.440327983568;11528.481218345194;11685.18203725748;11771.705707357214;11839.230450020721;427.3034953026547;438.5439143291005;439.984600538986;442.2614234228781;446.698478526993;6195.876082478325;6236.875262494725;6308.936910630927;6394.104825324458;6446.290908000116;319444;323103;407264.8910266289;423309;448599;80567;82580.17419825845;82664;82675;85173.57413212894;459.400482370503;464.1529209193236;471.2318920160117;472.5794043301464;472.7447178523627;10741.785164296514;11170.832437513282;11203.495842686936;11223.494942727433;11240.381779002233;35377.82311088531;37888.24223515459;37917.81041094777;38175.42736859005;38918.221635567694;53777;54031.18953215626;54663.45336546584;55084.44915550954;55610;6197.752089916353;6284.465820405317;6286.18279623646;6300.306966233619;6304.558680892477;12043;12446.439910204244;12480.563180288646;12486.43811028524;12497.500099995897;10164.745881353025;10461.47692615351;10539.525721342405;10898.12814974816;10910.945445272722;10317.432541210006;10365.326253793479;10602.628907988492;10728.302660327054;10818.459077046273;10758.354498730101;10862.239643225215;11212.327260364404;11215.66353009411;11234.045106166068;6338.714757836001;6357.777477788255;6373.458256048288;6392.3927226913365;6415.935840641534;10545.630902918361;10715.196360272856;10840.783184336427;10846.891531084588;10868.184886133413 20:39:09 INFO - 2015-10-22 20:39:09,795 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1192.677976946225;1246.2460211572;1282.8011658192977;1478.3421377487018;1635.2804765903002;8045.1552586978405;8671.089535598767;8673.219410253076;8760.386772925898;8809.603567839455;8805.691800786974;8850.380473366868;8896.243819275369;8941.955290223555;8966.327525435585;2019.717239586459;2031.9898400507977;2157.7194964654573;2245.023414814553;2250.9324720258337;2143.8928053597256;2204.559088182362;2236.340279617517;2242.4281808138985;2248.932532024042;2192.397090800027;2251.4033781048033;2266.648669456237;2267.444476103357;2269.5574362999223;1999.2602736987262;2051.476873397274;2070.948226294347;2084.8436367272366;2101.3485819396037;2221.31139346803;2236.2620335289316;2238.9776102238934;2263.886805659727;2267.138487374787;8629.827403451958;8817.911820881773;9080.954595226982;9157.130072643089;9166.358354915159;5045.798168073273;5092;5098.031374038957;5099.184130539096;5106.310648062501;3304.7025767680784;3348.179695974502;3355.127666806648;3431.605365382963;3459.2562599041075;1037.5694086953904;1041.666666666667;1059.1526778577108;1092.0826505735142;1095.950682219294;11743.647690569296;12213.938930305381;12332.383380830923;12336.383180840921;12658.556950506689;4403.603675669206;4456.752109409371;4491.797869095899;4512.2554778461845;4563.863084107482;10534.789304214026;10546.894531054591;10623.097036751962;10623.20325975555;10649.148068154529;4908.901821963541;4970.776315065831;5144.279800827887;5150.48495150482;5159.406668233201;4483.887902802456;4508.211063063951;4685.156671799041;4691.9530804692195;4694.50707675696;3564.0733409313248;3615.295017471568;3629.564452265692;3650.4159334506353;3743.3449146399817;1134.8070827959266;1142.2289954280827;1143.874173840877;1154.4862536171322;1165.1145129701488;841.469873979389;1115.3865374044244;1137.272145826655;1157.0801213035656;1157.6006277834133;7498.362639175732;8018.438709290293;8547.700830470903;8557.187067228604;8590.914090859136;626.9811905642838;630.3035146163423;647.4787795824407;647.7506160128385;647.760328678392;5896.292444906639;6035.486983606354;6086.573939824259;6095.695215239309;6143.078538219303;11029.83455248172;11130.666080017612;11200;11411.429428528707;11416.200865939305;11187.832182517268;11216.102711783038;11267.098632109575;11277.54889804415;11281.943590281997;7636;8043.39674524413;8195;8211.384146189059;8213.548254845922;7251.637418129178;7748.302652761278;7771.33943614799;7789.805254868674;7797.376209903307;9518.476483793458;9705.951470242886;9740.464274464972;9820.312578119463;9870.901290987285;9858.162056225014;10175.7456063599;10207.693769186937;10208.591656333663;10214.795704086026;7462.96268518654;7976.122626511195;8014.719484818003;8042.195780421911;8042.919570804451;8727.127287271222;8754.562271886507;8761.605727742395;8786.297096232414;8806.60370283326;7829.804254893673;7942.245486678757;8113.432059755996;8239;8307.418480706534;6710.530262881552;7129.108861392326;7215;7931.841363172589;8174.713885013996;7930.484518506279;7955.403344749283;7973.72091976778;7996.440249182734;7996.680132794623;6819.352161544645;6926.653667316715;6974.442044636316;6984.860302794017;6985.336393042653;7084.929150708427;7135.678894449658;7154.534955228101;7159.892601610876;7164.570125792469 20:39:09 INFO - 2015-10-22 20:39:09,871 DEBUG : BROWSER_OUTPUT: |4;prototype.html;324.9350129974006;328.23684932531853;329.36103958320865;337.5409443157307;338.9677980591843;5675.2054712340305;5732.111522713975;5770.567207459437;5798.65208087515;5838.357780644125;5688.061469857479;5737.598368114223;5747.137929310606;5754.338251101125;5761.452661997114;4172.7496350218935;4205.264078786216;4223.324268117102;4256.595623415786;4275.315949448089;4024.1951609678035;4133.359329303963;4150.522689890656;4272.871813845574;4280;4097.282975479294;4238.343056826181;4239.236937351276;4244.766537840417;4247.469066366704;3945.9210815783813;4242.893927651802;4244.681648876316;4248.957510424918;4249.915001699964;3736.663700266989;3762.567304759933;3763.6236376362144;3764.8682296119778;3770.227103443815;5325.813596524103;5483.451654834525;5642.351129620105;5643.971780141073;5656.349519805234;5002.674826136289;5067.315912351823;5076.492350764894;5100.591952643779;5115.488451154892;6005.129256257818;6014.8496287592725;6026.03583426654;6026.728797204137;6031.095335699636;4256.765877876715;4264.6801489888385;4271.658267338606;4275.957240427618;4276.486821581399;6171.290301615327;6214;6263;6331.968340158316;6341.365863413668;4163.437935878617;4224.1129362833535;4394.209042372404;4420.911581768347;4425.535318791545;4369.541198174146;4458.6878918475395;4471.32930060487;4479.731216127043;4481.215787237285;4845.273209018605;5048.5203905628905;5087.389513258433;5090.796368145307;5097.796088156507;5709.25779648643;5836.533077353802;5843.737031833578;5844.15259787337;5986.401359864066;1589.4675283779832;1604;1630.0301320714157;1637.0995952226228;1637.165045826637;1085.9728506787235;1111.827666711661;1125.8480105185695;1129.1926272715048;1133.1954312438097;5863.530917526673;5895.970520147372;5898.233229680114;5899.852503687443;5902.468777810018;6278.811635650938;6291.874162516724;6293.496520278367;6294.276158241769;6306.085617585424;5721.370649228582;5866.178734977107;5875.559333049953;5887.087501437284;5895.056790913433;3202.1034110449114;3373.0555444475585;3444.3800115979366;3471.94792078119;3476.4090104682264;6440.645764482999;6510.967445162744;6570.441512471493;6577.309382514863;6639.767608133692;6046.879062418727;6051.667158306335;6355.237371515448;6419.197600299963;6419.45434638061;6610.537262391683;6636.2700102987665;6639.800805975829;6640.933590664128;6665.1335326408225;6223.408776166257;6281.246250449975;6288.528360373082;6296.968515157395;6317.526185536195;6168.19813424252;6183.474404675653;6194.0708893666315;6195.19462469876;6196.783112591038;6208.192934918432;6505.707243173973;6695;6708.563943343667;6708.631025293461;4247.065645557972;4360.9345859812765;4467.195904737178;4468.620167285817;4478.193925093515;6229.470495007975;6232.657203853832;6260.812175634738;6273.278572964167;6276.435120839146;6101.694915254131;6111.388861113853;6113.663748493876;6118.877622447615;6119.4798442132915;5800.1589769484035;5995.5803093783015;6028.969855150696;6045.002574575145;6053.515718742402;5939.138824870456;5942;5947.256592925884;5952.910706339495;5965.582409231312;6076.270847498152;6103.23709536308;6105.725242364193;6119.663418512025;6142.924988127149;5983.9401605983385;6013.909791353046;6023.27720673522;6026.789062382795;6032.90950635732 20:39:09 INFO - 2015-10-22 20:39:09,872 DEBUG : BROWSER_OUTPUT: |5;yui.html;616.3466725271213;621.5245337316952;653.6013032050449;690.2200513419838;714.7426926306532;11918.225315354495;11950.581729639454;12195.756084878298;12268.325242111692;12269.079819013565;10962.451877406138;11258.98669119779;11381.26021808582;11431.142664300169;11434.142439317044;502.1413383114878;502.71345333160116;601.0923505506672;602.8553147244652;611.4313688269907;609.451493655709;610.856448734547;611.0223642172533;611.6544152553815;612.944834964853;600.3876046432636;616.0574321288414;632.4971647540217;642.0561774191954;642.3929386729543;625.377749139609;629.0784001437916;630.2405601250482;643.0739734781899;643.2827397451831;464.2873189653899;465.21611085321;465.26720415345966;465.27417229122676;465.3020469296056;11852.407379630984;12046.939765301116;12098.334591597459;12165.02679785615;12185.147039707224;11419.314841109477;11527.481263343087;11794.469248883821;11894.702632434171;11930.701732456671;10074.597016119347;10074.597016119347;10163.42466917629;10182.38905665655;10204.38773673582;3371.393149233137;3405.4721518164724;3438.2951494943477;3459.728072302873;3518.5249991251108;37614.99999999972;41548.584514155074;47087;47202;47439.762801186116;8640.395172337876;9256.676016339396;9328.813423731488;9468.57391417388;9639.807203856024;16417.343306267856;16425;16506.58733531671;16512.75230871538;16518.174091295627;6786.083878676315;6807.795766127024;6850.691718872663;6864.7940561783225;6984.126984126984;3828.425736139601;3869.1101046759404;3911.6870650348474;3930.449737036817;3956.9208615827524;2759.2550011496783;2901.8984335548153;2914.3005678636973;2931.050851183981;2934.3544420227518;457.8786621545293;476.29983923632204;480.7572176051142;481.2252273839116;487.8341363936208;563.4872266237704;635.8791829552463;642.0946465483646;677.864427114579;687.2097088348439;8903.643854245758;8938.329625278131;9023.548822558845;9112.589933452879;9312.674056408128;542.0568211312288;657.3229573539262;667.2326824152263;681.7443458702986;689.2211800665216;4219.915601688011;4270;4335.154644844225;4371.191329604034;4436.291858158306;55474;55920.720396398574;56461;56780.43219567834;57072.429275707545;47981.520184798406;49226;49712.0057598846;49945.25082123771;50781.492185078416;34433.311333773185;41742.37386439206;41798.1640367197;42661.57338426638;44687.55312446899;9268.258539316974;9281.953590232006;9306.395084319498;9399.483028433227;9645;30527.389452210387;30821.69178308278;31047.689523105382;31321.373572527966;31353;40964.38553421642;41146.38280425736;41182;41366.793166033975;41805.58194418017;10175.575419441433;11252.156088293246;11358.60244891425;11434.37110958872;11853.822192666945;12574;12612.747745045232;12839.422225999666;12859.678508037;13163.60509184726;11848.874356936078;12446.626601201979;12540.561080362144;12649.367531623566;13132.671683207996;11912.761744764883;12179.512819487121;12188.57339993096;12235.449404776984;12409.93795031019;12121.333326667118;12335.629931102081;12779.48882044745;12799.29603871796;12826.871731282568;9807.362521436084;10236.48817559134;10311.793764124526;10541.561524793562;10713.410762407831;9671.90328096738;10423.635172768916;10471.842922356018;10481.266311358131;10550.366977981346 20:39:09 INFO - 2015-10-22 20:39:09,874 DEBUG : BROWSER_OUTPUT: __end_tp_report 20:39:09 INFO - 2015-10-22 20:39:09,874 DEBUG : BROWSER_OUTPUT: __start_cc_report 20:39:09 INFO - 2015-10-22 20:39:09,874 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,722 20:39:09 INFO - 2015-10-22 20:39:09,874 DEBUG : BROWSER_OUTPUT: __end_cc_report 20:39:09 INFO - 2015-10-22 20:39:09,874 DEBUG : BROWSER_OUTPUT: __startTimestamp1445571549802__endTimestamp 20:39:09 INFO - 2015-10-22 20:39:09,874 DEBUG : BROWSER_OUTPUT: 20:39:09 INFO - 2015-10-22 20:39:09,875 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 20:39:09 INFO - 2015-10-22 20:39:09,875 DEBUG : BROWSER_OUTPUT: Number of tests: 6 20:39:09 INFO - 2015-10-22 20:39:09,875 DEBUG : BROWSER_OUTPUT: 20:39:09 INFO - 2015-10-22 20:39:09,875 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5048.65 Median:5285.81 stddev:745.87 (14.1%) stddev-sans-first:725.60 20:39:09 INFO - 2015-10-22 20:39:09,875 DEBUG : BROWSER_OUTPUT: Values: 2727.8 2753.9 2800.7 2810.0 2820.0 4973.8 4992.7 5151.8 5259.3 5264.9 5203.7 5318.2 5326.3 5368.5 5385.3 4441.6 4541.7 4556.7 4599.5 4609.4 4198.9 4537.6 4565.5 4568.5 4595.9 4419.7 4439.6 4595.7 4612.2 4619.4 4339.6 4510.3 4523.7 4613.2 4618.6 4138.4 4245.4 4412.8 4460.4 4506.5 5157.3 5160.4 5176.7 5194.6 5249.2 5285.8 5466.1 5480.1 5537.9 5567.2 5149.2 5503.4 5508.5 5584.6 5619.1 5099.7 5114.2 5137.8 5199.5 5200.9 5446.8 5647.0 5674.7 5774.1 5775.2 4962.4 5239.2 5302.4 5387.4 5420.3 5523.5 5561.4 5613.9 5628.3 5649.9 5437.8 5464.3 5568.9 5592.9 5597.2 5386.4 5401.1 5496.5 5563.9 5587.3 3400.8 3422.2 3481.1 3488.3 3489.4 3110.7 3120.3 3140.5 3188.3 3197.1 5162.7 5185.4 5275.9 5279.5 5284.0 5532.1 5532.6 5576.1 5611.4 5633.4 5107.6 5200.3 5238.1 5299.7 5304.9 4242.7 4275.1 4360.6 4370.3 4371.3 5803.1 5845.5 5942.0 5944.8 5953.1 5436.4 5527.5 5537.7 5575.0 5677.0 5841.5 5878.5 5950.9 5962.6 5972.8 5312.9 5412.2 5449.8 5545.1 5556.5 5408.0 5490.9 5553.4 5585.0 5685.6 5175.9 5213.8 5226.8 5563.1 5564.6 5226.6 5292.0 5457.7 5518.3 5536.8 5229.1 5278.1 5293.9 5352.7 5416.9 5229.3 5241.9 5372.8 5376.9 5516.8 5200.2 5203.8 5321.5 5477.1 5488.3 20:39:09 INFO - 2015-10-22 20:39:09,875 DEBUG : BROWSER_OUTPUT: 20:39:09 INFO - 2015-10-22 20:39:09,875 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:50291.21 Median:33171.12 stddev:97946.79 (295.3%) stddev-sans-first:98150.16 20:39:09 INFO - 2015-10-22 20:39:09,875 DEBUG : BROWSER_OUTPUT: Values: 479.7 490.2 500.8 515.3 519.7 30091.5 30373.5 30457.1 30819.2 30953.7 31053.7 37652.2 38573.8 38915.8 39141.4 8091.9 8252.6 8452.9 8572.7 8588.1 7229.5 7608.7 8346.7 8434.2 8444.8 10470.4 10595.1 10711.1 10747.4 10751.4 10054.8 10305.9 10423.3 10425.3 10427.9 7472.1 7671.9 9840.8 10236.3 10599.1 37123.0 37552.4 37741.6 37851.6 37892.2 3133.4 3218.0 3228.1 3484.7 3507.5 30123.6 30413.7 30551.0 30885.8 30947.2 2658.4 2684.1 2727.7 2731.0 2774.1 318370.0 319175.0 319519.0 319784.0 320973.0 43299.8 51207.7 51542.5 51578.5 51942.0 46527.8 51368.0 53843.0 53909.0 53946.5 12903.2 12964.3 13347.5 13689.2 13814.2 10459.6 10543.8 10560.0 10565.8 10570.6 16310.9 16509.3 16733.5 16745.6 16853.2 7665.3 7680.6 7760.7 7846.9 7854.4 862.1 894.9 907.8 931.4 1062.7 29113.6 29129.6 29150.0 29173.3 29290.3 968.6 970.4 971.6 984.8 992.4 14901.3 15158.8 15164.4 15189.5 15255.8 474685.0 476035.0 477921.0 484372.0 484678.0 46437.3 47914.0 48378.5 48428.8 48660.8 1155.3 1161.6 1166.2 1175.4 1183.9 27965.0 28420.0 28680.9 28870.0 28947.9 41028.6 41590.4 43787.8 44340.3 44812.0 277895.0 278684.0 278946.4 279828.0 280435.0 24750.3 24897.6 26342.7 27607.0 28262.4 36711.6 38627.8 39115.6 39359.2 39458.8 32361.7 33562.5 34049.5 34429.5 34519.7 33296.6 34141.0 34400.5 34526.0 35517.6 34807.3 35268.8 35283.6 35342.8 35376.3 29234.4 32595.0 33045.7 33316.3 33808.3 24945.9 25179.6 25404.7 25410.0 25918.4 20:39:09 INFO - 2015-10-22 20:39:09,875 DEBUG : BROWSER_OUTPUT: 20:39:09 INFO - 2015-10-22 20:39:09,875 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:23427.92 Median:213742.68 stddev:64537.02 (30.2%) stddev-sans-first:64694.75 20:39:09 INFO - 2015-10-22 20:39:09,875 DEBUG : BROWSER_OUTPUT: Values: 323.2 324.3 325.3 327.4 331.1 11301.7 12122.2 16683.7 16749.2 17148.4 17407.7 17510.0 17739.4 17753.6 17783.2 5445.4 5597.9 5613.8 5613.8 5615.6 5432.0 5491.1 5581.4 5588.8 5601.2 4176.4 4439.8 5529.8 5534.1 5541.1 6069.8 6123.4 6204.4 6224.8 6227.2 3906.7 5311.5 5358.6 5404.5 5422.5 13080.4 13134.2 13135.3 13169.7 13211.8 1990.4 2034.3 2039.3 2082.8 2085.1 9230.4 9601.1 9905.4 10017.7 10021.0 1573.5 1591.8 1592.6 1651.9 1666.5 66983.3 70529.0 70863.3 71294.3 71859.0 6195.8 6277.6 6365.4 6409.8 6478.1 7375.3 7387.7 7391.1 7398.4 7411.5 5299.0 5317.8 5323.9 5325.4 5367.7 5566.2 5661.5 5708.5 5714.3 5716.9 2816.7 2851.6 2862.4 2864.7 2883.3 992.2 1028.4 1040.9 1045.9 1086.0 371.9 394.7 399.3 406.9 409.6 11193.4 11528.5 11685.2 11771.7 11839.2 427.3 438.5 440.0 442.3 446.7 6195.9 6236.9 6308.9 6394.1 6446.3 319444.0 323103.0 407264.9 423309.0 448599.0 80567.0 82580.2 82664.0 82675.0 85173.6 459.4 464.2 471.2 472.6 472.7 10741.8 11170.8 11203.5 11223.5 11240.4 35377.8 37888.2 37917.8 38175.4 38918.2 53777.0 54031.2 54663.5 55084.4 55610.0 6197.8 6284.5 6286.2 6300.3 6304.6 12043.0 12446.4 12480.6 12486.4 12497.5 10164.7 10461.5 10539.5 10898.1 10910.9 10317.4 10365.3 10602.6 10728.3 10818.5 10758.4 10862.2 11212.3 11215.7 11234.0 6338.7 6357.8 6373.5 6392.4 6415.9 10545.6 10715.2 10840.8 10846.9 10868.2 20:39:09 INFO - 2015-10-22 20:39:09,878 DEBUG : BROWSER_OUTPUT: 20:39:09 INFO - 2015-10-22 20:39:09,878 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:6066.98 Median:5068.90 stddev:3426.59 (67.6%) stddev-sans-first:3416.61 20:39:09 INFO - 2015-10-22 20:39:09,878 DEBUG : BROWSER_OUTPUT: Values: 1192.7 1246.2 1282.8 1478.3 1635.3 8045.2 8671.1 8673.2 8760.4 8809.6 8805.7 8850.4 8896.2 8942.0 8966.3 2019.7 2032.0 2157.7 2245.0 2250.9 2143.9 2204.6 2236.3 2242.4 2248.9 2192.4 2251.4 2266.6 2267.4 2269.6 1999.3 2051.5 2070.9 2084.8 2101.3 2221.3 2236.3 2239.0 2263.9 2267.1 8629.8 8817.9 9081.0 9157.1 9166.4 5045.8 5092.0 5098.0 5099.2 5106.3 3304.7 3348.2 3355.1 3431.6 3459.3 1037.6 1041.7 1059.2 1092.1 1096.0 11743.6 12213.9 12332.4 12336.4 12658.6 4403.6 4456.8 4491.8 4512.3 4563.9 10534.8 10546.9 10623.1 10623.2 10649.1 4908.9 4970.8 5144.3 5150.5 5159.4 4483.9 4508.2 4685.2 4692.0 4694.5 3564.1 3615.3 3629.6 3650.4 3743.3 1134.8 1142.2 1143.9 1154.5 1165.1 841.5 1115.4 1137.3 1157.1 1157.6 7498.4 8018.4 8547.7 8557.2 8590.9 627.0 630.3 647.5 647.8 647.8 5896.3 6035.5 6086.6 6095.7 6143.1 11029.8 11130.7 11200.0 11411.4 11416.2 11187.8 11216.1 11267.1 11277.5 11281.9 7636.0 8043.4 8195.0 8211.4 8213.5 7251.6 7748.3 7771.3 7789.8 7797.4 9518.5 9706.0 9740.5 9820.3 9870.9 9858.2 10175.7 10207.7 10208.6 10214.8 7463.0 7976.1 8014.7 8042.2 8042.9 8727.1 8754.6 8761.6 8786.3 8806.6 7829.8 7942.2 8113.4 8239.0 8307.4 6710.5 7129.1 7215.0 7931.8 8174.7 7930.5 7955.4 7973.7 7996.4 7996.7 6819.4 6926.7 6974.4 6984.9 6985.3 7084.9 7135.7 7154.5 7159.9 7164.6 20:39:09 INFO - 2015-10-22 20:39:09,878 DEBUG : BROWSER_OUTPUT: 20:39:09 INFO - 2015-10-22 20:39:09,878 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5074.97 Median:5799.41 stddev:1529.09 (26.4%) stddev-sans-first:1491.23 20:39:09 INFO - 2015-10-22 20:39:09,878 DEBUG : BROWSER_OUTPUT: Values: 324.9 328.2 329.4 337.5 339.0 5675.2 5732.1 5770.6 5798.7 5838.4 5688.1 5737.6 5747.1 5754.3 5761.5 4172.7 4205.3 4223.3 4256.6 4275.3 4024.2 4133.4 4150.5 4272.9 4280.0 4097.3 4238.3 4239.2 4244.8 4247.5 3945.9 4242.9 4244.7 4249.0 4249.9 3736.7 3762.6 3763.6 3764.9 3770.2 5325.8 5483.5 5642.4 5644.0 5656.3 5002.7 5067.3 5076.5 5100.6 5115.5 6005.1 6014.8 6026.0 6026.7 6031.1 4256.8 4264.7 4271.7 4276.0 4276.5 6171.3 6214.0 6263.0 6332.0 6341.4 4163.4 4224.1 4394.2 4420.9 4425.5 4369.5 4458.7 4471.3 4479.7 4481.2 4845.3 5048.5 5087.4 5090.8 5097.8 5709.3 5836.5 5843.7 5844.2 5986.4 1589.5 1604.0 1630.0 1637.1 1637.2 1086.0 1111.8 1125.8 1129.2 1133.2 5863.5 5896.0 5898.2 5899.9 5902.5 6278.8 6291.9 6293.5 6294.3 6306.1 5721.4 5866.2 5875.6 5887.1 5895.1 3202.1 3373.1 3444.4 3471.9 3476.4 6440.6 6511.0 6570.4 6577.3 6639.8 6046.9 6051.7 6355.2 6419.2 6419.5 6610.5 6636.3 6639.8 6640.9 6665.1 6223.4 6281.2 6288.5 6297.0 6317.5 6168.2 6183.5 6194.1 6195.2 6196.8 6208.2 6505.7 6695.0 6708.6 6708.6 4247.1 4360.9 4467.2 4468.6 4478.2 6229.5 6232.7 6260.8 6273.3 6276.4 6101.7 6111.4 6113.7 6118.9 6119.5 5800.2 5995.6 6029.0 6045.0 6053.5 5939.1 5942.0 5947.3 5952.9 5965.6 6076.3 6103.2 6105.7 6119.7 6142.9 5983.9 6013.9 6023.3 6026.8 6032.9 20:39:09 INFO - 2015-10-22 20:39:09,880 DEBUG : BROWSER_OUTPUT: 20:39:09 INFO - 2015-10-22 20:39:09,880 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13156.25 Median:41770.27 stddev:14939.23 (35.8%) stddev-sans-first:14951.46 20:39:09 INFO - 2015-10-22 20:39:09,880 DEBUG : BROWSER_OUTPUT: Values: 616.3 621.5 653.6 690.2 714.7 11918.2 11950.6 12195.8 12268.3 12269.1 10962.5 11259.0 11381.3 11431.1 11434.1 502.1 502.7 601.1 602.9 611.4 609.5 610.9 611.0 611.7 612.9 600.4 616.1 632.5 642.1 642.4 625.4 629.1 630.2 643.1 643.3 464.3 465.2 465.3 465.3 465.3 11852.4 12046.9 12098.3 12165.0 12185.1 11419.3 11527.5 11794.5 11894.7 11930.7 10074.6 10074.6 10163.4 10182.4 10204.4 3371.4 3405.5 3438.3 3459.7 3518.5 37615.0 41548.6 47087.0 47202.0 47439.8 8640.4 9256.7 9328.8 9468.6 9639.8 16417.3 16425.0 16506.6 16512.8 16518.2 6786.1 6807.8 6850.7 6864.8 6984.1 3828.4 3869.1 3911.7 3930.4 3956.9 2759.3 2901.9 2914.3 2931.1 2934.4 457.9 476.3 480.8 481.2 487.8 563.5 635.9 642.1 677.9 687.2 8903.6 8938.3 9023.5 9112.6 9312.7 542.1 657.3 667.2 681.7 689.2 4219.9 4270.0 4335.2 4371.2 4436.3 55474.0 55920.7 56461.0 56780.4 57072.4 47981.5 49226.0 49712.0 49945.3 50781.5 34433.3 41742.4 41798.2 42661.6 44687.6 9268.3 9282.0 9306.4 9399.5 9645.0 30527.4 30821.7 31047.7 31321.4 31353.0 40964.4 41146.4 41182.0 41366.8 41805.6 10175.6 11252.2 11358.6 11434.4 11853.8 12574.0 12612.7 12839.4 12859.7 13163.6 11848.9 12446.6 12540.6 12649.4 13132.7 11912.8 12179.5 12188.6 12235.4 12409.9 12121.3 12335.6 12779.5 12799.3 12826.9 9807.4 10236.5 10311.8 10541.6 10713.4 9671.9 10423.6 10471.8 10481.3 10550.4 20:39:09 INFO - 2015-10-22 20:39:09,881 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 20:39:09 INFO - 2015-10-22 20:39:09,881 DEBUG : BROWSER_OUTPUT: 20:39:10 INFO - 2015-10-22 20:39:10,276 INFO : Browser exited with error code: 0 20:39:11 INFO - 2015-10-22 20:39:11,010 INFO : Completed test dromaeo_css (00:18:37) 20:39:11 INFO - 2015-10-22 20:39:11,010 INFO : Starting test kraken 20:39:11 INFO - 2015-10-22 20:39:11,010 DEBUG : operating with platform_type : w7_ 20:39:11 INFO - 2015-10-22 20:39:11,010 INFO : Initialising browser for kraken test... 20:39:11 INFO - 2015-10-22 20:39:11,036 DEBUG : command line: C:\slave\test-pgo\build\application\firefox\firefox -profile c:\users\cltbld\appdata\local\temp\tmpwrtxwf\profile http://localhost:49892/getInfo.html 20:39:15 INFO - 2015-10-22 20:39:15,387 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 20:39:15 INFO - 2015-10-22 20:39:15,387 DEBUG : BROWSER_OUTPUT: colorDepth:24 20:39:15 INFO - 2015-10-22 20:39:15,387 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1010/674 20:39:15 INFO - 2015-10-22 20:39:15,387 DEBUG : BROWSER_OUTPUT: __metrics 20:39:15 INFO - 2015-10-22 20:39:15,461 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:39:16 INFO - 2015-10-22 20:39:16,276 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 121: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 20:39:16 INFO - 2015-10-22 20:39:16,539 DEBUG : BROWSER_OUTPUT: console.error: 20:39:16 INFO - 2015-10-22 20:39:16,539 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] 20:39:16 INFO - 2015-10-22 20:39:16,539 DEBUG : BROWSER_OUTPUT: Stack: 20:39:16 INFO - 2015-10-22 20:39:16,539 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<._executeStatement@resource://gre/modules/Sqlite.jsm:758:19 20:39:16 INFO - 2015-10-22 20:39:16,539 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached/<@resource://gre/modules/Sqlite.jsm:493:9 20:39:16 INFO - 2015-10-22 20:39:16,540 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeCached@resource://gre/modules/Sqlite.jsm:491:1 20:39:16 INFO - 2015-10-22 20:39:16,540 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeBeforeShutdown/loggedDb<.executeCached.value<@resource://gre/modules/Sqlite.jsm:368:51 20:39:16 INFO - 2015-10-22 20:39:16,540 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 20:39:16 INFO - 2015-10-22 20:39:16,540 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 20:39:16 INFO - 2015-10-22 20:39:16,540 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 20:39:16 INFO - 2015-10-22 20:39:16,540 DEBUG : BROWSER_OUTPUT: transaction@resource://gre/modules/Bookmarks.jsm:783:15 20:39:16 INFO - 2015-10-22 20:39:16,540 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:314:40 20:39:16 INFO - 2015-10-22 20:39:16,540 DEBUG : BROWSER_OUTPUT: TaskImpl@resource://gre/modules/Task.jsm:275:3 20:39:16 INFO - 2015-10-22 20:39:16,540 DEBUG : BROWSER_OUTPUT: createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:249:14 20:39:16 INFO - 2015-10-22 20:39:16,540 DEBUG : BROWSER_OUTPUT: Task_spawn@resource://gre/modules/Task.jsm:164:12 20:39:16 INFO - 2015-10-22 20:39:16,540 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promisedromaeo_css: 6528.46 20:40:53 INFO - RETURN: kraken: 1597.30 20:40:53 INFO - RETURN: v8_7: 20784.46 20:40:53 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,25]]", "result": "20784.46"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,25]]", "result": "1597.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,25]]", "result": "6528.46"}}} 20:40:53 INFO - Return code: 0 20:40:53 INFO - # TBPL SUCCESS # 20:40:53 INFO - Running post-action listener: _resource_record_post_action 20:40:53 INFO - Running post-run listener: _resource_record_post_run 20:40:54 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 76588544; Write bytes: 432118272; Read time: 1283530; Write time: 4588730 20:40:54 INFO - install - Wall time: 2s; CPU: 9.0%; Read bytes: 49152; Write bytes: 17147904; Read time: 48470; Write time: 392600 20:40:54 INFO - run-tests - Wall time: 1224s; CPU: 13.0%; Read bytes: 76539392; Write bytes: 402859008; Read time: 1235060; Write time: 4154140 20:40:54 INFO - Running post-run listener: _upload_blobber_files 20:40:54 INFO - Blob upload gear active. 20:40:54 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 20:40:54 INFO - Copying logs to upload dir... 20:40:54 INFO - mkdir: C:\slave\test-pgo\build\upload\logs program finished with exit code 0 elapsedTime=1455.258000 ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 24 mins, 54 secs) (at 2015-10-22 20:41:32.859002) ========= ========= Started set props: build_url (results: 0, elapsed: 1 secs) (at 2015-10-22 20:41:32.863274) ========= '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-092 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-092 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-092 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/leEzJRuGRReUyB9O23bq3w/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/leEzJRuGRReUyB9O23bq3w/artifacts/public/build/firefox-44.0a1.en-US.win32.zip' ========= Finished set props: build_url (results: 0, elapsed: 1 secs) (at 2015-10-22 20:41:33.910063) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 9 secs) (at 2015-10-22 20:41:33.910364) ========= '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-092 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-092 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-092 USERNAME=cltbld USERPROFILE=C:\Users\cltbld WINDIR=C:\windows WINDOWS_TRACING_FLAGS=3 WINDOWS_TRACING_LOGFILE=C:\BVTBin\Tests\installpackage\csilogfile.log XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.101000 ========= Finished 'rm -f ...' (results: 0, elapsed: 9 secs) (at 2015-10-22 20:41:43.313011) ========= ========= Started reboot slave lost (results: 0, elapsed: 9 secs) (at 2015-10-22 20:41:43.313311) ========= ========= Finished reboot slave lost (results: 0, elapsed: 9 secs) (at 2015-10-22 20:41:52.564426) =========