builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-010 starttime: 1452491545.17 results: success (0) buildid: 20160110193521 builduid: 23a35881b2764afea56aef0202627a84 revision: 5c34826911da061621bfa8fd2b5c50bfb35176e3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.166248) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.166774) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.167128) ========= 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=eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006260 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.195426) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.195792) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.214301) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.214601) ========= 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=eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004651 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.267901) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.268219) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.268616) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.268942) ========= 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=eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-10 21:52: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 59.9M=0s 2016-01-10 21:52:25 (59.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.180641 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.463454) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.463793) ========= 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=eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017117 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:25.547589) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-10 21:52:25.547951) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 5c34826911da061621bfa8fd2b5c50bfb35176e3 --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 5c34826911da061621bfa8fd2b5c50bfb35176e3 --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=eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-10 21:52:25,854 truncating revision to first 12 chars 2016-01-10 21:52:25,854 Setting DEBUG logging. 2016-01-10 21:52:25,854 attempt 1/10 2016-01-10 21:52:25,854 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/5c34826911da?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-10 21:52:27,059 unpacking tar archive at: mozilla-inbound-5c34826911da/testing/mozharness/ program finished with exit code 0 elapsedTime=1.478909 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-10 21:52:27.039680) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:27.040026) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:27.047327) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:27.047636) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-10 21:52:27.048119) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 52 secs) (at 2016-01-10 21:52:27.048464) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:52:27 INFO - MultiFileLogger online at 20160110 21:52:27 in /builds/slave/test-pgo 21:52:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 21:52:27 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 21:52:27 INFO - {'append_to_log': False, 21:52:27 INFO - 'base_work_dir': '/builds/slave/test-pgo', 21:52:27 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 21:52:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 21:52:27 INFO - 'branch': 'Mozilla-Inbound', 21:52:27 INFO - 'buildbot_json_path': 'buildprops.json', 21:52:27 INFO - 'config_files': ('talos/linux_config.py',), 21:52:27 INFO - 'default_actions': ('clobber', 21:52:27 INFO - 'read-buildbot-config', 21:52:27 INFO - 'download-and-extract', 21:52:27 INFO - 'populate-webroot', 21:52:27 INFO - 'create-virtualenv', 21:52:27 INFO - 'install', 21:52:27 INFO - 'run-tests'), 21:52:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:52:27 INFO - 'download_minidump_stackwalk': True, 21:52:27 INFO - 'download_symbols': 'ondemand', 21:52:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:52:27 INFO - 'tooltool.py': '/tools/tooltool.py', 21:52:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:52:27 INFO - '/tools/misc-python/virtualenv.py')}, 21:52:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:52:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:52:27 INFO - 'installer_path': 'installer.exe', 21:52:27 INFO - 'log_level': 'info', 21:52:27 INFO - 'log_name': 'talos', 21:52:27 INFO - 'log_to_console': True, 21:52:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:52:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:52:27 INFO - 'opt_config_files': (), 21:52:27 INFO - 'pip_index': False, 21:52:27 INFO - 'sps_profile': False, 21:52:27 INFO - 'sps_profile_interval': 0, 21:52:27 INFO - 'suite': 'dromaeojs-e10s', 21:52:27 INFO - 'system_bits': '32', 21:52:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:52:27 INFO - 'title': 'talos-linux64-ix-010', 21:52:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:52:27 INFO - 'use_talos_json': True, 21:52:27 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 21:52:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:52:27 INFO - 'work_dir': 'build'} 21:52:27 INFO - ##### 21:52:27 INFO - ##### Running clobber step. 21:52:27 INFO - ##### 21:52:27 INFO - Running pre-action listener: _resource_record_pre_action 21:52:27 INFO - Running main action method: clobber 21:52:27 INFO - rmtree: /builds/slave/test-pgo/build 21:52:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 21:52:28 INFO - Running post-action listener: _resource_record_post_action 21:52:28 INFO - ##### 21:52:28 INFO - ##### Running read-buildbot-config step. 21:52:28 INFO - ##### 21:52:28 INFO - Running pre-action listener: _resource_record_pre_action 21:52:28 INFO - Running main action method: read_buildbot_config 21:52:28 INFO - Using buildbot properties: 21:52:28 INFO - { 21:52:28 INFO - "project": "", 21:52:28 INFO - "product": "firefox", 21:52:28 INFO - "script_repo_revision": "production", 21:52:28 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 21:52:28 INFO - "repository": "", 21:52:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 21:52:28 INFO - "buildid": "20160110193521", 21:52:28 INFO - "pgo_build": "True", 21:52:28 INFO - "basedir": "/builds/slave/test-pgo", 21:52:28 INFO - "buildnumber": 614, 21:52:28 INFO - "slavename": "talos-linux64-ix-010", 21:52:28 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:52:28 INFO - "platform": "ubuntu64_hw", 21:52:28 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 21:52:28 INFO - "branch": "mozilla-inbound", 21:52:28 INFO - "repo_path": "integration/mozilla-inbound", 21:52:28 INFO - "revision": "5c34826911da061621bfa8fd2b5c50bfb35176e3", 21:52:28 INFO - "stage_platform": "linux64-pgo", 21:52:28 INFO - "builduid": "23a35881b2764afea56aef0202627a84", 21:52:28 INFO - "slavebuilddir": "test" 21:52:28 INFO - } 21:52:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 21:52:28 INFO - Running post-action listener: _resource_record_post_action 21:52:28 INFO - ##### 21:52:28 INFO - ##### Running download-and-extract step. 21:52:28 INFO - ##### 21:52:28 INFO - Running pre-action listener: _resource_record_pre_action 21:52:28 INFO - Running main action method: download_and_extract 21:52:28 INFO - mkdir: /builds/slave/test-pgo/build/tests 21:52:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:52:28 INFO - https://queue.taskcluster.net/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:52:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/test_packages.json 21:52:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/test_packages.json 21:52:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 21:52:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 21:52:31 INFO - Downloaded 1302 bytes. 21:52:31 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 21:52:31 INFO - Using the following test package requirements: 21:52:31 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:52:31 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:52:31 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:31 INFO - u'jsshell-linux-x86_64.zip'], 21:52:31 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:52:31 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:52:31 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:52:31 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:52:31 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:52:31 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:52:31 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:52:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:52:31 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:52: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')]} 21:52:31 INFO - https://queue.taskcluster.net/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:52:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:52:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:52:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/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 21:52:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/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 21:53:01 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip: timed out 21:53:01 INFO - retry: attempt #1 caught exception: timed out 21:53:01 INFO - retry: Failed, sleeping 30 seconds before retrying 21:53:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/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 #2 21:53:34 INFO - Downloaded 24028682 bytes. 21:53:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 21:53:34 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:53:34 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 21:53:35 INFO - Return code: 0 21:53:35 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 21:53:35 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')]} 21:53:35 INFO - https://queue.taskcluster.net/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:53:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:53:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:53:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/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 21:53:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/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 21:54:05 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip: timed out 21:54:05 INFO - retry: attempt #1 caught exception: timed out 21:54:05 INFO - retry: Failed, sleeping 30 seconds before retrying 21:54:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/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 #2 21:54:59 INFO - Downloaded 24028682 bytes. 21:54:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 21:54:59 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:54:59 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 21:55:01 INFO - Return code: 0 21:55:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:01 INFO - https://queue.taskcluster.net/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:55:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:55:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:55:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/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 21:55:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/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 21:55:03 INFO - Downloaded 11132285 bytes. 21:55:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 21:55:03 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:55:03 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 21:55:03 INFO - Return code: 0 21:55:03 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')]} 21:55:03 INFO - https://queue.taskcluster.net/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:55:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:55:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:55:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/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 21:55:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aGc36MzXQjGWa1bcn06O4A/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 21:55:14 INFO - Downloaded 55922387 bytes. 21:55:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:55:14 INFO - mkdir: /builds/slave/test-pgo/properties 21:55:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 21:55:14 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 21:55:14 INFO - Contents: 21:55:14 INFO - build_url:https://queue.taskcluster.net/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:55:14 INFO - Running post-action listener: _resource_record_post_action 21:55:14 INFO - Running post-action listener: set_extra_try_arguments 21:55:14 INFO - ##### 21:55:14 INFO - ##### Running populate-webroot step. 21:55:14 INFO - ##### 21:55:14 INFO - Running pre-action listener: _resource_record_pre_action 21:55:14 INFO - Running main action method: populate_webroot 21:55:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:55:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:55:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:55:14 INFO - '--tppagecycles', 21:55:14 INFO - '7'], 21:55:14 INFO - 'tests': ['tsvgm']}}, 21:55:14 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 21:55:14 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 21:55:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:55:14 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:55:14 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 21:55:14 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:55:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:55:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:14 INFO - 'talos_options': ['--e10s'], 21:55:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:55:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:14 INFO - 'tests': ['damp', 'tps']}, 21:55:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:14 INFO - 'talos_options': ['--e10s'], 21:55:14 INFO - 'tests': ['damp', 'tps']}, 21:55:14 INFO - 'g3': {'tests': ['dromaeo_dom']}, 21:55:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:55:14 INFO - 'other': {'tests': ['a11yr', 21:55:14 INFO - 'ts_paint', 21:55:14 INFO - 'tpaint', 21:55:14 INFO - 'sessionrestore', 21:55:14 INFO - 'sessionrestore_no_auto_restore']}, 21:55:14 INFO - 'other-e10s': {'talos_options': ['--e10s'], 21:55:14 INFO - 'tests': ['a11yr', 21:55:14 INFO - 'ts_paint', 21:55:14 INFO - 'tpaint', 21:55:14 INFO - 'sessionrestore', 21:55:14 INFO - 'sessionrestore_no_auto_restore']}, 21:55:14 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 21:55:14 INFO - 'tests': ['a11yr', 21:55:14 INFO - 'ts_paint', 21:55:14 INFO - 'tpaint', 21:55:14 INFO - 'sessionrestore', 21:55:14 INFO - 'sessionrestore_no_auto_restore']}, 21:55:14 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 21:55:14 INFO - 'tests': ['a11yr', 21:55:14 INFO - 'ts_paint', 21:55:14 INFO - 'tpaint', 21:55:14 INFO - 'sessionrestore', 21:55:14 INFO - 'sessionrestore_no_auto_restore']}, 21:55:14 INFO - 'other_l64': {'tests': ['a11yr', 21:55:14 INFO - 'ts_paint', 21:55:14 INFO - 'tpaint', 21:55:14 INFO - 'sessionrestore', 21:55:14 INFO - 'sessionrestore_no_auto_restore']}, 21:55:14 INFO - 'other_nol64': {'tests': ['a11yr', 21:55:14 INFO - 'ts_paint', 21:55:14 INFO - 'tpaint', 21:55:14 INFO - 'sessionrestore', 21:55:14 INFO - 'sessionrestore_no_auto_restore']}, 21:55:14 INFO - 'svgr': {'tests': ['tsvgx', 21:55:14 INFO - 'tsvgr_opacity', 21:55:14 INFO - 'tart', 21:55:14 INFO - 'tscrollx', 21:55:14 INFO - 'cart']}, 21:55:14 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 21:55:14 INFO - 'tests': ['tsvgx', 21:55:14 INFO - 'tsvgr_opacity', 21:55:14 INFO - 'tart', 21:55:14 INFO - 'tscrollx', 21:55:14 INFO - 'cart']}, 21:55:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:14 INFO - 'tests': ['tp5o']}, 21:55:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:14 INFO - 'talos_options': ['--e10s'], 21:55:14 INFO - 'tests': ['tp5o']}, 21:55:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:14 INFO - 'talos_options': ['--xperf_path', 21:55:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:55:14 INFO - 'tests': ['tp5n']}, 21:55:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:14 INFO - 'talos_options': ['--e10s', 21:55:14 INFO - '--xperf_path', 21:55:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:55:14 INFO - 'tests': ['tp5n']}}, 21:55:14 INFO - 'talos.zip': {'path': '', 21:55:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:55:14 INFO - Running post-action listener: _resource_record_post_action 21:55:14 INFO - ##### 21:55:14 INFO - ##### Running create-virtualenv step. 21:55:14 INFO - ##### 21:55:14 INFO - Running pre-action listener: _resource_record_pre_action 21:55:14 INFO - Running main action method: create_virtualenv 21:55:14 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 21:55:14 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 21:55:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 21:55:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:55:15 INFO - Using real prefix '/usr' 21:55:15 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 21:55:15 INFO - Installing distribute.............................................................................................................................................................................................done. 21:55:17 INFO - Installing pip.................done. 21:55:17 INFO - Return code: 0 21:55:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 21:55:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:17 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f27d6d91f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a17f0>, '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 0x280d2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', '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 21:55:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 21:55:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 21:55:17 INFO - Using env: {'DISPLAY': ':0', 21:55:17 INFO - 'HOME': '/home/cltbld', 21:55:17 INFO - 'LANG': 'en_US.UTF-8', 21:55:17 INFO - 'LANGUAGE': 'en_US:en', 21:55:17 INFO - 'LOGNAME': 'cltbld', 21:55:17 INFO - 'MAIL': '/var/mail/cltbld', 21:55:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:17 INFO - 'MOZ_NO_REMOTE': '1', 21:55:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:17 INFO - 'NO_EM_RESTART': '1', 21:55:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:17 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:17 INFO - 'SHELL': '/bin/bash', 21:55:17 INFO - 'SHLVL': '1', 21:55:17 INFO - 'TERM': 'linux', 21:55:17 INFO - 'TMOUT': '86400', 21:55:17 INFO - 'USER': 'cltbld', 21:55:17 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', 21:55:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:17 INFO - '_': '/tools/buildbot/bin/python'} 21:55:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:17 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 21:55:17 INFO - Cleaning up... 21:55:17 INFO - Return code: 0 21:55:17 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 21:55:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:17 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f27d6d91f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a17f0>, '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 0x280d2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', '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 21:55:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 21:55:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 21:55:17 INFO - Using env: {'DISPLAY': ':0', 21:55:17 INFO - 'HOME': '/home/cltbld', 21:55:17 INFO - 'LANG': 'en_US.UTF-8', 21:55:17 INFO - 'LANGUAGE': 'en_US:en', 21:55:17 INFO - 'LOGNAME': 'cltbld', 21:55:17 INFO - 'MAIL': '/var/mail/cltbld', 21:55:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:17 INFO - 'MOZ_NO_REMOTE': '1', 21:55:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:17 INFO - 'NO_EM_RESTART': '1', 21:55:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:17 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:17 INFO - 'SHELL': '/bin/bash', 21:55:17 INFO - 'SHLVL': '1', 21:55:17 INFO - 'TERM': 'linux', 21:55:17 INFO - 'TMOUT': '86400', 21:55:17 INFO - 'USER': 'cltbld', 21:55:17 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', 21:55:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:17 INFO - '_': '/tools/buildbot/bin/python'} 21:55:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:17 INFO - Downloading/unpacking psutil>=0.7.1 21:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:17 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 21:55:17 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 21:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:19 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 21:55:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 21:55:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 21:55:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:55:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:55:19 INFO - Installing collected packages: psutil 21:55:19 INFO - Running setup.py install for psutil 21:55:19 INFO - building 'psutil._psutil_linux' extension 21:55:19 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 21:55:19 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 21:55:20 INFO - building 'psutil._psutil_posix' extension 21:55:20 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 21:55:20 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 21:55:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:55:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:55:20 INFO - Successfully installed psutil 21:55:20 INFO - Cleaning up... 21:55:20 INFO - Return code: 0 21:55:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 21:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:20 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f27d6d91f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a17f0>, '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 0x280d2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', '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 21:55:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 21:55:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 21:55:20 INFO - Using env: {'DISPLAY': ':0', 21:55:20 INFO - 'HOME': '/home/cltbld', 21:55:20 INFO - 'LANG': 'en_US.UTF-8', 21:55:20 INFO - 'LANGUAGE': 'en_US:en', 21:55:20 INFO - 'LOGNAME': 'cltbld', 21:55:20 INFO - 'MAIL': '/var/mail/cltbld', 21:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:20 INFO - 'MOZ_NO_REMOTE': '1', 21:55:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:20 INFO - 'NO_EM_RESTART': '1', 21:55:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:20 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:20 INFO - 'SHELL': '/bin/bash', 21:55:20 INFO - 'SHLVL': '1', 21:55:20 INFO - 'TERM': 'linux', 21:55:20 INFO - 'TMOUT': '86400', 21:55:20 INFO - 'USER': 'cltbld', 21:55:20 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', 21:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:20 INFO - '_': '/tools/buildbot/bin/python'} 21:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:20 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:22 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:55:22 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 21:55:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:55:22 INFO - Installing collected packages: mozsystemmonitor 21:55:22 INFO - Running setup.py install for mozsystemmonitor 21:55:22 INFO - Successfully installed mozsystemmonitor 21:55:22 INFO - Cleaning up... 21:55:22 INFO - Return code: 0 21:55:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 21:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:22 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f27d6d91f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a17f0>, '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 0x280d2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', '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 21:55:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 21:55:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:55:22 INFO - Using env: {'DISPLAY': ':0', 21:55:22 INFO - 'HOME': '/home/cltbld', 21:55:22 INFO - 'LANG': 'en_US.UTF-8', 21:55:22 INFO - 'LANGUAGE': 'en_US:en', 21:55:22 INFO - 'LOGNAME': 'cltbld', 21:55:22 INFO - 'MAIL': '/var/mail/cltbld', 21:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:22 INFO - 'MOZ_NO_REMOTE': '1', 21:55:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:22 INFO - 'NO_EM_RESTART': '1', 21:55:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:22 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:22 INFO - 'SHELL': '/bin/bash', 21:55:22 INFO - 'SHLVL': '1', 21:55:22 INFO - 'TERM': 'linux', 21:55:22 INFO - 'TMOUT': '86400', 21:55:22 INFO - 'USER': 'cltbld', 21:55:22 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', 21:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:22 INFO - '_': '/tools/buildbot/bin/python'} 21:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:22 INFO - Downloading/unpacking blobuploader==1.2.4 21:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:24 INFO - Downloading blobuploader-1.2.4.tar.gz 21:55:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 21:55:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:55:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 21:55:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 21:55:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:24 INFO - Downloading docopt-0.6.1.tar.gz 21:55:25 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 21:55:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 21:55:25 INFO - Installing collected packages: blobuploader, requests, docopt 21:55:25 INFO - Running setup.py install for blobuploader 21:55:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:55:25 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 21:55:25 INFO - Running setup.py install for requests 21:55:25 INFO - Running setup.py install for docopt 21:55:25 INFO - Successfully installed blobuploader requests docopt 21:55:25 INFO - Cleaning up... 21:55:25 INFO - Return code: 0 21:55:25 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:55:25 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')]} 21:55:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:25 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')]} 21:55:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:25 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f27d6d91f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a17f0>, '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 0x280d2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', '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 21:55:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 21:55:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:55:25 INFO - Using env: {'DISPLAY': ':0', 21:55:25 INFO - 'HOME': '/home/cltbld', 21:55:25 INFO - 'LANG': 'en_US.UTF-8', 21:55:25 INFO - 'LANGUAGE': 'en_US:en', 21:55:25 INFO - 'LOGNAME': 'cltbld', 21:55:25 INFO - 'MAIL': '/var/mail/cltbld', 21:55:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:25 INFO - 'MOZ_NO_REMOTE': '1', 21:55:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:25 INFO - 'NO_EM_RESTART': '1', 21:55:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:25 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:25 INFO - 'SHELL': '/bin/bash', 21:55:25 INFO - 'SHLVL': '1', 21:55:25 INFO - 'TERM': 'linux', 21:55:25 INFO - 'TMOUT': '86400', 21:55:25 INFO - 'USER': 'cltbld', 21:55:25 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', 21:55:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:25 INFO - '_': '/tools/buildbot/bin/python'} 21:55:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:55:25 INFO - Running setup.py (path:/tmp/pip-fI13HA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:55:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:55:25 INFO - Running setup.py (path:/tmp/pip-S5Waf9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:55:26 INFO - Running setup.py (path:/tmp/pip-AEIGlV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:55:26 INFO - Running setup.py (path:/tmp/pip-tfdciB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:55:26 INFO - Running setup.py (path:/tmp/pip-bGnyJQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:55:26 INFO - Running setup.py (path:/tmp/pip-pPQmUo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:55:26 INFO - Running setup.py (path:/tmp/pip-tr4FQp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:55:26 INFO - Running setup.py (path:/tmp/pip-iPhZN5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:55:26 INFO - Running setup.py (path:/tmp/pip-wIYB4s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:55:26 INFO - Running setup.py (path:/tmp/pip-NCNdBl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:55:26 INFO - Running setup.py (path:/tmp/pip-rnzKqB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:55:26 INFO - Running setup.py (path:/tmp/pip-Zwkuqf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:55:26 INFO - Running setup.py (path:/tmp/pip-TRE1X2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:55:26 INFO - Running setup.py (path:/tmp/pip-TLMkhF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:55:26 INFO - Running setup.py (path:/tmp/pip-K0BBly-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:55:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:55:26 INFO - Running setup.py (path:/tmp/pip-7kUnqs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:55:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:55:27 INFO - Running setup.py (path:/tmp/pip-PPk_hN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:55:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:55:27 INFO - Running setup.py install for manifestparser 21:55:27 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 21:55:27 INFO - Running setup.py install for mozcrash 21:55:27 INFO - Running setup.py install for mozdebug 21:55:27 INFO - Running setup.py install for mozdevice 21:55:27 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 21:55:27 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 21:55:27 INFO - Running setup.py install for mozfile 21:55:27 INFO - Running setup.py install for mozhttpd 21:55:27 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 21:55:27 INFO - Running setup.py install for mozinfo 21:55:27 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 21:55:27 INFO - Running setup.py install for mozInstall 21:55:27 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 21:55:27 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 21:55:27 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 21:55:27 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 21:55:27 INFO - Running setup.py install for mozleak 21:55:27 INFO - Running setup.py install for mozlog 21:55:28 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 21:55:28 INFO - Running setup.py install for moznetwork 21:55:28 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 21:55:28 INFO - Running setup.py install for mozprocess 21:55:28 INFO - Running setup.py install for mozprofile 21:55:28 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 21:55:28 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 21:55:28 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 21:55:28 INFO - Running setup.py install for mozrunner 21:55:28 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 21:55:28 INFO - Running setup.py install for mozscreenshot 21:55:28 INFO - Running setup.py install for moztest 21:55:28 INFO - Running setup.py install for mozversion 21:55:28 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 21:55:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:55:28 INFO - Cleaning up... 21:55:28 INFO - Return code: 0 21:55:28 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:28 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f27d6d91f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a17f0>, '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 0x280d2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', '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 21:55:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 21:55:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:55:28 INFO - Using env: {'DISPLAY': ':0', 21:55:28 INFO - 'HOME': '/home/cltbld', 21:55:28 INFO - 'LANG': 'en_US.UTF-8', 21:55:28 INFO - 'LANGUAGE': 'en_US:en', 21:55:28 INFO - 'LOGNAME': 'cltbld', 21:55:28 INFO - 'MAIL': '/var/mail/cltbld', 21:55:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:28 INFO - 'MOZ_NO_REMOTE': '1', 21:55:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:28 INFO - 'NO_EM_RESTART': '1', 21:55:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:28 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:28 INFO - 'SHELL': '/bin/bash', 21:55:28 INFO - 'SHLVL': '1', 21:55:28 INFO - 'TERM': 'linux', 21:55:28 INFO - 'TMOUT': '86400', 21:55:28 INFO - 'USER': 'cltbld', 21:55:28 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', 21:55:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:28 INFO - '_': '/tools/buildbot/bin/python'} 21:55:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:55:29 INFO - Running setup.py (path:/tmp/pip-nRl66e-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:55:29 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)) 21:55:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:55:29 INFO - Running setup.py (path:/tmp/pip-ynv_MH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:55:29 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)) 21:55:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:55:29 INFO - Running setup.py (path:/tmp/pip-lEaXFD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:55:29 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)) 21:55:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:55:29 INFO - Running setup.py (path:/tmp/pip-AXWdG4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 21:55:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:55:29 INFO - Running setup.py (path:/tmp/pip-2L1gNW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:55:29 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)) 21:55:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:55:29 INFO - Running setup.py (path:/tmp/pip-Wktvzk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:55:29 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)) 21:55:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:55:29 INFO - Running setup.py (path:/tmp/pip-3tZZM1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:55:29 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)) 21:55:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:55:29 INFO - Running setup.py (path:/tmp/pip-Ja7toC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:55:29 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)) 21:55:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:55:29 INFO - Running setup.py (path:/tmp/pip-miMba0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:55:29 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)) 21:55:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:55:29 INFO - Running setup.py (path:/tmp/pip-2TmojO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:55:29 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)) 21:55:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:55:29 INFO - Running setup.py (path:/tmp/pip-3KsySr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:55:29 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)) 21:55:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:55:29 INFO - Running setup.py (path:/tmp/pip-MyTbXb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:55:29 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)) 21:55:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:55:29 INFO - Running setup.py (path:/tmp/pip-j_a6Eo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:55:30 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)) 21:55:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:55:30 INFO - Running setup.py (path:/tmp/pip-vOni00-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:55:30 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)) 21:55:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:55:30 INFO - Running setup.py (path:/tmp/pip-AtMPds-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:55:30 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)) 21:55:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:55:30 INFO - Running setup.py (path:/tmp/pip-PPoNVw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:55:30 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)) 21:55:30 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:55:30 INFO - Running setup.py (path:/tmp/pip-6VJuRa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:55:30 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)) 21:55:30 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)) 21:55:30 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)) 21:55:30 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)) 21:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 21:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 21:55:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:55:30 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 21:55:30 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 21:55:30 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 21:55:30 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 21:55:30 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 21:55:30 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 21:55:32 INFO - Downloading blessings-1.5.1.tar.gz 21:55:32 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 21:55:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 21:55:32 INFO - Installing collected packages: blessings 21:55:32 INFO - Running setup.py install for blessings 21:55:32 INFO - Successfully installed blessings 21:55:32 INFO - Cleaning up... 21:55:32 INFO - Return code: 0 21:55:32 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 21:55:32 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:55:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:55:32 INFO - Reading from file tmpfile_stdout 21:55:32 INFO - Current package versions: 21:55:32 INFO - argparse == 1.2.1 21:55:32 INFO - blessings == 1.5.1 21:55:32 INFO - blobuploader == 1.2.4 21:55:32 INFO - docopt == 0.6.1 21:55:32 INFO - manifestparser == 1.1 21:55:32 INFO - mozInstall == 1.12 21:55:32 INFO - mozcrash == 0.16 21:55:32 INFO - mozdebug == 0.1 21:55:32 INFO - mozdevice == 0.47 21:55:32 INFO - mozfile == 1.2 21:55:32 INFO - mozhttpd == 0.7 21:55:32 INFO - mozinfo == 0.9 21:55:32 INFO - mozleak == 0.1 21:55:32 INFO - mozlog == 3.1 21:55:32 INFO - moznetwork == 0.27 21:55:32 INFO - mozprocess == 0.22 21:55:32 INFO - mozprofile == 0.28 21:55:32 INFO - mozrunner == 6.11 21:55:32 INFO - mozscreenshot == 0.1 21:55:32 INFO - mozsystemmonitor == 0.0 21:55:32 INFO - moztest == 0.7 21:55:32 INFO - mozversion == 1.4 21:55:32 INFO - psutil == 3.1.1 21:55:32 INFO - requests == 1.2.3 21:55:32 INFO - wsgiref == 0.1.2 21:55:32 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:55: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')]} 21:55:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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')]} 21:55:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:32 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f27d6d91f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a17f0>, '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 0x280d2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', '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 21:55:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 21:55:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 21:55:32 INFO - Using env: {'DISPLAY': ':0', 21:55:32 INFO - 'HOME': '/home/cltbld', 21:55:32 INFO - 'LANG': 'en_US.UTF-8', 21:55:32 INFO - 'LANGUAGE': 'en_US:en', 21:55:32 INFO - 'LOGNAME': 'cltbld', 21:55:32 INFO - 'MAIL': '/var/mail/cltbld', 21:55:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:32 INFO - 'MOZ_NO_REMOTE': '1', 21:55:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:32 INFO - 'NO_EM_RESTART': '1', 21:55:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:32 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:32 INFO - 'SHELL': '/bin/bash', 21:55:32 INFO - 'SHLVL': '1', 21:55:32 INFO - 'TERM': 'linux', 21:55:32 INFO - 'TMOUT': '86400', 21:55:32 INFO - 'USER': 'cltbld', 21:55:32 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', 21:55:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:32 INFO - '_': '/tools/buildbot/bin/python'} 21:55:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:32 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)) 21:55:32 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)) 21:55:32 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)) 21:55:32 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)) 21:55:32 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)) 21:55:32 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)) 21:55:32 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)) 21:55:32 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)) 21:55:32 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)) 21:55:32 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)) 21:55:32 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)) 21:55:32 INFO - Cleaning up... 21:55:32 INFO - Return code: 0 21:55:32 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 21:55: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')]} 21:55:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55: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')]} 21:55:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:32 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f27d6d91f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a17f0>, '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 0x280d2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', '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 21:55:32 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 21:55:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 21:55:32 INFO - Using env: {'DISPLAY': ':0', 21:55:32 INFO - 'HOME': '/home/cltbld', 21:55:32 INFO - 'LANG': 'en_US.UTF-8', 21:55:32 INFO - 'LANGUAGE': 'en_US:en', 21:55:32 INFO - 'LOGNAME': 'cltbld', 21:55:32 INFO - 'MAIL': '/var/mail/cltbld', 21:55:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:32 INFO - 'MOZ_NO_REMOTE': '1', 21:55:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:32 INFO - 'NO_EM_RESTART': '1', 21:55:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:32 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:32 INFO - 'SHELL': '/bin/bash', 21:55:32 INFO - 'SHLVL': '1', 21:55:32 INFO - 'TERM': 'linux', 21:55:32 INFO - 'TMOUT': '86400', 21:55:32 INFO - 'USER': 'cltbld', 21:55:32 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', 21:55:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:32 INFO - '_': '/tools/buildbot/bin/python'} 21:55:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:33 INFO - Downloading/unpacking jsonschema 21:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:55:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:55:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:55:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:55:34 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 21:55:34 INFO - Downloading/unpacking functools32 (from jsonschema) 21:55:34 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 21:55:34 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 21:55:34 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 21:55:34 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 21:55:34 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 21:55:34 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 21:55:35 INFO - Downloading functools32-3.2.3-2.tar.gz 21:55:35 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 21:55:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 21:55:35 INFO - warning: no files found matching '*.txt' 21:55:35 INFO - no previously-included directories found matching 'build' 21:55:35 INFO - no previously-included directories found matching 'dist' 21:55:35 INFO - no previously-included directories found matching '.git*' 21:55:35 INFO - Installing collected packages: jsonschema, functools32 21:55:35 INFO - Running setup.py install for functools32 21:55:35 INFO - warning: no files found matching '*.txt' 21:55:35 INFO - no previously-included directories found matching 'build' 21:55:35 INFO - no previously-included directories found matching 'dist' 21:55:35 INFO - no previously-included directories found matching '.git*' 21:55:35 INFO - Successfully installed jsonschema functools32 21:55:35 INFO - Cleaning up... 21:55:35 INFO - Return code: 0 21:55:35 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 21:55:35 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')]} 21:55:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:55:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:35 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')]} 21:55:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:55:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:55:35 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f27d6d91f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27408a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280f230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28a17f0>, '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 0x280d2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d760>, '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': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', '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 21:55:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 21:55:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 21:55:35 INFO - Using env: {'DISPLAY': ':0', 21:55:35 INFO - 'HOME': '/home/cltbld', 21:55:35 INFO - 'LANG': 'en_US.UTF-8', 21:55:35 INFO - 'LANGUAGE': 'en_US:en', 21:55:35 INFO - 'LOGNAME': 'cltbld', 21:55:35 INFO - 'MAIL': '/var/mail/cltbld', 21:55:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:35 INFO - 'MOZ_NO_REMOTE': '1', 21:55:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:35 INFO - 'NO_EM_RESTART': '1', 21:55:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:35 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:35 INFO - 'SHELL': '/bin/bash', 21:55:35 INFO - 'SHLVL': '1', 21:55:35 INFO - 'TERM': 'linux', 21:55:35 INFO - 'TMOUT': '86400', 21:55:35 INFO - 'USER': 'cltbld', 21:55:35 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', 21:55:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:35 INFO - '_': '/tools/buildbot/bin/python'} 21:55:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:55:35 INFO - Downloading/unpacking flake8 21:55:35 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 21:55:35 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 21:55:35 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 21:55:35 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 21:55:35 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 21:55:35 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 21:55:37 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:55:37 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 21:55:37 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:55:37 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 21:55:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55:37 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 21:55:37 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55: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 21:55:37 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 21:55:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 21:55:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:55:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:55:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:55:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:55:37 INFO - no previously-included directories found matching 'docs/_build' 21:55:37 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:55:38 INFO - Running setup.py install for pep8 21:55:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:55:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:55:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:55:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:55:38 INFO - no previously-included directories found matching 'docs/_build' 21:55:38 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 21:55:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:55:38 INFO - Cleaning up... 21:55:38 INFO - Return code: 0 21:55:38 INFO - Running post-action listener: _resource_record_post_action 21:55:38 INFO - Running post-action listener: _start_resource_monitoring 21:55:38 INFO - Starting resource monitoring. 21:55:38 INFO - ##### 21:55:38 INFO - ##### Running install step. 21:55:38 INFO - ##### 21:55:38 INFO - Running pre-action listener: _resource_record_pre_action 21:55:38 INFO - Running main action method: install 21:55:38 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:55:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:55:38 INFO - Reading from file tmpfile_stdout 21:55:38 INFO - Detecting whether we're running mozinstall >=1.0... 21:55:38 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 21:55:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 21:55:38 INFO - Reading from file tmpfile_stdout 21:55:38 INFO - Output received: 21:55:38 INFO - Usage: mozinstall [options] installer 21:55:38 INFO - Options: 21:55:38 INFO - -h, --help show this help message and exit 21:55:38 INFO - -d DEST, --destination=DEST 21:55:38 INFO - Directory to install application into. [default: 21:55:38 INFO - "/builds/slave/test-pgo"] 21:55:38 INFO - --app=APP Application being installed. [default: firefox] 21:55:38 INFO - mkdir: /builds/slave/test-pgo/build/application 21:55:38 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'] 21:55:38 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 21:55:54 INFO - Reading from file tmpfile_stdout 21:55:54 INFO - Output received: 21:55:54 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 21:55:54 INFO - Running post-action listener: _resource_record_post_action 21:55:54 INFO - ##### 21:55:54 INFO - ##### Running run-tests step. 21:55:54 INFO - ##### 21:55:54 INFO - Running pre-action listener: _resource_record_pre_action 21:55:54 INFO - Running main action method: run_tests 21:55:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 21:55:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 21:55:54 INFO - Python 2.7.3 21:55:54 INFO - Return code: 0 21:55:54 INFO - grabbing minidump binary from tooltool 21:55: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')]} 21:55:54 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 0x28a17f0>, '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 0x280d2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d760>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 21:55:54 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 21:55:54 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 21:55:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:55:54 INFO - Return code: 0 21:55:54 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 21:55:54 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 21:55:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 21:55:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 21:55:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 21:55:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 21:55:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 21:55:55 INFO - Return code: 0 21:55:55 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test-pgo/build 21:55:55 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-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:55:55 INFO - Using env: {'DISPLAY': ':0', 21:55:55 INFO - 'HOME': '/home/cltbld', 21:55:55 INFO - 'LANG': 'en_US.UTF-8', 21:55:55 INFO - 'LANGUAGE': 'en_US:en', 21:55:55 INFO - 'LOGNAME': 'cltbld', 21:55:55 INFO - 'MAIL': '/var/mail/cltbld', 21:55:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:55:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 21:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:55:55 INFO - 'MOZ_NO_REMOTE': '1', 21:55:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:55:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:55:55 INFO - 'NO_EM_RESTART': '1', 21:55:55 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:55:55 INFO - 'PWD': '/builds/slave/test-pgo', 21:55:55 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 21:55:55 INFO - 'SHELL': '/bin/bash', 21:55:55 INFO - 'SHLVL': '1', 21:55:55 INFO - 'TERM': 'linux', 21:55:55 INFO - 'TMOUT': '86400', 21:55:55 INFO - 'USER': 'cltbld', 21:55:55 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243', 21:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:55:55 INFO - '_': '/tools/buildbot/bin/python'} 21:55:55 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:55:55 INFO - mozversion application_buildid: 20160110193521 21:55:55 INFO - mozversion application_changeset: 5c34826911da061621bfa8fd2b5c50bfb35176e3 21:55:55 INFO - mozversion application_display_name: Nightly 21:55:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:55:55 INFO - mozversion application_name: Firefox 21:55:55 INFO - mozversion application_remotingname: firefox 21:55:55 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 21:55:55 INFO - mozversion application_vendor: Mozilla 21:55:55 INFO - mozversion application_version: 46.0a1 21:55:55 INFO - mozversion platform_buildid: 20160110193521 21:55:55 INFO - mozversion platform_changeset: 5c34826911da061621bfa8fd2b5c50bfb35176e3 21:55:55 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 21:55:55 INFO - mozversion platform_version: 46.0a1 21:55:55 INFO - using testdate: 1452491755 21:55:55 INFO - actual date: 1452491755 21:55:55 INFO - starting webserver on 'localhost:45835' 21:55:55 INFO - SUITE-START | Running 3 tests 21:55:55 INFO - TEST-START | dromaeo_css 21:55:55 INFO - operating with platform_type : linux_ 21:55:55 INFO - Initialising browser for dromaeo_css test... 21:55:55 INFO - TEST-INFO | started process 16572 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkCisV0/profile http://localhost:45835/getInfo.html) 21:55:59 INFO - PROCESS | 16572 | 21:55:59 INFO - PROCESS | 16572 | (process:16657): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:56:00 INFO - PROCESS | 16572 | JavaScript warning: resource://gre/modules/TelemetrySession.jsm, line 1783: unreachable code after return statement 21:56:00 INFO - PROCESS | 16572 | __metrics Screen width/height:1600/1200 21:56:00 INFO - PROCESS | 16572 | colorDepth:24 21:56:00 INFO - PROCESS | 16572 | Browser inner width/height: 1024/697 21:56:00 INFO - PROCESS | 16572 | __metrics 21:56:00 INFO - PROCESS | 16572 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 21:56:01 INFO - TEST-INFO | 16572: exit 0 21:56:01 INFO - Browser initialized. 21:56:01 INFO - Running cycle 1/1 for dromaeo_css test... 21:56:01 INFO - TEST-INFO | started process 16706 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkCisV0/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:56:03 INFO - PROCESS | 16706 | 21:56:03 INFO - PROCESS | 16706 | (process:16763): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:56:13 INFO - PROCESS | 16706 | RSS: Main: 150441984 21:56:13 INFO - PROCESS | 16706 | 21:57:03 INFO - PROCESS | 16706 | 1452491823347 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] 21:59:12 INFO - PROCESS | 16706 | Cycle 1(1): loaded http://localhost:45835/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45835/tests/dromaeo/cssquery-ext.html) 21:59:13 INFO - PROCESS | 16706 | RSS: Main: 158392320 21:59:13 INFO - PROCESS | 16706 | 22:02:15 INFO - PROCESS | 16706 | Cycle 1(1): loaded http://localhost:45835/tests/dromaeo/cssquery-ext.html (next: http://localhost:45835/tests/dromaeo/cssquery-jquery.html) 22:02:15 INFO - PROCESS | 16706 | RSS: Main: 153624576 22:02:15 INFO - PROCESS | 16706 | 22:05:17 INFO - PROCESS | 16706 | Cycle 1(1): loaded http://localhost:45835/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45835/tests/dromaeo/cssquery-mootools.html) 22:05:18 INFO - PROCESS | 16706 | RSS: Main: 154337280 22:05:18 INFO - PROCESS | 16706 | 22:08:20 INFO - PROCESS | 16706 | Cycle 1(1): loaded http://localhost:45835/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45835/tests/dromaeo/cssquery-prototype.html) 22:08:20 INFO - PROCESS | 16706 | RSS: Main: 152981504 22:08:20 INFO - PROCESS | 16706 | 22:11:22 INFO - PROCESS | 16706 | Cycle 1(1): loaded http://localhost:45835/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45835/tests/dromaeo/cssquery-yui.html) 22:11:23 INFO - PROCESS | 16706 | RSS: Main: 153432064 22:11:23 INFO - PROCESS | 16706 | 22:14:26 INFO - PROCESS | 16706 | Cycle 1(1): loaded http://localhost:45835/tests/dromaeo/cssquery-yui.html (next: http://localhost:45835/tests/dromaeo/cssquery-dojo.html) 22:14:26 INFO - PROCESS | 16706 | RSS: Main: 155320320 22:14:26 INFO - PROCESS | 16706 | 22:14:26 INFO - PROCESS | 16706 | __start_tp_report 22:14:26 INFO - PROCESS | 16706 | _x_x_mozilla_page_load 22:14:26 INFO - PROCESS | 16706 | _x_x_mozilla_page_load_details 22:14:26 INFO - PROCESS | 16706 | |i|pagename|runs| 22:14:26 INFO - PROCESS | 16706 | |0;dojo.html;3152.3852848694505;3187.1235410262175;3222.9516557251645;3227.4190645683766;3237.158338831903;5123.820666276681;5221.373435187783;5233.476652334764;5239.8690032749155;5281.18141688038;5150;5236.607254455922;5239.738013099349;5257.23770053342;5278.9208161877505;4489.663275254353;4768.451628062765;4893.975530122344;4900.485449027854;4931.038447502739;4739.360186374847;4827.08285425768;4853.538913803183;4887.926681099786;4900.264960255954;4724.267738500538;4787.210110331791;4796.208625576775;4845.055214233226;4858.5384388483035;4779.784909679072;4810.927836082462;4822;4830.830920917751;4842.080004799077;4671.299305104228;4763.666543341968;4783.019481006385;4807.807687692488;4838;5044.167712327461;5084.491550844923;5139.743012849342;5143.3570803649545;5180.197069454241;5555.027870122753;5572.749226284827;5573.637713548606;5573.832785016415;5581.720913954286;5535.446455354472;5624.3531993820825;5630.296212973379;5633.718314084279;5656.971715141439;5259.789608415659;5265.447128051576;5275.973620131913;5280.498352656491;5288.81489147878;5745.569082318842;5805.651660900359;5809.331926828469;5824.271966004249;5862.413758624103;5516.200150978166;5561.777528898799;5567.24842146305;5589.413111623302;5599.580031497654;5513.641613295123;5610;5631.577631677558;5675.460831221028;5684.232628595169;5561.054620714488;5579.107342825209;5579.972100139474;5581.330240371181;5585.078462053797;5511.807086751945;5563.554915606742;5568.6101972862325;5585.413531579207;5629.043062679354;4337.7397356158735;4341.6526677865695;4347.108842687241;4375.321825117112;4408.757518336458;3858.961410385916;3893.649571538575;3928;3933.940990885139;3969.861054863066;5215.713135777492;5244.108501554745;5313;5329.600279979017;5353.116735738559;5660.462256085665;5677.063284558001;5679.6876171810945;5683;5688.459596338341;5194.792208311701;5259.737013149327;5281.287026251407;5282.709450980233;5323.680579165185;4783.928241076386;4787.928181077286;4860.1737704590305;4885.5358740919555;4909.4354149273295;5653.858653533694;5697.373288938214;5724.656520608693;5739.913901291484;5742.3396309424115;5393.217983392465;5585.273914391103;5586.357568879631;5593.412691667317;5655.208270842168;5729.656220626775;5735.885282294247;5795;5802.390748971297;5852;5560.833175004756;5572.804951826666;5581;5606.24315717373;5629.352624448077;5508.20131080999;5625.803096891589;5669.858253543694;5704.80033198836;5707.9429205709075;5459.563234941115;5494.2582751328055;5508.091164957737;5532.944670553243;5560.527355174855;5499.780008799603;5536.667799932017;5542.057850165401;5551.278333816578;5568.415316391883;5347.251384806208;5407.134858422791;5441.727913604224;5474.671519708831;5489.780408783764;5440.782368705208;5529.391766905717;5533.7786488540005;5549.972250138723;5578.832635020955;5406;5451.945480545302;5467.425920278313;5472.288602481811;5476.863078423071 22:14:26 INFO - PROCESS | 16706 | |1;ext.html;505.6005755453192;540.1681410627633;579.1544345255926;583.9547210493217;605.2283338743102;27717.3070673233;35343.646563534356;35581.64418355816;35610.64389356106;35678.108047298796;38317.42523862137;38608;38789.806050969775;38907;38981.610183898156;8309.293710034639;8487.278581320596;8500.319974402064;8507.404481686284;8510.361722870779;8260.876086858701;8399.454035487704;8417.621207045697;8422.747317580452;8431.620577074016;8466.619002144887;8469.32245420365;8490.915090849074;8558.914410855905;8560.614772335228;8514.957425212866;8526.531040792754;8548.145185481464;8573.271271941892;8585.699500517514;8362.58187090643;8501.702440414618;8512.021117571496;8522.232999030055;8552.957235213846;38473.42289865654;39122.60877391218;39201;39203.4119488205;39253.411198832044;3255.9348813023607;3355.7147642450423;3463.3246516929207;3495.3533596284515;3690.907727306812;30320.24199395011;30504.694953050406;30557;31040.689593104005;31263.218419539695;3867.613238676138;3942.5860284669984;3969.523657161159;3973.304671682444;4024.094578719794;263073;263652;263739;264071;264549;54487.727561362735;55476.72261638666;60536.697316513135;60873;63187.6840615794;55956.16065759017;56207.43792562022;56208.437915621136;56286.43713562812;56332.718336408056;15202;15550.688986220212;15572.688546229012;15577.065376077471;15650.37398504047;11470.254433461798;11574.016208622194;11596.536138554364;11606.88393116075;11629.302241865515;19386.321478748177;19564.413067607995;20148.597028059357;20225.595488090155;20301.187952482032;8113.959430202811;8284.337253019745;8342.62458189383;8343.290820280223;8376.748697539084;946.2666433514082;1010.6311196413257;1018.4398580780542;1029.9545960849593;1032.566322144701;28175;28204.43591128166;28649.427011459655;28843.85578072096;28909.855450723033;1048.9685309440729;1070.8340207267886;1145.0724912820476;1182.4619797991893;1208.5951206345972;14825.553783625797;14991.32539035746;15063.54809355721;15180.772288415683;15218.315175817115;301884;303633;304265;304791;305244;50570;50901.49098508967;51074.74462627663;51428.22857657138;51526.74236628793;1332.666833291677;1343.4693296148162;1347.2724728646422;1354.3025341948708;1362.209918247434;27138.72861271362;28304.85847570749;28463.857680711462;30739;31785;46001.76999114983;47010.29484557803;47099.76450117728;47368.28947565858;47404;283350;287356;287680;287928;289842;42018.36972445354;43504.564954350055;43575.56424435715;44213.336799947385;44352.7782361099;39618;40374.59625403708;40583.3912491307;40596.79701601473;40864.79567602143;33237.667623323454;33728.49407258942;33753.3249335007;34115.82942085274;34136;34061.82969085238;34878.12804679904;35130.47304290387;35159;38662.613373865905;33748.325033499685;33858.83070584631;33984.320313594086;38682.22635547329;38983.41524877186;29322.853385732935;30525.389492210477;30690.693093068785;31204.53193202149;31503.84248078745;24737.381565461008;25019.374515637253;25032.874835625706;25442.491150177262;25488.362790930376 22:14:26 INFO - PROCESS | 16706 | |2;jquery.html;353.1453881606512;353.31985927090363;353.52980535887264;354.52139611524444;355.8843375902832;16034.679306413866;16558.337666493328;17006.234719437623;17705.1147442628;17719.911400442998;15586.142762148105;15587.532374028797;16022.278997445113;16497;16548.917255413737;4876.609871210295;5006.624503162259;5014.799408023675;5018.673786203904;5026.52248036436;4816.373871396713;4927.950720492785;5015.84952451427;5426.403095659474;5431.809886653968;5105.566026887719;5298.205269209611;5302.973485132569;5409.729513524328;5416.160495123242;5236.764345604457;5245.86885327866;5273.973630131844;5528.474794894479;5959.10613407988;5322.33470816148;5325.733713314319;5337.039332920072;5337.893242135175;5347.51872331492;14714.55856324312;19572.217111315673;20040.394748682724;20273;20300.492487687778;2021.29254760834;2161.8702877827222;2182.5416662500893;2233.7989580937633;2317.2237300504353;9671.854922176173;9807.460589667566;9839.98280352526;9932.056454636799;12083;1845.5386153461634;1867.561123136062;1872.4008317338457;1965.7935916728682;1971.2607772085469;49468.50531494675;51024.489755102346;51275.23087153696;51434.742826286005;51522.742386288206;9892.901070989343;9914.206863450907;9952.203823694088;9967.651132210483;10009;12742.936285318514;12950.805737913939;12964.027697922693;12971.156874803108;12985.870141298656;7726.690932362755;7741.922580774233;7767.766966990999;7770.728024519115;7787.727429539939;7492.288232617892;7494.362979146834;7529.774106776696;7548.584827834412;7576.7348142814735;4433.512313645497;4464.933026004613;4481.395011673448;4488.887777805581;4519.480259770168;1808.2495764257685;1854.5270955906158;1860.2744929477515;1881.2757088521018;1932.9710054348914;910.3809409601535;939.1829108675496;975.0834215837091;1007.4861820471613;1029.6602121300102;15649.29578168985;15852.682946341009;15889.52331430059;15928.522144335457;16411.42560010394;945.3666043750703;952.2334520710756;962.5764663548016;969.3602222533235;1017.3895662602528;9209.221183800622;9435.669751558662;9610.327277090537;9621;9658.323917325719;462783;472366;505458;513125;518393;64701.35298646953;64856.35143648597;73542.63228683929;73574.26425735674;73577.63211183909;1121.7139629394615;1156.5431654496426;1161.9419029048684;1182.3497076607832;1184.514349116858;14188.290585470892;14794;14943.476978305707;15160.848391516385;15230.46693365727;30401.239969000068;30807.53788693216;30815.38369232558;31405.371892561565;32119.518207226443;38006.049848754;38124;38705.4194187093;40021.59978400295;42651.3602295972;7090.503664743418;7224.096987876515;7376.336129748348;7378.741744038933;7421.666025028779;9191.540422978691;10178.23663225276;10273.897261027294;10960.12319014461;11367;8793.736187914374;9360.4383736976;9526.428414295164;9620.182284505896;9645.807083858424;9243.168114869693;9464.432134071978;9636.46999415039;10054.64808731691;10071.647492337734;9210.815783684155;9512.667056652985;9755;9809.950950245204;9893.802123957625;7404.111506619025;7440.962795185989;7524.360429363565;7536.811579710551;7555.73554925575;9210.631574736935;9772.560234789593;9855.753606159904;9882.50587470638;9901.55443005052 22:14:26 INFO - PROCESS | 16706 | |3;mootools.html;1446.4720377062372;1449.934752936118;1619.651774868403;1747.3098126240127;1776.2983621469527;7501.212372700863;7581.583012934287;7689.461737678364;7717.961410192948;7724.420668449868;7651.961740191306;7677.23227677232;7704.0755109386955;7710;7779.377649788017;2328.77876601723;2343.718753749544;2355.4817940053163;2356.77610626991;2357.528494301138;2384.1893756122913;2394.2577800881695;2410.4455975125734;2411.67442395277;2412.746661600533;2313.6298192289237;2341.3210169050953;2349.9412514687183;2384.95230095398;2393.9162129325478;2352.200251914349;2394.2338451695377;2394.5929192037397;2396.0775101586014;2396.4488167721433;2341.8477798943186;2341.94145146371;2357.6581395697526;2371.7311238487328;2372.1223147435558;7611.2388761124;7641.923580764177;7669.693212271558;7689.807754806118;7716.498427602188;4709.740964246965;4852.660313778037;4856.17445034345;4863;4871.610271178297;3085.182426656938;3163.942989329141;3172.714455698975;3224.6614105518815;3236.757243206769;1074.919381046417;1086.5653738504582;1966.7246585478044;1992.14337834731;2013.355726167627;10152.746181345452;10217.2847900647;10246;10247.538860751283;10249.180065594735;4181.351890456984;4262.2754131797665;4348.43470348859;4363.323684828857;4370.628496577827;8476.110008449148;8483.448575842674;8483.787905302417;8492.957535212285;8509;4833.3958255218095;4847.587955023792;4854.296127061556;4858.6356023298395;4881.438634557071;4424.6239069678795;4439.467263928349;4476.7090139140855;4478.462584489882;4515.88710282239;3482.164280572644;3570.8036058016537;3590.7666001709804;3597.118705917067;3609.4044482660065;1098.7692584557333;1101.911847052234;1104.2160066352978;1105.0441368216434;1107.3521989635992;1015.8323876560432;1021.4841505039907;1143.937083460401;1174.3364998775664;1328.7209685966056;6825.658717064127;7048.859022819617;7055.153381594241;7086.50394472382;7146.392556632641;1145.7937571237092;1151.3782557419067;1151.4588143572507;1156.5142640091183;1165.2658824940233;5726.856828579235;5783.045797443375;5788.102844059177;5880.617759845682;5926.703664816742;8605.827883442422;8746.65013399457;8998.235150012191;9038.457692538468;9134.81730365389;8887.066857979948;8909.5099769512;8941.284697224206;8988.730338089737;9024.097590241056;7085.326893945067;7089.751858684921;7228.819279518054;7231.783046508614;7274.927250727531;7169.892451613126;7250.854982900418;7263.309985551364;7272.636368181676;7300.817479563053;8043.839123217386;8289.088200297841;8291.792705182419;8336.041355243982;8412.915870841212;8075.394345423999;8225.753227403187;8322.958385208036;8341.16588341161;8343.207395297437;6739.5619284746335;6961.930380696128;6964.895526567207;6973.058637083929;6998.825029374307;7344.192138864613;7385.593792341473;7403.555786652602;7405.9629701851145;7415.147258065176;6465.288818230085;6907.827304317432;6927.3419025192525;7004.824879378057;7277.745278915212;6755.763548275787;6757.358050985148;6812.318768123148;6861.519693621598;6960.860782784417;6728.360805723449;6849.2808255132495;6874.828129296808;6889.345512176335;6894.379505844498;6049.18336024631;6050.727717252822;6051.788187413419;6053.788117415869;6056.727447264796;6351.07909353132;6408.903866441914;6500.544961852814;6501.642409667514;6510.283868774526 22:14:26 INFO - PROCESS | 16706 | |4;prototype.html;340.0817791961703;342.83029900199404;345.219803244667;349.6101846441218;350.71288371675945;6206.1001154832575;6238.6256824590555;6257.155284036659;6288.622682639045;6371.681415929196;6156.6613836238985;6211.8757624847585;6213.968930155354;6216.347283535231;6217.129601855732;4730.597899178574;4734.644901632374;4741.596964258034;4761.976190119045;4771.451283102436;4726.031163611451;4740.786664600084;4746.288056791492;4750.762461876892;4753.667243292971;4552.817887284521;4627.7967728390695;4638.744869032202;4649.04694537619;4657.720536767788;4609.976950115245;4616.976915115403;4634.467036290819;4667.533246675305;4671.462781780071;4054.6553542948886;4062.065724883264;4064.9390259146135;4077.2660921034203;4078.163976384834;5848.766049358021;5879.500242479395;5892.499137573312;5896.705164741788;5913.645181289136;5273.288106105665;5416.268803711528;5423.918641220345;5431.348238211401;5442.972785136049;6491.221053473566;6527.771527996498;6563.737450501974;6568.540202185849;6572.047053177263;4478.731276123443;4485.663575231838;4489.21438748221;4497.987952710614;4502.077074199781;6756.8850190577605;6809.625470599115;6820.6930688119155;6840.213375461786;6859.211190713082;4604.654650901196;4611.792469338888;4618.51505591908;4626.606738427204;4630.86107416778;4670.743109129031;4677.976610116928;4679;4680.765961701902;4683.320918466871;5417.972910135424;5434.130539113723;5435.1847222916085;5457.645253058538;5478.753456094485;6499.512536559777;6601.603903765789;6610.206775187008;6621.370969757865;6639.502037347218;1636.4599682104877;1658.519029481461;1659.3528523875698;1659.701253774308;1659.8672106231472;1061.7557961668858;1073.736934451049;1075.596651255779;1097.5006372100675;1102.239454776202;6151.384861513813;6175.980963141032;6183.474404675563;6186.969065154735;6205.906911396332;6746.730130794714;6805.693743781541;6808.11494505721;6824.249332573413;6835.282295359015;6131.356207598227;6163.537734669918;6195.81412557624;6233.158523599347;6265.561410701298;3694.5751238607904;3697;3713.2202237530423;3733.9253214935547;3742.3076730804887;7044.823879403056;7055.929440705528;7066.611336376446;7073.221945585981;7079.185893622196;6894.24163342032;7032.402245809163;7039.472039596948;7047.6828542714675;7054.506184567031;7028;7032.156141262979;7075.469339799535;7078.150621925299;7098.077249957472;6858.725650973905;6864.176298844171;6865.27914568963;6877.65611719402;6877.931220687729;6639.867202655823;6767.1879374475375;6776.830579235559;6779.830504237433;6791.524593278423;7013.789586312419;7014.859702806018;7051.6474176292;7062.293770622897;7067.328603782633;4603.792829322621;4605.93091103627;4611.077784443192;4616.676832621684;4647.55848194421;6639.601623902581;6841.589504629539;6864.38220560152;6868.725250989904;6897.7240910363025;6693.364130407604;6696.665166741741;6704.363085506869;6704.6982885769285;6723.394894459327;6533.150690410406;6642.568233064835;6658.900116498159;6685.231198412245;6694.866102677822;6448.258450278278;6533.085368048382;6539.084528165966;6542.084108224947;6543.476521878143;6668.399844013868;6675.03212034262;6681.498887583353;6696.799096027127;6704.229013663491;6380.266269378895;6509.511786616117;6514.9348506514325;6525.804225873231;6533.18335208099 22:14:26 INFO - PROCESS | 16706 | |5;yui.html;756.7237958145276;774.256713554987;798.4809873582171;844.4806444036915;867.5792240763233;13501.324933753322;15122.773158402619;15214.695706085871;15412.69174616507;15435.691286174299;14585.854141458582;14641.780373294408;14642.267886605681;14657.047291926017;14705.779413308808;800.1998001998002;810.6271115286975;811.4036183405193;813.0609146435876;828.5318794880903;809.3120847279795;810.6068556750001;813.032491335309;814.4461765999118;816.8407160603684;851.3146916732028;855.2858363266648;855.4140413816524;857.1214505132258;857.130013036765;848.3213429256632;849.7110982266059;851.2764150472111;853.6926706385697;857.5540718826206;627.6892937995722;629.1129507394552;630.250002497027;631.084926856056;633.5818359882502;13586.048976571645;13666.043376963617;13676.042677012514;13698.041137120406;13700.93149534256;12813.935930320382;12826.166299190616;12919.806202906964;13080.149790263698;13176.143550669272;10378.63674771387;10399.584016639325;10500.737481562946;10502.212334074975;10524.842127368096;3643.8542458301636;3675.117971686803;3675.558932928039;3688.7417880748358;3851.788151651686;45808.08383832338;46442.53557464416;46443;47600.761996189794;47695.76152119217;9973.80034467986;10223.795524089477;10270.537825797857;10298.536565854405;10381;18253.63492730138;18339.08304584792;18339.54151146232;18348.082595870153;18351.54121146982;7378.446616503784;7471.178170401252;7535.359494442924;7606.581638009852;7798.142204357516;4258.957410425856;4273.807678654468;4309.91380172401;4322.783860806947;4327.56724327571;3087.9073627791204;3102.674219206951;3119.906402807919;3122.0945925681754;3124.4532206864164;562.6230425614848;574.2563380422363;579.6666916523017;588.9234399528034;588.9852753681192;535.266684642043;649.9707513161919;656.4747306961647;659.7163219815429;665.6205962601271;8561.52911589856;8611.827763444697;8643.35174861888;8685.782855428664;8724.520151391735;535.4725595288637;552.2241251042292;665.1353240786957;686.0258433025116;704.4188544450849;4028.2950483665827;4084.65280451159;4085.346344584852;4155.836365817516;4173.165366926627;53665.46334536605;54791.72604136954;54968;55336.44663553313;55346.723266384215;49395.50604493981;49597;49608.255876161886;50197.74901125471;50784;45142;45501;45644.77177614091;46337.536624634;47750.52249477461;8614.267787238156;8624.698135565224;8864.689735859214;8894;8929;33678.49482257719;34469.82765086158;35072.47391289082;35074.29851403009;35174.944751657495;43625.47310844105;43823.780881095394;44373.778131110426;44392.33411498766;44714.3292850614;11511.136664750009;11556.248843825124;11702.70743231426;11748.88251117478;11830.940845295718;12402.875971240172;12440.440180192094;12524.123311368117;12952.611421657366;13094.803577946148;11040.904224861013;11366.829497557708;11461.713457163638;11606.535738570701;12017.879821201675;10648.414337211218;10985.780284394426;10995.505202265756;11027.83458248111;11108.44457777124;10263.84604230952;10541.683749487527;10949.288296260718;11412.258203216765;11503.48234329474;9369.344145909721;9681.757956051155;9692.854607180758;9742;9787.804243915225;8861.778455538662;9365.531723413938;9647.372920760128;9784.902150978683;9903.653372131941 22:14:26 INFO - PROCESS | 16706 | __end_tp_report 22:14:26 INFO - PROCESS | 16706 | __start_cc_report 22:14:26 INFO - PROCESS | 16706 | _x_x_mozilla_cycle_collect,317 22:14:26 INFO - PROCESS | 16706 | __end_cc_report 22:14:26 INFO - PROCESS | 16706 | __startTimestamp1452492866310__endTimestamp 22:14:26 INFO - PROCESS | 16706 | 22:14:26 INFO - PROCESS | 16706 | ------- Summary: start ------- 22:14:26 INFO - PROCESS | 16706 | Number of tests: 6 22:14:26 INFO - PROCESS | 16706 | 22:14:26 INFO - PROCESS | 16706 | [#0] dojo.html Cycles:165 Average:5228.28 Median:5459.56 stddev:561.53 (10.3%) stddev-sans-first:539.12 22:14:26 INFO - PROCESS | 16706 | Values: 3152.4 3187.1 3223.0 3227.4 3237.2 5123.8 5221.4 5233.5 5239.9 5281.2 5150.0 5236.6 5239.7 5257.2 5278.9 4489.7 4768.5 4894.0 4900.5 4931.0 4739.4 4827.1 4853.5 4887.9 4900.3 4724.3 4787.2 4796.2 4845.1 4858.5 4779.8 4810.9 4822.0 4830.8 4842.1 4671.3 4763.7 4783.0 4807.8 4838.0 5044.2 5084.5 5139.7 5143.4 5180.2 5555.0 5572.7 5573.6 5573.8 5581.7 5535.4 5624.4 5630.3 5633.7 5657.0 5259.8 5265.4 5276.0 5280.5 5288.8 5745.6 5805.7 5809.3 5824.3 5862.4 5516.2 5561.8 5567.2 5589.4 5599.6 5513.6 5610.0 5631.6 5675.5 5684.2 5561.1 5579.1 5580.0 5581.3 5585.1 5511.8 5563.6 5568.6 5585.4 5629.0 4337.7 4341.7 4347.1 4375.3 4408.8 3859.0 3893.6 3928.0 3933.9 3969.9 5215.7 5244.1 5313.0 5329.6 5353.1 5660.5 5677.1 5679.7 5683.0 5688.5 5194.8 5259.7 5281.3 5282.7 5323.7 4783.9 4787.9 4860.2 4885.5 4909.4 5653.9 5697.4 5724.7 5739.9 5742.3 5393.2 5585.3 5586.4 5593.4 5655.2 5729.7 5735.9 5795.0 5802.4 5852.0 5560.8 5572.8 5581.0 5606.2 5629.4 5508.2 5625.8 5669.9 5704.8 5707.9 5459.6 5494.3 5508.1 5532.9 5560.5 5499.8 5536.7 5542.1 5551.3 5568.4 5347.3 5407.1 5441.7 5474.7 5489.8 5440.8 5529.4 5533.8 5550.0 5578.8 5406.0 5451.9 5467.4 5472.3 5476.9 22:14:26 INFO - PROCESS | 16706 | 22:14:26 INFO - PROCESS | 16706 | [#1] ext.html Cycles:180 Average:45491.95 Median:35251.32 stddev:74468.11 (211.2%) stddev-sans-first:74600.41 22:14:26 INFO - PROCESS | 16706 | Values: 505.6 540.2 579.2 584.0 605.2 27717.3 35343.6 35581.6 35610.6 35678.1 38317.4 38608.0 38789.8 38907.0 38981.6 8309.3 8487.3 8500.3 8507.4 8510.4 8260.9 8399.5 8417.6 8422.7 8431.6 8466.6 8469.3 8490.9 8558.9 8560.6 8515.0 8526.5 8548.1 8573.3 8585.7 8362.6 8501.7 8512.0 8522.2 8553.0 38473.4 39122.6 39201.0 39203.4 39253.4 3255.9 3355.7 3463.3 3495.4 3690.9 30320.2 30504.7 30557.0 31040.7 31263.2 3867.6 3942.6 3969.5 3973.3 4024.1 263073.0 263652.0 263739.0 264071.0 264549.0 54487.7 55476.7 60536.7 60873.0 63187.7 55956.2 56207.4 56208.4 56286.4 56332.7 15202.0 15550.7 15572.7 15577.1 15650.4 11470.3 11574.0 11596.5 11606.9 11629.3 19386.3 19564.4 20148.6 20225.6 20301.2 8114.0 8284.3 8342.6 8343.3 8376.7 946.3 1010.6 1018.4 1030.0 1032.6 28175.0 28204.4 28649.4 28843.9 28909.9 1049.0 1070.8 1145.1 1182.5 1208.6 14825.6 14991.3 15063.5 15180.8 15218.3 301884.0 303633.0 304265.0 304791.0 305244.0 50570.0 50901.5 51074.7 51428.2 51526.7 1332.7 1343.5 1347.3 1354.3 1362.2 27138.7 28304.9 28463.9 30739.0 31785.0 46001.8 47010.3 47099.8 47368.3 47404.0 283350.0 287356.0 287680.0 287928.0 289842.0 42018.4 43504.6 43575.6 44213.3 44352.8 39618.0 40374.6 40583.4 40596.8 40864.8 33237.7 33728.5 33753.3 34115.8 34136.0 34061.8 34878.1 35130.5 35159.0 38662.6 33748.3 33858.8 33984.3 38682.2 38983.4 29322.9 30525.4 30690.7 31204.5 31503.8 24737.4 25019.4 25032.9 25442.5 25488.4 22:14:26 INFO - PROCESS | 16706 | 22:14:26 INFO - PROCESS | 16706 | [#2] jquery.html Cycles:180 Average:25847.99 Median:5022.60 stddev:80794.17 (1608.6%) stddev-sans-first:80998.14 22:14:26 INFO - PROCESS | 16706 | Values: 353.1 353.3 353.5 354.5 355.9 16034.7 16558.3 17006.2 17705.1 17719.9 15586.1 15587.5 16022.3 16497.0 16548.9 4876.6 5006.6 5014.8 5018.7 5026.5 4816.4 4928.0 5015.8 5426.4 5431.8 5105.6 5298.2 5303.0 5409.7 5416.2 5236.8 5245.9 5274.0 5528.5 5959.1 5322.3 5325.7 5337.0 5337.9 5347.5 14714.6 19572.2 20040.4 20273.0 20300.5 2021.3 2161.9 2182.5 2233.8 2317.2 9671.9 9807.5 9840.0 9932.1 12083.0 1845.5 1867.6 1872.4 1965.8 1971.3 49468.5 51024.5 51275.2 51434.7 51522.7 9892.9 9914.2 9952.2 9967.7 10009.0 12742.9 12950.8 12964.0 12971.2 12985.9 7726.7 7741.9 7767.8 7770.7 7787.7 7492.3 7494.4 7529.8 7548.6 7576.7 4433.5 4464.9 4481.4 4488.9 4519.5 1808.2 1854.5 1860.3 1881.3 1933.0 910.4 939.2 975.1 1007.5 1029.7 15649.3 15852.7 15889.5 15928.5 16411.4 945.4 952.2 962.6 969.4 1017.4 9209.2 9435.7 9610.3 9621.0 9658.3 462783.0 472366.0 505458.0 513125.0 518393.0 64701.4 64856.4 73542.6 73574.3 73577.6 1121.7 1156.5 1161.9 1182.3 1184.5 14188.3 14794.0 14943.5 15160.8 15230.5 30401.2 30807.5 30815.4 31405.4 32119.5 38006.0 38124.0 38705.4 40021.6 42651.4 7090.5 7224.1 7376.3 7378.7 7421.7 9191.5 10178.2 10273.9 10960.1 11367.0 8793.7 9360.4 9526.4 9620.2 9645.8 9243.2 9464.4 9636.5 10054.6 10071.6 9210.8 9512.7 9755.0 9810.0 9893.8 7404.1 7441.0 7524.4 7536.8 7555.7 9210.6 9772.6 9855.8 9882.5 9901.6 22:14:26 INFO - PROCESS | 16706 | 22:14:26 INFO - PROCESS | 16706 | [#3] mootools.html Cycles:180 Average:5402.99 Median:6049.96 stddev:2663.32 (44.0%) stddev-sans-first:2654.18 22:14:26 INFO - PROCESS | 16706 | Values: 1446.5 1449.9 1619.7 1747.3 1776.3 7501.2 7581.6 7689.5 7718.0 7724.4 7652.0 7677.2 7704.1 7710.0 7779.4 2328.8 2343.7 2355.5 2356.8 2357.5 2384.2 2394.3 2410.4 2411.7 2412.7 2313.6 2341.3 2349.9 2385.0 2393.9 2352.2 2394.2 2394.6 2396.1 2396.4 2341.8 2341.9 2357.7 2371.7 2372.1 7611.2 7641.9 7669.7 7689.8 7716.5 4709.7 4852.7 4856.2 4863.0 4871.6 3085.2 3163.9 3172.7 3224.7 3236.8 1074.9 1086.6 1966.7 1992.1 2013.4 10152.7 10217.3 10246.0 10247.5 10249.2 4181.4 4262.3 4348.4 4363.3 4370.6 8476.1 8483.4 8483.8 8493.0 8509.0 4833.4 4847.6 4854.3 4858.6 4881.4 4424.6 4439.5 4476.7 4478.5 4515.9 3482.2 3570.8 3590.8 3597.1 3609.4 1098.8 1101.9 1104.2 1105.0 1107.4 1015.8 1021.5 1143.9 1174.3 1328.7 6825.7 7048.9 7055.2 7086.5 7146.4 1145.8 1151.4 1151.5 1156.5 1165.3 5726.9 5783.0 5788.1 5880.6 5926.7 8605.8 8746.7 8998.2 9038.5 9134.8 8887.1 8909.5 8941.3 8988.7 9024.1 7085.3 7089.8 7228.8 7231.8 7274.9 7169.9 7250.9 7263.3 7272.6 7300.8 8043.8 8289.1 8291.8 8336.0 8412.9 8075.4 8225.8 8323.0 8341.2 8343.2 6739.6 6961.9 6964.9 6973.1 6998.8 7344.2 7385.6 7403.6 7406.0 7415.1 6465.3 6907.8 6927.3 7004.8 7277.7 6755.8 6757.4 6812.3 6861.5 6960.9 6728.4 6849.3 6874.8 6889.3 6894.4 6049.2 6050.7 6051.8 6053.8 6056.7 6351.1 6408.9 6500.5 6501.6 6510.3 22:14:26 INFO - PROCESS | 16706 | 22:14:26 INFO - PROCESS | 16706 | [#4] prototype.html Cycles:180 Average:5501.16 Median:6212.92 stddev:1678.86 (27.0%) stddev-sans-first:1638.27 22:14:26 INFO - PROCESS | 16706 | Values: 340.1 342.8 345.2 349.6 350.7 6206.1 6238.6 6257.2 6288.6 6371.7 6156.7 6211.9 6214.0 6216.3 6217.1 4730.6 4734.6 4741.6 4762.0 4771.5 4726.0 4740.8 4746.3 4750.8 4753.7 4552.8 4627.8 4638.7 4649.0 4657.7 4610.0 4617.0 4634.5 4667.5 4671.5 4054.7 4062.1 4064.9 4077.3 4078.2 5848.8 5879.5 5892.5 5896.7 5913.6 5273.3 5416.3 5423.9 5431.3 5443.0 6491.2 6527.8 6563.7 6568.5 6572.0 4478.7 4485.7 4489.2 4498.0 4502.1 6756.9 6809.6 6820.7 6840.2 6859.2 4604.7 4611.8 4618.5 4626.6 4630.9 4670.7 4678.0 4679.0 4680.8 4683.3 5418.0 5434.1 5435.2 5457.6 5478.8 6499.5 6601.6 6610.2 6621.4 6639.5 1636.5 1658.5 1659.4 1659.7 1659.9 1061.8 1073.7 1075.6 1097.5 1102.2 6151.4 6176.0 6183.5 6187.0 6205.9 6746.7 6805.7 6808.1 6824.2 6835.3 6131.4 6163.5 6195.8 6233.2 6265.6 3694.6 3697.0 3713.2 3733.9 3742.3 7044.8 7055.9 7066.6 7073.2 7079.2 6894.2 7032.4 7039.5 7047.7 7054.5 7028.0 7032.2 7075.5 7078.2 7098.1 6858.7 6864.2 6865.3 6877.7 6877.9 6639.9 6767.2 6776.8 6779.8 6791.5 7013.8 7014.9 7051.6 7062.3 7067.3 4603.8 4605.9 4611.1 4616.7 4647.6 6639.6 6841.6 6864.4 6868.7 6897.7 6693.4 6696.7 6704.4 6704.7 6723.4 6533.2 6642.6 6658.9 6685.2 6694.9 6448.3 6533.1 6539.1 6542.1 6543.5 6668.4 6675.0 6681.5 6696.8 6704.2 6380.3 6509.5 6514.9 6525.8 6533.2 22:14:26 INFO - PROCESS | 16706 | 22:14:26 INFO - PROCESS | 16706 | [#5] yui.html Cycles:180 Average:13762.53 Median:45572.89 stddev:15516.12 (34.0%) stddev-sans-first:15528.91 22:14:26 INFO - PROCESS | 16706 | Values: 756.7 774.3 798.5 844.5 867.6 13501.3 15122.8 15214.7 15412.7 15435.7 14585.9 14641.8 14642.3 14657.0 14705.8 800.2 810.6 811.4 813.1 828.5 809.3 810.6 813.0 814.4 816.8 851.3 855.3 855.4 857.1 857.1 848.3 849.7 851.3 853.7 857.6 627.7 629.1 630.3 631.1 633.6 13586.0 13666.0 13676.0 13698.0 13700.9 12813.9 12826.2 12919.8 13080.1 13176.1 10378.6 10399.6 10500.7 10502.2 10524.8 3643.9 3675.1 3675.6 3688.7 3851.8 45808.1 46442.5 46443.0 47600.8 47695.8 9973.8 10223.8 10270.5 10298.5 10381.0 18253.6 18339.1 18339.5 18348.1 18351.5 7378.4 7471.2 7535.4 7606.6 7798.1 4259.0 4273.8 4309.9 4322.8 4327.6 3087.9 3102.7 3119.9 3122.1 3124.5 562.6 574.3 579.7 588.9 589.0 535.3 650.0 656.5 659.7 665.6 8561.5 8611.8 8643.4 8685.8 8724.5 535.5 552.2 665.1 686.0 704.4 4028.3 4084.7 4085.3 4155.8 4173.2 53665.5 54791.7 54968.0 55336.4 55346.7 49395.5 49597.0 49608.3 50197.7 50784.0 45142.0 45501.0 45644.8 46337.5 47750.5 8614.3 8624.7 8864.7 8894.0 8929.0 33678.5 34469.8 35072.5 35074.3 35174.9 43625.5 43823.8 44373.8 44392.3 44714.3 11511.1 11556.2 11702.7 11748.9 11830.9 12402.9 12440.4 12524.1 12952.6 13094.8 11040.9 11366.8 11461.7 11606.5 12017.9 10648.4 10985.8 10995.5 11027.8 11108.4 10263.8 10541.7 10949.3 11412.3 11503.5 9369.3 9681.8 9692.9 9742.0 9787.8 8861.8 9365.5 9647.4 9784.9 9903.7 22:14:26 INFO - PROCESS | 16706 | -------- Summary: end -------- 22:14:26 INFO - PROCESS | 16706 | 22:14:26 INFO - TEST-INFO | 16706: exit 0 22:14:26 INFO - TEST-OK | dromaeo_css | took 1111048ms 22:14:26 INFO - TEST-START | kraken 22:14:26 INFO - operating with platform_type : linux_ 22:14:26 INFO - Initialising browser for kraken test... 22:14:26 INFO - TEST-INFO | started process 16939 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFttXSB/profile http://localhost:45835/getInfo.html) 22:14:30 INFO - PROCESS | 16939 | 22:14:30 INFO - PROCESS | 16939 | (process:17013): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:14:30 INFO - PROCESS | 16939 | JavaScript warning: resource://gre/modules/TelemetrySession.jsm, line 1783: unreachable code after return statement 22:14:31 INFO - PROCESS | 16939 | __metrics Screen width/height:1600/1200 22:14:31 INFO - PROCESS | 16939 | colorDepth:24 22:14:31 INFO - PROCESS | 16939 | Browser inner width/height: 1024/697 22:14:31 INFO - PROCESS | 16939 | __metrics 22:14:31 INFO - PROCESS | 16939 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:14:32 INFO - TEST-INFO | 16939: exit 0 22:14:32 INFO - Browser initialized. 22:14:32 INFO - Running cycle 1/1 for kraken test... 22:14:32 INFO - TEST-INFO | started process 17062 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpFttXSB/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:14:34 INFO - PROCESS | 17062 | 22:14:34 INFO - PROCESS | 17062 | (process:17122): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:14:44 INFO - PROCESS | 17062 | RSS: Main: 150573056 22:14:44 INFO - PROCESS | 17062 | 22:14:47 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45835/tests/kraken/driver.html?testName=audio-beat-detection) 22:14:47 INFO - PROCESS | 17062 | RSS: Main: 151203840 22:14:47 INFO - PROCESS | 17062 | 22:14:50 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45835/tests/kraken/driver.html?testName=audio-dft) 22:14:50 INFO - PROCESS | 17062 | RSS: Main: 149962752 22:14:50 INFO - PROCESS | 17062 | 22:14:53 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45835/tests/kraken/driver.html?testName=audio-fft) 22:14:54 INFO - PROCESS | 17062 | RSS: Main: 145858560 22:14:54 INFO - PROCESS | 17062 | 22:14:56 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45835/tests/kraken/driver.html?testName=audio-oscillator) 22:14:57 INFO - PROCESS | 17062 | RSS: Main: 146915328 22:14:57 INFO - PROCESS | 17062 | 22:14:59 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45835/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:15:00 INFO - PROCESS | 17062 | RSS: Main: 146718720 22:15:00 INFO - PROCESS | 17062 | 22:15:03 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45835/tests/kraken/driver.html?testName=imaging-darkroom) 22:15:03 INFO - PROCESS | 17062 | RSS: Main: 145977344 22:15:03 INFO - PROCESS | 17062 | 22:15:07 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45835/tests/kraken/driver.html?testName=imaging-desaturate) 22:15:07 INFO - PROCESS | 17062 | RSS: Main: 149520384 22:15:07 INFO - PROCESS | 17062 | 22:15:10 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45835/tests/kraken/driver.html?testName=json-parse-financial) 22:15:11 INFO - PROCESS | 17062 | RSS: Main: 147726336 22:15:11 INFO - PROCESS | 17062 | 22:15:12 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45835/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:15:13 INFO - PROCESS | 17062 | RSS: Main: 148889600 22:15:13 INFO - PROCESS | 17062 | 22:15:14 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45835/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:15:15 INFO - PROCESS | 17062 | RSS: Main: 149102592 22:15:15 INFO - PROCESS | 17062 | 22:15:17 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45835/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:15:18 INFO - PROCESS | 17062 | RSS: Main: 149295104 22:15:18 INFO - PROCESS | 17062 | 22:15:20 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45835/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:15:21 INFO - PROCESS | 17062 | RSS: Main: 149602304 22:15:21 INFO - PROCESS | 17062 | 22:15:23 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45835/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:15:24 INFO - PROCESS | 17062 | RSS: Main: 148774912 22:15:24 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | Cycle 1(1): loaded http://localhost:45835/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45835/tests/kraken/driver.html?testName=ai-astar) 22:15:26 INFO - PROCESS | 17062 | RSS: Main: 149356544 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | __start_tp_report 22:15:26 INFO - PROCESS | 17062 | _x_x_mozilla_page_load 22:15:26 INFO - PROCESS | 17062 | _x_x_mozilla_page_load_details 22:15:26 INFO - PROCESS | 17062 | |i|pagename|runs| 22:15:26 INFO - PROCESS | 17062 | |0;ai-astar;111;108;113;111;112;124;113;119;110;111 22:15:26 INFO - PROCESS | 17062 | |1;audio-beat-detection;157;129;150;165;128;157;164;137;163;135 22:15:26 INFO - PROCESS | 17062 | |2;audio-dft;164;163;162;163;162;163;164;175;164;164 22:15:26 INFO - PROCESS | 17062 | |3;audio-fft;75;72;73;75;75;127;71;75;75;74 22:15:26 INFO - PROCESS | 17062 | |4;audio-oscillator;96;96;141;95;96;96;139;96;96;138 22:15:26 INFO - PROCESS | 17062 | |5;imaging-gaussian-blur;110;110;110;111;109;111;110;111;110;111 22:15:26 INFO - PROCESS | 17062 | |6;imaging-darkroom;147;146;145;146;146;149;147;147;147;148 22:15:26 INFO - PROCESS | 17062 | |7;imaging-desaturate;97;96;100;95;96;97;97;96;96;97 22:15:26 INFO - PROCESS | 17062 | |8;json-parse-financial;58;57;57;55;57;56;56;56;53;55 22:15:26 INFO - PROCESS | 17062 | |9;json-stringify-tinderbox;69;70;69;71;69;70;69;70;70;69 22:15:26 INFO - PROCESS | 17062 | |10;stanford-crypto-aes;85;84;87;86;88;85;86;91;87;86 22:15:26 INFO - PROCESS | 17062 | |11;stanford-crypto-ccm;125;125;129;116;120;129;125;122;129;127 22:15:26 INFO - PROCESS | 17062 | |12;stanford-crypto-pbkdf2;154;150;151;150;153;153;164;163;152;142 22:15:26 INFO - PROCESS | 17062 | |13;stanford-crypto-sha256-iterative;68;69;71;68;72;69;70;70;69;68 22:15:26 INFO - PROCESS | 17062 | __end_tp_report 22:15:26 INFO - PROCESS | 17062 | __start_cc_report 22:15:26 INFO - PROCESS | 17062 | _x_x_mozilla_cycle_collect,752 22:15:26 INFO - PROCESS | 17062 | __end_cc_report 22:15:26 INFO - PROCESS | 17062 | __startTimestamp1452492926547__endTimestamp 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | ------- Summary: start ------- 22:15:26 INFO - PROCESS | 17062 | Number of tests: 14 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#0] ai-astar Cycles:10 Average:113.20 Median:112.50 stddev:4.76 (4.2%) stddev-sans-first:4.98 22:15:26 INFO - PROCESS | 17062 | Values: 111.0 108.0 113.0 111.0 112.0 124.0 113.0 119.0 110.0 111.0 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#1] audio-beat-detection Cycles:10 Average:148.50 Median:157.00 stddev:14.85 (9.5%) stddev-sans-first:15.43 22:15:26 INFO - PROCESS | 17062 | Values: 157.0 129.0 150.0 165.0 128.0 157.0 164.0 137.0 163.0 135.0 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#2] audio-dft Cycles:10 Average:164.40 Median:164.00 stddev:3.81 (2.3%) stddev-sans-first:4.03 22:15:26 INFO - PROCESS | 17062 | Values: 164.0 163.0 162.0 163.0 162.0 163.0 164.0 175.0 164.0 164.0 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#3] audio-fft Cycles:10 Average:79.20 Median:75.00 stddev:16.86 (22.5%) stddev-sans-first:17.81 22:15:26 INFO - PROCESS | 17062 | Values: 75.0 72.0 73.0 75.0 75.0 127.0 71.0 75.0 75.0 74.0 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#4] audio-oscillator Cycles:10 Average:108.90 Median:96.00 stddev:21.02 (21.9%) stddev-sans-first:21.77 22:15:26 INFO - PROCESS | 17062 | Values: 96.0 96.0 141.0 95.0 96.0 96.0 139.0 96.0 96.0 138.0 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#5] imaging-gaussian-blur Cycles:10 Average:110.30 Median:110.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 22:15:26 INFO - PROCESS | 17062 | Values: 110.0 110.0 110.0 111.0 109.0 111.0 110.0 111.0 110.0 111.0 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:1.14 (0.8%) stddev-sans-first:1.20 22:15:26 INFO - PROCESS | 17062 | Values: 147.0 146.0 145.0 146.0 146.0 149.0 147.0 147.0 147.0 148.0 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:96.50 stddev:1.34 (1.4%) stddev-sans-first:1.41 22:15:26 INFO - PROCESS | 17062 | Values: 97.0 96.0 100.0 95.0 96.0 97.0 97.0 96.0 96.0 97.0 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:56.50 stddev:1.41 (2.5%) stddev-sans-first:1.30 22:15:26 INFO - PROCESS | 17062 | Values: 58.0 57.0 57.0 55.0 57.0 56.0 56.0 56.0 53.0 55.0 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 22:15:26 INFO - PROCESS | 17062 | Values: 69.0 70.0 69.0 71.0 69.0 70.0 69.0 70.0 70.0 69.0 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:86.50 stddev:1.96 (2.3%) stddev-sans-first:2.00 22:15:26 INFO - PROCESS | 17062 | Values: 85.0 84.0 87.0 86.0 88.0 85.0 86.0 91.0 87.0 86.0 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#11] stanford-crypto-ccm Cycles:10 Average:124.70 Median:126.00 stddev:4.30 (3.4%) stddev-sans-first:4.56 22:15:26 INFO - PROCESS | 17062 | Values: 125.0 125.0 129.0 116.0 120.0 129.0 125.0 122.0 129.0 127.0 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.20 Median:153.00 stddev:6.37 (4.2%) stddev-sans-first:6.75 22:15:26 INFO - PROCESS | 17062 | Values: 154.0 150.0 151.0 150.0 153.0 153.0 164.0 163.0 152.0 142.0 22:15:26 INFO - PROCESS | 17062 | 22:15:26 INFO - PROCESS | 17062 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:69.50 stddev:1.35 (1.9%) stddev-sans-first:1.33 22:15:26 INFO - PROCESS | 17062 | Values: 68.0 69.0 71.0 68.0 72.0 69.0 70.0 70.0 69.0 68.0 22:15:26 INFO - PROCESS | 17062 | -------- Summary: end -------- 22:15:26 INFO - PROCESS | 17062 | 22:15:27 INFO - TEST-INFO | 17062: exit 0 22:15:27 INFO - TEST-OK | kraken | took 60524ms 22:15:27 INFO - TEST-START | v8_7 22:15:27 INFO - operating with platform_type : linux_ 22:15:27 INFO - Initialising browser for v8_7 test... 22:15:27 INFO - TEST-INFO | started process 17350 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoJ7MR0/profile http://localhost:45835/getInfo.html) 22:15:31 INFO - PROCESS | 17350 | 22:15:31 INFO - PROCESS | 17350 | (process:17424): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:15:31 INFO - PROCESS | 17350 | JavaScript warning: resource://gre/modules/TelemetrySession.jsm, line 1783: unreachable code after return statement 22:15:32 INFO - PROCESS | 17350 | __metrics Screen width/height:1600/1200 22:15:32 INFO - PROCESS | 17350 | colorDepth:24 22:15:32 INFO - PROCESS | 17350 | Browser inner width/height: 1024/697 22:15:32 INFO - PROCESS | 17350 | __metrics 22:15:32 INFO - PROCESS | 17350 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:15:33 INFO - TEST-INFO | 17350: exit 0 22:15:33 INFO - Browser initialized. 22:15:33 INFO - Running cycle 1/1 for v8_7 test... 22:15:33 INFO - TEST-INFO | started process 17473 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoJ7MR0/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:15:34 INFO - PROCESS | 17473 | 22:15:34 INFO - PROCESS | 17473 | (process:17533): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:15:45 INFO - PROCESS | 17473 | RSS: Main: 150491136 22:15:45 INFO - PROCESS | 17473 | 22:16:07 INFO - PROCESS | 17473 | Cycle 1(1): loaded http://localhost:45835/tests/v8_7/run.html (next: http://localhost:45835/tests/v8_7/run.html) 22:16:07 INFO - PROCESS | 17473 | RSS: Main: 146829312 22:16:07 INFO - PROCESS | 17473 | 22:16:07 INFO - PROCESS | 17473 | __start_tp_report 22:16:07 INFO - PROCESS | 17473 | _x_x_mozilla_page_load 22:16:07 INFO - PROCESS | 17473 | _x_x_mozilla_page_load_details 22:16:07 INFO - PROCESS | 17473 | |i|pagename|runs| 22:16:07 INFO - PROCESS | 17473 | |0;Richards;165.97510373443984 22:16:07 INFO - PROCESS | 17473 | |1;DeltaBlue;188.89308651303364 22:16:07 INFO - PROCESS | 17473 | |2;Encrypt;280.74115665356544 22:16:07 INFO - PROCESS | 17473 | |3;Decrypt;5240.837696335078 22:16:07 INFO - PROCESS | 17473 | |4;RayTrace;1100.1100110011 22:16:07 INFO - PROCESS | 17473 | |5;Earley;710.7320540156361 22:16:07 INFO - PROCESS | 17473 | |6;Boyer;12256.09756097561 22:16:07 INFO - PROCESS | 17473 | |7;RegExp;41142.857142857145 22:16:07 INFO - PROCESS | 17473 | |8;Splay;591.016548463357 22:16:07 INFO - PROCESS | 17473 | |9;NavierStokes;4830.917874396136 22:16:07 INFO - PROCESS | 17473 | __end_tp_report 22:16:07 INFO - PROCESS | 17473 | __startTimestamp1452492967734__endTimestamp 22:16:07 INFO - PROCESS | 17473 | 22:16:07 INFO - PROCESS | 17473 | ------- Summary: start ------- 22:16:07 INFO - PROCESS | 17473 | Number of tests: 10 22:16:07 INFO - PROCESS | 17473 | 22:16:07 INFO - PROCESS | 17473 | [#0] Richards Cycles:1 Average:165.98 Median:165.98 stddev:0.00 (0.0%) 22:16:07 INFO - PROCESS | 17473 | Values: 166.0 22:16:07 INFO - PROCESS | 17473 | 22:16:07 INFO - PROCESS | 17473 | [#1] DeltaBlue Cycles:1 Average:188.89 Median:188.89 stddev:0.00 (0.0%) 22:16:07 INFO - PROCESS | 17473 | Values: 188.9 22:16:07 INFO - PROCESS | 17473 | 22:16:07 INFO - PROCESS | 17473 | [#2] Encrypt Cycles:1 Average:280.74 Median:280.74 stddev:0.00 (0.0%) 22:16:07 INFO - PROCESS | 17473 | Values: 280.7 22:16:07 INFO - PROCESS | 17473 | 22:16:07 INFO - PROCESS | 17473 | [#3] Decrypt Cycles:1 Average:5240.84 Median:5240.84 stddev:0.00 (0.0%) 22:16:07 INFO - PROCESS | 17473 | Values: 5240.8 22:16:07 INFO - PROCESS | 17473 | 22:16:07 INFO - PROCESS | 17473 | [#4] RayTrace Cycles:1 Average:1100.11 Median:1100.11 stddev:0.00 (0.0%) 22:16:07 INFO - PROCESS | 17473 | Values: 1100.1 22:16:07 INFO - PROCESS | 17473 | 22:16:07 INFO - PROCESS | 17473 | [#5] Earley Cycles:1 Average:710.73 Median:710.73 stddev:0.00 (0.0%) 22:16:07 INFO - PROCESS | 17473 | Values: 710.7 22:16:07 INFO - PROCESS | 17473 | 22:16:07 INFO - PROCESS | 17473 | [#6] Boyer Cycles:1 Average:12256.10 Median:12256.10 stddev:0.00 (0.0%) 22:16:07 INFO - PROCESS | 17473 | Values: 12256.1 22:16:07 INFO - PROCESS | 17473 | 22:16:07 INFO - PROCESS | 17473 | [#7] RegExp Cycles:1 Average:41142.86 Median:41142.86 stddev:0.00 (0.0%) 22:16:07 INFO - PROCESS | 17473 | Values: 41142.9 22:16:07 INFO - PROCESS | 17473 | 22:16:07 INFO - PROCESS | 17473 | [#8] Splay Cycles:1 Average:591.02 Median:591.02 stddev:0.00 (0.0%) 22:16:07 INFO - PROCESS | 17473 | Values: 591.0 22:16:07 INFO - PROCESS | 17473 | 22:16:07 INFO - PROCESS | 17473 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 22:16:07 INFO - PROCESS | 17473 | Values: 4830.9 22:16:07 INFO - PROCESS | 17473 | -------- Summary: end -------- 22:16:07 INFO - PROCESS | 17473 | 22:16:08 INFO - TEST-INFO | 17473: exit 0 22:16:08 INFO - TEST-OK | v8_7 | took 41136ms 22:16:08 INFO - SUITE-END | took 1212s 22:16:08 INFO - Completed test suite (00:20:12) 22:16:08 INFO - Working with test: dromaeo_css 22:16:08 INFO - Generating results file: dromaeo_css 22:16:08 INFO - Working with test: kraken 22:16:08 INFO - Generating results file: kraken 22:16:08 INFO - javascript benchmark 22:16:08 INFO - Working with test: v8_7 22:16:08 INFO - Generating results file: v8_7 22:16:08 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:16:08 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,63,43]] 22:16:08 INFO - process_Request line: dromaeo_css 6926.78 graph.html#tests=[[72,63,43]] 22:16:08 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:16:08 INFO - process_Request line: kraken 1527.40 graph.html#tests=[[232,63,43]] 22:16:08 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 22:16:08 INFO - process_Request line: v8_7 20349.75 graph.html#tests=[[230,63,43]] 22:16:08 INFO - RETURN: dromaeo_css: 6926.78 22:16:08 INFO - RETURN: kraken: 1527.40 22:16:08 INFO - RETURN: v8_7: 20349.75 22:16:08 INFO - javascript benchmark 22:16:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3152.3852848694505, 3187.1235410262175, 3222.9516557251645, 3227.4190645683766, 3237.158338831903, 5123.820666276681, 5221.373435187783, 5233.476652334764, 5239.8690032749155, 5281.18141688038, 5150.0, 5236.607254455922, 5239.738013099349, 5257.23770053342, 5278.9208161877505, 4489.663275254353, 4768.451628062765, 4893.975530122344, 4900.485449027854, 4931.038447502739, 4739.360186374847, 4827.08285425768, 4853.538913803183, 4887.926681099786, 4900.264960255954, 4724.267738500538, 4787.210110331791, 4796.208625576775, 4845.055214233226, 4858.5384388483035, 4779.784909679072, 4810.927836082462, 4822.0, 4830.830920917751, 4842.080004799077, 4671.299305104228, 4763.666543341968, 4783.019481006385, 4807.807687692488, 4838.0, 5044.167712327461, 5084.491550844923, 5139.743012849342, 5143.3570803649545, 5180.197069454241, 5555.027870122753, 5572.749226284827, 5573.637713548606, 5573.832785016415, 5581.720913954286, 5535.446455354472, 5624.3531993820825, 5630.296212973379, 5633.718314084279, 5656.971715141439, 5259.789608415659, 5265.447128051576, 5275.973620131913, 5280.498352656491, 5288.81489147878, 5745.569082318842, 5805.651660900359, 5809.331926828469, 5824.271966004249, 5862.413758624103, 5516.200150978166, 5561.777528898799, 5567.24842146305, 5589.413111623302, 5599.580031497654, 5513.641613295123, 5610.0, 5631.577631677558, 5675.460831221028, 5684.232628595169, 5561.054620714488, 5579.107342825209, 5579.972100139474, 5581.330240371181, 5585.078462053797, 5511.807086751945, 5563.554915606742, 5568.6101972862325, 5585.413531579207, 5629.043062679354, 4337.7397356158735, 4341.6526677865695, 4347.108842687241, 4375.321825117112, 4408.757518336458, 3858.961410385916, 3893.649571538575, 3928.0, 3933.940990885139, 3969.861054863066, 5215.713135777492, 5244.108501554745, 5313.0, 5329.600279979017, 5353.116735738559, 5660.462256085665, 5677.063284558001, 5679.6876171810945, 5683.0, 5688.459596338341, 5194.792208311701, 5259.737013149327, 5281.287026251407, 5282.709450980233, 5323.680579165185, 4783.928241076386, 4787.928181077286, 4860.1737704590305, 4885.5358740919555, 4909.4354149273295, 5653.858653533694, 5697.373288938214, 5724.656520608693, 5739.913901291484, 5742.3396309424115, 5393.217983392465, 5585.273914391103, 5586.357568879631, 5593.412691667317, 5655.208270842168, 5729.656220626775, 5735.885282294247, 5795.0, 5802.390748971297, 5852.0, 5560.833175004756, 5572.804951826666, 5581.0, 5606.24315717373, 5629.352624448077, 5508.20131080999, 5625.803096891589, 5669.858253543694, 5704.80033198836, 5707.9429205709075, 5459.563234941115, 5494.2582751328055, 5508.091164957737, 5532.944670553243, 5560.527355174855, 5499.780008799603, 5536.667799932017, 5542.057850165401, 5551.278333816578, 5568.415316391883, 5347.251384806208, 5407.134858422791, 5441.727913604224, 5474.671519708831, 5489.780408783764, 5440.782368705208, 5529.391766905717, 5533.7786488540005, 5549.972250138723, 5578.832635020955, 5406.0, 5451.945480545302, 5467.425920278313, 5472.288602481811, 5476.863078423071], "unit": "score", "name": "dojo.html", "value": 5193.242669848397}, {"lowerIsBetter": false, "replicates": [505.6005755453192, 540.1681410627633, 579.1544345255926, 583.9547210493217, 605.2283338743102, 27717.3070673233, 35343.646563534356, 35581.64418355816, 35610.64389356106, 35678.108047298796, 38317.42523862137, 38608.0, 38789.806050969775, 38907.0, 38981.610183898156, 8309.293710034639, 8487.278581320596, 8500.319974402064, 8507.404481686284, 8510.361722870779, 8260.876086858701, 8399.454035487704, 8417.621207045697, 8422.747317580452, 8431.620577074016, 8466.619002144887, 8469.32245420365, 8490.915090849074, 8558.914410855905, 8560.614772335228, 8514.957425212866, 8526.531040792754, 8548.145185481464, 8573.271271941892, 8585.699500517514, 8362.58187090643, 8501.702440414618, 8512.021117571496, 8522.232999030055, 8552.957235213846, 38473.42289865654, 39122.60877391218, 39201.0, 39203.4119488205, 39253.411198832044, 3255.9348813023607, 3355.7147642450423, 3463.3246516929207, 3495.3533596284515, 3690.907727306812, 30320.24199395011, 30504.694953050406, 30557.0, 31040.689593104005, 31263.218419539695, 3867.613238676138, 3942.5860284669984, 3969.523657161159, 3973.304671682444, 4024.094578719794, 263073.0, 263652.0, 263739.0, 264071.0, 264549.0, 54487.727561362735, 55476.72261638666, 60536.697316513135, 60873.0, 63187.6840615794, 55956.16065759017, 56207.43792562022, 56208.437915621136, 56286.43713562812, 56332.718336408056, 15202.0, 15550.688986220212, 15572.688546229012, 15577.065376077471, 15650.37398504047, 11470.254433461798, 11574.016208622194, 11596.536138554364, 11606.88393116075, 11629.302241865515, 19386.321478748177, 19564.413067607995, 20148.597028059357, 20225.595488090155, 20301.187952482032, 8113.959430202811, 8284.337253019745, 8342.62458189383, 8343.290820280223, 8376.748697539084, 946.2666433514082, 1010.6311196413257, 1018.4398580780542, 1029.9545960849593, 1032.566322144701, 28175.0, 28204.43591128166, 28649.427011459655, 28843.85578072096, 28909.855450723033, 1048.9685309440729, 1070.8340207267886, 1145.0724912820476, 1182.4619797991893, 1208.5951206345972, 14825.553783625797, 14991.32539035746, 15063.54809355721, 15180.772288415683, 15218.315175817115, 301884.0, 303633.0, 304265.0, 304791.0, 305244.0, 50570.0, 50901.49098508967, 51074.74462627663, 51428.22857657138, 51526.74236628793, 1332.666833291677, 1343.4693296148162, 1347.2724728646422, 1354.3025341948708, 1362.209918247434, 27138.72861271362, 28304.85847570749, 28463.857680711462, 30739.0, 31785.0, 46001.76999114983, 47010.29484557803, 47099.76450117728, 47368.28947565858, 47404.0, 283350.0, 287356.0, 287680.0, 287928.0, 289842.0, 42018.36972445354, 43504.564954350055, 43575.56424435715, 44213.336799947385, 44352.7782361099, 39618.0, 40374.59625403708, 40583.3912491307, 40596.79701601473, 40864.79567602143, 33237.667623323454, 33728.49407258942, 33753.3249335007, 34115.82942085274, 34136.0, 34061.82969085238, 34878.12804679904, 35130.47304290387, 35159.0, 38662.613373865905, 33748.325033499685, 33858.83070584631, 33984.320313594086, 38682.22635547329, 38983.41524877186, 29322.853385732935, 30525.389492210477, 30690.693093068785, 31204.53193202149, 31503.84248078745, 24737.381565461008, 25019.374515637253, 25032.874835625706, 25442.491150177262, 25488.362790930376], "unit": "score", "name": "ext.html", "value": 18379.567293092543}, {"lowerIsBetter": false, "replicates": [353.1453881606512, 353.31985927090363, 353.52980535887264, 354.52139611524444, 355.8843375902832, 16034.679306413866, 16558.337666493328, 17006.234719437623, 17705.1147442628, 17719.911400442998, 15586.142762148105, 15587.532374028797, 16022.278997445113, 16497.0, 16548.917255413737, 4876.609871210295, 5006.624503162259, 5014.799408023675, 5018.673786203904, 5026.52248036436, 4816.373871396713, 4927.950720492785, 5015.84952451427, 5426.403095659474, 5431.809886653968, 5105.566026887719, 5298.205269209611, 5302.973485132569, 5409.729513524328, 5416.160495123242, 5236.764345604457, 5245.86885327866, 5273.973630131844, 5528.474794894479, 5959.10613407988, 5322.33470816148, 5325.733713314319, 5337.039332920072, 5337.893242135175, 5347.51872331492, 14714.55856324312, 19572.217111315673, 20040.394748682724, 20273.0, 20300.492487687778, 2021.29254760834, 2161.8702877827222, 2182.5416662500893, 2233.7989580937633, 2317.2237300504353, 9671.854922176173, 9807.460589667566, 9839.98280352526, 9932.056454636799, 12083.0, 1845.5386153461634, 1867.561123136062, 1872.4008317338457, 1965.7935916728682, 1971.2607772085469, 49468.50531494675, 51024.489755102346, 51275.23087153696, 51434.742826286005, 51522.742386288206, 9892.901070989343, 9914.206863450907, 9952.203823694088, 9967.651132210483, 10009.0, 12742.936285318514, 12950.805737913939, 12964.027697922693, 12971.156874803108, 12985.870141298656, 7726.690932362755, 7741.922580774233, 7767.766966990999, 7770.728024519115, 7787.727429539939, 7492.288232617892, 7494.362979146834, 7529.774106776696, 7548.584827834412, 7576.7348142814735, 4433.512313645497, 4464.933026004613, 4481.395011673448, 4488.887777805581, 4519.480259770168, 1808.2495764257685, 1854.5270955906158, 1860.2744929477515, 1881.2757088521018, 1932.9710054348914, 910.3809409601535, 939.1829108675496, 975.0834215837091, 1007.4861820471613, 1029.6602121300102, 15649.29578168985, 15852.682946341009, 15889.52331430059, 15928.522144335457, 16411.42560010394, 945.3666043750703, 952.2334520710756, 962.5764663548016, 969.3602222533235, 1017.3895662602528, 9209.221183800622, 9435.669751558662, 9610.327277090537, 9621.0, 9658.323917325719, 462783.0, 472366.0, 505458.0, 513125.0, 518393.0, 64701.35298646953, 64856.35143648597, 73542.63228683929, 73574.26425735674, 73577.63211183909, 1121.7139629394615, 1156.5431654496426, 1161.9419029048684, 1182.3497076607832, 1184.514349116858, 14188.290585470892, 14794.0, 14943.476978305707, 15160.848391516385, 15230.46693365727, 30401.239969000068, 30807.53788693216, 30815.38369232558, 31405.371892561565, 32119.518207226443, 38006.049848754, 38124.0, 38705.4194187093, 40021.59978400295, 42651.3602295972, 7090.503664743418, 7224.096987876515, 7376.336129748348, 7378.741744038933, 7421.666025028779, 9191.540422978691, 10178.23663225276, 10273.897261027294, 10960.12319014461, 11367.0, 8793.736187914374, 9360.4383736976, 9526.428414295164, 9620.182284505896, 9645.807083858424, 9243.168114869693, 9464.432134071978, 9636.46999415039, 10054.64808731691, 10071.647492337734, 9210.815783684155, 9512.667056652985, 9755.0, 9809.950950245204, 9893.802123957625, 7404.111506619025, 7440.962795185989, 7524.360429363565, 7536.811579710551, 7555.73554925575, 9210.631574736935, 9772.560234789593, 9855.753606159904, 9882.50587470638, 9901.55443005052], "unit": "score", "name": "jquery.html", "value": 8099.084754581662}, {"lowerIsBetter": false, "replicates": [1446.4720377062372, 1449.934752936118, 1619.651774868403, 1747.3098126240127, 1776.2983621469527, 7501.212372700863, 7581.583012934287, 7689.461737678364, 7717.961410192948, 7724.420668449868, 7651.961740191306, 7677.23227677232, 7704.0755109386955, 7710.0, 7779.377649788017, 2328.77876601723, 2343.718753749544, 2355.4817940053163, 2356.77610626991, 2357.528494301138, 2384.1893756122913, 2394.2577800881695, 2410.4455975125734, 2411.67442395277, 2412.746661600533, 2313.6298192289237, 2341.3210169050953, 2349.9412514687183, 2384.95230095398, 2393.9162129325478, 2352.200251914349, 2394.2338451695377, 2394.5929192037397, 2396.0775101586014, 2396.4488167721433, 2341.8477798943186, 2341.94145146371, 2357.6581395697526, 2371.7311238487328, 2372.1223147435558, 7611.2388761124, 7641.923580764177, 7669.693212271558, 7689.807754806118, 7716.498427602188, 4709.740964246965, 4852.660313778037, 4856.17445034345, 4863.0, 4871.610271178297, 3085.182426656938, 3163.942989329141, 3172.714455698975, 3224.6614105518815, 3236.757243206769, 1074.919381046417, 1086.5653738504582, 1966.7246585478044, 1992.14337834731, 2013.355726167627, 10152.746181345452, 10217.2847900647, 10246.0, 10247.538860751283, 10249.180065594735, 4181.351890456984, 4262.2754131797665, 4348.43470348859, 4363.323684828857, 4370.628496577827, 8476.110008449148, 8483.448575842674, 8483.787905302417, 8492.957535212285, 8509.0, 4833.3958255218095, 4847.587955023792, 4854.296127061556, 4858.6356023298395, 4881.438634557071, 4424.6239069678795, 4439.467263928349, 4476.7090139140855, 4478.462584489882, 4515.88710282239, 3482.164280572644, 3570.8036058016537, 3590.7666001709804, 3597.118705917067, 3609.4044482660065, 1098.7692584557333, 1101.911847052234, 1104.2160066352978, 1105.0441368216434, 1107.3521989635992, 1015.8323876560432, 1021.4841505039907, 1143.937083460401, 1174.3364998775664, 1328.7209685966056, 6825.658717064127, 7048.859022819617, 7055.153381594241, 7086.50394472382, 7146.392556632641, 1145.7937571237092, 1151.3782557419067, 1151.4588143572507, 1156.5142640091183, 1165.2658824940233, 5726.856828579235, 5783.045797443375, 5788.102844059177, 5880.617759845682, 5926.703664816742, 8605.827883442422, 8746.65013399457, 8998.235150012191, 9038.457692538468, 9134.81730365389, 8887.066857979948, 8909.5099769512, 8941.284697224206, 8988.730338089737, 9024.097590241056, 7085.326893945067, 7089.751858684921, 7228.819279518054, 7231.783046508614, 7274.927250727531, 7169.892451613126, 7250.854982900418, 7263.309985551364, 7272.636368181676, 7300.817479563053, 8043.839123217386, 8289.088200297841, 8291.792705182419, 8336.041355243982, 8412.915870841212, 8075.394345423999, 8225.753227403187, 8322.958385208036, 8341.16588341161, 8343.207395297437, 6739.5619284746335, 6961.930380696128, 6964.895526567207, 6973.058637083929, 6998.825029374307, 7344.192138864613, 7385.593792341473, 7403.555786652602, 7405.9629701851145, 7415.147258065176, 6465.288818230085, 6907.827304317432, 6927.3419025192525, 7004.824879378057, 7277.745278915212, 6755.763548275787, 6757.358050985148, 6812.318768123148, 6861.519693621598, 6960.860782784417, 6728.360805723449, 6849.2808255132495, 6874.828129296808, 6889.345512176335, 6894.379505844498, 6049.18336024631, 6050.727717252822, 6051.788187413419, 6053.788117415869, 6056.727447264796, 6351.07909353132, 6408.903866441914, 6500.544961852814, 6501.642409667514, 6510.283868774526], "unit": "score", "name": "mootools.html", "value": 4533.323054077352}, {"lowerIsBetter": false, "replicates": [340.0817791961703, 342.83029900199404, 345.219803244667, 349.6101846441218, 350.71288371675945, 6206.1001154832575, 6238.6256824590555, 6257.155284036659, 6288.622682639045, 6371.681415929196, 6156.6613836238985, 6211.8757624847585, 6213.968930155354, 6216.347283535231, 6217.129601855732, 4730.597899178574, 4734.644901632374, 4741.596964258034, 4761.976190119045, 4771.451283102436, 4726.031163611451, 4740.786664600084, 4746.288056791492, 4750.762461876892, 4753.667243292971, 4552.817887284521, 4627.7967728390695, 4638.744869032202, 4649.04694537619, 4657.720536767788, 4609.976950115245, 4616.976915115403, 4634.467036290819, 4667.533246675305, 4671.462781780071, 4054.6553542948886, 4062.065724883264, 4064.9390259146135, 4077.2660921034203, 4078.163976384834, 5848.766049358021, 5879.500242479395, 5892.499137573312, 5896.705164741788, 5913.645181289136, 5273.288106105665, 5416.268803711528, 5423.918641220345, 5431.348238211401, 5442.972785136049, 6491.221053473566, 6527.771527996498, 6563.737450501974, 6568.540202185849, 6572.047053177263, 4478.731276123443, 4485.663575231838, 4489.21438748221, 4497.987952710614, 4502.077074199781, 6756.8850190577605, 6809.625470599115, 6820.6930688119155, 6840.213375461786, 6859.211190713082, 4604.654650901196, 4611.792469338888, 4618.51505591908, 4626.606738427204, 4630.86107416778, 4670.743109129031, 4677.976610116928, 4679.0, 4680.765961701902, 4683.320918466871, 5417.972910135424, 5434.130539113723, 5435.1847222916085, 5457.645253058538, 5478.753456094485, 6499.512536559777, 6601.603903765789, 6610.206775187008, 6621.370969757865, 6639.502037347218, 1636.4599682104877, 1658.519029481461, 1659.3528523875698, 1659.701253774308, 1659.8672106231472, 1061.7557961668858, 1073.736934451049, 1075.596651255779, 1097.5006372100675, 1102.239454776202, 6151.384861513813, 6175.980963141032, 6183.474404675563, 6186.969065154735, 6205.906911396332, 6746.730130794714, 6805.693743781541, 6808.11494505721, 6824.249332573413, 6835.282295359015, 6131.356207598227, 6163.537734669918, 6195.81412557624, 6233.158523599347, 6265.561410701298, 3694.5751238607904, 3697.0, 3713.2202237530423, 3733.9253214935547, 3742.3076730804887, 7044.823879403056, 7055.929440705528, 7066.611336376446, 7073.221945585981, 7079.185893622196, 6894.24163342032, 7032.402245809163, 7039.472039596948, 7047.6828542714675, 7054.506184567031, 7028.0, 7032.156141262979, 7075.469339799535, 7078.150621925299, 7098.077249957472, 6858.725650973905, 6864.176298844171, 6865.27914568963, 6877.65611719402, 6877.931220687729, 6639.867202655823, 6767.1879374475375, 6776.830579235559, 6779.830504237433, 6791.524593278423, 7013.789586312419, 7014.859702806018, 7051.6474176292, 7062.293770622897, 7067.328603782633, 4603.792829322621, 4605.93091103627, 4611.077784443192, 4616.676832621684, 4647.55848194421, 6639.601623902581, 6841.589504629539, 6864.38220560152, 6868.725250989904, 6897.7240910363025, 6693.364130407604, 6696.665166741741, 6704.363085506869, 6704.6982885769285, 6723.394894459327, 6533.150690410406, 6642.568233064835, 6658.900116498159, 6685.231198412245, 6694.866102677822, 6448.258450278278, 6533.085368048382, 6539.084528165966, 6542.084108224947, 6543.476521878143, 6668.399844013868, 6675.03212034262, 6681.498887583353, 6696.799096027127, 6704.229013663491, 6380.266269378895, 6509.511786616117, 6514.9348506514325, 6525.804225873231, 6533.18335208099], "unit": "score", "name": "prototype.html", "value": 4952.338316430975}, {"lowerIsBetter": false, "replicates": [756.7237958145276, 774.256713554987, 798.4809873582171, 844.4806444036915, 867.5792240763233, 13501.324933753322, 15122.773158402619, 15214.695706085871, 15412.69174616507, 15435.691286174299, 14585.854141458582, 14641.780373294408, 14642.267886605681, 14657.047291926017, 14705.779413308808, 800.1998001998002, 810.6271115286975, 811.4036183405193, 813.0609146435876, 828.5318794880903, 809.3120847279795, 810.6068556750001, 813.032491335309, 814.4461765999118, 816.8407160603684, 851.3146916732028, 855.2858363266648, 855.4140413816524, 857.1214505132258, 857.130013036765, 848.3213429256632, 849.7110982266059, 851.2764150472111, 853.6926706385697, 857.5540718826206, 627.6892937995722, 629.1129507394552, 630.250002497027, 631.084926856056, 633.5818359882502, 13586.048976571645, 13666.043376963617, 13676.042677012514, 13698.041137120406, 13700.93149534256, 12813.935930320382, 12826.166299190616, 12919.806202906964, 13080.149790263698, 13176.143550669272, 10378.63674771387, 10399.584016639325, 10500.737481562946, 10502.212334074975, 10524.842127368096, 3643.8542458301636, 3675.117971686803, 3675.558932928039, 3688.7417880748358, 3851.788151651686, 45808.08383832338, 46442.53557464416, 46443.0, 47600.761996189794, 47695.76152119217, 9973.80034467986, 10223.795524089477, 10270.537825797857, 10298.536565854405, 10381.0, 18253.63492730138, 18339.08304584792, 18339.54151146232, 18348.082595870153, 18351.54121146982, 7378.446616503784, 7471.178170401252, 7535.359494442924, 7606.581638009852, 7798.142204357516, 4258.957410425856, 4273.807678654468, 4309.91380172401, 4322.783860806947, 4327.56724327571, 3087.9073627791204, 3102.674219206951, 3119.906402807919, 3122.0945925681754, 3124.4532206864164, 562.6230425614848, 574.2563380422363, 579.6666916523017, 588.9234399528034, 588.9852753681192, 535.266684642043, 649.9707513161919, 656.4747306961647, 659.7163219815429, 665.6205962601271, 8561.52911589856, 8611.827763444697, 8643.35174861888, 8685.782855428664, 8724.520151391735, 535.4725595288637, 552.2241251042292, 665.1353240786957, 686.0258433025116, 704.4188544450849, 4028.2950483665827, 4084.65280451159, 4085.346344584852, 4155.836365817516, 4173.165366926627, 53665.46334536605, 54791.72604136954, 54968.0, 55336.44663553313, 55346.723266384215, 49395.50604493981, 49597.0, 49608.255876161886, 50197.74901125471, 50784.0, 45142.0, 45501.0, 45644.77177614091, 46337.536624634, 47750.52249477461, 8614.267787238156, 8624.698135565224, 8864.689735859214, 8894.0, 8929.0, 33678.49482257719, 34469.82765086158, 35072.47391289082, 35074.29851403009, 35174.944751657495, 43625.47310844105, 43823.780881095394, 44373.778131110426, 44392.33411498766, 44714.3292850614, 11511.136664750009, 11556.248843825124, 11702.70743231426, 11748.88251117478, 11830.940845295718, 12402.875971240172, 12440.440180192094, 12524.123311368117, 12952.611421657366, 13094.803577946148, 11040.904224861013, 11366.829497557708, 11461.713457163638, 11606.535738570701, 12017.879821201675, 10648.414337211218, 10985.780284394426, 10995.505202265756, 11027.83458248111, 11108.44457777124, 10263.84604230952, 10541.683749487527, 10949.288296260718, 11412.258203216765, 11503.48234329474, 9369.344145909721, 9681.757956051155, 9692.854607180758, 9742.0, 9787.804243915225, 8861.778455538662, 9365.531723413938, 9647.372920760128, 9784.902150978683, 9903.653372131941], "unit": "score", "name": "yui.html", "value": 6363.801026026474}], "name": "dromaeo_css", "value": 6926.783896630105}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 108.0, 113.0, 111.0, 112.0, 124.0, 113.0, 119.0, 110.0, 111.0], "unit": "score", "name": "ai-astar", "value": 113.2}, {"lowerIsBetter": true, "replicates": [157.0, 129.0, 150.0, 165.0, 128.0, 157.0, 164.0, 137.0, 163.0, 135.0], "unit": "score", "name": "audio-beat-detection", "value": 148.5}, {"lowerIsBetter": true, "replicates": [164.0, 163.0, 162.0, 163.0, 162.0, 163.0, 164.0, 175.0, 164.0, 164.0], "unit": "score", "name": "audio-dft", "value": 164.4}, {"lowerIsBetter": true, "replicates": [75.0, 72.0, 73.0, 75.0, 75.0, 127.0, 71.0, 75.0, 75.0, 74.0], "unit": "score", "name": "audio-fft", "value": 79.2}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 141.0, 95.0, 96.0, 96.0, 139.0, 96.0, 96.0, 138.0], "unit": "score", "name": "audio-oscillator", "value": 108.9}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 111.0, 109.0, 111.0, 110.0, 111.0, 110.0, 111.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.3}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 145.0, 146.0, 146.0, 149.0, 147.0, 147.0, 147.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 146.8}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 100.0, 95.0, 96.0, 97.0, 97.0, 96.0, 96.0, 97.0], "unit": "score", "name": "imaging-desaturate", "value": 96.7}, {"lowerIsBetter": true, "replicates": [58.0, 57.0, 57.0, 55.0, 57.0, 56.0, 56.0, 56.0, 53.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 56.0}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 69.0, 71.0, 69.0, 70.0, 69.0, 70.0, 70.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 69.6}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 87.0, 86.0, 88.0, 85.0, 86.0, 91.0, 87.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.5}, {"lowerIsBetter": true, "replicates": [125.0, 125.0, 129.0, 116.0, 120.0, 129.0, 125.0, 122.0, 129.0, 127.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.7}, {"lowerIsBetter": true, "replicates": [154.0, 150.0, 151.0, 150.0, 153.0, 153.0, 164.0, 163.0, 152.0, 142.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 153.2}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 71.0, 68.0, 72.0, 69.0, 70.0, 70.0, 69.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.4}], "name": "kraken", "value": 1527.4}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.97510373443984], "unit": "score", "name": "Richards", "value": 212.69455}, {"lowerIsBetter": false, "replicates": [188.89308651303364], "unit": "score", "name": "DeltaBlue", "value": 350.02869200000004}, {"lowerIsBetter": false, "replicates": [280.74115665356544], "unit": "score", "name": "Encrypt", "value": 948.136722}, {"lowerIsBetter": false, "replicates": [5240.837696335078], "unit": "score", "name": "Decrypt", "value": 50.78978121878119}, {"lowerIsBetter": false, "replicates": [1100.1100110011], "unit": "score", "name": "RayTrace", "value": 672.6500009999999}, {"lowerIsBetter": false, "replicates": [710.7320540156361], "unit": "score", "name": "Earley", "value": 937.7134409999999}, {"lowerIsBetter": false, "replicates": [12256.09756097561], "unit": "score", "name": "Boyer", "value": 54.378075621890574}, {"lowerIsBetter": false, "replicates": [41142.857142857145], "unit": "score", "name": "RegExp", "value": 22.14199652777778}, {"lowerIsBetter": false, "replicates": [591.016548463357], "unit": "score", "name": "Splay", "value": 137.882772}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 20349.745384830276}]} 22:16:09 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,43]]", "result": "20349.75"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,43]]", "result": "1527.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,43]]", "result": "6926.78"}}} 22:16:09 INFO - Return code: 0 22:16:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 22:16:09 INFO - # TBPL SUCCESS # 22:16:09 INFO - Running post-action listener: _resource_record_post_action 22:16:09 INFO - Running post-run listener: _resource_record_post_run 22:16:10 INFO - Total resource usage - Wall time: 1230s; CPU: 13.0%; Read bytes: 4096; Write bytes: 330252288; Read time: 160; Write time: 200084 22:16:10 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 14495744; Read time: 0; Write time: 1940 22:16:10 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 4096; Write bytes: 315756544; Read time: 160; Write time: 198144 22:16:10 INFO - Running post-run listener: _upload_blobber_files 22:16:10 INFO - Blob upload gear active. 22:16:10 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 22:16:10 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 22:16:10 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'] 22:16:10 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 22:16:11 INFO - (blobuploader) - INFO - Open directory for files ... 22:16:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:16:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:16:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:13 INFO - (blobuploader) - INFO - Done attempting. 22:16:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:16:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:16:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:20 INFO - (blobuploader) - INFO - Done attempting. 22:16:20 INFO - (blobuploader) - INFO - Iteration through files over. 22:16:20 INFO - Return code: 0 22:16:20 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 22:16:20 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 22:16:20 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0a190eaa69dc2a19c1989272017ecd690bce19a5b1908ed6b1ba1e8cca79317493dc7721480d626c8db31a9b9baac8b112c0c2489102a8bc3088876e0b54c646", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fdc75295145cd5f6786cd352c9589c1282ee1ddcaa201283e5c0ea21ce4f19e49ed58dd6968a6fad409d00795f483f5ded1b7713c7371ad1809ad1fd37537b77"} 22:16:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 22:16:20 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 22:16:20 INFO - Contents: 22:16:20 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0a190eaa69dc2a19c1989272017ecd690bce19a5b1908ed6b1ba1e8cca79317493dc7721480d626c8db31a9b9baac8b112c0c2489102a8bc3088876e0b54c646", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fdc75295145cd5f6786cd352c9589c1282ee1ddcaa201283e5c0ea21ce4f19e49ed58dd6968a6fad409d00795f483f5ded1b7713c7371ad1809ad1fd37537b77"} 22:16:20 INFO - Copying logs to upload dir... 22:16:20 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1432.771947 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 52 secs) (at 2016-01-10 22:16:19.835405) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-10 22:16:19.839119) ========= 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=eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0a190eaa69dc2a19c1989272017ecd690bce19a5b1908ed6b1ba1e8cca79317493dc7721480d626c8db31a9b9baac8b112c0c2489102a8bc3088876e0b54c646", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fdc75295145cd5f6786cd352c9589c1282ee1ddcaa201283e5c0ea21ce4f19e49ed58dd6968a6fad409d00795f483f5ded1b7713c7371ad1809ad1fd37537b77"} build_url:https://queue.taskcluster.net/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011383 build_url: 'https://queue.taskcluster.net/v1/task/aGc36MzXQjGWa1bcn06O4A/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0a190eaa69dc2a19c1989272017ecd690bce19a5b1908ed6b1ba1e8cca79317493dc7721480d626c8db31a9b9baac8b112c0c2489102a8bc3088876e0b54c646", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fdc75295145cd5f6786cd352c9589c1282ee1ddcaa201283e5c0ea21ce4f19e49ed58dd6968a6fad409d00795f483f5ded1b7713c7371ad1809ad1fd37537b77"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-10 22:16:19.864267) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 22:16:19.864709) ========= 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=eca2ab57546de7b41f256b19000001ef-1452490667.245248-453815243 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004636 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-10 22:16:19.950939) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-10 22:16:19.951468) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-10 22:16:19.951967) ========= ========= Total master_lag: 0.27 =========