builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-056 starttime: 1453470732.53 results: success (0) buildid: 20160122032328 builduid: 2320f5f3157644089eea98ed7cf7f1aa revision: 882e6011c18f968f3e6993e4fac60c68ae6366a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.525837) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.526256) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.526805) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006327 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.558536) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.558823) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.598155) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.598438) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004523 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.649619) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.649910) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.650246) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.650527) ========= 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 /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-22 05:52:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.1M=0s 2016-01-22 05:52:13 (35.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.093033 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.757498) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.757810) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021410 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.808338) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:12.808675) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 882e6011c18f968f3e6993e4fac60c68ae6366a0 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 882e6011c18f968f3e6993e4fac60c68ae6366a0 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-22 05:52:14,059 truncating revision to first 12 chars 2016-01-22 05:52:14,059 Setting DEBUG logging. 2016-01-22 05:52:14,059 attempt 1/10 2016-01-22 05:52:14,060 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/882e6011c18f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-22 05:52:14,315 unpacking tar archive at: mozilla-aurora-882e6011c18f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.595844 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:13.417949) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:13.418281) ========= echo 882e6011c18f968f3e6993e4fac60c68ae6366a0 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'882e6011c18f968f3e6993e4fac60c68ae6366a0'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 882e6011c18f968f3e6993e4fac60c68ae6366a0 program finished with exit code 0 elapsedTime=0.004589 script_repo_revision: '882e6011c18f968f3e6993e4fac60c68ae6366a0' ========= master_lag: 0.08 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:13.506150) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:13.506486) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-22 05:52:13.519452) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-01-22 05:52:13.519841) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:52:14 INFO - MultiFileLogger online at 20160122 05:52:14 in /builds/slave/test-pgo 05:52:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 05:52:14 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:52:14 INFO - {'append_to_log': False, 05:52:14 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:52:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:52:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:52:14 INFO - 'branch': 'Mozilla-Aurora', 05:52:14 INFO - 'buildbot_json_path': 'buildprops.json', 05:52:14 INFO - 'config_files': ('talos/linux_config.py',), 05:52:14 INFO - 'default_actions': ('clobber', 05:52:14 INFO - 'read-buildbot-config', 05:52:14 INFO - 'download-and-extract', 05:52:14 INFO - 'populate-webroot', 05:52:14 INFO - 'create-virtualenv', 05:52:14 INFO - 'install', 05:52:14 INFO - 'run-tests'), 05:52:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:52:14 INFO - 'download_minidump_stackwalk': True, 05:52:14 INFO - 'download_symbols': 'ondemand', 05:52:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:52:14 INFO - 'tooltool.py': '/tools/tooltool.py', 05:52:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:52:14 INFO - '/tools/misc-python/virtualenv.py')}, 05:52:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:52:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:52:14 INFO - 'installer_path': 'installer.exe', 05:52:14 INFO - 'log_level': 'info', 05:52:14 INFO - 'log_name': 'talos', 05:52:14 INFO - 'log_to_console': True, 05:52:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:52:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:52:14 INFO - 'opt_config_files': (), 05:52:14 INFO - 'pip_index': False, 05:52:14 INFO - 'sps_profile': False, 05:52:14 INFO - 'sps_profile_interval': 0, 05:52:14 INFO - 'suite': 'dromaeojs', 05:52:14 INFO - 'system_bits': '32', 05:52:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:52:14 INFO - 'title': 'talos-linux64-ix-056', 05:52:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:52:14 INFO - 'use_talos_json': True, 05:52:14 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:52:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:52:14 INFO - 'work_dir': 'build'} 05:52:14 INFO - ##### 05:52:14 INFO - ##### Running clobber step. 05:52:14 INFO - ##### 05:52:14 INFO - Running pre-action listener: _resource_record_pre_action 05:52:14 INFO - Running main action method: clobber 05:52:14 INFO - rmtree: /builds/slave/test-pgo/build 05:52:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:52:15 INFO - Running post-action listener: _resource_record_post_action 05:52:15 INFO - ##### 05:52:15 INFO - ##### Running read-buildbot-config step. 05:52:15 INFO - ##### 05:52:15 INFO - Running pre-action listener: _resource_record_pre_action 05:52:15 INFO - Running main action method: read_buildbot_config 05:52:15 INFO - Using buildbot properties: 05:52:15 INFO - { 05:52:15 INFO - "properties": { 05:52:15 INFO - "buildnumber": 104, 05:52:15 INFO - "product": "firefox", 05:52:15 INFO - "script_repo_revision": "production", 05:52:15 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 05:52:15 INFO - "repository": "", 05:52:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 05:52:15 INFO - "buildid": "20160122032328", 05:52:15 INFO - "slavename": "talos-linux64-ix-056", 05:52:15 INFO - "pgo_build": "True", 05:52:15 INFO - "basedir": "/builds/slave/test-pgo", 05:52:15 INFO - "project": "", 05:52:15 INFO - "platform": "ubuntu64_hw", 05:52:15 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:52:15 INFO - "slavebuilddir": "test", 05:52:15 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 05:52:15 INFO - "branch": "mozilla-aurora", 05:52:15 INFO - "repo_path": "releases/mozilla-aurora", 05:52:15 INFO - "stage_platform": "linux64-pgo", 05:52:15 INFO - "builduid": "2320f5f3157644089eea98ed7cf7f1aa", 05:52:15 INFO - "revision": "882e6011c18f968f3e6993e4fac60c68ae6366a0" 05:52:15 INFO - }, 05:52:15 INFO - "sourcestamp": { 05:52:15 INFO - "repository": "", 05:52:15 INFO - "hasPatch": false, 05:52:15 INFO - "project": "", 05:52:15 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 05:52:15 INFO - "changes": [ 05:52:15 INFO - { 05:52:15 INFO - "category": null, 05:52:15 INFO - "files": [ 05:52:15 INFO - { 05:52:15 INFO - "url": null, 05:52:15 INFO - "name": "https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2" 05:52:15 INFO - } 05:52:15 INFO - ], 05:52:15 INFO - "repository": "", 05:52:15 INFO - "rev": "882e6011c18f968f3e6993e4fac60c68ae6366a0", 05:52:15 INFO - "who": "ttaubert@mozilla.com", 05:52:15 INFO - "when": 1453470698, 05:52:15 INFO - "number": 7500350, 05:52:15 INFO - "comments": "Bug 1230209 - Add more telemetry for Geolocation usage f=bsmedberg r=tanvi,rbarnes,jdm a=sylvestre", 05:52:15 INFO - "project": "", 05:52:15 INFO - "at": "Fri 22 Jan 2016 05:51:38", 05:52:15 INFO - "branch": "mozilla-aurora-linux64-pgo-talos", 05:52:15 INFO - "revlink": "", 05:52:15 INFO - "properties": [ 05:52:15 INFO - [ 05:52:15 INFO - "buildid", 05:52:15 INFO - "20160122032328", 05:52:15 INFO - "Change" 05:52:15 INFO - ], 05:52:15 INFO - [ 05:52:15 INFO - "builduid", 05:52:15 INFO - "2320f5f3157644089eea98ed7cf7f1aa", 05:52:15 INFO - "Change" 05:52:15 INFO - ], 05:52:15 INFO - [ 05:52:15 INFO - "pgo_build", 05:52:15 INFO - "True", 05:52:15 INFO - "Change" 05:52:15 INFO - ] 05:52:15 INFO - ], 05:52:15 INFO - "revision": "882e6011c18f968f3e6993e4fac60c68ae6366a0" 05:52:15 INFO - } 05:52:15 INFO - ], 05:52:15 INFO - "revision": "882e6011c18f968f3e6993e4fac60c68ae6366a0" 05:52:15 INFO - } 05:52:15 INFO - } 05:52:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2. 05:52:15 INFO - Running post-action listener: _resource_record_post_action 05:52:15 INFO - ##### 05:52:15 INFO - ##### Running download-and-extract step. 05:52:15 INFO - ##### 05:52:15 INFO - Running pre-action listener: _resource_record_pre_action 05:52:15 INFO - Running main action method: download_and_extract 05:52:15 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:52:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:52:15 INFO - https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:52:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/test_packages.json 05:52:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/test_packages.json 05:52:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:52:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:52:17 INFO - Downloaded 1302 bytes. 05:52:17 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:52:17 INFO - Using the following test package requirements: 05:52:17 INFO - {u'common': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 05:52:17 INFO - u'cppunittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 05:52:17 INFO - u'firefox-45.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 05:52:17 INFO - u'jittest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 05:52:17 INFO - u'jsshell-linux-x86_64.zip'], 05:52:17 INFO - u'mochitest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 05:52:17 INFO - u'firefox-45.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 05:52:17 INFO - u'mozbase': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 05:52:17 INFO - u'reftest': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 05:52:17 INFO - u'firefox-45.0a2.en-US.linux-x86_64.reftest.tests.zip'], 05:52:17 INFO - u'talos': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 05:52:17 INFO - u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'], 05:52:17 INFO - u'web-platform': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 05:52:17 INFO - u'firefox-45.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 05:52:17 INFO - u'webapprt': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'], 05:52:17 INFO - u'xpcshell': [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 05:52:17 INFO - u'firefox-45.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 05:52:17 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:52:17 INFO - https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:52:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 05:52:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 05:52:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 05:52:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:52:18 INFO - Downloaded 23159812 bytes. 05:52:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:52:18 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 05:52:18 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:52:19 INFO - Return code: 0 05:52:19 INFO - Downloading packages: [u'firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:52:19 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:52:19 INFO - https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:52:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 05:52:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 05:52:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 05:52:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:52:26 INFO - Downloaded 23159812 bytes. 05:52:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:52:26 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip 05:52:26 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:52:27 INFO - Return code: 0 05:52:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:52:27 INFO - https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:52:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 05:52:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 05:52:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 05:52:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:52:28 INFO - Downloaded 11132038 bytes. 05:52:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:52:28 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip 05:52:28 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:52:28 INFO - Return code: 0 05:52:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:52:28 INFO - https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:52:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 05:52:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 05:52:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 05:52:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:52:30 INFO - Downloaded 58150750 bytes. 05:52:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 05:52:30 INFO - mkdir: /builds/slave/test-pgo/properties 05:52:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:52:30 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:52:30 INFO - Contents: 05:52:30 INFO - build_url:https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 05:52:30 INFO - Running post-action listener: _resource_record_post_action 05:52:30 INFO - Running post-action listener: set_extra_try_arguments 05:52:30 INFO - ##### 05:52:30 INFO - ##### Running populate-webroot step. 05:52:30 INFO - ##### 05:52:30 INFO - Running pre-action listener: _resource_record_pre_action 05:52:30 INFO - Running main action method: populate_webroot 05:52:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:52:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:52:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:52:30 INFO - '--tppagecycles', 05:52:30 INFO - '7'], 05:52:30 INFO - 'tests': ['tsvgm']}}, 05:52:30 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:52:30 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:52:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:52:30 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:52:30 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:52:30 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:52:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:52:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:30 INFO - 'talos_options': ['--e10s'], 05:52:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:52:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:30 INFO - 'tests': ['damp', 'tps']}, 05:52:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:30 INFO - 'talos_options': ['--e10s'], 05:52:30 INFO - 'tests': ['damp', 'tps']}, 05:52:30 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:52:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:52:30 INFO - 'other': {'tests': ['a11yr', 05:52:30 INFO - 'ts_paint', 05:52:30 INFO - 'tpaint', 05:52:30 INFO - 'sessionrestore', 05:52:30 INFO - 'sessionrestore_no_auto_restore']}, 05:52:30 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:52:30 INFO - 'tests': ['a11yr', 05:52:30 INFO - 'ts_paint', 05:52:30 INFO - 'tpaint', 05:52:30 INFO - 'sessionrestore', 05:52:30 INFO - 'sessionrestore_no_auto_restore']}, 05:52:30 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:52:30 INFO - 'tests': ['a11yr', 05:52:30 INFO - 'ts_paint', 05:52:30 INFO - 'tpaint', 05:52:30 INFO - 'sessionrestore', 05:52:30 INFO - 'sessionrestore_no_auto_restore']}, 05:52:30 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:52:30 INFO - 'tests': ['a11yr', 05:52:30 INFO - 'ts_paint', 05:52:30 INFO - 'tpaint', 05:52:30 INFO - 'sessionrestore', 05:52:30 INFO - 'sessionrestore_no_auto_restore']}, 05:52:30 INFO - 'other_l64': {'tests': ['a11yr', 05:52:30 INFO - 'ts_paint', 05:52:30 INFO - 'tpaint', 05:52:30 INFO - 'sessionrestore', 05:52:30 INFO - 'sessionrestore_no_auto_restore']}, 05:52:30 INFO - 'other_nol64': {'tests': ['a11yr', 05:52:30 INFO - 'ts_paint', 05:52:30 INFO - 'tpaint', 05:52:30 INFO - 'sessionrestore', 05:52:30 INFO - 'sessionrestore_no_auto_restore']}, 05:52:30 INFO - 'svgr': {'tests': ['tsvgx', 05:52:30 INFO - 'tsvgr_opacity', 05:52:30 INFO - 'tart', 05:52:30 INFO - 'tscrollx', 05:52:30 INFO - 'cart']}, 05:52:30 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:52:30 INFO - 'tests': ['tsvgx', 05:52:30 INFO - 'tsvgr_opacity', 05:52:30 INFO - 'tart', 05:52:30 INFO - 'tscrollx', 05:52:30 INFO - 'cart']}, 05:52:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:30 INFO - 'tests': ['tp5o']}, 05:52:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:30 INFO - 'talos_options': ['--e10s'], 05:52:30 INFO - 'tests': ['tp5o']}, 05:52:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:30 INFO - 'talos_options': ['--xperf_path', 05:52:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:52:30 INFO - 'tests': ['tp5n']}, 05:52:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:52:30 INFO - 'talos_options': ['--e10s', 05:52:30 INFO - '--xperf_path', 05:52:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:52:30 INFO - 'tests': ['tp5n']}}, 05:52:30 INFO - 'talos.zip': {'path': '', 05:52:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:52:30 INFO - Running post-action listener: _resource_record_post_action 05:52:30 INFO - ##### 05:52:30 INFO - ##### Running create-virtualenv step. 05:52:30 INFO - ##### 05:52:30 INFO - Running pre-action listener: _resource_record_pre_action 05:52:30 INFO - Running main action method: create_virtualenv 05:52:30 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:52:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 05:52:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:52:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:52:30 INFO - Using real prefix '/usr' 05:52:30 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:52:31 INFO - Installing distribute.............................................................................................................................................................................................done. 05:52:33 INFO - Installing pip.................done. 05:52:33 INFO - Return code: 0 05:52:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:52: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:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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:52:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:33 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fe09b22af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6f350>, '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 0xd70250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd706e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:52:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 05:52:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:52:33 INFO - Using env: {'DISPLAY': ':0', 05:52:33 INFO - 'HOME': '/home/cltbld', 05:52:33 INFO - 'LANG': 'en_US.UTF-8', 05:52:33 INFO - 'LANGUAGE': 'en_US:en', 05:52:33 INFO - 'LOGNAME': 'cltbld', 05:52:33 INFO - 'MAIL': '/var/mail/cltbld', 05:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:33 INFO - 'MOZ_NO_REMOTE': '1', 05:52:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:33 INFO - 'NO_EM_RESTART': '1', 05:52:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:52:33 INFO - 'PWD': '/builds/slave/test-pgo', 05:52:33 INFO - 'SHELL': '/bin/bash', 05:52:33 INFO - 'SHLVL': '1', 05:52:33 INFO - 'TERM': 'linux', 05:52:33 INFO - 'TMOUT': '86400', 05:52:33 INFO - 'USER': 'cltbld', 05:52:33 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 05:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:33 INFO - '_': '/tools/buildbot/bin/python'} 05:52:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:52:33 INFO - Cleaning up... 05:52:33 INFO - Return code: 0 05:52:33 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:52: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:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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:52:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:33 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fe09b22af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6f350>, '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 0xd70250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd706e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:52:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 05:52:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:52:33 INFO - Using env: {'DISPLAY': ':0', 05:52:33 INFO - 'HOME': '/home/cltbld', 05:52:33 INFO - 'LANG': 'en_US.UTF-8', 05:52:33 INFO - 'LANGUAGE': 'en_US:en', 05:52:33 INFO - 'LOGNAME': 'cltbld', 05:52:33 INFO - 'MAIL': '/var/mail/cltbld', 05:52:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:33 INFO - 'MOZ_NO_REMOTE': '1', 05:52:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:33 INFO - 'NO_EM_RESTART': '1', 05:52:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:52:33 INFO - 'PWD': '/builds/slave/test-pgo', 05:52:33 INFO - 'SHELL': '/bin/bash', 05:52:33 INFO - 'SHLVL': '1', 05:52:33 INFO - 'TERM': 'linux', 05:52:33 INFO - 'TMOUT': '86400', 05:52:33 INFO - 'USER': 'cltbld', 05:52:33 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 05:52:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:33 INFO - '_': '/tools/buildbot/bin/python'} 05:52:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:33 INFO - Downloading/unpacking psutil>=0.7.1 05:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:52:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:52:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:52:35 INFO - Installing collected packages: psutil 05:52:35 INFO - Running setup.py install for psutil 05:52:35 INFO - building 'psutil._psutil_linux' extension 05:52:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:52:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:52:35 INFO - building 'psutil._psutil_posix' extension 05:52:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:52:36 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:52:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:52:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:52:36 INFO - Successfully installed psutil 05:52:36 INFO - Cleaning up... 05:52:36 INFO - Return code: 0 05:52:36 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:52: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:52:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52: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:52:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:36 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fe09b22af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6f350>, '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 0xd70250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd706e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:52:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 05:52:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:52:36 INFO - Using env: {'DISPLAY': ':0', 05:52:36 INFO - 'HOME': '/home/cltbld', 05:52:36 INFO - 'LANG': 'en_US.UTF-8', 05:52:36 INFO - 'LANGUAGE': 'en_US:en', 05:52:36 INFO - 'LOGNAME': 'cltbld', 05:52:36 INFO - 'MAIL': '/var/mail/cltbld', 05:52:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:36 INFO - 'MOZ_NO_REMOTE': '1', 05:52:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:36 INFO - 'NO_EM_RESTART': '1', 05:52:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:52:36 INFO - 'PWD': '/builds/slave/test-pgo', 05:52:36 INFO - 'SHELL': '/bin/bash', 05:52:36 INFO - 'SHLVL': '1', 05:52:36 INFO - 'TERM': 'linux', 05:52:36 INFO - 'TMOUT': '86400', 05:52:36 INFO - 'USER': 'cltbld', 05:52:36 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 05:52:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:36 INFO - '_': '/tools/buildbot/bin/python'} 05:52:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:36 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:52:36 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:52:36 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:52:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:36 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:52:36 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:52:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:52:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:52:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:52:38 INFO - Installing collected packages: mozsystemmonitor 05:52:38 INFO - Running setup.py install for mozsystemmonitor 05:52:38 INFO - Successfully installed mozsystemmonitor 05:52:38 INFO - Cleaning up... 05:52:38 INFO - Return code: 0 05:52:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:52:38 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:52:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:38 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:52:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:38 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fe09b22af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6f350>, '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 0xd70250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd706e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:52:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 05:52:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:52:38 INFO - Using env: {'DISPLAY': ':0', 05:52:38 INFO - 'HOME': '/home/cltbld', 05:52:38 INFO - 'LANG': 'en_US.UTF-8', 05:52:38 INFO - 'LANGUAGE': 'en_US:en', 05:52:38 INFO - 'LOGNAME': 'cltbld', 05:52:38 INFO - 'MAIL': '/var/mail/cltbld', 05:52:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:38 INFO - 'MOZ_NO_REMOTE': '1', 05:52:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:38 INFO - 'NO_EM_RESTART': '1', 05:52:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:52:38 INFO - 'PWD': '/builds/slave/test-pgo', 05:52:38 INFO - 'SHELL': '/bin/bash', 05:52:38 INFO - 'SHLVL': '1', 05:52:38 INFO - 'TERM': 'linux', 05:52:38 INFO - 'TMOUT': '86400', 05:52:38 INFO - 'USER': 'cltbld', 05:52:38 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 05:52:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:38 INFO - '_': '/tools/buildbot/bin/python'} 05:52:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:38 INFO - Downloading/unpacking blobuploader==1.2.4 05:52: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:52: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:52:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52: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:52: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:52:40 INFO - Downloading blobuploader-1.2.4.tar.gz 05:52:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:52:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:52:40 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:52:40 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:52:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:40 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:52:40 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:52:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:52:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:52:40 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:52:40 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:52:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:40 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:52:40 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:52:41 INFO - Downloading docopt-0.6.1.tar.gz 05:52:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:52:41 INFO - Installing collected packages: blobuploader, requests, docopt 05:52:41 INFO - Running setup.py install for blobuploader 05:52:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:52:41 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:52:41 INFO - Running setup.py install for requests 05:52:41 INFO - Running setup.py install for docopt 05:52:41 INFO - Successfully installed blobuploader requests docopt 05:52:41 INFO - Cleaning up... 05:52:41 INFO - Return code: 0 05:52:41 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:52:41 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:52:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:41 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:52:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:41 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fe09b22af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6f350>, '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 0xd70250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd706e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:52:41 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 05:52:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:52:41 INFO - Using env: {'DISPLAY': ':0', 05:52:41 INFO - 'HOME': '/home/cltbld', 05:52:41 INFO - 'LANG': 'en_US.UTF-8', 05:52:41 INFO - 'LANGUAGE': 'en_US:en', 05:52:41 INFO - 'LOGNAME': 'cltbld', 05:52:41 INFO - 'MAIL': '/var/mail/cltbld', 05:52:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:41 INFO - 'MOZ_NO_REMOTE': '1', 05:52:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:41 INFO - 'NO_EM_RESTART': '1', 05:52:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:52:41 INFO - 'PWD': '/builds/slave/test-pgo', 05:52:41 INFO - 'SHELL': '/bin/bash', 05:52:41 INFO - 'SHLVL': '1', 05:52:41 INFO - 'TERM': 'linux', 05:52:41 INFO - 'TMOUT': '86400', 05:52:41 INFO - 'USER': 'cltbld', 05:52:41 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 05:52:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:41 INFO - '_': '/tools/buildbot/bin/python'} 05:52:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:41 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:52:41 INFO - Running setup.py (path:/tmp/pip-YHB36F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:52:42 INFO - Running setup.py (path:/tmp/pip-RwzRIo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:52:42 INFO - Running setup.py (path:/tmp/pip-9igzo9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:52:42 INFO - Running setup.py (path:/tmp/pip-IPpx1g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:52:42 INFO - Running setup.py (path:/tmp/pip-FrA8MJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:52:42 INFO - Running setup.py (path:/tmp/pip-laXOMs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:52:42 INFO - Running setup.py (path:/tmp/pip-cpd5GF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:52:42 INFO - Running setup.py (path:/tmp/pip-3KsBxI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:52:42 INFO - Running setup.py (path:/tmp/pip-fYXZt5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:52:42 INFO - Running setup.py (path:/tmp/pip-u8xspy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:52:42 INFO - Running setup.py (path:/tmp/pip-kx2Hy8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:52:42 INFO - Running setup.py (path:/tmp/pip-MmqM4h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:52:42 INFO - Running setup.py (path:/tmp/pip-AxXWvW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:52:42 INFO - Running setup.py (path:/tmp/pip-d6Th_g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:52:42 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:52:42 INFO - Running setup.py (path:/tmp/pip-VYhN8M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:52:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:52:43 INFO - Running setup.py (path:/tmp/pip-4yRrxc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:52:43 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:52:43 INFO - Running setup.py (path:/tmp/pip-3F6cz6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:52:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:52:43 INFO - Running setup.py install for manifestparser 05:52:43 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:52:43 INFO - Running setup.py install for mozcrash 05:52:43 INFO - Running setup.py install for mozdebug 05:52:43 INFO - Running setup.py install for mozdevice 05:52:43 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:52:43 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:52:43 INFO - Running setup.py install for mozfile 05:52:43 INFO - Running setup.py install for mozhttpd 05:52:43 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:52:43 INFO - Running setup.py install for mozinfo 05:52:43 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:52:43 INFO - Running setup.py install for mozInstall 05:52:43 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:52:43 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:52:43 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:52:43 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:52:43 INFO - Running setup.py install for mozleak 05:52:44 INFO - Running setup.py install for mozlog 05:52:44 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:52:44 INFO - Running setup.py install for moznetwork 05:52:44 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:52:44 INFO - Running setup.py install for mozprocess 05:52:44 INFO - Running setup.py install for mozprofile 05:52:44 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:52:44 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:52:44 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:52:44 INFO - Running setup.py install for mozrunner 05:52:44 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:52:44 INFO - Running setup.py install for mozscreenshot 05:52:44 INFO - Running setup.py install for moztest 05:52:44 INFO - Running setup.py install for mozversion 05:52:44 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:52:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:52:44 INFO - Cleaning up... 05:52:44 INFO - Return code: 0 05:52:44 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:52:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:52:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:44 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fe09b22af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6f350>, '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 0xd70250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd706e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:52:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 05:52:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:52:44 INFO - Using env: {'DISPLAY': ':0', 05:52:44 INFO - 'HOME': '/home/cltbld', 05:52:44 INFO - 'LANG': 'en_US.UTF-8', 05:52:44 INFO - 'LANGUAGE': 'en_US:en', 05:52:44 INFO - 'LOGNAME': 'cltbld', 05:52:44 INFO - 'MAIL': '/var/mail/cltbld', 05:52:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:44 INFO - 'MOZ_NO_REMOTE': '1', 05:52:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:44 INFO - 'NO_EM_RESTART': '1', 05:52:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:52:44 INFO - 'PWD': '/builds/slave/test-pgo', 05:52:44 INFO - 'SHELL': '/bin/bash', 05:52:44 INFO - 'SHLVL': '1', 05:52:44 INFO - 'TERM': 'linux', 05:52:44 INFO - 'TMOUT': '86400', 05:52:44 INFO - 'USER': 'cltbld', 05:52:44 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 05:52:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:44 INFO - '_': '/tools/buildbot/bin/python'} 05:52:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:52:45 INFO - Running setup.py (path:/tmp/pip-bkEybv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 05:52:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:52:45 INFO - Running setup.py (path:/tmp/pip-xIqbcl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:52:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:52:45 INFO - Running setup.py (path:/tmp/pip-von3OC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:52:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:52:45 INFO - Running setup.py (path:/tmp/pip-HKm7op-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:52:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:52:45 INFO - Running setup.py (path:/tmp/pip-W_6NpQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 05:52:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:52:45 INFO - Running setup.py (path:/tmp/pip-WjaKG7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 05:52:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:52:45 INFO - Running setup.py (path:/tmp/pip-VqetLz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 05:52:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:52:45 INFO - Running setup.py (path:/tmp/pip-nx9mz5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 05:52:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:52:45 INFO - Running setup.py (path:/tmp/pip-RntBpu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 05:52:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:52:45 INFO - Running setup.py (path:/tmp/pip-m9KQjR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:52:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:52:45 INFO - Running setup.py (path:/tmp/pip-fBWYom-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:52:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 05:52:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:52:45 INFO - Running setup.py (path:/tmp/pip-6svI2J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 05:52:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:52:46 INFO - Running setup.py (path:/tmp/pip-Z6p9pN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 05:52:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:52:46 INFO - Running setup.py (path:/tmp/pip-m7DuHI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 05:52:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:52:46 INFO - Running setup.py (path:/tmp/pip-TtALVN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 05:52:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:52:46 INFO - Running setup.py (path:/tmp/pip-efKGBy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 05:52:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:52:46 INFO - Running setup.py (path:/tmp/pip-aG0csQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 05:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 05:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 05:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:52:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 05:52:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:52:46 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:52:46 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:52:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:46 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:52:46 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:52:48 INFO - Downloading blessings-1.6.tar.gz 05:52:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:52:48 INFO - Installing collected packages: blessings 05:52:48 INFO - Running setup.py install for blessings 05:52:48 INFO - Successfully installed blessings 05:52:48 INFO - Cleaning up... 05:52:48 INFO - Return code: 0 05:52:48 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:52:48 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:52:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:52:48 INFO - Reading from file tmpfile_stdout 05:52:48 INFO - Current package versions: 05:52:48 INFO - argparse == 1.2.1 05:52:48 INFO - blessings == 1.6 05:52:48 INFO - blobuploader == 1.2.4 05:52:48 INFO - docopt == 0.6.1 05:52:48 INFO - manifestparser == 1.1 05:52:48 INFO - mozInstall == 1.12 05:52:48 INFO - mozcrash == 0.16 05:52:48 INFO - mozdebug == 0.1 05:52:48 INFO - mozdevice == 0.47 05:52:48 INFO - mozfile == 1.2 05:52:48 INFO - mozhttpd == 0.7 05:52:48 INFO - mozinfo == 0.9 05:52:48 INFO - mozleak == 0.1 05:52:48 INFO - mozlog == 3.1 05:52:48 INFO - moznetwork == 0.27 05:52:48 INFO - mozprocess == 0.22 05:52:48 INFO - mozprofile == 0.27 05:52:48 INFO - mozrunner == 6.11 05:52:48 INFO - mozscreenshot == 0.1 05:52:48 INFO - mozsystemmonitor == 0.0 05:52:48 INFO - moztest == 0.7 05:52:48 INFO - mozversion == 1.4 05:52:48 INFO - psutil == 3.1.1 05:52:48 INFO - requests == 1.2.3 05:52:48 INFO - wsgiref == 0.1.2 05:52:48 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:52:48 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:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:48 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:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fe09b22af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6f350>, '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 0xd70250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd706e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:52:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 05:52:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:52:48 INFO - Using env: {'DISPLAY': ':0', 05:52:48 INFO - 'HOME': '/home/cltbld', 05:52:48 INFO - 'LANG': 'en_US.UTF-8', 05:52:48 INFO - 'LANGUAGE': 'en_US:en', 05:52:48 INFO - 'LOGNAME': 'cltbld', 05:52:48 INFO - 'MAIL': '/var/mail/cltbld', 05:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:48 INFO - 'MOZ_NO_REMOTE': '1', 05:52:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:48 INFO - 'NO_EM_RESTART': '1', 05:52:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:52:48 INFO - 'PWD': '/builds/slave/test-pgo', 05:52:48 INFO - 'SHELL': '/bin/bash', 05:52:48 INFO - 'SHLVL': '1', 05:52:48 INFO - 'TERM': 'linux', 05:52:48 INFO - 'TMOUT': '86400', 05:52:48 INFO - 'USER': 'cltbld', 05:52:48 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 05:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:48 INFO - '_': '/tools/buildbot/bin/python'} 05:52:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 05:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 05:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 05:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 05:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 05:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 05:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 05:52:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 05:52:48 INFO - Cleaning up... 05:52:48 INFO - Return code: 0 05:52:48 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:52:48 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:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:48 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:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe09b22af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6f350>, '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 0xd70250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd706e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:52:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 05:52:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:52:48 INFO - Using env: {'DISPLAY': ':0', 05:52:48 INFO - 'HOME': '/home/cltbld', 05:52:48 INFO - 'LANG': 'en_US.UTF-8', 05:52:48 INFO - 'LANGUAGE': 'en_US:en', 05:52:48 INFO - 'LOGNAME': 'cltbld', 05:52:48 INFO - 'MAIL': '/var/mail/cltbld', 05:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:48 INFO - 'MOZ_NO_REMOTE': '1', 05:52:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:48 INFO - 'NO_EM_RESTART': '1', 05:52:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:52:48 INFO - 'PWD': '/builds/slave/test-pgo', 05:52:48 INFO - 'SHELL': '/bin/bash', 05:52:48 INFO - 'SHLVL': '1', 05:52:48 INFO - 'TERM': 'linux', 05:52:48 INFO - 'TMOUT': '86400', 05:52:48 INFO - 'USER': 'cltbld', 05:52:48 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 05:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:48 INFO - '_': '/tools/buildbot/bin/python'} 05:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:49 INFO - Downloading/unpacking jsonschema 05:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:52:50 INFO - Downloading/unpacking functools32 (from jsonschema) 05:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:51 INFO - Downloading functools32-3.2.3-2.tar.gz 05:52:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:52:51 INFO - warning: no files found matching '*.txt' 05:52:51 INFO - no previously-included directories found matching 'build' 05:52:51 INFO - no previously-included directories found matching 'dist' 05:52:51 INFO - no previously-included directories found matching '.git*' 05:52:51 INFO - Installing collected packages: jsonschema, functools32 05:52:51 INFO - Running setup.py install for functools32 05:52:51 INFO - warning: no files found matching '*.txt' 05:52:51 INFO - no previously-included directories found matching 'build' 05:52:51 INFO - no previously-included directories found matching 'dist' 05:52:51 INFO - no previously-included directories found matching '.git*' 05:52:51 INFO - Successfully installed jsonschema functools32 05:52:51 INFO - Cleaning up... 05:52:51 INFO - Return code: 0 05:52:51 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:52:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:52:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:52:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:52:51 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe09b22af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc138a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xce0600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6f350>, '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 0xd70250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd706e0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:52:51 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 05:52:51 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:52:51 INFO - Using env: {'DISPLAY': ':0', 05:52:51 INFO - 'HOME': '/home/cltbld', 05:52:51 INFO - 'LANG': 'en_US.UTF-8', 05:52:51 INFO - 'LANGUAGE': 'en_US:en', 05:52:51 INFO - 'LOGNAME': 'cltbld', 05:52:51 INFO - 'MAIL': '/var/mail/cltbld', 05:52:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:52:51 INFO - 'MOZ_NO_REMOTE': '1', 05:52:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:52:51 INFO - 'NO_EM_RESTART': '1', 05:52:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:52:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:52:51 INFO - 'PWD': '/builds/slave/test-pgo', 05:52:51 INFO - 'SHELL': '/bin/bash', 05:52:51 INFO - 'SHLVL': '1', 05:52:51 INFO - 'TERM': 'linux', 05:52:51 INFO - 'TMOUT': '86400', 05:52:51 INFO - 'USER': 'cltbld', 05:52:51 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 05:52:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:52:51 INFO - '_': '/tools/buildbot/bin/python'} 05:52:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:52:51 INFO - Downloading/unpacking flake8 05:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:53 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:52:53 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:53 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:52:53 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:52:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:52:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:52:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:52:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:52:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:52:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:52:53 INFO - no previously-included directories found matching 'docs/_build' 05:52:53 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:52:54 INFO - Running setup.py install for pep8 05:52:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:52:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:52:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:52:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:52:54 INFO - no previously-included directories found matching 'docs/_build' 05:52:54 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:52:54 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:52:54 INFO - Cleaning up... 05:52:54 INFO - Return code: 0 05:52:54 INFO - Running post-action listener: _resource_record_post_action 05:52:54 INFO - Running post-action listener: _start_resource_monitoring 05:52:54 INFO - Starting resource monitoring. 05:52:54 INFO - ##### 05:52:54 INFO - ##### Running install step. 05:52:54 INFO - ##### 05:52:54 INFO - Running pre-action listener: _resource_record_pre_action 05:52:54 INFO - Running main action method: install 05:52:54 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:52:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:52:54 INFO - Reading from file tmpfile_stdout 05:52:54 INFO - Detecting whether we're running mozinstall >=1.0... 05:52:54 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:52:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:52:54 INFO - Reading from file tmpfile_stdout 05:52:54 INFO - Output received: 05:52:54 INFO - Usage: mozinstall [options] installer 05:52:54 INFO - Options: 05:52:54 INFO - -h, --help show this help message and exit 05:52:54 INFO - -d DEST, --destination=DEST 05:52:54 INFO - Directory to install application into. [default: 05:52:54 INFO - "/builds/slave/test-pgo"] 05:52:54 INFO - --app=APP Application being installed. [default: firefox] 05:52:54 INFO - mkdir: /builds/slave/test-pgo/build/application 05:52:54 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:52:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:53:09 INFO - Reading from file tmpfile_stdout 05:53:09 INFO - Output received: 05:53:09 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:53:09 INFO - Running post-action listener: _resource_record_post_action 05:53:09 INFO - ##### 05:53:09 INFO - ##### Running run-tests step. 05:53:09 INFO - ##### 05:53:09 INFO - Running pre-action listener: _resource_record_pre_action 05:53:09 INFO - Running main action method: run_tests 05:53:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:53:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:53:09 INFO - Python 2.7.3 05:53:09 INFO - Return code: 0 05:53:09 INFO - grabbing minidump binary from tooltool 05:53:09 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:09 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xd6f350>, '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 0xd70250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd706e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:53:09 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 05:53:09 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:53:09 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:53:09 INFO - Return code: 0 05:53:09 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:53:09 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:53:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:53:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:53:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:53:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:53:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:53:10 INFO - Return code: 0 05:53:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:53:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost 05:53:10 INFO - Using env: {'DISPLAY': ':0', 05:53:10 INFO - 'HOME': '/home/cltbld', 05:53:10 INFO - 'LANG': 'en_US.UTF-8', 05:53:10 INFO - 'LANGUAGE': 'en_US:en', 05:53:10 INFO - 'LOGNAME': 'cltbld', 05:53:10 INFO - 'MAIL': '/var/mail/cltbld', 05:53:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:53:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:53:10 INFO - 'MOZ_NO_REMOTE': '1', 05:53:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:53:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:53:10 INFO - 'NO_EM_RESTART': '1', 05:53:10 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:53:10 INFO - 'PWD': '/builds/slave/test-pgo', 05:53:10 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:53:10 INFO - 'SHELL': '/bin/bash', 05:53:10 INFO - 'SHLVL': '1', 05:53:10 INFO - 'TERM': 'linux', 05:53:10 INFO - 'TMOUT': '86400', 05:53:10 INFO - 'USER': 'cltbld', 05:53:10 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001', 05:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:53:10 INFO - '_': '/tools/buildbot/bin/python'} 05:53:10 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost'] with output_timeout 3600 05:53:10 INFO - mozversion INFO | application_buildid: 20160122032328 05:53:10 INFO - mozversion INFO | application_changeset: 882e6011c18f968f3e6993e4fac60c68ae6366a0 05:53:10 INFO - mozversion INFO | application_display_name: FirefoxDeveloperEdition 05:53:10 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:53:10 INFO - mozversion INFO | application_name: Firefox 05:53:10 INFO - mozversion INFO | application_remotingname: firefox-dev 05:53:10 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:53:10 INFO - mozversion INFO | application_vendor: Mozilla 05:53:10 INFO - mozversion INFO | application_version: 45.0a2 05:53:10 INFO - mozversion INFO | platform_buildid: 20160122032328 05:53:10 INFO - mozversion INFO | platform_changeset: 882e6011c18f968f3e6993e4fac60c68ae6366a0 05:53:10 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 05:53:10 INFO - mozversion INFO | platform_version: 45.0a2 05:53:10 INFO - 2016-01-22 05:53:10,486 DEBUG : using testdate: 1453470790 05:53:10 INFO - 2016-01-22 05:53:10,486 DEBUG : actual date: 1453470790 05:53:10 INFO - 2016-01-22 05:53:10,494 INFO : starting webserver on 'localhost:37823' 05:53:10 INFO - 2016-01-22 05:53:10,495 INFO : Starting test suite talos-linux64-ix-056 05:53:10 INFO - 2016-01-22 05:53:10,496 INFO : Starting test dromaeo_css 05:53:10 INFO - 2016-01-22 05:53:10,496 DEBUG : operating with platform_type : linux_ 05:53:10 INFO - 2016-01-22 05:53:10,496 INFO : Initialising browser for dromaeo_css test... 05:53:10 INFO - 2016-01-22 05:53:10,513 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOGPWTd/profile http://localhost:37823/getInfo.html 05:53:14 INFO - 2016-01-22 05:53:14,782 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:53:14 INFO - 2016-01-22 05:53:14,782 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:53:14 INFO - 2016-01-22 05:53:14,782 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:53:14 INFO - 2016-01-22 05:53:14,782 DEBUG : BROWSER_OUTPUT: __metrics 05:53:14 INFO - 2016-01-22 05:53:14,992 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:53:16 INFO - 2016-01-22 05:53:16,183 INFO : Browser initialized. 05:53:16 INFO - 2016-01-22 05:53:16,184 INFO : Running cycle 1/1 for dromaeo_css test... 05:53:16 INFO - 2016-01-22 05:53:16,184 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOGPWTd/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:53:28 INFO - 2016-01-22 05:53:28,008 DEBUG : BROWSER_OUTPUT: RSS: Main: 137519104 05:53:28 INFO - 2016-01-22 05:53:28,009 DEBUG : BROWSER_OUTPUT: 05:54:18 INFO - 2016-01-22 05:54:18,710 DEBUG : BROWSER_OUTPUT: 1453470858709 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:27 INFO - 2016-01-22 05:56:27,152 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37823/tests/dromaeo/cssquery-ext.html) 05:56:28 INFO - 2016-01-22 05:56:28,156 DEBUG : BROWSER_OUTPUT: RSS: Main: 218034176 05:56:28 INFO - 2016-01-22 05:56:28,156 DEBUG : BROWSER_OUTPUT: 05:59:30 INFO - 2016-01-22 05:59:30,439 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/dromaeo/cssquery-ext.html (next: http://localhost:37823/tests/dromaeo/cssquery-jquery.html) 05:59:31 INFO - 2016-01-22 05:59:31,001 DEBUG : BROWSER_OUTPUT: RSS: Main: 225542144 05:59:31 INFO - 2016-01-22 05:59:31,001 DEBUG : BROWSER_OUTPUT: 06:02:32 INFO - 2016-01-22 06:02:32,831 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37823/tests/dromaeo/cssquery-mootools.html) 06:02:33 INFO - 2016-01-22 06:02:33,397 DEBUG : BROWSER_OUTPUT: RSS: Main: 217649152 06:02:33 INFO - 2016-01-22 06:02:33,397 DEBUG : BROWSER_OUTPUT: 06:05:35 INFO - 2016-01-22 06:05:35,157 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37823/tests/dromaeo/cssquery-prototype.html) 06:05:35 INFO - 2016-01-22 06:05:35,723 DEBUG : BROWSER_OUTPUT: RSS: Main: 200400896 06:05:35 INFO - 2016-01-22 06:05:35,723 DEBUG : BROWSER_OUTPUT: 06:08:38 INFO - 2016-01-22 06:08:38,213 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37823/tests/dromaeo/cssquery-yui.html) 06:08:38 INFO - 2016-01-22 06:08:38,807 DEBUG : BROWSER_OUTPUT: RSS: Main: 204845056 06:08:38 INFO - 2016-01-22 06:08:38,807 DEBUG : BROWSER_OUTPUT: 06:11:40 INFO - 2016-01-22 06:11:40,993 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/dromaeo/cssquery-yui.html (next: http://localhost:37823/tests/dromaeo/cssquery-dojo.html) 06:11:41 INFO - 2016-01-22 06:11:41,250 DEBUG : BROWSER_OUTPUT: RSS: Main: 249413632 06:11:41 INFO - 2016-01-22 06:11:41,250 DEBUG : BROWSER_OUTPUT: 06:11:41 INFO - 2016-01-22 06:11:41,251 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:11:41 INFO - 2016-01-22 06:11:41,251 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:11:41 INFO - 2016-01-22 06:11:41,251 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:11:41 INFO - 2016-01-22 06:11:41,251 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:11:41 INFO - 2016-01-22 06:11:41,251 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3103.7516998640103;3167.9683203167992;3196.52052192171;3197.5843140391744;3207.454732695442;5388.353397592295;5391.218273350361;5413.729313534327;5427.9185812212845;5433.891322173554;5360.356757189133;5365.865853353668;5373.81191658292;5388.137897936331;5423.728813559326;4857.2714092886;4948.505149485059;4954.925676114861;4970.453250142481;4991.725455099968;4875.049365373754;4921.926171107436;4975.850724478253;4988.2766998785155;4993.950060499385;4846.442659094214;4937.901241975158;4953.727544985024;4962.975185124069;5019.52314530119;4960.355153829997;4968.677035992649;4999.4000719913665;5021.171506701427;5025.99480103977;4760.33355330254;4827.782749776268;4924.916518365953;4931.605471562267;4960.479149689267;5195.428502864682;5226.503482169188;5239;5245.160774276135;5248.081585722483;5805.535557155417;5833.649981001154;5846.9707651461895;5849.44430279127;5852.15143804146;5642.717864106819;5696.857578560528;5729.484346408802;5833.912491312633;5904.0553511437965;5364.409914909396;5392.083345831218;5392.40683524812;5414.187871819219;5417.024935511607;5852.678102704393;5918.260217472816;5936.079907614327;5938.851528711731;5952.85117872048;5526.447355264441;5630.577706672016;5690.886182276331;5698.088305871041;5703.62926409782;5626.831195064155;5712.428757124254;5716.5998380113;5730.570207234474;5740.712964351766;5806.332271788799;5826.126081087871;5860.3260625027815;5864.44287792659;5877.706114694248;5654.37801841797;5745.885082298414;5772.624779389326;5799.9130013049835;5852.034414321589;4495.77521123949;4505.932411013838;4511.120331535385;4512.842050528281;4525.615322697608;4023.1752490739327;4051.6961227907436;4061.3095773718537;4065.735727177724;4067.2272268268935;5395.865103372447;5472.945270547323;5494.780208791683;5548.611597188158;5562.471565201299;5828.6211396259105;5852.385499522574;5854.121881717691;5860;5894.440028197314;5518.862028449321;5525.005499010217;5529.0324193266015;5531.446855314436;5535.944640553624;4906.70559766408;4994.200927851599;5006.499350064964;5055.9494405055475;5076.086304465158;5982.850428739229;6023.909641355383;6032.095185722177;6052.303985041776;6064.151018857364;5885.499732522612;5889.764409423747;5892.469677728939;5893.882122357614;5897.3807750187325;5835.270591176103;5886.676232807065;6000.459958603747;6020.8795824084145;6077.058056001327;5753.683547404766;5788.797392091256;5812.244408227072;5814.592978491465;5820.417958204146;5894.528437724886;5896.056630939029;5896.616719913192;5898.085796701518;5900.527957763455;5519.917201241904;5614.326280846324;5638.802641907513;5763.36602973681;5779.306483222041;5700.600957932905;5720.227769251098;5728.627639203439;5733.627314224562;5734.025215713422;5404.675719456845;5643.435656434323;5660.46225608583;5667.319921609434;5672.319321681424;5556.694381809039;5647.350554686263;5739.512141468022;5747.3103227612955;5779.624324418899;5530.253415788817;5598.608097433141;5642.263502201511;5646.71766411686;5674.290713660793 06:11:41 INFO - 2016-01-22 06:11:41,251 DEBUG : BROWSER_OUTPUT: |1;ext.html;596.9940300596993;603.9607425517341;622.520659092499;635.9650219237943;641.1952998986271;31903.202419939484;33002.504962425555;33041.66958330416;39404;39835.60164398355;44318.33522497157;44874.551254487444;44896.775516122376;44942.55057449424;44962.55037449625;8993.77515562113;9165.999999999967;9170.312226583033;9176.632934682639;9179.58691858868;8223.42436029478;8404.285635720971;10738.355698658066;11049.779004419906;11056.447177641126;10773.461326933622;10859.945700271488;10900.890991090106;10903.563857445693;10907.181961352891;8228.62971166299;10729.195310351755;10840.457977101114;10842.457877106153;10844.891551084467;8000.5999700015345;10716.73208169802;10980;10986.285891417032;10992.505337259843;41992;42359.36460953088;42423.57576424227;42688.57311426908;42937;3665.8533658653623;4273.529911709709;4286.807093680761;4293.463317085364;4367.803448844777;31817.84091079553;32047.359052819047;32258.193545161324;32532.91331094531;33037.33925321504;4181.560936101726;4212.9157416851795;4278.572142785696;4297.763623000734;4298.849540266076;292683;294989;295642;295913;297320;58934.70532647309;60504.69747651234;65400;66192.33807661958;66462.33537664659;57326.14010789841;58003;58101.418985810444;58134.12798808021;58205;16134.435294764628;16148.75776863348;16162.919185403998;16283.267252973645;16333.26500307489;12709.872901270868;12738.172018818746;12745.490180392866;12746;12757.681057973625;21054.789247129138;21525.354239372842;21640.891795540923;21671.45821354479;21685.783142168693;7864.2922137007945;8055.838883222303;8083.232092951161;8176.6320515576945;8294.797254398078;983.4148681534475;1022.6114076650825;1054.6466933577337;1090.9145400326727;1094.5567045346647;27607;28049.43901122007;28085.297867553065;28172.154835354973;28764.712352876202;1079.2822772855964;1103.9172062095374;1106.363840791532;1107.5680484611007;1139.071656599885;13690.247036412893;14090.225037622824;14388.352524136439;14997.550073497812;16306.755398668793;361455;365535;366534;366783;367599;57126.7143664279;57262.42737572654;57431.712841435525;57440;57476.71261643665;1350.6690860738986;1381.240317825192;1448.6885319656324;1476.1217075839859;1494.4097081652903;25682.614760778975;26789.464210715287;27097.72902270952;27179.320516987234;27285;46098;47100.7644961773;47226.76386618045;47248.527514725785;47607.52392476031;198646;333984;339754;339845;339982;30225.395492090476;30834.69165308318;30861.845690771403;30883.0735077948;30893.845530772203;41780.58219417767;42262.366064509675;42295.7885210572;43154.78422607867;43628.12743745171;38982.6101738979;39055.21889562136;39849.80075099606;39853;39876.60123398729;35893.461598076574;36359;37619.81190094032;38345.80827095846;39065.60934390734;37911.2417751649;38571.80714096524;38823;39339.80330098331;39364.803175985086;29926.551101733057;31651.683483164874;33015;34121;34798.82600586981;26414.867925660248;26882.865585671945;26932.865335673196;26940.595891061257;27005.59491607667 06:11:41 INFO - 2016-01-22 06:11:41,252 DEBUG : BROWSER_OUTPUT: |2;jquery.html;362.4002276232834;366.0464490003541;366.0665303476136;367.6415494892482;368.9797061161636;14851.257437128155;16540;17888.642227155447;18757.15592798324;19388.51528711781;17959.922404655696;17967.550811229725;18004.639907201847;18571.535711607215;18813.05934703266;5398.2982212312445;5510.559155267589;5520.254765606652;5524.281843360358;5524.751386187611;4087.979560102195;4201.894952626179;5522.337319521663;5850.4734573888345;5976.252968378953;5979.910301345483;6069;6073.757049718006;6079.331273559905;6102.328743838174;5259.474052594748;5360.410354860962;5362.946370536303;5378.892422151535;5387.919181212285;5157.432682404932;5167.069927413064;5170.146925757245;5335.653182543161;5346.358436987547;12775.680607984781;12977.545785897542;13003.674908127372;18478.353257635918;21964.560708785895;2211.9225827095975;2242.7084479017785;2255.70675812145;2255.8308126890547;2305.976940230593;9662.468564228964;9888.604455821758;9922;10036.849447258297;11456.541738330458;1908.4847091285415;1915.817997290255;1940.932067377635;1984.474114359696;2000.9199632014702;61332;62817.37182628161;62865.68567157181;63077.36922630761;63645.72708545849;10017.248706346907;10081.344712593658;10200.0819926207;10258;10557.683269501927;13575.253361065039;13607.183568985893;13687.931560342135;13688.931555342358;13715.314234288246;7884.448088633741;7904.644291006918;7907.0906845712325;7965.960170199112;7977.282044616012;7671.808204794925;7700.345470634947;7726.690932362642;7728.690852365842;7762.262585054411;4461.977690111594;4467.24056910326;4487.910241795146;4488.977555112268;4491.3487544306545;1726.5252055684537;1774.6450709857822;1782.3405340024274;1821.2259789589637;1833;939.1922946266205;1004.7287232447344;1036.2280101324561;1088.700607332974;1101.542859713226;14847.925760371345;16780.916095419445;17244.568885777706;17463.825361746472;17512.649747004987;953.4755884263766;963.1957315641378;982.5038355630504;1009.9091081802673;1043.3948309980194;9363.344565880323;9459.195968342769;9548.42709437436;9568.378055426376;9709.951450242845;478418;479865;601875;615626;617167;76020.099497513;81232;87830;89361.55319223492;89941.55029224812;1164.5225457562358;1197.8081808600498;1206.4631239098692;1208.9032877369789;1216.853977522703;15746.76379854326;16040.839591603935;16084;16225;16370.263338149574;35806;35992.820035899655;36303.23917287466;36323.63676363203;36976.81511592425;47594;50134.749326253135;50400.24399634082;51128.23307650463;51322.23016654828;9264.629414823332;9480.288978326791;9500.144986951207;9930.503474826375;9960.55177517028;11574.18980671345;11686.883131168579;12389.814152787536;13178.802317965046;17915.552111197325;14909.478168264059;15016.699666006838;15023.323950422477;15158.014729042578;15402.15288159162;14392.352344144749;14408;14425.278736062945;14968.550943471713;15006.474773382879;14524.927375363055;14561.27193640335;14687.55937321882;14932.328045237773;14980.700385992;9512.239020878174;9588.952055239679;9981.550830212804;10006;10095.798084038424;10653.786924261627;10766.192535559707;12767.808482872579;15474.613134671274;15880.285387157375 06:11:41 INFO - 2016-01-22 06:11:41,255 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1458.6207586027629;1530.3113598880502;1588.5234429671095;1698.7282034874415;1750.789905211374;6975.1978522469935;7402.6668799904;7576.356009739165;7594.050743657043;7594.392448604105;7599.962000189991;7803.531788092719;7810.9609451952665;7820.296173344398;7829.138794732575;2195.9451013724624;2231.364061242546;2232.4195709115666;2238.6642003699494;2239.8656080635133;2215.7008803811523;2243;2279.168103642167;2284.6344584866424;2284.8743319117443;2182.7708090650412;2208.5251670890757;2240.339099965514;2240.5518896220738;2248.05581655705;2188.1466228171025;2213.103693004336;2237.7762223777654;2242.9439264018365;2243.2597242909883;2224.8665080095084;2239.7984181423585;2251.0095557954446;2251.79733823955;2256.8533045352;7631.961840190819;7721.729739459092;7755.1469338372735;7763.883541746878;7779.922200777976;4439.977800111011;4526.0495295987885;4528.298113792367;4530.637548996106;4533.206688829475;2924.2250803536867;2982.6271716035494;2994.326276587772;2999.2501874531367;3031.8787248510034;974.3228456222928;990.3612170150285;1002.7092143278513;1006.5822683586302;1026.1790567545934;10032.097111260022;10121.69634910954;10126.54430550627;10126.594936202542;10165.339252948535;3996.320625493673;4001.2197621464115;4026.778527181033;4055.513338399411;4061.756294622332;8653.524056176971;8656.61045252965;8663.133686631287;8670.479771213748;8675.089115642766;4542.477615074243;4557.45310562728;4557.680962332671;4558.0655965526985;4574.542545745466;3854.69162467002;3888.105735680753;4182.435371224907;4208.537060923296;4210.894727631834;3587.5336206293537;3611.259691763182;3617.4212126059706;3625.329314076904;3628.691561217326;1106.9612563560236;1113.5434471866495;1116.542217690743;1123.8932301431514;1125.8198688209848;1422.50923431416;1436.913785172893;1447.4282658349894;1447.5150824473708;1469.2653673163418;7479.887801682979;7522.473426860067;7532.811679708051;7539.0576177977755;7542.886856697154;455.6992385025867;460.1372426699938;473.29951671525646;473.5122823491809;474.3525088254508;5920.082387229986;5959.255093113361;5970.253718285215;5980.581359304807;5992.0712289595185;8703.173198546128;9100.635974561075;9188.90811091894;9205.907940920639;9258.675946341846;9098.681546145854;9113.453192808453;9133.817323653622;9141.862872056925;9152.725418237464;6933;7105.32499412555;7132.358087772125;7137.678804453812;7215.422766178693;7310.890336645061;7374.741884034033;7399.371053460516;7414.555126692416;7432.145303290191;7600.619969001417;7839.882401763864;7977.8404431912195;7981.840363192588;7985.441019128838;8431.8735218973;8452.535110568977;8502.617382217692;8663;8665.350098742494;6621.900671489835;6964;7007.544509606859;7067.187273463412;7331.743388981574;7507.024086868672;7517.173110957681;7520.473566850268;7524.473286869867;7547;6909.619970901447;7065.717371305091;7073.964630176816;7185.101862267216;7218.386437152901;7005.929940700732;7017.614031228331;7063.117110361205;7082.043924070391;7110.32451917067;6858.211305699909;6896.413804826646;7114.750983715545;7115.146182458138;7121.465890058163;6577.210734711865;6645.169353830771;6655.367740064686;6660.600363978177;6672.065910772399;6548.639824809491;6552.8361790955605;6651.767188148391;6699.028640846953;6839.726410943506 06:11:41 INFO - 2016-01-22 06:11:41,255 DEBUG : BROWSER_OUTPUT: |4;prototype.html;349.54908168462686;352.5945163062478;359.8830380126459;361.6058496239099;363.29339434799294;6034.577579569432;6061.848453788658;6087.604305720136;6103.694815259241;6104.969475152618;6013.188219590354;6022.0966854971775;6036;6040.0939859021055;6044.546659000571;4511.661625378093;4645.9070818583605;4745.6915300505525;4746.335513028179;4747.8813029674275;4643.976780116095;4644.094401591691;4644.5819876211135;4693.413323334583;4712.646551508634;4615.769211539427;4617.422822147231;4638.397008388922;4644.628429725631;4645.837395691152;4433.357163211333;4480.910381792346;4485.349624304474;4536.455625324949;4621.4223222097235;4028.1742242840146;4029.7783621900785;4049.817758200888;4055.9797201014103;4070.715049946505;5733.226014488074;5790.218320526717;5793.391693872167;5796.304443466769;5805.4194580542035;5079.619028572872;5220.2430647556275;5222.112240919015;5225.399079105913;5389.892202155974;6365.8408539786415;6366.172397588331;6377.426031657174;6389.5527313088105;6401.455876250524;4491.191585514606;4491.461024677027;4491.752953587551;4492.505824359318;4492.9550704493195;6568.343165683394;6640.5683630564345;6706;6742.764003259862;6751.392374686302;4593.839215627437;4626.537346265347;4632.143053535106;4633.582977532038;4639.164950308977;4686.695364801277;4687.99208170245;4694.201985662446;4694.788734506956;4695.014047050089;5336.226247194213;5406;5410.702411367412;5410.729463526808;5418.133098704188;6380.776672816522;6499.350064993463;6509.674516274167;6514.3811337922825;6516.315786842408;1627.7070127376949;1652.3225477554145;1654.8250741973093;1655.470249520142;1656.0477725307996;1093;1110.4558766204664;1111.527600769686;1118.8713297970676;1132.1961407400672;6322.841928951721;6359.9364006360265;6365.427111559982;6368.745250189941;6379.170707807955;6754.223264324567;6755.088063111515;6757.526973111933;6759.9522074078595;6761.898571521431;6207.906881396782;6352.110704501373;6360.14138091361;6363.7772677956045;6365.204349456318;3619.2942376236906;3656.3418584654487;3657.65252301031;3661.707063434919;3663;6906.758263460855;6986.231514533397;6997.860042799217;7009;7010.789676309617;6409.359064093646;6649.833754156088;6833.760818371433;6914.723411063501;6925.965370173218;6799.762008329783;6816.659167041628;6972.012976301831;6994.61529615876;7009.404200643003;6762.4928130389435;6777.661116944231;6779.6271205084195;6793.961811456642;6800.761973330909;6541.771038013647;6677.432418244503;6679.666016699049;6689.531732778659;6689.8327541809895;6985.057017302803;6992.685329160098;6992.720291188295;6995.335443132894;7008.194057683431;4740.928886066781;4779.832705855418;4782.402199725035;4786.401699787527;4787.760611969318;6606.894236370928;6616.338366163345;6694.631795251308;6709.36261055199;6710.597364158166;6477.1579694639395;6495.1556297681;6510.44661203803;6518.185226846645;6528.67356632176;6467.805965821033;6495.090687303876;6506.934930650822;6520.282768895323;6527.445167160845;6323.557350985387;6386.297507274103;6403.167588213502;6412.871742565029;6496.123023391781;6637.668116594247;6639.269680335063;6645.933540664725;6660.866782664417;6669.0663307136065;6479.708413121515;6499.805005849832;6500.382463665944;6506.3493650634555;6507.642079685474 06:11:41 INFO - 2016-01-22 06:11:41,255 DEBUG : BROWSER_OUTPUT: |5;yui.html;897.8294124116419;900.3933661467909;931.8695382646431;935.3499317974002;945.7162851144659;14707.779383309246;15294.235288235599;15333.616659583502;15334.46329378472;15378.384864605403;16293.674126517462;16324.510264692048;16325.265363058661;16358.591035224126;16412.917935410336;755.452297084612;767.3515879082195;891.1088911088912;892.7946572288378;921.2215677752289;924.5746956400064;928.4429342394543;931.2829121576382;931.3899395895666;935.0275713258237;792.3383974381399;961.2358175250657;970.335320305593;970.7961328121103;972.2173650211577;799.6561478564215;936.8173206224754;948.127722495306;949.5679465843025;950.5912457643211;703.4056222491986;704.0460176460924;704.3484776581693;705.7459314646723;708.1749761527814;14978.62553436162;14988.400463981428;15045.473408430762;15095;15103.244837758179;12213.389330533526;12476.376181190904;12522;12525.044593115876;12673.493060277578;11035.72410689731;11048.889511104866;11120.777584448266;11476.311421314747;11575.884241157564;3913.1782325711633;3943.9014024649327;3990.1421194443333;4001.8399264029404;4004.2391945530258;50240.497595023946;50485.495145048444;51136.74431627818;51427;51479.74260128675;10292.536835842406;10952.890471095347;10976.451177441097;11187.105031597453;11285.661430157108;19525.90237048834;19655;19725.704114438297;19751.604967900563;19789.505262368268;7598.240175982468;7605.809854753676;7698.2686644768655;7741.567809955176;7905.5651939142745;4570.405847239904;4628;4633.86098417048;4642.628589712815;4735.479097299294;3011.0966709987356;3013;3020.758339332848;3044.0715581747336;3050.34417600217;506.364512536764;522.7334059629617;524.7245196271988;528.7092099345344;531.0255680825651;701.0784294254306;711.9038929744522;729.2525161709291;754.4341743692231;776.6039319946755;8886.555672216493;9226.53867306632;9610.807783844284;9667.129958303787;9697.078777516266;634.4924060751381;732.8827387618062;750.3546949623319;771.0939645916026;778.7702627724835;4415.448068991376;4433.866983990485;4466.486354069324;4506.684532082722;4608.516105808909;58905.41094589085;59008;59602.10596840964;59725;59962.400375996556;50312;55866.44133558694;56170.43829561652;56428.717856410454;56448;49326.26010609844;50650.24024639634;50927.7082531287;51083;51256.743716281926;8947.642094316154;9005.324600655109;9106.908930910871;9205.355625106178;9398.107179817905;37376.62623373731;37665.43501847529;38198.045048874;38441.615583843806;38637.22725545417;44977;47742.5225747752;48103.75948120237;48620.27069594029;48852.2672159925;11161.386123763272;11612.593559225725;11857.229280096766;11932.22440541362;11974.101942354533;11522.53909843597;11716.94141529287;11730.296182229093;11737.35444550558;11931.164818462972;10598.15214782831;11034.668959931216;11065.114790816877;11229.831552526883;11512.59705910289;11254.83117753218;11458.885411146115;11686.70783230426;11965.401729913296;12031.819522706992;12372.81440778407;12600.810987835006;12603.18079324841;12934.805977910151;13030.41363138642;10392.22058345612;10416.041904076705;10509.474526273809;10978.780424391627;11141.83287250708;10422.791544168922;10684.73288167771;10776.191785616267;10837.67486975392;10944.671659850217 06:11:41 INFO - 2016-01-22 06:11:41,255 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:11:41 INFO - 2016-01-22 06:11:41,255 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:11:41 INFO - 2016-01-22 06:11:41,255 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,780 06:11:41 INFO - 2016-01-22 06:11:41,255 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:11:41 INFO - 2016-01-22 06:11:41,256 DEBUG : BROWSER_OUTPUT: __startTimestamp1453471901244__endTimestamp 06:11:41 INFO - 2016-01-22 06:11:41,256 DEBUG : BROWSER_OUTPUT: 06:11:41 INFO - 2016-01-22 06:11:41,256 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:11:41 INFO - 2016-01-22 06:11:41,256 DEBUG : BROWSER_OUTPUT: Number of tests: 6 06:11:41 INFO - 2016-01-22 06:11:41,256 DEBUG : BROWSER_OUTPUT: 06:11:41 INFO - 2016-01-22 06:11:41,256 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5398.19 Median:5626.83 stddev:602.48 (10.7%) stddev-sans-first:576.81 06:11:41 INFO - 2016-01-22 06:11:41,257 DEBUG : BROWSER_OUTPUT: Values: 3103.8 3168.0 3196.5 3197.6 3207.5 5388.4 5391.2 5413.7 5427.9 5433.9 5360.4 5365.9 5373.8 5388.1 5423.7 4857.3 4948.5 4954.9 4970.5 4991.7 4875.0 4921.9 4975.9 4988.3 4994.0 4846.4 4937.9 4953.7 4963.0 5019.5 4960.4 4968.7 4999.4 5021.2 5026.0 4760.3 4827.8 4924.9 4931.6 4960.5 5195.4 5226.5 5239.0 5245.2 5248.1 5805.5 5833.6 5847.0 5849.4 5852.2 5642.7 5696.9 5729.5 5833.9 5904.1 5364.4 5392.1 5392.4 5414.2 5417.0 5852.7 5918.3 5936.1 5938.9 5952.9 5526.4 5630.6 5690.9 5698.1 5703.6 5626.8 5712.4 5716.6 5730.6 5740.7 5806.3 5826.1 5860.3 5864.4 5877.7 5654.4 5745.9 5772.6 5799.9 5852.0 4495.8 4505.9 4511.1 4512.8 4525.6 4023.2 4051.7 4061.3 4065.7 4067.2 5395.9 5472.9 5494.8 5548.6 5562.5 5828.6 5852.4 5854.1 5860.0 5894.4 5518.9 5525.0 5529.0 5531.4 5535.9 4906.7 4994.2 5006.5 5055.9 5076.1 5982.9 6023.9 6032.1 6052.3 6064.2 5885.5 5889.8 5892.5 5893.9 5897.4 5835.3 5886.7 6000.5 6020.9 6077.1 5753.7 5788.8 5812.2 5814.6 5820.4 5894.5 5896.1 5896.6 5898.1 5900.5 5519.9 5614.3 5638.8 5763.4 5779.3 5700.6 5720.2 5728.6 5733.6 5734.0 5404.7 5643.4 5660.5 5667.3 5672.3 5556.7 5647.4 5739.5 5747.3 5779.6 5530.3 5598.6 5642.3 5646.7 5674.3 06:11:41 INFO - 2016-01-22 06:11:41,257 DEBUG : BROWSER_OUTPUT: 06:11:41 INFO - 2016-01-22 06:11:41,257 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49786.11 Median:187051.50 stddev:85618.31 (45.8%) stddev-sans-first:85778.83 06:11:41 INFO - 2016-01-22 06:11:41,257 DEBUG : BROWSER_OUTPUT: Values: 597.0 604.0 622.5 636.0 641.2 31903.2 33002.5 33041.7 39404.0 39835.6 44318.3 44874.6 44896.8 44942.6 44962.6 8993.8 9166.0 9170.3 9176.6 9179.6 8223.4 8404.3 10738.4 11049.8 11056.4 10773.5 10859.9 10900.9 10903.6 10907.2 8228.6 10729.2 10840.5 10842.5 10844.9 8000.6 10716.7 10980.0 10986.3 10992.5 41992.0 42359.4 42423.6 42688.6 42937.0 3665.9 4273.5 4286.8 4293.5 4367.8 31817.8 32047.4 32258.2 32532.9 33037.3 4181.6 4212.9 4278.6 4297.8 4298.8 292683.0 294989.0 295642.0 295913.0 297320.0 58934.7 60504.7 65400.0 66192.3 66462.3 57326.1 58003.0 58101.4 58134.1 58205.0 16134.4 16148.8 16162.9 16283.3 16333.3 12709.9 12738.2 12745.5 12746.0 12757.7 21054.8 21525.4 21640.9 21671.5 21685.8 7864.3 8055.8 8083.2 8176.6 8294.8 983.4 1022.6 1054.6 1090.9 1094.6 27607.0 28049.4 28085.3 28172.2 28764.7 1079.3 1103.9 1106.4 1107.6 1139.1 13690.2 14090.2 14388.4 14997.6 16306.8 361455.0 365535.0 366534.0 366783.0 367599.0 57126.7 57262.4 57431.7 57440.0 57476.7 1350.7 1381.2 1448.7 1476.1 1494.4 25682.6 26789.5 27097.7 27179.3 27285.0 46098.0 47100.8 47226.8 47248.5 47607.5 198646.0 333984.0 339754.0 339845.0 339982.0 30225.4 30834.7 30861.8 30883.1 30893.8 41780.6 42262.4 42295.8 43154.8 43628.1 38982.6 39055.2 39849.8 39853.0 39876.6 35893.5 36359.0 37619.8 38345.8 39065.6 37911.2 38571.8 38823.0 39339.8 39364.8 29926.6 31651.7 33015.0 34121.0 34798.8 26414.9 26882.9 26932.9 26940.6 27005.6 06:11:41 INFO - 2016-01-22 06:11:41,257 DEBUG : BROWSER_OUTPUT: 06:11:41 INFO - 2016-01-22 06:11:41,257 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29667.83 Median:35899.41 stddev:92002.34 (256.3%) stddev-sans-first:92234.11 06:11:41 INFO - 2016-01-22 06:11:41,259 DEBUG : BROWSER_OUTPUT: Values: 362.4 366.0 366.1 367.6 369.0 14851.3 16540.0 17888.6 18757.2 19388.5 17959.9 17967.6 18004.6 18571.5 18813.1 5398.3 5510.6 5520.3 5524.3 5524.8 4088.0 4201.9 5522.3 5850.5 5976.3 5979.9 6069.0 6073.8 6079.3 6102.3 5259.5 5360.4 5362.9 5378.9 5387.9 5157.4 5167.1 5170.1 5335.7 5346.4 12775.7 12977.5 13003.7 18478.4 21964.6 2211.9 2242.7 2255.7 2255.8 2306.0 9662.5 9888.6 9922.0 10036.8 11456.5 1908.5 1915.8 1940.9 1984.5 2000.9 61332.0 62817.4 62865.7 63077.4 63645.7 10017.2 10081.3 10200.1 10258.0 10557.7 13575.3 13607.2 13687.9 13688.9 13715.3 7884.4 7904.6 7907.1 7966.0 7977.3 7671.8 7700.3 7726.7 7728.7 7762.3 4462.0 4467.2 4487.9 4489.0 4491.3 1726.5 1774.6 1782.3 1821.2 1833.0 939.2 1004.7 1036.2 1088.7 1101.5 14847.9 16780.9 17244.6 17463.8 17512.6 953.5 963.2 982.5 1009.9 1043.4 9363.3 9459.2 9548.4 9568.4 9710.0 478418.0 479865.0 601875.0 615626.0 617167.0 76020.1 81232.0 87830.0 89361.6 89941.6 1164.5 1197.8 1206.5 1208.9 1216.9 15746.8 16040.8 16084.0 16225.0 16370.3 35806.0 35992.8 36303.2 36323.6 36976.8 47594.0 50134.7 50400.2 51128.2 51322.2 9264.6 9480.3 9500.1 9930.5 9960.6 11574.2 11686.9 12389.8 13178.8 17915.6 14909.5 15016.7 15023.3 15158.0 15402.2 14392.4 14408.0 14425.3 14968.6 15006.5 14524.9 14561.3 14687.6 14932.3 14980.7 9512.2 9589.0 9981.6 10006.0 10095.8 10653.8 10766.2 12767.8 15474.6 15880.3 06:11:41 INFO - 2016-01-22 06:11:41,259 DEBUG : BROWSER_OUTPUT: 06:11:41 INFO - 2016-01-22 06:11:41,259 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5386.22 Median:6599.56 stddev:2787.90 (42.2%) stddev-sans-first:2780.09 06:11:41 INFO - 2016-01-22 06:11:41,259 DEBUG : BROWSER_OUTPUT: Values: 1458.6 1530.3 1588.5 1698.7 1750.8 6975.2 7402.7 7576.4 7594.1 7594.4 7600.0 7803.5 7811.0 7820.3 7829.1 2195.9 2231.4 2232.4 2238.7 2239.9 2215.7 2243.0 2279.2 2284.6 2284.9 2182.8 2208.5 2240.3 2240.6 2248.1 2188.1 2213.1 2237.8 2242.9 2243.3 2224.9 2239.8 2251.0 2251.8 2256.9 7632.0 7721.7 7755.1 7763.9 7779.9 4440.0 4526.0 4528.3 4530.6 4533.2 2924.2 2982.6 2994.3 2999.3 3031.9 974.3 990.4 1002.7 1006.6 1026.2 10032.1 10121.7 10126.5 10126.6 10165.3 3996.3 4001.2 4026.8 4055.5 4061.8 8653.5 8656.6 8663.1 8670.5 8675.1 4542.5 4557.5 4557.7 4558.1 4574.5 3854.7 3888.1 4182.4 4208.5 4210.9 3587.5 3611.3 3617.4 3625.3 3628.7 1107.0 1113.5 1116.5 1123.9 1125.8 1422.5 1436.9 1447.4 1447.5 1469.3 7479.9 7522.5 7532.8 7539.1 7542.9 455.7 460.1 473.3 473.5 474.4 5920.1 5959.3 5970.3 5980.6 5992.1 8703.2 9100.6 9188.9 9205.9 9258.7 9098.7 9113.5 9133.8 9141.9 9152.7 6933.0 7105.3 7132.4 7137.7 7215.4 7310.9 7374.7 7399.4 7414.6 7432.1 7600.6 7839.9 7977.8 7981.8 7985.4 8431.9 8452.5 8502.6 8663.0 8665.4 6621.9 6964.0 7007.5 7067.2 7331.7 7507.0 7517.2 7520.5 7524.5 7547.0 6909.6 7065.7 7074.0 7185.1 7218.4 7005.9 7017.6 7063.1 7082.0 7110.3 6858.2 6896.4 7114.8 7115.1 7121.5 6577.2 6645.2 6655.4 6660.6 6672.1 6548.6 6552.8 6651.8 6699.0 6839.7 06:11:41 INFO - 2016-01-22 06:11:41,259 DEBUG : BROWSER_OUTPUT: 06:11:41 INFO - 2016-01-22 06:11:41,259 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5436.53 Median:6265.37 stddev:1641.68 (26.2%) stddev-sans-first:1601.27 06:11:41 INFO - 2016-01-22 06:11:41,260 DEBUG : BROWSER_OUTPUT: Values: 349.5 352.6 359.9 361.6 363.3 6034.6 6061.8 6087.6 6103.7 6105.0 6013.2 6022.1 6036.0 6040.1 6044.5 4511.7 4645.9 4745.7 4746.3 4747.9 4644.0 4644.1 4644.6 4693.4 4712.6 4615.8 4617.4 4638.4 4644.6 4645.8 4433.4 4480.9 4485.3 4536.5 4621.4 4028.2 4029.8 4049.8 4056.0 4070.7 5733.2 5790.2 5793.4 5796.3 5805.4 5079.6 5220.2 5222.1 5225.4 5389.9 6365.8 6366.2 6377.4 6389.6 6401.5 4491.2 4491.5 4491.8 4492.5 4493.0 6568.3 6640.6 6706.0 6742.8 6751.4 4593.8 4626.5 4632.1 4633.6 4639.2 4686.7 4688.0 4694.2 4694.8 4695.0 5336.2 5406.0 5410.7 5410.7 5418.1 6380.8 6499.4 6509.7 6514.4 6516.3 1627.7 1652.3 1654.8 1655.5 1656.0 1093.0 1110.5 1111.5 1118.9 1132.2 6322.8 6359.9 6365.4 6368.7 6379.2 6754.2 6755.1 6757.5 6760.0 6761.9 6207.9 6352.1 6360.1 6363.8 6365.2 3619.3 3656.3 3657.7 3661.7 3663.0 6906.8 6986.2 6997.9 7009.0 7010.8 6409.4 6649.8 6833.8 6914.7 6926.0 6799.8 6816.7 6972.0 6994.6 7009.4 6762.5 6777.7 6779.6 6794.0 6800.8 6541.8 6677.4 6679.7 6689.5 6689.8 6985.1 6992.7 6992.7 6995.3 7008.2 4740.9 4779.8 4782.4 4786.4 4787.8 6606.9 6616.3 6694.6 6709.4 6710.6 6477.2 6495.2 6510.4 6518.2 6528.7 6467.8 6495.1 6506.9 6520.3 6527.4 6323.6 6386.3 6403.2 6412.9 6496.1 6637.7 6639.3 6645.9 6660.9 6669.1 6479.7 6499.8 6500.4 6506.3 6507.6 06:11:41 INFO - 2016-01-22 06:11:41,260 DEBUG : BROWSER_OUTPUT: 06:11:41 INFO - 2016-01-22 06:11:41,260 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14798.10 Median:4589.46 stddev:16974.07 (369.8%) stddev-sans-first:16989.59 06:11:41 INFO - 2016-01-22 06:11:41,260 DEBUG : BROWSER_OUTPUT: Values: 897.8 900.4 931.9 935.3 945.7 14707.8 15294.2 15333.6 15334.5 15378.4 16293.7 16324.5 16325.3 16358.6 16412.9 755.5 767.4 891.1 892.8 921.2 924.6 928.4 931.3 931.4 935.0 792.3 961.2 970.3 970.8 972.2 799.7 936.8 948.1 949.6 950.6 703.4 704.0 704.3 705.7 708.2 14978.6 14988.4 15045.5 15095.0 15103.2 12213.4 12476.4 12522.0 12525.0 12673.5 11035.7 11048.9 11120.8 11476.3 11575.9 3913.2 3943.9 3990.1 4001.8 4004.2 50240.5 50485.5 51136.7 51427.0 51479.7 10292.5 10952.9 10976.5 11187.1 11285.7 19525.9 19655.0 19725.7 19751.6 19789.5 7598.2 7605.8 7698.3 7741.6 7905.6 4570.4 4628.0 4633.9 4642.6 4735.5 3011.1 3013.0 3020.8 3044.1 3050.3 506.4 522.7 524.7 528.7 531.0 701.1 711.9 729.3 754.4 776.6 8886.6 9226.5 9610.8 9667.1 9697.1 634.5 732.9 750.4 771.1 778.8 4415.4 4433.9 4466.5 4506.7 4608.5 58905.4 59008.0 59602.1 59725.0 59962.4 50312.0 55866.4 56170.4 56428.7 56448.0 49326.3 50650.2 50927.7 51083.0 51256.7 8947.6 9005.3 9106.9 9205.4 9398.1 37376.6 37665.4 38198.0 38441.6 38637.2 44977.0 47742.5 48103.8 48620.3 48852.3 11161.4 11612.6 11857.2 11932.2 11974.1 11522.5 11716.9 11730.3 11737.4 11931.2 10598.2 11034.7 11065.1 11229.8 11512.6 11254.8 11458.9 11686.7 11965.4 12031.8 12372.8 12600.8 12603.2 12934.8 13030.4 10392.2 10416.0 10509.5 10978.8 11141.8 10422.8 10684.7 10776.2 10837.7 10944.7 06:11:41 INFO - 2016-01-22 06:11:41,260 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:11:41 INFO - 2016-01-22 06:11:41,261 DEBUG : BROWSER_OUTPUT: 06:11:41 INFO - 2016-01-22 06:11:41,929 INFO : Browser exited with error code: 0 06:11:41 INFO - 2016-01-22 06:11:41,946 INFO : Completed test dromaeo_css (00:18:31) 06:11:41 INFO - 2016-01-22 06:11:41,946 INFO : Starting test kraken 06:11:41 INFO - 2016-01-22 06:11:41,946 DEBUG : operating with platform_type : linux_ 06:11:41 INFO - 2016-01-22 06:11:41,946 INFO : Initialising browser for kraken test... 06:11:41 INFO - 2016-01-22 06:11:41,952 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaHbWYn/profile http://localhost:37823/getInfo.html 06:11:46 INFO - 2016-01-22 06:11:46,017 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:11:46 INFO - 2016-01-22 06:11:46,017 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:11:46 INFO - 2016-01-22 06:11:46,017 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:11:46 INFO - 2016-01-22 06:11:46,017 DEBUG : BROWSER_OUTPUT: __metrics 06:11:46 INFO - 2016-01-22 06:11:46,179 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:11:47 INFO - 2016-01-22 06:11:47,372 INFO : Browser initialized. 06:11:47 INFO - 2016-01-22 06:11:47,372 INFO : Running cycle 1/1 for kraken test... 06:11:47 INFO - 2016-01-22 06:11:47,373 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaHbWYn/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:11:59 INFO - 2016-01-22 06:11:59,229 DEBUG : BROWSER_OUTPUT: RSS: Main: 137154560 06:11:59 INFO - 2016-01-22 06:11:59,230 DEBUG : BROWSER_OUTPUT: 06:12:01 INFO - 2016-01-22 06:12:01,686 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37823/tests/kraken/driver.html?testName=audio-beat-detection) 06:12:02 INFO - 2016-01-22 06:12:02,252 DEBUG : BROWSER_OUTPUT: RSS: Main: 170385408 06:12:02 INFO - 2016-01-22 06:12:02,252 DEBUG : BROWSER_OUTPUT: 06:12:04 INFO - 2016-01-22 06:12:04,830 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37823/tests/kraken/driver.html?testName=audio-dft) 06:12:05 INFO - 2016-01-22 06:12:05,399 DEBUG : BROWSER_OUTPUT: RSS: Main: 169472000 06:12:05 INFO - 2016-01-22 06:12:05,399 DEBUG : BROWSER_OUTPUT: 06:12:08 INFO - 2016-01-22 06:12:08,240 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37823/tests/kraken/driver.html?testName=audio-fft) 06:12:08 INFO - 2016-01-22 06:12:08,801 DEBUG : BROWSER_OUTPUT: RSS: Main: 166256640 06:12:08 INFO - 2016-01-22 06:12:08,802 DEBUG : BROWSER_OUTPUT: 06:12:10 INFO - 2016-01-22 06:12:10,780 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37823/tests/kraken/driver.html?testName=audio-oscillator) 06:12:11 INFO - 2016-01-22 06:12:11,347 DEBUG : BROWSER_OUTPUT: RSS: Main: 165183488 06:12:11 INFO - 2016-01-22 06:12:11,347 DEBUG : BROWSER_OUTPUT: 06:12:13 INFO - 2016-01-22 06:12:13,549 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37823/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:12:14 INFO - 2016-01-22 06:12:14,110 DEBUG : BROWSER_OUTPUT: RSS: Main: 165023744 06:12:14 INFO - 2016-01-22 06:12:14,111 DEBUG : BROWSER_OUTPUT: 06:12:16 INFO - 2016-01-22 06:12:16,960 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37823/tests/kraken/driver.html?testName=imaging-darkroom) 06:12:17 INFO - 2016-01-22 06:12:17,528 DEBUG : BROWSER_OUTPUT: RSS: Main: 165953536 06:12:17 INFO - 2016-01-22 06:12:17,528 DEBUG : BROWSER_OUTPUT: 06:12:20 INFO - 2016-01-22 06:12:20,919 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37823/tests/kraken/driver.html?testName=imaging-desaturate) 06:12:21 INFO - 2016-01-22 06:12:21,492 DEBUG : BROWSER_OUTPUT: RSS: Main: 164700160 06:12:21 INFO - 2016-01-22 06:12:21,492 DEBUG : BROWSER_OUTPUT: 06:12:24 INFO - 2016-01-22 06:12:24,191 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37823/tests/kraken/driver.html?testName=json-parse-financial) 06:12:24 INFO - 2016-01-22 06:12:24,772 DEBUG : BROWSER_OUTPUT: RSS: Main: 165367808 06:12:24 INFO - 2016-01-22 06:12:24,772 DEBUG : BROWSER_OUTPUT: 06:12:26 INFO - 2016-01-22 06:12:26,322 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37823/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:12:26 INFO - 2016-01-22 06:12:26,883 DEBUG : BROWSER_OUTPUT: RSS: Main: 173187072 06:12:26 INFO - 2016-01-22 06:12:26,884 DEBUG : BROWSER_OUTPUT: 06:12:28 INFO - 2016-01-22 06:12:28,647 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:12:29 INFO - 2016-01-22 06:12:29,240 DEBUG : BROWSER_OUTPUT: RSS: Main: 169701376 06:12:29 INFO - 2016-01-22 06:12:29,240 DEBUG : BROWSER_OUTPUT: 06:12:31 INFO - 2016-01-22 06:12:31,273 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:12:31 INFO - 2016-01-22 06:12:31,841 DEBUG : BROWSER_OUTPUT: RSS: Main: 181989376 06:12:31 INFO - 2016-01-22 06:12:31,841 DEBUG : BROWSER_OUTPUT: 06:12:34 INFO - 2016-01-22 06:12:34,188 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:12:34 INFO - 2016-01-22 06:12:34,769 DEBUG : BROWSER_OUTPUT: RSS: Main: 188952576 06:12:34 INFO - 2016-01-22 06:12:34,769 DEBUG : BROWSER_OUTPUT: 06:12:37 INFO - 2016-01-22 06:12:37,284 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:12:37 INFO - 2016-01-22 06:12:37,853 DEBUG : BROWSER_OUTPUT: RSS: Main: 186982400 06:12:37 INFO - 2016-01-22 06:12:37,855 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,549 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37823/tests/kraken/driver.html?testName=ai-astar) 06:12:39 INFO - 2016-01-22 06:12:39,799 DEBUG : BROWSER_OUTPUT: RSS: Main: 244875264 06:12:39 INFO - 2016-01-22 06:12:39,799 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,800 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:12:39 INFO - 2016-01-22 06:12:39,800 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:12:39 INFO - 2016-01-22 06:12:39,800 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:12:39 INFO - 2016-01-22 06:12:39,800 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:12:39 INFO - 2016-01-22 06:12:39,800 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;110;103;112;110;111;100;112;111;113 06:12:39 INFO - 2016-01-22 06:12:39,800 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;150;123;147;157;128;145;148;123;156;123 06:12:39 INFO - 2016-01-22 06:12:39,800 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;162;160;163;159;160;163;162;160;160 06:12:39 INFO - 2016-01-22 06:12:39,800 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;73;73;157;73;73;71;71;159;72 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;139;92;93;93;140;92;93;136 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;110;109;110;109;110;112;110;111;110 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;145;146;146;147;146;145;146;146 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;55;57;58;57;59;59;57;58;56;56 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;65;64;65;64;65;66;65;63;64;65 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;81;79;89;83;81;83;84;82;82;83 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;119;119;112;112;115;121;112;119;121 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;135;146;155;149;146;139;146;144;138;135 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;67;68;66;69;67;68;67;67;68;67 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,904 06:12:39 INFO - 2016-01-22 06:12:39,801 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:12:39 INFO - 2016-01-22 06:12:39,802 DEBUG : BROWSER_OUTPUT: __startTimestamp1453471959798__endTimestamp 06:12:39 INFO - 2016-01-22 06:12:39,802 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,803 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:12:39 INFO - 2016-01-22 06:12:39,803 DEBUG : BROWSER_OUTPUT: Number of tests: 14 06:12:39 INFO - 2016-01-22 06:12:39,803 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,803 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.30 Median:111.00 stddev:4.27 (3.8%) stddev-sans-first:4.48 06:12:39 INFO - 2016-01-22 06:12:39,803 DEBUG : BROWSER_OUTPUT: Values: 111.0 110.0 103.0 112.0 110.0 111.0 100.0 112.0 111.0 113.0 06:12:39 INFO - 2016-01-22 06:12:39,803 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,803 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.00 Median:147.50 stddev:14.12 (9.6%) stddev-sans-first:14.50 06:12:39 INFO - 2016-01-22 06:12:39,803 DEBUG : BROWSER_OUTPUT: Values: 150.0 123.0 147.0 157.0 128.0 145.0 148.0 123.0 156.0 123.0 06:12:39 INFO - 2016-01-22 06:12:39,804 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,804 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:1.41 (0.9%) stddev-sans-first:1.50 06:12:39 INFO - 2016-01-22 06:12:39,804 DEBUG : BROWSER_OUTPUT: Values: 161.0 162.0 160.0 163.0 159.0 160.0 163.0 162.0 160.0 160.0 06:12:39 INFO - 2016-01-22 06:12:39,804 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,804 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:89.50 Median:73.00 stddev:36.11 (49.5%) stddev-sans-first:37.81 06:12:39 INFO - 2016-01-22 06:12:39,804 DEBUG : BROWSER_OUTPUT: Values: 73.0 73.0 73.0 157.0 73.0 73.0 71.0 71.0 159.0 72.0 06:12:39 INFO - 2016-01-22 06:12:39,804 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,804 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.20 Median:92.00 stddev:22.20 (24.1%) stddev-sans-first:22.94 06:12:39 INFO - 2016-01-22 06:12:39,804 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 139.0 92.0 93.0 93.0 140.0 92.0 93.0 136.0 06:12:39 INFO - 2016-01-22 06:12:39,804 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,804 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.94 (0.9%) stddev-sans-first:0.93 06:12:39 INFO - 2016-01-22 06:12:39,804 DEBUG : BROWSER_OUTPUT: Values: 109.0 110.0 109.0 110.0 109.0 110.0 112.0 110.0 111.0 110.0 06:12:39 INFO - 2016-01-22 06:12:39,804 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,805 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 06:12:39 INFO - 2016-01-22 06:12:39,805 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 145.0 146.0 146.0 147.0 146.0 145.0 146.0 146.0 06:12:39 INFO - 2016-01-22 06:12:39,805 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,805 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 06:12:39 INFO - 2016-01-22 06:12:39,805 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 06:12:39 INFO - 2016-01-22 06:12:39,805 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,805 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.50 stddev:1.32 (2.3%) stddev-sans-first:1.13 06:12:39 INFO - 2016-01-22 06:12:39,806 DEBUG : BROWSER_OUTPUT: Values: 55.0 57.0 58.0 57.0 59.0 59.0 57.0 58.0 56.0 56.0 06:12:39 INFO - 2016-01-22 06:12:39,806 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,806 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:0.84 (1.3%) stddev-sans-first:0.88 06:12:39 INFO - 2016-01-22 06:12:39,806 DEBUG : BROWSER_OUTPUT: Values: 65.0 64.0 65.0 64.0 65.0 66.0 65.0 63.0 64.0 65.0 06:12:39 INFO - 2016-01-22 06:12:39,806 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,806 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:2.63 (3.2%) stddev-sans-first:2.71 06:12:39 INFO - 2016-01-22 06:12:39,806 DEBUG : BROWSER_OUTPUT: Values: 81.0 79.0 89.0 83.0 81.0 83.0 84.0 82.0 82.0 83.0 06:12:39 INFO - 2016-01-22 06:12:39,806 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,806 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:116.70 Median:119.00 stddev:3.68 (3.1%) stddev-sans-first:3.91 06:12:39 INFO - 2016-01-22 06:12:39,806 DEBUG : BROWSER_OUTPUT: Values: 117.0 119.0 119.0 112.0 112.0 115.0 121.0 112.0 119.0 121.0 06:12:39 INFO - 2016-01-22 06:12:39,806 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,806 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.30 Median:146.00 stddev:6.46 (4.4%) stddev-sans-first:6.12 06:12:39 INFO - 2016-01-22 06:12:39,807 DEBUG : BROWSER_OUTPUT: Values: 135.0 146.0 155.0 149.0 146.0 139.0 146.0 144.0 138.0 135.0 06:12:39 INFO - 2016-01-22 06:12:39,807 DEBUG : BROWSER_OUTPUT: 06:12:39 INFO - 2016-01-22 06:12:39,807 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:0.84 (1.2%) stddev-sans-first:0.88 06:12:39 INFO - 2016-01-22 06:12:39,807 DEBUG : BROWSER_OUTPUT: Values: 67.0 68.0 66.0 69.0 67.0 68.0 67.0 67.0 68.0 67.0 06:12:39 INFO - 2016-01-22 06:12:39,807 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:12:39 INFO - 2016-01-22 06:12:39,807 DEBUG : BROWSER_OUTPUT: 06:12:40 INFO - 2016-01-22 06:12:40,609 INFO : Browser exited with error code: 0 06:12:40 INFO - 2016-01-22 06:12:40,659 INFO : Completed test kraken (00:00:58) 06:12:40 INFO - 2016-01-22 06:12:40,659 INFO : Starting test v8_7 06:12:40 INFO - 2016-01-22 06:12:40,659 DEBUG : operating with platform_type : linux_ 06:12:40 INFO - 2016-01-22 06:12:40,659 INFO : Initialising browser for v8_7 test... 06:12:40 INFO - 2016-01-22 06:12:40,665 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOtsINB/profile http://localhost:37823/getInfo.html 06:12:44 INFO - 2016-01-22 06:12:44,887 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:12:44 INFO - 2016-01-22 06:12:44,887 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:12:44 INFO - 2016-01-22 06:12:44,887 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:12:44 INFO - 2016-01-22 06:12:44,887 DEBUG : BROWSER_OUTPUT: __metrics 06:12:45 INFO - 2016-01-22 06:12:45,069 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:12:45 INFO - 2016-01-22 06:12:45,884 INFO : Browser initialized. 06:12:45 INFO - 2016-01-22 06:12:45,885 INFO : Running cycle 1/1 for v8_7 test... 06:12:45 INFO - 2016-01-22 06:12:45,885 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpOtsINB/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:12:57 INFO - 2016-01-22 06:12:57,638 DEBUG : BROWSER_OUTPUT: RSS: Main: 137625600 06:12:57 INFO - 2016-01-22 06:12:57,638 DEBUG : BROWSER_OUTPUT: 06:13:19 INFO - 2016-01-22 06:13:19,002 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37823/tests/v8_7/run.html (next: http://localhost:37823/tests/v8_7/run.html) 06:13:19 INFO - 2016-01-22 06:13:19,252 DEBUG : BROWSER_OUTPUT: RSS: Main: 428703744 06:13:19 INFO - 2016-01-22 06:13:19,252 DEBUG : BROWSER_OUTPUT: 06:13:19 INFO - 2016-01-22 06:13:19,254 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:13:19 INFO - 2016-01-22 06:13:19,254 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:13:19 INFO - 2016-01-22 06:13:19,254 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:13:19 INFO - 2016-01-22 06:13:19,254 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:13:19 INFO - 2016-01-22 06:13:19,254 DEBUG : BROWSER_OUTPUT: |0;Richards;165.78249336870027 06:13:19 INFO - 2016-01-22 06:13:19,254 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;167.81339150864238 06:13:19 INFO - 2016-01-22 06:13:19,255 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.4857462269424 06:13:19 INFO - 2016-01-22 06:13:19,255 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5208.333333333333 06:13:19 INFO - 2016-01-22 06:13:19,255 DEBUG : BROWSER_OUTPUT: |4;RayTrace;961.5384615384615 06:13:19 INFO - 2016-01-22 06:13:19,255 DEBUG : BROWSER_OUTPUT: |5;Earley;557.4136008918617 06:13:19 INFO - 2016-01-22 06:13:19,255 DEBUG : BROWSER_OUTPUT: |6;Boyer;11142.857142857143 06:13:19 INFO - 2016-01-22 06:13:19,256 DEBUG : BROWSER_OUTPUT: |7;RegExp;24119.04761904762 06:13:19 INFO - 2016-01-22 06:13:19,256 DEBUG : BROWSER_OUTPUT: |8;Splay;526.6179540709812 06:13:19 INFO - 2016-01-22 06:13:19,256 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 06:13:19 INFO - 2016-01-22 06:13:19,256 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:13:19 INFO - 2016-01-22 06:13:19,256 DEBUG : BROWSER_OUTPUT: __startTimestamp1453471999252__endTimestamp 06:13:19 INFO - 2016-01-22 06:13:19,257 DEBUG : BROWSER_OUTPUT: 06:13:19 INFO - 2016-01-22 06:13:19,257 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:13:19 INFO - 2016-01-22 06:13:19,257 DEBUG : BROWSER_OUTPUT: Number of tests: 10 06:13:19 INFO - 2016-01-22 06:13:19,257 DEBUG : BROWSER_OUTPUT: 06:13:19 INFO - 2016-01-22 06:13:19,257 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:165.78 Median:165.78 stddev:0.00 (0.0%) 06:13:19 INFO - 2016-01-22 06:13:19,258 DEBUG : BROWSER_OUTPUT: Values: 165.8 06:13:19 INFO - 2016-01-22 06:13:19,258 DEBUG : BROWSER_OUTPUT: 06:13:19 INFO - 2016-01-22 06:13:19,258 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:167.81 Median:167.81 stddev:0.00 (0.0%) 06:13:19 INFO - 2016-01-22 06:13:19,258 DEBUG : BROWSER_OUTPUT: Values: 167.8 06:13:19 INFO - 2016-01-22 06:13:19,258 DEBUG : BROWSER_OUTPUT: 06:13:19 INFO - 2016-01-22 06:13:19,259 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.49 Median:279.49 stddev:0.00 (0.0%) 06:13:19 INFO - 2016-01-22 06:13:19,259 DEBUG : BROWSER_OUTPUT: Values: 279.5 06:13:19 INFO - 2016-01-22 06:13:19,259 DEBUG : BROWSER_OUTPUT: 06:13:19 INFO - 2016-01-22 06:13:19,259 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5208.33 Median:5208.33 stddev:0.00 (0.0%) 06:13:19 INFO - 2016-01-22 06:13:19,259 DEBUG : BROWSER_OUTPUT: Values: 5208.3 06:13:19 INFO - 2016-01-22 06:13:19,259 DEBUG : BROWSER_OUTPUT: 06:13:19 INFO - 2016-01-22 06:13:19,260 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:961.54 Median:961.54 stddev:0.00 (0.0%) 06:13:19 INFO - 2016-01-22 06:13:19,260 DEBUG : BROWSER_OUTPUT: Values: 961.5 06:13:19 INFO - 2016-01-22 06:13:19,260 DEBUG : BROWSER_OUTPUT: 06:13:19 INFO - 2016-01-22 06:13:19,260 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:557.41 Median:557.41 stddev:0.00 (0.0%) 06:13:19 INFO - 2016-01-22 06:13:19,261 DEBUG : BROWSER_OUTPUT: Values: 557.4 06:13:19 INFO - 2016-01-22 06:13:19,261 DEBUG : BROWSER_OUTPUT: 06:13:19 INFO - 2016-01-22 06:13:19,262 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11142.86 Median:11142.86 stddev:0.00 (0.0%) 06:13:19 INFO - 2016-01-22 06:13:19,262 DEBUG : BROWSER_OUTPUT: Values: 11142.9 06:13:19 INFO - 2016-01-22 06:13:19,262 DEBUG : BROWSER_OUTPUT: 06:13:19 INFO - 2016-01-22 06:13:19,262 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24119.05 Median:24119.05 stddev:0.00 (0.0%) 06:13:19 INFO - 2016-01-22 06:13:19,262 DEBUG : BROWSER_OUTPUT: Values: 24119.0 06:13:19 INFO - 2016-01-22 06:13:19,262 DEBUG : BROWSER_OUTPUT: 06:13:19 INFO - 2016-01-22 06:13:19,262 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:526.62 Median:526.62 stddev:0.00 (0.0%) 06:13:19 INFO - 2016-01-22 06:13:19,263 DEBUG : BROWSER_OUTPUT: Values: 526.6 06:13:19 INFO - 2016-01-22 06:13:19,263 DEBUG : BROWSER_OUTPUT: 06:13:19 INFO - 2016-01-22 06:13:19,263 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 06:13:19 INFO - 2016-01-22 06:13:19,263 DEBUG : BROWSER_OUTPUT: Values: 4840.6 06:13:19 INFO - 2016-01-22 06:13:19,263 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:13:19 INFO - 2016-01-22 06:13:19,263 DEBUG : BROWSER_OUTPUT: 06:13:20 INFO - 2016-01-22 06:13:20,125 INFO : Browser exited with error code: 0 06:13:20 INFO - 2016-01-22 06:13:20,140 INFO : Completed test v8_7 (00:00:39) 06:13:20 INFO - 2016-01-22 06:13:20,270 INFO : Completed test suite (00:20:09) 06:13:20 INFO - 2016-01-22 06:13:20,271 DEBUG : Working with test: dromaeo_css 06:13:20 INFO - 2016-01-22 06:13:20,271 DEBUG : Generating results file: dromaeo_css 06:13:20 INFO - 2016-01-22 06:13:20,272 DEBUG : Working with test: kraken 06:13:20 INFO - 2016-01-22 06:13:20,272 DEBUG : Generating results file: kraken 06:13:20 INFO - 2016-01-22 06:13:20,272 INFO : javascript benchmark 06:13:20 INFO - 2016-01-22 06:13:20,272 DEBUG : Working with test: v8_7 06:13:20 INFO - 2016-01-22 06:13:20,273 DEBUG : Generating results file: v8_7 06:13:20 INFO - 2016-01-22 06:13:20,273 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:13:20 INFO - 2016-01-22 06:13:20,323 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,52,35]] 06:13:20 INFO - 2016-01-22 06:13:20,323 DEBUG : process_Request line: dromaeo_css 7191.42 graph.html#tests=[[72,52,35]] 06:13:20 INFO - 2016-01-22 06:13:20,324 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:13:20 INFO - 2016-01-22 06:13:20,350 DEBUG : process_Request line: kraken 1488.80 graph.html#tests=[[232,52,35]] 06:13:20 INFO - 2016-01-22 06:13:20,350 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:13:20 INFO - 2016-01-22 06:13:20,374 DEBUG : process_Request line: v8_7 23024.29 graph.html#tests=[[230,52,35]] 06:13:20 INFO - 2016-01-22 06:13:20,375 INFO : javascript benchmark 06:13:20 INFO - 2016-01-22 06:13:20,379 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3103.7516998640103, 3167.9683203167992, 3196.52052192171, 3197.5843140391744, 3207.454732695442, 5388.353397592295, 5391.218273350361, 5413.729313534327, 5427.9185812212845, 5433.891322173554, 5360.356757189133, 5365.865853353668, 5373.81191658292, 5388.137897936331, 5423.728813559326, 4857.2714092886, 4948.505149485059, 4954.925676114861, 4970.453250142481, 4991.725455099968, 4875.049365373754, 4921.926171107436, 4975.850724478253, 4988.2766998785155, 4993.950060499385, 4846.442659094214, 4937.901241975158, 4953.727544985024, 4962.975185124069, 5019.52314530119, 4960.355153829997, 4968.677035992649, 4999.4000719913665, 5021.171506701427, 5025.99480103977, 4760.33355330254, 4827.782749776268, 4924.916518365953, 4931.605471562267, 4960.479149689267, 5195.428502864682, 5226.503482169188, 5239.0, 5245.160774276135, 5248.081585722483, 5805.535557155417, 5833.649981001154, 5846.9707651461895, 5849.44430279127, 5852.15143804146, 5642.717864106819, 5696.857578560528, 5729.484346408802, 5833.912491312633, 5904.0553511437965, 5364.409914909396, 5392.083345831218, 5392.40683524812, 5414.187871819219, 5417.024935511607, 5852.678102704393, 5918.260217472816, 5936.079907614327, 5938.851528711731, 5952.85117872048, 5526.447355264441, 5630.577706672016, 5690.886182276331, 5698.088305871041, 5703.62926409782, 5626.831195064155, 5712.428757124254, 5716.5998380113, 5730.570207234474, 5740.712964351766, 5806.332271788799, 5826.126081087871, 5860.3260625027815, 5864.44287792659, 5877.706114694248, 5654.37801841797, 5745.885082298414, 5772.624779389326, 5799.9130013049835, 5852.034414321589, 4495.77521123949, 4505.932411013838, 4511.120331535385, 4512.842050528281, 4525.615322697608, 4023.1752490739327, 4051.6961227907436, 4061.3095773718537, 4065.735727177724, 4067.2272268268935, 5395.865103372447, 5472.945270547323, 5494.780208791683, 5548.611597188158, 5562.471565201299, 5828.6211396259105, 5852.385499522574, 5854.121881717691, 5860.0, 5894.440028197314, 5518.862028449321, 5525.005499010217, 5529.0324193266015, 5531.446855314436, 5535.944640553624, 4906.70559766408, 4994.200927851599, 5006.499350064964, 5055.9494405055475, 5076.086304465158, 5982.850428739229, 6023.909641355383, 6032.095185722177, 6052.303985041776, 6064.151018857364, 5885.499732522612, 5889.764409423747, 5892.469677728939, 5893.882122357614, 5897.3807750187325, 5835.270591176103, 5886.676232807065, 6000.459958603747, 6020.8795824084145, 6077.058056001327, 5753.683547404766, 5788.797392091256, 5812.244408227072, 5814.592978491465, 5820.417958204146, 5894.528437724886, 5896.056630939029, 5896.616719913192, 5898.085796701518, 5900.527957763455, 5519.917201241904, 5614.326280846324, 5638.802641907513, 5763.36602973681, 5779.306483222041, 5700.600957932905, 5720.227769251098, 5728.627639203439, 5733.627314224562, 5734.025215713422, 5404.675719456845, 5643.435656434323, 5660.46225608583, 5667.319921609434, 5672.319321681424, 5556.694381809039, 5647.350554686263, 5739.512141468022, 5747.3103227612955, 5779.624324418899, 5530.253415788817, 5598.608097433141, 5642.263502201511, 5646.71766411686, 5674.290713660793], "unit": "ms", "name": "dojo.html", "value": 5358.527018458845}, {"lowerIsBetter": false, "replicates": [596.9940300596993, 603.9607425517341, 622.520659092499, 635.9650219237943, 641.1952998986271, 31903.202419939484, 33002.504962425555, 33041.66958330416, 39404.0, 39835.60164398355, 44318.33522497157, 44874.551254487444, 44896.775516122376, 44942.55057449424, 44962.55037449625, 8993.77515562113, 9165.999999999967, 9170.312226583033, 9176.632934682639, 9179.58691858868, 8223.42436029478, 8404.285635720971, 10738.355698658066, 11049.779004419906, 11056.447177641126, 10773.461326933622, 10859.945700271488, 10900.890991090106, 10903.563857445693, 10907.181961352891, 8228.62971166299, 10729.195310351755, 10840.457977101114, 10842.457877106153, 10844.891551084467, 8000.5999700015345, 10716.73208169802, 10980.0, 10986.285891417032, 10992.505337259843, 41992.0, 42359.36460953088, 42423.57576424227, 42688.57311426908, 42937.0, 3665.8533658653623, 4273.529911709709, 4286.807093680761, 4293.463317085364, 4367.803448844777, 31817.84091079553, 32047.359052819047, 32258.193545161324, 32532.91331094531, 33037.33925321504, 4181.560936101726, 4212.9157416851795, 4278.572142785696, 4297.763623000734, 4298.849540266076, 292683.0, 294989.0, 295642.0, 295913.0, 297320.0, 58934.70532647309, 60504.69747651234, 65400.0, 66192.33807661958, 66462.33537664659, 57326.14010789841, 58003.0, 58101.418985810444, 58134.12798808021, 58205.0, 16134.435294764628, 16148.75776863348, 16162.919185403998, 16283.267252973645, 16333.26500307489, 12709.872901270868, 12738.172018818746, 12745.490180392866, 12746.0, 12757.681057973625, 21054.789247129138, 21525.354239372842, 21640.891795540923, 21671.45821354479, 21685.783142168693, 7864.2922137007945, 8055.838883222303, 8083.232092951161, 8176.6320515576945, 8294.797254398078, 983.4148681534475, 1022.6114076650825, 1054.6466933577337, 1090.9145400326727, 1094.5567045346647, 27607.0, 28049.43901122007, 28085.297867553065, 28172.154835354973, 28764.712352876202, 1079.2822772855964, 1103.9172062095374, 1106.363840791532, 1107.5680484611007, 1139.071656599885, 13690.247036412893, 14090.225037622824, 14388.352524136439, 14997.550073497812, 16306.755398668793, 361455.0, 365535.0, 366534.0, 366783.0, 367599.0, 57126.7143664279, 57262.42737572654, 57431.712841435525, 57440.0, 57476.71261643665, 1350.6690860738986, 1381.240317825192, 1448.6885319656324, 1476.1217075839859, 1494.4097081652903, 25682.614760778975, 26789.464210715287, 27097.72902270952, 27179.320516987234, 27285.0, 46098.0, 47100.7644961773, 47226.76386618045, 47248.527514725785, 47607.52392476031, 198646.0, 333984.0, 339754.0, 339845.0, 339982.0, 30225.395492090476, 30834.69165308318, 30861.845690771403, 30883.0735077948, 30893.845530772203, 41780.58219417767, 42262.366064509675, 42295.7885210572, 43154.78422607867, 43628.12743745171, 38982.6101738979, 39055.21889562136, 39849.80075099606, 39853.0, 39876.60123398729, 35893.461598076574, 36359.0, 37619.81190094032, 38345.80827095846, 39065.60934390734, 37911.2417751649, 38571.80714096524, 38823.0, 39339.80330098331, 39364.803175985086, 29926.551101733057, 31651.683483164874, 33015.0, 34121.0, 34798.82600586981, 26414.867925660248, 26882.865585671945, 26932.865335673196, 26940.595891061257, 27005.59491607667], "unit": "ms", "name": "ext.html", "value": 19655.36381509396}, {"lowerIsBetter": false, "replicates": [362.4002276232834, 366.0464490003541, 366.0665303476136, 367.6415494892482, 368.9797061161636, 14851.257437128155, 16540.0, 17888.642227155447, 18757.15592798324, 19388.51528711781, 17959.922404655696, 17967.550811229725, 18004.639907201847, 18571.535711607215, 18813.05934703266, 5398.2982212312445, 5510.559155267589, 5520.254765606652, 5524.281843360358, 5524.751386187611, 4087.979560102195, 4201.894952626179, 5522.337319521663, 5850.4734573888345, 5976.252968378953, 5979.910301345483, 6069.0, 6073.757049718006, 6079.331273559905, 6102.328743838174, 5259.474052594748, 5360.410354860962, 5362.946370536303, 5378.892422151535, 5387.919181212285, 5157.432682404932, 5167.069927413064, 5170.146925757245, 5335.653182543161, 5346.358436987547, 12775.680607984781, 12977.545785897542, 13003.674908127372, 18478.353257635918, 21964.560708785895, 2211.9225827095975, 2242.7084479017785, 2255.70675812145, 2255.8308126890547, 2305.976940230593, 9662.468564228964, 9888.604455821758, 9922.0, 10036.849447258297, 11456.541738330458, 1908.4847091285415, 1915.817997290255, 1940.932067377635, 1984.474114359696, 2000.9199632014702, 61332.0, 62817.37182628161, 62865.68567157181, 63077.36922630761, 63645.72708545849, 10017.248706346907, 10081.344712593658, 10200.0819926207, 10258.0, 10557.683269501927, 13575.253361065039, 13607.183568985893, 13687.931560342135, 13688.931555342358, 13715.314234288246, 7884.448088633741, 7904.644291006918, 7907.0906845712325, 7965.960170199112, 7977.282044616012, 7671.808204794925, 7700.345470634947, 7726.690932362642, 7728.690852365842, 7762.262585054411, 4461.977690111594, 4467.24056910326, 4487.910241795146, 4488.977555112268, 4491.3487544306545, 1726.5252055684537, 1774.6450709857822, 1782.3405340024274, 1821.2259789589637, 1833.0, 939.1922946266205, 1004.7287232447344, 1036.2280101324561, 1088.700607332974, 1101.542859713226, 14847.925760371345, 16780.916095419445, 17244.568885777706, 17463.825361746472, 17512.649747004987, 953.4755884263766, 963.1957315641378, 982.5038355630504, 1009.9091081802673, 1043.3948309980194, 9363.344565880323, 9459.195968342769, 9548.42709437436, 9568.378055426376, 9709.951450242845, 478418.0, 479865.0, 601875.0, 615626.0, 617167.0, 76020.099497513, 81232.0, 87830.0, 89361.55319223492, 89941.55029224812, 1164.5225457562358, 1197.8081808600498, 1206.4631239098692, 1208.9032877369789, 1216.853977522703, 15746.76379854326, 16040.839591603935, 16084.0, 16225.0, 16370.263338149574, 35806.0, 35992.820035899655, 36303.23917287466, 36323.63676363203, 36976.81511592425, 47594.0, 50134.749326253135, 50400.24399634082, 51128.23307650463, 51322.23016654828, 9264.629414823332, 9480.288978326791, 9500.144986951207, 9930.503474826375, 9960.55177517028, 11574.18980671345, 11686.883131168579, 12389.814152787536, 13178.802317965046, 17915.552111197325, 14909.478168264059, 15016.699666006838, 15023.323950422477, 15158.014729042578, 15402.15288159162, 14392.352344144749, 14408.0, 14425.278736062945, 14968.550943471713, 15006.474773382879, 14524.927375363055, 14561.27193640335, 14687.55937321882, 14932.328045237773, 14980.700385992, 9512.239020878174, 9588.952055239679, 9981.550830212804, 10006.0, 10095.798084038424, 10653.786924261627, 10766.192535559707, 12767.808482872579, 15474.613134671274, 15880.285387157375], "unit": "ms", "name": "jquery.html", "value": 9028.06310991922}, {"lowerIsBetter": false, "replicates": [1458.6207586027629, 1530.3113598880502, 1588.5234429671095, 1698.7282034874415, 1750.789905211374, 6975.1978522469935, 7402.6668799904, 7576.356009739165, 7594.050743657043, 7594.392448604105, 7599.962000189991, 7803.531788092719, 7810.9609451952665, 7820.296173344398, 7829.138794732575, 2195.9451013724624, 2231.364061242546, 2232.4195709115666, 2238.6642003699494, 2239.8656080635133, 2215.7008803811523, 2243.0, 2279.168103642167, 2284.6344584866424, 2284.8743319117443, 2182.7708090650412, 2208.5251670890757, 2240.339099965514, 2240.5518896220738, 2248.05581655705, 2188.1466228171025, 2213.103693004336, 2237.7762223777654, 2242.9439264018365, 2243.2597242909883, 2224.8665080095084, 2239.7984181423585, 2251.0095557954446, 2251.79733823955, 2256.8533045352, 7631.961840190819, 7721.729739459092, 7755.1469338372735, 7763.883541746878, 7779.922200777976, 4439.977800111011, 4526.0495295987885, 4528.298113792367, 4530.637548996106, 4533.206688829475, 2924.2250803536867, 2982.6271716035494, 2994.326276587772, 2999.2501874531367, 3031.8787248510034, 974.3228456222928, 990.3612170150285, 1002.7092143278513, 1006.5822683586302, 1026.1790567545934, 10032.097111260022, 10121.69634910954, 10126.54430550627, 10126.594936202542, 10165.339252948535, 3996.320625493673, 4001.2197621464115, 4026.778527181033, 4055.513338399411, 4061.756294622332, 8653.524056176971, 8656.61045252965, 8663.133686631287, 8670.479771213748, 8675.089115642766, 4542.477615074243, 4557.45310562728, 4557.680962332671, 4558.0655965526985, 4574.542545745466, 3854.69162467002, 3888.105735680753, 4182.435371224907, 4208.537060923296, 4210.894727631834, 3587.5336206293537, 3611.259691763182, 3617.4212126059706, 3625.329314076904, 3628.691561217326, 1106.9612563560236, 1113.5434471866495, 1116.542217690743, 1123.8932301431514, 1125.8198688209848, 1422.50923431416, 1436.913785172893, 1447.4282658349894, 1447.5150824473708, 1469.2653673163418, 7479.887801682979, 7522.473426860067, 7532.811679708051, 7539.0576177977755, 7542.886856697154, 455.6992385025867, 460.1372426699938, 473.29951671525646, 473.5122823491809, 474.3525088254508, 5920.082387229986, 5959.255093113361, 5970.253718285215, 5980.581359304807, 5992.0712289595185, 8703.173198546128, 9100.635974561075, 9188.90811091894, 9205.907940920639, 9258.675946341846, 9098.681546145854, 9113.453192808453, 9133.817323653622, 9141.862872056925, 9152.725418237464, 6933.0, 7105.32499412555, 7132.358087772125, 7137.678804453812, 7215.422766178693, 7310.890336645061, 7374.741884034033, 7399.371053460516, 7414.555126692416, 7432.145303290191, 7600.619969001417, 7839.882401763864, 7977.8404431912195, 7981.840363192588, 7985.441019128838, 8431.8735218973, 8452.535110568977, 8502.617382217692, 8663.0, 8665.350098742494, 6621.900671489835, 6964.0, 7007.544509606859, 7067.187273463412, 7331.743388981574, 7507.024086868672, 7517.173110957681, 7520.473566850268, 7524.473286869867, 7547.0, 6909.619970901447, 7065.717371305091, 7073.964630176816, 7185.101862267216, 7218.386437152901, 7005.929940700732, 7017.614031228331, 7063.117110361205, 7082.043924070391, 7110.32451917067, 6858.211305699909, 6896.413804826646, 7114.750983715545, 7115.146182458138, 7121.465890058163, 6577.210734711865, 6645.169353830771, 6655.367740064686, 6660.600363978177, 6672.065910772399, 6548.639824809491, 6552.8361790955605, 6651.767188148391, 6699.028640846953, 6839.726410943506], "unit": "ms", "name": "mootools.html", "value": 4358.904916412983}, {"lowerIsBetter": false, "replicates": [349.54908168462686, 352.5945163062478, 359.8830380126459, 361.6058496239099, 363.29339434799294, 6034.577579569432, 6061.848453788658, 6087.604305720136, 6103.694815259241, 6104.969475152618, 6013.188219590354, 6022.0966854971775, 6036.0, 6040.0939859021055, 6044.546659000571, 4511.661625378093, 4645.9070818583605, 4745.6915300505525, 4746.335513028179, 4747.8813029674275, 4643.976780116095, 4644.094401591691, 4644.5819876211135, 4693.413323334583, 4712.646551508634, 4615.769211539427, 4617.422822147231, 4638.397008388922, 4644.628429725631, 4645.837395691152, 4433.357163211333, 4480.910381792346, 4485.349624304474, 4536.455625324949, 4621.4223222097235, 4028.1742242840146, 4029.7783621900785, 4049.817758200888, 4055.9797201014103, 4070.715049946505, 5733.226014488074, 5790.218320526717, 5793.391693872167, 5796.304443466769, 5805.4194580542035, 5079.619028572872, 5220.2430647556275, 5222.112240919015, 5225.399079105913, 5389.892202155974, 6365.8408539786415, 6366.172397588331, 6377.426031657174, 6389.5527313088105, 6401.455876250524, 4491.191585514606, 4491.461024677027, 4491.752953587551, 4492.505824359318, 4492.9550704493195, 6568.343165683394, 6640.5683630564345, 6706.0, 6742.764003259862, 6751.392374686302, 4593.839215627437, 4626.537346265347, 4632.143053535106, 4633.582977532038, 4639.164950308977, 4686.695364801277, 4687.99208170245, 4694.201985662446, 4694.788734506956, 4695.014047050089, 5336.226247194213, 5406.0, 5410.702411367412, 5410.729463526808, 5418.133098704188, 6380.776672816522, 6499.350064993463, 6509.674516274167, 6514.3811337922825, 6516.315786842408, 1627.7070127376949, 1652.3225477554145, 1654.8250741973093, 1655.470249520142, 1656.0477725307996, 1093.0, 1110.4558766204664, 1111.527600769686, 1118.8713297970676, 1132.1961407400672, 6322.841928951721, 6359.9364006360265, 6365.427111559982, 6368.745250189941, 6379.170707807955, 6754.223264324567, 6755.088063111515, 6757.526973111933, 6759.9522074078595, 6761.898571521431, 6207.906881396782, 6352.110704501373, 6360.14138091361, 6363.7772677956045, 6365.204349456318, 3619.2942376236906, 3656.3418584654487, 3657.65252301031, 3661.707063434919, 3663.0, 6906.758263460855, 6986.231514533397, 6997.860042799217, 7009.0, 7010.789676309617, 6409.359064093646, 6649.833754156088, 6833.760818371433, 6914.723411063501, 6925.965370173218, 6799.762008329783, 6816.659167041628, 6972.012976301831, 6994.61529615876, 7009.404200643003, 6762.4928130389435, 6777.661116944231, 6779.6271205084195, 6793.961811456642, 6800.761973330909, 6541.771038013647, 6677.432418244503, 6679.666016699049, 6689.531732778659, 6689.8327541809895, 6985.057017302803, 6992.685329160098, 6992.720291188295, 6995.335443132894, 7008.194057683431, 4740.928886066781, 4779.832705855418, 4782.402199725035, 4786.401699787527, 4787.760611969318, 6606.894236370928, 6616.338366163345, 6694.631795251308, 6709.36261055199, 6710.597364158166, 6477.1579694639395, 6495.1556297681, 6510.44661203803, 6518.185226846645, 6528.67356632176, 6467.805965821033, 6495.090687303876, 6506.934930650822, 6520.282768895323, 6527.445167160845, 6323.557350985387, 6386.297507274103, 6403.167588213502, 6412.871742565029, 6496.123023391781, 6637.668116594247, 6639.269680335063, 6645.933540664725, 6660.866782664417, 6669.0663307136065, 6479.708413121515, 6499.805005849832, 6500.382463665944, 6506.3493650634555, 6507.642079685474], "unit": "ms", "name": "prototype.html", "value": 4908.200542357319}, {"lowerIsBetter": false, "replicates": [897.8294124116419, 900.3933661467909, 931.8695382646431, 935.3499317974002, 945.7162851144659, 14707.779383309246, 15294.235288235599, 15333.616659583502, 15334.46329378472, 15378.384864605403, 16293.674126517462, 16324.510264692048, 16325.265363058661, 16358.591035224126, 16412.917935410336, 755.452297084612, 767.3515879082195, 891.1088911088912, 892.7946572288378, 921.2215677752289, 924.5746956400064, 928.4429342394543, 931.2829121576382, 931.3899395895666, 935.0275713258237, 792.3383974381399, 961.2358175250657, 970.335320305593, 970.7961328121103, 972.2173650211577, 799.6561478564215, 936.8173206224754, 948.127722495306, 949.5679465843025, 950.5912457643211, 703.4056222491986, 704.0460176460924, 704.3484776581693, 705.7459314646723, 708.1749761527814, 14978.62553436162, 14988.400463981428, 15045.473408430762, 15095.0, 15103.244837758179, 12213.389330533526, 12476.376181190904, 12522.0, 12525.044593115876, 12673.493060277578, 11035.72410689731, 11048.889511104866, 11120.777584448266, 11476.311421314747, 11575.884241157564, 3913.1782325711633, 3943.9014024649327, 3990.1421194443333, 4001.8399264029404, 4004.2391945530258, 50240.497595023946, 50485.495145048444, 51136.74431627818, 51427.0, 51479.74260128675, 10292.536835842406, 10952.890471095347, 10976.451177441097, 11187.105031597453, 11285.661430157108, 19525.90237048834, 19655.0, 19725.704114438297, 19751.604967900563, 19789.505262368268, 7598.240175982468, 7605.809854753676, 7698.2686644768655, 7741.567809955176, 7905.5651939142745, 4570.405847239904, 4628.0, 4633.86098417048, 4642.628589712815, 4735.479097299294, 3011.0966709987356, 3013.0, 3020.758339332848, 3044.0715581747336, 3050.34417600217, 506.364512536764, 522.7334059629617, 524.7245196271988, 528.7092099345344, 531.0255680825651, 701.0784294254306, 711.9038929744522, 729.2525161709291, 754.4341743692231, 776.6039319946755, 8886.555672216493, 9226.53867306632, 9610.807783844284, 9667.129958303787, 9697.078777516266, 634.4924060751381, 732.8827387618062, 750.3546949623319, 771.0939645916026, 778.7702627724835, 4415.448068991376, 4433.866983990485, 4466.486354069324, 4506.684532082722, 4608.516105808909, 58905.41094589085, 59008.0, 59602.10596840964, 59725.0, 59962.400375996556, 50312.0, 55866.44133558694, 56170.43829561652, 56428.717856410454, 56448.0, 49326.26010609844, 50650.24024639634, 50927.7082531287, 51083.0, 51256.743716281926, 8947.642094316154, 9005.324600655109, 9106.908930910871, 9205.355625106178, 9398.107179817905, 37376.62623373731, 37665.43501847529, 38198.045048874, 38441.615583843806, 38637.22725545417, 44977.0, 47742.5225747752, 48103.75948120237, 48620.27069594029, 48852.2672159925, 11161.386123763272, 11612.593559225725, 11857.229280096766, 11932.22440541362, 11974.101942354533, 11522.53909843597, 11716.94141529287, 11730.296182229093, 11737.35444550558, 11931.164818462972, 10598.15214782831, 11034.668959931216, 11065.114790816877, 11229.831552526883, 11512.59705910289, 11254.83117753218, 11458.885411146115, 11686.70783230426, 11965.401729913296, 12031.819522706992, 12372.81440778407, 12600.810987835006, 12603.18079324841, 12934.805977910151, 13030.41363138642, 10392.22058345612, 10416.041904076705, 10509.474526273809, 10978.780424391627, 11141.83287250708, 10422.791544168922, 10684.73288167771, 10776.191785616267, 10837.67486975392, 10944.671659850217], "unit": "ms", "name": "yui.html", "value": 6798.708394817338}], "name": "dromaeo_css", "value": 7191.42048277409}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 110.0, 103.0, 112.0, 110.0, 111.0, 100.0, 112.0, 111.0, 113.0], "unit": "ms", "name": "ai-astar", "value": 109.3}, {"lowerIsBetter": true, "replicates": [150.0, 123.0, 147.0, 157.0, 128.0, 145.0, 148.0, 123.0, 156.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.0}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 160.0, 163.0, 159.0, 160.0, 163.0, 162.0, 160.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 161.0}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 73.0, 157.0, 73.0, 73.0, 71.0, 71.0, 159.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 89.5}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 139.0, 92.0, 93.0, 93.0, 140.0, 92.0, 93.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 106.2}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 110.0, 109.0, 110.0, 112.0, 110.0, 111.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 147.0, 146.0, 145.0, 146.0, 146.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.9}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.0}, {"lowerIsBetter": true, "replicates": [55.0, 57.0, 58.0, 57.0, 59.0, 59.0, 57.0, 58.0, 56.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 57.2}, {"lowerIsBetter": true, "replicates": [65.0, 64.0, 65.0, 64.0, 65.0, 66.0, 65.0, 63.0, 64.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.6}, {"lowerIsBetter": true, "replicates": [81.0, 79.0, 89.0, 83.0, 81.0, 83.0, 84.0, 82.0, 82.0, 83.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 82.7}, {"lowerIsBetter": true, "replicates": [117.0, 119.0, 119.0, 112.0, 112.0, 115.0, 121.0, 112.0, 119.0, 121.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 116.7}, {"lowerIsBetter": true, "replicates": [135.0, 146.0, 155.0, 149.0, 146.0, 139.0, 146.0, 144.0, 138.0, 135.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 143.3}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 66.0, 69.0, 67.0, 68.0, 67.0, 67.0, 68.0, 67.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.4}], "name": "kraken", "value": 1488.8000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.78249336870027], "unit": "score", "name": "Richards", "value": 212.94166399999992}, {"lowerIsBetter": false, "replicates": [167.81339150864238], "unit": "score", "name": "DeltaBlue", "value": 393.9971619999999}, {"lowerIsBetter": false, "replicates": [279.4857462269424], "unit": "score", "name": "Encrypt", "value": 952.3956179999999}, {"lowerIsBetter": false, "replicates": [5208.333333333333], "unit": "score", "name": "Decrypt", "value": 51.10675199999996}, {"lowerIsBetter": false, "replicates": [961.5384615384615], "unit": "score", "name": "RayTrace", "value": 769.5885599999998}, {"lowerIsBetter": false, "replicates": [557.4136008918617], "unit": "score", "name": "Earley", "value": 1195.6346220000003}, {"lowerIsBetter": false, "replicates": [11142.857142857143], "unit": "score", "name": "Boyer", "value": 59.81078205128202}, {"lowerIsBetter": false, "replicates": [24119.04761904762], "unit": "score", "name": "RegExp", "value": 37.77035538005923}, {"lowerIsBetter": false, "replicates": [526.6179540709812], "unit": "score", "name": "Splay", "value": 154.7440594648166}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 23024.28590351468}]} 06:13:20 INFO - RETURN: dromaeo_css: 7191.42 06:13:20 INFO - RETURN: kraken: 1488.80 06:13:20 INFO - RETURN: v8_7: 23024.29 06:13:20 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,35]]", "result": "23024.29"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,35]]", "result": "1488.80"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,35]]", "result": "7191.42"}}} 06:13:20 INFO - Return code: 0 06:13:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 06:13:20 INFO - # TBPL SUCCESS # 06:13:20 INFO - Running post-action listener: _resource_record_post_action 06:13:20 INFO - Running post-run listener: _resource_record_post_run 06:13:21 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 0; Write bytes: 501321728; Read time: 0; Write time: 468740 06:13:21 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 237199360; Read time: 0; Write time: 306068 06:13:21 INFO - run-tests - Wall time: 1211s; CPU: 13.0%; Read bytes: 0; Write bytes: 263839744; Read time: 0; Write time: 162652 06:13:21 INFO - Running post-run listener: _upload_blobber_files 06:13:21 INFO - Blob upload gear active. 06:13:21 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 06:13:21 INFO - Copying logs to upload dir... 06:13:21 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1266.877923 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-01-22 06:13:20.392527) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-22 06:13:20.395675) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010034 build_url: 'https://queue.taskcluster.net/v1/task/2p0v77w4SLK4Lf8Vflb2rg/artifacts/public/build/firefox-45.0a2.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-01-22 06:13:20.447344) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 06:13:20.447670) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1453470515.862637-1350476001 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004660 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-22 06:13:20.497084) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-22 06:13:20.497414) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-22 06:13:20.497729) ========= ========= Total master_lag: 0.29 =========