builder: fx-team_xp-ix_test-g2 slave: t-xp32-ix-143 starttime: 1447941151.36 results: success (0) buildid: 20151119042020 builduid: d1c7e1fcb9d947bead57838aeca77b56 revision: bc29e2e5fac327b36b25e8d38107e09adc0a91b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:31.361743) ========= master: http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:31.362529) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:31.362837) ========= 'cd' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['cd'] environment: ALLUSERSPROFILE=C:\Documents and Settings\All Users APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-143\Application Data COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-XP32-IX-143 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=\Documents and Settings\cltbld.T-XP32-IX-143 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOGONSERVER=\\T-XP32-IX-143 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;.PSC1 PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMFILES=C:\Program Files PROMPT=$P$G PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SESSIONNAME=Console SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\WINDOWS TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp TEST1=testie TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp USERDOMAIN=T-XP32-IX-143 USERNAME=cltbld USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-143 WINDIR=C:\WINDOWS XPCOM_DEBUG_BREAK=warn using PTY: False C:\slave\test program finished with exit code 0 elapsedTime=0.250000 basedir: 'C:\\slave\\test' ========= master_lag: 0.10 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:31.716081) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:31.716352) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:31.775983) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:31.776357) ========= 'rm' '-rf' 'properties' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: ALLUSERSPROFILE=C:\Documents and Settings\All Users APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-143\Application Data COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-XP32-IX-143 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=\Documents and Settings\cltbld.T-XP32-IX-143 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOGONSERVER=\\T-XP32-IX-143 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;.PSC1 PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMFILES=C:\Program Files PROMPT=$P$G PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SESSIONNAME=Console SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\WINDOWS TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp TEST1=testie TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp USERDOMAIN=T-XP32-IX-143 USERNAME=cltbld USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-143 WINDIR=C:\WINDOWS XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.109000 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:31.895109) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:31.895372) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:31.895714) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:31.895987) ========= 'bash' '-c' 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: ALLUSERSPROFILE=C:\Documents and Settings\All Users APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-143\Application Data COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-XP32-IX-143 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=\Documents and Settings\cltbld.T-XP32-IX-143 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOGONSERVER=\\T-XP32-IX-143 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;.PSC1 PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMFILES=C:\Program Files PROMPT=$P$G PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SESSIONNAME=Console SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\WINDOWS TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp TEST1=testie TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp USERDOMAIN=T-XP32-IX-143 USERNAME=cltbld USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-143 WINDIR=C:\WINDOWS XPCOM_DEBUG_BREAK=warn using PTY: False --05:52:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py => `archiver_client.py' Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12,141 (12K) [text/x-python] 0K .......... . 100% 7.47 MB/s 05:52:32 (7.47 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.813000 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:32.718789) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-11-19 05:52:32.719115) ========= 'rm' '-rf' 'scripts' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: ALLUSERSPROFILE=C:\Documents and Settings\All Users APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-143\Application Data COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-XP32-IX-143 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=\Documents and Settings\cltbld.T-XP32-IX-143 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOGONSERVER=\\T-XP32-IX-143 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;.PSC1 PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMFILES=C:\Program Files PROMPT=$P$G PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SESSIONNAME=Console SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\WINDOWS TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp TEST1=testie TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp USERDOMAIN=T-XP32-IX-143 USERNAME=cltbld USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-143 WINDIR=C:\WINDOWS XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=2.734000 ========= master_lag: 0.00 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 2 secs) (at 2015-11-19 05:52:35.453294) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 05:52:35.453682) ========= 'bash' '-c' u'python archiver_client.py mozharness --repo integration/fx-team --rev bc29e2e5fac327b36b25e8d38107e09adc0a91b9 --destination scripts --debug' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev bc29e2e5fac327b36b25e8d38107e09adc0a91b9 --destination scripts --debug'] environment: ALLUSERSPROFILE=C:\Documents and Settings\All Users APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-143\Application Data COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-XP32-IX-143 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=\Documents and Settings\cltbld.T-XP32-IX-143 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOGONSERVER=\\T-XP32-IX-143 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;.PSC1 PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMFILES=C:\Program Files PROMPT=$P$G PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SESSIONNAME=Console SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\WINDOWS TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp TEST1=testie TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp USERDOMAIN=T-XP32-IX-143 USERNAME=cltbld USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-143 WINDIR=C:\WINDOWS XPCOM_DEBUG_BREAK=warn using PTY: False 2015-11-19 05:52:35,792 truncating revision to first 12 chars 2015-11-19 05:52:35,792 Setting DEBUG logging. 2015-11-19 05:52:35,792 attempt 1/10 2015-11-19 05:52:35,792 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/bc29e2e5fac3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-19 05:52:36,153 unpacking tar archive at: fx-team-bc29e2e5fac3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.234000 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 05:52:36.740270) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:36.740580) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:36.753100) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:36.753365) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 05:52:36.753820) ========= ========= Started 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 9 mins, 45 secs) (at 2015-11-19 05:52:36.754124) ========= 'c:/mozilla-build/python27/python' '-u' 'scripts/scripts/talos_script.py' '--suite' 'g2' '--add-option' '--webServer,localhost' '--branch-name' 'Fx-Team-Non-PGO' '--system-bits' '32' '--cfg' 'talos/windows_config.py' '--download-symbols' 'ondemand' '--use-talos-json' '--blob-upload-branch' 'Fx-Team-Non-PGO' in dir C:\slave\test\. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['c:/mozilla-build/python27/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'g2', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '32', '--cfg', 'talos/windows_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: ALLUSERSPROFILE=C:\Documents and Settings\All Users APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-143\Application Data COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-XP32-IX-143 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=\Documents and Settings\cltbld.T-XP32-IX-143 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOGONSERVER=\\T-XP32-IX-143 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;.PSC1 PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMFILES=C:\Program Files PROMPT=$P$G PROPERTIES_FILE=C:\slave\test/buildprops.json PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SESSIONNAME=Console SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\WINDOWS TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp TEST1=testie TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp USERDOMAIN=T-XP32-IX-143 USERNAME=cltbld USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-143 WINDIR=C:\WINDOWS XPCOM_DEBUG_BREAK=warn using PTY: False 05:52:37 INFO - MultiFileLogger online at 20151119 05:52:37 in C:\slave\test 05:52:37 INFO - Run as scripts/scripts/talos_script.py --suite g2 --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 32 --cfg talos/windows_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 05:52:37 INFO - Dumping config to C:\slave\test\logs\localconfig.json. 05:52:37 INFO - {'append_to_log': False, 05:52:37 INFO - 'base_work_dir': 'C:\\slave\\test', 05:52:37 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 05:52:37 INFO - 'blob_uploader_auth_file': 'C:\\slave\\test\\oauth.txt', 05:52:37 INFO - 'branch': 'Fx-Team-Non-PGO', 05:52:37 INFO - 'buildbot_json_path': 'buildprops.json', 05:52:37 INFO - 'config_files': ('talos/windows_config.py',), 05:52:37 INFO - 'default_actions': ('clobber', 05:52:37 INFO - 'read-buildbot-config', 05:52:37 INFO - 'download-and-extract', 05:52:37 INFO - 'populate-webroot', 05:52:37 INFO - 'create-virtualenv', 05:52:37 INFO - 'install', 05:52:37 INFO - 'run-tests'), 05:52:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:52:37 INFO - 'download_minidump_stackwalk': True, 05:52:37 INFO - 'download_symbols': 'ondemand', 05:52:37 INFO - 'exes': {'easy_install': ('C:\\slave\\test\\build/venv/scripts/python', 05:52:37 INFO - 'C:\\slave\\test\\build/venv/scripts/easy_install-2.7-script.py'), 05:52:37 INFO - 'hg': 'c:/mozilla-build/hg/hg', 05:52:37 INFO - 'mozinstall': ('C:\\slave\\test\\build/venv/scripts/python', 05:52:37 INFO - 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py'), 05:52:37 INFO - 'python': 'c:/mozilla-build/python27/python.exe', 05:52:37 INFO - 'tooltool.py': ('c:/mozilla-build/python27/python.exe', 05:52:37 INFO - 'C:/mozilla-build/tooltool.py'), 05:52:37 INFO - 'virtualenv': ('c:/mozilla-build/python27/python.exe', 05:52:37 INFO - 'c:/mozilla-build/buildbotve/virtualenv.py')}, 05:52:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:52:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:52:37 INFO - 'installer_path': 'installer.exe', 05:52:37 INFO - 'log_level': 'info', 05:52:37 INFO - 'log_name': 'talos', 05:52:37 INFO - 'log_to_console': True, 05:52:37 INFO - 'metro_harness_path_frmt': '%(metro_base_path)s/metro/metrotestharness.exe', 05:52:37 INFO - 'minidump_stackwalk_path': 'win32-minidump_stackwalk.exe', 05:52:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/win32/releng.manifest', 05:52:37 INFO - 'opt_config_files': (), 05:52:37 INFO - 'pip_index': False, 05:52:37 INFO - 'sps_profile': False, 05:52:37 INFO - 'sps_profile_interval': 0, 05:52:37 INFO - 'suite': 'g2', 05:52:37 INFO - 'system_bits': '32', 05:52:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:52:37 INFO - 'title': 'T-XP32-IX-143', 05:52:37 INFO - 'use_talos_json': True, 05:52:37 INFO - 'virtualenv_modules': ('pywin32', 'talos', 'mozinstall'), 05:52:37 INFO - 'virtualenv_path': 'C:\\slave\\test\\build/venv', 05:52:37 INFO - 'virtualenv_python_dll': 'c:/mozilla-build/python27/python27.dll', 05:52:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:52:37 INFO - 'work_dir': 'build'} 05:52:37 INFO - ##### 05:52:37 INFO - ##### Running clobber step. 05:52:37 INFO - ##### 05:52:37 INFO - Running pre-action listener: _resource_record_pre_action 05:52:37 INFO - Running main action method: clobber 05:52:37 INFO - rmtree: C:\slave\test\build 05:52:37 INFO - Using _rmtree_windows ... 05:52:37 INFO - retry: Calling _rmtree_windows with args: ('C:\\slave\\test\\build',), kwargs: {}, attempt #1 05:53:36 INFO - Running post-action listener: _resource_record_post_action 05:53:36 INFO - ##### 05:53:36 INFO - ##### Running read-buildbot-config step. 05:53:36 INFO - ##### 05:53:36 INFO - Running pre-action listener: _resource_record_pre_action 05:53:36 INFO - Running main action method: read_buildbot_config 05:53:36 INFO - Using buildbot properties: 05:53:36 INFO - { 05:53:36 INFO - "properties": { 05:53:36 INFO - "buildnumber": 650, 05:53:36 INFO - "product": "firefox", 05:53:36 INFO - "script_repo_revision": "production", 05:53:36 INFO - "builddir": "fx-team_xp-ix_test-g2", 05:53:36 INFO - "repository": "", 05:53:36 INFO - "buildername": "Windows XP 32-bit fx-team talos g2", 05:53:36 INFO - "buildid": "20151119042020", 05:53:36 INFO - "slavename": "t-xp32-ix-143", 05:53:36 INFO - "pgo_build": "False", 05:53:36 INFO - "basedir": "C:\\slave\\test", 05:53:36 INFO - "project": "", 05:53:36 INFO - "platform": "xp-ix", 05:53:36 INFO - "master": "http://buildbot-master119.bb.releng.scl3.mozilla.com:8201/", 05:53:36 INFO - "slavebuilddir": "test", 05:53:36 INFO - "scheduler": "tests-fx-team-win32-talos", 05:53:36 INFO - "branch": "fx-team", 05:53:36 INFO - "repo_path": "integration/fx-team", 05:53:36 INFO - "stage_platform": "win32", 05:53:36 INFO - "builduid": "d1c7e1fcb9d947bead57838aeca77b56", 05:53:36 INFO - "revision": "bc29e2e5fac327b36b25e8d38107e09adc0a91b9" 05:53:36 INFO - }, 05:53:36 INFO - "sourcestamp": { 05:53:36 INFO - "repository": "", 05:53:36 INFO - "hasPatch": false, 05:53:36 INFO - "project": "", 05:53:36 INFO - "branch": "fx-team-win32-talos", 05:53:36 INFO - "changes": [ 05:53:36 INFO - { 05:53:36 INFO - "category": null, 05:53:36 INFO - "files": [ 05:53:36 INFO - { 05:53:36 INFO - "url": null, 05:53:36 INFO - "name": "https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip" 05:53:36 INFO - } 05:53:36 INFO - ], 05:53:36 INFO - "repository": "", 05:53:36 INFO - "rev": "bc29e2e5fac327b36b25e8d38107e09adc0a91b9", 05:53:36 INFO - "who": "nigelbabu@gmail.com", 05:53:36 INFO - "when": 1447941084, 05:53:36 INFO - "number": 6682462, 05:53:36 INFO - "comments": "Backed out 8 changesets (bug 1171708) for bustage in it's own tests ON A CLOSED TREE\n\nBacked out changeset cae56980cbec (bug 1171708)\nBacked out changeset c60c33f9b573 (bug 1171708)\nBacked out changeset 96830bc690b6 (bug 1171708)\nBacked out changeset e1fcd236bdd3 (bug 1171708)\nBacked out changeset 9a48c1d22248 (bug 1171708)\nBacked out changeset 020345f46fbd (bug 1171708)\nBacked out changeset 6098e098d9eb (bug 1171708)\nBacked out changeset c67eefd6cf4d (bug 1171708)", 05:53:36 INFO - "project": "", 05:53:36 INFO - "at": "Thu 19 Nov 2015 05:51:24", 05:53:36 INFO - "branch": "fx-team-win32-talos", 05:53:36 INFO - "revlink": "", 05:53:36 INFO - "properties": [ 05:53:36 INFO - [ 05:53:36 INFO - "buildid", 05:53:36 INFO - "20151119042020", 05:53:36 INFO - "Change" 05:53:36 INFO - ], 05:53:36 INFO - [ 05:53:36 INFO - "builduid", 05:53:36 INFO - "d1c7e1fcb9d947bead57838aeca77b56", 05:53:36 INFO - "Change" 05:53:36 INFO - ], 05:53:36 INFO - [ 05:53:36 INFO - "pgo_build", 05:53:36 INFO - "False", 05:53:36 INFO - "Change" 05:53:36 INFO - ] 05:53:36 INFO - ], 05:53:36 INFO - "revision": "bc29e2e5fac327b36b25e8d38107e09adc0a91b9" 05:53:36 INFO - } 05:53:36 INFO - ], 05:53:36 INFO - "revision": "bc29e2e5fac327b36b25e8d38107e09adc0a91b9" 05:53:36 INFO - } 05:53:36 INFO - } 05:53:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip. 05:53:36 INFO - Running post-action listener: _resource_record_post_action 05:53:36 INFO - ##### 05:53:36 INFO - ##### Running download-and-extract step. 05:53:36 INFO - ##### 05:53:36 INFO - Running pre-action listener: _resource_record_pre_action 05:53:36 INFO - Running main action method: download_and_extract 05:53:36 INFO - mkdir: C:\slave\test\build\tests 05:53:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:53:36 INFO - https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:53:36 INFO - trying https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/test_packages.json 05:53:36 INFO - Downloading https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/test_packages.json to C:\slave\test\build\test_packages.json 05:53:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/test_packages.json', 'file_name': 'C:\\slave\\test\\build\\test_packages.json'}, attempt #1 05:53:42 INFO - Downloaded 1221 bytes. 05:53:42 INFO - Reading from file C:\slave\test\build\test_packages.json 05:53:42 INFO - Using the following test package requirements: 05:53:42 INFO - {u'common': [u'firefox-45.0a1.en-US.win32.common.tests.zip'], 05:53:42 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.win32.common.tests.zip', 05:53:42 INFO - u'firefox-45.0a1.en-US.win32.cppunittest.tests.zip'], 05:53:42 INFO - u'jittest': [u'firefox-45.0a1.en-US.win32.common.tests.zip', 05:53:42 INFO - u'jsshell-win32.zip'], 05:53:42 INFO - u'mochitest': [u'firefox-45.0a1.en-US.win32.common.tests.zip', 05:53:42 INFO - u'firefox-45.0a1.en-US.win32.mochitest.tests.zip'], 05:53:42 INFO - u'mozbase': [u'firefox-45.0a1.en-US.win32.common.tests.zip'], 05:53:42 INFO - u'reftest': [u'firefox-45.0a1.en-US.win32.common.tests.zip', 05:53:42 INFO - u'firefox-45.0a1.en-US.win32.reftest.tests.zip'], 05:53:42 INFO - u'talos': [u'firefox-45.0a1.en-US.win32.common.tests.zip', 05:53:42 INFO - u'firefox-45.0a1.en-US.win32.talos.tests.zip'], 05:53:42 INFO - u'web-platform': [u'firefox-45.0a1.en-US.win32.common.tests.zip', 05:53:42 INFO - u'firefox-45.0a1.en-US.win32.web-platform.tests.zip'], 05:53:42 INFO - u'webapprt': [u'firefox-45.0a1.en-US.win32.common.tests.zip'], 05:53:42 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.win32.common.tests.zip', 05:53:42 INFO - u'firefox-45.0a1.en-US.win32.xpcshell.tests.zip']} 05:53:42 INFO - Downloading packages: [u'firefox-45.0a1.en-US.win32.common.tests.zip'] for test suite category: common 05:53:42 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')]} 05:53:42 INFO - https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 05:53:42 INFO - trying https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip 05:53:42 INFO - Downloading https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip to C:\slave\test\build\firefox-45.0a1.en-US.win32.common.tests.zip 05:53:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'}, attempt #1 05:53:44 INFO - Downloaded 18033112 bytes. 05:53:44 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'] in C:\slave\test\build\tests 05:53:44 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-45.0a1.en-US.win32.common.tests.zip 05:53:44 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 05:54:04 INFO - Return code: 0 05:54:04 INFO - Downloading packages: [u'firefox-45.0a1.en-US.win32.common.tests.zip', u'firefox-45.0a1.en-US.win32.talos.tests.zip'] for test suite category: talos 05:54:04 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')]} 05:54:04 INFO - https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip matches https://queue.taskcluster.net 05:54:04 INFO - trying https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip 05:54:04 INFO - Downloading https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip to C:\slave\test\build\firefox-45.0a1.en-US.win32.common.tests.zip 05:54:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.common.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'}, attempt #1 05:54:06 INFO - Downloaded 18033112 bytes. 05:54:06 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'] in C:\slave\test\build\tests 05:54:06 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-45.0a1.en-US.win32.common.tests.zip 05:54:06 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.common.tests.zip'] with output_timeout 1760 05:54:22 INFO - Return code: 0 05:54:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:22 INFO - https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.talos.tests.zip matches https://queue.taskcluster.net 05:54:22 INFO - trying https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.talos.tests.zip 05:54:22 INFO - Downloading https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.talos.tests.zip to C:\slave\test\build\firefox-45.0a1.en-US.win32.talos.tests.zip 05:54:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.talos.tests.zip', 'file_name': u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.talos.tests.zip'}, attempt #1 05:54:23 INFO - Downloaded 11130997 bytes. 05:54:23 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.talos.tests.zip'] in C:\slave\test\build\tests 05:54:23 INFO - Copy/paste: unzip -q -o C:\slave\test\build\firefox-45.0a1.en-US.win32.talos.tests.zip 05:54:23 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.talos.tests.zip'] with output_timeout 1760 05:54:24 INFO - Return code: 0 05:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:24 INFO - https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip matches https://queue.taskcluster.net 05:54:24 INFO - trying https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip 05:54:24 INFO - Downloading https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip to C:\slave\test\build\firefox-45.0a1.en-US.win32.zip 05:54:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip', 'file_name': 'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.zip'}, attempt #1 05:54:33 INFO - Downloaded 51670579 bytes. 05:54:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip 05:54:33 INFO - mkdir: C:\slave\test\properties 05:54:33 INFO - Writing buildbot properties ['build_url'] to C:\slave\test\properties\build_url 05:54:33 INFO - Writing to file C:\slave\test\properties\build_url 05:54:33 INFO - Contents: 05:54:33 INFO - build_url:https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip 05:54:33 INFO - Running post-action listener: _resource_record_post_action 05:54:33 INFO - Running post-action listener: set_extra_try_arguments 05:54:33 INFO - ##### 05:54:33 INFO - ##### Running populate-webroot step. 05:54:33 INFO - ##### 05:54:33 INFO - Running pre-action listener: _resource_record_pre_action 05:54:33 INFO - Running main action method: populate_webroot 05:54:33 INFO - {u'extra_options': {u'android': [u'--apkPath=%(apk_path)s']}, 05:54:33 INFO - u'mobile-suites': {u'remote-tp4m_nochrome': {u'tests': [u'tp4m']}, 05:54:33 INFO - u'remote-trobocheck2': {u'talos_options': [u'--fennecIDs', 05:54:33 INFO - u'../fennec_ids.txt'], 05:54:33 INFO - u'tests': [u'tcheck2']}, 05:54:33 INFO - u'remote-tsvgx': {u'talos_options': [u'--noChrome', 05:54:33 INFO - u'--tppagecycles', 05:54:33 INFO - u'7'], 05:54:33 INFO - u'tests': [u'tsvgm']}}, 05:54:33 INFO - u'suites': {u'chromez': {u'tests': [u'tresize', u'tcanvasmark']}, 05:54:33 INFO - u'chromez-e10s': {u'talos_options': [u'--e10s'], 05:54:33 INFO - u'tests': [u'tresize', u'tcanvasmark']}, 05:54:33 INFO - u'dromaeojs': {u'tests': [u'dromaeo_css', u'kraken', u'v8_7']}, 05:54:33 INFO - u'dromaeojs-e10s': {u'talos_options': [u'--e10s'], 05:54:33 INFO - u'tests': [u'dromaeo_css', 05:54:33 INFO - u'kraken', 05:54:33 INFO - u'v8_7']}, 05:54:33 INFO - u'g1': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:33 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 05:54:33 INFO - u'g1-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:33 INFO - u'talos_options': [u'--e10s'], 05:54:33 INFO - u'tests': [u'tp5o_scroll', u'glterrain']}, 05:54:33 INFO - u'g2': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:33 INFO - u'tests': [u'damp', u'tps']}, 05:54:33 INFO - u'g2-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:33 INFO - u'talos_options': [u'--e10s'], 05:54:33 INFO - u'tests': [u'damp', u'tps']}, 05:54:33 INFO - u'g3': {u'tests': [u'dromaeo_dom']}, 05:54:33 INFO - u'g3-e10s': {u'tests': [u'dromaeo_dom']}, 05:54:33 INFO - u'other': {u'tests': [u'a11yr', 05:54:33 INFO - u'ts_paint', 05:54:33 INFO - u'tpaint', 05:54:33 INFO - u'sessionrestore', 05:54:33 INFO - u'sessionrestore_no_auto_restore']}, 05:54:33 INFO - u'other-e10s': {u'talos_options': [u'--e10s'], 05:54:33 INFO - u'tests': [u'a11yr', 05:54:33 INFO - u'ts_paint', 05:54:33 INFO - u'tpaint', 05:54:33 INFO - u'sessionrestore', 05:54:33 INFO - u'sessionrestore_no_auto_restore']}, 05:54:33 INFO - u'other-e10s_l64': {u'talos_options': [u'--e10s'], 05:54:33 INFO - u'tests': [u'a11yr', 05:54:33 INFO - u'ts_paint', 05:54:33 INFO - u'tpaint', 05:54:33 INFO - u'sessionrestore', 05:54:33 INFO - u'sessionrestore_no_auto_restore']}, 05:54:33 INFO - u'other-e10s_nol64': {u'talos_options': [u'--e10s'], 05:54:33 INFO - u'tests': [u'a11yr', 05:54:33 INFO - u'ts_paint', 05:54:33 INFO - u'tpaint', 05:54:33 INFO - u'sessionrestore', 05:54:33 INFO - u'sessionrestore_no_auto_restore']}, 05:54:33 INFO - u'other_l64': {u'tests': [u'a11yr', 05:54:33 INFO - u'ts_paint', 05:54:33 INFO - u'tpaint', 05:54:33 INFO - u'sessionrestore', 05:54:33 INFO - u'sessionrestore_no_auto_restore']}, 05:54:33 INFO - u'other_nol64': {u'tests': [u'a11yr', 05:54:33 INFO - u'ts_paint', 05:54:33 INFO - u'tpaint', 05:54:33 INFO - u'sessionrestore', 05:54:33 INFO - u'sessionrestore_no_auto_restore']}, 05:54:33 INFO - u'svgr': {u'tests': [u'tsvgx', 05:54:33 INFO - u'tsvgr_opacity', 05:54:33 INFO - u'tart', 05:54:33 INFO - u'tscrollx', 05:54:33 INFO - u'cart']}, 05:54:33 INFO - u'svgr-e10s': {u'talos_options': [u'--e10s'], 05:54:33 INFO - u'tests': [u'tsvgx', 05:54:33 INFO - u'tsvgr_opacity', 05:54:33 INFO - u'tart', 05:54:33 INFO - u'tscrollx', 05:54:33 INFO - u'cart']}, 05:54:33 INFO - u'tp5o': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:33 INFO - u'tests': [u'tp5o']}, 05:54:33 INFO - u'tp5o-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:33 INFO - u'talos_options': [u'--e10s'], 05:54:33 INFO - u'tests': [u'tp5o']}, 05:54:33 INFO - u'xperf': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:33 INFO - u'talos_options': [u'--xperf_path', 05:54:33 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:33 INFO - u'tests': [u'tp5n']}, 05:54:33 INFO - u'xperf-e10s': {u'pagesets_url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:54:33 INFO - u'talos_options': [u'--e10s', 05:54:33 INFO - u'--xperf_path', 05:54:33 INFO - u'"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:54:33 INFO - u'tests': [u'tp5n']}}, 05:54:33 INFO - u'talos.zip': {u'path': u'', 05:54:33 INFO - u'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:54:33 INFO - Downloading pageset... 05:54:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:54:33 INFO - trying http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip 05:54:33 INFO - Downloading http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip to C:\slave\test\build\tp5n.zip 05:54:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 'file_name': u'C:\\slave\\test\\build\\tp5n.zip'}, attempt #1 05:54:35 INFO - Downloaded 81753769 bytes. 05:54:35 INFO - Running command: ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\tp5n.zip'] in C:\slave\test\build\tests\talos\talos\tests 05:54:35 INFO - Copy/paste: unzip -q -o C:\slave\test\build\tp5n.zip 05:54:35 INFO - Calling ['unzip', '-q', '-o', u'C:\\slave\\test\\build\\tp5n.zip'] with output_timeout 1760 05:55:08 INFO - Return code: 0 05:55:09 INFO - Running post-action listener: _resource_record_post_action 05:55:09 INFO - ##### 05:55:09 INFO - ##### Running create-virtualenv step. 05:55:09 INFO - ##### 05:55:09 INFO - Running pre-action listener: _resource_record_pre_action 05:55:09 INFO - Running main action method: create_virtualenv 05:55:09 INFO - Creating virtualenv C:\slave\test\build/venv 05:55:09 INFO - mkdir: C:\slave\test\build\venv\Scripts 05:55:09 INFO - Copying c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll 05:55:09 WARNING - Can't copy c:/mozilla-build/python27/python27.dll to C:\slave\test\build\venv\Scripts\python27.dll: [Errno 2] No such file or directory: 'c:/mozilla-build/python27/python27.dll'! 05:55:09 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'c:/mozilla-build/buildbotve/virtualenv.py', '--no-site-packages', '--distribute', 'C:\\slave\\test\\build/venv'] in C:\slave\test\build 05:55:09 INFO - Copy/paste: c:/mozilla-build/python27/python.exe c:/mozilla-build/buildbotve/virtualenv.py --no-site-packages --distribute C:\slave\test\build/venv 05:55:10 INFO - New python executable in C:\slave\test\build/venv\Scripts\python.exe 05:55:12 INFO - Installing distribute......................................................................................................................................................................................done. 05:55:12 INFO - Return code: 0 05:55:12 INFO - Installing pip>=1.5 into virtualenv C:\slave\test\build/venv 05:55:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:12 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--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 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C97E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D9D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-143', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-143', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-143', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143'}}, attempt #1 05:55:13 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in C:\slave\test\build 05:55:13 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:55:13 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 05:55:13 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 05:55:13 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:55:13 INFO - 'COMPUTERNAME': 'T-XP32-IX-143', 05:55:13 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 05:55:13 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:55:13 INFO - 'DCLOCATION': 'SCL3', 05:55:13 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:55:13 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:55:13 INFO - 'HOMEDRIVE': 'C:', 05:55:13 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:13 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:55:13 INFO - 'KTS_VERSION': '1.19c', 05:55:13 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-143', 05:55:13 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:55:13 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:55:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:13 INFO - 'MOZ_NO_REMOTE': '1', 05:55:13 INFO - 'NO_EM_RESTART': '1', 05:55:13 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:55:13 INFO - 'OS': 'Windows_NT', 05:55:13 INFO - 'OURDRIVE': 'C:', 05:55:13 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;', 05:55:13 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 05:55:13 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:55:13 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:55:13 INFO - 'PROCESSOR_LEVEL': '6', 05:55:13 INFO - 'PROCESSOR_REVISION': '1e05', 05:55:13 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:55:13 INFO - 'PROMPT': '$P$G', 05:55:13 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:55:13 INFO - 'PWD': 'C:\\slave\\test', 05:55:13 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:55:13 INFO - 'SESSIONNAME': 'Console', 05:55:13 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:55:13 INFO - 'SYSTEMDRIVE': 'C:', 05:55:13 INFO - 'SYSTEMROOT': 'C:\\WINDOWS', 05:55:13 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:13 INFO - 'TEST1': 'testie', 05:55:13 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:13 INFO - 'USERDOMAIN': 'T-XP32-IX-143', 05:55:13 INFO - 'USERNAME': 'cltbld', 05:55:13 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:13 INFO - 'WINDIR': 'C:\\WINDOWS', 05:55:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:55:13 INFO - Ignoring indexes: http://pypi.python.org/simple/ 05:55:13 INFO - Downloading/unpacking pip>=1.5 05:55:13 INFO - Creating supposed download cache at C:\slave\test\build/venv\cache 05:55:16 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpip-1.5.5.tar.gz 05:55:16 INFO - Running setup.py egg_info for package pip 05:55:16 INFO - warning: no files found matching 'pip\cacert.pem' 05:55:16 INFO - warning: no files found matching '*.html' under directory 'docs' 05:55:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 05:55:16 INFO - no previously-included directories found matching 'docs\_build\_sources' 05:55:16 INFO - Installing collected packages: pip 05:55:16 INFO - Found existing installation: pip 0.8.2 05:55:16 INFO - Uninstalling pip: 05:55:16 INFO - Successfully uninstalled pip 05:55:16 INFO - Running setup.py install for pip 05:55:16 INFO - warning: no files found matching 'pip\cacert.pem' 05:55:16 INFO - warning: no files found matching '*.html' under directory 'docs' 05:55:16 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs\_build' 05:55:16 INFO - no previously-included directories found matching 'docs\_build\_sources' 05:55:16 INFO - Installing pip-script.py script to C:\slave\test\build\venv\Scripts 05:55:16 INFO - Installing pip.exe script to C:\slave\test\build\venv\Scripts 05:55:16 INFO - Installing pip2.7-script.py script to C:\slave\test\build\venv\Scripts 05:55:16 INFO - Installing pip2.7.exe script to C:\slave\test\build\venv\Scripts 05:55:16 INFO - Installing pip2-script.py script to C:\slave\test\build\venv\Scripts 05:55:16 INFO - Installing pip2.exe script to C:\slave\test\build\venv\Scripts 05:55:16 INFO - Successfully installed pip 05:55:16 INFO - Cleaning up... 05:55:16 INFO - Return code: 0 05:55:16 INFO - Installing psutil>=0.7.1 into virtualenv C:\slave\test\build/venv 05:55: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')]} 05:55:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55: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')]} 05:55:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:16 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C97E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D9D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-143', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-143', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-143', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143'}}, attempt #1 05:55:16 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in C:\slave\test\build 05:55:16 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:55:16 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 05:55:16 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 05:55:16 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:55:16 INFO - 'COMPUTERNAME': 'T-XP32-IX-143', 05:55:16 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 05:55:16 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:55:16 INFO - 'DCLOCATION': 'SCL3', 05:55:16 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:55:16 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:55:16 INFO - 'HOMEDRIVE': 'C:', 05:55:16 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:16 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:55:16 INFO - 'KTS_VERSION': '1.19c', 05:55:16 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-143', 05:55:16 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:55:16 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:55:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:16 INFO - 'MOZ_NO_REMOTE': '1', 05:55:16 INFO - 'NO_EM_RESTART': '1', 05:55:16 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:55:16 INFO - 'OS': 'Windows_NT', 05:55:16 INFO - 'OURDRIVE': 'C:', 05:55:16 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 05:55:16 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 05:55:16 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:55:16 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:55:16 INFO - 'PROCESSOR_LEVEL': '6', 05:55:16 INFO - 'PROCESSOR_REVISION': '1e05', 05:55:16 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:55:16 INFO - 'PROMPT': '$P$G', 05:55:16 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:55:16 INFO - 'PWD': 'C:\\slave\\test', 05:55:16 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:55:16 INFO - 'SESSIONNAME': 'Console', 05:55:16 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:55:16 INFO - 'SYSTEMDRIVE': 'C:', 05:55:16 INFO - 'SYSTEMROOT': 'C:\\WINDOWS', 05:55:16 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:16 INFO - 'TEST1': 'testie', 05:55:16 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:16 INFO - 'USERDOMAIN': 'T-XP32-IX-143', 05:55:16 INFO - 'USERNAME': 'cltbld', 05:55:16 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:16 INFO - 'WINDIR': 'C:\\WINDOWS', 05:55:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:55:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:17 INFO - Downloading/unpacking psutil>=0.7.1 05:55:17 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 05:55:17 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 05:55:18 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fpsutil-3.1.1-cp27-none-win32.whl 05:55:18 INFO - Installing collected packages: psutil 05:55:18 INFO - Successfully installed psutil 05:55:18 INFO - Cleaning up... 05:55:18 INFO - Return code: 0 05:55:18 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv C:\slave\test\build/venv 05:55: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')]} 05:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55: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')]} 05:55:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:18 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C97E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D9D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-143', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-143', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-143', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143'}}, attempt #1 05:55:18 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in C:\slave\test\build 05:55:18 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:55:18 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 05:55:18 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 05:55:18 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:55:18 INFO - 'COMPUTERNAME': 'T-XP32-IX-143', 05:55:18 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 05:55:18 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:55:18 INFO - 'DCLOCATION': 'SCL3', 05:55:18 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:55:18 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:55:18 INFO - 'HOMEDRIVE': 'C:', 05:55:18 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:18 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:55:18 INFO - 'KTS_VERSION': '1.19c', 05:55:18 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-143', 05:55:18 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:55:18 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:55:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:18 INFO - 'MOZ_NO_REMOTE': '1', 05:55:18 INFO - 'NO_EM_RESTART': '1', 05:55:18 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:55:18 INFO - 'OS': 'Windows_NT', 05:55:18 INFO - 'OURDRIVE': 'C:', 05:55: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;', 05:55:18 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 05:55:18 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:55:18 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:55:18 INFO - 'PROCESSOR_LEVEL': '6', 05:55:18 INFO - 'PROCESSOR_REVISION': '1e05', 05:55:18 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:55:18 INFO - 'PROMPT': '$P$G', 05:55:18 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:55:18 INFO - 'PWD': 'C:\\slave\\test', 05:55:18 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:55:18 INFO - 'SESSIONNAME': 'Console', 05:55:18 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:55:18 INFO - 'SYSTEMDRIVE': 'C:', 05:55:18 INFO - 'SYSTEMROOT': 'C:\\WINDOWS', 05:55:18 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:18 INFO - 'TEST1': 'testie', 05:55:18 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:18 INFO - 'USERDOMAIN': 'T-XP32-IX-143', 05:55:18 INFO - 'USERNAME': 'cltbld', 05:55:18 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:18 INFO - 'WINDIR': 'C:\\WINDOWS', 05:55:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:55:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:18 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:55:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:55:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:55:19 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fmozsystemmonitor-0.0.tar.gz 05:55:19 INFO - Running setup.py (path:C:\slave\test\build\venv\build\mozsystemmonitor\setup.py) egg_info for package mozsystemmonitor 05:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in c:\slave\test\build\venv\lib\site-packages (from mozsystemmonitor==0.0.0) 05:55:19 INFO - Installing collected packages: mozsystemmonitor 05:55:19 INFO - Running setup.py install for mozsystemmonitor 05:55:20 INFO - Successfully installed mozsystemmonitor 05:55:20 INFO - Cleaning up... 05:55:20 INFO - Return code: 0 05:55:20 INFO - Installing blobuploader==1.2.4 into virtualenv C:\slave\test\build/venv 05:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:20 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C97E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D9D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-143', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-143', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-143', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143'}}, attempt #1 05:55:20 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in C:\slave\test\build 05:55:20 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:55:20 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 05:55:20 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 05:55:20 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:55:20 INFO - 'COMPUTERNAME': 'T-XP32-IX-143', 05:55:20 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 05:55:20 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:55:20 INFO - 'DCLOCATION': 'SCL3', 05:55:20 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:55:20 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:55:20 INFO - 'HOMEDRIVE': 'C:', 05:55:20 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:20 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:55:20 INFO - 'KTS_VERSION': '1.19c', 05:55:20 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-143', 05:55:20 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:55:20 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:20 INFO - 'MOZ_NO_REMOTE': '1', 05:55:20 INFO - 'NO_EM_RESTART': '1', 05:55:20 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:55:20 INFO - 'OS': 'Windows_NT', 05:55:20 INFO - 'OURDRIVE': 'C:', 05:55:20 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;', 05:55:20 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 05:55:20 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:55:20 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:55:20 INFO - 'PROCESSOR_LEVEL': '6', 05:55:20 INFO - 'PROCESSOR_REVISION': '1e05', 05:55:20 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:55:20 INFO - 'PROMPT': '$P$G', 05:55:20 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:55:20 INFO - 'PWD': 'C:\\slave\\test', 05:55:20 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:55:20 INFO - 'SESSIONNAME': 'Console', 05:55:20 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:55:20 INFO - 'SYSTEMDRIVE': 'C:', 05:55:20 INFO - 'SYSTEMROOT': 'C:\\WINDOWS', 05:55:20 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:20 INFO - 'TEST1': 'testie', 05:55:20 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:20 INFO - 'USERDOMAIN': 'T-XP32-IX-143', 05:55:20 INFO - 'USERNAME': 'cltbld', 05:55:20 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:20 INFO - 'WINDIR': 'C:\\WINDOWS', 05:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:20 INFO - Downloading/unpacking blobuploader==1.2.4 05:55: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 05:55: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 05:55:21 INFO - Downloading blobuploader-1.2.4.tar.gz 05:55:21 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblobuploader-1.2.4.tar.gz 05:55:21 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blobuploader\setup.py) egg_info for package blobuploader 05:55:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:55:21 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 05:55:21 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 05:55:22 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2frequests-1.2.3.tar.gz 05:55:22 INFO - Running setup.py (path:C:\slave\test\build\venv\build\requests\setup.py) egg_info for package requests 05:55:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:55:22 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 05:55:22 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 05:55:22 INFO - Downloading docopt-0.6.1.tar.gz 05:55:22 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fdocopt-0.6.1.tar.gz 05:55:22 INFO - Running setup.py (path:C:\slave\test\build\venv\build\docopt\setup.py) egg_info for package docopt 05:55:22 INFO - Installing collected packages: blobuploader, requests, docopt 05:55:22 INFO - Running setup.py install for blobuploader 05:55:23 INFO - Running setup.py install for requests 05:55:23 INFO - Running setup.py install for docopt 05:55:24 INFO - Successfully installed blobuploader requests docopt 05:55:24 INFO - Cleaning up... 05:55:24 INFO - Return code: 0 05:55:24 INFO - Installing None into virtualenv C:\slave\test\build/venv 05:55:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:24 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C97E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D9D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-143', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-143', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-143', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143'}}, attempt #1 05:55:24 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--no-deps', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 05:55:24 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --no-deps --download-cache C:\slave\test\build/venv\cache --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:24 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 05:55:24 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 05:55:24 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:55:24 INFO - 'COMPUTERNAME': 'T-XP32-IX-143', 05:55:24 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 05:55:24 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:55:24 INFO - 'DCLOCATION': 'SCL3', 05:55:24 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:55:24 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:55:24 INFO - 'HOMEDRIVE': 'C:', 05:55:24 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:24 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:55:24 INFO - 'KTS_VERSION': '1.19c', 05:55:24 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-143', 05:55:24 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:55:24 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:24 INFO - 'MOZ_NO_REMOTE': '1', 05:55:24 INFO - 'NO_EM_RESTART': '1', 05:55:24 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:55:24 INFO - 'OS': 'Windows_NT', 05:55:24 INFO - 'OURDRIVE': 'C:', 05:55:24 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;', 05:55:24 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 05:55:24 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:55:24 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:55:24 INFO - 'PROCESSOR_LEVEL': '6', 05:55:24 INFO - 'PROCESSOR_REVISION': '1e05', 05:55:24 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:55:24 INFO - 'PROMPT': '$P$G', 05:55:24 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:55:24 INFO - 'PWD': 'C:\\slave\\test', 05:55:24 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:55:24 INFO - 'SESSIONNAME': 'Console', 05:55:24 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:55:24 INFO - 'SYSTEMDRIVE': 'C:', 05:55:24 INFO - 'SYSTEMROOT': 'C:\\WINDOWS', 05:55:24 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:24 INFO - 'TEST1': 'testie', 05:55:24 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:24 INFO - 'USERDOMAIN': 'T-XP32-IX-143', 05:55:24 INFO - 'USERNAME': 'cltbld', 05:55:24 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:24 INFO - 'WINDIR': 'C:\\WINDOWS', 05:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:55:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:24 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 05:55:24 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-2x1hbc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 05:55:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 05:55:25 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-vozpxp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 05:55:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 05:55:25 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-hybgjg-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 05:55:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 05:55:25 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-a4myjh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 05:55:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 05:55:25 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-0gxdpj-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 05:55:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 05:55:25 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-evrkdh-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 05:55:25 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 05:55:25 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-eltvfy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 05:55:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 05:55:26 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-rdbjbx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 05:55:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 05:55:26 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-rkovyz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 05:55:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 05:55:26 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-10xfcn-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 05:55:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 05:55:26 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-nrhy4_-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 05:55:26 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 05:55:26 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-hrfmf0-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 05:55:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 05:55:27 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-llqwod-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 05:55:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 05:55:27 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ctnbiw-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 05:55:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 05:55:27 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-hciaol-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 05:55:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 05:55:27 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ubnifx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 05:55:27 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 05:55:27 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-pdp4wc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 05:55:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:55:28 INFO - Running setup.py install for manifestparser 05:55:28 INFO - Installing manifestparser-script.py script to C:\slave\test\build\venv\Scripts 05:55:28 INFO - Installing manifestparser.exe script to C:\slave\test\build\venv\Scripts 05:55:28 INFO - Running setup.py install for mozcrash 05:55:28 INFO - Running setup.py install for mozdebug 05:55:29 INFO - Running setup.py install for mozdevice 05:55:29 INFO - Installing sutini-script.py script to C:\slave\test\build\venv\Scripts 05:55:29 INFO - Installing sutini.exe script to C:\slave\test\build\venv\Scripts 05:55:29 INFO - Installing dm-script.py script to C:\slave\test\build\venv\Scripts 05:55:29 INFO - Installing dm.exe script to C:\slave\test\build\venv\Scripts 05:55:29 INFO - Running setup.py install for mozfile 05:55:29 INFO - Running setup.py install for mozhttpd 05:55:30 INFO - Installing mozhttpd-script.py script to C:\slave\test\build\venv\Scripts 05:55:30 INFO - Installing mozhttpd.exe script to C:\slave\test\build\venv\Scripts 05:55:30 INFO - Running setup.py install for mozinfo 05:55:30 INFO - Installing mozinfo-script.py script to C:\slave\test\build\venv\Scripts 05:55:30 INFO - Installing mozinfo.exe script to C:\slave\test\build\venv\Scripts 05:55:30 INFO - Running setup.py install for mozInstall 05:55:30 INFO - Installing moz_remove_from_system-script.py script to C:\slave\test\build\venv\Scripts 05:55:30 INFO - Installing moz_remove_from_system.exe script to C:\slave\test\build\venv\Scripts 05:55:30 INFO - Installing mozuninstall-script.py script to C:\slave\test\build\venv\Scripts 05:55:30 INFO - Installing mozuninstall.exe script to C:\slave\test\build\venv\Scripts 05:55:30 INFO - Installing mozinstall-script.py script to C:\slave\test\build\venv\Scripts 05:55:30 INFO - Installing mozinstall.exe script to C:\slave\test\build\venv\Scripts 05:55:30 INFO - Installing moz_add_to_system-script.py script to C:\slave\test\build\venv\Scripts 05:55:30 INFO - Installing moz_add_to_system.exe script to C:\slave\test\build\venv\Scripts 05:55:30 INFO - Running setup.py install for mozleak 05:55:31 INFO - Running setup.py install for mozlog 05:55:31 INFO - Installing structlog-script.py script to C:\slave\test\build\venv\Scripts 05:55:31 INFO - Installing structlog.exe script to C:\slave\test\build\venv\Scripts 05:55:31 INFO - Running setup.py install for moznetwork 05:55:31 INFO - Installing moznetwork-script.py script to C:\slave\test\build\venv\Scripts 05:55:31 INFO - Installing moznetwork.exe script to C:\slave\test\build\venv\Scripts 05:55:32 INFO - Running setup.py install for mozprocess 05:55:32 INFO - Running setup.py install for mozprofile 05:55:32 INFO - Installing mozprofile-script.py script to C:\slave\test\build\venv\Scripts 05:55:32 INFO - Installing mozprofile.exe script to C:\slave\test\build\venv\Scripts 05:55:32 INFO - Installing diff-profiles-script.py script to C:\slave\test\build\venv\Scripts 05:55:32 INFO - Installing diff-profiles.exe script to C:\slave\test\build\venv\Scripts 05:55:32 INFO - Installing view-profile-script.py script to C:\slave\test\build\venv\Scripts 05:55:32 INFO - Installing view-profile.exe script to C:\slave\test\build\venv\Scripts 05:55:32 INFO - Running setup.py install for mozrunner 05:55:33 INFO - Installing mozrunner-script.py script to C:\slave\test\build\venv\Scripts 05:55:33 INFO - Installing mozrunner.exe script to C:\slave\test\build\venv\Scripts 05:55:33 INFO - Running setup.py install for mozscreenshot 05:55:33 INFO - Running setup.py install for moztest 05:55:33 INFO - Running setup.py install for mozversion 05:55:34 INFO - Installing mozversion-script.py script to C:\slave\test\build\venv\Scripts 05:55:34 INFO - Installing mozversion.exe script to C:\slave\test\build\venv\Scripts 05:55:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:55:34 INFO - Cleaning up... 05:55:34 INFO - Return code: 0 05:55:34 INFO - Installing None into virtualenv C:\slave\test\build/venv 05:55:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:34 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C97E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D9D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\config', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-143', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-143', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-143', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143'}}, attempt #1 05:55:34 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\config\\mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\config 05:55:34 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 -r C:\slave\test\build\tests\config\mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:34 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 05:55:34 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 05:55:34 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:55:34 INFO - 'COMPUTERNAME': 'T-XP32-IX-143', 05:55:34 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 05:55:34 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:55:34 INFO - 'DCLOCATION': 'SCL3', 05:55:34 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:55:34 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:55:34 INFO - 'HOMEDRIVE': 'C:', 05:55:34 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:34 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:55:34 INFO - 'KTS_VERSION': '1.19c', 05:55:34 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-143', 05:55:34 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:55:34 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:55:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:34 INFO - 'MOZ_NO_REMOTE': '1', 05:55:34 INFO - 'NO_EM_RESTART': '1', 05:55:34 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:55:34 INFO - 'OS': 'Windows_NT', 05:55:34 INFO - 'OURDRIVE': 'C:', 05:55:34 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;', 05:55:34 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 05:55:34 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:55:34 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:55:34 INFO - 'PROCESSOR_LEVEL': '6', 05:55:34 INFO - 'PROCESSOR_REVISION': '1e05', 05:55:34 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:55:34 INFO - 'PROMPT': '$P$G', 05:55:34 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:55:34 INFO - 'PWD': 'C:\\slave\\test', 05:55:34 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:55:34 INFO - 'SESSIONNAME': 'Console', 05:55:34 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:55:34 INFO - 'SYSTEMDRIVE': 'C:', 05:55:34 INFO - 'SYSTEMROOT': 'C:\\WINDOWS', 05:55:34 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:34 INFO - 'TEST1': 'testie', 05:55:34 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:34 INFO - 'USERDOMAIN': 'T-XP32-IX-143', 05:55:34 INFO - 'USERNAME': 'cltbld', 05:55:34 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:34 INFO - 'WINDIR': 'C:\\WINDOWS', 05:55:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:55:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\manifestparser 05:55:34 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-i5qbbp-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/manifestparser 05:55:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///C:/slave/test/build/tests/mozbase/manifestparser in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 1)) 05:55:34 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozcrash 05:55:34 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-q5wwf8-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozcrash 05:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///C:/slave/test/build/tests/mozbase/mozcrash in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 05:55:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdebug 05:55:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-zshslk-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdebug 05:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///C:/slave/test/build/tests/mozbase/mozdebug in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 05:55:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozdevice 05:55:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-ar0avz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozdevice 05:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///C:/slave/test/build/tests/mozbase/mozdevice in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 05:55:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozfile 05:55:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-dczcgi-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozfile 05:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///C:/slave/test/build/tests/mozbase/mozfile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 5)) 05:55:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozhttpd 05:55:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-pwrzak-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozhttpd 05:55:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///C:/slave/test/build/tests/mozbase/mozhttpd in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 6)) 05:55:35 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinfo 05:55:35 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-kcouhc-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinfo 05:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///C:/slave/test/build/tests/mozbase/mozinfo in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 7)) 05:55:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozinstall 05:55:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-_jqtam-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozinstall 05:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///C:/slave/test/build/tests/mozbase/mozinstall in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 8)) 05:55:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozleak 05:55:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-pncjrx-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozleak 05:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///C:/slave/test/build/tests/mozbase/mozleak in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 9)) 05:55:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozlog 05:55:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-md3b7c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozlog 05:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///C:/slave/test/build/tests/mozbase/mozlog in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 05:55:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\moznetwork 05:55:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-lk8_hl-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moznetwork 05:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///C:/slave/test/build/tests/mozbase/moznetwork in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 11)) 05:55:36 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprocess 05:55:36 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-vt7u6c-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprocess 05:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///C:/slave/test/build/tests/mozbase/mozprocess in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 12)) 05:55:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozprofile 05:55:37 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-sn6j8q-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozprofile 05:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///C:/slave/test/build/tests/mozbase/mozprofile in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 13)) 05:55:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozrunner 05:55:37 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-2neudq-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozrunner 05:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///C:/slave/test/build/tests/mozbase/mozrunner in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 14)) 05:55:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozscreenshot 05:55:37 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-9i5ugz-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozscreenshot 05:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///C:/slave/test/build/tests/mozbase/mozscreenshot in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 15)) 05:55:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\moztest 05:55:37 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-exolmy-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/moztest 05:55:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///C:/slave/test/build/tests/mozbase/moztest in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 16)) 05:55:37 INFO - Unpacking c:\slave\test\build\tests\mozbase\mozversion 05:55:37 INFO - Running setup.py (path:c:\docume~1\cltbld~1.t-x\locals~1\temp\pip-fe1v94-build\setup.py) egg_info for package from file:///C:/slave/test/build/tests/mozbase/mozversion 05:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///C:/slave/test/build/tests/mozbase/mozversion in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 17)) 05:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 05:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash==0.16->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 2)) 05:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in c:\slave\test\build\venv\lib\site-packages (from mozdebug==0.1->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 3)) 05:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.47->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 05:55:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in c:\slave\test\build\venv\lib\site-packages (from mozdevice==0.47->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 4)) 05:55:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r C:\slave\test\build\tests\config\mozbase_requirements.txt (line 10)) 05:55:38 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 05:55:38 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 05:55:39 INFO - Downloading blessings-1.5.1.tar.gz 05:55:39 INFO - Storing download in cache at c:\slave\test\build\venv\cache\http%3a%2f%2fpypi.pvt.build.mozilla.org%2fpub%2fblessings-1.5.1.tar.gz 05:55:39 INFO - Running setup.py (path:C:\slave\test\build\venv\build\blessings\setup.py) egg_info for package blessings 05:55:39 INFO - Installing collected packages: blessings 05:55:39 INFO - Running setup.py install for blessings 05:55:39 INFO - Successfully installed blessings 05:55:39 INFO - Cleaning up... 05:55:39 INFO - Return code: 0 05:55:39 INFO - Done creating virtualenv C:\slave\test\build/venv. 05:55:39 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 05:55:39 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 05:55:40 INFO - Reading from file tmpfile_stdout 05:55:40 INFO - Using _rmtree_windows ... 05:55:40 INFO - Using _rmtree_windows ... 05:55:40 INFO - Current package versions: 05:55:40 INFO - blessings == 1.5.1 05:55:40 INFO - blobuploader == 1.2.4 05:55:40 INFO - docopt == 0.6.1 05:55:40 INFO - manifestparser == 1.1 05:55:40 INFO - mozInstall == 1.12 05:55:40 INFO - mozcrash == 0.16 05:55:40 INFO - mozdebug == 0.1 05:55:40 INFO - mozdevice == 0.47 05:55:40 INFO - mozfile == 1.2 05:55:40 INFO - mozhttpd == 0.7 05:55:40 INFO - mozinfo == 0.9 05:55:40 INFO - mozleak == 0.1 05:55:40 INFO - mozlog == 3.0 05:55:40 INFO - moznetwork == 0.27 05:55:40 INFO - mozprocess == 0.22 05:55:40 INFO - mozprofile == 0.27 05:55:40 INFO - mozrunner == 6.11 05:55:40 INFO - mozscreenshot == 0.1 05:55:40 INFO - mozsystemmonitor == 0.0 05:55:40 INFO - moztest == 0.7 05:55:40 INFO - mozversion == 1.4 05:55:40 INFO - psutil == 3.1.1 05:55:40 INFO - requests == 1.2.3 05:55:40 INFO - Installing None into virtualenv C:\slave\test\build/venv 05:55:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:55:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:55:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:55:40 INFO - retry: Calling run_command with args: [['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00DBDB10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00C97E30>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x00C024D8>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D9D660>, 'level': 'critical'}], 'cwd': 'C:\\slave\\test\\build\\tests\\talos', 'env': {'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERNAME': 'cltbld', 'COMPUTERNAME': 'T-XP32-IX-143', 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 'KTS_VERSION': '1.19c', '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', '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;', 'NO_EM_RESTART': '1', 'OURDRIVE': 'C:', 'XPCOM_DEBUG_BREAK': 'warn', 'MONDIR': 'C:\\Monitor_config\\', 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 'USERDOMAIN': 'T-XP32-IX-143', 'PROCESSOR_ARCHITECTURE': 'x86', 'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 'SESSIONNAME': 'Console', 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 'MOZBUILDDIR': 'C:\\mozilla-build\\', 'TEST1': 'testie', 'LOGONSERVER': '\\\\T-XP32-IX-143', 'MOZ_NO_REMOTE': '1', 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 'CYGWINBASE': 'C:\\cygwin', 'SYSTEMROOT': 'C:\\WINDOWS', 'DCLOCATION': 'SCL3', 'PROMPT': '$P$G', 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 'FP_NO_HOST_CHECK': 'NO', 'WINDIR': 'C:\\WINDOWS', 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 'HOMEDRIVE': 'C:', 'SYSTEMDRIVE': 'C:', 'SLAVEDIR': 'C:\\slave\\', 'NUMBER_OF_PROCESSORS': '8', 'PWD': 'C:\\slave\\test', 'PROCESSOR_LEVEL': '6', 'KTS_HOME': 'C:\\Program Files\\KTS', 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'OS': 'Windows_NT', 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143'}}, attempt #1 05:55:40 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'install', '--download-cache', 'C:\\slave\\test\\build/venv\\cache', '--timeout', '120', '-r', 'C:\\slave\\test\\build\\tests\\talos\\requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in C:\slave\test\build\tests\talos 05:55:40 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip install --download-cache C:\slave\test\build/venv\cache --timeout 120 -r C:\slave\test\build\tests\talos\requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:55:40 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 05:55:40 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 05:55:40 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:55:40 INFO - 'COMPUTERNAME': 'T-XP32-IX-143', 05:55:40 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 05:55:40 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:55:40 INFO - 'DCLOCATION': 'SCL3', 05:55:40 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:55:40 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:55:40 INFO - 'HOMEDRIVE': 'C:', 05:55:40 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:40 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:55:40 INFO - 'KTS_VERSION': '1.19c', 05:55:40 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-143', 05:55:40 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:55:40 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:55:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:40 INFO - 'MOZ_NO_REMOTE': '1', 05:55:40 INFO - 'NO_EM_RESTART': '1', 05:55:40 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:55:40 INFO - 'OS': 'Windows_NT', 05:55:40 INFO - 'OURDRIVE': 'C:', 05:55:40 INFO - 'PATH': 'C:\\Python24;C:\\Python24\\Scripts;C:\\cygwin\\bin;C:\\WINDOWS\\System32;C:\\program files\\gnuwin32\\bin;C:\\WINDOWS;C:\\mozilla-build\\msys\\bin;C:\\mozilla-build\\python27;C:\\mozilla-build\\python27\\Scripts;C:\\mozilla-build\\info-zip;C:\\mozilla-build\\wget;C:\\Program Files (x86)\\GnuWin32\\bin;', 05:55:40 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 05:55:40 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:55:40 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:55:40 INFO - 'PROCESSOR_LEVEL': '6', 05:55:40 INFO - 'PROCESSOR_REVISION': '1e05', 05:55:40 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:55:40 INFO - 'PROMPT': '$P$G', 05:55:40 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:55:40 INFO - 'PWD': 'C:\\slave\\test', 05:55:40 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:55:40 INFO - 'SESSIONNAME': 'Console', 05:55:40 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:55:40 INFO - 'SYSTEMDRIVE': 'C:', 05:55:40 INFO - 'SYSTEMROOT': 'C:\\WINDOWS', 05:55:40 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:40 INFO - 'TEST1': 'testie', 05:55:40 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:40 INFO - 'USERDOMAIN': 'T-XP32-IX-143', 05:55:40 INFO - 'USERNAME': 'cltbld', 05:55:40 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:40 INFO - 'WINDIR': 'C:\\WINDOWS', 05:55:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:55:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 05:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 2)) 05:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 3)) 05:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 4)) 05:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 5)) 05:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 6)) 05:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 7)) 05:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in c:\slave\test\build\venv\lib\site-packages (from -r C:\slave\test\build\tests\talos\requirements.txt (line 8)) 05:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in c:\slave\test\build\venv\lib\site-packages (from mozcrash>=0.15->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 05:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in c:\slave\test\build\venv\lib\site-packages (from mozhttpd>=0.7->-r C:\slave\test\build\tests\talos\requirements.txt (line 3)) 05:55:40 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in c:\slave\test\build\venv\lib\site-packages (from mozlog>=3.0->mozcrash>=0.15->-r C:\slave\test\build\tests\talos\requirements.txt (line 1)) 05:55:40 INFO - Cleaning up... 05:55:40 INFO - Return code: 0 05:55:40 INFO - Running post-action listener: _resource_record_post_action 05:55:40 INFO - Running post-action listener: _start_resource_monitoring 05:55:40 INFO - Starting resource monitoring. 05:55:41 INFO - ##### 05:55:41 INFO - ##### Running install step. 05:55:41 INFO - ##### 05:55:41 INFO - Running pre-action listener: _resource_record_pre_action 05:55:41 INFO - Running main action method: install 05:55:41 INFO - Getting output from command: ['C:\\slave\\test\\build\\venv\\Scripts\\pip', 'freeze'] 05:55:41 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\pip freeze 05:55:41 INFO - Reading from file tmpfile_stdout 05:55:41 INFO - Using _rmtree_windows ... 05:55:41 INFO - Using _rmtree_windows ... 05:55:41 INFO - Detecting whether we're running mozinstall >=1.0... 05:55:41 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', '-h'] 05:55:41 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py -h 05:55:41 INFO - Reading from file tmpfile_stdout 05:55:41 INFO - Output received: 05:55:41 INFO - Usage: mozinstall-script.py [options] installer 05:55:41 INFO - Options: 05:55:41 INFO - -h, --help show this help message and exit 05:55:41 INFO - -d DEST, --destination=DEST 05:55:41 INFO - Directory to install application into. [default: 05:55:41 INFO - "C:\slave\test"] 05:55:41 INFO - --app=APP Application being installed. [default: firefox] 05:55:41 INFO - Using _rmtree_windows ... 05:55:41 INFO - Using _rmtree_windows ... 05:55:41 INFO - mkdir: C:\slave\test\build\application 05:55:41 INFO - Getting output from command: ['C:\\slave\\test\\build/venv/scripts/python', 'C:\\slave\\test\\build/venv/scripts/mozinstall-script.py', 'C:\\slave\\test\\build\\firefox-45.0a1.en-US.win32.zip', '--destination', 'C:\\slave\\test\\build\\application'] 05:55:41 INFO - Copy/paste: C:\slave\test\build/venv/scripts/python C:\slave\test\build/venv/scripts/mozinstall-script.py C:\slave\test\build\firefox-45.0a1.en-US.win32.zip --destination C:\slave\test\build\application 05:55:43 INFO - Reading from file tmpfile_stdout 05:55:43 INFO - Output received: 05:55:43 INFO - C:\slave\test\build\application\firefox\firefox.exe 05:55:43 INFO - Using _rmtree_windows ... 05:55:43 INFO - Using _rmtree_windows ... 05:55:43 INFO - Running post-action listener: _resource_record_post_action 05:55:43 INFO - ##### 05:55:43 INFO - ##### Running run-tests step. 05:55:43 INFO - ##### 05:55:43 INFO - Running pre-action listener: _resource_record_pre_action 05:55:43 INFO - Running main action method: run_tests 05:55:43 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', '--version'] 05:55:43 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python --version 05:55:43 INFO - Python 2.7.3 05:55:43 INFO - Return code: 0 05:55:43 INFO - grabbing minidump binary from tooltool 05:55:43 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')]} 05:55:43 INFO - retry: Calling run_command with args: (['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x00A58B48>, '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 0x00A7A650>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x00D9D660>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': 'C:\\slave\\test\\build', 'privileged': False}, attempt #1 05:55:43 INFO - Running command: ['c:/mozilla-build/python27/python.exe', 'C:/mozilla-build/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', 'c:\\builds\\relengapi.tok', 'fetch', '-m', 'C:\\slave\\test\\build\\tests\\config/tooltool-manifests/win32/releng.manifest', '-o'] in C:\slave\test\build 05:55:43 INFO - Copy/paste: c:/mozilla-build/python27/python.exe C:/mozilla-build/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file c:\builds\relengapi.tok fetch -m C:\slave\test\build\tests\config/tooltool-manifests/win32/releng.manifest -o 05:55:43 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 05:55:44 INFO - INFO - File win32-minidump_stackwalk.exe fetched from https://api.pub.build.mozilla.org/tooltool/ as C:\slave\test\build\tmpwgcvfr 05:55:44 INFO - INFO - File integrity verified, renaming tmpwgcvfr to win32-minidump_stackwalk.exe 05:55:44 INFO - Return code: 0 05:55:44 INFO - Chmoding C:\slave\test\build\win32-minidump_stackwalk.exe to 0755 05:55:44 INFO - mkdir: C:\slave\test\build\blobber_upload_dir 05:55:44 INFO - ENV: MOZ_UPLOAD_DIR is now C:\slave\test\build\blobber_upload_dir 05:55:44 INFO - ENV: MINIDUMP_STACKWALK is now C:\slave\test\build\win32-minidump_stackwalk.exe 05:55:44 INFO - ENV: MINIDUMP_SAVE_PATH is now C:\slave\test\build\blobber_upload_dir 05:55:44 INFO - Running command: ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'g2', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-XP32-IX-143', '--webServer', 'localhost'] in C:\slave\test\build 05:55:44 INFO - Copy/paste: C:\slave\test\build\venv\Scripts\python C:\slave\test\build\tests\talos\talos\run_tests.py --debug --branchName Fx-Team-Non-PGO --suite g2 --executablePath C:\slave\test\build\application\firefox\firefox --symbolsPath https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.crashreporter-symbols.zip --title T-XP32-IX-143 --webServer localhost 05:55:44 INFO - Using env: {'ALLUSERSPROFILE': 'C:\\Documents and Settings\\All Users', 05:55:44 INFO - 'APPDATA': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143\\Application Data', 05:55:44 INFO - 'COMMONPROGRAMFILES': 'C:\\Program Files\\Common Files', 05:55:44 INFO - 'COMPUTERNAME': 'T-XP32-IX-143', 05:55:44 INFO - 'COMSPEC': 'C:\\WINDOWS\\system32\\cmd.exe', 05:55:44 INFO - 'CYGWINBASE': 'C:\\cygwin', 05:55:44 INFO - 'DCLOCATION': 'SCL3', 05:55:44 INFO - 'DNSSUFFIX': 'wintest.releng.scl3.mozilla.com', 05:55:44 INFO - 'FP_NO_HOST_CHECK': 'NO', 05:55:44 INFO - 'HOMEDRIVE': 'C:', 05:55:44 INFO - 'HOMEPATH': '\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:44 INFO - 'KTS_HOME': 'C:\\Program Files\\KTS', 05:55:44 INFO - 'KTS_VERSION': '1.19c', 05:55:44 INFO - 'LOGONSERVER': '\\\\T-XP32-IX-143', 05:55:44 INFO - 'MINIDUMP_SAVE_PATH': 'C:\\slave\\test\\build\\blobber_upload_dir', 05:55:44 INFO - 'MINIDUMP_STACKWALK': 'C:\\slave\\test\\build\\win32-minidump_stackwalk.exe', 05:55:44 INFO - 'MONDIR': 'C:\\Monitor_config\\', 05:55:44 INFO - 'MOZBUILDDIR': 'C:\\mozilla-build\\', 05:55:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:55:44 INFO - 'MOZ_NO_REMOTE': '1', 05:55:44 INFO - 'MOZ_UPLOAD_DIR': 'C:\\slave\\test\\build\\blobber_upload_dir', 05:55:44 INFO - 'NO_EM_RESTART': '1', 05:55:44 INFO - 'NUMBER_OF_PROCESSORS': '8', 05:55:44 INFO - 'OS': 'Windows_NT', 05:55:44 INFO - 'OURDRIVE': 'C:', 05:55: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;', 05:55:44 INFO - 'PATHEXT': '.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.PSC1', 05:55:44 INFO - 'PROCESSOR_ARCHITECTURE': 'x86', 05:55:44 INFO - 'PROCESSOR_IDENTIFIER': 'x86 Family 6 Model 30 Stepping 5, GenuineIntel', 05:55:44 INFO - 'PROCESSOR_LEVEL': '6', 05:55:44 INFO - 'PROCESSOR_REVISION': '1e05', 05:55:44 INFO - 'PROGRAMFILES': 'C:\\Program Files', 05:55:44 INFO - 'PROMPT': '$P$G', 05:55:44 INFO - 'PROPERTIES_FILE': 'C:\\slave\\test/buildprops.json', 05:55:44 INFO - 'PWD': 'C:\\slave\\test', 05:55:44 INFO - 'PYTHONPATH': 'C:\\slave\\test\\build\\tests\\talos', 05:55:44 INFO - 'RUNLOGFILE': 'C:\\slave\\\\runslave.log', 05:55:44 INFO - 'SESSIONNAME': 'Console', 05:55:44 INFO - 'SLAVEDIR': 'C:\\slave\\', 05:55:44 INFO - 'SYSTEMDRIVE': 'C:', 05:55:44 INFO - 'SYSTEMROOT': 'C:\\WINDOWS', 05:55:44 INFO - 'TEMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:44 INFO - 'TEST1': 'testie', 05:55:44 INFO - 'TMP': 'C:\\DOCUME~1\\CLTBLD~1.T-X\\LOCALS~1\\Temp', 05:55:44 INFO - 'USERDOMAIN': 'T-XP32-IX-143', 05:55:44 INFO - 'USERNAME': 'cltbld', 05:55:44 INFO - 'USERPROFILE': 'C:\\Documents and Settings\\cltbld.T-XP32-IX-143', 05:55:44 INFO - 'WINDIR': 'C:\\WINDOWS', 05:55:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn'} 05:55:44 INFO - Calling ['C:\\slave\\test\\build\\venv\\Scripts\\python', 'C:\\slave\\test\\build\\tests\\talos\\talos\\run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'g2', '--executablePath', 'C:\\slave\\test\\build\\application\\firefox\\firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.crashreporter-symbols.zip', '--title', 'T-XP32-IX-143', '--webServer', 'localhost'] with output_timeout 3600 05:55:44 INFO - mozversion INFO | application_buildid: 20151119042020 05:55:44 INFO - mozversion INFO | application_changeset: bc29e2e5fac327b36b25e8d38107e09adc0a91b9 05:55:44 INFO - mozversion INFO | application_display_name: Nightly 05:55:44 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:55:44 INFO - mozversion INFO | application_name: Firefox 05:55:44 INFO - mozversion INFO | application_remotingname: firefox 05:55:44 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 05:55:44 INFO - mozversion INFO | application_vendor: Mozilla 05:55:44 INFO - mozversion INFO | application_version: 45.0a1 05:55:44 INFO - mozversion INFO | platform_buildid: 20151119042020 05:55:44 INFO - mozversion INFO | platform_changeset: bc29e2e5fac327b36b25e8d38107e09adc0a91b9 05:55:44 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 05:55:44 INFO - mozversion INFO | platform_version: 45.0a1 05:55:44 INFO - 2015-11-19 05:55:44,798 DEBUG : using testdate: 1447941344 05:55:44 INFO - 2015-11-19 05:55:44,798 DEBUG : actual date: 1447941344 05:55:44 INFO - 2015-11-19 05:55:44,813 INFO : starting webserver on 'localhost:1837' 05:55:44 INFO - 2015-11-19 05:55:44,813 INFO : Starting test suite T-XP32-IX-143 05:55:44 INFO - 2015-11-19 05:55:44,813 INFO : Starting test tps 05:55:44 INFO - 2015-11-19 05:55:44,813 DEBUG : operating with platform_type : win_ 05:55:44 INFO - 2015-11-19 05:55:44,813 INFO : Initialising browser for tps test... 05:55:44 INFO - 2015-11-19 05:55:44,861 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp1lgzjh\profile http://localhost:1837/getInfo.html 05:55:48 INFO - 2015-11-19 05:55:48,438 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:55:48 INFO - 2015-11-19 05:55:48,438 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:55:48 INFO - 2015-11-19 05:55:48,438 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1016/661 05:55:48 INFO - 2015-11-19 05:55:48,438 DEBUG : BROWSER_OUTPUT: __metrics 05:55:48 INFO - 2015-11-19 05:55:48,470 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:55:48 INFO - 2015-11-19 05:55:48,845 DEBUG : BROWSER_OUTPUT: console.error: 05:55:48 INFO - 2015-11-19 05:55:48,845 DEBUG : BROWSER_OUTPUT: Message: Error: Connection closed before committing the transaction. 05:55:48 INFO - 2015-11-19 05:55:48,845 DEBUG : BROWSER_OUTPUT: Stack: 05:55:48 INFO - 2015-11-19 05:55:48,845 DEBUG : BROWSER_OUTPUT: ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:934:23 05:55:48 INFO - 2015-11-19 05:55:48,845 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 05:55:48 INFO - 2015-11-19 05:55:48,845 DEBUG : BROWSER_OUTPUT: Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 05:55:48 INFO - 2015-11-19 05:55:48,845 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 05:55:48 INFO - 2015-11-19 05:55:48,845 DEBUG : BROWSER_OUTPUT: this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 05:55:48 INFO - 2015-11-19 05:55:48,845 DEBUG : BROWSER_OUTPUT: TaskImpl_run@resource://gre/modules/Task.jsm:319:13 05:55:48 INFO - 2015-11-19 05:55:48,845 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 05:55:48 INFO - 2015-11-19 05:55:48,845 DEBUG : BROWSER_OUTPUT: nsAsyncShutdownBarrier.prototype.wait/<@resource://gre/components/nsAsyncShutdown.js:215:7 05:55:48 INFO - 2015-11-19 05:55:48,845 DEBUG : BROWSER_OUTPUT: Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:523:9 05:55:48 INFO - 2015-11-19 05:55:48,845 DEBUG : BROWSER_OUTPUT: 05:55:49 INFO - 2015-11-19 05:55:49,407 INFO : Browser initialized. 05:55:49 INFO - 2015-11-19 05:55:49,407 INFO : Running cycle 1/1 for tps test... 05:55:49 INFO - 2015-11-19 05:55:49,407 DEBUG : command line: C:\slave\test\build\application\firefox\firefox -profile c:\docume~1\cltbld~1.t-x\locals~1\temp\tmp1lgzjh\profile -tp file:\C:\slave\test\build\tests\talos\talos\tests\tabswitch\tps.manifest.develop -tpchrome -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5 05:56:01 INFO - 2015-11-19 05:56:01,049 DEBUG : BROWSER_OUTPUT: RSS: Main: 143327232 05:56:01 INFO - 2015-11-19 05:56:01,049 DEBUG : BROWSER_OUTPUT: 05:56:02 INFO - 2015-11-19 05:56:02,454 DEBUG : BROWSER_OUTPUT: Loaded: about:blank 05:56:02 INFO - 2015-11-19 05:56:02,674 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mail.ru/mail.ru/index.html 05:56:03 INFO - 2015-11-19 05:56:03,642 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/guardian.co.uk/www.guardian.co.uk/index.html 05:56:18 INFO - 2015-11-19 05:56:18,954 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/google.com/www.google.com/search@q=mozilla.html 05:56:20 INFO - 2015-11-19 05:56:20,720 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/goo.ne.jp/goo.ne.jp/index.html 05:56:23 INFO - 2015-11-19 05:56:23,892 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/beatonna.livejournal.com/beatonna.livejournal.com/index.html 05:56:24 INFO - 2015-11-19 05:56:24,611 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/imgur.com/imgur.com/gallery/index.html 05:56:27 INFO - 2015-11-19 05:56:27,033 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/chemistry.about.com/chemistry.about.com/index.html 05:56:27 INFO - 2015-11-19 05:56:27,299 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/chinaz.com/chinaz.com/index.html 05:56:30 INFO - 2015-11-19 05:56:30,236 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/tudou.com/www.tudou.com/index.html 05:56:30 INFO - 2015-11-19 05:56:30,517 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/dailymotion.com/www.dailymotion.com/us.html 05:56:31 INFO - 2015-11-19 05:56:31,331 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/whois.domaintools.com/whois.domaintools.com/mozilla.com.html 05:56:33 INFO - 2015-11-19 05:56:33,424 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/thepiratebay.org/thepiratebay.org/top/201.html 05:56:33 INFO - 2015-11-19 05:56:33,706 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/media.photobucket.com/media.photobucket.com/image/funny%20gif/findstuff22/Best%20Images/Funny/funny-gif1.jpg@o=1.html 05:56:34 INFO - 2015-11-19 05:56:34,408 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/naver.com/www.naver.com/index.html 05:56:34 INFO - 2015-11-19 05:56:34,486 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/wsj.com/online.wsj.com/home-page.html 05:56:35 INFO - 2015-11-19 05:56:35,065 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/store.apple.com/store.apple.com/us@mco=Nzc1MjMwNA.html 05:56:35 INFO - 2015-11-19 05:56:35,720 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/page.renren.com/page.renren.com/index.html 05:56:36 INFO - 2015-11-19 05:56:36,736 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/cgi.ebay.com/cgi.ebay.com/ALL-NEW-KINDLE-3-eBOOK-WIRELESS-READING-DEVICE-W-WIFI-/130496077314@pt=LH_DefaultDomain_0&hash=item1e622c1e02.html 05:56:37 INFO - 2015-11-19 05:56:37,565 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/ezinearticles.com/ezinearticles.com/index.html@Migraine-Ocular---The-Eye-Migraines&id=4684133.html 05:56:37 INFO - 2015-11-19 05:56:37,674 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/reddit.com/www.reddit.com/index.html 05:56:37 INFO - 2015-11-19 05:56:37,736 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/myspace.com/www.myspace.com/albumart.html 05:56:37 INFO - 2015-11-19 05:56:37,831 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mashable.com/mashable.com/index.html 05:56:38 INFO - 2015-11-19 05:56:38,595 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/guardian.co.uk/www.guardian.co.uk/index.html 05:56:39 INFO - 2015-11-19 05:56:39,486 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/noimpactman.typepad.com/noimpactman.typepad.com/index.html 05:56:40 INFO - 2015-11-19 05:56:40,220 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mail.ru/mail.ru/index.html 05:56:42 INFO - 2015-11-19 05:56:42,236 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/stackoverflow.com/stackoverflow.com/questions/184618/what-is-the-best-comment-in-source-code-you-have-ever-encountered.html 05:56:42 INFO - 2015-11-19 05:56:42,361 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/imdb.com/www.imdb.com/title/tt1099212/index.html 05:56:42 INFO - 2015-11-19 05:56:42,690 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/uol.com.br/www.uol.com.br/index.html 05:56:43 INFO - 2015-11-19 05:56:43,003 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/spiegel.de/www.spiegel.de/index.html 05:56:43 INFO - 2015-11-19 05:56:43,611 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/163.com/www.163.com/index.html 05:56:44 INFO - 2015-11-19 05:56:44,331 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/globo.com/www.globo.com/index.html 05:56:45 INFO - 2015-11-19 05:56:45,721 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/indiatimes.com/www.indiatimes.com/index.html 05:56:46 INFO - 2015-11-19 05:56:46,690 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/ifeng.com/ifeng.com/index.html 05:56:47 INFO - 2015-11-19 05:56:47,721 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/bbc.co.uk/www.bbc.co.uk/news/index.html 05:56:48 INFO - 2015-11-19 05:56:48,033 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/dailymail.co.uk/www.dailymail.co.uk/ushome/index.html 05:56:48 INFO - 2015-11-19 05:56:48,128 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/reuters.com/www.reuters.com/index.html 05:56:48 INFO - 2015-11-19 05:56:48,456 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/bild.de/www.bild.de/index.html 05:56:48 INFO - 2015-11-19 05:56:48,753 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/yelp.com/www.yelp.com/biz/alexanders-steakhouse-cupertino.html 05:56:49 INFO - 2015-11-19 05:56:49,236 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/cnn.com/www.cnn.com/index.html 05:56:50 INFO - 2015-11-19 05:56:50,017 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/sohu.com/www.sohu.com/index.html 05:56:50 INFO - 2015-11-19 05:56:50,206 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/rakuten.co.jp/www.rakuten.co.jp/index.html 05:56:50 INFO - 2015-11-19 05:56:50,721 DEBUG : BROWSER_OUTPUT: 1447941410723 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] 05:56:50 INFO - 2015-11-19 05:56:50,831 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/huffingtonpost.com/www.huffingtonpost.com/index.html 05:56:51 INFO - 2015-11-19 05:56:51,049 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/56.com/www.56.com/index.html 05:56:51 INFO - 2015-11-19 05:56:51,517 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/youku.com/www.youku.com/index.html 05:56:51 INFO - 2015-11-19 05:56:51,628 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/xinhuanet.com/xinhuanet.com/index.html 05:56:52 INFO - 2015-11-19 05:56:52,769 DEBUG : BROWSER_OUTPUT: ???????????????????: TLoaded: http://localhost:1837/tests/tp5n/amazon.com/www.amazon.com/Kindle-Wireless-Reader-Wifi-Graphite/dp/B002Y27P3M/507846.html 05:57:01 INFO - 2015-11-19 05:57:01,565 DEBUG : BROWSER_OUTPUT: 1447941421569 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] 05:57:15 INFO - 2015-11-19 05:57:15,346 DEBUG : BROWSER_OUTPUT: total tab switch time:2528.4550000000236 05:57:16 INFO - 2015-11-19 05:57:16,331 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded chrome://tabswitch/content/test.html#auto (next: chrome://tabswitch/content/test.html#auto) 05:57:17 INFO - 2015-11-19 05:57:17,894 DEBUG : BROWSER_OUTPUT: RSS: Main: 333897728 05:57:17 INFO - 2015-11-19 05:57:17,894 DEBUG : BROWSER_OUTPUT: 05:57:19 INFO - 2015-11-19 05:57:19,332 DEBUG : BROWSER_OUTPUT: Loaded: about:blank 05:57:19 INFO - 2015-11-19 05:57:19,503 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mail.ru/mail.ru/index.html 05:57:20 INFO - 2015-11-19 05:57:20,519 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/guardian.co.uk/www.guardian.co.uk/index.html 05:57:20 INFO - 2015-11-19 05:57:20,815 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/guardian.co.uk/www.guardian.co.uk/index.html 05:57:31 INFO - 2015-11-19 05:57:31,957 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/google.com/www.google.com/search@q=mozilla.html 05:57:33 INFO - 2015-11-19 05:57:33,471 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/goo.ne.jp/goo.ne.jp/index.html 05:57:35 INFO - 2015-11-19 05:57:35,628 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/beatonna.livejournal.com/beatonna.livejournal.com/index.html 05:57:38 INFO - 2015-11-19 05:57:38,253 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/chemistry.about.com/chemistry.about.com/index.html 05:57:38 INFO - 2015-11-19 05:57:38,519 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/chinaz.com/chinaz.com/index.html 05:57:41 INFO - 2015-11-19 05:57:41,660 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/dailymotion.com/www.dailymotion.com/us.html 05:57:41 INFO - 2015-11-19 05:57:41,832 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/imgur.com/imgur.com/gallery/index.html 05:57:42 INFO - 2015-11-19 05:57:42,785 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/whois.domaintools.com/whois.domaintools.com/mozilla.com.html 05:57:43 INFO - 2015-11-19 05:57:43,394 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/naver.com/www.naver.com/index.html 05:57:44 INFO - 2015-11-19 05:57:44,551 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/tudou.com/www.tudou.com/index.html 05:57:44 INFO - 2015-11-19 05:57:44,737 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/thepiratebay.org/thepiratebay.org/top/201.html 05:57:44 INFO - 2015-11-19 05:57:44,987 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/media.photobucket.com/media.photobucket.com/image/funny%20gif/findstuff22/Best%20Images/Funny/funny-gif1.jpg@o=1.html 05:57:46 INFO - 2015-11-19 05:57:46,957 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/page.renren.com/page.renren.com/index.html 05:57:48 INFO - 2015-11-19 05:57:48,348 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/ezinearticles.com/ezinearticles.com/index.html@Migraine-Ocular---The-Eye-Migraines&id=4684133.html 05:57:48 INFO - 2015-11-19 05:57:48,535 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/store.apple.com/store.apple.com/us@mco=Nzc1MjMwNA.html 05:57:49 INFO - 2015-11-19 05:57:49,487 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/myspace.com/www.myspace.com/albumart.html 05:57:52 INFO - 2015-11-19 05:57:52,332 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/wsj.com/online.wsj.com/home-page.html 05:57:52 INFO - 2015-11-19 05:57:52,519 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/cgi.ebay.com/cgi.ebay.com/ALL-NEW-KINDLE-3-eBOOK-WIRELESS-READING-DEVICE-W-WIFI-/130496077314@pt=LH_DefaultDomain_0&hash=item1e622c1e02.html 05:57:53 INFO - 2015-11-19 05:57:53,378 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/reddit.com/www.reddit.com/index.html 05:57:53 INFO - 2015-11-19 05:57:53,457 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/noimpactman.typepad.com/noimpactman.typepad.com/index.html 05:57:54 INFO - 2015-11-19 05:57:54,066 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mail.ru/mail.ru/index.html 05:57:56 INFO - 2015-11-19 05:57:56,098 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/stackoverflow.com/stackoverflow.com/questions/184618/what-is-the-best-comment-in-source-code-you-have-ever-encountered.html 05:57:56 INFO - 2015-11-19 05:57:56,237 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/imdb.com/www.imdb.com/title/tt1099212/index.html 05:57:56 INFO - 2015-11-19 05:57:56,832 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mashable.com/mashable.com/index.html 05:57:58 INFO - 2015-11-19 05:57:58,691 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/163.com/www.163.com/index.html 05:57:59 INFO - 2015-11-19 05:57:59,628 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/spiegel.de/www.spiegel.de/index.html 05:58:00 INFO - 2015-11-19 05:58:00,582 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/uol.com.br/www.uol.com.br/index.html 05:58:00 INFO - 2015-11-19 05:58:00,801 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/indiatimes.com/www.indiatimes.com/index.html 05:58:01 INFO - 2015-11-19 05:58:01,348 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/cnn.com/www.cnn.com/index.html 05:58:01 INFO - 2015-11-19 05:58:01,848 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/globo.com/www.globo.com/index.html 05:58:02 INFO - 2015-11-19 05:58:02,503 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/yelp.com/www.yelp.com/biz/alexanders-steakhouse-cupertino.html 05:58:03 INFO - 2015-11-19 05:58:03,864 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/sohu.com/www.sohu.com/index.html 05:58:03 INFO - 2015-11-19 05:58:03,941 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/ifeng.com/ifeng.com/index.html 05:58:04 INFO - 2015-11-19 05:58:04,707 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/bild.de/www.bild.de/index.html 05:58:04 INFO - 2015-11-19 05:58:04,911 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/bbc.co.uk/www.bbc.co.uk/news/index.html 05:58:05 INFO - 2015-11-19 05:58:05,426 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/dailymail.co.uk/www.dailymail.co.uk/ushome/index.html 05:58:05 INFO - 2015-11-19 05:58:05,441 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/reuters.com/www.reuters.com/index.html 05:58:05 INFO - 2015-11-19 05:58:05,786 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/rakuten.co.jp/www.rakuten.co.jp/index.html 05:58:05 INFO - 2015-11-19 05:58:05,864 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/huffingtonpost.com/www.huffingtonpost.com/index.html 05:58:06 INFO - 2015-11-19 05:58:06,098 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/56.com/www.56.com/index.html 05:58:06 INFO - 2015-11-19 05:58:06,644 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/youku.com/www.youku.com/index.html 05:58:06 INFO - 2015-11-19 05:58:06,786 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/xinhuanet.com/xinhuanet.com/index.html 05:58:08 INFO - 2015-11-19 05:58:08,036 DEBUG : BROWSER_OUTPUT: ???????????????????: TLoaded: http://localhost:1837/tests/tp5n/amazon.com/www.amazon.com/Kindle-Wireless-Reader-Wifi-Graphite/dp/B002Y27P3M/507846.html 05:58:18 INFO - 2015-11-19 05:58:18,505 DEBUG : BROWSER_OUTPUT: 1447941498514 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] 05:58:31 INFO - 2015-11-19 05:58:31,144 DEBUG : BROWSER_OUTPUT: total tab switch time:2617.8450000000303 05:58:32 INFO - 2015-11-19 05:58:32,144 DEBUG : BROWSER_OUTPUT: Cycle 1(2): loaded chrome://tabswitch/content/test.html#auto (next: chrome://tabswitch/content/test.html#auto) 05:58:33 INFO - 2015-11-19 05:58:33,707 DEBUG : BROWSER_OUTPUT: RSS: Main: 348471296 05:58:33 INFO - 2015-11-19 05:58:33,707 DEBUG : BROWSER_OUTPUT: 05:58:35 INFO - 2015-11-19 05:58:35,161 DEBUG : BROWSER_OUTPUT: Loaded: about:blank 05:58:35 INFO - 2015-11-19 05:58:35,348 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mail.ru/mail.ru/index.html 05:58:36 INFO - 2015-11-19 05:58:36,395 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/guardian.co.uk/www.guardian.co.uk/index.html 05:58:44 INFO - 2015-11-19 05:58:44,973 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/google.com/www.google.com/search@q=mozilla.html 05:58:49 INFO - 2015-11-19 05:58:49,566 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/goo.ne.jp/goo.ne.jp/index.html 05:58:52 INFO - 2015-11-19 05:58:52,036 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/beatonna.livejournal.com/beatonna.livejournal.com/index.html 05:58:54 INFO - 2015-11-19 05:58:54,707 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/chemistry.about.com/chemistry.about.com/index.html 05:58:54 INFO - 2015-11-19 05:58:54,957 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/chinaz.com/chinaz.com/index.html 05:58:55 INFO - 2015-11-19 05:58:55,114 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/dailymotion.com/www.dailymotion.com/us.html 05:58:58 INFO - 2015-11-19 05:58:58,052 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/tudou.com/www.tudou.com/index.html 05:58:58 INFO - 2015-11-19 05:58:58,552 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/imgur.com/imgur.com/gallery/index.html 05:58:59 INFO - 2015-11-19 05:58:59,161 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/whois.domaintools.com/whois.domaintools.com/mozilla.com.html 05:58:59 INFO - 2015-11-19 05:58:59,552 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/naver.com/www.naver.com/index.html 05:59:00 INFO - 2015-11-19 05:59:00,880 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/thepiratebay.org/thepiratebay.org/top/201.html 05:59:01 INFO - 2015-11-19 05:59:01,114 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/media.photobucket.com/media.photobucket.com/image/funny%20gif/findstuff22/Best%20Images/Funny/funny-gif1.jpg@o=1.html 05:59:03 INFO - 2015-11-19 05:59:03,207 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/page.renren.com/page.renren.com/index.html 05:59:03 INFO - 2015-11-19 05:59:03,473 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/myspace.com/www.myspace.com/albumart.html 05:59:04 INFO - 2015-11-19 05:59:04,130 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/wsj.com/online.wsj.com/home-page.html 05:59:04 INFO - 2015-11-19 05:59:04,427 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/cgi.ebay.com/cgi.ebay.com/ALL-NEW-KINDLE-3-eBOOK-WIRELESS-READING-DEVICE-W-WIFI-/130496077314@pt=LH_DefaultDomain_0&hash=item1e622c1e02.html 05:59:04 INFO - 2015-11-19 05:59:04,739 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/guardian.co.uk/www.guardian.co.uk/index.html 05:59:05 INFO - 2015-11-19 05:59:05,693 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/reddit.com/www.reddit.com/index.html 05:59:05 INFO - 2015-11-19 05:59:05,911 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mashable.com/mashable.com/index.html 05:59:06 INFO - 2015-11-19 05:59:06,568 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/ezinearticles.com/ezinearticles.com/index.html@Migraine-Ocular---The-Eye-Migraines&id=4684133.html 05:59:06 INFO - 2015-11-19 05:59:06,707 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/store.apple.com/store.apple.com/us@mco=Nzc1MjMwNA.html 05:59:07 INFO - 2015-11-19 05:59:07,505 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/noimpactman.typepad.com/noimpactman.typepad.com/index.html 05:59:08 INFO - 2015-11-19 05:59:08,536 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/stackoverflow.com/stackoverflow.com/questions/184618/what-is-the-best-comment-in-source-code-you-have-ever-encountered.html 05:59:11 INFO - 2015-11-19 05:59:11,005 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/globo.com/www.globo.com/index.html 05:59:11 INFO - 2015-11-19 05:59:11,098 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/spiegel.de/www.spiegel.de/index.html 05:59:11 INFO - 2015-11-19 05:59:11,911 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mail.ru/mail.ru/index.html 05:59:12 INFO - 2015-11-19 05:59:12,020 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/imdb.com/www.imdb.com/title/tt1099212/index.html 05:59:12 INFO - 2015-11-19 05:59:12,582 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/indiatimes.com/www.indiatimes.com/index.html 05:59:13 INFO - 2015-11-19 05:59:13,114 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/163.com/www.163.com/index.html 05:59:13 INFO - 2015-11-19 05:59:13,505 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/uol.com.br/www.uol.com.br/index.html 05:59:14 INFO - 2015-11-19 05:59:14,130 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/cnn.com/www.cnn.com/index.html 05:59:16 INFO - 2015-11-19 05:59:16,473 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/reuters.com/www.reuters.com/index.html 05:59:17 INFO - 2015-11-19 05:59:17,302 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/yelp.com/www.yelp.com/biz/alexanders-steakhouse-cupertino.html 05:59:17 INFO - 2015-11-19 05:59:17,490 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/ifeng.com/ifeng.com/index.html 05:59:17 INFO - 2015-11-19 05:59:17,943 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/dailymail.co.uk/www.dailymail.co.uk/ushome/index.html 05:59:18 INFO - 2015-11-19 05:59:18,302 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/bild.de/www.bild.de/index.html 05:59:18 INFO - 2015-11-19 05:59:18,536 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/bbc.co.uk/www.bbc.co.uk/news/index.html 05:59:18 INFO - 2015-11-19 05:59:18,755 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/huffingtonpost.com/www.huffingtonpost.com/index.html 05:59:18 INFO - 2015-11-19 05:59:18,973 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/56.com/www.56.com/index.html 05:59:19 INFO - 2015-11-19 05:59:19,068 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/sohu.com/www.sohu.com/index.html 05:59:19 INFO - 2015-11-19 05:59:19,693 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/xinhuanet.com/xinhuanet.com/index.html 05:59:19 INFO - 2015-11-19 05:59:19,911 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/rakuten.co.jp/www.rakuten.co.jp/index.html 05:59:20 INFO - 2015-11-19 05:59:20,286 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/youku.com/www.youku.com/index.html 05:59:21 INFO - 2015-11-19 05:59:21,395 DEBUG : BROWSER_OUTPUT: ???????????????????: TLoaded: http://localhost:1837/tests/tp5n/amazon.com/www.amazon.com/Kindle-Wireless-Reader-Wifi-Graphite/dp/B002Y27P3M/507846.html 05:59:34 INFO - 2015-11-19 05:59:34,005 DEBUG : BROWSER_OUTPUT: 1447941574021 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] 05:59:44 INFO - 2015-11-19 05:59:44,505 DEBUG : BROWSER_OUTPUT: total tab switch time:2414.884999999973 05:59:45 INFO - 2015-11-19 05:59:45,505 DEBUG : BROWSER_OUTPUT: Cycle 1(3): loaded chrome://tabswitch/content/test.html#auto (next: chrome://tabswitch/content/test.html#auto) 05:59:47 INFO - 2015-11-19 05:59:47,098 DEBUG : BROWSER_OUTPUT: RSS: Main: 353103872 05:59:47 INFO - 2015-11-19 05:59:47,098 DEBUG : BROWSER_OUTPUT: 05:59:48 INFO - 2015-11-19 05:59:48,490 DEBUG : BROWSER_OUTPUT: Loaded: about:blank 05:59:48 INFO - 2015-11-19 05:59:48,645 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mail.ru/mail.ru/index.html 05:59:49 INFO - 2015-11-19 05:59:49,707 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/guardian.co.uk/www.guardian.co.uk/index.html 06:00:01 INFO - 2015-11-19 06:00:01,395 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/google.com/www.google.com/search@q=mozilla.html 06:00:02 INFO - 2015-11-19 06:00:02,880 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/goo.ne.jp/goo.ne.jp/index.html 06:00:05 INFO - 2015-11-19 06:00:05,411 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/beatonna.livejournal.com/beatonna.livejournal.com/index.html 06:00:05 INFO - 2015-11-19 06:00:05,818 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/dailymotion.com/www.dailymotion.com/us.html 06:00:08 INFO - 2015-11-19 06:00:08,536 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/chinaz.com/chinaz.com/index.html 06:00:11 INFO - 2015-11-19 06:00:11,552 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/chemistry.about.com/chemistry.about.com/index.html 06:00:12 INFO - 2015-11-19 06:00:12,286 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/imgur.com/imgur.com/gallery/index.html 06:00:12 INFO - 2015-11-19 06:00:12,959 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/whois.domaintools.com/whois.domaintools.com/mozilla.com.html 06:00:13 INFO - 2015-11-19 06:00:13,256 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/naver.com/www.naver.com/index.html 06:00:14 INFO - 2015-11-19 06:00:14,459 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/tudou.com/www.tudou.com/index.html 06:00:14 INFO - 2015-11-19 06:00:14,645 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/thepiratebay.org/thepiratebay.org/top/201.html 06:00:14 INFO - 2015-11-19 06:00:14,943 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/media.photobucket.com/media.photobucket.com/image/funny%20gif/findstuff22/Best%20Images/Funny/funny-gif1.jpg@o=1.html 06:00:16 ERROR - 2015-11-19 06:00:16,990 DEBUG : BROWSER_OUTPUT: ???????????????????: TJavaScript error: http://localhost:1837/tests/tp5n/page.renren.com/page.renren.com/index.html, line 719: TypeError: XN.Cookie is undefined 06:00:17 INFO - 2015-11-19 06:00:17,084 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/page.renren.com/page.renren.com/index.html 06:00:17 INFO - 2015-11-19 06:00:17,411 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/myspace.com/www.myspace.com/albumart.html 06:00:19 INFO - 2015-11-19 06:00:19,520 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/ezinearticles.com/ezinearticles.com/index.html@Migraine-Ocular---The-Eye-Migraines&id=4684133.html 06:00:19 INFO - 2015-11-19 06:00:19,645 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/reddit.com/www.reddit.com/index.html 06:00:19 INFO - 2015-11-19 06:00:19,770 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mashable.com/mashable.com/index.html 06:00:20 INFO - 2015-11-19 06:00:20,256 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/wsj.com/online.wsj.com/home-page.html 06:00:20 INFO - 2015-11-19 06:00:20,552 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/cgi.ebay.com/cgi.ebay.com/ALL-NEW-KINDLE-3-eBOOK-WIRELESS-READING-DEVICE-W-WIFI-/130496077314@pt=LH_DefaultDomain_0&hash=item1e622c1e02.html 06:00:20 INFO - 2015-11-19 06:00:20,770 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/guardian.co.uk/www.guardian.co.uk/index.html 06:00:21 INFO - 2015-11-19 06:00:21,006 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/store.apple.com/store.apple.com/us@mco=Nzc1MjMwNA.html 06:00:21 INFO - 2015-11-19 06:00:21,677 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/noimpactman.typepad.com/noimpactman.typepad.com/index.html 06:00:24 INFO - 2015-11-19 06:00:24,348 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/imdb.com/www.imdb.com/title/tt1099212/index.html 06:00:24 INFO - 2015-11-19 06:00:24,973 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/spiegel.de/www.spiegel.de/index.html 06:00:25 INFO - 2015-11-19 06:00:25,145 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/indiatimes.com/www.indiatimes.com/index.html 06:00:25 INFO - 2015-11-19 06:00:25,834 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/stackoverflow.com/stackoverflow.com/questions/184618/what-is-the-best-comment-in-source-code-you-have-ever-encountered.html 06:00:26 INFO - 2015-11-19 06:00:26,740 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/globo.com/www.globo.com/index.html 06:00:27 INFO - 2015-11-19 06:00:27,286 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mail.ru/mail.ru/index.html 06:00:27 INFO - 2015-11-19 06:00:27,286 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/163.com/www.163.com/index.html 06:00:27 INFO - 2015-11-19 06:00:27,645 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/uol.com.br/www.uol.com.br/index.html 06:00:29 INFO - 2015-11-19 06:00:29,193 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/yelp.com/www.yelp.com/biz/alexanders-steakhouse-cupertino.html 06:00:29 INFO - 2015-11-19 06:00:29,286 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/cnn.com/www.cnn.com/index.html 06:00:30 INFO - 2015-11-19 06:00:30,723 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/ifeng.com/ifeng.com/index.html 06:00:31 INFO - 2015-11-19 06:00:31,568 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/bbc.co.uk/www.bbc.co.uk/news/index.html 06:00:31 INFO - 2015-11-19 06:00:31,818 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/dailymail.co.uk/www.dailymail.co.uk/ushome/index.html 06:00:32 INFO - 2015-11-19 06:00:32,427 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/bild.de/www.bild.de/index.html 06:00:33 INFO - 2015-11-19 06:00:33,036 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/sohu.com/www.sohu.com/index.html 06:00:33 INFO - 2015-11-19 06:00:33,490 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/56.com/www.56.com/index.html 06:00:33 INFO - 2015-11-19 06:00:33,709 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/rakuten.co.jp/www.rakuten.co.jp/index.html 06:00:34 INFO - 2015-11-19 06:00:34,115 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/reuters.com/www.reuters.com/index.html 06:00:34 INFO - 2015-11-19 06:00:34,348 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/huffingtonpost.com/www.huffingtonpost.com/index.html 06:00:34 INFO - 2015-11-19 06:00:34,506 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/xinhuanet.com/xinhuanet.com/index.html 06:00:34 INFO - 2015-11-19 06:00:34,645 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/youku.com/www.youku.com/index.html 06:00:35 INFO - 2015-11-19 06:00:35,848 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/amazon.com/www.amazon.com/Kindle-Wireless-Reader-Wifi-Graphite/dp/B002Y27P3M/507846.html 06:00:47 INFO - 2015-11-19 06:00:47,443 DEBUG : BROWSER_OUTPUT: 1447941647450 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 06:00:59 INFO - 2015-11-19 06:00:59,020 DEBUG : BROWSER_OUTPUT: total tab switch time:2418.8050000000003 06:01:00 INFO - 2015-11-19 06:01:00,020 DEBUG : BROWSER_OUTPUT: Cycle 1(4): loaded chrome://tabswitch/content/test.html#auto (next: chrome://tabswitch/content/test.html#auto) 06:01:01 INFO - 2015-11-19 06:01:01,645 DEBUG : BROWSER_OUTPUT: RSS: Main: 353652736 06:01:01 INFO - 2015-11-19 06:01:01,645 DEBUG : BROWSER_OUTPUT: 06:01:03 INFO - 2015-11-19 06:01:03,084 DEBUG : BROWSER_OUTPUT: Loaded: about:blank 06:01:03 INFO - 2015-11-19 06:01:03,270 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mail.ru/mail.ru/index.html 06:01:04 INFO - 2015-11-19 06:01:04,302 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/guardian.co.uk/www.guardian.co.uk/index.html 06:01:15 INFO - 2015-11-19 06:01:15,990 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/google.com/www.google.com/search@q=mozilla.html 06:01:17 INFO - 2015-11-19 06:01:17,584 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/goo.ne.jp/goo.ne.jp/index.html 06:01:20 INFO - 2015-11-19 06:01:20,224 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/beatonna.livejournal.com/beatonna.livejournal.com/index.html 06:01:22 INFO - 2015-11-19 06:01:22,990 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/chemistry.about.com/chemistry.about.com/index.html 06:01:23 INFO - 2015-11-19 06:01:23,240 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/chinaz.com/chinaz.com/index.html 06:01:23 INFO - 2015-11-19 06:01:23,411 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/dailymotion.com/www.dailymotion.com/us.html 06:01:26 INFO - 2015-11-19 06:01:26,302 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/tudou.com/www.tudou.com/index.html 06:01:26 INFO - 2015-11-19 06:01:26,881 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/imgur.com/imgur.com/gallery/index.html 06:01:27 INFO - 2015-11-19 06:01:27,490 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/whois.domaintools.com/whois.domaintools.com/mozilla.com.html 06:01:28 INFO - 2015-11-19 06:01:28,036 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/naver.com/www.naver.com/index.html 06:01:29 INFO - 2015-11-19 06:01:29,427 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/thepiratebay.org/thepiratebay.org/top/201.html 06:01:29 INFO - 2015-11-19 06:01:29,661 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/media.photobucket.com/media.photobucket.com/image/funny%20gif/findstuff22/Best%20Images/Funny/funny-gif1.jpg@o=1.html 06:01:30 INFO - 2015-11-19 06:01:30,397 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/wsj.com/online.wsj.com/home-page.html 06:01:31 ERROR - 2015-11-19 06:01:31,694 DEBUG : BROWSER_OUTPUT: ???????????????????: TJavaScript error: http://localhost:1837/tests/tp5n/page.renren.com/page.renren.com/index.html, line 719: TypeError: XN.Cookie is undefined 06:01:31 INFO - 2015-11-19 06:01:31,786 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/page.renren.com/page.renren.com/index.html 06:01:32 INFO - 2015-11-19 06:01:32,022 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/noimpactman.typepad.com/noimpactman.typepad.com/index.html 06:01:32 INFO - 2015-11-19 06:01:32,069 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/myspace.com/www.myspace.com/albumart.html 06:01:32 INFO - 2015-11-19 06:01:32,897 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/cgi.ebay.com/cgi.ebay.com/ALL-NEW-KINDLE-3-eBOOK-WIRELESS-READING-DEVICE-W-WIFI-/130496077314@pt=LH_DefaultDomain_0&hash=item1e622c1e02.html 06:01:33 INFO - 2015-11-19 06:01:33,147 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/guardian.co.uk/www.guardian.co.uk/index.html 06:01:34 INFO - 2015-11-19 06:01:34,069 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/reddit.com/www.reddit.com/index.html 06:01:34 INFO - 2015-11-19 06:01:34,881 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/ezinearticles.com/ezinearticles.com/index.html@Migraine-Ocular---The-Eye-Migraines&id=4684133.html 06:01:35 INFO - 2015-11-19 06:01:35,069 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/store.apple.com/store.apple.com/us@mco=Nzc1MjMwNA.html 06:01:35 INFO - 2015-11-19 06:01:35,849 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mashable.com/mashable.com/index.html 06:01:36 INFO - 2015-11-19 06:01:36,756 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/stackoverflow.com/stackoverflow.com/questions/184618/what-is-the-best-comment-in-source-code-you-have-ever-encountered.html 06:01:38 INFO - 2015-11-19 06:01:38,052 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/mail.ru/mail.ru/index.html 06:01:39 INFO - 2015-11-19 06:01:38,990 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/spiegel.de/www.spiegel.de/index.html 06:01:39 INFO - 2015-11-19 06:01:39,052 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/indiatimes.com/www.indiatimes.com/index.html 06:01:39 INFO - 2015-11-19 06:01:39,724 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/imdb.com/www.imdb.com/title/tt1099212/index.html 06:01:40 INFO - 2015-11-19 06:01:40,944 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/163.com/www.163.com/index.html 06:01:41 INFO - 2015-11-19 06:01:41,334 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/uol.com.br/www.uol.com.br/index.html 06:01:41 INFO - 2015-11-19 06:01:41,724 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/globo.com/www.globo.com/index.html 06:01:44 INFO - 2015-11-19 06:01:44,161 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/reuters.com/www.reuters.com/index.html 06:01:44 INFO - 2015-11-19 06:01:44,599 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/yelp.com/www.yelp.com/biz/alexanders-steakhouse-cupertino.html 06:01:45 INFO - 2015-11-19 06:01:45,084 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/dailymail.co.uk/www.dailymail.co.uk/ushome/index.html 06:01:45 INFO - 2015-11-19 06:01:45,459 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/bbc.co.uk/www.bbc.co.uk/news/index.html 06:01:45 INFO - 2015-11-19 06:01:45,474 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/cnn.com/www.cnn.com/index.html 06:01:45 INFO - 2015-11-19 06:01:45,631 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/ifeng.com/ifeng.com/index.html 06:01:46 INFO - 2015-11-19 06:01:46,802 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/xinhuanet.com/xinhuanet.com/index.html 06:01:46 INFO - 2015-11-19 06:01:46,990 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/bild.de/www.bild.de/index.html 06:01:47 INFO - 2015-11-19 06:01:47,115 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/sohu.com/www.sohu.com/index.html 06:01:47 INFO - 2015-11-19 06:01:47,865 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/56.com/www.56.com/index.html 06:01:48 INFO - 2015-11-19 06:01:48,022 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/huffingtonpost.com/www.huffingtonpost.com/index.html 06:01:48 INFO - 2015-11-19 06:01:48,272 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/rakuten.co.jp/www.rakuten.co.jp/index.html 06:01:48 INFO - 2015-11-19 06:01:48,536 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/youku.com/www.youku.com/index.html 06:01:49 INFO - 2015-11-19 06:01:49,661 DEBUG : BROWSER_OUTPUT: Loaded: http://localhost:1837/tests/tp5n/amazon.com/www.amazon.com/Kindle-Wireless-Reader-Wifi-Graphite/dp/B002Y27P3M/507846.html 06:02:01 INFO - 2015-11-19 06:02:01,974 DEBUG : BROWSER_OUTPUT: 1447941721991 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 06:02:12 INFO - 2015-11-19 06:02:12,990 DEBUG : BROWSER_OUTPUT: total tab switch time:2416.884999999951 06:02:14 INFO - 2015-11-19 06:02:13,990 DEBUG : BROWSER_OUTPUT: Cycle 1(5): loaded chrome://tabswitch/content/test.html#auto (next: chrome://tabswitch/content/test.html#auto) 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: RSS: Main: 426500096 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |0;amazon.com/www.amazon.com/Kindle-Wireless-Reader-Wifi-Graphite/dp/B002Y27P3M/507846.html;70.56500000000233;65.375;69.81999999999971;69.47499999999854;77.375 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |1;cgi.ebay.com/cgi.ebay.com/ALL-NEW-KINDLE-3-eBOOK-WIRELESS-READING-DEVICE-W-WIFI-/130496077314@pt=LH_DefaultDomain_0&hash=item1e622c1e02.html;75.16500000000087;65.35499999999593;67.88999999999942;70.08499999999913;68.07499999999709 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |2;163.com/www.163.com/index.html;86.44999999999709;60.01499999999942;58.904999999998836;57.56999999999971;65.03499999999622 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |3;mail.ru/mail.ru/index.html;77.52500000000146;58.46499999999651;57.154999999998836;58.229999999995925;57.8949999999968 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |4;bbc.co.uk/www.bbc.co.uk/news/index.html;49.83499999999913;48.78499999999622;48.75;59.224999999998545;51.49000000000524 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |5;store.apple.com/store.apple.com/us@mco=Nzc1MjMwNA.html;76.06500000000233;111.72000000000116;79.54000000000087;78.79500000000553;75.37999999999738 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |6;imdb.com/www.imdb.com/title/tt1099212/index.html;58.3550000000032;57.5199999999968;59.2300000000032;56.395000000004075;57.48999999999796 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |7;cnn.com/www.cnn.com/index.html;52.70999999999913;68.02500000000146;56.25499999999738;57.794999999998254;53.46500000000378 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |8;sohu.com/www.sohu.com/index.html;60.00499999999738;60.724999999998545;59.32999999999447;57.724999999998545;56.724999999998545 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |9;youku.com/www.youku.com/index.html;57.05000000000291;68.51499999999942;56.31999999999971;55.44000000000233;55.69499999999971 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |10;ifeng.com/ifeng.com/index.html;57.66000000000349;58.145000000004075;58.075000000004366;59.50500000000466;55.979999999995925 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |11;tudou.com/www.tudou.com/index.html;50.74499999999534;52.58499999999913;50.19999999999709;48.78500000000349;52.04000000000087 06:02:14 INFO - 2015-11-19 06:02:14,240 DEBUG : BROWSER_OUTPUT: |12;chemistry.about.com/chemistry.about.com/index.html;47.05500000000029;85.13500000000204;48.16500000000087;46.455000000001746;47.8949999999968 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |13;beatonna.livejournal.com/beatonna.livejournal.com/index.html;42.4149999999936;42.33499999999913;45.68000000000029;45.18999999999505;46.30000000000291 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |14;rakuten.co.jp/www.rakuten.co.jp/index.html;53.49499999999534;52.73999999999796;55.83499999999913;57.94499999999971;58.349999999998545 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |15;uol.com.br/www.uol.com.br/index.html;53.349999999998545;54.3949999999968;58.24500000000262;53.86500000000524;58.145000000004075 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |16;thepiratebay.org/thepiratebay.org/top/201.html;48.95999999999913;47.229999999995925;44.92500000000291;45.140000000006694;44.58499999999913 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |17;page.renren.com/page.renren.com/index.html;44.99500000000262;46.200000000004366;45.38500000000204;45.25499999999738;44.73500000000058 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |18;chinaz.com/chinaz.com/index.html;59.86500000000524;94.625;63.16000000000349;64.93499999999767;62.82999999999447 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |19;globo.com/www.globo.com/index.html;56.84000000000378;56.48500000000058;56.515000000006694;56.29000000000087;57.25499999999738 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |20;spiegel.de/www.spiegel.de/index.html;44.220000000001164;43.13999999999942;43.11000000000058;43.06999999999971;42.31499999999505 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |21;dailymotion.com/www.dailymotion.com/us.html;61.04000000000087;91.85499999999593;59.06999999999971;55.47500000000582;54.724999999998545 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |22;goo.ne.jp/goo.ne.jp/index.html;51.575000000004366;68.12999999999738;54.05999999999767;52.845000000001164;59.19000000000233 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |23;stackoverflow.com/stackoverflow.com/questions/184618/what-is-the-best-comment-in-source-code-you-have-ever-encountered.html;53.580000000001746;49.66000000000349;56.604999999995925;50.66500000000087;49.78500000000349 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |24;ezinearticles.com/ezinearticles.com/index.html@Migraine-Ocular---The-Eye-Migraines&id=4684133.html;44.61499999999796;59.38499999999476;45.029999999998836;46.30500000000029;46.96499999999651 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |25;huffingtonpost.com/www.huffingtonpost.com/index.html;73.53499999999622;49.44000000000233;47.84000000000378;49.87000000000262;58.205000000001746 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |26;media.photobucket.com/media.photobucket.com/image/funny%20gif/findstuff22/Best%20Images/Funny/funny-gif1.jpg@o=1.html;64.56500000000233;79.05500000000029;64.09500000000116;67.09000000000378;59.63500000000204 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |27;imgur.com/imgur.com/gallery/index.html;52.669999999998254;45.974999999998545;53.30500000000029;42.81999999999971;44.974999999998545 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |28;reddit.com/www.reddit.com/index.html;50.9600000000064;49.1449999999968;49.8550000000032;50.88499999999476;53.294999999998254 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |29;noimpactman.typepad.com/noimpactman.typepad.com/index.html;40.83499999999185;47.80500000000757;44.970000000001164;45.59499999999389;44.50500000000466 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |30;myspace.com/www.myspace.com/albumart.html;61.205000000001746;57.3350000000064;60.53499999999622;59.705000000001746;56.25499999999738 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |31;mashable.com/mashable.com/index.html;47.99000000000524;50.495000000009895;44.919999999998254;47.70499999999447;46.29000000000087 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |32;dailymail.co.uk/www.dailymail.co.uk/ushome/index.html;56.80500000000757;59.919999999998254;60.53499999999622;58.86499999999069;60.73999999999796 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |33;whois.domaintools.com/whois.domaintools.com/mozilla.com.html;36.810000000012224;40.18000000000757;37.51499999999942;40.625;44.82499999999709 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |34;indiatimes.com/www.indiatimes.com/index.html;54.76499999999942;59.52500000000873;57.479999999995925;58.91000000000349;55.904999999998836 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |35;reuters.com/www.reuters.com/index.html;44.080000000001746;46.7899999999936;45.74499999999534;43.86000000000058;47.76999999998952 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |36;xinhuanet.com/xinhuanet.com/index.html;63.94000000000233;71.55999999999767;66.52499999999418;67.54499999999825;67.99000000000524 06:02:14 INFO - 2015-11-19 06:02:14,256 DEBUG : BROWSER_OUTPUT: |37;56.com/www.56.com/index.html;62.76499999999942;63.43000000000757;60.254999999990105;65.7899999999936;60.69999999999709 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: |38;bild.de/www.bild.de/index.html;41.55500000000757;42.770000000004075;42.35000000000582;41.68499999999767;42.71999999998661 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: |39;guardian.co.uk/www.guardian.co.uk/index.html;68.17499999998836;34.13499999999476;53.229999999995925;52.0850000000064;52.92500000000291 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: |40;naver.com/www.naver.com/index.html;111.36000000000058;71.80000000000291;66.125;68.25999999999476;64.82499999999709 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: |41;yelp.com/www.yelp.com/biz/alexanders-steakhouse-cupertino.html;61.31500000000233;63.19000000000233;57.8350000000064;58.61999999999534;60.19499999999243 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: |42;wsj.com/online.wsj.com/home-page.html;56.38499999999476;55.05000000000291;51.11499999999069;53.13000000000466;51.93499999999767 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: |43;google.com/www.google.com/search@q=mozilla.html;44.604999999995925;63.70000000001164;53.39999999999418;53.30000000000291;43.47500000000582 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,3976 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: __startTimestamp1447941734260__endTimestamp 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: Number of tests: 44 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: [#0] amazon.com/www.amazon.com/Kindle-Wireless-Reader-Wifi-Graphite/dp/B002Y27P3M/507846.html Cycles:5 Average:70.52 Median:69.82 stddev:4.33 (6.2%) stddev-sans-first:5.00 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: Values: 70.6 65.4 69.8 69.5 77.4 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: [#1] cgi.ebay.com/cgi.ebay.com/ALL-NEW-KINDLE-3-eBOOK-WIRELESS-READING-DEVICE-W-WIFI-/130496077314@pt=LH_DefaultDomain_0&hash=item1e622c1e02.html Cycles:5 Average:69.31 Median:68.07 stddev:3.68 (5.4%) stddev-sans-first:1.94 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: Values: 75.2 65.4 67.9 70.1 68.1 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: [#2] 163.com/www.163.com/index.html Cycles:5 Average:65.59 Median:60.01 stddev:12.00 (20.0%) stddev-sans-first:3.26 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: Values: 86.4 60.0 58.9 57.6 65.0 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: [#3] mail.ru/mail.ru/index.html Cycles:5 Average:61.85 Median:58.23 stddev:8.77 (15.1%) stddev-sans-first:0.57 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: Values: 77.5 58.5 57.2 58.2 57.9 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: [#4] bbc.co.uk/www.bbc.co.uk/news/index.html Cycles:5 Average:51.62 Median:49.83 stddev:4.40 (8.8%) stddev-sans-first:4.94 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: Values: 49.8 48.8 48.8 59.2 51.5 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: [#5] store.apple.com/store.apple.com/us@mco=Nzc1MjMwNA.html Cycles:5 Average:84.30 Median:76.07 stddev:15.43 (20.3%) stddev-sans-first:17.00 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: Values: 76.1 111.7 79.5 78.8 75.4 06:02:14 INFO - 2015-11-19 06:02:14,319 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: [#6] imdb.com/www.imdb.com/title/tt1099212/index.html Cycles:5 Average:57.80 Median:57.52 stddev:1.06 (1.8%) stddev-sans-first:1.17 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: Values: 58.4 57.5 59.2 56.4 57.5 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: [#7] cnn.com/www.cnn.com/index.html Cycles:5 Average:57.65 Median:56.25 stddev:6.15 (10.9%) stddev-sans-first:6.35 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: Values: 52.7 68.0 56.3 57.8 53.5 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: [#8] sohu.com/www.sohu.com/index.html Cycles:5 Average:58.90 Median:59.33 stddev:1.65 (2.8%) stddev-sans-first:1.76 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: Values: 60.0 60.7 59.3 57.7 56.7 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: [#9] youku.com/www.youku.com/index.html Cycles:5 Average:58.60 Median:56.32 stddev:5.58 (9.9%) stddev-sans-first:6.36 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: Values: 57.1 68.5 56.3 55.4 55.7 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: [#10] ifeng.com/ifeng.com/index.html Cycles:5 Average:57.87 Median:58.08 stddev:1.27 (2.2%) stddev-sans-first:1.45 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: Values: 57.7 58.1 58.1 59.5 56.0 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: [#11] tudou.com/www.tudou.com/index.html Cycles:5 Average:50.87 Median:50.74 stddev:1.51 (3.0%) stddev-sans-first:1.74 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: Values: 50.7 52.6 50.2 48.8 52.0 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: [#12] chemistry.about.com/chemistry.about.com/index.html Cycles:5 Average:54.94 Median:47.89 stddev:16.89 (35.3%) stddev-sans-first:18.83 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: Values: 47.1 85.1 48.2 46.5 47.9 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: [#13] beatonna.livejournal.com/beatonna.livejournal.com/index.html Cycles:5 Average:44.38 Median:45.19 stddev:1.88 (4.2%) stddev-sans-first:1.75 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: Values: 42.4 42.3 45.7 45.2 46.3 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: [#14] rakuten.co.jp/www.rakuten.co.jp/index.html Cycles:5 Average:55.67 Median:55.83 stddev:2.53 (4.5%) stddev-sans-first:2.57 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: Values: 53.5 52.7 55.8 57.9 58.3 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: [#15] uol.com.br/www.uol.com.br/index.html Cycles:5 Average:55.60 Median:54.39 stddev:2.40 (4.4%) stddev-sans-first:2.36 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: Values: 53.3 54.4 58.2 53.9 58.1 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: [#16] thepiratebay.org/thepiratebay.org/top/201.html Cycles:5 Average:46.17 Median:45.14 stddev:1.87 (4.1%) stddev-sans-first:1.20 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: Values: 49.0 47.2 44.9 45.1 44.6 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,334 DEBUG : BROWSER_OUTPUT: [#17] page.renren.com/page.renren.com/index.html Cycles:5 Average:45.31 Median:45.25 stddev:0.55 (1.2%) stddev-sans-first:0.61 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: Values: 45.0 46.2 45.4 45.3 44.7 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: [#18] chinaz.com/chinaz.com/index.html Cycles:5 Average:69.08 Median:63.16 stddev:14.39 (22.8%) stddev-sans-first:15.52 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: Values: 59.9 94.6 63.2 64.9 62.8 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: [#19] globo.com/www.globo.com/index.html Cycles:5 Average:56.68 Median:56.52 stddev:0.38 (0.7%) stddev-sans-first:0.42 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: Values: 56.8 56.5 56.5 56.3 57.3 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: [#20] spiegel.de/www.spiegel.de/index.html Cycles:5 Average:43.17 Median:43.11 stddev:0.68 (1.6%) stddev-sans-first:0.40 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: Values: 44.2 43.1 43.1 43.1 42.3 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: [#21] dailymotion.com/www.dailymotion.com/us.html Cycles:5 Average:64.43 Median:59.07 stddev:15.55 (26.3%) stddev-sans-first:17.82 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: Values: 61.0 91.9 59.1 55.5 54.7 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: [#22] goo.ne.jp/goo.ne.jp/index.html Cycles:5 Average:57.16 Median:54.06 stddev:6.78 (12.5%) stddev-sans-first:6.95 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: Values: 51.6 68.1 54.1 52.8 59.2 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: [#23] stackoverflow.com/stackoverflow.com/questions/184618/what-is-the-best-comment-in-source-code-you-have-ever-encountered.html Cycles:5 Average:52.06 Median:50.67 stddev:2.99 (5.9%) stddev-sans-first:3.31 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: Values: 53.6 49.7 56.6 50.7 49.8 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: [#24] ezinearticles.com/ezinearticles.com/index.html@Migraine-Ocular---The-Eye-Migraines&id=4684133.html Cycles:5 Average:48.46 Median:46.31 stddev:6.18 (13.3%) stddev-sans-first:6.69 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: Values: 44.6 59.4 45.0 46.3 47.0 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: [#25] huffingtonpost.com/www.huffingtonpost.com/index.html Cycles:5 Average:55.78 Median:49.87 stddev:10.72 (21.5%) stddev-sans-first:4.66 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: Values: 73.5 49.4 47.8 49.9 58.2 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: [#26] media.photobucket.com/media.photobucket.com/image/funny%20gif/findstuff22/Best%20Images/Funny/funny-gif1.jpg@o=1.html Cycles:5 Average:66.89 Median:64.57 stddev:7.31 (11.3%) stddev-sans-first:8.31 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: Values: 64.6 79.1 64.1 67.1 59.6 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: [#27] imgur.com/imgur.com/gallery/index.html Cycles:5 Average:47.95 Median:45.97 stddev:4.74 (10.3%) stddev-sans-first:4.55 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: Values: 52.7 46.0 53.3 42.8 45.0 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,349 DEBUG : BROWSER_OUTPUT: [#28] reddit.com/www.reddit.com/index.html Cycles:5 Average:50.83 Median:50.88 stddev:1.57 (3.1%) stddev-sans-first:1.81 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: Values: 51.0 49.1 49.9 50.9 53.3 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: [#29] noimpactman.typepad.com/noimpactman.typepad.com/index.html Cycles:5 Average:44.74 Median:44.97 stddev:2.52 (5.6%) stddev-sans-first:1.46 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: Values: 40.8 47.8 45.0 45.6 44.5 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: [#30] myspace.com/www.myspace.com/albumart.html Cycles:5 Average:59.01 Median:59.71 stddev:2.12 (3.6%) stddev-sans-first:2.00 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: Values: 61.2 57.3 60.5 59.7 56.3 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: [#31] mashable.com/mashable.com/index.html Cycles:5 Average:47.48 Median:47.70 stddev:2.08 (4.4%) stddev-sans-first:2.38 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: Values: 48.0 50.5 44.9 47.7 46.3 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: [#32] dailymail.co.uk/www.dailymail.co.uk/ushome/index.html Cycles:5 Average:59.37 Median:59.92 stddev:1.61 (2.7%) stddev-sans-first:0.84 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: Values: 56.8 59.9 60.5 58.9 60.7 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: [#33] whois.domaintools.com/whois.domaintools.com/mozilla.com.html Cycles:5 Average:39.99 Median:40.18 stddev:3.16 (7.9%) stddev-sans-first:3.02 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: Values: 36.8 40.2 37.5 40.6 44.8 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: [#34] indiatimes.com/www.indiatimes.com/index.html Cycles:5 Average:57.32 Median:57.48 stddev:2.00 (3.5%) stddev-sans-first:1.61 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: Values: 54.8 59.5 57.5 58.9 55.9 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: [#35] reuters.com/www.reuters.com/index.html Cycles:5 Average:45.65 Median:45.74 stddev:1.69 (3.7%) stddev-sans-first:1.67 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: Values: 44.1 46.8 45.7 43.9 47.8 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: [#36] xinhuanet.com/xinhuanet.com/index.html Cycles:5 Average:67.51 Median:67.54 stddev:2.75 (4.1%) stddev-sans-first:2.19 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: Values: 63.9 71.6 66.5 67.5 68.0 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: [#37] 56.com/www.56.com/index.html Cycles:5 Average:62.59 Median:62.76 stddev:2.24 (3.6%) stddev-sans-first:2.58 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: Values: 62.8 63.4 60.3 65.8 60.7 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: [#38] bild.de/www.bild.de/index.html Cycles:5 Average:42.22 Median:42.35 stddev:0.57 (1.3%) stddev-sans-first:0.50 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: Values: 41.6 42.8 42.4 41.7 42.7 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,365 DEBUG : BROWSER_OUTPUT: [#39] guardian.co.uk/www.guardian.co.uk/index.html Cycles:5 Average:52.11 Median:52.93 stddev:12.07 (22.8%) stddev-sans-first:9.32 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: Values: 68.2 34.1 53.2 52.1 52.9 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: [#40] naver.com/www.naver.com/index.html Cycles:5 Average:76.47 Median:66.13 stddev:19.68 (29.8%) stddev-sans-first:3.05 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: Values: 111.4 71.8 66.1 68.3 64.8 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: [#41] yelp.com/www.yelp.com/biz/alexanders-steakhouse-cupertino.html Cycles:5 Average:60.23 Median:60.19 stddev:2.14 (3.6%) stddev-sans-first:2.37 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: Values: 61.3 63.2 57.8 58.6 60.2 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: [#42] wsj.com/online.wsj.com/home-page.html Cycles:5 Average:53.52 Median:53.13 stddev:2.18 (4.1%) stddev-sans-first:1.71 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: Values: 56.4 55.1 51.1 53.1 51.9 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: [#43] google.com/www.google.com/search@q=mozilla.html Cycles:5 Average:51.70 Median:53.30 stddev:8.18 (15.3%) stddev-sans-first:8.26 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: Values: 44.6 63.7 53.4 53.3 43.5 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:02:14 INFO - 2015-11-19 06:02:14,381 DEBUG : BROWSER_OUTPUT: 06:02:14 INFO - 2015-11-19 06:02:14,897 INFO : Browser exited with error code: 0 06:02:16 INFO - 2015-11-19 06:02:16,209 INFO : Completed test tps (00:06:31) 06:02:16 INFO - 2015-11-19 06:02:16,459 INFO : Completed test suite (00:06:31) 06:02:16 INFO - 2015-11-19 06:02:16,459 DEBUG : Working with test: tps 06:02:16 INFO - 2015-11-19 06:02:16,459 DEBUG : Generating results file: tps 06:02:16 INFO - 2015-11-19 06:02:16,459 INFO : Posting result 0 of 1 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:02:16 INFO - 2015-11-19 06:02:16,631 DEBUG : process_Request line: tps graph.html#tests=[[329,132,37]] 06:02:16 INFO - 2015-11-19 06:02:16,631 DEBUG : process_Request line: tps 54.36 graph.html#tests=[[329,132,37]] 06:02:16 INFO - 2015-11-19 06:02:16,631 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [70.56500000000233, 65.375, 69.81999999999971, 69.47499999999854, 77.375], "unit": "ms", "name": "amazon.com", "value": 69.64749999999913}, {"lowerIsBetter": true, "replicates": [75.16500000000087, 65.35499999999593, 67.88999999999942, 70.08499999999913, 68.07499999999709], "unit": "ms", "name": "cgi.ebay.com", "value": 67.98249999999825}, {"lowerIsBetter": true, "replicates": [86.44999999999709, 60.01499999999942, 58.904999999998836, 57.56999999999971, 65.03499999999622], "unit": "ms", "name": "163.com", "value": 59.45999999999913}, {"lowerIsBetter": true, "replicates": [77.52500000000146, 58.46499999999651, 57.154999999998836, 58.229999999995925, 57.8949999999968], "unit": "ms", "name": "mail.ru", "value": 58.06249999999636}, {"lowerIsBetter": true, "replicates": [49.83499999999913, 48.78499999999622, 48.75, 59.224999999998545, 51.49000000000524], "unit": "ms", "name": "bbc.co.uk", "value": 50.13750000000073}, {"lowerIsBetter": true, "replicates": [76.06500000000233, 111.72000000000116, 79.54000000000087, 78.79500000000553, 75.37999999999738], "unit": "ms", "name": "store.apple.com", "value": 79.1675000000032}, {"lowerIsBetter": true, "replicates": [58.3550000000032, 57.5199999999968, 59.2300000000032, 56.395000000004075, 57.48999999999796], "unit": "ms", "name": "imdb.com", "value": 57.50499999999738}, {"lowerIsBetter": true, "replicates": [52.70999999999913, 68.02500000000146, 56.25499999999738, 57.794999999998254, 53.46500000000378], "unit": "ms", "name": "cnn.com", "value": 57.02499999999782}, {"lowerIsBetter": true, "replicates": [60.00499999999738, 60.724999999998545, 59.32999999999447, 57.724999999998545, 56.724999999998545], "unit": "ms", "name": "sohu.com", "value": 58.52749999999651}, {"lowerIsBetter": true, "replicates": [57.05000000000291, 68.51499999999942, 56.31999999999971, 55.44000000000233, 55.69499999999971], "unit": "ms", "name": "youku.com", "value": 56.00749999999971}, {"lowerIsBetter": true, "replicates": [57.66000000000349, 58.145000000004075, 58.075000000004366, 59.50500000000466, 55.979999999995925], "unit": "ms", "name": "ifeng.com", "value": 58.11000000000422}, {"lowerIsBetter": true, "replicates": [50.74499999999534, 52.58499999999913, 50.19999999999709, 48.78500000000349, 52.04000000000087], "unit": "ms", "name": "tudou.com", "value": 51.11999999999898}, {"lowerIsBetter": true, "replicates": [47.05500000000029, 85.13500000000204, 48.16500000000087, 46.455000000001746, 47.8949999999968], "unit": "ms", "name": "chemistry.about.com", "value": 48.029999999998836}, {"lowerIsBetter": true, "replicates": [42.4149999999936, 42.33499999999913, 45.68000000000029, 45.18999999999505, 46.30000000000291], "unit": "ms", "name": "beatonna.livejournal.com", "value": 45.43499999999767}, {"lowerIsBetter": true, "replicates": [53.49499999999534, 52.73999999999796, 55.83499999999913, 57.94499999999971, 58.349999999998545], "unit": "ms", "name": "rakuten.co.jp", "value": 56.88999999999942}, {"lowerIsBetter": true, "replicates": [53.349999999998545, 54.3949999999968, 58.24500000000262, 53.86500000000524, 58.145000000004075], "unit": "ms", "name": "uol.com.br", "value": 56.27000000000044}, {"lowerIsBetter": true, "replicates": [48.95999999999913, 47.229999999995925, 44.92500000000291, 45.140000000006694, 44.58499999999913], "unit": "ms", "name": "thepiratebay.org", "value": 45.0325000000048}, {"lowerIsBetter": true, "replicates": [44.99500000000262, 46.200000000004366, 45.38500000000204, 45.25499999999738, 44.73500000000058], "unit": "ms", "name": "page.renren.com", "value": 45.31999999999971}, {"lowerIsBetter": true, "replicates": [59.86500000000524, 94.625, 63.16000000000349, 64.93499999999767, 62.82999999999447], "unit": "ms", "name": "chinaz.com", "value": 64.04750000000058}, {"lowerIsBetter": true, "replicates": [56.84000000000378, 56.48500000000058, 56.515000000006694, 56.29000000000087, 57.25499999999738], "unit": "ms", "name": "globo.com", "value": 56.50000000000364}, {"lowerIsBetter": true, "replicates": [44.220000000001164, 43.13999999999942, 43.11000000000058, 43.06999999999971, 42.31499999999505], "unit": "ms", "name": "spiegel.de", "value": 43.090000000000146}, {"lowerIsBetter": true, "replicates": [61.04000000000087, 91.85499999999593, 59.06999999999971, 55.47500000000582, 54.724999999998545], "unit": "ms", "name": "dailymotion.com", "value": 57.272500000002765}, {"lowerIsBetter": true, "replicates": [51.575000000004366, 68.12999999999738, 54.05999999999767, 52.845000000001164, 59.19000000000233], "unit": "ms", "name": "goo.ne.jp", "value": 56.625}, {"lowerIsBetter": true, "replicates": [53.580000000001746, 49.66000000000349, 56.604999999995925, 50.66500000000087, 49.78500000000349], "unit": "ms", "name": "stackoverflow.com", "value": 50.22500000000218}, {"lowerIsBetter": true, "replicates": [44.61499999999796, 59.38499999999476, 45.029999999998836, 46.30500000000029, 46.96499999999651], "unit": "ms", "name": "ezinearticles.com", "value": 46.6349999999984}, {"lowerIsBetter": true, "replicates": [73.53499999999622, 49.44000000000233, 47.84000000000378, 49.87000000000262, 58.205000000001746], "unit": "ms", "name": "huffingtonpost.com", "value": 49.655000000002474}, {"lowerIsBetter": true, "replicates": [64.56500000000233, 79.05500000000029, 64.09500000000116, 67.09000000000378, 59.63500000000204], "unit": "ms", "name": "media.photobucket.com", "value": 65.59250000000247}, {"lowerIsBetter": true, "replicates": [52.669999999998254, 45.974999999998545, 53.30500000000029, 42.81999999999971, 44.974999999998545], "unit": "ms", "name": "imgur.com", "value": 45.474999999998545}, {"lowerIsBetter": true, "replicates": [50.9600000000064, 49.1449999999968, 49.8550000000032, 50.88499999999476, 53.294999999998254], "unit": "ms", "name": "reddit.com", "value": 50.36999999999898}, {"lowerIsBetter": true, "replicates": [40.83499999999185, 47.80500000000757, 44.970000000001164, 45.59499999999389, 44.50500000000466], "unit": "ms", "name": "noimpactman.typepad.com", "value": 45.282499999997526}, {"lowerIsBetter": true, "replicates": [61.205000000001746, 57.3350000000064, 60.53499999999622, 59.705000000001746, 56.25499999999738], "unit": "ms", "name": "myspace.com", "value": 58.520000000004075}, {"lowerIsBetter": true, "replicates": [47.99000000000524, 50.495000000009895, 44.919999999998254, 47.70499999999447, 46.29000000000087], "unit": "ms", "name": "mashable.com", "value": 46.99749999999767}, {"lowerIsBetter": true, "replicates": [56.80500000000757, 59.919999999998254, 60.53499999999622, 58.86499999999069, 60.73999999999796], "unit": "ms", "name": "dailymail.co.uk", "value": 60.227499999997235}, {"lowerIsBetter": true, "replicates": [36.810000000012224, 40.18000000000757, 37.51499999999942, 40.625, 44.82499999999709], "unit": "ms", "name": "whois.domaintools.com", "value": 40.40250000000378}, {"lowerIsBetter": true, "replicates": [54.76499999999942, 59.52500000000873, 57.479999999995925, 58.91000000000349, 55.904999999998836], "unit": "ms", "name": "indiatimes.com", "value": 58.19499999999971}, {"lowerIsBetter": true, "replicates": [44.080000000001746, 46.7899999999936, 45.74499999999534, 43.86000000000058, 47.76999999998952], "unit": "ms", "name": "reuters.com", "value": 46.26749999999447}, {"lowerIsBetter": true, "replicates": [63.94000000000233, 71.55999999999767, 66.52499999999418, 67.54499999999825, 67.99000000000524], "unit": "ms", "name": "xinhuanet.com", "value": 67.76750000000175}, {"lowerIsBetter": true, "replicates": [62.76499999999942, 63.43000000000757, 60.254999999990105, 65.7899999999936, 60.69999999999709], "unit": "ms", "name": "56.com", "value": 62.06500000000233}, {"lowerIsBetter": true, "replicates": [41.55500000000757, 42.770000000004075, 42.35000000000582, 41.68499999999767, 42.71999999998661], "unit": "ms", "name": "bild.de", "value": 42.53499999999622}, {"lowerIsBetter": true, "replicates": [68.17499999998836, 34.13499999999476, 53.229999999995925, 52.0850000000064, 52.92500000000291], "unit": "ms", "name": "guardian.co.uk", "value": 52.50500000000466}, {"lowerIsBetter": true, "replicates": [111.36000000000058, 71.80000000000291, 66.125, 68.25999999999476, 64.82499999999709], "unit": "ms", "name": "naver.com", "value": 67.19249999999738}, {"lowerIsBetter": true, "replicates": [61.31500000000233, 63.19000000000233, 57.8350000000064, 58.61999999999534, 60.19499999999243], "unit": "ms", "name": "yelp.com", "value": 59.40749999999389}, {"lowerIsBetter": true, "replicates": [56.38499999999476, 55.05000000000291, 51.11499999999069, 53.13000000000466, 51.93499999999767], "unit": "ms", "name": "wsj.com", "value": 52.532500000001164}, {"lowerIsBetter": true, "replicates": [44.604999999995925, 63.70000000001164, 53.39999999999418, 53.30000000000291, 43.47500000000582], "unit": "ms", "name": "google.com", "value": 53.349999999998545}], "name": "tps", "value": 54.36401652152755}]} 06:02:16 INFO - RETURN: tps: 54.36 06:02:16 INFO - TinderboxPrint: TalosResult: {"graphserver": {"tps": {"url": "http://graphs.mozilla.org/graph.html#tests=[[329,132,37]]", "result": "54.36"}}} 06:02:16 INFO - Return code: 0 06:02:16 ERROR - # TBPL SUCCESS # 06:02:16 INFO - Running post-action listener: _resource_record_post_action 06:02:16 INFO - Running post-run listener: _resource_record_post_run 06:02:17 INFO - Total resource usage - Wall time: 395s; CPU: 10.0%; Read bytes: 73689088; Write bytes: 471932416; Read time: 1020844; Write time: 3287405 06:02:17 INFO - install - Wall time: 3s; CPU: 11.0%; Read bytes: 0; Write bytes: 53434368; Read time: 0; Write time: 61033 06:02:17 INFO - run-tests - Wall time: 393s; CPU: 10.0%; Read bytes: 73689088; Write bytes: 378551296; Read time: 1020844; Write time: 3189556 06:02:17 INFO - Running post-run listener: _upload_blobber_files 06:02:17 INFO - Blob upload gear active. 06:02:17 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 06:02:17 INFO - Copying logs to upload dir... 06:02:17 INFO - mkdir: C:\slave\test\build\upload\logs program finished with exit code 0 elapsedTime=581.041000 ========= master_lag: 4.08 ========= ========= Finished 'c:/mozilla-build/python27/python -u ...' (results: 0, elapsed: 9 mins, 45 secs) (at 2015-11-19 06:02:21.877695) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 06:02:21.878550) ========= 'cmd' '/C' 'for' '%f' 'in' '(*)' 'do' '@type' '%f' in dir C:\slave\test\properties (timeout 1200 secs) watching logfiles {} argv: ['cmd', '/C', 'for', '%f', 'in', '(*)', 'do', '@type', '%f'] environment: ALLUSERSPROFILE=C:\Documents and Settings\All Users APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-143\Application Data COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-XP32-IX-143 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=\Documents and Settings\cltbld.T-XP32-IX-143 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOGONSERVER=\\T-XP32-IX-143 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;.PSC1 PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMFILES=C:\Program Files PROMPT=$P$G PWD=C:\slave\test\properties RUNLOGFILE=C:\slave\\runslave.log SESSIONNAME=Console SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\WINDOWS TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp TEST1=testie TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp USERDOMAIN=T-XP32-IX-143 USERNAME=cltbld USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-143 WINDIR=C:\WINDOWS XPCOM_DEBUG_BREAK=warn using PTY: False build_url:https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip program finished with exit code 0 elapsedTime=0.109000 build_url: 'https://queue.taskcluster.net/v1/task/FRcsFT9hQRuwruSahZbNGQ/artifacts/public/build/firefox-45.0a1.en-US.win32.zip' ========= master_lag: 0.01 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 06:02:22.001664) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 06:02:22.002001) ========= 'rm' '-f' 'oauth.txt' in dir C:\slave\test\. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: ALLUSERSPROFILE=C:\Documents and Settings\All Users APPDATA=C:\Documents and Settings\cltbld.T-XP32-IX-143\Application Data COMMONPROGRAMFILES=C:\Program Files\Common Files COMPUTERNAME=T-XP32-IX-143 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=\Documents and Settings\cltbld.T-XP32-IX-143 KTS_HOME=C:\Program Files\KTS KTS_VERSION=1.19c LOGONSERVER=\\T-XP32-IX-143 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;.PSC1 PROCESSOR_ARCHITECTURE=x86 PROCESSOR_IDENTIFIER=x86 Family 6 Model 30 Stepping 5, GenuineIntel PROCESSOR_LEVEL=6 PROCESSOR_REVISION=1e05 PROGRAMFILES=C:\Program Files PROMPT=$P$G PWD=C:\slave\test RUNLOGFILE=C:\slave\\runslave.log SESSIONNAME=Console SLAVEDIR=C:\slave\ SYSTEMDRIVE=C: SYSTEMROOT=C:\WINDOWS TEMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp TEST1=testie TMP=C:\DOCUME~1\CLTBLD~1.T-X\LOCALS~1\Temp USERDOMAIN=T-XP32-IX-143 USERNAME=cltbld USERPROFILE=C:\Documents and Settings\cltbld.T-XP32-IX-143 WINDIR=C:\WINDOWS XPCOM_DEBUG_BREAK=warn using PTY: False program finished with exit code 0 elapsedTime=0.109000 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 06:02:22.124277) ========= ========= Started reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-11-19 06:02:22.124625) ========= ========= Finished reboot slave lost (results: 0, elapsed: 0 secs) (at 2015-11-19 06:02:22.517868) ========= ========= Total master_lag: 4.29 =========