builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-065 starttime: 1453432164.76 results: success (0) buildid: 20160121163024 builduid: 08790f546a1649b2bf9651b10a56843d revision: 632c8912c742a43ed6deb4153759a7113f3c051a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:24.762560) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:24.763141) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:24.763492) ========= 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=523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.004345 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:24.783491) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:24.783820) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:24.796182) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:24.796644) ========= 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=523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004414 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:24.847312) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:24.847682) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:24.848118) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:24.848452) ========= 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=523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-21 19:09:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 217M=0s 2016-01-21 19:09:25 (217 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.165882 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:25.031056) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:25.031807) ========= 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=523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015861 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:25.086112) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:25.086800) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 632c8912c742a43ed6deb4153759a7113f3c051a --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 632c8912c742a43ed6deb4153759a7113f3c051a --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=523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-21 19:09:25,318 truncating revision to first 12 chars 2016-01-21 19:09:25,318 Setting DEBUG logging. 2016-01-21 19:09:25,318 attempt 1/10 2016-01-21 19:09:25,318 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/632c8912c742?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-21 19:09:25,571 unpacking tar archive at: mozilla-inbound-632c8912c742/testing/mozharness/ program finished with exit code 0 elapsedTime=0.556862 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:25.658204) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:25.658615) ========= echo 632c8912c742a43ed6deb4153759a7113f3c051a in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'632c8912c742a43ed6deb4153759a7113f3c051a'] 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=523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 632c8912c742a43ed6deb4153759a7113f3c051a program finished with exit code 0 elapsedTime=0.004642 script_repo_revision: '632c8912c742a43ed6deb4153759a7113f3c051a' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:25.712491) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:25.712986) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-21 19:09:25.723885) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-01-21 19:09:25.724365) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --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', '--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=523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:09:26 INFO - MultiFileLogger online at 20160121 19:09:26 in /builds/slave/test-pgo 19:09:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 19:09:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 19:09:26 INFO - {'append_to_log': False, 19:09:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 19:09:26 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 19:09:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 19:09:26 INFO - 'branch': 'Mozilla-Inbound', 19:09:26 INFO - 'buildbot_json_path': 'buildprops.json', 19:09:26 INFO - 'config_files': ('talos/linux_config.py',), 19:09:26 INFO - 'default_actions': ('clobber', 19:09:26 INFO - 'read-buildbot-config', 19:09:26 INFO - 'download-and-extract', 19:09:26 INFO - 'populate-webroot', 19:09:26 INFO - 'create-virtualenv', 19:09:26 INFO - 'install', 19:09:26 INFO - 'run-tests'), 19:09:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:09:26 INFO - 'download_minidump_stackwalk': True, 19:09:26 INFO - 'download_symbols': 'ondemand', 19:09:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:09:26 INFO - 'tooltool.py': '/tools/tooltool.py', 19:09:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:09:26 INFO - '/tools/misc-python/virtualenv.py')}, 19:09:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:09:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:09:26 INFO - 'installer_path': 'installer.exe', 19:09:26 INFO - 'log_level': 'info', 19:09:26 INFO - 'log_name': 'talos', 19:09:26 INFO - 'log_to_console': True, 19:09:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:09:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:09:26 INFO - 'opt_config_files': (), 19:09:26 INFO - 'pip_index': False, 19:09:26 INFO - 'sps_profile': False, 19:09:26 INFO - 'sps_profile_interval': 0, 19:09:26 INFO - 'suite': 'dromaeojs', 19:09:26 INFO - 'system_bits': '32', 19:09:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:09:26 INFO - 'title': 'talos-linux64-ix-065', 19:09:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:09:26 INFO - 'use_talos_json': True, 19:09:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 19:09:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:09:26 INFO - 'work_dir': 'build'} 19:09:26 INFO - ##### 19:09:26 INFO - ##### Running clobber step. 19:09:26 INFO - ##### 19:09:26 INFO - Running pre-action listener: _resource_record_pre_action 19:09:26 INFO - Running main action method: clobber 19:09:26 INFO - rmtree: /builds/slave/test-pgo/build 19:09:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 19:09:26 INFO - Running post-action listener: _resource_record_post_action 19:09:26 INFO - ##### 19:09:26 INFO - ##### Running read-buildbot-config step. 19:09:26 INFO - ##### 19:09:26 INFO - Running pre-action listener: _resource_record_pre_action 19:09:26 INFO - Running main action method: read_buildbot_config 19:09:26 INFO - Using buildbot properties: 19:09:26 INFO - { 19:09:26 INFO - "project": "", 19:09:26 INFO - "product": "firefox", 19:09:26 INFO - "script_repo_revision": "production", 19:09:26 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 19:09:26 INFO - "repository": "", 19:09:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 19:09:26 INFO - "buildid": "20160121163024", 19:09:26 INFO - "pgo_build": "True", 19:09:26 INFO - "basedir": "/builds/slave/test-pgo", 19:09:26 INFO - "buildnumber": 629, 19:09:26 INFO - "slavename": "talos-linux64-ix-065", 19:09:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:09:26 INFO - "platform": "ubuntu64_hw", 19:09:26 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 19:09:26 INFO - "branch": "mozilla-inbound", 19:09:26 INFO - "repo_path": "integration/mozilla-inbound", 19:09:26 INFO - "revision": "632c8912c742a43ed6deb4153759a7113f3c051a", 19:09:26 INFO - "stage_platform": "linux64-pgo", 19:09:26 INFO - "builduid": "08790f546a1649b2bf9651b10a56843d", 19:09:26 INFO - "slavebuilddir": "test" 19:09:26 INFO - } 19:09:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 19:09:26 INFO - Running post-action listener: _resource_record_post_action 19:09:26 INFO - ##### 19:09:26 INFO - ##### Running download-and-extract step. 19:09:26 INFO - ##### 19:09:26 INFO - Running pre-action listener: _resource_record_pre_action 19:09:26 INFO - Running main action method: download_and_extract 19:09:26 INFO - mkdir: /builds/slave/test-pgo/build/tests 19:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:26 INFO - https://queue.taskcluster.net/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 19:09:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/test_packages.json 19:09:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/test_packages.json 19:09:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 19:09:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 19:09:27 INFO - Downloaded 1448 bytes. 19:09:27 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 19:09:27 INFO - Using the following test package requirements: 19:09:27 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 19:09:27 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:09:27 INFO - u'gtest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:09:27 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:27 INFO - u'jsshell-linux-x86_64.zip'], 19:09:27 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:09:27 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 19:09:27 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:09:27 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:09:27 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:09:27 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 19:09:27 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 19:09:27 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:09:27 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:09:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:27 INFO - https://queue.taskcluster.net/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:09:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 19:09:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 19:09:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 19:09:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:09:28 INFO - Downloaded 22486750 bytes. 19:09:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 19:09:28 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 19:09:29 INFO - Return code: 0 19:09:29 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:29 INFO - https://queue.taskcluster.net/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:09:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 19:09:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 19:09:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 19:09:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:09:30 INFO - Downloaded 22486750 bytes. 19:09:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 19:09:30 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 19:09:31 INFO - Return code: 0 19:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:31 INFO - https://queue.taskcluster.net/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:09:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 19:09:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 19:09:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 19:09:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:09:32 INFO - Downloaded 10705515 bytes. 19:09:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 19:09:32 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 19:09:32 INFO - Return code: 0 19:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:32 INFO - https://queue.taskcluster.net/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:09:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 19:09:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 19:09:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 19:09:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:09:34 INFO - Downloaded 55969773 bytes. 19:09:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 19:09:34 INFO - mkdir: /builds/slave/test-pgo/properties 19:09:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 19:09:34 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 19:09:34 INFO - Contents: 19:09:34 INFO - build_url:https://queue.taskcluster.net/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 19:09:34 INFO - Running post-action listener: _resource_record_post_action 19:09:34 INFO - Running post-action listener: set_extra_try_arguments 19:09:34 INFO - ##### 19:09:34 INFO - ##### Running populate-webroot step. 19:09:34 INFO - ##### 19:09:34 INFO - Running pre-action listener: _resource_record_pre_action 19:09:34 INFO - Running main action method: populate_webroot 19:09:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:09:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:09:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:09:34 INFO - '--tppagecycles', 19:09:34 INFO - '7'], 19:09:34 INFO - 'tests': ['tsvgm']}}, 19:09:34 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 19:09:34 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 19:09:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:09:34 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:09:34 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 19:09:34 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 19:09:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:34 INFO - 'talos_options': ['--e10s'], 19:09:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:09:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:34 INFO - 'tests': ['damp', 'tps']}, 19:09:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:34 INFO - 'talos_options': ['--e10s'], 19:09:34 INFO - 'tests': ['damp', 'tps']}, 19:09:34 INFO - 'g3': {'tests': ['dromaeo_dom']}, 19:09:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:09:34 INFO - 'other': {'tests': ['a11yr', 19:09:34 INFO - 'ts_paint', 19:09:34 INFO - 'tpaint', 19:09:34 INFO - 'sessionrestore', 19:09:34 INFO - 'sessionrestore_no_auto_restore']}, 19:09:34 INFO - 'other-e10s': {'talos_options': ['--e10s'], 19:09:34 INFO - 'tests': ['a11yr', 19:09:34 INFO - 'ts_paint', 19:09:34 INFO - 'tpaint', 19:09:34 INFO - 'sessionrestore', 19:09:34 INFO - 'sessionrestore_no_auto_restore']}, 19:09:34 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 19:09:34 INFO - 'tests': ['a11yr', 19:09:34 INFO - 'ts_paint', 19:09:34 INFO - 'tpaint', 19:09:34 INFO - 'sessionrestore', 19:09:34 INFO - 'sessionrestore_no_auto_restore']}, 19:09:34 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 19:09:34 INFO - 'tests': ['a11yr', 19:09:34 INFO - 'ts_paint', 19:09:34 INFO - 'tpaint', 19:09:34 INFO - 'sessionrestore', 19:09:34 INFO - 'sessionrestore_no_auto_restore']}, 19:09:34 INFO - 'other_l64': {'tests': ['a11yr', 19:09:34 INFO - 'ts_paint', 19:09:34 INFO - 'tpaint', 19:09:34 INFO - 'sessionrestore', 19:09:34 INFO - 'sessionrestore_no_auto_restore']}, 19:09:34 INFO - 'other_nol64': {'tests': ['a11yr', 19:09:34 INFO - 'ts_paint', 19:09:34 INFO - 'tpaint', 19:09:34 INFO - 'sessionrestore', 19:09:34 INFO - 'sessionrestore_no_auto_restore']}, 19:09:34 INFO - 'svgr': {'tests': ['tsvgx', 19:09:34 INFO - 'tsvgr_opacity', 19:09:34 INFO - 'tart', 19:09:34 INFO - 'tscrollx', 19:09:34 INFO - 'cart']}, 19:09:34 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 19:09:34 INFO - 'tests': ['tsvgx', 19:09:34 INFO - 'tsvgr_opacity', 19:09:34 INFO - 'tart', 19:09:34 INFO - 'tscrollx', 19:09:34 INFO - 'cart']}, 19:09:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:34 INFO - 'tests': ['tp5o']}, 19:09:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:34 INFO - 'talos_options': ['--e10s'], 19:09:34 INFO - 'tests': ['tp5o']}, 19:09:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:34 INFO - 'talos_options': ['--xperf_path', 19:09:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:34 INFO - 'tests': ['tp5n']}, 19:09:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:09:34 INFO - 'talos_options': ['--e10s', 19:09:34 INFO - '--xperf_path', 19:09:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:09:34 INFO - 'tests': ['tp5n']}}, 19:09:34 INFO - 'talos.zip': {'path': '', 19:09:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:09:34 INFO - Running post-action listener: _resource_record_post_action 19:09:34 INFO - ##### 19:09:34 INFO - ##### Running create-virtualenv step. 19:09:34 INFO - ##### 19:09:34 INFO - Running pre-action listener: _resource_record_pre_action 19:09:34 INFO - Running main action method: create_virtualenv 19:09:34 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 19:09:34 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 19:09:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 19:09:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:09:34 INFO - Using real prefix '/usr' 19:09:34 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 19:09:35 INFO - Installing distribute.............................................................................................................................................................................................done. 19:09:37 INFO - Installing pip.................done. 19:09:37 INFO - Return code: 0 19:09:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 19:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:37 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2faf29df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2814480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a3660>, '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 0x28a4560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a10>, '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': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', '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 19:09:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 19:09:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:09:37 INFO - Using env: {'DISPLAY': ':0', 19:09:37 INFO - 'HOME': '/home/cltbld', 19:09:37 INFO - 'LANG': 'en_US.UTF-8', 19:09:37 INFO - 'LANGUAGE': 'en_US:en', 19:09:37 INFO - 'LOGNAME': 'cltbld', 19:09:37 INFO - 'MAIL': '/var/mail/cltbld', 19:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:37 INFO - 'MOZ_NO_REMOTE': '1', 19:09:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:37 INFO - 'NO_EM_RESTART': '1', 19:09:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:09:37 INFO - 'PWD': '/builds/slave/test-pgo', 19:09:37 INFO - 'SHELL': '/bin/bash', 19:09:37 INFO - 'SHLVL': '1', 19:09:37 INFO - 'TERM': 'linux', 19:09:37 INFO - 'TMOUT': '86400', 19:09:37 INFO - 'USER': 'cltbld', 19:09:37 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', 19:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:37 INFO - '_': '/tools/buildbot/bin/python'} 19:09:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:37 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 19:09:37 INFO - Cleaning up... 19:09:37 INFO - Return code: 0 19:09:37 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 19:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:37 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2faf29df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2814480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a3660>, '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 0x28a4560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a10>, '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': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', '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 19:09:37 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 19:09:37 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 19:09:37 INFO - Using env: {'DISPLAY': ':0', 19:09:37 INFO - 'HOME': '/home/cltbld', 19:09:37 INFO - 'LANG': 'en_US.UTF-8', 19:09:37 INFO - 'LANGUAGE': 'en_US:en', 19:09:37 INFO - 'LOGNAME': 'cltbld', 19:09:37 INFO - 'MAIL': '/var/mail/cltbld', 19:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:37 INFO - 'MOZ_NO_REMOTE': '1', 19:09:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:37 INFO - 'NO_EM_RESTART': '1', 19:09:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:09:37 INFO - 'PWD': '/builds/slave/test-pgo', 19:09:37 INFO - 'SHELL': '/bin/bash', 19:09:37 INFO - 'SHLVL': '1', 19:09:37 INFO - 'TERM': 'linux', 19:09:37 INFO - 'TMOUT': '86400', 19:09:37 INFO - 'USER': 'cltbld', 19:09:37 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', 19:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:37 INFO - '_': '/tools/buildbot/bin/python'} 19:09:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:37 INFO - Downloading/unpacking psutil>=0.7.1 19:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:37 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 19:09:37 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 19:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:39 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 19:09:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:39 INFO - Installing collected packages: psutil 19:09:39 INFO - Running setup.py install for psutil 19:09:39 INFO - building 'psutil._psutil_linux' extension 19:09:39 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 19:09:39 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 19:09:39 INFO - building 'psutil._psutil_posix' extension 19:09:39 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 19:09:39 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 19:09:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:09:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:09:39 INFO - Successfully installed psutil 19:09:39 INFO - Cleaning up... 19:09:39 INFO - Return code: 0 19:09:39 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 19:09: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')]} 19:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09: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')]} 19:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:39 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2faf29df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2814480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a3660>, '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 0x28a4560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a10>, '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': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', '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 19:09:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 19:09:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 19:09:39 INFO - Using env: {'DISPLAY': ':0', 19:09:39 INFO - 'HOME': '/home/cltbld', 19:09:39 INFO - 'LANG': 'en_US.UTF-8', 19:09:39 INFO - 'LANGUAGE': 'en_US:en', 19:09:39 INFO - 'LOGNAME': 'cltbld', 19:09:39 INFO - 'MAIL': '/var/mail/cltbld', 19:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:39 INFO - 'MOZ_NO_REMOTE': '1', 19:09:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:39 INFO - 'NO_EM_RESTART': '1', 19:09:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:09:39 INFO - 'PWD': '/builds/slave/test-pgo', 19:09:39 INFO - 'SHELL': '/bin/bash', 19:09:39 INFO - 'SHLVL': '1', 19:09:39 INFO - 'TERM': 'linux', 19:09:39 INFO - 'TMOUT': '86400', 19:09:39 INFO - 'USER': 'cltbld', 19:09:39 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', 19:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:39 INFO - '_': '/tools/buildbot/bin/python'} 19:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:40 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:09:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:09:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:09:41 INFO - Installing collected packages: mozsystemmonitor 19:09:41 INFO - Running setup.py install for mozsystemmonitor 19:09:42 INFO - Successfully installed mozsystemmonitor 19:09:42 INFO - Cleaning up... 19:09:42 INFO - Return code: 0 19:09:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 19:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:42 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2faf29df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2814480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a3660>, '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 0x28a4560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a10>, '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': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', '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 19:09:42 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 19:09:42 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:09:42 INFO - Using env: {'DISPLAY': ':0', 19:09:42 INFO - 'HOME': '/home/cltbld', 19:09:42 INFO - 'LANG': 'en_US.UTF-8', 19:09:42 INFO - 'LANGUAGE': 'en_US:en', 19:09:42 INFO - 'LOGNAME': 'cltbld', 19:09:42 INFO - 'MAIL': '/var/mail/cltbld', 19:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:42 INFO - 'MOZ_NO_REMOTE': '1', 19:09:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:42 INFO - 'NO_EM_RESTART': '1', 19:09:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:09:42 INFO - 'PWD': '/builds/slave/test-pgo', 19:09:42 INFO - 'SHELL': '/bin/bash', 19:09:42 INFO - 'SHLVL': '1', 19:09:42 INFO - 'TERM': 'linux', 19:09:42 INFO - 'TMOUT': '86400', 19:09:42 INFO - 'USER': 'cltbld', 19:09:42 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', 19:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:42 INFO - '_': '/tools/buildbot/bin/python'} 19:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:42 INFO - Downloading/unpacking blobuploader==1.2.4 19:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:42 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 19:09:42 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 19:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:44 INFO - Downloading blobuploader-1.2.4.tar.gz 19:09:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:09:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:44 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 19:09:44 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 19:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 19:09:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:44 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 19:09:44 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 19:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:44 INFO - Downloading docopt-0.6.1.tar.gz 19:09:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 19:09:44 INFO - Installing collected packages: blobuploader, requests, docopt 19:09:44 INFO - Running setup.py install for blobuploader 19:09:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:09:44 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 19:09:44 INFO - Running setup.py install for requests 19:09:45 INFO - Running setup.py install for docopt 19:09:45 INFO - Successfully installed blobuploader requests docopt 19:09:45 INFO - Cleaning up... 19:09:45 INFO - Return code: 0 19:09:45 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:45 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2faf29df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2814480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a3660>, '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 0x28a4560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a10>, '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': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', '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 19:09:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 19:09:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:09:45 INFO - Using env: {'DISPLAY': ':0', 19:09:45 INFO - 'HOME': '/home/cltbld', 19:09:45 INFO - 'LANG': 'en_US.UTF-8', 19:09:45 INFO - 'LANGUAGE': 'en_US:en', 19:09:45 INFO - 'LOGNAME': 'cltbld', 19:09:45 INFO - 'MAIL': '/var/mail/cltbld', 19:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:45 INFO - 'MOZ_NO_REMOTE': '1', 19:09:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:45 INFO - 'NO_EM_RESTART': '1', 19:09:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:09:45 INFO - 'PWD': '/builds/slave/test-pgo', 19:09:45 INFO - 'SHELL': '/bin/bash', 19:09:45 INFO - 'SHLVL': '1', 19:09:45 INFO - 'TERM': 'linux', 19:09:45 INFO - 'TMOUT': '86400', 19:09:45 INFO - 'USER': 'cltbld', 19:09:45 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', 19:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:45 INFO - '_': '/tools/buildbot/bin/python'} 19:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:09:45 INFO - Running setup.py (path:/tmp/pip-Rker9Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:09:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:09:45 INFO - Running setup.py (path:/tmp/pip-a34afo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:09:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:09:45 INFO - Running setup.py (path:/tmp/pip-gzIdqT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:09:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:09:45 INFO - Running setup.py (path:/tmp/pip-E9JzuG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:09:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:09:45 INFO - Running setup.py (path:/tmp/pip-9WRwmO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:09:45 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:09:46 INFO - Running setup.py (path:/tmp/pip-CiyTnV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:09:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:09:46 INFO - Running setup.py (path:/tmp/pip-o7fJTG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:09:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:09:46 INFO - Running setup.py (path:/tmp/pip-OS20Oy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:09:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:09:46 INFO - Running setup.py (path:/tmp/pip-MqnGVM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:09:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:09:46 INFO - Running setup.py (path:/tmp/pip-b7fjOi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:09:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:09:46 INFO - Running setup.py (path:/tmp/pip-_uPt5q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:09:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:09:46 INFO - Running setup.py (path:/tmp/pip-CqfaOp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:09:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:09:46 INFO - Running setup.py (path:/tmp/pip-KkcxQD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:09:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:09:46 INFO - Running setup.py (path:/tmp/pip-86rY0G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:09:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:09:46 INFO - Running setup.py (path:/tmp/pip-fzTbSz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:09:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:09:46 INFO - Running setup.py (path:/tmp/pip-xmf4su-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:09:46 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:09:46 INFO - Running setup.py (path:/tmp/pip-HHH_AR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:09:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:09:46 INFO - Running setup.py install for manifestparser 19:09:46 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 19:09:46 INFO - Running setup.py install for mozcrash 19:09:47 INFO - Running setup.py install for mozdebug 19:09:47 INFO - Running setup.py install for mozdevice 19:09:47 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 19:09:47 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 19:09:47 INFO - Running setup.py install for mozfile 19:09:47 INFO - Running setup.py install for mozhttpd 19:09:47 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 19:09:47 INFO - Running setup.py install for mozinfo 19:09:47 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 19:09:47 INFO - Running setup.py install for mozInstall 19:09:47 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 19:09:47 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 19:09:47 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 19:09:47 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 19:09:47 INFO - Running setup.py install for mozleak 19:09:47 INFO - Running setup.py install for mozlog 19:09:47 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 19:09:47 INFO - Running setup.py install for moznetwork 19:09:47 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 19:09:47 INFO - Running setup.py install for mozprocess 19:09:48 INFO - Running setup.py install for mozprofile 19:09:48 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 19:09:48 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 19:09:48 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 19:09:48 INFO - Running setup.py install for mozrunner 19:09:48 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 19:09:48 INFO - Running setup.py install for mozscreenshot 19:09:48 INFO - Running setup.py install for moztest 19:09:48 INFO - Running setup.py install for mozversion 19:09:48 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 19:09:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:09:48 INFO - Cleaning up... 19:09:48 INFO - Return code: 0 19:09:48 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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 0x7f2faf29df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2814480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a3660>, '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 0x28a4560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a10>, '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': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', '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 19:09:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 19:09:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:09:48 INFO - Using env: {'DISPLAY': ':0', 19:09:48 INFO - 'HOME': '/home/cltbld', 19:09:48 INFO - 'LANG': 'en_US.UTF-8', 19:09:48 INFO - 'LANGUAGE': 'en_US:en', 19:09:48 INFO - 'LOGNAME': 'cltbld', 19:09:48 INFO - 'MAIL': '/var/mail/cltbld', 19:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:48 INFO - 'MOZ_NO_REMOTE': '1', 19:09:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:48 INFO - 'NO_EM_RESTART': '1', 19:09:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:09:48 INFO - 'PWD': '/builds/slave/test-pgo', 19:09:48 INFO - 'SHELL': '/bin/bash', 19:09:48 INFO - 'SHLVL': '1', 19:09:48 INFO - 'TERM': 'linux', 19:09:48 INFO - 'TMOUT': '86400', 19:09:48 INFO - 'USER': 'cltbld', 19:09:48 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', 19:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:48 INFO - '_': '/tools/buildbot/bin/python'} 19:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:09:48 INFO - Running setup.py (path:/tmp/pip-O5g3dM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 19:09:48 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)) 19:09:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:09:48 INFO - Running setup.py (path:/tmp/pip-oIhaYy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 19:09:48 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)) 19:09:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:09:48 INFO - Running setup.py (path:/tmp/pip-LtSi9h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 19:09:49 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:09:49 INFO - Running setup.py (path:/tmp/pip-99_Klw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 19:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 19:09:49 INFO - Running setup.py (path:/tmp/pip-s5FUGC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 19:09:49 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:09:49 INFO - Running setup.py (path:/tmp/pip-8Grrf6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 19:09:49 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:09:49 INFO - Running setup.py (path:/tmp/pip-aZPeic-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 19:09:49 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:09:49 INFO - Running setup.py (path:/tmp/pip-6kXHUA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 19:09:49 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 19:09:49 INFO - Running setup.py (path:/tmp/pip-ZczK1_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 19:09:49 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 19:09:49 INFO - Running setup.py (path:/tmp/pip-iy2wyL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 19:09:49 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:09:49 INFO - Running setup.py (path:/tmp/pip-UlikXu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 19:09:49 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:09:49 INFO - Running setup.py (path:/tmp/pip-CrO4bm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 19:09:49 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:09:49 INFO - Running setup.py (path:/tmp/pip-qIqvf9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 19:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:09:49 INFO - Running setup.py (path:/tmp/pip-20WgyE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 19:09:49 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:09:49 INFO - Running setup.py (path:/tmp/pip-tc1jpp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 19:09:49 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 19:09:49 INFO - Running setup.py (path:/tmp/pip-12i4II-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 19:09:49 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)) 19:09:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 19:09:50 INFO - Running setup.py (path:/tmp/pip-ltewwM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 19:09:50 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)) 19:09:50 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)) 19:09:50 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)) 19:09:50 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)) 19:09:50 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.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 19:09:50 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.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 19:09:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 19:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:51 INFO - Downloading blessings-1.6.tar.gz 19:09:51 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 19:09:51 INFO - Installing collected packages: blessings 19:09:51 INFO - Running setup.py install for blessings 19:09:52 INFO - Successfully installed blessings 19:09:52 INFO - Cleaning up... 19:09:52 INFO - Return code: 0 19:09:52 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 19:09:52 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:09:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:09:52 INFO - Reading from file tmpfile_stdout 19:09:52 INFO - Current package versions: 19:09:52 INFO - argparse == 1.2.1 19:09:52 INFO - blessings == 1.6 19:09:52 INFO - blobuploader == 1.2.4 19:09:52 INFO - docopt == 0.6.1 19:09:52 INFO - manifestparser == 1.1 19:09:52 INFO - mozInstall == 1.12 19:09:52 INFO - mozcrash == 0.16 19:09:52 INFO - mozdebug == 0.1 19:09:52 INFO - mozdevice == 0.48 19:09:52 INFO - mozfile == 1.2 19:09:52 INFO - mozhttpd == 0.7 19:09:52 INFO - mozinfo == 0.9 19:09:52 INFO - mozleak == 0.1 19:09:52 INFO - mozlog == 3.1 19:09:52 INFO - moznetwork == 0.27 19:09:52 INFO - mozprocess == 0.22 19:09:52 INFO - mozprofile == 0.28 19:09:52 INFO - mozrunner == 6.11 19:09:52 INFO - mozscreenshot == 0.1 19:09:52 INFO - mozsystemmonitor == 0.0 19:09:52 INFO - moztest == 0.7 19:09:52 INFO - mozversion == 1.4 19:09:52 INFO - psutil == 3.1.1 19:09:52 INFO - requests == 1.2.3 19:09:52 INFO - wsgiref == 0.1.2 19:09:52 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 19:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2faf29df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2814480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a3660>, '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 0x28a4560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a10>, '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': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', '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 19:09:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 19:09:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 19:09:52 INFO - Using env: {'DISPLAY': ':0', 19:09:52 INFO - 'HOME': '/home/cltbld', 19:09:52 INFO - 'LANG': 'en_US.UTF-8', 19:09:52 INFO - 'LANGUAGE': 'en_US:en', 19:09:52 INFO - 'LOGNAME': 'cltbld', 19:09:52 INFO - 'MAIL': '/var/mail/cltbld', 19:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:52 INFO - 'MOZ_NO_REMOTE': '1', 19:09:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:52 INFO - 'NO_EM_RESTART': '1', 19:09:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:09:52 INFO - 'PWD': '/builds/slave/test-pgo', 19:09:52 INFO - 'SHELL': '/bin/bash', 19:09:52 INFO - 'SHLVL': '1', 19:09:52 INFO - 'TERM': 'linux', 19:09:52 INFO - 'TMOUT': '86400', 19:09:52 INFO - 'USER': 'cltbld', 19:09:52 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', 19:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:52 INFO - '_': '/tools/buildbot/bin/python'} 19:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.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 1)) 19:09:52 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 2)) 19:09:52 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 3)) 19:09:52 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 4)) 19:09:52 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 5)) 19:09:52 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 6)) 19:09:52 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 7)) 19:09:52 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 8)) 19:09:52 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 9)) 19:09:52 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.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 19:09:52 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 4)) 19:09:52 INFO - Cleaning up... 19:09:52 INFO - Return code: 0 19:09:52 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 19:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2faf29df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2814480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a3660>, '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 0x28a4560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a10>, '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': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', '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 19:09:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 19:09:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:09:52 INFO - Using env: {'DISPLAY': ':0', 19:09:52 INFO - 'HOME': '/home/cltbld', 19:09:52 INFO - 'LANG': 'en_US.UTF-8', 19:09:52 INFO - 'LANGUAGE': 'en_US:en', 19:09:52 INFO - 'LOGNAME': 'cltbld', 19:09:52 INFO - 'MAIL': '/var/mail/cltbld', 19:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:52 INFO - 'MOZ_NO_REMOTE': '1', 19:09:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:52 INFO - 'NO_EM_RESTART': '1', 19:09:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:09:52 INFO - 'PWD': '/builds/slave/test-pgo', 19:09:52 INFO - 'SHELL': '/bin/bash', 19:09:52 INFO - 'SHLVL': '1', 19:09:52 INFO - 'TERM': 'linux', 19:09:52 INFO - 'TMOUT': '86400', 19:09:52 INFO - 'USER': 'cltbld', 19:09:52 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', 19:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:52 INFO - '_': '/tools/buildbot/bin/python'} 19:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:52 INFO - Downloading/unpacking jsonschema 19:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:52 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 19:09:52 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 19:09:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:09:54 INFO - Downloading/unpacking functools32 (from jsonschema) 19:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:54 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 19:09:54 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 19:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:54 INFO - Downloading functools32-3.2.3-2.tar.gz 19:09:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 19:09:54 INFO - warning: no files found matching '*.txt' 19:09:54 INFO - no previously-included directories found matching 'build' 19:09:54 INFO - no previously-included directories found matching 'dist' 19:09:54 INFO - no previously-included directories found matching '.git*' 19:09:54 INFO - Installing collected packages: jsonschema, functools32 19:09:54 INFO - Running setup.py install for functools32 19:09:54 INFO - warning: no files found matching '*.txt' 19:09:54 INFO - no previously-included directories found matching 'build' 19:09:54 INFO - no previously-included directories found matching 'dist' 19:09:54 INFO - no previously-included directories found matching '.git*' 19:09:54 INFO - Successfully installed jsonschema functools32 19:09:54 INFO - Cleaning up... 19:09:54 INFO - Return code: 0 19:09:54 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 19:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:09:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f2faf29df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27478a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2814480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a3660>, '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 0x28a4560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a10>, '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': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', '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 19:09:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 19:09:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 19:09:54 INFO - Using env: {'DISPLAY': ':0', 19:09:54 INFO - 'HOME': '/home/cltbld', 19:09:54 INFO - 'LANG': 'en_US.UTF-8', 19:09:54 INFO - 'LANGUAGE': 'en_US:en', 19:09:54 INFO - 'LOGNAME': 'cltbld', 19:09:54 INFO - 'MAIL': '/var/mail/cltbld', 19:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:09:54 INFO - 'MOZ_NO_REMOTE': '1', 19:09:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:09:54 INFO - 'NO_EM_RESTART': '1', 19:09:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:09:54 INFO - 'PWD': '/builds/slave/test-pgo', 19:09:54 INFO - 'SHELL': '/bin/bash', 19:09:54 INFO - 'SHLVL': '1', 19:09:54 INFO - 'TERM': 'linux', 19:09:54 INFO - 'TMOUT': '86400', 19:09:54 INFO - 'USER': 'cltbld', 19:09:54 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', 19:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:09:54 INFO - '_': '/tools/buildbot/bin/python'} 19:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:09:55 INFO - Downloading/unpacking flake8 19:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:55 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 19:09:55 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 19:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:09:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:56 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 19:09:56 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 19:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:09:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:57 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 19:09:57 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 19:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:57 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 19:09:57 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 19:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:09:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 19:09:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:09:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:09:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:09:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:09:57 INFO - no previously-included directories found matching 'docs/_build' 19:09:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:09:57 INFO - Running setup.py install for pep8 19:09:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:09:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:09:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:09:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:09:57 INFO - no previously-included directories found matching 'docs/_build' 19:09:57 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 19:09:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:09:57 INFO - Cleaning up... 19:09:57 INFO - Return code: 0 19:09:57 INFO - Running post-action listener: _resource_record_post_action 19:09:57 INFO - Running post-action listener: _start_resource_monitoring 19:09:57 INFO - Starting resource monitoring. 19:09:57 INFO - ##### 19:09:57 INFO - ##### Running install step. 19:09:57 INFO - ##### 19:09:57 INFO - Running pre-action listener: _resource_record_pre_action 19:09:57 INFO - Running main action method: install 19:09:57 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 19:09:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 19:09:58 INFO - Reading from file tmpfile_stdout 19:09:58 INFO - Detecting whether we're running mozinstall >=1.0... 19:09:58 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 19:09:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 19:09:58 INFO - Reading from file tmpfile_stdout 19:09:58 INFO - Output received: 19:09:58 INFO - Usage: mozinstall [options] installer 19:09:58 INFO - Options: 19:09:58 INFO - -h, --help show this help message and exit 19:09:58 INFO - -d DEST, --destination=DEST 19:09:58 INFO - Directory to install application into. [default: 19:09:58 INFO - "/builds/slave/test-pgo"] 19:09:58 INFO - --app=APP Application being installed. [default: firefox] 19:09:58 INFO - mkdir: /builds/slave/test-pgo/build/application 19:09:58 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 19:09:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 19:10:14 INFO - Reading from file tmpfile_stdout 19:10:14 INFO - Output received: 19:10:14 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 19:10:14 INFO - Running post-action listener: _resource_record_post_action 19:10:14 INFO - ##### 19:10:14 INFO - ##### Running run-tests step. 19:10:14 INFO - ##### 19:10:14 INFO - Running pre-action listener: _resource_record_pre_action 19:10:14 INFO - Running main action method: run_tests 19:10:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 19:10:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 19:10:14 INFO - Python 2.7.3 19:10:14 INFO - Return code: 0 19:10:14 INFO - grabbing minidump binary from tooltool 19:10:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:10:14 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 0x28a3660>, '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 0x28a4560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28a4a10>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 19:10:14 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 19:10:14 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 19:10:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:10:14 INFO - Return code: 0 19:10:14 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 19:10:14 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 19:10:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 19:10:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 19:10:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 19:10:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 19:10:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 19:10:15 INFO - Return code: 0 19:10:15 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 19:10:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 19:10:15 INFO - Using env: {'DISPLAY': ':0', 19:10:15 INFO - 'HOME': '/home/cltbld', 19:10:15 INFO - 'LANG': 'en_US.UTF-8', 19:10:15 INFO - 'LANGUAGE': 'en_US:en', 19:10:15 INFO - 'LOGNAME': 'cltbld', 19:10:15 INFO - 'MAIL': '/var/mail/cltbld', 19:10:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:10:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 19:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:10:15 INFO - 'MOZ_NO_REMOTE': '1', 19:10:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 19:10:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:10:15 INFO - 'NO_EM_RESTART': '1', 19:10:15 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 19:10:15 INFO - 'PWD': '/builds/slave/test-pgo', 19:10:15 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 19:10:15 INFO - 'SHELL': '/bin/bash', 19:10:15 INFO - 'SHLVL': '1', 19:10:15 INFO - 'TERM': 'linux', 19:10:15 INFO - 'TMOUT': '86400', 19:10:15 INFO - 'USER': 'cltbld', 19:10:15 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767', 19:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:10:15 INFO - '_': '/tools/buildbot/bin/python'} 19:10:15 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:10:15 INFO - mozversion application_buildid: 20160121163024 19:10:15 INFO - mozversion application_changeset: 632c8912c742a43ed6deb4153759a7113f3c051a 19:10:15 INFO - mozversion application_display_name: Nightly 19:10:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:10:15 INFO - mozversion application_name: Firefox 19:10:15 INFO - mozversion application_remotingname: firefox 19:10:15 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:10:15 INFO - mozversion application_vendor: Mozilla 19:10:15 INFO - mozversion application_version: 46.0a1 19:10:15 INFO - mozversion platform_buildid: 20160121163024 19:10:15 INFO - mozversion platform_changeset: 632c8912c742a43ed6deb4153759a7113f3c051a 19:10:15 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:10:15 INFO - mozversion platform_version: 46.0a1 19:10:15 INFO - using testdate: 1453432215 19:10:15 INFO - actual date: 1453432215 19:10:15 INFO - starting webserver on 'localhost:54376' 19:10:15 INFO - SUITE-START | Running 3 tests 19:10:15 INFO - TEST-START | dromaeo_css 19:10:15 INFO - operating with platform_type : linux_ 19:10:15 INFO - Initialising browser for dromaeo_css test... 19:10:15 INFO - TEST-INFO | started process 25801 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZCr4jF/profile http://localhost:54376/getInfo.html) 19:10:20 INFO - PROCESS | 25801 | __metrics Screen width/height:1600/1200 19:10:20 INFO - PROCESS | 25801 | colorDepth:24 19:10:20 INFO - PROCESS | 25801 | Browser inner width/height: 1024/697 19:10:20 INFO - PROCESS | 25801 | __metrics 19:10:20 INFO - PROCESS | 25801 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 19:10:20 INFO - PROCESS | 25801 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:10:20 INFO - TEST-INFO | 25801: exit 0 19:10:20 INFO - Browser initialized. 19:10:20 INFO - Running cycle 1/1 for dromaeo_css test... 19:10:20 INFO - TEST-INFO | started process 25895 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZCr4jF/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:10:32 INFO - PROCESS | 25895 | RSS: Main: 150519808 19:10:32 INFO - PROCESS | 25895 | 19:11:24 INFO - PROCESS | 25895 | 1453432284316 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:13:32 INFO - PROCESS | 25895 | Cycle 1(1): loaded http://localhost:54376/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54376/tests/dromaeo/cssquery-ext.html) 19:13:33 INFO - PROCESS | 25895 | RSS: Main: 268226560 19:13:33 INFO - PROCESS | 25895 | 19:16:35 INFO - PROCESS | 25895 | Cycle 1(1): loaded http://localhost:54376/tests/dromaeo/cssquery-ext.html (next: http://localhost:54376/tests/dromaeo/cssquery-jquery.html) 19:16:36 INFO - PROCESS | 25895 | RSS: Main: 233467904 19:16:36 INFO - PROCESS | 25895 | 19:19:38 INFO - PROCESS | 25895 | Cycle 1(1): loaded http://localhost:54376/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54376/tests/dromaeo/cssquery-mootools.html) 19:19:39 INFO - PROCESS | 25895 | RSS: Main: 244043776 19:19:39 INFO - PROCESS | 25895 | 19:22:40 INFO - PROCESS | 25895 | Cycle 1(1): loaded http://localhost:54376/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54376/tests/dromaeo/cssquery-prototype.html) 19:22:41 INFO - PROCESS | 25895 | RSS: Main: 212992000 19:22:41 INFO - PROCESS | 25895 | 19:25:44 INFO - PROCESS | 25895 | Cycle 1(1): loaded http://localhost:54376/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54376/tests/dromaeo/cssquery-yui.html) 19:25:44 INFO - PROCESS | 25895 | RSS: Main: 216813568 19:25:44 INFO - PROCESS | 25895 | 19:28:47 INFO - PROCESS | 25895 | Cycle 1(1): loaded http://localhost:54376/tests/dromaeo/cssquery-yui.html (next: http://localhost:54376/tests/dromaeo/cssquery-dojo.html) 19:28:47 INFO - PROCESS | 25895 | RSS: Main: 297340928 19:28:47 INFO - PROCESS | 25895 | 19:28:47 INFO - PROCESS | 25895 | __start_tp_report 19:28:47 INFO - PROCESS | 25895 | _x_x_mozilla_page_load 19:28:47 INFO - PROCESS | 25895 | _x_x_mozilla_page_load_details 19:28:47 INFO - PROCESS | 25895 | |i|pagename|runs| 19:28:47 INFO - PROCESS | 25895 | |0;dojo.html;3063.3413816029556;3152.2277042124683;3197.7281931035877;3200.2639392939623;3226.5805445292117;4915.803367865281;4984.875378115549;5101.132807422738;5128.589712822974;5188.533032027117;5077.466865979074;5107.616928730342;5124.333836601237;5209.843704688866;5212.739363031852;4703.2239680452685;4707.999999999992;4734.810607575693;4755.714657120567;4858.805647774085;4789.497102804207;4807.951920480803;4848.2727590861305;4862.97568512157;4897.265410188465;4625;4674.041821426599;4683.836065737717;4781.043791241748;4790.928136077979;4715.31627913954;4734.479207287196;4756.381670382828;4762;4774.355462012653;4727.172744769651;4736.668433209677;4737.8341758038305;4745.454272758642;4764;4895.339129217546;4959.032988567231;4985.102681517325;5000.949990500085;5032.521910418541;5557.749901254453;5561.5272701820395;5565.165225216209;5568.1647752837;5594.272744543184;5561.721913904329;5573.191887176336;5574.219609254707;5577.2191893135;5582.497575218209;5137.332146820927;5146.768395422215;5212.296339994053;5213.817516386946;5226.5818734501145;5606.523445507096;5661.858453538612;5668.801591944262;5671.2060311556415;5696.914546281726;5462.7268636568015;5472.480114389126;5473.480019398151;5475.151351540518;5475.315585551806;5361.973190134025;5448.91826622601;5454.972725136349;5494.752736126884;5572.665640061609;5504.642198257181;5514.228008078872;5514.696691681916;5519.254900588369;5526.447355264522;5363.222332761728;5511.255980442669;5513.062779327524;5519;5571.609987300931;4229.365595160689;4277.807498662568;4279.935800962988;4311;4350.760708161081;3851.9614803851605;3857.1321452673487;3870.3613903706137;3899.8245078971513;3901.668358189586;5000.2499625056535;5130.7947682093045;5137.40919794221;5140.126178549655;5184;5481.725913704299;5497.972510137423;5504.284443022381;5541.722913854291;5557.360903496069;5132.640715149903;5146.639735218574;5147.562457191106;5147.922781158286;5149.356330458692;4712.458067322234;4717.551832575899;4719.315699223662;4756.476787553366;4803.471618122004;5628.71856407178;5689.175069614883;5716.771329146871;5733.455321744427;5803.680797556175;5481.095619222783;5489.972550137224;5490.835274941598;5491.725413729378;5498.45015498447;5665.20687103814;5667.603267771216;5673.432656734293;5678.176664383724;5678.801241956512;5271.551918086852;5406.378266499403;5453.918191227214;5537.086380747292;5567.109262517978;5494.917576236439;5511.944880551143;5532.861678458071;5546.750396232099;5559.58303127259;5417.8103766367985;5420.674759514442;5427.294451721251;5432.456754324536;5479.013777520085;5248.816291429782;5343.278657381204;5367.946320536745;5422.213779001943;5446.891062178814;5314.28257185275;5315.840524784263;5315.920261196163;5317.0960936640095;5318.282031925641;5391.191321301836;5412.891742165213;5416.160495123262;5457.808976685797;5459.235707000944;5204.63567550283;5241.213817927342;5254.5533629641905;5303.098473259478;5347.946520534745 19:28:47 INFO - PROCESS | 25895 | |1;ext.html;546.6282927609229;571.4257171542599;584.1296468262287;597.053669933156;608.7915487756802;29392.118236452912;29698.257543561394;29884;35572.64427355726;35960.82019589905;39410.8029459853;39923.800380998124;40008;40008.79995600018;40014.39978400319;8299.21157490039;8484.19400156984;8486.915130848705;8488.108748581402;8495.447795893278;8279.337652987777;8397.6221070052;8405.957970210171;8414;8414.747557573282;8594.398392112555;8628.050914399411;8637.784055398603;8643.394962352639;8659.04750477447;8524.957375213115;8599.140085991414;8612.913870861337;8613.827723445496;8620.65517379301;8497.532635705034;8605.139486051406;8613.741587752378;8636.17956294151;8643.481391116553;40160.59839401598;40346.39480407767;40550.594494054974;40642.79678601617;40653.390199147034;2911.737943585066;2960.6299212598424;2973.52423612221;2980.7764417668764;3144.3239703463637;28894.422111557862;29419.85290073536;29712.108636740715;30044.699553004408;30381.696183038108;3855.730098893079;3879.844806207748;3919.196564704229;3929.783861887595;3962.3660214365564;256416;256789;256917;257299.71350143317;257801;57268.42731572631;57956.71021644865;58048;58404.707976459846;59646.70176649089;52944;53077.7346113267;53178.73410633;53200.46799532032;53242.20136697953;14527.636809079857;14765.33555989983;14780.260986950609;14808.62978425548;14811.703765924836;10623.150147988143;10742.78514429707;11276.210665253353;11528.59649912249;11704.356260405759;19267.51831204231;19369.612607748048;19382;19402.70895936562;19522.12150453233;7123.013890276723;7319.817004574928;7418.777436676908;7503.324700776956;7583.924160758432;953.4184147669915;969.3989726369696;1029.3360782295526;1076.2358725304964;1126.6225814352283;24780;26773.196804095518;26861.731382685924;28761.856190718914;31489.685103149135;1002.3033991376069;1052.6473631333588;1055.0504545908743;1098.6154845804065;1122.3097794856105;15039.398424063134;15121.697566048837;15179.924100379427;15289;15405.537833865003;484026;484821;486490;487930;491602;50734.238986415236;51128;51372;51485.48514514807;51548.48451515437;1383.080251632673;1395.0862185268463;1401.32736286583;1419.5386499387741;1420.4957240179795;27670.308242294104;29327.26681832886;29823;30884.53673194859;31377;47084.52915470801;47473.762631186626;47525.28712069253;47539.762301188275;47628.523714763796;299490;302249;302917;302986;303472;28497.857510712314;28673.713262867106;31960.360792783547;32616;33183.33633327272;37860.81069594635;37904.81047594855;38349.808250958566;39245;41586;32946.34107317888;34358.82820585881;35708.821455892554;35783.642163579076;35902.281954361286;30275.394492110474;34093.659063409046;34619.4807077889;35967.28065438624;36028.27943441169;32915.17712057122;32998.83500582482;33113.834430827694;33207.833960830045;38131.61868381392;28648.427031458836;29593;31065;31100.533491998092;31133.221669458446;23563.64654530149;23798.762012379655;24299.63550546708;25357.619635705847;25649.743502564736 19:28:47 INFO - PROCESS | 25895 | |2;jquery.html;362.28268029301995;364.184227330779;365.3952708267817;365.48466662006575;367.80690137677715;13102.73794524109;13403.396847141878;15546.766798498018;16542.255598498068;17603.119844007797;15004.249787510635;15081.170535620564;16750.078745668983;16754.16229188542;16765.748513772272;4881.853544393656;5287.524122828944;5288.682679039251;5294.7617357219015;5296.073187192245;2939.970600293991;3910.843566257346;4024.215278020787;5231.73841307935;5559.916601250984;5713.8285851424525;5736.225609542721;5742.856428589298;5752.597318187729;5754.4533269339345;5467.125259958408;5646.604737668365;5684.204211410406;5685.4883060524335;5695;4843;5002.149634562097;5002.59979201663;5009.373828271466;5010.123228435009;12460.813087803599;12621.684457888534;13092.345382730826;13137.802932956014;13140.277284749334;2015.5666531695756;2032.7763945965933;2047.6518991771286;2074.5747121839986;2099.4856260216193;9199.67801126964;9245;10931.890681093168;11996.58011969577;12498.125131240817;1676.4635316698661;1883.359657716383;1966.72465854779;1997.680371140625;2015.3450128708228;53049.46950530523;55768.721156393956;55948.72025639886;57352.13971790426;58149.12776308316;9799.608015679436;10167.7458063549;10307.330023548446;10317.48412579368;10382.480875956171;13358.064935454619;13405.865941340462;13408.463661453432;13463.798043029363;13488.865111348958;7427.591482468516;7456.7390141344795;7596.316331530078;7657.693692252359;7663.080430348394;7141.285871412921;7239.276072392719;7306.196318405077;7353.374963128194;7355.742549010759;4445.133199026159;4555.06621142665;4560.224761790503;4563.338315944169;4568.314752787109;1718.458685514059;1741.9216135273944;1787.374418953382;1864.8041955594738;1875.4373687893851;895.892492900856;965.6137544982064;969.8836139663285;982.2191357870367;1039.5841663334727;15386.538403847902;15758.76361854573;16293.51119466418;16478.2584783685;16673.66652666964;893.0533634347612;908.4004556992353;911.7219248129384;921.2123634292717;935.6444551070549;8030.594631058991;9366.718998429922;9781.608735650636;9814.852777208347;9915.256355773347;558117.0470738264;561743;574580;588381;589863;71641.64179179176;73517.63241183867;74091;74669.62665186639;74727.28545289078;1066.8506409102731;1095.5836782022939;1097.4951522299832;1117.6255954255416;1141.8629764428156;14979.176145312113;15267.77098343548;15391.692166156838;15396.153211573022;15536.223188840288;26612.7338726618;27632.723672763015;30395;31628;34449.48325775166;37753.43369849509;37966.43050354192;38219.61780382161;39015.41476877906;39887;6990.615516146458;7098.929010709827;7169.247229041084;7263.16473605521;7385.150707668687;10219.131373833308;10605.681829545127;11043.613473528389;11216.439178041228;11236.269642473213;9509.429434233969;9581.856272155785;9592.08875156859;9686.85469717969;9828.901710983084;9265.629374825201;9487.478188699688;9597.856032159383;9700.660476883275;9890;9511.571979261089;9606;9774.609015639295;9826.459544724823;9940.353876997971;7199.784006479814;7449.4412919032375;7470.589117598584;7640.503367281109;7749.457537972288;9554.474503902353;10148.54331555067;10176.694699159038;10331.32846365014;10412.375257484575 19:28:47 INFO - PROCESS | 25895 | |3;mootools.html;1301.8763217494334;1466.0470694048868;1504.2027725305588;1659.1787065402627;1813.229377514556;6711.463082953364;7395.038644976153;7534.472586918911;7552.8867066994035;7582.4313176511705;7456.515326503773;7643.273888980538;7650.770476885702;7654.923450765491;7655.885161722578;1929.826315631593;1970.6452838489138;1973.0430741090559;2280.4754906371545;2306.8961896714686;2001.269536619138;2004.0881398963509;2212.6902233687297;2273.908523908525;2279.099755596538;2205.228170140454;2228.353777404551;2231.3306008197555;2236.13908645172;2276.4081338851934;2279.87460689662;2283.8401311908174;2287;2288.1991303043883;2288.8441337124814;2192.3861318830754;2239.9328020159423;2241.7197850268717;2241.8430709850154;2242.7196600424945;7678.232176782333;7710.460267781257;7728.149903510609;7732.729354472566;7733.535987840747;4743.24108142699;4855.441624213226;4882.218844984821;4883.34074899891;4890.755462226874;3034.544818277254;3155.763317751178;3158.1630867820045;3177.3009937813645;3178.062471570869;964.8022155458184;981.3375971219398;986.2061040862103;1019.9490025498769;1037.0873631204568;10850.511726972227;10864.402457864815;10865.3480791152;10872.402017889013;10873.130149588014;4289.399484072233;4299.333603291495;4315.244832154361;4389.166058448948;4405.29515277554;9218.354715169873;9224.723258302261;9225.815483690423;9239.907600923905;9249.075092490697;4719.433667959866;4778.904421911612;4788.1381351356395;4791.760411979387;4792.448868380111;4294.570542945743;4321.114171594815;4355.586219309161;4360.694751367438;4361.0841723237845;3397.456406974872;3727.8508859645854;3749.418840079792;3769.7738135711943;3787.1289603391374;1116.3636727065657;1119.6193294279983;1122.6239209864623;1217.5799349224503;1236.3694515797008;1311.302914943984;1311.5278499740086;1316.9144005639794;1360.4286199796109;1360.7890776929592;7342.339189472973;7346.302101300368;7356.117265928123;7373.115226172787;7378.5941773202985;1202.8436303280519;1208.8972437342925;1216.2337727231964;1217.9573714919936;1260.382412617829;5676.716164191774;5727.742251598688;5788.479036886701;5799.2750906136735;5804.303483581997;9182.86225706615;9365.672201472917;9430.669926552539;9515.096065873731;9556.522173891277;9422.057794220524;9581.281403894596;9624.51877406127;9642.469664168539;9663.758406039906;7237.601931893796;7290.708371665074;7382.704691812267;7383.483156179123;7393.630318484054;6976.720931162697;7097.25478824716;7121.359077683033;7136.143662760502;7137.10786151731;8047.275745182962;8353.164683531599;8442.620082096444;8554.486730796172;8612.65549378018;8343.874841877256;8415.990081190297;8436.831263374821;8442.071372149181;8442.957785211034;6761.087253220752;6898.551594146365;6899.965500172467;6904.861902762017;6909.654517274015;7267.963660181666;7325.523840950321;7337.413006959538;7369.410447164321;7374.299441553044;6092.725827337869;6173.783917562864;6785.491088168308;6811.7275308989065;7224.711011559479;6743.696533655899;6955;6969.895451568129;6997.580145191305;7114.964425177841;6605.174353205849;6636.734530618721;6647.102641143577;6654.933450665625;6660.6336651484635;6506.219253689587;6543.869122617616;6588.769393071411;6593.175853018372;6614.966925165343;6469.4824414045825;6517.4134327910715;6520.510961677798;6529.412352888263;6539.019147127969 19:28:47 INFO - PROCESS | 25895 | |4;prototype.html;334.05629097798715;342.5769175068791;343.833240878174;344.9758516903817;348.84302064071176;5600.103983362653;5748.655080695156;5779.19091327214;5801.796937107202;5818.359980402152;5558.86102847429;5644.830655080344;5680.772769089242;5683.460071293231;5704.172894930232;4514.593686568216;4514.887127821798;4522.321651752247;4527.547245275463;4528.25283828168;4325.870223893273;4400.933985990213;4423.093265880487;4437.866863994085;4473.4855491618555;4360.542143074979;4419.094085712437;4420.580044895746;4445.644348452132;4459.130469558438;4448.733076015434;4459.219636563588;4465.040016396458;4465.620422264095;4471.82112715491;3965.365541513373;3974.9006274843073;3976.1252524444576;3979.045029193002;3988.4017397390335;5328.680279183222;5451.100568406208;5469.863253418656;5489.890202195934;5526.226328314039;5139.563137133348;5140.614453915934;5141.30592370029;5147.8636365908105;5147.922781158286;5792.073268277098;6098.176746139259;6108.3280839107665;6109.847253818646;6120.602160859575;4127.9174416511505;4195.4336164618;4308.9353659695125;4317.956820431818;4336.067745434747;6319.33646967071;6372.044193370985;6382;6386.904196437057;6402.519811014193;4414.404055452473;4415.735055896656;4418.80115394808;4420.58004489573;4423.601875831127;4316.956830431718;4432.534583868712;4482.529334419904;4484.887877803081;4489.349044388546;5051.393832740095;5052.469490703496;5140.485951404904;5147.5881929446305;5156;6067.453929146398;6071.089336599545;6087.482563982023;6095.207623008981;6098.390160983866;1561.2896132259796;1587.8332775058452;1588.0074953154278;1588.3249618911916;1589.3880855870573;1077.9946100269606;1082.1775450657558;1083.6099004358425;1085.7014321061772;1110.7889500994786;5765.048766953406;5772.018756811352;5786.1610066540115;5788.884222315614;5792.88414231713;6309.495240380758;6315.96842015787;6316.147320111726;6321.747130114744;6326.2092238470195;5768.134779783066;5770.942290577124;5772.826815195467;5783.2192653992015;5785.595008349375;3299.802011879295;3406.114410253352;3412.505186747908;3417.333619944137;3418.4872269159328;6444.226692796894;6519.543631945813;6524.902126468107;6526.412622863965;6543.1166792483355;6438.903416448757;6441.5490915635455;6443.903341449882;6444.903326450107;6449.709763060673;6322.178116844781;6494.6103233806125;6495.512836537184;6499.480041596661;6502.252240992252;6070.271567411939;6239.282482514569;6278.6546739929745;6283.497320214463;6316.873662526816;6086.421789929949;6214.8135555931585;6235.220597425322;6264.749410023548;6267.6866156692895;6584.341565843377;6589.505787065893;6589.571677840925;6592.90110648331;6593.241777195684;4370.6284965776995;4453.933191002202;4455.844045458393;4456.799444025091;4465.240909045405;6367.140436041261;6368.617882927039;6372.171617689671;6376.936230637634;6385.265694445013;6199.5040396767245;6202.596831205957;6203.410675985774;6204.627722336674;6207.472364848858;6036.9094463582205;6188.81433556994;6192.628442293477;6194.132821404917;6197.721102550306;6030.879382412415;6054.969725151346;6071.605345652519;6073.483753880793;6077.331493535619;6219.657918814509;6226.12834203221;6227.034809604518;6230.595011324431;6230.7819226326865;5937.168796368598;6066.666333351708;6074.149619053248;6081.118237855575;6093.939060609515 19:28:47 INFO - PROCESS | 25895 | |5;yui.html;758.1205801270527;773.9883901741473;827.0695467598952;843.3421930893901;876.8509353409918;12270.141090123672;12326.81509777353;12377.752444951095;12416.503339866405;12462.252264864115;12137.757244855098;12213.084018698588;12402.751944961095;12578.119531632788;12657.683557911058;789.8973133492652;803.3091541274529;812.297362781196;812.4800127918137;813.4671789977574;799.8280369720509;809.0978558906806;809.5345176523493;809.8906647602587;810.0360570920585;839.3746658739248;840.2899549880341;842.5702891525335;843;843.1863251961855;825.8719898415725;827.230675471811;832.6419639554989;833.9583020848963;835.3526017336552;615.9260888693385;617.3517806303408;617.4350469320566;617.71276356494;617.996910015447;11172.050375718074;11190.38452885091;11191.216614836965;11233.887661123366;11237.157213209042;10795.676129716121;10854.511546980404;10856.2197092084;10944.288621239593;11048.779024419466;9475.241980641533;9663.903360966371;9669.854952175723;9727.416355018722;9975.850362244571;3394.0835974286806;3425.2122011937395;3433.4163192257374;3463.515107884898;3564.32277867207;44445;44634.33048504275;44737.32894006593;45405.318920216225;56118.719406403114;9781.559829807533;9952.900470995342;10014.39913605186;10046.14607758334;10335.741606459898;16696.49910502687;17286.308547658205;17290.04904730252;17300.740488892676;17324.826751732573;7362.595057271901;7456.888146677804;7576.659050342748;7595.620218989028;7643.235676432423;4226.4928208615165;4265.722728022669;4295.162443323522;4323;4340;2975.7173068558454;3000.7599392048583;3024.6219222597174;3040.5743195952587;3042.1786117748525;557.6681874284794;568.1931657047003;570.4352690836042;575.9049756790166;576.7001159397174;532.6910391972647;557.542814891793;557.8131326005753;611.3183800062898;662.7084083003559;7925.722599708983;8358.749237522885;8431.494110353398;8501.574921253912;8505.489670619783;537.2183473046786;571.9199312096309;602.1449541650779;609.7560975609791;618.6782872906064;3719.9442008369897;3749.4375843623675;3802.1255111324;3900.551436584772;3909.27678379495;54549.454505454436;55328.72335638296;55397;55512.16731749027;55601;42788.35817462741;48951.51048489541;49254.261186082236;49857.75071124694;49870.75064624654;39995.40006899899;42198.15603687909;42524.78737606354;42570.36144457835;43199.35200971988;8475.957620212106;8640.351973601879;8818.691345802867;8939;9065.90934090677;28938;30446.543301850048;34338;34750.82624586962;35418;43036.063377147475;43193.784031079646;43615.56384436242;43869.78065109654;44645.776771117235;10503.369797812156;10701.357918524602;11055.668329950115;11115.444227788417;11528.538858445569;12063.819042714542;12231.082668799698;12281.44733487013;12306.384680766105;12395.070369722127;10988.615398461016;11042.503087361249;11103.72240693989;11305;11945.104117191071;11204.719882003015;11644.825327619923;11689.824652630048;11838.644840654793;11964.341961192218;10677.89322106769;10681.89318106809;10755.247132700948;10986.066184374418;11086.279391839505;9310.72067837966;9342.392744471588;9405.623775048922;9612.423254604746;9882.555285012335;8708.956455217683;8856.247218986458;8919.955400223216;9025.3231007676;9470.621175153192 19:28:47 INFO - PROCESS | 25895 | __end_tp_report 19:28:47 INFO - PROCESS | 25895 | __start_cc_report 19:28:47 INFO - PROCESS | 25895 | _x_x_mozilla_cycle_collect,864 19:28:47 INFO - PROCESS | 25895 | __end_cc_report 19:28:47 INFO - PROCESS | 25895 | __startTimestamp1453433327367__endTimestamp 19:28:47 INFO - PROCESS | 25895 | 19:28:47 INFO - PROCESS | 25895 | ------- Summary: start ------- 19:28:47 INFO - PROCESS | 25895 | Number of tests: 6 19:28:47 INFO - PROCESS | 25895 | 19:28:47 INFO - PROCESS | 25895 | [#0] dojo.html Cycles:165 Average:5134.74 Median:5315.84 stddev:544.67 (10.2%) stddev-sans-first:521.53 19:28:47 INFO - PROCESS | 25895 | Values: 3063.3 3152.2 3197.7 3200.3 3226.6 4915.8 4984.9 5101.1 5128.6 5188.5 5077.5 5107.6 5124.3 5209.8 5212.7 4703.2 4708.0 4734.8 4755.7 4858.8 4789.5 4808.0 4848.3 4863.0 4897.3 4625.0 4674.0 4683.8 4781.0 4790.9 4715.3 4734.5 4756.4 4762.0 4774.4 4727.2 4736.7 4737.8 4745.5 4764.0 4895.3 4959.0 4985.1 5000.9 5032.5 5557.7 5561.5 5565.2 5568.2 5594.3 5561.7 5573.2 5574.2 5577.2 5582.5 5137.3 5146.8 5212.3 5213.8 5226.6 5606.5 5661.9 5668.8 5671.2 5696.9 5462.7 5472.5 5473.5 5475.2 5475.3 5362.0 5448.9 5455.0 5494.8 5572.7 5504.6 5514.2 5514.7 5519.3 5526.4 5363.2 5511.3 5513.1 5519.0 5571.6 4229.4 4277.8 4279.9 4311.0 4350.8 3852.0 3857.1 3870.4 3899.8 3901.7 5000.2 5130.8 5137.4 5140.1 5184.0 5481.7 5498.0 5504.3 5541.7 5557.4 5132.6 5146.6 5147.6 5147.9 5149.4 4712.5 4717.6 4719.3 4756.5 4803.5 5628.7 5689.2 5716.8 5733.5 5803.7 5481.1 5490.0 5490.8 5491.7 5498.5 5665.2 5667.6 5673.4 5678.2 5678.8 5271.6 5406.4 5453.9 5537.1 5567.1 5494.9 5511.9 5532.9 5546.8 5559.6 5417.8 5420.7 5427.3 5432.5 5479.0 5248.8 5343.3 5367.9 5422.2 5446.9 5314.3 5315.8 5315.9 5317.1 5318.3 5391.2 5412.9 5416.2 5457.8 5459.2 5204.6 5241.2 5254.6 5303.1 5347.9 19:28:47 INFO - PROCESS | 25895 | 19:28:47 INFO - PROCESS | 25895 | [#1] ext.html Cycles:180 Average:50155.47 Median:35640.73 stddev:96137.71 (269.7%) stddev-sans-first:96335.25 19:28:47 INFO - PROCESS | 25895 | Values: 546.6 571.4 584.1 597.1 608.8 29392.1 29698.3 29884.0 35572.6 35960.8 39410.8 39923.8 40008.0 40008.8 40014.4 8299.2 8484.2 8486.9 8488.1 8495.4 8279.3 8397.6 8406.0 8414.0 8414.7 8594.4 8628.1 8637.8 8643.4 8659.0 8525.0 8599.1 8612.9 8613.8 8620.7 8497.5 8605.1 8613.7 8636.2 8643.5 40160.6 40346.4 40550.6 40642.8 40653.4 2911.7 2960.6 2973.5 2980.8 3144.3 28894.4 29419.9 29712.1 30044.7 30381.7 3855.7 3879.8 3919.2 3929.8 3962.4 256416.0 256789.0 256917.0 257299.7 257801.0 57268.4 57956.7 58048.0 58404.7 59646.7 52944.0 53077.7 53178.7 53200.5 53242.2 14527.6 14765.3 14780.3 14808.6 14811.7 10623.2 10742.8 11276.2 11528.6 11704.4 19267.5 19369.6 19382.0 19402.7 19522.1 7123.0 7319.8 7418.8 7503.3 7583.9 953.4 969.4 1029.3 1076.2 1126.6 24780.0 26773.2 26861.7 28761.9 31489.7 1002.3 1052.6 1055.1 1098.6 1122.3 15039.4 15121.7 15179.9 15289.0 15405.5 484026.0 484821.0 486490.0 487930.0 491602.0 50734.2 51128.0 51372.0 51485.5 51548.5 1383.1 1395.1 1401.3 1419.5 1420.5 27670.3 29327.3 29823.0 30884.5 31377.0 47084.5 47473.8 47525.3 47539.8 47628.5 299490.0 302249.0 302917.0 302986.0 303472.0 28497.9 28673.7 31960.4 32616.0 33183.3 37860.8 37904.8 38349.8 39245.0 41586.0 32946.3 34358.8 35708.8 35783.6 35902.3 30275.4 34093.7 34619.5 35967.3 36028.3 32915.2 32998.8 33113.8 33207.8 38131.6 28648.4 29593.0 31065.0 31100.5 31133.2 23563.6 23798.8 24299.6 25357.6 25649.7 19:28:47 INFO - PROCESS | 25895 | 19:28:47 INFO - PROCESS | 25895 | [#2] jquery.html Cycles:180 Average:28096.03 Median:4862.43 stddev:93857.30 (1930.3%) stddev-sans-first:94097.49 19:28:47 INFO - PROCESS | 25895 | Values: 362.3 364.2 365.4 365.5 367.8 13102.7 13403.4 15546.8 16542.3 17603.1 15004.2 15081.2 16750.1 16754.2 16765.7 4881.9 5287.5 5288.7 5294.8 5296.1 2940.0 3910.8 4024.2 5231.7 5559.9 5713.8 5736.2 5742.9 5752.6 5754.5 5467.1 5646.6 5684.2 5685.5 5695.0 4843.0 5002.1 5002.6 5009.4 5010.1 12460.8 12621.7 13092.3 13137.8 13140.3 2015.6 2032.8 2047.7 2074.6 2099.5 9199.7 9245.0 10931.9 11996.6 12498.1 1676.5 1883.4 1966.7 1997.7 2015.3 53049.5 55768.7 55948.7 57352.1 58149.1 9799.6 10167.7 10307.3 10317.5 10382.5 13358.1 13405.9 13408.5 13463.8 13488.9 7427.6 7456.7 7596.3 7657.7 7663.1 7141.3 7239.3 7306.2 7353.4 7355.7 4445.1 4555.1 4560.2 4563.3 4568.3 1718.5 1741.9 1787.4 1864.8 1875.4 895.9 965.6 969.9 982.2 1039.6 15386.5 15758.8 16293.5 16478.3 16673.7 893.1 908.4 911.7 921.2 935.6 8030.6 9366.7 9781.6 9814.9 9915.3 558117.0 561743.0 574580.0 588381.0 589863.0 71641.6 73517.6 74091.0 74669.6 74727.3 1066.9 1095.6 1097.5 1117.6 1141.9 14979.2 15267.8 15391.7 15396.2 15536.2 26612.7 27632.7 30395.0 31628.0 34449.5 37753.4 37966.4 38219.6 39015.4 39887.0 6990.6 7098.9 7169.2 7263.2 7385.2 10219.1 10605.7 11043.6 11216.4 11236.3 9509.4 9581.9 9592.1 9686.9 9828.9 9265.6 9487.5 9597.9 9700.7 9890.0 9511.6 9606.0 9774.6 9826.5 9940.4 7199.8 7449.4 7470.6 7640.5 7749.5 9554.5 10148.5 10176.7 10331.3 10412.4 19:28:47 INFO - PROCESS | 25895 | 19:28:47 INFO - PROCESS | 25895 | [#3] mootools.html Cycles:180 Average:5445.77 Median:6511.82 stddev:2815.24 (43.2%) stddev-sans-first:2805.91 19:28:47 INFO - PROCESS | 25895 | Values: 1301.9 1466.0 1504.2 1659.2 1813.2 6711.5 7395.0 7534.5 7552.9 7582.4 7456.5 7643.3 7650.8 7654.9 7655.9 1929.8 1970.6 1973.0 2280.5 2306.9 2001.3 2004.1 2212.7 2273.9 2279.1 2205.2 2228.4 2231.3 2236.1 2276.4 2279.9 2283.8 2287.0 2288.2 2288.8 2192.4 2239.9 2241.7 2241.8 2242.7 7678.2 7710.5 7728.1 7732.7 7733.5 4743.2 4855.4 4882.2 4883.3 4890.8 3034.5 3155.8 3158.2 3177.3 3178.1 964.8 981.3 986.2 1019.9 1037.1 10850.5 10864.4 10865.3 10872.4 10873.1 4289.4 4299.3 4315.2 4389.2 4405.3 9218.4 9224.7 9225.8 9239.9 9249.1 4719.4 4778.9 4788.1 4791.8 4792.4 4294.6 4321.1 4355.6 4360.7 4361.1 3397.5 3727.9 3749.4 3769.8 3787.1 1116.4 1119.6 1122.6 1217.6 1236.4 1311.3 1311.5 1316.9 1360.4 1360.8 7342.3 7346.3 7356.1 7373.1 7378.6 1202.8 1208.9 1216.2 1218.0 1260.4 5676.7 5727.7 5788.5 5799.3 5804.3 9182.9 9365.7 9430.7 9515.1 9556.5 9422.1 9581.3 9624.5 9642.5 9663.8 7237.6 7290.7 7382.7 7383.5 7393.6 6976.7 7097.3 7121.4 7136.1 7137.1 8047.3 8353.2 8442.6 8554.5 8612.7 8343.9 8416.0 8436.8 8442.1 8443.0 6761.1 6898.6 6900.0 6904.9 6909.7 7268.0 7325.5 7337.4 7369.4 7374.3 6092.7 6173.8 6785.5 6811.7 7224.7 6743.7 6955.0 6969.9 6997.6 7115.0 6605.2 6636.7 6647.1 6654.9 6660.6 6506.2 6543.9 6588.8 6593.2 6615.0 6469.5 6517.4 6520.5 6529.4 6539.0 19:28:47 INFO - PROCESS | 25895 | 19:28:47 INFO - PROCESS | 25895 | [#4] prototype.html Cycles:180 Average:5132.83 Median:5772.42 stddev:1524.98 (26.4%) stddev-sans-first:1486.12 19:28:47 INFO - PROCESS | 25895 | Values: 334.1 342.6 343.8 345.0 348.8 5600.1 5748.7 5779.2 5801.8 5818.4 5558.9 5644.8 5680.8 5683.5 5704.2 4514.6 4514.9 4522.3 4527.5 4528.3 4325.9 4400.9 4423.1 4437.9 4473.5 4360.5 4419.1 4420.6 4445.6 4459.1 4448.7 4459.2 4465.0 4465.6 4471.8 3965.4 3974.9 3976.1 3979.0 3988.4 5328.7 5451.1 5469.9 5489.9 5526.2 5139.6 5140.6 5141.3 5147.9 5147.9 5792.1 6098.2 6108.3 6109.8 6120.6 4127.9 4195.4 4308.9 4318.0 4336.1 6319.3 6372.0 6382.0 6386.9 6402.5 4414.4 4415.7 4418.8 4420.6 4423.6 4317.0 4432.5 4482.5 4484.9 4489.3 5051.4 5052.5 5140.5 5147.6 5156.0 6067.5 6071.1 6087.5 6095.2 6098.4 1561.3 1587.8 1588.0 1588.3 1589.4 1078.0 1082.2 1083.6 1085.7 1110.8 5765.0 5772.0 5786.2 5788.9 5792.9 6309.5 6316.0 6316.1 6321.7 6326.2 5768.1 5770.9 5772.8 5783.2 5785.6 3299.8 3406.1 3412.5 3417.3 3418.5 6444.2 6519.5 6524.9 6526.4 6543.1 6438.9 6441.5 6443.9 6444.9 6449.7 6322.2 6494.6 6495.5 6499.5 6502.3 6070.3 6239.3 6278.7 6283.5 6316.9 6086.4 6214.8 6235.2 6264.7 6267.7 6584.3 6589.5 6589.6 6592.9 6593.2 4370.6 4453.9 4455.8 4456.8 4465.2 6367.1 6368.6 6372.2 6376.9 6385.3 6199.5 6202.6 6203.4 6204.6 6207.5 6036.9 6188.8 6192.6 6194.1 6197.7 6030.9 6055.0 6071.6 6073.5 6077.3 6219.7 6226.1 6227.0 6230.6 6230.8 5937.2 6066.7 6074.1 6081.1 6093.9 19:28:47 INFO - PROCESS | 25895 | 19:28:47 INFO - PROCESS | 25895 | [#5] yui.html Cycles:180 Average:13167.98 Median:44691.55 stddev:15193.08 (34.0%) stddev-sans-first:15207.12 19:28:47 INFO - PROCESS | 25895 | Values: 758.1 774.0 827.1 843.3 876.9 12270.1 12326.8 12377.8 12416.5 12462.3 12137.8 12213.1 12402.8 12578.1 12657.7 789.9 803.3 812.3 812.5 813.5 799.8 809.1 809.5 809.9 810.0 839.4 840.3 842.6 843.0 843.2 825.9 827.2 832.6 834.0 835.4 615.9 617.4 617.4 617.7 618.0 11172.1 11190.4 11191.2 11233.9 11237.2 10795.7 10854.5 10856.2 10944.3 11048.8 9475.2 9663.9 9669.9 9727.4 9975.9 3394.1 3425.2 3433.4 3463.5 3564.3 44445.0 44634.3 44737.3 45405.3 56118.7 9781.6 9952.9 10014.4 10046.1 10335.7 16696.5 17286.3 17290.0 17300.7 17324.8 7362.6 7456.9 7576.7 7595.6 7643.2 4226.5 4265.7 4295.2 4323.0 4340.0 2975.7 3000.8 3024.6 3040.6 3042.2 557.7 568.2 570.4 575.9 576.7 532.7 557.5 557.8 611.3 662.7 7925.7 8358.7 8431.5 8501.6 8505.5 537.2 571.9 602.1 609.8 618.7 3719.9 3749.4 3802.1 3900.6 3909.3 54549.5 55328.7 55397.0 55512.2 55601.0 42788.4 48951.5 49254.3 49857.8 49870.8 39995.4 42198.2 42524.8 42570.4 43199.4 8476.0 8640.4 8818.7 8939.0 9065.9 28938.0 30446.5 34338.0 34750.8 35418.0 43036.1 43193.8 43615.6 43869.8 44645.8 10503.4 10701.4 11055.7 11115.4 11528.5 12063.8 12231.1 12281.4 12306.4 12395.1 10988.6 11042.5 11103.7 11305.0 11945.1 11204.7 11644.8 11689.8 11838.6 11964.3 10677.9 10681.9 10755.2 10986.1 11086.3 9310.7 9342.4 9405.6 9612.4 9882.6 8709.0 8856.2 8920.0 9025.3 9470.6 19:28:47 INFO - PROCESS | 25895 | -------- Summary: end -------- 19:28:47 INFO - PROCESS | 25895 | 19:28:48 INFO - TEST-INFO | 25895: exit 0 19:28:48 INFO - TEST-OK | dromaeo_css | took 1112237ms 19:28:48 INFO - TEST-START | kraken 19:28:48 INFO - operating with platform_type : linux_ 19:28:48 INFO - Initialising browser for kraken test... 19:28:48 INFO - TEST-INFO | started process 26092 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgoELm0/profile http://localhost:54376/getInfo.html) 19:28:51 INFO - PROCESS | 26092 | __metrics Screen width/height:1600/1200 19:28:51 INFO - PROCESS | 26092 | colorDepth:24 19:28:51 INFO - PROCESS | 26092 | Browser inner width/height: 1024/697 19:28:51 INFO - PROCESS | 26092 | __metrics 19:28:51 INFO - PROCESS | 26092 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 19:28:51 INFO - PROCESS | 26092 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:28:52 INFO - TEST-INFO | 26092: exit 0 19:28:52 INFO - Browser initialized. 19:28:52 INFO - Running cycle 1/1 for kraken test... 19:28:52 INFO - TEST-INFO | started process 26176 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgoELm0/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:29:04 INFO - PROCESS | 26176 | RSS: Main: 150200320 19:29:04 INFO - PROCESS | 26176 | 19:29:07 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54376/tests/kraken/driver.html?testName=audio-beat-detection) 19:29:07 INFO - PROCESS | 26176 | RSS: Main: 190193664 19:29:07 INFO - PROCESS | 26176 | 19:29:10 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54376/tests/kraken/driver.html?testName=audio-dft) 19:29:10 INFO - PROCESS | 26176 | RSS: Main: 179949568 19:29:10 INFO - PROCESS | 26176 | 19:29:13 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54376/tests/kraken/driver.html?testName=audio-fft) 19:29:14 INFO - PROCESS | 26176 | RSS: Main: 168574976 19:29:14 INFO - PROCESS | 26176 | 19:29:16 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54376/tests/kraken/driver.html?testName=audio-oscillator) 19:29:16 INFO - PROCESS | 26176 | RSS: Main: 167858176 19:29:16 INFO - PROCESS | 26176 | 19:29:19 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54376/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:29:19 INFO - PROCESS | 26176 | RSS: Main: 169447424 19:29:19 INFO - PROCESS | 26176 | 19:29:22 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54376/tests/kraken/driver.html?testName=imaging-darkroom) 19:29:23 INFO - PROCESS | 26176 | RSS: Main: 168304640 19:29:23 INFO - PROCESS | 26176 | 19:29:26 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54376/tests/kraken/driver.html?testName=imaging-desaturate) 19:29:27 INFO - PROCESS | 26176 | RSS: Main: 171352064 19:29:27 INFO - PROCESS | 26176 | 19:29:29 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54376/tests/kraken/driver.html?testName=json-parse-financial) 19:29:30 INFO - PROCESS | 26176 | RSS: Main: 171864064 19:29:30 INFO - PROCESS | 26176 | 19:29:32 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54376/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:29:32 INFO - PROCESS | 26176 | RSS: Main: 180654080 19:29:32 INFO - PROCESS | 26176 | 19:29:34 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:29:34 INFO - PROCESS | 26176 | RSS: Main: 180170752 19:29:34 INFO - PROCESS | 26176 | 19:29:37 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:29:37 INFO - PROCESS | 26176 | RSS: Main: 182759424 19:29:37 INFO - PROCESS | 26176 | 19:29:39 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:29:40 INFO - PROCESS | 26176 | RSS: Main: 192966656 19:29:40 INFO - PROCESS | 26176 | 19:29:43 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:29:43 INFO - PROCESS | 26176 | RSS: Main: 190427136 19:29:43 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | Cycle 1(1): loaded http://localhost:54376/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54376/tests/kraken/driver.html?testName=ai-astar) 19:29:45 INFO - PROCESS | 26176 | RSS: Main: 248651776 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | __start_tp_report 19:29:45 INFO - PROCESS | 26176 | _x_x_mozilla_page_load 19:29:45 INFO - PROCESS | 26176 | _x_x_mozilla_page_load_details 19:29:45 INFO - PROCESS | 26176 | |i|pagename|runs| 19:29:45 INFO - PROCESS | 26176 | |0;ai-astar;110;111;106;109;109;110;110;109;103;109 19:29:45 INFO - PROCESS | 26176 | |1;audio-beat-detection;146;148;152;160;129;151;149;141;177;133 19:29:45 INFO - PROCESS | 26176 | |2;audio-dft;162;161;162;160;162;162;161;160;159;162 19:29:45 INFO - PROCESS | 26176 | |3;audio-fft;72;72;73;152;72;73;73;75;159;75 19:29:45 INFO - PROCESS | 26176 | |4;audio-oscillator;105;97;149;95;96;96;149;95;96;140 19:29:45 INFO - PROCESS | 26176 | |5;imaging-gaussian-blur;110;109;109;109;109;109;110;109;109;109 19:29:45 INFO - PROCESS | 26176 | |6;imaging-darkroom;146;145;146;145;147;147;145;145;145;145 19:29:45 INFO - PROCESS | 26176 | |7;imaging-desaturate;96;96;96;96;96;96;96;97;96;95 19:29:45 INFO - PROCESS | 26176 | |8;json-parse-financial;56;55;55;55;54;55;54;56;56;53 19:29:45 INFO - PROCESS | 26176 | |9;json-stringify-tinderbox;67;68;68;68;69;68;69;68;69;67 19:29:45 INFO - PROCESS | 26176 | |10;stanford-crypto-aes;82;87;83;83;82;81;82;89;83;82 19:29:45 INFO - PROCESS | 26176 | |11;stanford-crypto-ccm;122;118;116;125;114;115;125;118;125;117 19:29:45 INFO - PROCESS | 26176 | |12;stanford-crypto-pbkdf2;147;135;146;146;143;144;144;143;144;142 19:29:45 INFO - PROCESS | 26176 | |13;stanford-crypto-sha256-iterative;66;67;68;65;67;66;67;67;66;66 19:29:45 INFO - PROCESS | 26176 | __end_tp_report 19:29:45 INFO - PROCESS | 26176 | __start_cc_report 19:29:45 INFO - PROCESS | 26176 | _x_x_mozilla_cycle_collect,951 19:29:45 INFO - PROCESS | 26176 | __end_cc_report 19:29:45 INFO - PROCESS | 26176 | __startTimestamp1453433385588__endTimestamp 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | ------- Summary: start ------- 19:29:45 INFO - PROCESS | 26176 | Number of tests: 14 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#0] ai-astar Cycles:10 Average:108.60 Median:109.50 stddev:2.37 (2.2%) stddev-sans-first:2.46 19:29:45 INFO - PROCESS | 26176 | Values: 110.0 111.0 106.0 109.0 109.0 110.0 110.0 109.0 103.0 109.0 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#1] audio-beat-detection Cycles:10 Average:148.60 Median:150.00 stddev:13.53 (9.0%) stddev-sans-first:14.31 19:29:45 INFO - PROCESS | 26176 | Values: 146.0 148.0 152.0 160.0 129.0 151.0 149.0 141.0 177.0 133.0 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#2] audio-dft Cycles:10 Average:161.10 Median:162.00 stddev:1.10 (0.7%) stddev-sans-first:1.12 19:29:45 INFO - PROCESS | 26176 | Values: 162.0 161.0 162.0 160.0 162.0 162.0 161.0 160.0 159.0 162.0 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#3] audio-fft Cycles:10 Average:89.60 Median:73.00 stddev:34.79 (47.7%) stddev-sans-first:36.31 19:29:45 INFO - PROCESS | 26176 | Values: 72.0 72.0 73.0 152.0 72.0 73.0 73.0 75.0 159.0 75.0 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#4] audio-oscillator Cycles:10 Average:111.80 Median:95.50 stddev:23.90 (25.0%) stddev-sans-first:25.22 19:29:45 INFO - PROCESS | 26176 | Values: 105.0 97.0 149.0 95.0 96.0 96.0 149.0 95.0 96.0 140.0 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#5] imaging-gaussian-blur Cycles:10 Average:109.20 Median:109.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 19:29:45 INFO - PROCESS | 26176 | Values: 110.0 109.0 109.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:145.50 stddev:0.84 (0.6%) stddev-sans-first:0.88 19:29:45 INFO - PROCESS | 26176 | Values: 146.0 145.0 146.0 145.0 147.0 147.0 145.0 145.0 145.0 145.0 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 19:29:45 INFO - PROCESS | 26176 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 97.0 96.0 95.0 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:0.99 (1.8%) stddev-sans-first:0.97 19:29:45 INFO - PROCESS | 26176 | Values: 56.0 55.0 55.0 55.0 54.0 55.0 54.0 56.0 56.0 53.0 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:68.00 stddev:0.74 (1.1%) stddev-sans-first:0.67 19:29:45 INFO - PROCESS | 26176 | Values: 67.0 68.0 68.0 68.0 69.0 68.0 69.0 68.0 69.0 67.0 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:83.00 stddev:2.55 (3.1%) stddev-sans-first:2.65 19:29:45 INFO - PROCESS | 26176 | Values: 82.0 87.0 83.0 83.0 82.0 81.0 82.0 89.0 83.0 82.0 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#11] stanford-crypto-ccm Cycles:10 Average:119.50 Median:120.00 stddev:4.35 (3.6%) stddev-sans-first:4.52 19:29:45 INFO - PROCESS | 26176 | Values: 122.0 118.0 116.0 125.0 114.0 115.0 125.0 118.0 125.0 117.0 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.40 Median:144.00 stddev:3.34 (2.3%) stddev-sans-first:3.28 19:29:45 INFO - PROCESS | 26176 | Values: 147.0 135.0 146.0 146.0 143.0 144.0 144.0 143.0 144.0 142.0 19:29:45 INFO - PROCESS | 26176 | 19:29:45 INFO - PROCESS | 26176 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 19:29:45 INFO - PROCESS | 26176 | Values: 66.0 67.0 68.0 65.0 67.0 66.0 67.0 67.0 66.0 66.0 19:29:45 INFO - PROCESS | 26176 | -------- Summary: end -------- 19:29:45 INFO - PROCESS | 26176 | 19:29:46 INFO - TEST-INFO | 26176: exit 0 19:29:46 INFO - TEST-OK | kraken | took 58327ms 19:29:46 INFO - TEST-START | v8_7 19:29:46 INFO - operating with platform_type : linux_ 19:29:46 INFO - Initialising browser for v8_7 test... 19:29:46 INFO - TEST-INFO | started process 26429 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEhZrZY/profile http://localhost:54376/getInfo.html) 19:29:49 INFO - PROCESS | 26429 | __metrics Screen width/height:1600/1200 19:29:49 INFO - PROCESS | 26429 | colorDepth:24 19:29:49 INFO - PROCESS | 26429 | Browser inner width/height: 1024/697 19:29:49 INFO - PROCESS | 26429 | __metrics 19:29:50 INFO - PROCESS | 26429 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 19:29:50 INFO - PROCESS | 26429 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:29:51 INFO - TEST-INFO | 26429: exit 0 19:29:51 INFO - Browser initialized. 19:29:51 INFO - Running cycle 1/1 for v8_7 test... 19:29:51 INFO - TEST-INFO | started process 26513 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEhZrZY/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:30:03 INFO - PROCESS | 26513 | RSS: Main: 150523904 19:30:03 INFO - PROCESS | 26513 | 19:30:25 INFO - PROCESS | 26513 | Cycle 1(1): loaded http://localhost:54376/tests/v8_7/run.html (next: http://localhost:54376/tests/v8_7/run.html) 19:30:25 INFO - PROCESS | 26513 | RSS: Main: 554942464 19:30:25 INFO - PROCESS | 26513 | 19:30:25 INFO - PROCESS | 26513 | __start_tp_report 19:30:25 INFO - PROCESS | 26513 | _x_x_mozilla_page_load 19:30:25 INFO - PROCESS | 26513 | _x_x_mozilla_page_load_details 19:30:25 INFO - PROCESS | 26513 | |i|pagename|runs| 19:30:25 INFO - PROCESS | 26513 | |0;Richards;166.5278934221482 19:30:25 INFO - PROCESS | 26513 | |1;DeltaBlue;189.64536317087047 19:30:25 INFO - PROCESS | 26513 | |2;Encrypt;280.662363177098 19:30:25 INFO - PROCESS | 26513 | |3;Decrypt;5196.891191709845 19:30:25 INFO - PROCESS | 26513 | |4;RayTrace;1127.3957158962796 19:30:25 INFO - PROCESS | 26513 | |5;Earley;800.8 19:30:25 INFO - PROCESS | 26513 | |6;Boyer;12456.79012345679 19:30:25 INFO - PROCESS | 26513 | |7;RegExp;42571.42857142857 19:30:25 INFO - PROCESS | 26513 | |8;Splay;564.9717514124294 19:30:25 INFO - PROCESS | 26513 | |9;NavierStokes;4830.917874396136 19:30:25 INFO - PROCESS | 26513 | __end_tp_report 19:30:25 INFO - PROCESS | 26513 | __startTimestamp1453433425849__endTimestamp 19:30:25 INFO - PROCESS | 26513 | 19:30:25 INFO - PROCESS | 26513 | ------- Summary: start ------- 19:30:25 INFO - PROCESS | 26513 | Number of tests: 10 19:30:25 INFO - PROCESS | 26513 | 19:30:25 INFO - PROCESS | 26513 | [#0] Richards Cycles:1 Average:166.53 Median:166.53 stddev:0.00 (0.0%) 19:30:25 INFO - PROCESS | 26513 | Values: 166.5 19:30:25 INFO - PROCESS | 26513 | 19:30:25 INFO - PROCESS | 26513 | [#1] DeltaBlue Cycles:1 Average:189.65 Median:189.65 stddev:0.00 (0.0%) 19:30:25 INFO - PROCESS | 26513 | Values: 189.6 19:30:25 INFO - PROCESS | 26513 | 19:30:25 INFO - PROCESS | 26513 | [#2] Encrypt Cycles:1 Average:280.66 Median:280.66 stddev:0.00 (0.0%) 19:30:25 INFO - PROCESS | 26513 | Values: 280.7 19:30:25 INFO - PROCESS | 26513 | 19:30:25 INFO - PROCESS | 26513 | [#3] Decrypt Cycles:1 Average:5196.89 Median:5196.89 stddev:0.00 (0.0%) 19:30:25 INFO - PROCESS | 26513 | Values: 5196.9 19:30:25 INFO - PROCESS | 26513 | 19:30:25 INFO - PROCESS | 26513 | [#4] RayTrace Cycles:1 Average:1127.40 Median:1127.40 stddev:0.00 (0.0%) 19:30:25 INFO - PROCESS | 26513 | Values: 1127.4 19:30:25 INFO - PROCESS | 26513 | 19:30:25 INFO - PROCESS | 26513 | [#5] Earley Cycles:1 Average:800.80 Median:800.80 stddev:0.00 (0.0%) 19:30:25 INFO - PROCESS | 26513 | Values: 800.8 19:30:25 INFO - PROCESS | 26513 | 19:30:25 INFO - PROCESS | 26513 | [#6] Boyer Cycles:1 Average:12456.79 Median:12456.79 stddev:0.00 (0.0%) 19:30:25 INFO - PROCESS | 26513 | Values: 12456.8 19:30:25 INFO - PROCESS | 26513 | 19:30:25 INFO - PROCESS | 26513 | [#7] RegExp Cycles:1 Average:42571.43 Median:42571.43 stddev:0.00 (0.0%) 19:30:25 INFO - PROCESS | 26513 | Values: 42571.4 19:30:25 INFO - PROCESS | 26513 | 19:30:25 INFO - PROCESS | 26513 | [#8] Splay Cycles:1 Average:564.97 Median:564.97 stddev:0.00 (0.0%) 19:30:25 INFO - PROCESS | 26513 | Values: 565.0 19:30:25 INFO - PROCESS | 26513 | 19:30:25 INFO - PROCESS | 26513 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 19:30:25 INFO - PROCESS | 26513 | Values: 4830.9 19:30:25 INFO - PROCESS | 26513 | -------- Summary: end -------- 19:30:25 INFO - PROCESS | 26513 | 19:30:26 INFO - TEST-INFO | 26513: exit 0 19:30:26 INFO - TEST-OK | v8_7 | took 40396ms 19:30:26 INFO - SUITE-END | took 1210s 19:30:27 INFO - Completed test suite (00:20:11) 19:30:27 INFO - Working with test: dromaeo_css 19:30:27 INFO - Generating results file: dromaeo_css 19:30:27 INFO - Working with test: kraken 19:30:27 INFO - Generating results file: kraken 19:30:27 INFO - javascript benchmark 19:30:27 INFO - Working with test: v8_7 19:30:27 INFO - Generating results file: v8_7 19:30:27 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:30:27 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,63,35]] 19:30:27 INFO - process_Request line: dromaeo_css 6748.99 graph.html#tests=[[72,63,35]] 19:30:27 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:30:27 INFO - process_Request line: kraken 1506.30 graph.html#tests=[[232,63,35]] 19:30:27 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:30:27 INFO - process_Request line: v8_7 20052.93 graph.html#tests=[[230,63,35]] 19:30:27 INFO - RETURN: dromaeo_css: 6748.99 19:30:27 INFO - RETURN: kraken: 1506.30 19:30:27 INFO - RETURN: v8_7: 20052.93 19:30:27 INFO - javascript benchmark 19:30:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3063.3413816029556, 3152.2277042124683, 3197.7281931035877, 3200.2639392939623, 3226.5805445292117, 4915.803367865281, 4984.875378115549, 5101.132807422738, 5128.589712822974, 5188.533032027117, 5077.466865979074, 5107.616928730342, 5124.333836601237, 5209.843704688866, 5212.739363031852, 4703.2239680452685, 4707.999999999992, 4734.810607575693, 4755.714657120567, 4858.805647774085, 4789.497102804207, 4807.951920480803, 4848.2727590861305, 4862.97568512157, 4897.265410188465, 4625.0, 4674.041821426599, 4683.836065737717, 4781.043791241748, 4790.928136077979, 4715.31627913954, 4734.479207287196, 4756.381670382828, 4762.0, 4774.355462012653, 4727.172744769651, 4736.668433209677, 4737.8341758038305, 4745.454272758642, 4764.0, 4895.339129217546, 4959.032988567231, 4985.102681517325, 5000.949990500085, 5032.521910418541, 5557.749901254453, 5561.5272701820395, 5565.165225216209, 5568.1647752837, 5594.272744543184, 5561.721913904329, 5573.191887176336, 5574.219609254707, 5577.2191893135, 5582.497575218209, 5137.332146820927, 5146.768395422215, 5212.296339994053, 5213.817516386946, 5226.5818734501145, 5606.523445507096, 5661.858453538612, 5668.801591944262, 5671.2060311556415, 5696.914546281726, 5462.7268636568015, 5472.480114389126, 5473.480019398151, 5475.151351540518, 5475.315585551806, 5361.973190134025, 5448.91826622601, 5454.972725136349, 5494.752736126884, 5572.665640061609, 5504.642198257181, 5514.228008078872, 5514.696691681916, 5519.254900588369, 5526.447355264522, 5363.222332761728, 5511.255980442669, 5513.062779327524, 5519.0, 5571.609987300931, 4229.365595160689, 4277.807498662568, 4279.935800962988, 4311.0, 4350.760708161081, 3851.9614803851605, 3857.1321452673487, 3870.3613903706137, 3899.8245078971513, 3901.668358189586, 5000.2499625056535, 5130.7947682093045, 5137.40919794221, 5140.126178549655, 5184.0, 5481.725913704299, 5497.972510137423, 5504.284443022381, 5541.722913854291, 5557.360903496069, 5132.640715149903, 5146.639735218574, 5147.562457191106, 5147.922781158286, 5149.356330458692, 4712.458067322234, 4717.551832575899, 4719.315699223662, 4756.476787553366, 4803.471618122004, 5628.71856407178, 5689.175069614883, 5716.771329146871, 5733.455321744427, 5803.680797556175, 5481.095619222783, 5489.972550137224, 5490.835274941598, 5491.725413729378, 5498.45015498447, 5665.20687103814, 5667.603267771216, 5673.432656734293, 5678.176664383724, 5678.801241956512, 5271.551918086852, 5406.378266499403, 5453.918191227214, 5537.086380747292, 5567.109262517978, 5494.917576236439, 5511.944880551143, 5532.861678458071, 5546.750396232099, 5559.58303127259, 5417.8103766367985, 5420.674759514442, 5427.294451721251, 5432.456754324536, 5479.013777520085, 5248.816291429782, 5343.278657381204, 5367.946320536745, 5422.213779001943, 5446.891062178814, 5314.28257185275, 5315.840524784263, 5315.920261196163, 5317.0960936640095, 5318.282031925641, 5391.191321301836, 5412.891742165213, 5416.160495123262, 5457.808976685797, 5459.235707000944, 5204.63567550283, 5241.213817927342, 5254.5533629641905, 5303.098473259478, 5347.946520534745], "unit": "score", "name": "dojo.html", "value": 5101.27640065649}, {"lowerIsBetter": false, "replicates": [546.6282927609229, 571.4257171542599, 584.1296468262287, 597.053669933156, 608.7915487756802, 29392.118236452912, 29698.257543561394, 29884.0, 35572.64427355726, 35960.82019589905, 39410.8029459853, 39923.800380998124, 40008.0, 40008.79995600018, 40014.39978400319, 8299.21157490039, 8484.19400156984, 8486.915130848705, 8488.108748581402, 8495.447795893278, 8279.337652987777, 8397.6221070052, 8405.957970210171, 8414.0, 8414.747557573282, 8594.398392112555, 8628.050914399411, 8637.784055398603, 8643.394962352639, 8659.04750477447, 8524.957375213115, 8599.140085991414, 8612.913870861337, 8613.827723445496, 8620.65517379301, 8497.532635705034, 8605.139486051406, 8613.741587752378, 8636.17956294151, 8643.481391116553, 40160.59839401598, 40346.39480407767, 40550.594494054974, 40642.79678601617, 40653.390199147034, 2911.737943585066, 2960.6299212598424, 2973.52423612221, 2980.7764417668764, 3144.3239703463637, 28894.422111557862, 29419.85290073536, 29712.108636740715, 30044.699553004408, 30381.696183038108, 3855.730098893079, 3879.844806207748, 3919.196564704229, 3929.783861887595, 3962.3660214365564, 256416.0, 256789.0, 256917.0, 257299.71350143317, 257801.0, 57268.42731572631, 57956.71021644865, 58048.0, 58404.707976459846, 59646.70176649089, 52944.0, 53077.7346113267, 53178.73410633, 53200.46799532032, 53242.20136697953, 14527.636809079857, 14765.33555989983, 14780.260986950609, 14808.62978425548, 14811.703765924836, 10623.150147988143, 10742.78514429707, 11276.210665253353, 11528.59649912249, 11704.356260405759, 19267.51831204231, 19369.612607748048, 19382.0, 19402.70895936562, 19522.12150453233, 7123.013890276723, 7319.817004574928, 7418.777436676908, 7503.324700776956, 7583.924160758432, 953.4184147669915, 969.3989726369696, 1029.3360782295526, 1076.2358725304964, 1126.6225814352283, 24780.0, 26773.196804095518, 26861.731382685924, 28761.856190718914, 31489.685103149135, 1002.3033991376069, 1052.6473631333588, 1055.0504545908743, 1098.6154845804065, 1122.3097794856105, 15039.398424063134, 15121.697566048837, 15179.924100379427, 15289.0, 15405.537833865003, 484026.0, 484821.0, 486490.0, 487930.0, 491602.0, 50734.238986415236, 51128.0, 51372.0, 51485.48514514807, 51548.48451515437, 1383.080251632673, 1395.0862185268463, 1401.32736286583, 1419.5386499387741, 1420.4957240179795, 27670.308242294104, 29327.26681832886, 29823.0, 30884.53673194859, 31377.0, 47084.52915470801, 47473.762631186626, 47525.28712069253, 47539.762301188275, 47628.523714763796, 299490.0, 302249.0, 302917.0, 302986.0, 303472.0, 28497.857510712314, 28673.713262867106, 31960.360792783547, 32616.0, 33183.33633327272, 37860.81069594635, 37904.81047594855, 38349.808250958566, 39245.0, 41586.0, 32946.34107317888, 34358.82820585881, 35708.821455892554, 35783.642163579076, 35902.281954361286, 30275.394492110474, 34093.659063409046, 34619.4807077889, 35967.28065438624, 36028.27943441169, 32915.17712057122, 32998.83500582482, 33113.834430827694, 33207.833960830045, 38131.61868381392, 28648.427031458836, 29593.0, 31065.0, 31100.533491998092, 31133.221669458446, 23563.64654530149, 23798.762012379655, 24299.63550546708, 25357.619635705847, 25649.743502564736], "unit": "score", "name": "ext.html", "value": 18191.779151935916}, {"lowerIsBetter": false, "replicates": [362.28268029301995, 364.184227330779, 365.3952708267817, 365.48466662006575, 367.80690137677715, 13102.73794524109, 13403.396847141878, 15546.766798498018, 16542.255598498068, 17603.119844007797, 15004.249787510635, 15081.170535620564, 16750.078745668983, 16754.16229188542, 16765.748513772272, 4881.853544393656, 5287.524122828944, 5288.682679039251, 5294.7617357219015, 5296.073187192245, 2939.970600293991, 3910.843566257346, 4024.215278020787, 5231.73841307935, 5559.916601250984, 5713.8285851424525, 5736.225609542721, 5742.856428589298, 5752.597318187729, 5754.4533269339345, 5467.125259958408, 5646.604737668365, 5684.204211410406, 5685.4883060524335, 5695.0, 4843.0, 5002.149634562097, 5002.59979201663, 5009.373828271466, 5010.123228435009, 12460.813087803599, 12621.684457888534, 13092.345382730826, 13137.802932956014, 13140.277284749334, 2015.5666531695756, 2032.7763945965933, 2047.6518991771286, 2074.5747121839986, 2099.4856260216193, 9199.67801126964, 9245.0, 10931.890681093168, 11996.58011969577, 12498.125131240817, 1676.4635316698661, 1883.359657716383, 1966.72465854779, 1997.680371140625, 2015.3450128708228, 53049.46950530523, 55768.721156393956, 55948.72025639886, 57352.13971790426, 58149.12776308316, 9799.608015679436, 10167.7458063549, 10307.330023548446, 10317.48412579368, 10382.480875956171, 13358.064935454619, 13405.865941340462, 13408.463661453432, 13463.798043029363, 13488.865111348958, 7427.591482468516, 7456.7390141344795, 7596.316331530078, 7657.693692252359, 7663.080430348394, 7141.285871412921, 7239.276072392719, 7306.196318405077, 7353.374963128194, 7355.742549010759, 4445.133199026159, 4555.06621142665, 4560.224761790503, 4563.338315944169, 4568.314752787109, 1718.458685514059, 1741.9216135273944, 1787.374418953382, 1864.8041955594738, 1875.4373687893851, 895.892492900856, 965.6137544982064, 969.8836139663285, 982.2191357870367, 1039.5841663334727, 15386.538403847902, 15758.76361854573, 16293.51119466418, 16478.2584783685, 16673.66652666964, 893.0533634347612, 908.4004556992353, 911.7219248129384, 921.2123634292717, 935.6444551070549, 8030.594631058991, 9366.718998429922, 9781.608735650636, 9814.852777208347, 9915.256355773347, 558117.0470738264, 561743.0, 574580.0, 588381.0, 589863.0, 71641.64179179176, 73517.63241183867, 74091.0, 74669.62665186639, 74727.28545289078, 1066.8506409102731, 1095.5836782022939, 1097.4951522299832, 1117.6255954255416, 1141.8629764428156, 14979.176145312113, 15267.77098343548, 15391.692166156838, 15396.153211573022, 15536.223188840288, 26612.7338726618, 27632.723672763015, 30395.0, 31628.0, 34449.48325775166, 37753.43369849509, 37966.43050354192, 38219.61780382161, 39015.41476877906, 39887.0, 6990.615516146458, 7098.929010709827, 7169.247229041084, 7263.16473605521, 7385.150707668687, 10219.131373833308, 10605.681829545127, 11043.613473528389, 11216.439178041228, 11236.269642473213, 9509.429434233969, 9581.856272155785, 9592.08875156859, 9686.85469717969, 9828.901710983084, 9265.629374825201, 9487.478188699688, 9597.856032159383, 9700.660476883275, 9890.0, 9511.571979261089, 9606.0, 9774.609015639295, 9826.459544724823, 9940.353876997971, 7199.784006479814, 7449.4412919032375, 7470.589117598584, 7640.503367281109, 7749.457537972288, 9554.474503902353, 10148.54331555067, 10176.694699159038, 10331.32846365014, 10412.375257484575], "unit": "score", "name": "jquery.html", "value": 8025.905254789529}, {"lowerIsBetter": false, "replicates": [1301.8763217494334, 1466.0470694048868, 1504.2027725305588, 1659.1787065402627, 1813.229377514556, 6711.463082953364, 7395.038644976153, 7534.472586918911, 7552.8867066994035, 7582.4313176511705, 7456.515326503773, 7643.273888980538, 7650.770476885702, 7654.923450765491, 7655.885161722578, 1929.826315631593, 1970.6452838489138, 1973.0430741090559, 2280.4754906371545, 2306.8961896714686, 2001.269536619138, 2004.0881398963509, 2212.6902233687297, 2273.908523908525, 2279.099755596538, 2205.228170140454, 2228.353777404551, 2231.3306008197555, 2236.13908645172, 2276.4081338851934, 2279.87460689662, 2283.8401311908174, 2287.0, 2288.1991303043883, 2288.8441337124814, 2192.3861318830754, 2239.9328020159423, 2241.7197850268717, 2241.8430709850154, 2242.7196600424945, 7678.232176782333, 7710.460267781257, 7728.149903510609, 7732.729354472566, 7733.535987840747, 4743.24108142699, 4855.441624213226, 4882.218844984821, 4883.34074899891, 4890.755462226874, 3034.544818277254, 3155.763317751178, 3158.1630867820045, 3177.3009937813645, 3178.062471570869, 964.8022155458184, 981.3375971219398, 986.2061040862103, 1019.9490025498769, 1037.0873631204568, 10850.511726972227, 10864.402457864815, 10865.3480791152, 10872.402017889013, 10873.130149588014, 4289.399484072233, 4299.333603291495, 4315.244832154361, 4389.166058448948, 4405.29515277554, 9218.354715169873, 9224.723258302261, 9225.815483690423, 9239.907600923905, 9249.075092490697, 4719.433667959866, 4778.904421911612, 4788.1381351356395, 4791.760411979387, 4792.448868380111, 4294.570542945743, 4321.114171594815, 4355.586219309161, 4360.694751367438, 4361.0841723237845, 3397.456406974872, 3727.8508859645854, 3749.418840079792, 3769.7738135711943, 3787.1289603391374, 1116.3636727065657, 1119.6193294279983, 1122.6239209864623, 1217.5799349224503, 1236.3694515797008, 1311.302914943984, 1311.5278499740086, 1316.9144005639794, 1360.4286199796109, 1360.7890776929592, 7342.339189472973, 7346.302101300368, 7356.117265928123, 7373.115226172787, 7378.5941773202985, 1202.8436303280519, 1208.8972437342925, 1216.2337727231964, 1217.9573714919936, 1260.382412617829, 5676.716164191774, 5727.742251598688, 5788.479036886701, 5799.2750906136735, 5804.303483581997, 9182.86225706615, 9365.672201472917, 9430.669926552539, 9515.096065873731, 9556.522173891277, 9422.057794220524, 9581.281403894596, 9624.51877406127, 9642.469664168539, 9663.758406039906, 7237.601931893796, 7290.708371665074, 7382.704691812267, 7383.483156179123, 7393.630318484054, 6976.720931162697, 7097.25478824716, 7121.359077683033, 7136.143662760502, 7137.10786151731, 8047.275745182962, 8353.164683531599, 8442.620082096444, 8554.486730796172, 8612.65549378018, 8343.874841877256, 8415.990081190297, 8436.831263374821, 8442.071372149181, 8442.957785211034, 6761.087253220752, 6898.551594146365, 6899.965500172467, 6904.861902762017, 6909.654517274015, 7267.963660181666, 7325.523840950321, 7337.413006959538, 7369.410447164321, 7374.299441553044, 6092.725827337869, 6173.783917562864, 6785.491088168308, 6811.7275308989065, 7224.711011559479, 6743.696533655899, 6955.0, 6969.895451568129, 6997.580145191305, 7114.964425177841, 6605.174353205849, 6636.734530618721, 6647.102641143577, 6654.933450665625, 6660.6336651484635, 6506.219253689587, 6543.869122617616, 6588.769393071411, 6593.175853018372, 6614.966925165343, 6469.4824414045825, 6517.4134327910715, 6520.510961677798, 6529.412352888263, 6539.019147127969], "unit": "score", "name": "mootools.html", "value": 4491.383639768611}, {"lowerIsBetter": false, "replicates": [334.05629097798715, 342.5769175068791, 343.833240878174, 344.9758516903817, 348.84302064071176, 5600.103983362653, 5748.655080695156, 5779.19091327214, 5801.796937107202, 5818.359980402152, 5558.86102847429, 5644.830655080344, 5680.772769089242, 5683.460071293231, 5704.172894930232, 4514.593686568216, 4514.887127821798, 4522.321651752247, 4527.547245275463, 4528.25283828168, 4325.870223893273, 4400.933985990213, 4423.093265880487, 4437.866863994085, 4473.4855491618555, 4360.542143074979, 4419.094085712437, 4420.580044895746, 4445.644348452132, 4459.130469558438, 4448.733076015434, 4459.219636563588, 4465.040016396458, 4465.620422264095, 4471.82112715491, 3965.365541513373, 3974.9006274843073, 3976.1252524444576, 3979.045029193002, 3988.4017397390335, 5328.680279183222, 5451.100568406208, 5469.863253418656, 5489.890202195934, 5526.226328314039, 5139.563137133348, 5140.614453915934, 5141.30592370029, 5147.8636365908105, 5147.922781158286, 5792.073268277098, 6098.176746139259, 6108.3280839107665, 6109.847253818646, 6120.602160859575, 4127.9174416511505, 4195.4336164618, 4308.9353659695125, 4317.956820431818, 4336.067745434747, 6319.33646967071, 6372.044193370985, 6382.0, 6386.904196437057, 6402.519811014193, 4414.404055452473, 4415.735055896656, 4418.80115394808, 4420.58004489573, 4423.601875831127, 4316.956830431718, 4432.534583868712, 4482.529334419904, 4484.887877803081, 4489.349044388546, 5051.393832740095, 5052.469490703496, 5140.485951404904, 5147.5881929446305, 5156.0, 6067.453929146398, 6071.089336599545, 6087.482563982023, 6095.207623008981, 6098.390160983866, 1561.2896132259796, 1587.8332775058452, 1588.0074953154278, 1588.3249618911916, 1589.3880855870573, 1077.9946100269606, 1082.1775450657558, 1083.6099004358425, 1085.7014321061772, 1110.7889500994786, 5765.048766953406, 5772.018756811352, 5786.1610066540115, 5788.884222315614, 5792.88414231713, 6309.495240380758, 6315.96842015787, 6316.147320111726, 6321.747130114744, 6326.2092238470195, 5768.134779783066, 5770.942290577124, 5772.826815195467, 5783.2192653992015, 5785.595008349375, 3299.802011879295, 3406.114410253352, 3412.505186747908, 3417.333619944137, 3418.4872269159328, 6444.226692796894, 6519.543631945813, 6524.902126468107, 6526.412622863965, 6543.1166792483355, 6438.903416448757, 6441.5490915635455, 6443.903341449882, 6444.903326450107, 6449.709763060673, 6322.178116844781, 6494.6103233806125, 6495.512836537184, 6499.480041596661, 6502.252240992252, 6070.271567411939, 6239.282482514569, 6278.6546739929745, 6283.497320214463, 6316.873662526816, 6086.421789929949, 6214.8135555931585, 6235.220597425322, 6264.749410023548, 6267.6866156692895, 6584.341565843377, 6589.505787065893, 6589.571677840925, 6592.90110648331, 6593.241777195684, 4370.6284965776995, 4453.933191002202, 4455.844045458393, 4456.799444025091, 4465.240909045405, 6367.140436041261, 6368.617882927039, 6372.171617689671, 6376.936230637634, 6385.265694445013, 6199.5040396767245, 6202.596831205957, 6203.410675985774, 6204.627722336674, 6207.472364848858, 6036.9094463582205, 6188.81433556994, 6192.628442293477, 6194.132821404917, 6197.721102550306, 6030.879382412415, 6054.969725151346, 6071.605345652519, 6073.483753880793, 6077.331493535619, 6219.657918814509, 6226.12834203221, 6227.034809604518, 6230.595011324431, 6230.7819226326865, 5937.168796368598, 6066.666333351708, 6074.149619053248, 6081.118237855575, 6093.939060609515], "unit": "score", "name": "prototype.html", "value": 4646.079516766195}, {"lowerIsBetter": false, "replicates": [758.1205801270527, 773.9883901741473, 827.0695467598952, 843.3421930893901, 876.8509353409918, 12270.141090123672, 12326.81509777353, 12377.752444951095, 12416.503339866405, 12462.252264864115, 12137.757244855098, 12213.084018698588, 12402.751944961095, 12578.119531632788, 12657.683557911058, 789.8973133492652, 803.3091541274529, 812.297362781196, 812.4800127918137, 813.4671789977574, 799.8280369720509, 809.0978558906806, 809.5345176523493, 809.8906647602587, 810.0360570920585, 839.3746658739248, 840.2899549880341, 842.5702891525335, 843.0, 843.1863251961855, 825.8719898415725, 827.230675471811, 832.6419639554989, 833.9583020848963, 835.3526017336552, 615.9260888693385, 617.3517806303408, 617.4350469320566, 617.71276356494, 617.996910015447, 11172.050375718074, 11190.38452885091, 11191.216614836965, 11233.887661123366, 11237.157213209042, 10795.676129716121, 10854.511546980404, 10856.2197092084, 10944.288621239593, 11048.779024419466, 9475.241980641533, 9663.903360966371, 9669.854952175723, 9727.416355018722, 9975.850362244571, 3394.0835974286806, 3425.2122011937395, 3433.4163192257374, 3463.515107884898, 3564.32277867207, 44445.0, 44634.33048504275, 44737.32894006593, 45405.318920216225, 56118.719406403114, 9781.559829807533, 9952.900470995342, 10014.39913605186, 10046.14607758334, 10335.741606459898, 16696.49910502687, 17286.308547658205, 17290.04904730252, 17300.740488892676, 17324.826751732573, 7362.595057271901, 7456.888146677804, 7576.659050342748, 7595.620218989028, 7643.235676432423, 4226.4928208615165, 4265.722728022669, 4295.162443323522, 4323.0, 4340.0, 2975.7173068558454, 3000.7599392048583, 3024.6219222597174, 3040.5743195952587, 3042.1786117748525, 557.6681874284794, 568.1931657047003, 570.4352690836042, 575.9049756790166, 576.7001159397174, 532.6910391972647, 557.542814891793, 557.8131326005753, 611.3183800062898, 662.7084083003559, 7925.722599708983, 8358.749237522885, 8431.494110353398, 8501.574921253912, 8505.489670619783, 537.2183473046786, 571.9199312096309, 602.1449541650779, 609.7560975609791, 618.6782872906064, 3719.9442008369897, 3749.4375843623675, 3802.1255111324, 3900.551436584772, 3909.27678379495, 54549.454505454436, 55328.72335638296, 55397.0, 55512.16731749027, 55601.0, 42788.35817462741, 48951.51048489541, 49254.261186082236, 49857.75071124694, 49870.75064624654, 39995.40006899899, 42198.15603687909, 42524.78737606354, 42570.36144457835, 43199.35200971988, 8475.957620212106, 8640.351973601879, 8818.691345802867, 8939.0, 9065.90934090677, 28938.0, 30446.543301850048, 34338.0, 34750.82624586962, 35418.0, 43036.063377147475, 43193.784031079646, 43615.56384436242, 43869.78065109654, 44645.776771117235, 10503.369797812156, 10701.357918524602, 11055.668329950115, 11115.444227788417, 11528.538858445569, 12063.819042714542, 12231.082668799698, 12281.44733487013, 12306.384680766105, 12395.070369722127, 10988.615398461016, 11042.503087361249, 11103.72240693989, 11305.0, 11945.104117191071, 11204.719882003015, 11644.825327619923, 11689.824652630048, 11838.644840654793, 11964.341961192218, 10677.89322106769, 10681.89318106809, 10755.247132700948, 10986.066184374418, 11086.279391839505, 9310.72067837966, 9342.392744471588, 9405.623775048922, 9612.423254604746, 9882.555285012335, 8708.956455217683, 8856.247218986458, 8919.955400223216, 9025.3231007676, 9470.621175153192], "unit": "score", "name": "yui.html", "value": 6079.652205691366}], "name": "dromaeo_css", "value": 6748.988688980229}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 111.0, 106.0, 109.0, 109.0, 110.0, 110.0, 109.0, 103.0, 109.0], "unit": "score", "name": "ai-astar", "value": 108.6}, {"lowerIsBetter": true, "replicates": [146.0, 148.0, 152.0, 160.0, 129.0, 151.0, 149.0, 141.0, 177.0, 133.0], "unit": "score", "name": "audio-beat-detection", "value": 148.6}, {"lowerIsBetter": true, "replicates": [162.0, 161.0, 162.0, 160.0, 162.0, 162.0, 161.0, 160.0, 159.0, 162.0], "unit": "score", "name": "audio-dft", "value": 161.1}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 73.0, 152.0, 72.0, 73.0, 73.0, 75.0, 159.0, 75.0], "unit": "score", "name": "audio-fft", "value": 89.6}, {"lowerIsBetter": true, "replicates": [105.0, 97.0, 149.0, 95.0, 96.0, 96.0, 149.0, 95.0, 96.0, 140.0], "unit": "score", "name": "audio-oscillator", "value": 111.8}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.2}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 145.0, 147.0, 147.0, 145.0, 145.0, 145.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 96.0}, {"lowerIsBetter": true, "replicates": [56.0, 55.0, 55.0, 55.0, 54.0, 55.0, 54.0, 56.0, 56.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 54.9}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 68.0, 68.0, 69.0, 68.0, 69.0, 68.0, 69.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.1}, {"lowerIsBetter": true, "replicates": [82.0, 87.0, 83.0, 83.0, 82.0, 81.0, 82.0, 89.0, 83.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.4}, {"lowerIsBetter": true, "replicates": [122.0, 118.0, 116.0, 125.0, 114.0, 115.0, 125.0, 118.0, 125.0, 117.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 119.5}, {"lowerIsBetter": true, "replicates": [147.0, 135.0, 146.0, 146.0, 143.0, 144.0, 144.0, 143.0, 144.0, 142.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 143.4}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 68.0, 65.0, 67.0, 66.0, 67.0, 67.0, 66.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.5}], "name": "kraken", "value": 1506.3000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.5278934221482], "unit": "score", "name": "Richards", "value": 211.98851000000005}, {"lowerIsBetter": false, "replicates": [189.64536317087047], "unit": "score", "name": "DeltaBlue", "value": 348.64021400000007}, {"lowerIsBetter": false, "replicates": [280.662363177098], "unit": "score", "name": "Encrypt", "value": 948.4029029999999}, {"lowerIsBetter": false, "replicates": [5196.891191709845], "unit": "score", "name": "Decrypt", "value": 51.219275174476564}, {"lowerIsBetter": false, "replicates": [1127.3957158962796], "unit": "score", "name": "RayTrace", "value": 656.370243}, {"lowerIsBetter": false, "replicates": [800.8], "unit": "score", "name": "Earley", "value": 832.2465034965037}, {"lowerIsBetter": false, "replicates": [12456.79012345679], "unit": "score", "name": "Boyer", "value": 53.501985133795806}, {"lowerIsBetter": false, "replicates": [42571.42857142857], "unit": "score", "name": "RegExp", "value": 21.398976510067104}, {"lowerIsBetter": false, "replicates": [564.9717514124294], "unit": "score", "name": "Splay", "value": 144.23906999999997}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 20052.930944482057}]} 19:30:27 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,35]]", "result": "20052.93"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,35]]", "result": "1506.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,35]]", "result": "6748.99"}}} 19:30:27 INFO - Return code: 0 19:30:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 19:30:27 INFO - # TBPL SUCCESS # 19:30:27 INFO - Running post-action listener: _resource_record_post_action 19:30:27 INFO - Running post-run listener: _resource_record_post_run 19:30:28 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 4096; Write bytes: 548405248; Read time: 12; Write time: 666160 19:30:28 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 242581504; Read time: 0; Write time: 354368 19:30:28 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 4096; Write bytes: 301920256; Read time: 12; Write time: 311148 19:30:28 INFO - Running post-run listener: _upload_blobber_files 19:30:28 INFO - Blob upload gear active. 19:30:28 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 19:30:28 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:30:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 19:30:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Mozilla-Inbound -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 19:30:29 INFO - (blobuploader) - INFO - Open directory for files ... 19:30:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:30:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:30:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:30:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:30:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:30:29 INFO - (blobuploader) - INFO - Done attempting. 19:30:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 19:30:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:30:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:30:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:30:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:30:30 INFO - (blobuploader) - INFO - Done attempting. 19:30:30 INFO - (blobuploader) - INFO - Iteration through files over. 19:30:30 INFO - Return code: 0 19:30:30 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 19:30:30 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 19:30:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9f6ee8a788933fdae41af0bb3e90cdbb13d48374110a6323f3b73cbb3e4e75cacf053d6468f133ce7325312a2b99175228ef401bf053e35822285b9f1623815b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3624a1581f661680f89d04075f3251e009455ba6239d81c1f086a5a9bf19c9cf0eb514b320f03e8eb2194d7ee36670c02ca86694bb78de9ce077dd7250a34f9a"} 19:30:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 19:30:30 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 19:30:30 INFO - Contents: 19:30:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9f6ee8a788933fdae41af0bb3e90cdbb13d48374110a6323f3b73cbb3e4e75cacf053d6468f133ce7325312a2b99175228ef401bf053e35822285b9f1623815b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3624a1581f661680f89d04075f3251e009455ba6239d81c1f086a5a9bf19c9cf0eb514b320f03e8eb2194d7ee36670c02ca86694bb78de9ce077dd7250a34f9a"} 19:30:30 INFO - Running post-run listener: copy_logs_to_upload_dir 19:30:30 INFO - Copying logs to upload dir... 19:30:30 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 19:30:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.093875 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-01-21 19:30:30.841986) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-21 19:30:30.846407) ========= 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=523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9f6ee8a788933fdae41af0bb3e90cdbb13d48374110a6323f3b73cbb3e4e75cacf053d6468f133ce7325312a2b99175228ef401bf053e35822285b9f1623815b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3624a1581f661680f89d04075f3251e009455ba6239d81c1f086a5a9bf19c9cf0eb514b320f03e8eb2194d7ee36670c02ca86694bb78de9ce077dd7250a34f9a"} build_url:https://queue.taskcluster.net/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011217 build_url: 'https://queue.taskcluster.net/v1/task/MuyOV_6oSk6qAxQAEhcvYw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/9f6ee8a788933fdae41af0bb3e90cdbb13d48374110a6323f3b73cbb3e4e75cacf053d6468f133ce7325312a2b99175228ef401bf053e35822285b9f1623815b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3624a1581f661680f89d04075f3251e009455ba6239d81c1f086a5a9bf19c9cf0eb514b320f03e8eb2194d7ee36670c02ca86694bb78de9ce077dd7250a34f9a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-21 19:30:30.874340) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 19:30:30.874727) ========= 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=523ca41ca1e0623de333ad52000001bf-1453432164.117298-1323199767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004488 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-21 19:30:30.961855) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-21 19:30:30.962224) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-21 19:30:30.962628) ========= ========= Total master_lag: 0.30 =========