builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-057 starttime: 1448371717.74 results: success (0) buildid: 20151124033743 builduid: 48f996bb24324f6480d5df528c608230 revision: cf8cf1a039dd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:37.739046) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:37.739481) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:37.739771) ========= 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=6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006496 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:37.782721) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:37.783055) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:37.849342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:37.849612) ========= 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=6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004582 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:37.899799) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:37.900125) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:37.900480) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:37.900779) ========= 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=6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-24 05:28:38-- 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% 42.6M=0s 2015-11-24 05:28:38 (42.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.182434 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:38.095657) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:38.096011) ========= 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=6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013875 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:38.145867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:38.146232) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev cf8cf1a039dd --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev cf8cf1a039dd --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=6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-24 05:28:38,649 Setting DEBUG logging. 2015-11-24 05:28:38,649 attempt 1/10 2015-11-24 05:28:38,649 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/cf8cf1a039dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-24 05:28:38,891 unpacking tar archive at: mozilla-inbound-cf8cf1a039dd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.546984 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:38.706076) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:38.706456) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:38.716150) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:38.716573) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-24 05:28:38.717094) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2015-11-24 05:28:38.717401) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-Inbound', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:28:39 INFO - MultiFileLogger online at 20151124 05:28:39 in /builds/slave/test-pgo 05:28:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 05:28:39 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:28:39 INFO - {'append_to_log': False, 05:28:39 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:28:39 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 05:28:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:28:39 INFO - 'branch': 'Mozilla-Inbound', 05:28:39 INFO - 'buildbot_json_path': 'buildprops.json', 05:28:39 INFO - 'config_files': ('talos/linux_config.py',), 05:28:39 INFO - 'default_actions': ('clobber', 05:28:39 INFO - 'read-buildbot-config', 05:28:39 INFO - 'download-and-extract', 05:28:39 INFO - 'populate-webroot', 05:28:39 INFO - 'create-virtualenv', 05:28:39 INFO - 'install', 05:28:39 INFO - 'run-tests'), 05:28:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:28:39 INFO - 'download_minidump_stackwalk': True, 05:28:39 INFO - 'download_symbols': 'ondemand', 05:28:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:28:39 INFO - 'tooltool.py': '/tools/tooltool.py', 05:28:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:28:39 INFO - '/tools/misc-python/virtualenv.py')}, 05:28:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:28:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:28:39 INFO - 'installer_path': 'installer.exe', 05:28:39 INFO - 'log_level': 'info', 05:28:39 INFO - 'log_name': 'talos', 05:28:39 INFO - 'log_to_console': True, 05:28:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:28:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:28:39 INFO - 'opt_config_files': (), 05:28:39 INFO - 'pip_index': False, 05:28:39 INFO - 'sps_profile': False, 05:28:39 INFO - 'sps_profile_interval': 0, 05:28:39 INFO - 'suite': 'dromaeojs', 05:28:39 INFO - 'system_bits': '64', 05:28:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:28:39 INFO - 'title': 'talos-linux64-ix-057', 05:28:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:28:39 INFO - 'use_talos_json': True, 05:28:39 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:28:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:28:39 INFO - 'work_dir': 'build'} 05:28:39 INFO - ##### 05:28:39 INFO - ##### Running clobber step. 05:28:39 INFO - ##### 05:28:39 INFO - Running pre-action listener: _resource_record_pre_action 05:28:39 INFO - Running main action method: clobber 05:28:39 INFO - rmtree: /builds/slave/test-pgo/build 05:28:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:28:39 INFO - Running post-action listener: _resource_record_post_action 05:28:39 INFO - ##### 05:28:39 INFO - ##### Running read-buildbot-config step. 05:28:39 INFO - ##### 05:28:39 INFO - Running pre-action listener: _resource_record_pre_action 05:28:39 INFO - Running main action method: read_buildbot_config 05:28:39 INFO - Using buildbot properties: 05:28:39 INFO - { 05:28:39 INFO - "properties": { 05:28:39 INFO - "buildnumber": 1296, 05:28:39 INFO - "product": "firefox", 05:28:39 INFO - "script_repo_revision": "production", 05:28:39 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 05:28:39 INFO - "repository": "", 05:28:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 05:28:39 INFO - "buildid": "20151124033743", 05:28:39 INFO - "slavename": "talos-linux64-ix-057", 05:28:39 INFO - "pgo_build": "True", 05:28:39 INFO - "basedir": "/builds/slave/test-pgo", 05:28:39 INFO - "project": "", 05:28:39 INFO - "platform": "ubuntu64_hw", 05:28:39 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:28:39 INFO - "slavebuilddir": "test", 05:28:39 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 05:28:39 INFO - "branch": "mozilla-inbound", 05:28:39 INFO - "repo_path": "integration/mozilla-inbound", 05:28:39 INFO - "stage_platform": "linux64-pgo", 05:28:39 INFO - "builduid": "48f996bb24324f6480d5df528c608230", 05:28:39 INFO - "revision": "cf8cf1a039dd" 05:28:39 INFO - }, 05:28:39 INFO - "sourcestamp": { 05:28:39 INFO - "repository": "", 05:28:39 INFO - "hasPatch": false, 05:28:39 INFO - "project": "", 05:28:39 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 05:28:39 INFO - "changes": [ 05:28:39 INFO - { 05:28:39 INFO - "category": null, 05:28:39 INFO - "files": [ 05:28:39 INFO - { 05:28:39 INFO - "url": null, 05:28:39 INFO - "name": "https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 05:28:39 INFO - } 05:28:39 INFO - ], 05:28:39 INFO - "repository": "", 05:28:39 INFO - "rev": "cf8cf1a039dd", 05:28:39 INFO - "who": "sendchange", 05:28:39 INFO - "when": 1448371690, 05:28:39 INFO - "number": 6700110, 05:28:39 INFO - "comments": "", 05:28:39 INFO - "project": "", 05:28:39 INFO - "at": "Tue 24 Nov 2015 05:28:10", 05:28:39 INFO - "branch": "mozilla-inbound-linux64-pgo-talos", 05:28:39 INFO - "revlink": "", 05:28:39 INFO - "properties": [ 05:28:39 INFO - [ 05:28:39 INFO - "buildid", 05:28:39 INFO - "20151124033743", 05:28:39 INFO - "Change" 05:28:39 INFO - ], 05:28:39 INFO - [ 05:28:39 INFO - "builduid", 05:28:39 INFO - "48f996bb24324f6480d5df528c608230", 05:28:39 INFO - "Change" 05:28:39 INFO - ], 05:28:39 INFO - [ 05:28:39 INFO - "pgo_build", 05:28:39 INFO - "True", 05:28:39 INFO - "Change" 05:28:39 INFO - ] 05:28:39 INFO - ], 05:28:39 INFO - "revision": "cf8cf1a039dd" 05:28:39 INFO - } 05:28:39 INFO - ], 05:28:39 INFO - "revision": "cf8cf1a039dd" 05:28:39 INFO - } 05:28:39 INFO - } 05:28:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 05:28:39 INFO - Running post-action listener: _resource_record_post_action 05:28:39 INFO - ##### 05:28:39 INFO - ##### Running download-and-extract step. 05:28:39 INFO - ##### 05:28:39 INFO - Running pre-action listener: _resource_record_pre_action 05:28:39 INFO - Running main action method: download_and_extract 05:28:39 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:28:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:39 INFO - https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 05:28:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/test_packages.json 05:28:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/test_packages.json 05:28:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:28:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:28:47 INFO - Downloaded 1302 bytes. 05:28:47 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:28:47 INFO - Using the following test package requirements: 05:28:47 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:47 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:28:47 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:47 INFO - u'jsshell-linux-x86_64.zip'], 05:28:47 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:28:47 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:47 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:28:47 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:28:47 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:28:47 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 05:28:47 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 05:28:47 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:28:47 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:28:47 INFO - https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:28:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:28:54 INFO - Downloaded 22529620 bytes. 05:28:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:28:54 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:54 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:28:55 INFO - Return code: 0 05:28:55 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:28:55 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:28:55 INFO - https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:28:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:28:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:29:00 INFO - Downloaded 22529620 bytes. 05:29:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:29:00 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 05:29:00 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:29:01 INFO - Return code: 0 05:29:01 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:29:01 INFO - https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:29:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:29:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:29:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:29:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:29:02 INFO - Downloaded 11131717 bytes. 05:29:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:29:02 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 05:29:02 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:29:02 INFO - Return code: 0 05:29:02 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:29:02 INFO - https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:29:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:29:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:29:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:29:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:29:04 INFO - Downloaded 58361210 bytes. 05:29:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:29:04 INFO - mkdir: /builds/slave/test-pgo/properties 05:29:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:29:04 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:29:04 INFO - Contents: 05:29:04 INFO - build_url:https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 05:29:04 INFO - Running post-action listener: _resource_record_post_action 05:29:04 INFO - Running post-action listener: set_extra_try_arguments 05:29:04 INFO - ##### 05:29:04 INFO - ##### Running populate-webroot step. 05:29:04 INFO - ##### 05:29:04 INFO - Running pre-action listener: _resource_record_pre_action 05:29:04 INFO - Running main action method: populate_webroot 05:29:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:29:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:29:04 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 05:29:04 INFO - '../fennec_ids.txt'], 05:29:04 INFO - 'tests': ['tcheck2']}, 05:29:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:29:04 INFO - '--tppagecycles', 05:29:04 INFO - '7'], 05:29:04 INFO - 'tests': ['tsvgm']}}, 05:29:04 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:29:04 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:29:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:29:04 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:29:04 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:29:04 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:29:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:04 INFO - 'talos_options': ['--e10s'], 05:29:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:29:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:04 INFO - 'tests': ['damp', 'tps']}, 05:29:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:04 INFO - 'talos_options': ['--e10s'], 05:29:04 INFO - 'tests': ['damp', 'tps']}, 05:29:04 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:29:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:29:04 INFO - 'other': {'tests': ['a11yr', 05:29:04 INFO - 'ts_paint', 05:29:04 INFO - 'tpaint', 05:29:04 INFO - 'sessionrestore', 05:29:04 INFO - 'sessionrestore_no_auto_restore']}, 05:29:04 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:29:04 INFO - 'tests': ['a11yr', 05:29:04 INFO - 'ts_paint', 05:29:04 INFO - 'tpaint', 05:29:04 INFO - 'sessionrestore', 05:29:04 INFO - 'sessionrestore_no_auto_restore']}, 05:29:04 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:29:04 INFO - 'tests': ['a11yr', 05:29:04 INFO - 'ts_paint', 05:29:04 INFO - 'tpaint', 05:29:04 INFO - 'sessionrestore', 05:29:04 INFO - 'sessionrestore_no_auto_restore']}, 05:29:04 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:29:04 INFO - 'tests': ['a11yr', 05:29:04 INFO - 'ts_paint', 05:29:04 INFO - 'tpaint', 05:29:04 INFO - 'sessionrestore', 05:29:04 INFO - 'sessionrestore_no_auto_restore']}, 05:29:04 INFO - 'other_l64': {'tests': ['a11yr', 05:29:04 INFO - 'ts_paint', 05:29:04 INFO - 'tpaint', 05:29:04 INFO - 'sessionrestore', 05:29:04 INFO - 'sessionrestore_no_auto_restore']}, 05:29:04 INFO - 'other_nol64': {'tests': ['a11yr', 05:29:04 INFO - 'ts_paint', 05:29:04 INFO - 'tpaint', 05:29:04 INFO - 'sessionrestore', 05:29:04 INFO - 'sessionrestore_no_auto_restore']}, 05:29:04 INFO - 'svgr': {'tests': ['tsvgx', 05:29:04 INFO - 'tsvgr_opacity', 05:29:04 INFO - 'tart', 05:29:04 INFO - 'tscrollx', 05:29:04 INFO - 'cart']}, 05:29:04 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:29:04 INFO - 'tests': ['tsvgx', 05:29:04 INFO - 'tsvgr_opacity', 05:29:04 INFO - 'tart', 05:29:04 INFO - 'tscrollx', 05:29:04 INFO - 'cart']}, 05:29:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:04 INFO - 'tests': ['tp5o']}, 05:29:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:04 INFO - 'talos_options': ['--e10s'], 05:29:04 INFO - 'tests': ['tp5o']}, 05:29:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:04 INFO - 'talos_options': ['--xperf_path', 05:29:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:04 INFO - 'tests': ['tp5n']}, 05:29:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:29:04 INFO - 'talos_options': ['--e10s', 05:29:04 INFO - '--xperf_path', 05:29:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:29:04 INFO - 'tests': ['tp5n']}}, 05:29:04 INFO - 'talos.zip': {'path': '', 05:29:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:29:04 INFO - Running post-action listener: _resource_record_post_action 05:29:04 INFO - ##### 05:29:04 INFO - ##### Running create-virtualenv step. 05:29:04 INFO - ##### 05:29:04 INFO - Running pre-action listener: _resource_record_pre_action 05:29:04 INFO - Running main action method: create_virtualenv 05:29:04 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:29:04 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:29:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:29:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:29:04 INFO - Using real prefix '/usr' 05:29:04 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:29:05 INFO - Installing distribute.............................................................................................................................................................................................done. 05:29:07 INFO - Installing pip.................done. 05:29:07 INFO - Return code: 0 05:29:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x1dfd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x202ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b9e50>, '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 0x20bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bb1e0>, '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': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', '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:29:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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:29:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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:29:07 INFO - Using env: {'DISPLAY': ':0', 05:29:07 INFO - 'HOME': '/home/cltbld', 05:29:07 INFO - 'LANG': 'en_US.UTF-8', 05:29:07 INFO - 'LANGUAGE': 'en_US:en', 05:29:07 INFO - 'LOGNAME': 'cltbld', 05:29:07 INFO - 'MAIL': '/var/mail/cltbld', 05:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:07 INFO - 'MOZ_NO_REMOTE': '1', 05:29:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:07 INFO - 'NO_EM_RESTART': '1', 05:29:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:07 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:07 INFO - 'SHELL': '/bin/bash', 05:29:07 INFO - 'SHLVL': '1', 05:29:07 INFO - 'TERM': 'linux', 05:29:07 INFO - 'TMOUT': '86400', 05:29:07 INFO - 'USER': 'cltbld', 05:29:07 INFO - 'XDG_SESSION_COOKIE': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', 05:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:07 INFO - '_': '/tools/buildbot/bin/python'} 05:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:07 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:29:07 INFO - Cleaning up... 05:29:07 INFO - Return code: 0 05:29:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x1dfd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x202ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b9e50>, '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 0x20bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bb1e0>, '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': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', '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:29:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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:29:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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:29:07 INFO - Using env: {'DISPLAY': ':0', 05:29:07 INFO - 'HOME': '/home/cltbld', 05:29:07 INFO - 'LANG': 'en_US.UTF-8', 05:29:07 INFO - 'LANGUAGE': 'en_US:en', 05:29:07 INFO - 'LOGNAME': 'cltbld', 05:29:07 INFO - 'MAIL': '/var/mail/cltbld', 05:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:07 INFO - 'MOZ_NO_REMOTE': '1', 05:29:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:07 INFO - 'NO_EM_RESTART': '1', 05:29:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:07 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:07 INFO - 'SHELL': '/bin/bash', 05:29:07 INFO - 'SHLVL': '1', 05:29:07 INFO - 'TERM': 'linux', 05:29:07 INFO - 'TMOUT': '86400', 05:29:07 INFO - 'USER': 'cltbld', 05:29:07 INFO - 'XDG_SESSION_COOKIE': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', 05:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:07 INFO - '_': '/tools/buildbot/bin/python'} 05:29:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:07 INFO - Downloading/unpacking psutil>=0.7.1 05:29:07 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:29:07 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:29:07 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:29:07 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:29:07 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:29:07 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:29:09 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 05:29:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 05:29:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:29:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:09 INFO - Installing collected packages: psutil 05:29:09 INFO - Running setup.py install for psutil 05:29:09 INFO - building 'psutil._psutil_linux' extension 05:29:09 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:29:09 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:29:09 INFO - building 'psutil._psutil_posix' extension 05:29:09 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:29:09 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:29:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:29:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:29:10 INFO - Successfully installed psutil 05:29:10 INFO - Cleaning up... 05:29:10 INFO - Return code: 0 05:29:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x1dfd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x202ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b9e50>, '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 0x20bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bb1e0>, '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': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', '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:29:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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:29:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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:29:10 INFO - Using env: {'DISPLAY': ':0', 05:29:10 INFO - 'HOME': '/home/cltbld', 05:29:10 INFO - 'LANG': 'en_US.UTF-8', 05:29:10 INFO - 'LANGUAGE': 'en_US:en', 05:29:10 INFO - 'LOGNAME': 'cltbld', 05:29:10 INFO - 'MAIL': '/var/mail/cltbld', 05:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:10 INFO - 'MOZ_NO_REMOTE': '1', 05:29:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:10 INFO - 'NO_EM_RESTART': '1', 05:29:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:10 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:10 INFO - 'SHELL': '/bin/bash', 05:29:10 INFO - 'SHLVL': '1', 05:29:10 INFO - 'TERM': 'linux', 05:29:10 INFO - 'TMOUT': '86400', 05:29:10 INFO - 'USER': 'cltbld', 05:29:10 INFO - 'XDG_SESSION_COOKIE': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', 05:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:10 INFO - '_': '/tools/buildbot/bin/python'} 05:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:10 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:29:10 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:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:29:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 05:29:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:29:12 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:29:12 INFO - Installing collected packages: mozsystemmonitor 05:29:12 INFO - Running setup.py install for mozsystemmonitor 05:29:12 INFO - Successfully installed mozsystemmonitor 05:29:12 INFO - Cleaning up... 05:29:12 INFO - Return code: 0 05:29:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:12 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x1dfd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x202ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b9e50>, '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 0x20bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bb1e0>, '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': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', '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:29:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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:29:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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:29:12 INFO - Using env: {'DISPLAY': ':0', 05:29:12 INFO - 'HOME': '/home/cltbld', 05:29:12 INFO - 'LANG': 'en_US.UTF-8', 05:29:12 INFO - 'LANGUAGE': 'en_US:en', 05:29:12 INFO - 'LOGNAME': 'cltbld', 05:29:12 INFO - 'MAIL': '/var/mail/cltbld', 05:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:12 INFO - 'MOZ_NO_REMOTE': '1', 05:29:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:12 INFO - 'NO_EM_RESTART': '1', 05:29:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:12 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:12 INFO - 'SHELL': '/bin/bash', 05:29:12 INFO - 'SHLVL': '1', 05:29:12 INFO - 'TERM': 'linux', 05:29:12 INFO - 'TMOUT': '86400', 05:29:12 INFO - 'USER': 'cltbld', 05:29:12 INFO - 'XDG_SESSION_COOKIE': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', 05:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:12 INFO - '_': '/tools/buildbot/bin/python'} 05:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:12 INFO - Downloading/unpacking blobuploader==1.2.4 05:29:12 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:29:12 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:29:12 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:29:12 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:29:12 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:29:12 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:29:14 INFO - Downloading blobuploader-1.2.4.tar.gz 05:29:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 05:29:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:29:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:29:14 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:29:14 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:29:14 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:29:14 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:29:14 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:29:14 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:29:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 05:29:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:29:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:29:14 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:29:14 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:29:14 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:29:14 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:29:14 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:29:14 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:29:14 INFO - Downloading docopt-0.6.1.tar.gz 05:29:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 05:29:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:29:14 INFO - Installing collected packages: blobuploader, requests, docopt 05:29:14 INFO - Running setup.py install for blobuploader 05:29:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:29:14 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:29:14 INFO - Running setup.py install for requests 05:29:15 INFO - Running setup.py install for docopt 05:29:15 INFO - Successfully installed blobuploader requests docopt 05:29:15 INFO - Cleaning up... 05:29:15 INFO - Return code: 0 05:29:15 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:29: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:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29: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:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:15 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x1dfd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x202ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b9e50>, '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 0x20bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bb1e0>, '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': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', '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:29:15 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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:29:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --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:29:15 INFO - Using env: {'DISPLAY': ':0', 05:29:15 INFO - 'HOME': '/home/cltbld', 05:29:15 INFO - 'LANG': 'en_US.UTF-8', 05:29:15 INFO - 'LANGUAGE': 'en_US:en', 05:29:15 INFO - 'LOGNAME': 'cltbld', 05:29:15 INFO - 'MAIL': '/var/mail/cltbld', 05:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:15 INFO - 'MOZ_NO_REMOTE': '1', 05:29:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:15 INFO - 'NO_EM_RESTART': '1', 05:29:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:15 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:15 INFO - 'SHELL': '/bin/bash', 05:29:15 INFO - 'SHLVL': '1', 05:29:15 INFO - 'TERM': 'linux', 05:29:15 INFO - 'TMOUT': '86400', 05:29:15 INFO - 'USER': 'cltbld', 05:29:15 INFO - 'XDG_SESSION_COOKIE': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', 05:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:15 INFO - '_': '/tools/buildbot/bin/python'} 05:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:29:15 INFO - Running setup.py (path:/tmp/pip-kV0VlN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:29:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:29:15 INFO - Running setup.py (path:/tmp/pip-2KYJO_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:29:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:29:15 INFO - Running setup.py (path:/tmp/pip-JPkFtg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:29:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:29:15 INFO - Running setup.py (path:/tmp/pip-NPAYnk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:29:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:29:15 INFO - Running setup.py (path:/tmp/pip-Z6gUSW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:29:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:29:15 INFO - Running setup.py (path:/tmp/pip-Pdcnt4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:29:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:29:15 INFO - Running setup.py (path:/tmp/pip-7cCSNQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:29:16 INFO - Running setup.py (path:/tmp/pip-Av5OS3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:29:16 INFO - Running setup.py (path:/tmp/pip-yZE8eN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:29:16 INFO - Running setup.py (path:/tmp/pip-KjzWcN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:29:16 INFO - Running setup.py (path:/tmp/pip-a7eERp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:29:16 INFO - Running setup.py (path:/tmp/pip-i6yqXo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:29:16 INFO - Running setup.py (path:/tmp/pip-wwpFhd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:29:16 INFO - Running setup.py (path:/tmp/pip-AmAQyR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:29:16 INFO - Running setup.py (path:/tmp/pip-w3ELXs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:29:16 INFO - Running setup.py (path:/tmp/pip-q2A4K4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:29:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:16 INFO - Running setup.py (path:/tmp/pip-lPQTr6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:29:16 INFO - Running setup.py install for manifestparser 05:29:16 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:29:16 INFO - Running setup.py install for mozcrash 05:29:16 INFO - Running setup.py install for mozdebug 05:29:17 INFO - Running setup.py install for mozdevice 05:29:17 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:29:17 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:29:17 INFO - Running setup.py install for mozfile 05:29:17 INFO - Running setup.py install for mozhttpd 05:29:17 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:29:17 INFO - Running setup.py install for mozinfo 05:29:17 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:29:17 INFO - Running setup.py install for mozInstall 05:29:17 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:29:17 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:29:17 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:29:17 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:29:17 INFO - Running setup.py install for mozleak 05:29:17 INFO - Running setup.py install for mozlog 05:29:17 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:29:17 INFO - Running setup.py install for moznetwork 05:29:17 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:29:17 INFO - Running setup.py install for mozprocess 05:29:18 INFO - Running setup.py install for mozprofile 05:29:18 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:29:18 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:29:18 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:29:18 INFO - Running setup.py install for mozrunner 05:29:18 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:29:18 INFO - Running setup.py install for mozscreenshot 05:29:18 INFO - Running setup.py install for moztest 05:29:18 INFO - Running setup.py install for mozversion 05:29:18 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:29:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:29:18 INFO - Cleaning up... 05:29:18 INFO - Return code: 0 05:29:18 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:18 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x1dfd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x202ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b9e50>, '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 0x20bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bb1e0>, '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': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', '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:29:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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:29:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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:29:18 INFO - Using env: {'DISPLAY': ':0', 05:29:18 INFO - 'HOME': '/home/cltbld', 05:29:18 INFO - 'LANG': 'en_US.UTF-8', 05:29:18 INFO - 'LANGUAGE': 'en_US:en', 05:29:18 INFO - 'LOGNAME': 'cltbld', 05:29:18 INFO - 'MAIL': '/var/mail/cltbld', 05:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:18 INFO - 'MOZ_NO_REMOTE': '1', 05:29:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:18 INFO - 'NO_EM_RESTART': '1', 05:29:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:18 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:18 INFO - 'SHELL': '/bin/bash', 05:29:18 INFO - 'SHLVL': '1', 05:29:18 INFO - 'TERM': 'linux', 05:29:18 INFO - 'TMOUT': '86400', 05:29:18 INFO - 'USER': 'cltbld', 05:29:18 INFO - 'XDG_SESSION_COOKIE': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', 05:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:18 INFO - '_': '/tools/buildbot/bin/python'} 05:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:29:18 INFO - Running setup.py (path:/tmp/pip-yNaj8G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:29:18 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:29:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:29:18 INFO - Running setup.py (path:/tmp/pip-JQArr7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:29:18 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:29:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:29:18 INFO - Running setup.py (path:/tmp/pip-q6RDid-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:29:18 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:29:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:29:19 INFO - Running setup.py (path:/tmp/pip-efPrcE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:29:19 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:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:29:19 INFO - Running setup.py (path:/tmp/pip-aPlUTL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:29:19 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:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:29:19 INFO - Running setup.py (path:/tmp/pip-ee5qdF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:29:19 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:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:29:19 INFO - Running setup.py (path:/tmp/pip-uLErtW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:29:19 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:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:29:19 INFO - Running setup.py (path:/tmp/pip-mCjLsh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:29:19 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:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:29:19 INFO - Running setup.py (path:/tmp/pip-mdHSE_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:29:19 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:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:29:19 INFO - Running setup.py (path:/tmp/pip-FmrM2_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:29:19 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:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:29:19 INFO - Running setup.py (path:/tmp/pip-QNCzYo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:29:19 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:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:29:19 INFO - Running setup.py (path:/tmp/pip-VQPBBG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:29:19 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:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:29:19 INFO - Running setup.py (path:/tmp/pip-wIkXw8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:29:19 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:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:29:19 INFO - Running setup.py (path:/tmp/pip-AHSyM7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:29:19 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:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:29:19 INFO - Running setup.py (path:/tmp/pip-umpBfT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:29:19 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:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:29:19 INFO - Running setup.py (path:/tmp/pip-E7WCl4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:29:19 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:29:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:19 INFO - Running setup.py (path:/tmp/pip-Fxuypb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:29:20 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:29:20 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:29:20 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:29:20 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:29:20 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:29:20 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:29:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:20 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:29:20 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:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:21 INFO - Downloading blessings-1.5.1.tar.gz 05:29:21 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 05:29:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:29:21 INFO - Installing collected packages: blessings 05:29:21 INFO - Running setup.py install for blessings 05:29:21 INFO - Successfully installed blessings 05:29:21 INFO - Cleaning up... 05:29:21 INFO - Return code: 0 05:29:21 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:29:21 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:29:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:29:22 INFO - Reading from file tmpfile_stdout 05:29:22 INFO - Current package versions: 05:29:22 INFO - argparse == 1.2.1 05:29:22 INFO - blessings == 1.5.1 05:29:22 INFO - blobuploader == 1.2.4 05:29:22 INFO - docopt == 0.6.1 05:29:22 INFO - manifestparser == 1.1 05:29:22 INFO - mozInstall == 1.12 05:29:22 INFO - mozcrash == 0.16 05:29:22 INFO - mozdebug == 0.1 05:29:22 INFO - mozdevice == 0.47 05:29:22 INFO - mozfile == 1.2 05:29:22 INFO - mozhttpd == 0.7 05:29:22 INFO - mozinfo == 0.9 05:29:22 INFO - mozleak == 0.1 05:29:22 INFO - mozlog == 3.1 05:29:22 INFO - moznetwork == 0.27 05:29:22 INFO - mozprocess == 0.22 05:29:22 INFO - mozprofile == 0.27 05:29:22 INFO - mozrunner == 6.11 05:29:22 INFO - mozscreenshot == 0.1 05:29:22 INFO - mozsystemmonitor == 0.0 05:29:22 INFO - moztest == 0.7 05:29:22 INFO - mozversion == 1.4 05:29:22 INFO - psutil == 3.1.1 05:29:22 INFO - requests == 1.2.3 05:29:22 INFO - wsgiref == 0.1.2 05:29:22 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:29:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:22 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x1dfd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x202ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b9e50>, '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 0x20bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bb1e0>, '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': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', '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:29:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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:29:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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:29:22 INFO - Using env: {'DISPLAY': ':0', 05:29:22 INFO - 'HOME': '/home/cltbld', 05:29:22 INFO - 'LANG': 'en_US.UTF-8', 05:29:22 INFO - 'LANGUAGE': 'en_US:en', 05:29:22 INFO - 'LOGNAME': 'cltbld', 05:29:22 INFO - 'MAIL': '/var/mail/cltbld', 05:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:22 INFO - 'MOZ_NO_REMOTE': '1', 05:29:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:22 INFO - 'NO_EM_RESTART': '1', 05:29:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:22 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:22 INFO - 'SHELL': '/bin/bash', 05:29:22 INFO - 'SHLVL': '1', 05:29:22 INFO - 'TERM': 'linux', 05:29:22 INFO - 'TMOUT': '86400', 05:29:22 INFO - 'USER': 'cltbld', 05:29:22 INFO - 'XDG_SESSION_COOKIE': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', 05:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:22 INFO - '_': '/tools/buildbot/bin/python'} 05:29:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:22 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:29:22 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:29:22 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:29:22 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:29:22 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:29:22 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:29:22 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:29:22 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:29:22 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:29:22 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:29:22 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:29:22 INFO - Cleaning up... 05:29:22 INFO - Return code: 0 05:29:22 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:29:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:29:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:29:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:29:22 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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 0x1dfd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f5e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x202ac70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b9e50>, '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 0x20bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bb1e0>, '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': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', '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:29:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--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:29:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --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:29:22 INFO - Using env: {'DISPLAY': ':0', 05:29:22 INFO - 'HOME': '/home/cltbld', 05:29:22 INFO - 'LANG': 'en_US.UTF-8', 05:29:22 INFO - 'LANGUAGE': 'en_US:en', 05:29:22 INFO - 'LOGNAME': 'cltbld', 05:29:22 INFO - 'MAIL': '/var/mail/cltbld', 05:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:22 INFO - 'MOZ_NO_REMOTE': '1', 05:29:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:22 INFO - 'NO_EM_RESTART': '1', 05:29:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:22 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:22 INFO - 'SHELL': '/bin/bash', 05:29:22 INFO - 'SHLVL': '1', 05:29:22 INFO - 'TERM': 'linux', 05:29:22 INFO - 'TMOUT': '86400', 05:29:22 INFO - 'USER': 'cltbld', 05:29:22 INFO - 'XDG_SESSION_COOKIE': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', 05:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:22 INFO - '_': '/tools/buildbot/bin/python'} 05:29:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:29:22 INFO - Downloading/unpacking jsonschema 05:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:22 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:29:22 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:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:29:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:29:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:29:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 05:29:24 INFO - Downloading/unpacking functools32 (from jsonschema) 05:29:24 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:29:24 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:29:24 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:29:24 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:29:24 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:29:24 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:29:24 INFO - Downloading functools32-3.2.3-2.tar.gz 05:29:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 05:29:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:29:24 INFO - warning: no files found matching '*.txt' 05:29:24 INFO - no previously-included directories found matching 'build' 05:29:24 INFO - no previously-included directories found matching 'dist' 05:29:24 INFO - no previously-included directories found matching '.git*' 05:29:24 INFO - Installing collected packages: jsonschema, functools32 05:29:24 INFO - Running setup.py install for functools32 05:29:24 INFO - warning: no files found matching '*.txt' 05:29:24 INFO - no previously-included directories found matching 'build' 05:29:24 INFO - no previously-included directories found matching 'dist' 05:29:24 INFO - no previously-included directories found matching '.git*' 05:29:24 INFO - Successfully installed jsonschema functools32 05:29:24 INFO - Cleaning up... 05:29:24 INFO - Return code: 0 05:29:24 INFO - Running post-action listener: _resource_record_post_action 05:29:24 INFO - Running post-action listener: _start_resource_monitoring 05:29:24 INFO - Starting resource monitoring. 05:29:24 INFO - ##### 05:29:24 INFO - ##### Running install step. 05:29:24 INFO - ##### 05:29:24 INFO - Running pre-action listener: _resource_record_pre_action 05:29:24 INFO - Running main action method: install 05:29:24 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:29:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:29:24 INFO - Reading from file tmpfile_stdout 05:29:24 INFO - Detecting whether we're running mozinstall >=1.0... 05:29:24 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:29:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:29:25 INFO - Reading from file tmpfile_stdout 05:29:25 INFO - Output received: 05:29:25 INFO - Usage: mozinstall [options] installer 05:29:25 INFO - Options: 05:29:25 INFO - -h, --help show this help message and exit 05:29:25 INFO - -d DEST, --destination=DEST 05:29:25 INFO - Directory to install application into. [default: 05:29:25 INFO - "/builds/slave/test-pgo"] 05:29:25 INFO - --app=APP Application being installed. [default: firefox] 05:29:25 INFO - mkdir: /builds/slave/test-pgo/build/application 05:29:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:29:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:29:39 INFO - Reading from file tmpfile_stdout 05:29:39 INFO - Output received: 05:29:39 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:29:39 INFO - Running post-action listener: _resource_record_post_action 05:29:39 INFO - ##### 05:29:39 INFO - ##### Running run-tests step. 05:29:39 INFO - ##### 05:29:39 INFO - Running pre-action listener: _resource_record_pre_action 05:29:39 INFO - Running main action method: run_tests 05:29:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:29:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:29:39 INFO - Python 2.7.3 05:29:39 INFO - Return code: 0 05:29:39 INFO - grabbing minidump binary from tooltool 05:29:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:29:39 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 0x20b9e50>, '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 0x20bad50>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x20bb1e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:29:39 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:29:39 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:29:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:29:39 INFO - Return code: 0 05:29:39 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:29:39 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:29:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:29:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:29:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:29:39 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-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:29:39 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-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost 05:29:39 INFO - Using env: {'DISPLAY': ':0', 05:29:39 INFO - 'HOME': '/home/cltbld', 05:29:39 INFO - 'LANG': 'en_US.UTF-8', 05:29:39 INFO - 'LANGUAGE': 'en_US:en', 05:29:39 INFO - 'LOGNAME': 'cltbld', 05:29:39 INFO - 'MAIL': '/var/mail/cltbld', 05:29:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:29:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:29:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:29:39 INFO - 'MOZ_NO_REMOTE': '1', 05:29:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:29:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:29:39 INFO - 'NO_EM_RESTART': '1', 05:29:39 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:29:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:29:39 INFO - 'PWD': '/builds/slave/test-pgo', 05:29:39 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:29:39 INFO - 'SHELL': '/bin/bash', 05:29:39 INFO - 'SHLVL': '1', 05:29:39 INFO - 'TERM': 'linux', 05:29:39 INFO - 'TMOUT': '86400', 05:29:39 INFO - 'USER': 'cltbld', 05:29:39 INFO - 'XDG_SESSION_COOKIE': '6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258', 05:29:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:29:39 INFO - '_': '/tools/buildbot/bin/python'} 05:29:39 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost'] with output_timeout 3600 05:29:39 INFO - mozversion INFO | application_buildid: 20151124033743 05:29:39 INFO - mozversion INFO | application_changeset: cf8cf1a039dd370d157b2afaa1fed719f1e34069 05:29:39 INFO - mozversion INFO | application_display_name: Nightly 05:29:39 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:29:39 INFO - mozversion INFO | application_name: Firefox 05:29:39 INFO - mozversion INFO | application_remotingname: firefox 05:29:39 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:29:39 INFO - mozversion INFO | application_vendor: Mozilla 05:29:39 INFO - mozversion INFO | application_version: 45.0a1 05:29:39 INFO - mozversion INFO | platform_buildid: 20151124033743 05:29:39 INFO - mozversion INFO | platform_changeset: cf8cf1a039dd370d157b2afaa1fed719f1e34069 05:29:39 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 05:29:39 INFO - mozversion INFO | platform_version: 45.0a1 05:29:39 INFO - 2015-11-24 05:29:39,893 DEBUG : using testdate: 1448371779 05:29:39 INFO - 2015-11-24 05:29:39,893 DEBUG : actual date: 1448371779 05:29:39 INFO - 2015-11-24 05:29:39,908 INFO : starting webserver on 'localhost:37282' 05:29:39 INFO - 2015-11-24 05:29:39,909 INFO : Starting test suite talos-linux64-ix-057 05:29:39 INFO - 2015-11-24 05:29:39,909 INFO : Starting test dromaeo_css 05:29:39 INFO - 2015-11-24 05:29:39,909 DEBUG : operating with platform_type : linux_ 05:29:39 INFO - 2015-11-24 05:29:39,910 INFO : Initialising browser for dromaeo_css test... 05:29:39 INFO - 2015-11-24 05:29:39,924 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1ABZP6/profile http://localhost:37282/getInfo.html 05:29:43 INFO - 2015-11-24 05:29:43,624 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:29:43 INFO - 2015-11-24 05:29:43,625 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:29:43 INFO - 2015-11-24 05:29:43,625 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:29:43 INFO - 2015-11-24 05:29:43,625 DEBUG : BROWSER_OUTPUT: __metrics 05:29:43 INFO - 2015-11-24 05:29:43,729 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:29:44 INFO - 2015-11-24 05:29:44,929 INFO : Browser initialized. 05:29:44 INFO - 2015-11-24 05:29:44,930 INFO : Running cycle 1/1 for dromaeo_css test... 05:29:44 INFO - 2015-11-24 05:29:44,930 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp1ABZP6/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:29:56 INFO - 2015-11-24 05:29:56,725 DEBUG : BROWSER_OUTPUT: RSS: Main: 139075584 05:29:56 INFO - 2015-11-24 05:29:56,726 DEBUG : BROWSER_OUTPUT: 05:30:48 INFO - 2015-11-24 05:30:48,385 DEBUG : BROWSER_OUTPUT: 1448371848381 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:32:56 INFO - 2015-11-24 05:32:56,858 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37282/tests/dromaeo/cssquery-ext.html) 05:32:58 INFO - 2015-11-24 05:32:58,354 DEBUG : BROWSER_OUTPUT: RSS: Main: 308355072 05:32:58 INFO - 2015-11-24 05:32:58,354 DEBUG : BROWSER_OUTPUT: 05:36:00 INFO - 2015-11-24 05:36:00,751 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/dromaeo/cssquery-ext.html (next: http://localhost:37282/tests/dromaeo/cssquery-jquery.html) 05:36:01 INFO - 2015-11-24 05:36:01,316 DEBUG : BROWSER_OUTPUT: RSS: Main: 224251904 05:36:01 INFO - 2015-11-24 05:36:01,317 DEBUG : BROWSER_OUTPUT: 05:39:03 INFO - 2015-11-24 05:39:03,228 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37282/tests/dromaeo/cssquery-mootools.html) 05:39:03 INFO - 2015-11-24 05:39:03,801 DEBUG : BROWSER_OUTPUT: RSS: Main: 218001408 05:39:03 INFO - 2015-11-24 05:39:03,801 DEBUG : BROWSER_OUTPUT: 05:42:05 INFO - 2015-11-24 05:42:05,773 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37282/tests/dromaeo/cssquery-prototype.html) 05:42:06 INFO - 2015-11-24 05:42:06,374 DEBUG : BROWSER_OUTPUT: RSS: Main: 199262208 05:42:06 INFO - 2015-11-24 05:42:06,374 DEBUG : BROWSER_OUTPUT: 05:45:08 INFO - 2015-11-24 05:45:08,884 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37282/tests/dromaeo/cssquery-yui.html) 05:45:09 INFO - 2015-11-24 05:45:09,466 DEBUG : BROWSER_OUTPUT: RSS: Main: 204914688 05:45:09 INFO - 2015-11-24 05:45:09,466 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-11-24 05:48:12,357 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/dromaeo/cssquery-yui.html (next: http://localhost:37282/tests/dromaeo/cssquery-dojo.html) 05:48:12 INFO - 2015-11-24 05:48:12,607 DEBUG : BROWSER_OUTPUT: RSS: Main: 246988800 05:48:12 INFO - 2015-11-24 05:48:12,608 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-11-24 05:48:12,608 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:48:12 INFO - 2015-11-24 05:48:12,608 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:48:12 INFO - 2015-11-24 05:48:12,608 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:48:12 INFO - 2015-11-24 05:48:12,609 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:48:12 INFO - 2015-11-24 05:48:12,610 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3163.2408222026716;3236.9028929132132;3280.885169019085;3283.4089863824515;3295.2915123248495;5079.466656001122;5209.088409528327;5307.575393968483;5383.488568585988;5418.997485465186;5233.921491177635;5308.973455132719;5384.461553844613;5413.052715774744;5439.972800136004;4915.2627105934125;4943.604511639078;4950.381202349707;4964.0816448956875;5009.398872135331;4964.4787297333805;5091.847244582668;5094.1085310070775;5101.51535604117;5142.023015627019;4704.576588107053;4813.3261343411605;4818.7831547580445;5008.29883816266;5032.748362581875;4921.753912304371;4932.531409516126;4943.357363542752;4961.751912404366;4971.801127954877;4878.5365390288225;4915.213565829486;4918.655694101414;4932.186189278765;4963.1810751226;4968.925466118011;5068;5093.337866077423;5219.634625576211;5281.287026251445;5737.655740655532;5743.856403589902;5756;5759.769609215627;5767.36558978512;5751.309842818889;5755.906377788207;5757.769689212427;5763.538916886639;5765.711714414305;5326.307580014612;5381.865453363697;5392.4337944515655;5413.106837371829;5438.347398312228;5831.970840145772;5852.385499522574;5873.442023007808;5897.67562784051;5901.291844978544;5446.782128714807;5555.694436805934;5600.27196464457;5664.773409063674;5666.518345940559;5766.336871259775;5770.2498675171955;5770.42295770428;5781.335146458127;5815.505682017076;5678.432156784371;5683.346415162309;5746.4253574643035;5751.1085781703905;5751.367349591542;5452.618316717871;5552.944470555323;5657.971710141505;5754.712264386764;5798.536117110705;4508.1659892919915;4510.594046535827;4512.774361281989;4532.682712210114;4552.044070745114;3993.4808474898077;3997.8600748973645;4008.198360327946;4038.0510580013674;4042.717009809285;5275.103232450492;5389.730513474311;5449.346078470529;5553.194786755978;5593.105103183471;5818.127280907813;5834.883302333929;5840.737166827503;5863.384344643836;5867.735951882176;5261.76322065508;5558.888822223533;5570.637908535932;5575.749091290821;5576.749046292926;4972.4033116025585;5026.145555255615;5034.16936205522;5038.874028149252;5073.619478539124;6062.969685151546;6092.5430592705725;6093.36019717941;6095.999999999911;6100.084987251859;5529.502344788988;5629.493345598916;5822.359540450631;5825.0679891217205;5830.416958304136;5876.764929402776;5881.882362352643;5951.672658003678;5965.850853728692;6021.066734656135;5671.347795003462;5716.685582293014;5719.656820590778;5724.227229323988;5726.513246374105;5612.915806262991;5730.885382292414;5824.621399609012;5835.270591176103;5837.153612726216;5573.526250268773;5625.915611265752;5640.266765320482;5640.830775076754;5643.661380317194;5624.015797235549;5626.7902400983985;5629.8592535186945;5630.887382252414;5636;5480.451954804487;5530.585206109477;5533.142362933751;5533.972330138324;5594.02104631696;5697.344805347272;5711.657300561813;5713.571482138939;5713.9142912855505;5775.075987841925;5575.414581468887;5586.972065139648;5599.272094627672;5602.411746766694;5606.495415412633 05:48:12 INFO - 2015-11-24 05:48:12,611 DEBUG : BROWSER_OUTPUT: |1;ext.html;556.6075916478883;607.5139888089527;610.4109534299402;610.8533951851557;615.7352338494447;29105.563416548743;39271;40278.59721402785;40407.19185616286;40486.59513404865;42432.57567424325;42715.35926961098;42847.35728964068;43051.78474107633;43053.7847310763;7664.156942736322;7751.22487751226;8697.826043479143;8850.911490885073;8857.158569935846;10516.894831051668;10769.192310576698;10789.83815242772;10796.298240614375;10828.566857325697;10487.318324308933;10620.256582039261;10621.41582212978;10635;10642.78714425711;7559.659815308324;7687.423443241779;10437.269391142623;10534.157267418665;10778.892211077868;10373.740656483573;10459.895401045967;10460.947695261475;10474.738131546772;10481.161507079416;39950.80024599859;39981.40027899584;40264;40480.19039619221;40637.39043914343;3069.493533566959;3264.983675081633;3918.337362195559;4011.2980228460196;4028.237546364622;29559.261018474495;29714.655000994146;30227.09318720442;30306.393872122655;30332;3738.140227747606;3822.5412950446125;3826.579076301605;3830.597787232356;3855.61443855615;272785;272885;274170.999999998;274704.62647686835;274734.62632686907;56951.73530911597;57179.42820571824;57673.13490297649;57753.71123144357;57821.42178578245;58875;59356.70321648451;59423.70288148532;59511.70244148838;59645;14096.50662226817;14198.219097949504;14248.287585620677;14256.073355232083;14300.713985720227;9621.422714637145;9652.806943861084;9656.082672146134;9665.613375465044;9707.90292097084;18020.189091490913;18567.25730970773;18612.162452689663;18620.348287809862;18688.813111868978;7494.700211991569;7661.348785353196;7717.421193410517;7744.1094274159195;7788.9911463768785;931.6925414613297;992.0575453319375;1009.3590569988111;1052.6684094510358;1055.3931489393628;27960.44079118406;28134.29664258369;28271.43457130846;28514.71485285162;28805.85597072001;975.6048800235916;1081.432248069754;1125.6285425809626;1150.0593915718764;1180.8464899563003;14301.499447519287;14378.85621143796;14456.349464274133;14721.116732996055;14801;305175;307347;310518;310539.15686745004;310980;43062.78468607637;43701.78149109234;50972.235416468786;54557.45442545603;55608.721956389956;1330.8070329802124;1343.8387393512842;1355.1327150623413;1355.4442678501964;1356.803263526783;26254.737452625992;27220.183394498195;27355.86322068377;32105.35789284248;33672;47962.760186198844;48134.759326203144;48301;48566.5143348562;48642.270365943834;275411;284080;284585;284660;285254;42108.789456052524;42852.35721464118;42861;43128.13743725045;43370;50341.49658503368;51593.74203128961;51626;52395.21407178819;53091.734541327045;38605.22789544137;39468.2106357877;39911.60088399079;40143;40498;39007.414888776126;39530.802345988086;39567.36280715768;39680.60319396769;39952.40071398873;38150.23699525939;38412.2317553653;38850;39180.80409598048;39253.60746392499;32911.67088329086;33537.329253415286;35307.470387943686;37557.248855023296;39055.41416878692;26580.734192657826;26998;27691.72308276972;28285.29286767765;28543.857280713462 05:48:12 INFO - 2015-11-24 05:48:12,615 DEBUG : BROWSER_OUTPUT: |2;jquery.html;351.1414591324212;353.8993729501249;355.3443896011858;355.63902638821594;359.37827558324096;16632.750508742378;16645.83354166458;16891.746623800624;17080.743788843145;17134.65730685387;10809;11098.278611890222;16064.116473593947;16280.104594247312;16300.510984670449;4790.880227994302;4898.828541001066;4904.509549045085;4908.067467181242;4912.164931961557;4407.206702793496;4869.707817530942;5440.700761458118;5452.4274951130155;5455;3743.232637309359;3850.4031875059272;5405.162199859008;5410.08028635131;5431;5355.758990845421;5477.534409575191;5524.779008839641;5526.834194974157;5535.750891209906;5152.175651895716;5262.394824595144;5269.341332333458;5292.708901010443;5298.125809241471;10772.784544309148;11038.503267352988;12610.936945315214;19158;19258.518537036685;2127.5213077057692;2148.924787632441;2160.470684682263;2165.6426689596196;2172.6306527890297;9094.45433273999;9269.351145419823;9803.509824508747;11611.518690422736;12344.95067919228;1778.510909499885;1797.0565453137076;1802.2971041293906;1828.4880233534632;1845.8338749512475;58699.41300587025;61355;61715;62171.68914155445;62190;10117.898821011842;10170.338927969658;10175.338602990782;10216.131628811634;10242.897571024194;12202.389880506116;12231.816522752166;12233.204841685261;12283.447244874002;12586.81119783204;7054.08296921397;7216.494845360774;7259.455540834353;7268.127824660969;7329.5968721719755;6889.793306200822;6892.138482689664;6902.792916212522;6918.06606108179;6918.515703900679;4380.846670366522;4415.072834704683;4432.202203603382;4445.044315472189;4471.977640111779;1784.2595322941095;1805.2417984446563;1812.7099664053687;1846.759921210261;1863.2546981207624;386.7969316109009;405.49920847753003;445.24975416422785;452.38701559386556;987.3582171588525;10653.9467302663;10711.143108551298;10793.02862742357;11236.381998989971;11285.322880627024;431.09900308355685;431.3594312446078;443.14694213638614;446.5021501026332;449.03905641926303;5175.870603234949;6173.135760993465;6246.937530624727;6328.778492752732;6427.132337134521;498692;510393;513008;515374;537139;70873.29126708666;70909.2909070913;72278;75318;78154.21845781583;484.8157700073949;497.8954419571858;498.7331777499093;498.81045202822696;499.1364938656073;10262.897371026194;10389.740256493647;10425.687229383131;10569.365838049742;10593.735156621146;35075.12312192113;35537.28925421425;35942.460863087595;35956.4606530897;36091.639083608825;46082.539174607824;46613;46965.295520566535;47004.529954701386;47250.76374618243;8881.689140880038;9067.229285510806;9069.682561110329;9092.772680683036;9136.132067453582;17460.563485912953;17608.38370657021;17714.91142544279;17828.55428614295;17989.73015404796;14280;14337.856621433652;14754.852451475777;14853.702925941205;14857.108573485626;14088.577342679737;14218.289085545888;14385.42458301698;14736;14881.032732872329;14082.859171408154;14756.188409637143;14831.703365932837;14896.553103406915;15084.321205545997;8086.11052784205;8365.28895043928;9424.858627120462;9532.189763870148;9663.46850923206;10040.646376524413;10055.69832905014;10155.441450719985;14546.85453145455;14933.626659333604 05:48:12 INFO - 2015-11-24 05:48:12,616 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1004.2819384140347;1664.4673704414588;1690.8478236958672;1703.7018521758696;1720.707479728446;5914.792982245623;6917.204521480031;7133.251008644095;7152.892706609405;7154.213036565973;7066.222715501291;7217.206107328189;7223.061002069737;7231.67457464414;7237.348638622523;1907.9523011924673;1943.3004118517392;2187.5187458759046;2207.0730293276824;2240.350298413458;2220.6335954567476;2235.8099561537288;2236;2239.1715065425815;2242.7981481666648;2124.2034237161065;2186.0709198590534;2186.6392045312505;2194.078487035441;2195.8572692774997;1914.5117994911277;1964.067068142628;2178.3900507857825;2223.032980653421;2230.163688616769;2158.751743549496;2187.55155193185;2198.6482162854027;2205.8676479411124;2210.624193887027;7026.1217347831525;7067.399271061914;7073.221945585981;7079.185893622248;7081.92918070823;4379.562043795627;4435.778211089432;4436.645068394553;4481.103779244132;4481.775911204427;2948.292409821649;3010.5785190073407;3041.4525385430616;3042.543618457227;3043.117495926179;950.4154944708954;966.0725703324816;989.9406035637812;994.0159242350094;995.2386424385351;9702.61189552417;9718.465484398355;9729.805403891882;9731.367461115005;9735.464549449778;3949.3681011038097;4033.4353190553347;4052.2098190852503;4078.225137223977;4090.427340172378;8447.577621118919;8477.237048665527;8478.957605212057;8482.660693572312;8497.575121244037;4478.776061196927;4482.529334419839;4516.638668906479;4521.412216411911;4530.388397566287;4112.506499220112;4118;4118.938215926763;4126.566710495415;4131.380292956081;3520.718342532591;3573.2496175802853;3588.8026158561006;3589.892303230907;3601.4777857210556;1206.1315852586124;1208.456194712383;1223.473906220334;1231.8029115341503;1236.4312416288608;871.4248595926658;1251.8310028146266;1263.9873601264053;1268.77796385634;1297.6236891301605;5874.530037596982;6718.294579069127;6892.896606550906;6903.378695917391;6908.585484870924;593.1991811055127;597.238520885874;605.8818530386533;635.2440595691113;636.5671343486474;5384.111621582473;5415.593830462731;5416.187571864173;5430.42980487051;5431.049566325877;8400.327973762085;8635;8670.393072484992;8680.392372533863;8687.826243475096;8513.914860851437;8571.314294856396;8647.092055334226;8659.956700216459;8663.95668021656;6510.3489651034515;6655.534112612071;6700.55937973338;6763.053172555748;6765.52641315113;6561.081448597105;6587.176602924635;6634.170728658918;6644.4020038196795;6650.866982660416;7523.360514356122;7622.847543049219;7629.504082234637;7775.92224077752;7777.377809775091;7846.450748447553;7874.094479134973;7888.171741967011;8017.398695098008;8023.558704271321;6350.714217860115;6429;6492.902606460812;6576.868462630816;6600.438962688226;6885.586864787929;6946.617936013366;6977.302269772982;6980.581165130108;6981.79054628362;6545.607263564011;6562.376574225441;6601.174853143357;6631.801045968628;6675.198976122896;6655.767048153485;6673.299303573055;6727.183586147407;6727.327267273234;6763.458923286224;5478.780848766005;5604.915926261027;6593.241777195684;6649.202095748541;6652;5705.914411283917;5828.6211396259105;5876.118582212703;5898.7935422261735;5900.43945825146;5933.673647949232;5982.49148822355;5999.760009599567;6000.129981152621;6008.5493587981955 05:48:12 INFO - 2015-11-24 05:48:12,618 DEBUG : BROWSER_OUTPUT: |4;prototype.html;311.9287928157036;319.286394907382;324.8668046101099;325.19676399293746;327.74763432157255;5813.970930145354;5847.327557330908;5878.177055212268;5884.823455296342;5944.821655350346;5720.22776925115;5834.533237341013;5836.824895253149;5839.824805255849;5864;4334.241507736149;4458.799354029076;4581.88545286368;4583.977080114595;4585.908281834361;4486.147631949935;4554.886127846814;4562;4584.7478388688605;4586.1974154522995;4512;4525.027119169378;4533.977330113345;4537.886552836172;4541.545845415465;4500.774961251925;4535.841245556389;4547.1587756265035;4548.8862778430475;4554.635629149677;3819.5416550013897;4006.659433948118;4007.198560287954;4018.1361007383553;4024.3158663027357;5567.554595632339;5575.442455754433;5584;5591.329040515124;5605.159226116074;5223.503767142153;5229.2417599448245;5239.449857764956;5242.6330156889035;5243.318368612056;5951.2858456985;6087.3303936566945;6097.756089756404;6100.542459315525;6237.313895471494;4051.0682543015273;4155.231282212801;4189.518205406356;4239.576042395766;4272.636825869805;6411.1024456576115;6442.323556026643;6479.870402591921;6497.675116244216;6508.837279068014;4388.605025547652;4391.890202744957;4393.51671316155;4394.956050439519;4401;4441.733495990186;4445.711028783118;4447;4451.198784218807;4455.598996090367;5089.655787143607;5137.8715532112;5162.328897243335;5178.3785945686;5187.948120518822;6249.78125765596;6251.906221406683;6253.843653908598;6262;6264.154339164245;1567.2085596773557;1584.168311636377;1594.593378688427;1595.2980688497023;1596.3454983456727;1040.1314902056868;1060.5863713151878;1062.5484169228048;1063.9042486176281;1087.6954452753241;5970.194023806731;6099.542534309944;6103.725332360054;6110.358412366726;6110.7861224856915;6363.9045414318825;6437.420632143129;6526.934730652632;6545.770898018546;6547.083408322838;6096.847578810565;6150.50795936324;6168.19813424252;6171.6297022178815;6175.907361389583;3490.7905525668543;3503.211777350127;3532.4171511700797;3534.770239934396;3544.326577950181;6619.602823830585;6754.0881980931945;6762.526623136431;6780.627065511444;6840.623765692934;6660.200775906922;6686.4985126115735;6698.22970358415;6700.128983232149;6707.496937729592;6657.567258128206;6826.829329266709;6837.863242735217;6841.897371539431;6843.8973415398805;6516.185476815398;6519.315471875574;6523.804285871432;6524.9673751630935;6531.615960174597;6435.4529864962005;6443.871122577616;6447.290798012121;6449.806505804833;6454.19322584677;6645.800625981228;6811.625360605214;6815;6815.216250131299;6817.386435220854;4447.243968525294;4450.799714012813;4469.865904022885;4472.642188624967;4490.865274041784;6457.321981191907;6500.5449618526245;6504.056911747864;6513.250976137615;6515.381038801306;6332.52506062038;6335.90496142549;6336.429721324918;6337.556371053982;6343.11196432509;6184.536159787944;6306.558540902093;6321.1782468278825;6327.430531252209;6329.8734025320155;6206.565540412128;6220.626762394271;6221.875562488815;6222.439980401786;6222.937770622417;6320.5891617044745;6324.3991820776955;6328.588641738273;6335.909149980587;6343.904841427475;6099.634021958696;6237.12680224778;6240.500759939285;6247.562670613195;6250.749970001149 05:48:12 INFO - 2015-11-24 05:48:12,621 DEBUG : BROWSER_OUTPUT: |5;yui.html;713.7971031734229;791.9802004949879;826.2274773087164;845.1210740829536;845.2308399356587;12265.264084154946;12545.247285162897;12545.874541254585;12556.81164782529;12559.434825432854;14610.269486525658;14908.254587270647;14935.253237338144;14945.402183912658;14977.700445991073;675.5642610516238;829.6681327469015;846.6951897316961;847.9321654267644;848.2620120495178;683.9042534045237;821.2362502872327;838.7106448275335;839.0979696825906;840.3949156607233;867.8264347130568;870.1994165367845;877.4165180155189;877.934154938379;878.547548012774;711.0542977839461;722.458156382713;838.7986883148001;868.8783570300163;870.0907551608584;629.971651275689;630.2059405149499;630.4200135875006;631.9241690997064;633.6483251795252;13331.466741330432;13445.932770336183;13449.596512104652;13450.192988420726;13452.596422107255;11074.944625276903;11581.478833452431;11668.416579171093;11814.586489472827;11900.940495297555;9655.662051828152;9668.371555848847;9699.757506062335;10044.945280745489;10318.565065224288;3527.753057285991;3576.678098971079;3604.711623070148;3650.2699460108083;3684.6683798458266;47189.764051179525;47506.76246618779;47564.52435475636;47605.761971190266;47628.7618561905;9546.522673866279;9666.95166524163;10159.746006349753;10183.287169898036;10421.635242766466;15981.680366392608;16036.518904432885;16045.839541604668;16060.839391606169;16062;6996.790096297119;7113.92886071143;7252.927470725331;7265.164506081763;7303.671334789948;3963.801809909493;3980.8805735827973;4032.6370626643743;4081.2449696806184;4149.97925010373;2858.113984664719;2897.5219088850527;2898.9565156522667;2904.2158617173245;2908.0548821633056;526.0819869328047;535.2666846420351;538.3701069748404;542.926704894842;544.1511242461772;508.9033133597104;567.4779203133128;583.5914859598213;591.4617697894895;615.7167702856647;7362;7493.85012299751;7572.621368931532;7689.077310722749;7711.884321735179;479.35342099887;503.5015334818577;514.2594663684283;524.5200641413064;592.1443514122138;3579.7494175407473;3587.121155316964;3669.119411341312;3701.481792549045;3715.294094122108;56546.717266413405;57516;57641.13538296929;57701.71149144227;57968.71015644895;49770.50229497731;50678.49321506738;51616.48383516192;52262.73868630632;52294;46312.30531542029;46683;47430;47434.28848567274;47562.28656570154;7282.235365286569;7381.704731810667;7442.255774422731;7539.962300188464;7632.084149902047;33901.49147762835;34659.4801077979;34682.653173467945;34753.13117172091;35065.64934350624;42333.57666423296;46404.30393544167;46601.766991164826;46797.29804052874;47031.294530581385;9218.28725019293;9242.168204861326;9304.488253146143;9703.805923881624;9754.85367719499;9786.608535658494;10240.53917573696;10292.382457052601;10343.120834729132;10460.843087353544;9455.621775129193;9643.807123857623;9719.708408747749;10122.696319110439;10133.08802207805;9245.676401325922;9357.39176953523;9378.249740020918;9427.481488518197;9784.168345690412;9616.663416780379;9885.308028437941;10042.397456152654;10091.596336146766;10095.44475053879;6731;8826.779330516789;9244.95377523108;9358.625654973725;9453.810923781348;8467.703630372907;8504.914950850412;8644;8925.196732294125;9056.366054376394 05:48:12 INFO - 2015-11-24 05:48:12,621 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:48:12 INFO - 2015-11-24 05:48:12,622 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:48:12 INFO - 2015-11-24 05:48:12,622 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1295 05:48:12 INFO - 2015-11-24 05:48:12,622 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:48:12 INFO - 2015-11-24 05:48:12,622 DEBUG : BROWSER_OUTPUT: __startTimestamp1448372892606__endTimestamp 05:48:12 INFO - 2015-11-24 05:48:12,622 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-11-24 05:48:12,622 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:48:12 INFO - 2015-11-24 05:48:12,622 DEBUG : BROWSER_OUTPUT: Number of tests: 6 05:48:12 INFO - 2015-11-24 05:48:12,623 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-11-24 05:48:12,623 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5364.07 Median:5575.41 stddev:580.09 (10.4%) stddev-sans-first:555.58 05:48:12 INFO - 2015-11-24 05:48:12,623 DEBUG : BROWSER_OUTPUT: Values: 3163.2 3236.9 3280.9 3283.4 3295.3 5079.5 5209.1 5307.6 5383.5 5419.0 5233.9 5309.0 5384.5 5413.1 5440.0 4915.3 4943.6 4950.4 4964.1 5009.4 4964.5 5091.8 5094.1 5101.5 5142.0 4704.6 4813.3 4818.8 5008.3 5032.7 4921.8 4932.5 4943.4 4961.8 4971.8 4878.5 4915.2 4918.7 4932.2 4963.2 4968.9 5068.0 5093.3 5219.6 5281.3 5737.7 5743.9 5756.0 5759.8 5767.4 5751.3 5755.9 5757.8 5763.5 5765.7 5326.3 5381.9 5392.4 5413.1 5438.3 5832.0 5852.4 5873.4 5897.7 5901.3 5446.8 5555.7 5600.3 5664.8 5666.5 5766.3 5770.2 5770.4 5781.3 5815.5 5678.4 5683.3 5746.4 5751.1 5751.4 5452.6 5552.9 5658.0 5754.7 5798.5 4508.2 4510.6 4512.8 4532.7 4552.0 3993.5 3997.9 4008.2 4038.1 4042.7 5275.1 5389.7 5449.3 5553.2 5593.1 5818.1 5834.9 5840.7 5863.4 5867.7 5261.8 5558.9 5570.6 5575.7 5576.7 4972.4 5026.1 5034.2 5038.9 5073.6 6063.0 6092.5 6093.4 6096.0 6100.1 5529.5 5629.5 5822.4 5825.1 5830.4 5876.8 5881.9 5951.7 5965.9 6021.1 5671.3 5716.7 5719.7 5724.2 5726.5 5612.9 5730.9 5824.6 5835.3 5837.2 5573.5 5625.9 5640.3 5640.8 5643.7 5624.0 5626.8 5629.9 5630.9 5636.0 5480.5 5530.6 5533.1 5534.0 5594.0 5697.3 5711.7 5713.6 5713.9 5775.1 5575.4 5587.0 5599.3 5602.4 5606.5 05:48:12 INFO - 2015-11-24 05:48:12,623 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-11-24 05:48:12,623 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46963.31 Median:3828.59 stddev:75264.96 (1965.9%) stddev-sans-first:75395.44 05:48:12 INFO - 2015-11-24 05:48:12,623 DEBUG : BROWSER_OUTPUT: Values: 556.6 607.5 610.4 610.9 615.7 29105.6 39271.0 40278.6 40407.2 40486.6 42432.6 42715.4 42847.4 43051.8 43053.8 7664.2 7751.2 8697.8 8850.9 8857.2 10516.9 10769.2 10789.8 10796.3 10828.6 10487.3 10620.3 10621.4 10635.0 10642.8 7559.7 7687.4 10437.3 10534.2 10778.9 10373.7 10459.9 10460.9 10474.7 10481.2 39950.8 39981.4 40264.0 40480.2 40637.4 3069.5 3265.0 3918.3 4011.3 4028.2 29559.3 29714.7 30227.1 30306.4 30332.0 3738.1 3822.5 3826.6 3830.6 3855.6 272785.0 272885.0 274171.0 274704.6 274734.6 56951.7 57179.4 57673.1 57753.7 57821.4 58875.0 59356.7 59423.7 59511.7 59645.0 14096.5 14198.2 14248.3 14256.1 14300.7 9621.4 9652.8 9656.1 9665.6 9707.9 18020.2 18567.3 18612.2 18620.3 18688.8 7494.7 7661.3 7717.4 7744.1 7789.0 931.7 992.1 1009.4 1052.7 1055.4 27960.4 28134.3 28271.4 28514.7 28805.9 975.6 1081.4 1125.6 1150.1 1180.8 14301.5 14378.9 14456.3 14721.1 14801.0 305175.0 307347.0 310518.0 310539.2 310980.0 43062.8 43701.8 50972.2 54557.5 55608.7 1330.8 1343.8 1355.1 1355.4 1356.8 26254.7 27220.2 27355.9 32105.4 33672.0 47962.8 48134.8 48301.0 48566.5 48642.3 275411.0 284080.0 284585.0 284660.0 285254.0 42108.8 42852.4 42861.0 43128.1 43370.0 50341.5 51593.7 51626.0 52395.2 53091.7 38605.2 39468.2 39911.6 40143.0 40498.0 39007.4 39530.8 39567.4 39680.6 39952.4 38150.2 38412.2 38850.0 39180.8 39253.6 32911.7 33537.3 35307.5 37557.2 39055.4 26580.7 26998.0 27691.7 28285.3 28543.9 05:48:12 INFO - 2015-11-24 05:48:12,623 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-11-24 05:48:12,623 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:27258.68 Median:418.30 stddev:84240.79 (20138.9%) stddev-sans-first:84452.88 05:48:12 INFO - 2015-11-24 05:48:12,624 DEBUG : BROWSER_OUTPUT: Values: 351.1 353.9 355.3 355.6 359.4 16632.8 16645.8 16891.7 17080.7 17134.7 10809.0 11098.3 16064.1 16280.1 16300.5 4790.9 4898.8 4904.5 4908.1 4912.2 4407.2 4869.7 5440.7 5452.4 5455.0 3743.2 3850.4 5405.2 5410.1 5431.0 5355.8 5477.5 5524.8 5526.8 5535.8 5152.2 5262.4 5269.3 5292.7 5298.1 10772.8 11038.5 12610.9 19158.0 19258.5 2127.5 2148.9 2160.5 2165.6 2172.6 9094.5 9269.4 9803.5 11611.5 12345.0 1778.5 1797.1 1802.3 1828.5 1845.8 58699.4 61355.0 61715.0 62171.7 62190.0 10117.9 10170.3 10175.3 10216.1 10242.9 12202.4 12231.8 12233.2 12283.4 12586.8 7054.1 7216.5 7259.5 7268.1 7329.6 6889.8 6892.1 6902.8 6918.1 6918.5 4380.8 4415.1 4432.2 4445.0 4472.0 1784.3 1805.2 1812.7 1846.8 1863.3 386.8 405.5 445.2 452.4 987.4 10653.9 10711.1 10793.0 11236.4 11285.3 431.1 431.4 443.1 446.5 449.0 5175.9 6173.1 6246.9 6328.8 6427.1 498692.0 510393.0 513008.0 515374.0 537139.0 70873.3 70909.3 72278.0 75318.0 78154.2 484.8 497.9 498.7 498.8 499.1 10262.9 10389.7 10425.7 10569.4 10593.7 35075.1 35537.3 35942.5 35956.5 36091.6 46082.5 46613.0 46965.3 47004.5 47250.8 8881.7 9067.2 9069.7 9092.8 9136.1 17460.6 17608.4 17714.9 17828.6 17989.7 14280.0 14337.9 14754.9 14853.7 14857.1 14088.6 14218.3 14385.4 14736.0 14881.0 14082.9 14756.2 14831.7 14896.6 15084.3 8086.1 8365.3 9424.9 9532.2 9663.5 10040.6 10055.7 10155.4 14546.9 14933.6 05:48:12 INFO - 2015-11-24 05:48:12,624 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-11-24 05:48:12,625 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5065.41 Median:620.56 stddev:2573.16 (414.6%) stddev-sans-first:2562.26 05:48:12 INFO - 2015-11-24 05:48:12,625 DEBUG : BROWSER_OUTPUT: Values: 1004.3 1664.5 1690.8 1703.7 1720.7 5914.8 6917.2 7133.3 7152.9 7154.2 7066.2 7217.2 7223.1 7231.7 7237.3 1908.0 1943.3 2187.5 2207.1 2240.4 2220.6 2235.8 2236.0 2239.2 2242.8 2124.2 2186.1 2186.6 2194.1 2195.9 1914.5 1964.1 2178.4 2223.0 2230.2 2158.8 2187.6 2198.6 2205.9 2210.6 7026.1 7067.4 7073.2 7079.2 7081.9 4379.6 4435.8 4436.6 4481.1 4481.8 2948.3 3010.6 3041.5 3042.5 3043.1 950.4 966.1 989.9 994.0 995.2 9702.6 9718.5 9729.8 9731.4 9735.5 3949.4 4033.4 4052.2 4078.2 4090.4 8447.6 8477.2 8479.0 8482.7 8497.6 4478.8 4482.5 4516.6 4521.4 4530.4 4112.5 4118.0 4118.9 4126.6 4131.4 3520.7 3573.2 3588.8 3589.9 3601.5 1206.1 1208.5 1223.5 1231.8 1236.4 871.4 1251.8 1264.0 1268.8 1297.6 5874.5 6718.3 6892.9 6903.4 6908.6 593.2 597.2 605.9 635.2 636.6 5384.1 5415.6 5416.2 5430.4 5431.0 8400.3 8635.0 8670.4 8680.4 8687.8 8513.9 8571.3 8647.1 8660.0 8664.0 6510.3 6655.5 6700.6 6763.1 6765.5 6561.1 6587.2 6634.2 6644.4 6650.9 7523.4 7622.8 7629.5 7775.9 7777.4 7846.5 7874.1 7888.2 8017.4 8023.6 6350.7 6429.0 6492.9 6576.9 6600.4 6885.6 6946.6 6977.3 6980.6 6981.8 6545.6 6562.4 6601.2 6631.8 6675.2 6655.8 6673.3 6727.2 6727.3 6763.5 5478.8 5604.9 6593.2 6649.2 6652.0 5705.9 5828.6 5876.1 5898.8 5900.4 5933.7 5982.5 5999.8 6000.1 6008.5 05:48:12 INFO - 2015-11-24 05:48:12,625 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-11-24 05:48:12,626 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5254.92 Median:5960.74 stddev:1595.88 (26.8%) stddev-sans-first:1556.64 05:48:12 INFO - 2015-11-24 05:48:12,626 DEBUG : BROWSER_OUTPUT: Values: 311.9 319.3 324.9 325.2 327.7 5814.0 5847.3 5878.2 5884.8 5944.8 5720.2 5834.5 5836.8 5839.8 5864.0 4334.2 4458.8 4581.9 4584.0 4585.9 4486.1 4554.9 4562.0 4584.7 4586.2 4512.0 4525.0 4534.0 4537.9 4541.5 4500.8 4535.8 4547.2 4548.9 4554.6 3819.5 4006.7 4007.2 4018.1 4024.3 5567.6 5575.4 5584.0 5591.3 5605.2 5223.5 5229.2 5239.4 5242.6 5243.3 5951.3 6087.3 6097.8 6100.5 6237.3 4051.1 4155.2 4189.5 4239.6 4272.6 6411.1 6442.3 6479.9 6497.7 6508.8 4388.6 4391.9 4393.5 4395.0 4401.0 4441.7 4445.7 4447.0 4451.2 4455.6 5089.7 5137.9 5162.3 5178.4 5187.9 6249.8 6251.9 6253.8 6262.0 6264.2 1567.2 1584.2 1594.6 1595.3 1596.3 1040.1 1060.6 1062.5 1063.9 1087.7 5970.2 6099.5 6103.7 6110.4 6110.8 6363.9 6437.4 6526.9 6545.8 6547.1 6096.8 6150.5 6168.2 6171.6 6175.9 3490.8 3503.2 3532.4 3534.8 3544.3 6619.6 6754.1 6762.5 6780.6 6840.6 6660.2 6686.5 6698.2 6700.1 6707.5 6657.6 6826.8 6837.9 6841.9 6843.9 6516.2 6519.3 6523.8 6525.0 6531.6 6435.5 6443.9 6447.3 6449.8 6454.2 6645.8 6811.6 6815.0 6815.2 6817.4 4447.2 4450.8 4469.9 4472.6 4490.9 6457.3 6500.5 6504.1 6513.3 6515.4 6332.5 6335.9 6336.4 6337.6 6343.1 6184.5 6306.6 6321.2 6327.4 6329.9 6206.6 6220.6 6221.9 6222.4 6222.9 6320.6 6324.4 6328.6 6335.9 6343.9 6099.6 6237.1 6240.5 6247.6 6250.7 05:48:12 INFO - 2015-11-24 05:48:12,626 DEBUG : BROWSER_OUTPUT: 05:48:12 INFO - 2015-11-24 05:48:12,626 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13367.16 Median:536.82 stddev:16100.92 (2999.3%) stddev-sans-first:16118.05 05:48:12 INFO - 2015-11-24 05:48:12,626 DEBUG : BROWSER_OUTPUT: Values: 713.8 792.0 826.2 845.1 845.2 12265.3 12545.2 12545.9 12556.8 12559.4 14610.3 14908.3 14935.3 14945.4 14977.7 675.6 829.7 846.7 847.9 848.3 683.9 821.2 838.7 839.1 840.4 867.8 870.2 877.4 877.9 878.5 711.1 722.5 838.8 868.9 870.1 630.0 630.2 630.4 631.9 633.6 13331.5 13445.9 13449.6 13450.2 13452.6 11074.9 11581.5 11668.4 11814.6 11900.9 9655.7 9668.4 9699.8 10044.9 10318.6 3527.8 3576.7 3604.7 3650.3 3684.7 47189.8 47506.8 47564.5 47605.8 47628.8 9546.5 9667.0 10159.7 10183.3 10421.6 15981.7 16036.5 16045.8 16060.8 16062.0 6996.8 7113.9 7252.9 7265.2 7303.7 3963.8 3980.9 4032.6 4081.2 4150.0 2858.1 2897.5 2899.0 2904.2 2908.1 526.1 535.3 538.4 542.9 544.2 508.9 567.5 583.6 591.5 615.7 7362.0 7493.9 7572.6 7689.1 7711.9 479.4 503.5 514.3 524.5 592.1 3579.7 3587.1 3669.1 3701.5 3715.3 56546.7 57516.0 57641.1 57701.7 57968.7 49770.5 50678.5 51616.5 52262.7 52294.0 46312.3 46683.0 47430.0 47434.3 47562.3 7282.2 7381.7 7442.3 7540.0 7632.1 33901.5 34659.5 34682.7 34753.1 35065.6 42333.6 46404.3 46601.8 46797.3 47031.3 9218.3 9242.2 9304.5 9703.8 9754.9 9786.6 10240.5 10292.4 10343.1 10460.8 9455.6 9643.8 9719.7 10122.7 10133.1 9245.7 9357.4 9378.2 9427.5 9784.2 9616.7 9885.3 10042.4 10091.6 10095.4 6731.0 8826.8 9245.0 9358.6 9453.8 8467.7 8504.9 8644.0 8925.2 9056.4 05:48:12 INFO - 2015-11-24 05:48:12,626 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:48:12 INFO - 2015-11-24 05:48:12,626 DEBUG : BROWSER_OUTPUT: 05:48:13 INFO - 2015-11-24 05:48:13,348 INFO : Browser exited with error code: 0 05:48:13 INFO - 2015-11-24 05:48:13,371 INFO : Completed test dromaeo_css (00:18:33) 05:48:13 INFO - 2015-11-24 05:48:13,371 INFO : Starting test kraken 05:48:13 INFO - 2015-11-24 05:48:13,371 DEBUG : operating with platform_type : linux_ 05:48:13 INFO - 2015-11-24 05:48:13,371 INFO : Initialising browser for kraken test... 05:48:13 INFO - 2015-11-24 05:48:13,377 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaz1sEB/profile http://localhost:37282/getInfo.html 05:48:17 INFO - 2015-11-24 05:48:17,070 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:48:17 INFO - 2015-11-24 05:48:17,071 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:48:17 INFO - 2015-11-24 05:48:17,071 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:48:17 INFO - 2015-11-24 05:48:17,071 DEBUG : BROWSER_OUTPUT: __metrics 05:48:17 INFO - 2015-11-24 05:48:17,173 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:48:18 INFO - 2015-11-24 05:48:18,348 INFO : Browser initialized. 05:48:18 INFO - 2015-11-24 05:48:18,348 INFO : Running cycle 1/1 for kraken test... 05:48:18 INFO - 2015-11-24 05:48:18,349 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaz1sEB/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:48:30 INFO - 2015-11-24 05:48:30,084 DEBUG : BROWSER_OUTPUT: RSS: Main: 138653696 05:48:30 INFO - 2015-11-24 05:48:30,085 DEBUG : BROWSER_OUTPUT: 05:48:32 INFO - 2015-11-24 05:48:32,531 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37282/tests/kraken/driver.html?testName=audio-beat-detection) 05:48:33 INFO - 2015-11-24 05:48:33,112 DEBUG : BROWSER_OUTPUT: RSS: Main: 176857088 05:48:33 INFO - 2015-11-24 05:48:33,112 DEBUG : BROWSER_OUTPUT: 05:48:35 INFO - 2015-11-24 05:48:35,749 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37282/tests/kraken/driver.html?testName=audio-dft) 05:48:36 INFO - 2015-11-24 05:48:36,330 DEBUG : BROWSER_OUTPUT: RSS: Main: 165732352 05:48:36 INFO - 2015-11-24 05:48:36,330 DEBUG : BROWSER_OUTPUT: 05:48:39 INFO - 2015-11-24 05:48:39,230 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37282/tests/kraken/driver.html?testName=audio-fft) 05:48:39 INFO - 2015-11-24 05:48:39,791 DEBUG : BROWSER_OUTPUT: RSS: Main: 149975040 05:48:39 INFO - 2015-11-24 05:48:39,791 DEBUG : BROWSER_OUTPUT: 05:48:41 INFO - 2015-11-24 05:48:41,783 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37282/tests/kraken/driver.html?testName=audio-oscillator) 05:48:42 INFO - 2015-11-24 05:48:42,352 DEBUG : BROWSER_OUTPUT: RSS: Main: 151838720 05:48:42 INFO - 2015-11-24 05:48:42,352 DEBUG : BROWSER_OUTPUT: 05:48:44 INFO - 2015-11-24 05:48:44,533 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37282/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:48:45 INFO - 2015-11-24 05:48:45,098 DEBUG : BROWSER_OUTPUT: RSS: Main: 154869760 05:48:45 INFO - 2015-11-24 05:48:45,098 DEBUG : BROWSER_OUTPUT: 05:48:47 INFO - 2015-11-24 05:48:47,983 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37282/tests/kraken/driver.html?testName=imaging-darkroom) 05:48:48 INFO - 2015-11-24 05:48:48,551 DEBUG : BROWSER_OUTPUT: RSS: Main: 155447296 05:48:48 INFO - 2015-11-24 05:48:48,552 DEBUG : BROWSER_OUTPUT: 05:48:51 INFO - 2015-11-24 05:48:51,914 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37282/tests/kraken/driver.html?testName=imaging-desaturate) 05:48:52 INFO - 2015-11-24 05:48:52,487 DEBUG : BROWSER_OUTPUT: RSS: Main: 156061696 05:48:52 INFO - 2015-11-24 05:48:52,488 DEBUG : BROWSER_OUTPUT: 05:48:55 INFO - 2015-11-24 05:48:55,277 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37282/tests/kraken/driver.html?testName=json-parse-financial) 05:48:55 INFO - 2015-11-24 05:48:55,846 DEBUG : BROWSER_OUTPUT: RSS: Main: 156106752 05:48:55 INFO - 2015-11-24 05:48:55,847 DEBUG : BROWSER_OUTPUT: 05:48:57 INFO - 2015-11-24 05:48:57,387 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37282/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:48:57 INFO - 2015-11-24 05:48:57,960 DEBUG : BROWSER_OUTPUT: RSS: Main: 165347328 05:48:57 INFO - 2015-11-24 05:48:57,960 DEBUG : BROWSER_OUTPUT: 05:48:59 INFO - 2015-11-24 05:48:59,758 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37282/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:49:00 INFO - 2015-11-24 05:49:00,359 DEBUG : BROWSER_OUTPUT: RSS: Main: 162897920 05:49:00 INFO - 2015-11-24 05:49:00,360 DEBUG : BROWSER_OUTPUT: 05:49:02 INFO - 2015-11-24 05:49:02,392 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37282/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:49:02 INFO - 2015-11-24 05:49:02,960 DEBUG : BROWSER_OUTPUT: RSS: Main: 170115072 05:49:02 INFO - 2015-11-24 05:49:02,960 DEBUG : BROWSER_OUTPUT: 05:49:05 INFO - 2015-11-24 05:49:05,360 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:49:05 INFO - 2015-11-24 05:49:05,946 DEBUG : BROWSER_OUTPUT: RSS: Main: 179589120 05:49:05 INFO - 2015-11-24 05:49:05,947 DEBUG : BROWSER_OUTPUT: 05:49:08 INFO - 2015-11-24 05:49:08,461 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:49:09 INFO - 2015-11-24 05:49:09,034 DEBUG : BROWSER_OUTPUT: RSS: Main: 177197056 05:49:09 INFO - 2015-11-24 05:49:09,035 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,684 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37282/tests/kraken/driver.html?testName=ai-astar) 05:49:10 INFO - 2015-11-24 05:49:10,930 DEBUG : BROWSER_OUTPUT: RSS: Main: 237174784 05:49:10 INFO - 2015-11-24 05:49:10,930 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,931 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:49:10 INFO - 2015-11-24 05:49:10,932 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:49:10 INFO - 2015-11-24 05:49:10,932 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:49:10 INFO - 2015-11-24 05:49:10,932 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:49:10 INFO - 2015-11-24 05:49:10,932 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;110;104;109;109;110;98;109;110;102 05:49:10 INFO - 2015-11-24 05:49:10,932 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;157;129;153;174;127;149;155;127;166;128 05:49:10 INFO - 2015-11-24 05:49:10,932 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;162;162;161;163;161;159;161;161;160 05:49:10 INFO - 2015-11-24 05:49:10,932 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;72;82;94;71;71;71;108;92;73 05:49:10 INFO - 2015-11-24 05:49:10,933 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;135;93;93;93;147;93;93;132 05:49:10 INFO - 2015-11-24 05:49:10,933 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;109;109;109;110;110;110;110;110;109 05:49:10 INFO - 2015-11-24 05:49:10,933 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;146;145;146;146;145;145;145;144;145 05:49:10 INFO - 2015-11-24 05:49:10,933 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;96;95;95;96;96;96;96;96 05:49:10 INFO - 2015-11-24 05:49:10,933 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;58;57;57;57;60;56;57;57;56;56 05:49:10 INFO - 2015-11-24 05:49:10,934 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;70;70;69;70;70;69;71;70;70 05:49:10 INFO - 2015-11-24 05:49:10,934 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;83;84;85;83;84;86;85;85;85 05:49:10 INFO - 2015-11-24 05:49:10,934 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;126;130;130;116;126;115;116;115;119;119 05:49:10 INFO - 2015-11-24 05:49:10,934 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;151;148;148;148;138;147;147;147;141 05:49:10 INFO - 2015-11-24 05:49:10,934 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;68;65;66;66;67;67;67;65;65 05:49:10 INFO - 2015-11-24 05:49:10,934 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:49:10 INFO - 2015-11-24 05:49:10,935 DEBUG : BROWSER_OUTPUT: __start_cc_report 05:49:10 INFO - 2015-11-24 05:49:10,935 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,941 05:49:10 INFO - 2015-11-24 05:49:10,935 DEBUG : BROWSER_OUTPUT: __end_cc_report 05:49:10 INFO - 2015-11-24 05:49:10,935 DEBUG : BROWSER_OUTPUT: __startTimestamp1448372950930__endTimestamp 05:49:10 INFO - 2015-11-24 05:49:10,935 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,939 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:49:10 INFO - 2015-11-24 05:49:10,939 DEBUG : BROWSER_OUTPUT: Number of tests: 14 05:49:10 INFO - 2015-11-24 05:49:10,939 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,939 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.10 Median:110.00 stddev:4.25 (3.9%) stddev-sans-first:4.38 05:49:10 INFO - 2015-11-24 05:49:10,939 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 104.0 109.0 109.0 110.0 98.0 109.0 110.0 102.0 05:49:10 INFO - 2015-11-24 05:49:10,940 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,940 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:146.50 Median:154.00 stddev:17.56 (11.4%) stddev-sans-first:18.21 05:49:10 INFO - 2015-11-24 05:49:10,940 DEBUG : BROWSER_OUTPUT: Values: 157.0 129.0 153.0 174.0 127.0 149.0 155.0 127.0 166.0 128.0 05:49:10 INFO - 2015-11-24 05:49:10,940 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,940 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 05:49:10 INFO - 2015-11-24 05:49:10,940 DEBUG : BROWSER_OUTPUT: Values: 161.0 162.0 162.0 161.0 163.0 161.0 159.0 161.0 161.0 160.0 05:49:10 INFO - 2015-11-24 05:49:10,941 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,941 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.70 Median:73.00 stddev:13.03 (17.8%) stddev-sans-first:13.52 05:49:10 INFO - 2015-11-24 05:49:10,941 DEBUG : BROWSER_OUTPUT: Values: 73.0 72.0 82.0 94.0 71.0 71.0 71.0 108.0 92.0 73.0 05:49:10 INFO - 2015-11-24 05:49:10,941 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,941 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.50 Median:93.00 stddev:22.06 (23.7%) stddev-sans-first:22.85 05:49:10 INFO - 2015-11-24 05:49:10,941 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 135.0 93.0 93.0 93.0 147.0 93.0 93.0 132.0 05:49:10 INFO - 2015-11-24 05:49:10,942 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,942 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:110.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 05:49:10 INFO - 2015-11-24 05:49:10,942 DEBUG : BROWSER_OUTPUT: Values: 110.0 109.0 109.0 109.0 110.0 110.0 110.0 110.0 110.0 109.0 05:49:10 INFO - 2015-11-24 05:49:10,942 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,942 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 05:49:10 INFO - 2015-11-24 05:49:10,943 DEBUG : BROWSER_OUTPUT: Values: 145.0 146.0 145.0 146.0 146.0 145.0 145.0 145.0 144.0 145.0 05:49:10 INFO - 2015-11-24 05:49:10,943 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,943 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 05:49:10 INFO - 2015-11-24 05:49:10,943 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 96.0 95.0 95.0 96.0 96.0 96.0 96.0 96.0 05:49:10 INFO - 2015-11-24 05:49:10,943 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,943 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.00 stddev:1.20 (2.1%) stddev-sans-first:1.22 05:49:10 INFO - 2015-11-24 05:49:10,943 DEBUG : BROWSER_OUTPUT: Values: 58.0 57.0 57.0 57.0 60.0 56.0 57.0 57.0 56.0 56.0 05:49:10 INFO - 2015-11-24 05:49:10,943 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,944 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:0.63 (0.9%) stddev-sans-first:0.60 05:49:10 INFO - 2015-11-24 05:49:10,944 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 70.0 69.0 70.0 70.0 69.0 71.0 70.0 70.0 05:49:10 INFO - 2015-11-24 05:49:10,944 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,944 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:85.00 stddev:0.97 (1.1%) stddev-sans-first:1.01 05:49:10 INFO - 2015-11-24 05:49:10,944 DEBUG : BROWSER_OUTPUT: Values: 84.0 83.0 84.0 85.0 83.0 84.0 86.0 85.0 85.0 85.0 05:49:10 INFO - 2015-11-24 05:49:10,944 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,944 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:121.20 Median:122.50 stddev:6.16 (5.0%) stddev-sans-first:6.28 05:49:10 INFO - 2015-11-24 05:49:10,945 DEBUG : BROWSER_OUTPUT: Values: 126.0 130.0 130.0 116.0 126.0 115.0 116.0 115.0 119.0 119.0 05:49:10 INFO - 2015-11-24 05:49:10,945 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,946 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.40 Median:147.50 stddev:4.40 (3.0%) stddev-sans-first:4.01 05:49:10 INFO - 2015-11-24 05:49:10,946 DEBUG : BROWSER_OUTPUT: Values: 139.0 151.0 148.0 148.0 148.0 138.0 147.0 147.0 147.0 141.0 05:49:10 INFO - 2015-11-24 05:49:10,946 DEBUG : BROWSER_OUTPUT: 05:49:10 INFO - 2015-11-24 05:49:10,946 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:1.03 (1.6%) stddev-sans-first:1.09 05:49:10 INFO - 2015-11-24 05:49:10,946 DEBUG : BROWSER_OUTPUT: Values: 66.0 68.0 65.0 66.0 66.0 67.0 67.0 67.0 65.0 65.0 05:49:10 INFO - 2015-11-24 05:49:10,946 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:49:10 INFO - 2015-11-24 05:49:10,946 DEBUG : BROWSER_OUTPUT: 05:49:11 INFO - 2015-11-24 05:49:11,739 INFO : Browser exited with error code: 0 05:49:11 INFO - 2015-11-24 05:49:11,788 INFO : Completed test kraken (00:00:58) 05:49:11 INFO - 2015-11-24 05:49:11,788 INFO : Starting test v8_7 05:49:11 INFO - 2015-11-24 05:49:11,788 DEBUG : operating with platform_type : linux_ 05:49:11 INFO - 2015-11-24 05:49:11,788 INFO : Initialising browser for v8_7 test... 05:49:11 INFO - 2015-11-24 05:49:11,794 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBulPGi/profile http://localhost:37282/getInfo.html 05:49:15 INFO - 2015-11-24 05:49:15,386 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:49:15 INFO - 2015-11-24 05:49:15,386 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:49:15 INFO - 2015-11-24 05:49:15,386 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:49:15 INFO - 2015-11-24 05:49:15,386 DEBUG : BROWSER_OUTPUT: __metrics 05:49:15 INFO - 2015-11-24 05:49:15,490 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:49:16 INFO - 2015-11-24 05:49:16,714 INFO : Browser initialized. 05:49:16 INFO - 2015-11-24 05:49:16,715 INFO : Running cycle 1/1 for v8_7 test... 05:49:16 INFO - 2015-11-24 05:49:16,715 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpBulPGi/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:49:28 INFO - 2015-11-24 05:49:28,447 DEBUG : BROWSER_OUTPUT: RSS: Main: 140963840 05:49:28 INFO - 2015-11-24 05:49:28,447 DEBUG : BROWSER_OUTPUT: 05:49:49 INFO - 2015-11-24 05:49:49,836 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37282/tests/v8_7/run.html (next: http://localhost:37282/tests/v8_7/run.html) 05:49:50 INFO - 2015-11-24 05:49:50,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 568406016 05:49:50 INFO - 2015-11-24 05:49:50,094 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-11-24 05:49:50,094 DEBUG : BROWSER_OUTPUT: __start_tp_report 05:49:50 INFO - 2015-11-24 05:49:50,095 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 05:49:50 INFO - 2015-11-24 05:49:50,095 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 05:49:50 INFO - 2015-11-24 05:49:50,095 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 05:49:50 INFO - 2015-11-24 05:49:50,095 DEBUG : BROWSER_OUTPUT: |0;Richards;164.50074025333114 05:49:50 INFO - 2015-11-24 05:49:50,095 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;193.7984496124031 05:49:50 INFO - 2015-11-24 05:49:50,096 DEBUG : BROWSER_OUTPUT: |2;Encrypt;284.7380410022779 05:49:50 INFO - 2015-11-24 05:49:50,096 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5143.589743589743 05:49:50 INFO - 2015-11-24 05:49:50,096 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1118.5682326621925 05:49:50 INFO - 2015-11-24 05:49:50,096 DEBUG : BROWSER_OUTPUT: |5;Earley;788.6435331230284 05:49:50 INFO - 2015-11-24 05:49:50,096 DEBUG : BROWSER_OUTPUT: |6;Boyer;12370.37037037037 05:49:50 INFO - 2015-11-24 05:49:50,097 DEBUG : BROWSER_OUTPUT: |7;RegExp;26256.410256410258 05:49:50 INFO - 2015-11-24 05:49:50,097 DEBUG : BROWSER_OUTPUT: |8;Splay;592.0663114268798 05:49:50 INFO - 2015-11-24 05:49:50,097 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4854.368932038835 05:49:50 INFO - 2015-11-24 05:49:50,097 DEBUG : BROWSER_OUTPUT: __end_tp_report 05:49:50 INFO - 2015-11-24 05:49:50,097 DEBUG : BROWSER_OUTPUT: __startTimestamp1448372990087__endTimestamp 05:49:50 INFO - 2015-11-24 05:49:50,098 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-11-24 05:49:50,098 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 05:49:50 INFO - 2015-11-24 05:49:50,098 DEBUG : BROWSER_OUTPUT: Number of tests: 10 05:49:50 INFO - 2015-11-24 05:49:50,098 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-11-24 05:49:50,099 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.50 Median:164.50 stddev:0.00 (0.0%) 05:49:50 INFO - 2015-11-24 05:49:50,099 DEBUG : BROWSER_OUTPUT: Values: 164.5 05:49:50 INFO - 2015-11-24 05:49:50,099 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-11-24 05:49:50,099 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:193.80 Median:193.80 stddev:0.00 (0.0%) 05:49:50 INFO - 2015-11-24 05:49:50,099 DEBUG : BROWSER_OUTPUT: Values: 193.8 05:49:50 INFO - 2015-11-24 05:49:50,099 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-11-24 05:49:50,099 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:284.74 Median:284.74 stddev:0.00 (0.0%) 05:49:50 INFO - 2015-11-24 05:49:50,100 DEBUG : BROWSER_OUTPUT: Values: 284.7 05:49:50 INFO - 2015-11-24 05:49:50,100 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-11-24 05:49:50,100 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5143.59 Median:5143.59 stddev:0.00 (0.0%) 05:49:50 INFO - 2015-11-24 05:49:50,100 DEBUG : BROWSER_OUTPUT: Values: 5143.6 05:49:50 INFO - 2015-11-24 05:49:50,100 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-11-24 05:49:50,100 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1118.57 Median:1118.57 stddev:0.00 (0.0%) 05:49:50 INFO - 2015-11-24 05:49:50,100 DEBUG : BROWSER_OUTPUT: Values: 1118.6 05:49:50 INFO - 2015-11-24 05:49:50,100 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-11-24 05:49:50,100 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:788.64 Median:788.64 stddev:0.00 (0.0%) 05:49:50 INFO - 2015-11-24 05:49:50,100 DEBUG : BROWSER_OUTPUT: Values: 788.6 05:49:50 INFO - 2015-11-24 05:49:50,101 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-11-24 05:49:50,101 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12370.37 Median:12370.37 stddev:0.00 (0.0%) 05:49:50 INFO - 2015-11-24 05:49:50,101 DEBUG : BROWSER_OUTPUT: Values: 12370.4 05:49:50 INFO - 2015-11-24 05:49:50,101 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-11-24 05:49:50,101 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26256.41 Median:26256.41 stddev:0.00 (0.0%) 05:49:50 INFO - 2015-11-24 05:49:50,101 DEBUG : BROWSER_OUTPUT: Values: 26256.4 05:49:50 INFO - 2015-11-24 05:49:50,101 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-11-24 05:49:50,101 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:592.07 Median:592.07 stddev:0.00 (0.0%) 05:49:50 INFO - 2015-11-24 05:49:50,101 DEBUG : BROWSER_OUTPUT: Values: 592.1 05:49:50 INFO - 2015-11-24 05:49:50,101 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-11-24 05:49:50,102 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4854.37 Median:4854.37 stddev:0.00 (0.0%) 05:49:50 INFO - 2015-11-24 05:49:50,102 DEBUG : BROWSER_OUTPUT: Values: 4854.4 05:49:50 INFO - 2015-11-24 05:49:50,102 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 05:49:50 INFO - 2015-11-24 05:49:50,102 DEBUG : BROWSER_OUTPUT: 05:49:50 INFO - 2015-11-24 05:49:50,923 INFO : Browser exited with error code: 0 05:49:50 INFO - 2015-11-24 05:49:50,940 INFO : Completed test v8_7 (00:00:39) 05:49:51 INFO - 2015-11-24 05:49:51,101 INFO : Completed test suite (00:20:11) 05:49:51 INFO - 2015-11-24 05:49:51,101 DEBUG : Working with test: dromaeo_css 05:49:51 INFO - 2015-11-24 05:49:51,101 DEBUG : Generating results file: dromaeo_css 05:49:51 INFO - 2015-11-24 05:49:51,102 DEBUG : Working with test: kraken 05:49:51 INFO - 2015-11-24 05:49:51,102 DEBUG : Generating results file: kraken 05:49:51 INFO - 2015-11-24 05:49:51,103 INFO : javascript benchmark 05:49:51 INFO - 2015-11-24 05:49:51,103 DEBUG : Working with test: v8_7 05:49:51 INFO - 2015-11-24 05:49:51,103 DEBUG : Generating results file: v8_7 05:49:51 INFO - 2015-11-24 05:49:51,103 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:49:51 INFO - 2015-11-24 05:49:51,162 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,63,35]] 05:49:51 INFO - 2015-11-24 05:49:51,162 DEBUG : process_Request line: dromaeo_css 6739.43 graph.html#tests=[[72,63,35]] 05:49:51 INFO - 2015-11-24 05:49:51,163 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:49:51 INFO - 2015-11-24 05:49:51,193 DEBUG : process_Request line: kraken 1496.60 graph.html#tests=[[232,63,35]] 05:49:51 INFO - 2015-11-24 05:49:51,194 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 05:49:51 INFO - 2015-11-24 05:49:51,223 DEBUG : process_Request line: v8_7 20940.38 graph.html#tests=[[230,63,35]] 05:49:51 INFO - 2015-11-24 05:49:51,227 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3163.2408222026716, 3236.9028929132132, 3280.885169019085, 3283.4089863824515, 3295.2915123248495, 5079.466656001122, 5209.088409528327, 5307.575393968483, 5383.488568585988, 5418.997485465186, 5233.921491177635, 5308.973455132719, 5384.461553844613, 5413.052715774744, 5439.972800136004, 4915.2627105934125, 4943.604511639078, 4950.381202349707, 4964.0816448956875, 5009.398872135331, 4964.4787297333805, 5091.847244582668, 5094.1085310070775, 5101.51535604117, 5142.023015627019, 4704.576588107053, 4813.3261343411605, 4818.7831547580445, 5008.29883816266, 5032.748362581875, 4921.753912304371, 4932.531409516126, 4943.357363542752, 4961.751912404366, 4971.801127954877, 4878.5365390288225, 4915.213565829486, 4918.655694101414, 4932.186189278765, 4963.1810751226, 4968.925466118011, 5068.0, 5093.337866077423, 5219.634625576211, 5281.287026251445, 5737.655740655532, 5743.856403589902, 5756.0, 5759.769609215627, 5767.36558978512, 5751.309842818889, 5755.906377788207, 5757.769689212427, 5763.538916886639, 5765.711714414305, 5326.307580014612, 5381.865453363697, 5392.4337944515655, 5413.106837371829, 5438.347398312228, 5831.970840145772, 5852.385499522574, 5873.442023007808, 5897.67562784051, 5901.291844978544, 5446.782128714807, 5555.694436805934, 5600.27196464457, 5664.773409063674, 5666.518345940559, 5766.336871259775, 5770.2498675171955, 5770.42295770428, 5781.335146458127, 5815.505682017076, 5678.432156784371, 5683.346415162309, 5746.4253574643035, 5751.1085781703905, 5751.367349591542, 5452.618316717871, 5552.944470555323, 5657.971710141505, 5754.712264386764, 5798.536117110705, 4508.1659892919915, 4510.594046535827, 4512.774361281989, 4532.682712210114, 4552.044070745114, 3993.4808474898077, 3997.8600748973645, 4008.198360327946, 4038.0510580013674, 4042.717009809285, 5275.103232450492, 5389.730513474311, 5449.346078470529, 5553.194786755978, 5593.105103183471, 5818.127280907813, 5834.883302333929, 5840.737166827503, 5863.384344643836, 5867.735951882176, 5261.76322065508, 5558.888822223533, 5570.637908535932, 5575.749091290821, 5576.749046292926, 4972.4033116025585, 5026.145555255615, 5034.16936205522, 5038.874028149252, 5073.619478539124, 6062.969685151546, 6092.5430592705725, 6093.36019717941, 6095.999999999911, 6100.084987251859, 5529.502344788988, 5629.493345598916, 5822.359540450631, 5825.0679891217205, 5830.416958304136, 5876.764929402776, 5881.882362352643, 5951.672658003678, 5965.850853728692, 6021.066734656135, 5671.347795003462, 5716.685582293014, 5719.656820590778, 5724.227229323988, 5726.513246374105, 5612.915806262991, 5730.885382292414, 5824.621399609012, 5835.270591176103, 5837.153612726216, 5573.526250268773, 5625.915611265752, 5640.266765320482, 5640.830775076754, 5643.661380317194, 5624.015797235549, 5626.7902400983985, 5629.8592535186945, 5630.887382252414, 5636.0, 5480.451954804487, 5530.585206109477, 5533.142362933751, 5533.972330138324, 5594.02104631696, 5697.344805347272, 5711.657300561813, 5713.571482138939, 5713.9142912855505, 5775.075987841925, 5575.414581468887, 5586.972065139648, 5599.272094627672, 5602.411746766694, 5606.495415412633], "unit": "ms", "name": "dojo.html", "value": 5327.67112954332}, {"lowerIsBetter": false, "replicates": [556.6075916478883, 607.5139888089527, 610.4109534299402, 610.8533951851557, 615.7352338494447, 29105.563416548743, 39271.0, 40278.59721402785, 40407.19185616286, 40486.59513404865, 42432.57567424325, 42715.35926961098, 42847.35728964068, 43051.78474107633, 43053.7847310763, 7664.156942736322, 7751.22487751226, 8697.826043479143, 8850.911490885073, 8857.158569935846, 10516.894831051668, 10769.192310576698, 10789.83815242772, 10796.298240614375, 10828.566857325697, 10487.318324308933, 10620.256582039261, 10621.41582212978, 10635.0, 10642.78714425711, 7559.659815308324, 7687.423443241779, 10437.269391142623, 10534.157267418665, 10778.892211077868, 10373.740656483573, 10459.895401045967, 10460.947695261475, 10474.738131546772, 10481.161507079416, 39950.80024599859, 39981.40027899584, 40264.0, 40480.19039619221, 40637.39043914343, 3069.493533566959, 3264.983675081633, 3918.337362195559, 4011.2980228460196, 4028.237546364622, 29559.261018474495, 29714.655000994146, 30227.09318720442, 30306.393872122655, 30332.0, 3738.140227747606, 3822.5412950446125, 3826.579076301605, 3830.597787232356, 3855.61443855615, 272785.0, 272885.0, 274170.999999998, 274704.62647686835, 274734.62632686907, 56951.73530911597, 57179.42820571824, 57673.13490297649, 57753.71123144357, 57821.42178578245, 58875.0, 59356.70321648451, 59423.70288148532, 59511.70244148838, 59645.0, 14096.50662226817, 14198.219097949504, 14248.287585620677, 14256.073355232083, 14300.713985720227, 9621.422714637145, 9652.806943861084, 9656.082672146134, 9665.613375465044, 9707.90292097084, 18020.189091490913, 18567.25730970773, 18612.162452689663, 18620.348287809862, 18688.813111868978, 7494.700211991569, 7661.348785353196, 7717.421193410517, 7744.1094274159195, 7788.9911463768785, 931.6925414613297, 992.0575453319375, 1009.3590569988111, 1052.6684094510358, 1055.3931489393628, 27960.44079118406, 28134.29664258369, 28271.43457130846, 28514.71485285162, 28805.85597072001, 975.6048800235916, 1081.432248069754, 1125.6285425809626, 1150.0593915718764, 1180.8464899563003, 14301.499447519287, 14378.85621143796, 14456.349464274133, 14721.116732996055, 14801.0, 305175.0, 307347.0, 310518.0, 310539.15686745004, 310980.0, 43062.78468607637, 43701.78149109234, 50972.235416468786, 54557.45442545603, 55608.721956389956, 1330.8070329802124, 1343.8387393512842, 1355.1327150623413, 1355.4442678501964, 1356.803263526783, 26254.737452625992, 27220.183394498195, 27355.86322068377, 32105.35789284248, 33672.0, 47962.760186198844, 48134.759326203144, 48301.0, 48566.5143348562, 48642.270365943834, 275411.0, 284080.0, 284585.0, 284660.0, 285254.0, 42108.789456052524, 42852.35721464118, 42861.0, 43128.13743725045, 43370.0, 50341.49658503368, 51593.74203128961, 51626.0, 52395.21407178819, 53091.734541327045, 38605.22789544137, 39468.2106357877, 39911.60088399079, 40143.0, 40498.0, 39007.414888776126, 39530.802345988086, 39567.36280715768, 39680.60319396769, 39952.40071398873, 38150.23699525939, 38412.2317553653, 38850.0, 39180.80409598048, 39253.60746392499, 32911.67088329086, 33537.329253415286, 35307.470387943686, 37557.248855023296, 39055.41416878692, 26580.734192657826, 26998.0, 27691.72308276972, 28285.29286767765, 28543.857280713462], "unit": "ms", "name": "ext.html", "value": 19143.921877013152}, {"lowerIsBetter": false, "replicates": [351.1414591324212, 353.8993729501249, 355.3443896011858, 355.63902638821594, 359.37827558324096, 16632.750508742378, 16645.83354166458, 16891.746623800624, 17080.743788843145, 17134.65730685387, 10809.0, 11098.278611890222, 16064.116473593947, 16280.104594247312, 16300.510984670449, 4790.880227994302, 4898.828541001066, 4904.509549045085, 4908.067467181242, 4912.164931961557, 4407.206702793496, 4869.707817530942, 5440.700761458118, 5452.4274951130155, 5455.0, 3743.232637309359, 3850.4031875059272, 5405.162199859008, 5410.08028635131, 5431.0, 5355.758990845421, 5477.534409575191, 5524.779008839641, 5526.834194974157, 5535.750891209906, 5152.175651895716, 5262.394824595144, 5269.341332333458, 5292.708901010443, 5298.125809241471, 10772.784544309148, 11038.503267352988, 12610.936945315214, 19158.0, 19258.518537036685, 2127.5213077057692, 2148.924787632441, 2160.470684682263, 2165.6426689596196, 2172.6306527890297, 9094.45433273999, 9269.351145419823, 9803.509824508747, 11611.518690422736, 12344.95067919228, 1778.510909499885, 1797.0565453137076, 1802.2971041293906, 1828.4880233534632, 1845.8338749512475, 58699.41300587025, 61355.0, 61715.0, 62171.68914155445, 62190.0, 10117.898821011842, 10170.338927969658, 10175.338602990782, 10216.131628811634, 10242.897571024194, 12202.389880506116, 12231.816522752166, 12233.204841685261, 12283.447244874002, 12586.81119783204, 7054.08296921397, 7216.494845360774, 7259.455540834353, 7268.127824660969, 7329.5968721719755, 6889.793306200822, 6892.138482689664, 6902.792916212522, 6918.06606108179, 6918.515703900679, 4380.846670366522, 4415.072834704683, 4432.202203603382, 4445.044315472189, 4471.977640111779, 1784.2595322941095, 1805.2417984446563, 1812.7099664053687, 1846.759921210261, 1863.2546981207624, 386.7969316109009, 405.49920847753003, 445.24975416422785, 452.38701559386556, 987.3582171588525, 10653.9467302663, 10711.143108551298, 10793.02862742357, 11236.381998989971, 11285.322880627024, 431.09900308355685, 431.3594312446078, 443.14694213638614, 446.5021501026332, 449.03905641926303, 5175.870603234949, 6173.135760993465, 6246.937530624727, 6328.778492752732, 6427.132337134521, 498692.0, 510393.0, 513008.0, 515374.0, 537139.0, 70873.29126708666, 70909.2909070913, 72278.0, 75318.0, 78154.21845781583, 484.8157700073949, 497.8954419571858, 498.7331777499093, 498.81045202822696, 499.1364938656073, 10262.897371026194, 10389.740256493647, 10425.687229383131, 10569.365838049742, 10593.735156621146, 35075.12312192113, 35537.28925421425, 35942.460863087595, 35956.4606530897, 36091.639083608825, 46082.539174607824, 46613.0, 46965.295520566535, 47004.529954701386, 47250.76374618243, 8881.689140880038, 9067.229285510806, 9069.682561110329, 9092.772680683036, 9136.132067453582, 17460.563485912953, 17608.38370657021, 17714.91142544279, 17828.55428614295, 17989.73015404796, 14280.0, 14337.856621433652, 14754.852451475777, 14853.702925941205, 14857.108573485626, 14088.577342679737, 14218.289085545888, 14385.42458301698, 14736.0, 14881.032732872329, 14082.859171408154, 14756.188409637143, 14831.703365932837, 14896.553103406915, 15084.321205545997, 8086.11052784205, 8365.28895043928, 9424.858627120462, 9532.189763870148, 9663.46850923206, 10040.646376524413, 10055.69832905014, 10155.441450719985, 14546.85453145455, 14933.626659333604], "unit": "ms", "name": "jquery.html", "value": 7827.604321418652}, {"lowerIsBetter": false, "replicates": [1004.2819384140347, 1664.4673704414588, 1690.8478236958672, 1703.7018521758696, 1720.707479728446, 5914.792982245623, 6917.204521480031, 7133.251008644095, 7152.892706609405, 7154.213036565973, 7066.222715501291, 7217.206107328189, 7223.061002069737, 7231.67457464414, 7237.348638622523, 1907.9523011924673, 1943.3004118517392, 2187.5187458759046, 2207.0730293276824, 2240.350298413458, 2220.6335954567476, 2235.8099561537288, 2236.0, 2239.1715065425815, 2242.7981481666648, 2124.2034237161065, 2186.0709198590534, 2186.6392045312505, 2194.078487035441, 2195.8572692774997, 1914.5117994911277, 1964.067068142628, 2178.3900507857825, 2223.032980653421, 2230.163688616769, 2158.751743549496, 2187.55155193185, 2198.6482162854027, 2205.8676479411124, 2210.624193887027, 7026.1217347831525, 7067.399271061914, 7073.221945585981, 7079.185893622248, 7081.92918070823, 4379.562043795627, 4435.778211089432, 4436.645068394553, 4481.103779244132, 4481.775911204427, 2948.292409821649, 3010.5785190073407, 3041.4525385430616, 3042.543618457227, 3043.117495926179, 950.4154944708954, 966.0725703324816, 989.9406035637812, 994.0159242350094, 995.2386424385351, 9702.61189552417, 9718.465484398355, 9729.805403891882, 9731.367461115005, 9735.464549449778, 3949.3681011038097, 4033.4353190553347, 4052.2098190852503, 4078.225137223977, 4090.427340172378, 8447.577621118919, 8477.237048665527, 8478.957605212057, 8482.660693572312, 8497.575121244037, 4478.776061196927, 4482.529334419839, 4516.638668906479, 4521.412216411911, 4530.388397566287, 4112.506499220112, 4118.0, 4118.938215926763, 4126.566710495415, 4131.380292956081, 3520.718342532591, 3573.2496175802853, 3588.8026158561006, 3589.892303230907, 3601.4777857210556, 1206.1315852586124, 1208.456194712383, 1223.473906220334, 1231.8029115341503, 1236.4312416288608, 871.4248595926658, 1251.8310028146266, 1263.9873601264053, 1268.77796385634, 1297.6236891301605, 5874.530037596982, 6718.294579069127, 6892.896606550906, 6903.378695917391, 6908.585484870924, 593.1991811055127, 597.238520885874, 605.8818530386533, 635.2440595691113, 636.5671343486474, 5384.111621582473, 5415.593830462731, 5416.187571864173, 5430.42980487051, 5431.049566325877, 8400.327973762085, 8635.0, 8670.393072484992, 8680.392372533863, 8687.826243475096, 8513.914860851437, 8571.314294856396, 8647.092055334226, 8659.956700216459, 8663.95668021656, 6510.3489651034515, 6655.534112612071, 6700.55937973338, 6763.053172555748, 6765.52641315113, 6561.081448597105, 6587.176602924635, 6634.170728658918, 6644.4020038196795, 6650.866982660416, 7523.360514356122, 7622.847543049219, 7629.504082234637, 7775.92224077752, 7777.377809775091, 7846.450748447553, 7874.094479134973, 7888.171741967011, 8017.398695098008, 8023.558704271321, 6350.714217860115, 6429.0, 6492.902606460812, 6576.868462630816, 6600.438962688226, 6885.586864787929, 6946.617936013366, 6977.302269772982, 6980.581165130108, 6981.79054628362, 6545.607263564011, 6562.376574225441, 6601.174853143357, 6631.801045968628, 6675.198976122896, 6655.767048153485, 6673.299303573055, 6727.183586147407, 6727.327267273234, 6763.458923286224, 5478.780848766005, 5604.915926261027, 6593.241777195684, 6649.202095748541, 6652.0, 5705.914411283917, 5828.6211396259105, 5876.118582212703, 5898.7935422261735, 5900.43945825146, 5933.673647949232, 5982.49148822355, 5999.760009599567, 6000.129981152621, 6008.5493587981955], "unit": "ms", "name": "mootools.html", "value": 4167.223216973251}, {"lowerIsBetter": false, "replicates": [311.9287928157036, 319.286394907382, 324.8668046101099, 325.19676399293746, 327.74763432157255, 5813.970930145354, 5847.327557330908, 5878.177055212268, 5884.823455296342, 5944.821655350346, 5720.22776925115, 5834.533237341013, 5836.824895253149, 5839.824805255849, 5864.0, 4334.241507736149, 4458.799354029076, 4581.88545286368, 4583.977080114595, 4585.908281834361, 4486.147631949935, 4554.886127846814, 4562.0, 4584.7478388688605, 4586.1974154522995, 4512.0, 4525.027119169378, 4533.977330113345, 4537.886552836172, 4541.545845415465, 4500.774961251925, 4535.841245556389, 4547.1587756265035, 4548.8862778430475, 4554.635629149677, 3819.5416550013897, 4006.659433948118, 4007.198560287954, 4018.1361007383553, 4024.3158663027357, 5567.554595632339, 5575.442455754433, 5584.0, 5591.329040515124, 5605.159226116074, 5223.503767142153, 5229.2417599448245, 5239.449857764956, 5242.6330156889035, 5243.318368612056, 5951.2858456985, 6087.3303936566945, 6097.756089756404, 6100.542459315525, 6237.313895471494, 4051.0682543015273, 4155.231282212801, 4189.518205406356, 4239.576042395766, 4272.636825869805, 6411.1024456576115, 6442.323556026643, 6479.870402591921, 6497.675116244216, 6508.837279068014, 4388.605025547652, 4391.890202744957, 4393.51671316155, 4394.956050439519, 4401.0, 4441.733495990186, 4445.711028783118, 4447.0, 4451.198784218807, 4455.598996090367, 5089.655787143607, 5137.8715532112, 5162.328897243335, 5178.3785945686, 5187.948120518822, 6249.78125765596, 6251.906221406683, 6253.843653908598, 6262.0, 6264.154339164245, 1567.2085596773557, 1584.168311636377, 1594.593378688427, 1595.2980688497023, 1596.3454983456727, 1040.1314902056868, 1060.5863713151878, 1062.5484169228048, 1063.9042486176281, 1087.6954452753241, 5970.194023806731, 6099.542534309944, 6103.725332360054, 6110.358412366726, 6110.7861224856915, 6363.9045414318825, 6437.420632143129, 6526.934730652632, 6545.770898018546, 6547.083408322838, 6096.847578810565, 6150.50795936324, 6168.19813424252, 6171.6297022178815, 6175.907361389583, 3490.7905525668543, 3503.211777350127, 3532.4171511700797, 3534.770239934396, 3544.326577950181, 6619.602823830585, 6754.0881980931945, 6762.526623136431, 6780.627065511444, 6840.623765692934, 6660.200775906922, 6686.4985126115735, 6698.22970358415, 6700.128983232149, 6707.496937729592, 6657.567258128206, 6826.829329266709, 6837.863242735217, 6841.897371539431, 6843.8973415398805, 6516.185476815398, 6519.315471875574, 6523.804285871432, 6524.9673751630935, 6531.615960174597, 6435.4529864962005, 6443.871122577616, 6447.290798012121, 6449.806505804833, 6454.19322584677, 6645.800625981228, 6811.625360605214, 6815.0, 6815.216250131299, 6817.386435220854, 4447.243968525294, 4450.799714012813, 4469.865904022885, 4472.642188624967, 4490.865274041784, 6457.321981191907, 6500.5449618526245, 6504.056911747864, 6513.250976137615, 6515.381038801306, 6332.52506062038, 6335.90496142549, 6336.429721324918, 6337.556371053982, 6343.11196432509, 6184.536159787944, 6306.558540902093, 6321.1782468278825, 6327.430531252209, 6329.8734025320155, 6206.565540412128, 6220.626762394271, 6221.875562488815, 6222.439980401786, 6222.937770622417, 6320.5891617044745, 6324.3991820776955, 6328.588641738273, 6335.909149980587, 6343.904841427475, 6099.634021958696, 6237.12680224778, 6240.500759939285, 6247.562670613195, 6250.749970001149], "unit": "ms", "name": "prototype.html", "value": 4732.484185679864}, {"lowerIsBetter": false, "replicates": [713.7971031734229, 791.9802004949879, 826.2274773087164, 845.1210740829536, 845.2308399356587, 12265.264084154946, 12545.247285162897, 12545.874541254585, 12556.81164782529, 12559.434825432854, 14610.269486525658, 14908.254587270647, 14935.253237338144, 14945.402183912658, 14977.700445991073, 675.5642610516238, 829.6681327469015, 846.6951897316961, 847.9321654267644, 848.2620120495178, 683.9042534045237, 821.2362502872327, 838.7106448275335, 839.0979696825906, 840.3949156607233, 867.8264347130568, 870.1994165367845, 877.4165180155189, 877.934154938379, 878.547548012774, 711.0542977839461, 722.458156382713, 838.7986883148001, 868.8783570300163, 870.0907551608584, 629.971651275689, 630.2059405149499, 630.4200135875006, 631.9241690997064, 633.6483251795252, 13331.466741330432, 13445.932770336183, 13449.596512104652, 13450.192988420726, 13452.596422107255, 11074.944625276903, 11581.478833452431, 11668.416579171093, 11814.586489472827, 11900.940495297555, 9655.662051828152, 9668.371555848847, 9699.757506062335, 10044.945280745489, 10318.565065224288, 3527.753057285991, 3576.678098971079, 3604.711623070148, 3650.2699460108083, 3684.6683798458266, 47189.764051179525, 47506.76246618779, 47564.52435475636, 47605.761971190266, 47628.7618561905, 9546.522673866279, 9666.95166524163, 10159.746006349753, 10183.287169898036, 10421.635242766466, 15981.680366392608, 16036.518904432885, 16045.839541604668, 16060.839391606169, 16062.0, 6996.790096297119, 7113.92886071143, 7252.927470725331, 7265.164506081763, 7303.671334789948, 3963.801809909493, 3980.8805735827973, 4032.6370626643743, 4081.2449696806184, 4149.97925010373, 2858.113984664719, 2897.5219088850527, 2898.9565156522667, 2904.2158617173245, 2908.0548821633056, 526.0819869328047, 535.2666846420351, 538.3701069748404, 542.926704894842, 544.1511242461772, 508.9033133597104, 567.4779203133128, 583.5914859598213, 591.4617697894895, 615.7167702856647, 7362.0, 7493.85012299751, 7572.621368931532, 7689.077310722749, 7711.884321735179, 479.35342099887, 503.5015334818577, 514.2594663684283, 524.5200641413064, 592.1443514122138, 3579.7494175407473, 3587.121155316964, 3669.119411341312, 3701.481792549045, 3715.294094122108, 56546.717266413405, 57516.0, 57641.13538296929, 57701.71149144227, 57968.71015644895, 49770.50229497731, 50678.49321506738, 51616.48383516192, 52262.73868630632, 52294.0, 46312.30531542029, 46683.0, 47430.0, 47434.28848567274, 47562.28656570154, 7282.235365286569, 7381.704731810667, 7442.255774422731, 7539.962300188464, 7632.084149902047, 33901.49147762835, 34659.4801077979, 34682.653173467945, 34753.13117172091, 35065.64934350624, 42333.57666423296, 46404.30393544167, 46601.766991164826, 46797.29804052874, 47031.294530581385, 9218.28725019293, 9242.168204861326, 9304.488253146143, 9703.805923881624, 9754.85367719499, 9786.608535658494, 10240.53917573696, 10292.382457052601, 10343.120834729132, 10460.843087353544, 9455.621775129193, 9643.807123857623, 9719.708408747749, 10122.696319110439, 10133.08802207805, 9245.676401325922, 9357.39176953523, 9378.249740020918, 9427.481488518197, 9784.168345690412, 9616.663416780379, 9885.308028437941, 10042.397456152654, 10091.596336146766, 10095.44475053879, 6731.0, 8826.779330516789, 9244.95377523108, 9358.625654973725, 9453.810923781348, 8467.703630372907, 8504.914950850412, 8644.0, 8925.196732294125, 9056.366054376394], "unit": "ms", "name": "yui.html", "value": 5950.661859877986}], "name": "dromaeo_css"}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 110.0, 104.0, 109.0, 109.0, 110.0, 98.0, 109.0, 110.0, 102.0], "unit": "ms", "name": "ai-astar", "value": 107.1}, {"lowerIsBetter": true, "replicates": [157.0, 129.0, 153.0, 174.0, 127.0, 149.0, 155.0, 127.0, 166.0, 128.0], "unit": "ms", "name": "audio-beat-detection", "value": 146.5}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 162.0, 161.0, 163.0, 161.0, 159.0, 161.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 161.1}, {"lowerIsBetter": true, "replicates": [73.0, 72.0, 82.0, 94.0, 71.0, 71.0, 71.0, 108.0, 92.0, 73.0], "unit": "ms", "name": "audio-fft", "value": 80.7}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 135.0, 93.0, 93.0, 93.0, 147.0, 93.0, 93.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 106.5}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 109.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0, 144.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.2}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.8}, {"lowerIsBetter": true, "replicates": [58.0, 57.0, 57.0, 57.0, 60.0, 56.0, 57.0, 57.0, 56.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 57.1}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 70.0, 69.0, 70.0, 70.0, 69.0, 71.0, 70.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.8}, {"lowerIsBetter": true, "replicates": [84.0, 83.0, 84.0, 85.0, 83.0, 84.0, 86.0, 85.0, 85.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 84.4}, {"lowerIsBetter": true, "replicates": [126.0, 130.0, 130.0, 116.0, 126.0, 115.0, 116.0, 115.0, 119.0, 119.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 121.2}, {"lowerIsBetter": true, "replicates": [139.0, 151.0, 148.0, 148.0, 148.0, 138.0, 147.0, 147.0, 147.0, 141.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 145.4}, {"lowerIsBetter": true, "replicates": [66.0, 68.0, 65.0, 66.0, 66.0, 67.0, 67.0, 67.0, 65.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 66.2}], "name": "kraken"}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.50074025333114], "unit": "score", "name": "Richards", "value": 214.60085799999993}, {"lowerIsBetter": false, "replicates": [193.7984496124031], "unit": "score", "name": "DeltaBlue", "value": 341.16888000000006}, {"lowerIsBetter": false, "replicates": [284.7380410022779], "unit": "score", "name": "Encrypt", "value": 934.8276720000003}, {"lowerIsBetter": false, "replicates": [5143.589743589743], "unit": "score", "name": "Decrypt", "value": 51.75004486540379}, {"lowerIsBetter": false, "replicates": [1118.5682326621925], "unit": "score", "name": "RayTrace", "value": 661.550166}, {"lowerIsBetter": false, "replicates": [788.6435331230284], "unit": "score", "name": "Earley", "value": 845.075084}, {"lowerIsBetter": false, "replicates": [12370.37037037037], "unit": "score", "name": "Boyer", "value": 53.87575149700595}, {"lowerIsBetter": false, "replicates": [26256.410256410258], "unit": "score", "name": "RegExp", "value": 34.69571777343749}, {"lowerIsBetter": false, "replicates": [592.0663114268798], "unit": "score", "name": "Splay", "value": 137.63829900000002}, {"lowerIsBetter": false, "replicates": [4854.368932038835], "unit": "score", "name": "NavierStokes", "value": 305.7040000000001}], "name": "v8_7"}]} 05:49:51 INFO - RETURN: dromaeo_css: 6739.43 05:49:51 INFO - RETURN: kraken: 1496.60 05:49:51 INFO - RETURN: v8_7: 20940.38 05:49:51 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,35]]", "result": "20940.38"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,35]]", "result": "1496.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,35]]", "result": "6739.43"}}} 05:49:51 INFO - Return code: 0 05:49:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 05:49:51 INFO - # TBPL SUCCESS # 05:49:51 INFO - Running post-action listener: _resource_record_post_action 05:49:51 INFO - Running post-run listener: _resource_record_post_run 05:49:52 INFO - Total resource usage - Wall time: 1226s; CPU: 13.0%; Read bytes: 8192; Write bytes: 499122176; Read time: 68; Write time: 435192 05:49:52 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 222498816; Read time: 0; Write time: 265092 05:49:52 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 8192; Write bytes: 265457664; Read time: 68; Write time: 167840 05:49:52 INFO - Running post-run listener: _upload_blobber_files 05:49:52 INFO - Blob upload gear active. 05:49:52 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 05:49:52 INFO - Copying logs to upload dir... 05:49:52 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1273.274661 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2015-11-24 05:49:52.008219) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:49:52.011427) ========= 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=6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009993 build_url: 'https://queue.taskcluster.net/v1/task/jX3jfUgNRF-Ig3ddlUz_Sg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-24 05:49:52.063237) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:49:52.063596) ========= 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=6b42339bb4b96cfc3b1ae7e5000001a3-1448369430.644023-1786252258 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004684 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-24 05:49:52.114137) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 05:49:52.114483) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-24 05:49:52.114792) ========= ========= Total master_lag: 0.25 =========