builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1452383244.66 results: success (0) buildid: 20160109133014 builduid: c11aa47d691f4819be32df7a88673234 revision: c33f30666b37dbceffb9fbe5089a668db8893a85 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.664250) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.664728) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.667409) ========= 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=6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006378 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.697218) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.697541) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.716510) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.716859) ========= 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=6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004499 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.769001) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.769306) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.769713) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.770030) ========= 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=6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-09 15:47: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% 43.7M=0s 2016-01-09 15:47:25 (43.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.153033 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.935902) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.936231) ========= 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=6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019443 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.987233) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:24.987596) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c33f30666b37dbceffb9fbe5089a668db8893a85 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev c33f30666b37dbceffb9fbe5089a668db8893a85 --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=6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-09 15:47:25,900 truncating revision to first 12 chars 2016-01-09 15:47:25,900 Setting DEBUG logging. 2016-01-09 15:47:25,900 attempt 1/10 2016-01-09 15:47:25,901 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c33f30666b37?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-09 15:47:26,380 unpacking tar archive at: mozilla-central-c33f30666b37/testing/mozharness/ program finished with exit code 0 elapsedTime=0.766682 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:25.767981) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:25.768334) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:25.780465) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:25.780978) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-09 15:47:25.781626) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-01-09 15:47:25.782088) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:47:26 INFO - MultiFileLogger online at 20160109 15:47:26 in /builds/slave/test-pgo 15:47:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:47:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 15:47:26 INFO - {'append_to_log': False, 15:47:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 15:47:26 INFO - 'blob_upload_branch': 'Firefox', 15:47:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 15:47:26 INFO - 'branch': 'Firefox', 15:47:26 INFO - 'buildbot_json_path': 'buildprops.json', 15:47:26 INFO - 'config_files': ('talos/linux_config.py',), 15:47:26 INFO - 'default_actions': ('clobber', 15:47:26 INFO - 'read-buildbot-config', 15:47:26 INFO - 'download-and-extract', 15:47:26 INFO - 'populate-webroot', 15:47:26 INFO - 'create-virtualenv', 15:47:26 INFO - 'install', 15:47:26 INFO - 'run-tests'), 15:47:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:47:26 INFO - 'download_minidump_stackwalk': True, 15:47:26 INFO - 'download_symbols': 'ondemand', 15:47:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:47:26 INFO - 'tooltool.py': '/tools/tooltool.py', 15:47:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:47:26 INFO - '/tools/misc-python/virtualenv.py')}, 15:47:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:47:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:47:26 INFO - 'installer_path': 'installer.exe', 15:47:26 INFO - 'log_level': 'info', 15:47:26 INFO - 'log_name': 'talos', 15:47:26 INFO - 'log_to_console': True, 15:47:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:47:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:47:26 INFO - 'opt_config_files': (), 15:47:26 INFO - 'pip_index': False, 15:47:26 INFO - 'sps_profile': False, 15:47:26 INFO - 'sps_profile_interval': 0, 15:47:26 INFO - 'suite': 'dromaeojs', 15:47:26 INFO - 'system_bits': '32', 15:47:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:47:26 INFO - 'title': 'talos-linux64-ix-023', 15:47:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:47:26 INFO - 'use_talos_json': True, 15:47:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 15:47:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:47:26 INFO - 'work_dir': 'build'} 15:47:26 INFO - ##### 15:47:26 INFO - ##### Running clobber step. 15:47:26 INFO - ##### 15:47:26 INFO - Running pre-action listener: _resource_record_pre_action 15:47:26 INFO - Running main action method: clobber 15:47:26 INFO - rmtree: /builds/slave/test-pgo/build 15:47:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 15:47:27 INFO - Running post-action listener: _resource_record_post_action 15:47:27 INFO - ##### 15:47:27 INFO - ##### Running read-buildbot-config step. 15:47:27 INFO - ##### 15:47:27 INFO - Running pre-action listener: _resource_record_pre_action 15:47:27 INFO - Running main action method: read_buildbot_config 15:47:27 INFO - Using buildbot properties: 15:47:27 INFO - { 15:47:27 INFO - "project": "", 15:47:27 INFO - "product": "firefox", 15:47:27 INFO - "script_repo_revision": "production", 15:47:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 15:47:27 INFO - "repository": "", 15:47:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 15:47:27 INFO - "buildid": "20160109133014", 15:47:27 INFO - "pgo_build": "True", 15:47:27 INFO - "basedir": "/builds/slave/test-pgo", 15:47:27 INFO - "buildnumber": 638, 15:47:27 INFO - "slavename": "talos-linux64-ix-023", 15:47:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:47:27 INFO - "platform": "ubuntu64_hw", 15:47:27 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 15:47:27 INFO - "branch": "mozilla-central", 15:47:27 INFO - "repo_path": "mozilla-central", 15:47:27 INFO - "revision": "c33f30666b37dbceffb9fbe5089a668db8893a85", 15:47:27 INFO - "stage_platform": "linux64-pgo", 15:47:27 INFO - "builduid": "c11aa47d691f4819be32df7a88673234", 15:47:27 INFO - "slavebuilddir": "test" 15:47:27 INFO - } 15:47:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 15:47:27 INFO - Running post-action listener: _resource_record_post_action 15:47:27 INFO - ##### 15:47:27 INFO - ##### Running download-and-extract step. 15:47:27 INFO - ##### 15:47:27 INFO - Running pre-action listener: _resource_record_pre_action 15:47:27 INFO - Running main action method: download_and_extract 15:47:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 15:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:27 INFO - https://queue.taskcluster.net/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 15:47:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/test_packages.json 15:47:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/test_packages.json 15:47:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 15:47:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 15:47:31 INFO - Downloaded 1302 bytes. 15:47:31 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 15:47:31 INFO - Using the following test package requirements: 15:47:31 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 15:47:31 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 15:47:31 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:31 INFO - u'jsshell-linux-x86_64.zip'], 15:47:31 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 15:47:31 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 15:47:31 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 15:47:31 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 15:47:31 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 15:47:31 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 15:47:31 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 15:47:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 15:47:31 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:47: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')]} 15:47:31 INFO - https://queue.taskcluster.net/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:47:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 15:47:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 15:47:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/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 15:47:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/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 15:47:32 INFO - Downloaded 24028275 bytes. 15:47:32 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 15:47:32 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 15:47:32 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 15:47:34 INFO - Return code: 0 15:47:34 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 15:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:34 INFO - https://queue.taskcluster.net/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:47:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 15:47:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 15:47:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/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 15:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/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 15:47:36 INFO - Downloaded 24028275 bytes. 15:47:36 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 15:47:36 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 15:47:36 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 15:47:37 INFO - Return code: 0 15:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:37 INFO - https://queue.taskcluster.net/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:47:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 15:47:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 15:47:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/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 15:47:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/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 15:47:38 INFO - Downloaded 11132285 bytes. 15:47:38 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 15:47:38 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 15:47:38 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 15:47:38 INFO - Return code: 0 15:47:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:38 INFO - https://queue.taskcluster.net/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:47:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 15:47:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 15:47:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/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 15:47:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C1Jw8qSZQJODDUFi1LFjmg/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 15:47:40 INFO - Downloaded 55923947 bytes. 15:47:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 15:47:40 INFO - mkdir: /builds/slave/test-pgo/properties 15:47:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 15:47:40 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 15:47:40 INFO - Contents: 15:47:40 INFO - build_url:https://queue.taskcluster.net/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 15:47:40 INFO - Running post-action listener: _resource_record_post_action 15:47:40 INFO - Running post-action listener: set_extra_try_arguments 15:47:40 INFO - ##### 15:47:40 INFO - ##### Running populate-webroot step. 15:47:40 INFO - ##### 15:47:40 INFO - Running pre-action listener: _resource_record_pre_action 15:47:40 INFO - Running main action method: populate_webroot 15:47:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:47:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:47:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:47:40 INFO - '--tppagecycles', 15:47:40 INFO - '7'], 15:47:40 INFO - 'tests': ['tsvgm']}}, 15:47:40 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 15:47:40 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 15:47:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:47:40 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:47:40 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 15:47:40 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 15:47:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:47:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:40 INFO - 'talos_options': ['--e10s'], 15:47:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:47:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:40 INFO - 'tests': ['damp', 'tps']}, 15:47:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:40 INFO - 'talos_options': ['--e10s'], 15:47:40 INFO - 'tests': ['damp', 'tps']}, 15:47:40 INFO - 'g3': {'tests': ['dromaeo_dom']}, 15:47:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:47:40 INFO - 'other': {'tests': ['a11yr', 15:47:40 INFO - 'ts_paint', 15:47:40 INFO - 'tpaint', 15:47:40 INFO - 'sessionrestore', 15:47:40 INFO - 'sessionrestore_no_auto_restore']}, 15:47:40 INFO - 'other-e10s': {'talos_options': ['--e10s'], 15:47:40 INFO - 'tests': ['a11yr', 15:47:40 INFO - 'ts_paint', 15:47:40 INFO - 'tpaint', 15:47:40 INFO - 'sessionrestore', 15:47:40 INFO - 'sessionrestore_no_auto_restore']}, 15:47:40 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 15:47:40 INFO - 'tests': ['a11yr', 15:47:40 INFO - 'ts_paint', 15:47:40 INFO - 'tpaint', 15:47:40 INFO - 'sessionrestore', 15:47:40 INFO - 'sessionrestore_no_auto_restore']}, 15:47:40 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 15:47:40 INFO - 'tests': ['a11yr', 15:47:40 INFO - 'ts_paint', 15:47:40 INFO - 'tpaint', 15:47:40 INFO - 'sessionrestore', 15:47:40 INFO - 'sessionrestore_no_auto_restore']}, 15:47:40 INFO - 'other_l64': {'tests': ['a11yr', 15:47:40 INFO - 'ts_paint', 15:47:40 INFO - 'tpaint', 15:47:40 INFO - 'sessionrestore', 15:47:40 INFO - 'sessionrestore_no_auto_restore']}, 15:47:40 INFO - 'other_nol64': {'tests': ['a11yr', 15:47:40 INFO - 'ts_paint', 15:47:40 INFO - 'tpaint', 15:47:40 INFO - 'sessionrestore', 15:47:40 INFO - 'sessionrestore_no_auto_restore']}, 15:47:40 INFO - 'svgr': {'tests': ['tsvgx', 15:47:40 INFO - 'tsvgr_opacity', 15:47:40 INFO - 'tart', 15:47:40 INFO - 'tscrollx', 15:47:40 INFO - 'cart']}, 15:47:40 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 15:47:40 INFO - 'tests': ['tsvgx', 15:47:40 INFO - 'tsvgr_opacity', 15:47:40 INFO - 'tart', 15:47:40 INFO - 'tscrollx', 15:47:40 INFO - 'cart']}, 15:47:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:40 INFO - 'tests': ['tp5o']}, 15:47:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:40 INFO - 'talos_options': ['--e10s'], 15:47:40 INFO - 'tests': ['tp5o']}, 15:47:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:40 INFO - 'talos_options': ['--xperf_path', 15:47:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:47:40 INFO - 'tests': ['tp5n']}, 15:47:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:40 INFO - 'talos_options': ['--e10s', 15:47:40 INFO - '--xperf_path', 15:47:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:47:40 INFO - 'tests': ['tp5n']}}, 15:47:40 INFO - 'talos.zip': {'path': '', 15:47:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:47:40 INFO - Running post-action listener: _resource_record_post_action 15:47:40 INFO - ##### 15:47:40 INFO - ##### Running create-virtualenv step. 15:47:40 INFO - ##### 15:47:40 INFO - Running pre-action listener: _resource_record_pre_action 15:47:40 INFO - Running main action method: create_virtualenv 15:47:40 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 15:47:40 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 15:47:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 15:47:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:47:40 INFO - Using real prefix '/usr' 15:47:40 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 15:47:40 INFO - Installing distribute.............................................................................................................................................................................................done. 15:47:42 INFO - Installing pip.................done. 15:47:42 INFO - Return code: 0 15:47:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 15:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:42 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 0x7f4ed4766f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2038660>, '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 0x2039560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2039a10>, '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': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', '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 15:47:42 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 15:47:42 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 15:47:42 INFO - Using env: {'DISPLAY': ':0', 15:47:42 INFO - 'HOME': '/home/cltbld', 15:47:42 INFO - 'LANG': 'en_US.UTF-8', 15:47:42 INFO - 'LANGUAGE': 'en_US:en', 15:47:42 INFO - 'LOGNAME': 'cltbld', 15:47:42 INFO - 'MAIL': '/var/mail/cltbld', 15:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:42 INFO - 'MOZ_NO_REMOTE': '1', 15:47:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:42 INFO - 'NO_EM_RESTART': '1', 15:47:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:47:42 INFO - 'PWD': '/builds/slave/test-pgo', 15:47:42 INFO - 'SHELL': '/bin/bash', 15:47:42 INFO - 'SHLVL': '1', 15:47:42 INFO - 'TERM': 'linux', 15:47:42 INFO - 'TMOUT': '86400', 15:47:42 INFO - 'USER': 'cltbld', 15:47:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', 15:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:42 INFO - '_': '/tools/buildbot/bin/python'} 15:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:42 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 15:47:42 INFO - Cleaning up... 15:47:42 INFO - Return code: 0 15:47:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 15:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:42 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 0x7f4ed4766f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2038660>, '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 0x2039560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2039a10>, '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': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', '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 15:47:42 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 15:47:42 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 15:47:42 INFO - Using env: {'DISPLAY': ':0', 15:47:42 INFO - 'HOME': '/home/cltbld', 15:47:42 INFO - 'LANG': 'en_US.UTF-8', 15:47:42 INFO - 'LANGUAGE': 'en_US:en', 15:47:42 INFO - 'LOGNAME': 'cltbld', 15:47:42 INFO - 'MAIL': '/var/mail/cltbld', 15:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:42 INFO - 'MOZ_NO_REMOTE': '1', 15:47:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:42 INFO - 'NO_EM_RESTART': '1', 15:47:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:47:42 INFO - 'PWD': '/builds/slave/test-pgo', 15:47:42 INFO - 'SHELL': '/bin/bash', 15:47:42 INFO - 'SHLVL': '1', 15:47:42 INFO - 'TERM': 'linux', 15:47:42 INFO - 'TMOUT': '86400', 15:47:42 INFO - 'USER': 'cltbld', 15:47:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', 15:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:42 INFO - '_': '/tools/buildbot/bin/python'} 15:47:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:43 INFO - Downloading/unpacking psutil>=0.7.1 15:47:43 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 15:47:43 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 15:47:43 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 15:47:43 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 15:47:43 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 15:47:43 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 15:47:44 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 15:47:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 15:47:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 15:47:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:47:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:47:44 INFO - Installing collected packages: psutil 15:47:44 INFO - Running setup.py install for psutil 15:47:44 INFO - building 'psutil._psutil_linux' extension 15:47:44 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 15:47:45 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 15:47:45 INFO - building 'psutil._psutil_posix' extension 15:47:45 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 15:47:45 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 15:47:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:47:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:47:45 INFO - Successfully installed psutil 15:47:45 INFO - Cleaning up... 15:47:45 INFO - Return code: 0 15:47:45 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 15:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:45 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 0x7f4ed4766f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2038660>, '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 0x2039560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2039a10>, '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': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', '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 15:47:45 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 15:47:45 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 15:47:45 INFO - Using env: {'DISPLAY': ':0', 15:47:45 INFO - 'HOME': '/home/cltbld', 15:47:45 INFO - 'LANG': 'en_US.UTF-8', 15:47:45 INFO - 'LANGUAGE': 'en_US:en', 15:47:45 INFO - 'LOGNAME': 'cltbld', 15:47:45 INFO - 'MAIL': '/var/mail/cltbld', 15:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:45 INFO - 'MOZ_NO_REMOTE': '1', 15:47:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:45 INFO - 'NO_EM_RESTART': '1', 15:47:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:47:45 INFO - 'PWD': '/builds/slave/test-pgo', 15:47:45 INFO - 'SHELL': '/bin/bash', 15:47:45 INFO - 'SHLVL': '1', 15:47:45 INFO - 'TERM': 'linux', 15:47:45 INFO - 'TMOUT': '86400', 15:47:45 INFO - 'USER': 'cltbld', 15:47:45 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', 15:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:45 INFO - '_': '/tools/buildbot/bin/python'} 15:47:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:45 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:47:45 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 15:47:45 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 15:47:45 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 15:47:45 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 15:47:45 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 15:47:45 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 15:47:47 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:47:47 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 15:47:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:47:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:47:47 INFO - Installing collected packages: mozsystemmonitor 15:47:47 INFO - Running setup.py install for mozsystemmonitor 15:47:47 INFO - Successfully installed mozsystemmonitor 15:47:47 INFO - Cleaning up... 15:47:47 INFO - Return code: 0 15:47:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 15:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:47 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 0x7f4ed4766f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2038660>, '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 0x2039560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2039a10>, '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': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', '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 15:47:47 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 15:47:47 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 15:47:47 INFO - Using env: {'DISPLAY': ':0', 15:47:47 INFO - 'HOME': '/home/cltbld', 15:47:47 INFO - 'LANG': 'en_US.UTF-8', 15:47:47 INFO - 'LANGUAGE': 'en_US:en', 15:47:47 INFO - 'LOGNAME': 'cltbld', 15:47:47 INFO - 'MAIL': '/var/mail/cltbld', 15:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:47 INFO - 'MOZ_NO_REMOTE': '1', 15:47:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:47 INFO - 'NO_EM_RESTART': '1', 15:47:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:47:47 INFO - 'PWD': '/builds/slave/test-pgo', 15:47:47 INFO - 'SHELL': '/bin/bash', 15:47:47 INFO - 'SHLVL': '1', 15:47:47 INFO - 'TERM': 'linux', 15:47:47 INFO - 'TMOUT': '86400', 15:47:47 INFO - 'USER': 'cltbld', 15:47:47 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', 15:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:47 INFO - '_': '/tools/buildbot/bin/python'} 15:47:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:47 INFO - Downloading/unpacking blobuploader==1.2.4 15:47:47 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 15:47:47 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 15:47:47 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 15:47:47 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 15:47:47 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 15:47:47 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 15:47:49 INFO - Downloading blobuploader-1.2.4.tar.gz 15:47:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 15:47:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:47:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:49 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 15:47:49 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 15:47:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:50 INFO - Downloading docopt-0.6.1.tar.gz 15:47:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 15:47:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 15:47:50 INFO - Installing collected packages: blobuploader, requests, docopt 15:47:50 INFO - Running setup.py install for blobuploader 15:47:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:47:50 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 15:47:50 INFO - Running setup.py install for requests 15:47:50 INFO - Running setup.py install for docopt 15:47:50 INFO - Successfully installed blobuploader requests docopt 15:47:50 INFO - Cleaning up... 15:47:50 INFO - Return code: 0 15:47:50 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:47:50 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')]} 15:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:50 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')]} 15:47:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:50 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 0x7f4ed4766f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2038660>, '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 0x2039560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2039a10>, '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': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', '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 15:47:50 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 15:47:50 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 15:47:50 INFO - Using env: {'DISPLAY': ':0', 15:47:50 INFO - 'HOME': '/home/cltbld', 15:47:50 INFO - 'LANG': 'en_US.UTF-8', 15:47:50 INFO - 'LANGUAGE': 'en_US:en', 15:47:50 INFO - 'LOGNAME': 'cltbld', 15:47:50 INFO - 'MAIL': '/var/mail/cltbld', 15:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:50 INFO - 'MOZ_NO_REMOTE': '1', 15:47:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:50 INFO - 'NO_EM_RESTART': '1', 15:47:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:47:50 INFO - 'PWD': '/builds/slave/test-pgo', 15:47:50 INFO - 'SHELL': '/bin/bash', 15:47:50 INFO - 'SHLVL': '1', 15:47:50 INFO - 'TERM': 'linux', 15:47:50 INFO - 'TMOUT': '86400', 15:47:50 INFO - 'USER': 'cltbld', 15:47:50 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', 15:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:50 INFO - '_': '/tools/buildbot/bin/python'} 15:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:47:51 INFO - Running setup.py (path:/tmp/pip-VLATQu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:47:51 INFO - Running setup.py (path:/tmp/pip-041EGu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:47:51 INFO - Running setup.py (path:/tmp/pip-H4ogeX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:47:51 INFO - Running setup.py (path:/tmp/pip-mZqZzG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:47:51 INFO - Running setup.py (path:/tmp/pip-yX65gR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:47:51 INFO - Running setup.py (path:/tmp/pip-SvorUM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:47:51 INFO - Running setup.py (path:/tmp/pip-MqnKaL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:47:51 INFO - Running setup.py (path:/tmp/pip-KAj_22-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:47:51 INFO - Running setup.py (path:/tmp/pip-C9z5tJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:47:51 INFO - Running setup.py (path:/tmp/pip-C0kp6L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:47:51 INFO - Running setup.py (path:/tmp/pip-ioZjMc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:47:51 INFO - Running setup.py (path:/tmp/pip-MZ2vpi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:47:51 INFO - Running setup.py (path:/tmp/pip-sRt2Ba-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:47:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:47:51 INFO - Running setup.py (path:/tmp/pip-lYFOgd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:47:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:47:52 INFO - Running setup.py (path:/tmp/pip-v8Bb7z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:47:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:47:52 INFO - Running setup.py (path:/tmp/pip-1HAmqx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:47:52 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:47:52 INFO - Running setup.py (path:/tmp/pip-lde45L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:47:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:47:52 INFO - Running setup.py install for manifestparser 15:47:52 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 15:47:52 INFO - Running setup.py install for mozcrash 15:47:52 INFO - Running setup.py install for mozdebug 15:47:52 INFO - Running setup.py install for mozdevice 15:47:52 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 15:47:52 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 15:47:52 INFO - Running setup.py install for mozfile 15:47:52 INFO - Running setup.py install for mozhttpd 15:47:52 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 15:47:52 INFO - Running setup.py install for mozinfo 15:47:52 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 15:47:52 INFO - Running setup.py install for mozInstall 15:47:53 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 15:47:53 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 15:47:53 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 15:47:53 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 15:47:53 INFO - Running setup.py install for mozleak 15:47:53 INFO - Running setup.py install for mozlog 15:47:53 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 15:47:53 INFO - Running setup.py install for moznetwork 15:47:53 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 15:47:53 INFO - Running setup.py install for mozprocess 15:47:53 INFO - Running setup.py install for mozprofile 15:47:53 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 15:47:53 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 15:47:53 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 15:47:53 INFO - Running setup.py install for mozrunner 15:47:53 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 15:47:53 INFO - Running setup.py install for mozscreenshot 15:47:53 INFO - Running setup.py install for moztest 15:47:53 INFO - Running setup.py install for mozversion 15:47:54 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 15:47:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:47:54 INFO - Cleaning up... 15:47:54 INFO - Return code: 0 15:47:54 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:47: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')]} 15:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:54 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 0x7f4ed4766f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2038660>, '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 0x2039560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2039a10>, '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': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', '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 15:47:54 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 15:47:54 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 15:47:54 INFO - Using env: {'DISPLAY': ':0', 15:47:54 INFO - 'HOME': '/home/cltbld', 15:47:54 INFO - 'LANG': 'en_US.UTF-8', 15:47:54 INFO - 'LANGUAGE': 'en_US:en', 15:47:54 INFO - 'LOGNAME': 'cltbld', 15:47:54 INFO - 'MAIL': '/var/mail/cltbld', 15:47:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:54 INFO - 'MOZ_NO_REMOTE': '1', 15:47:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:54 INFO - 'NO_EM_RESTART': '1', 15:47:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:47:54 INFO - 'PWD': '/builds/slave/test-pgo', 15:47:54 INFO - 'SHELL': '/bin/bash', 15:47:54 INFO - 'SHLVL': '1', 15:47:54 INFO - 'TERM': 'linux', 15:47:54 INFO - 'TMOUT': '86400', 15:47:54 INFO - 'USER': 'cltbld', 15:47:54 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', 15:47:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:54 INFO - '_': '/tools/buildbot/bin/python'} 15:47:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:47:54 INFO - Running setup.py (path:/tmp/pip-Udp8Uj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 15:47:54 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)) 15:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:47:54 INFO - Running setup.py (path:/tmp/pip-8WSzGr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 15:47:54 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)) 15:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:47:54 INFO - Running setup.py (path:/tmp/pip-G64ecf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 15:47:54 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)) 15:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:47:54 INFO - Running setup.py (path:/tmp/pip-qy2HDC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 15:47:54 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)) 15:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 15:47:54 INFO - Running setup.py (path:/tmp/pip-bFl0Uy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 15:47:54 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)) 15:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:47:54 INFO - Running setup.py (path:/tmp/pip-ZHPVdV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 15:47:54 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)) 15:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:47:54 INFO - Running setup.py (path:/tmp/pip-3_4DaK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 15:47:54 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)) 15:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:47:54 INFO - Running setup.py (path:/tmp/pip-GDI4eQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 15:47:54 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)) 15:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 15:47:54 INFO - Running setup.py (path:/tmp/pip-X_Jn2I-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 15:47:54 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)) 15:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 15:47:54 INFO - Running setup.py (path:/tmp/pip-m66PPp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 15:47:54 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)) 15:47:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:47:54 INFO - Running setup.py (path:/tmp/pip-x0vhWS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 15:47:55 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)) 15:47:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:47:55 INFO - Running setup.py (path:/tmp/pip-VENkh0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 15:47:55 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)) 15:47:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:47:55 INFO - Running setup.py (path:/tmp/pip-5On_iT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 15:47:55 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)) 15:47:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:47:55 INFO - Running setup.py (path:/tmp/pip-6iwJXf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 15:47:55 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)) 15:47:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:47:55 INFO - Running setup.py (path:/tmp/pip-i1UbbG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 15:47:55 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)) 15:47:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 15:47:55 INFO - Running setup.py (path:/tmp/pip-yNmHrZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 15:47:55 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)) 15:47:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 15:47:55 INFO - Running setup.py (path:/tmp/pip-1pGUCA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 15:47:55 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)) 15:47:55 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)) 15:47:55 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)) 15:47:55 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)) 15:47:55 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)) 15:47:55 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)) 15:47:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 15:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:57 INFO - Downloading blessings-1.5.1.tar.gz 15:47:57 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 15:47:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 15:47:57 INFO - Installing collected packages: blessings 15:47:57 INFO - Running setup.py install for blessings 15:47:57 INFO - Successfully installed blessings 15:47:57 INFO - Cleaning up... 15:47:57 INFO - Return code: 0 15:47:57 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 15:47:57 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:47:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:47:57 INFO - Reading from file tmpfile_stdout 15:47:57 INFO - Current package versions: 15:47:57 INFO - argparse == 1.2.1 15:47:57 INFO - blessings == 1.5.1 15:47:57 INFO - blobuploader == 1.2.4 15:47:57 INFO - docopt == 0.6.1 15:47:57 INFO - manifestparser == 1.1 15:47:57 INFO - mozInstall == 1.12 15:47:57 INFO - mozcrash == 0.16 15:47:57 INFO - mozdebug == 0.1 15:47:57 INFO - mozdevice == 0.47 15:47:57 INFO - mozfile == 1.2 15:47:57 INFO - mozhttpd == 0.7 15:47:57 INFO - mozinfo == 0.9 15:47:57 INFO - mozleak == 0.1 15:47:57 INFO - mozlog == 3.1 15:47:57 INFO - moznetwork == 0.27 15:47:57 INFO - mozprocess == 0.22 15:47:57 INFO - mozprofile == 0.28 15:47:57 INFO - mozrunner == 6.11 15:47:57 INFO - mozscreenshot == 0.1 15:47:57 INFO - mozsystemmonitor == 0.0 15:47:57 INFO - moztest == 0.7 15:47:57 INFO - mozversion == 1.4 15:47:57 INFO - psutil == 3.1.1 15:47:57 INFO - requests == 1.2.3 15:47:57 INFO - wsgiref == 0.1.2 15:47:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 15:47:57 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')]} 15:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:57 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')]} 15:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:57 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 0x7f4ed4766f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2038660>, '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 0x2039560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2039a10>, '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': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', '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 15:47:57 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 15:47:57 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 15:47:57 INFO - Using env: {'DISPLAY': ':0', 15:47:57 INFO - 'HOME': '/home/cltbld', 15:47:57 INFO - 'LANG': 'en_US.UTF-8', 15:47:57 INFO - 'LANGUAGE': 'en_US:en', 15:47:57 INFO - 'LOGNAME': 'cltbld', 15:47:57 INFO - 'MAIL': '/var/mail/cltbld', 15:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:57 INFO - 'MOZ_NO_REMOTE': '1', 15:47:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:57 INFO - 'NO_EM_RESTART': '1', 15:47:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:47:57 INFO - 'PWD': '/builds/slave/test-pgo', 15:47:57 INFO - 'SHELL': '/bin/bash', 15:47:57 INFO - 'SHLVL': '1', 15:47:57 INFO - 'TERM': 'linux', 15:47:57 INFO - 'TMOUT': '86400', 15:47:57 INFO - 'USER': 'cltbld', 15:47:57 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', 15:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:57 INFO - '_': '/tools/buildbot/bin/python'} 15:47:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:57 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)) 15:47:57 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)) 15:47:57 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)) 15:47:57 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)) 15:47:57 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)) 15:47:57 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)) 15:47:57 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)) 15:47:57 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)) 15:47:57 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)) 15:47:57 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)) 15:47:57 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)) 15:47:57 INFO - Cleaning up... 15:47:57 INFO - Return code: 0 15:47:57 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 15:47:57 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')]} 15:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:57 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')]} 15:47:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:57 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 0x7f4ed4766f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2038660>, '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 0x2039560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2039a10>, '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': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', '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 15:47:57 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 15:47:57 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 15:47:57 INFO - Using env: {'DISPLAY': ':0', 15:47:57 INFO - 'HOME': '/home/cltbld', 15:47:57 INFO - 'LANG': 'en_US.UTF-8', 15:47:57 INFO - 'LANGUAGE': 'en_US:en', 15:47:57 INFO - 'LOGNAME': 'cltbld', 15:47:57 INFO - 'MAIL': '/var/mail/cltbld', 15:47:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:57 INFO - 'MOZ_NO_REMOTE': '1', 15:47:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:47:57 INFO - 'NO_EM_RESTART': '1', 15:47:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:47:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:47:57 INFO - 'PWD': '/builds/slave/test-pgo', 15:47:57 INFO - 'SHELL': '/bin/bash', 15:47:57 INFO - 'SHLVL': '1', 15:47:57 INFO - 'TERM': 'linux', 15:47:57 INFO - 'TMOUT': '86400', 15:47:57 INFO - 'USER': 'cltbld', 15:47:57 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', 15:47:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:57 INFO - '_': '/tools/buildbot/bin/python'} 15:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:58 INFO - Downloading/unpacking jsonschema 15:47:58 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 15:47:58 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 15:47:58 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 15:47:58 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 15:47:58 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 15:47:58 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 15:47:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:47:59 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 15:47:59 INFO - Downloading/unpacking functools32 (from jsonschema) 15:47:59 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 15:47:59 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 15:47:59 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 15:47:59 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 15:47:59 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 15:47:59 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 15:47:59 INFO - Downloading functools32-3.2.3-2.tar.gz 15:47:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 15:47:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 15:48:00 INFO - warning: no files found matching '*.txt' 15:48:00 INFO - no previously-included directories found matching 'build' 15:48:00 INFO - no previously-included directories found matching 'dist' 15:48:00 INFO - no previously-included directories found matching '.git*' 15:48:00 INFO - Installing collected packages: jsonschema, functools32 15:48:00 INFO - Running setup.py install for functools32 15:48:00 INFO - warning: no files found matching '*.txt' 15:48:00 INFO - no previously-included directories found matching 'build' 15:48:00 INFO - no previously-included directories found matching 'dist' 15:48:00 INFO - no previously-included directories found matching '.git*' 15:48:00 INFO - Successfully installed jsonschema functools32 15:48:00 INFO - Cleaning up... 15:48:00 INFO - Return code: 0 15:48:00 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 15:48:00 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')]} 15:48:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:48:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:00 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')]} 15:48:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:48:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:48:00 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 0x7f4ed4766f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1edc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fa9480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2038660>, '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 0x2039560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2039a10>, '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': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', '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 15:48:00 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 15:48:00 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 15:48:00 INFO - Using env: {'DISPLAY': ':0', 15:48:00 INFO - 'HOME': '/home/cltbld', 15:48:00 INFO - 'LANG': 'en_US.UTF-8', 15:48:00 INFO - 'LANGUAGE': 'en_US:en', 15:48:00 INFO - 'LOGNAME': 'cltbld', 15:48:00 INFO - 'MAIL': '/var/mail/cltbld', 15:48:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:00 INFO - 'MOZ_NO_REMOTE': '1', 15:48:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:00 INFO - 'NO_EM_RESTART': '1', 15:48:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:48:00 INFO - 'PWD': '/builds/slave/test-pgo', 15:48:00 INFO - 'SHELL': '/bin/bash', 15:48:00 INFO - 'SHLVL': '1', 15:48:00 INFO - 'TERM': 'linux', 15:48:00 INFO - 'TMOUT': '86400', 15:48:00 INFO - 'USER': 'cltbld', 15:48:00 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', 15:48:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:00 INFO - '_': '/tools/buildbot/bin/python'} 15:48:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:48:00 INFO - Downloading/unpacking flake8 15:48:00 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 15:48:00 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 15:48:00 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 15:48:00 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 15:48:00 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 15:48:00 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 15:48:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:48:02 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 15:48:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:48:02 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 15:48:02 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 15:48:02 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 15:48:02 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 15:48:02 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 15:48:02 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 15:48:02 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:48:02 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 15:48:02 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:48:02 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 15:48:02 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 15:48:02 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 15:48:02 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 15:48:02 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 15:48:02 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 15:48:02 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 15:48:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:48:02 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 15:48:02 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 15:48:02 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 15:48:02 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 15:48:02 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 15:48:02 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 15:48:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 15:48:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 15:48:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:48:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:48:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:48:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:48:02 INFO - no previously-included directories found matching 'docs/_build' 15:48:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:48:02 INFO - Running setup.py install for pep8 15:48:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:48:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:48:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:48:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:48:02 INFO - no previously-included directories found matching 'docs/_build' 15:48:02 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 15:48:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:48:02 INFO - Cleaning up... 15:48:03 INFO - Return code: 0 15:48:03 INFO - Running post-action listener: _resource_record_post_action 15:48:03 INFO - Running post-action listener: _start_resource_monitoring 15:48:03 INFO - Starting resource monitoring. 15:48:03 INFO - ##### 15:48:03 INFO - ##### Running install step. 15:48:03 INFO - ##### 15:48:03 INFO - Running pre-action listener: _resource_record_pre_action 15:48:03 INFO - Running main action method: install 15:48:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 15:48:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 15:48:03 INFO - Reading from file tmpfile_stdout 15:48:03 INFO - Detecting whether we're running mozinstall >=1.0... 15:48:03 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 15:48:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 15:48:03 INFO - Reading from file tmpfile_stdout 15:48:03 INFO - Output received: 15:48:03 INFO - Usage: mozinstall [options] installer 15:48:03 INFO - Options: 15:48:03 INFO - -h, --help show this help message and exit 15:48:03 INFO - -d DEST, --destination=DEST 15:48:03 INFO - Directory to install application into. [default: 15:48:03 INFO - "/builds/slave/test-pgo"] 15:48:03 INFO - --app=APP Application being installed. [default: firefox] 15:48:03 INFO - mkdir: /builds/slave/test-pgo/build/application 15:48:03 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'] 15:48:03 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 15:48:19 INFO - Reading from file tmpfile_stdout 15:48:19 INFO - Output received: 15:48:19 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 15:48:19 INFO - Running post-action listener: _resource_record_post_action 15:48:19 INFO - ##### 15:48:19 INFO - ##### Running run-tests step. 15:48:19 INFO - ##### 15:48:19 INFO - Running pre-action listener: _resource_record_pre_action 15:48:19 INFO - Running main action method: run_tests 15:48:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 15:48:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 15:48:19 INFO - Python 2.7.3 15:48:19 INFO - Return code: 0 15:48:19 INFO - grabbing minidump binary from tooltool 15:48:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:19 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 0x2038660>, '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 0x2039560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2039a10>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 15:48:19 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 15:48:19 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 15:48:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:48:19 INFO - Return code: 0 15:48:19 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 15:48:19 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 15:48:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 15:48:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 15:48:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 15:48:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 15:48:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 15:48:20 INFO - Return code: 0 15:48:20 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 15:48:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 15:48:20 INFO - Using env: {'DISPLAY': ':0', 15:48:20 INFO - 'HOME': '/home/cltbld', 15:48:20 INFO - 'LANG': 'en_US.UTF-8', 15:48:20 INFO - 'LANGUAGE': 'en_US:en', 15:48:20 INFO - 'LOGNAME': 'cltbld', 15:48:20 INFO - 'MAIL': '/var/mail/cltbld', 15:48:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:48:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 15:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:20 INFO - 'MOZ_NO_REMOTE': '1', 15:48:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 15:48:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:48:20 INFO - 'NO_EM_RESTART': '1', 15:48:20 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 15:48:20 INFO - 'PWD': '/builds/slave/test-pgo', 15:48:20 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 15:48:20 INFO - 'SHELL': '/bin/bash', 15:48:20 INFO - 'SHLVL': '1', 15:48:20 INFO - 'TERM': 'linux', 15:48:20 INFO - 'TMOUT': '86400', 15:48:20 INFO - 'USER': 'cltbld', 15:48:20 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735', 15:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:20 INFO - '_': '/tools/buildbot/bin/python'} 15:48:20 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:48:20 INFO - mozversion application_buildid: 20160109133014 15:48:20 INFO - mozversion application_changeset: c33f30666b37dbceffb9fbe5089a668db8893a85 15:48:20 INFO - mozversion application_display_name: Nightly 15:48:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:48:20 INFO - mozversion application_name: Firefox 15:48:20 INFO - mozversion application_remotingname: firefox 15:48:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:48:20 INFO - mozversion application_vendor: Mozilla 15:48:20 INFO - mozversion application_version: 46.0a1 15:48:20 INFO - mozversion platform_buildid: 20160109133014 15:48:20 INFO - mozversion platform_changeset: c33f30666b37dbceffb9fbe5089a668db8893a85 15:48:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:48:20 INFO - mozversion platform_version: 46.0a1 15:48:20 INFO - using testdate: 1452383300 15:48:20 INFO - actual date: 1452383300 15:48:20 INFO - starting webserver on 'localhost:33424' 15:48:20 INFO - SUITE-START | Running 3 tests 15:48:20 INFO - TEST-START | dromaeo_css 15:48:20 INFO - operating with platform_type : linux_ 15:48:20 INFO - Initialising browser for dromaeo_css test... 15:48:20 INFO - TEST-INFO | started process 4449 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGJasWU/profile http://localhost:33424/getInfo.html) 15:48:24 INFO - PROCESS | 4449 | __metrics Screen width/height:1600/1200 15:48:24 INFO - PROCESS | 4449 | colorDepth:24 15:48:24 INFO - PROCESS | 4449 | Browser inner width/height: 1024/697 15:48:24 INFO - PROCESS | 4449 | __metrics 15:48:24 INFO - PROCESS | 4449 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 15:48:24 INFO - PROCESS | 4449 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:48:25 INFO - TEST-INFO | 4449: exit 0 15:48:25 INFO - Browser initialized. 15:48:25 INFO - Running cycle 1/1 for dromaeo_css test... 15:48:25 INFO - TEST-INFO | started process 4543 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGJasWU/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:48:37 INFO - PROCESS | 4543 | RSS: Main: 148893696 15:48:37 INFO - PROCESS | 4543 | 15:49:29 INFO - PROCESS | 4543 | 1452383369087 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] 15:51:37 INFO - PROCESS | 4543 | Cycle 1(1): loaded http://localhost:33424/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33424/tests/dromaeo/cssquery-ext.html) 15:51:38 INFO - PROCESS | 4543 | RSS: Main: 275378176 15:51:38 INFO - PROCESS | 4543 | 15:54:41 INFO - PROCESS | 4543 | Cycle 1(1): loaded http://localhost:33424/tests/dromaeo/cssquery-ext.html (next: http://localhost:33424/tests/dromaeo/cssquery-jquery.html) 15:54:41 INFO - PROCESS | 4543 | RSS: Main: 237563904 15:54:41 INFO - PROCESS | 4543 | 15:57:43 INFO - PROCESS | 4543 | Cycle 1(1): loaded http://localhost:33424/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33424/tests/dromaeo/cssquery-mootools.html) 15:57:44 INFO - PROCESS | 4543 | RSS: Main: 252608512 15:57:44 INFO - PROCESS | 4543 | 16:00:46 INFO - PROCESS | 4543 | Cycle 1(1): loaded http://localhost:33424/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33424/tests/dromaeo/cssquery-prototype.html) 16:00:46 INFO - PROCESS | 4543 | RSS: Main: 218144768 16:00:46 INFO - PROCESS | 4543 | 16:03:49 INFO - PROCESS | 4543 | Cycle 1(1): loaded http://localhost:33424/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33424/tests/dromaeo/cssquery-yui.html) 16:03:49 INFO - PROCESS | 4543 | RSS: Main: 219910144 16:03:49 INFO - PROCESS | 4543 | 16:06:52 INFO - PROCESS | 4543 | Cycle 1(1): loaded http://localhost:33424/tests/dromaeo/cssquery-yui.html (next: http://localhost:33424/tests/dromaeo/cssquery-dojo.html) 16:06:52 INFO - PROCESS | 4543 | RSS: Main: 267558912 16:06:52 INFO - PROCESS | 4543 | 16:06:52 INFO - PROCESS | 4543 | __start_tp_report 16:06:52 INFO - PROCESS | 4543 | _x_x_mozilla_page_load 16:06:52 INFO - PROCESS | 4543 | _x_x_mozilla_page_load_details 16:06:52 INFO - PROCESS | 4543 | |i|pagename|runs| 16:06:52 INFO - PROCESS | 4543 | |0;dojo.html;3070.2631368471566;3160.778745487814;3168.5088811234236;3189.314297426053;3217.5173723941407;4961.20620700688;5089.185730283156;5141.357330333703;5170.767315470804;5209.062368773619;5099.954509325588;5105.897882042357;5182;5198;5205.583553315735;4496.9550304497025;4616.584507394334;4665.976670116653;4737.4551926528375;4812.398450193726;4606.585407313341;4707.858764237061;4748.430188377382;4758.072175925696;4861.805527778903;4575.702579332333;4660.743659098782;4664.416947881515;4667;4668.089722504079;4743.09881122589;4753.857384278477;4757.167495688275;4760.024195040043;4766.142094423002;4601.401817763669;4673.439187297512;4679.789409476582;4726.480087190406;4730.62155027597;5036.6726162799305;5054.69671819692;5057.342545469065;5057.342545469102;5065.797368105308;5553.278073850414;5586.664800112007;5588.273524441837;5597.524210442118;5646.774129034834;5395.973020134874;5456.208849716809;5493.176023596453;5505.339359276913;5583.022970980062;5119.641625086283;5121.155009423441;5125.6155788315655;5128.512791284859;5140.563052140573;5578.470045345685;5591.580631452576;5630.915536266959;5632.690202038845;5635.971820140873;5278.656887302313;5385.380681221632;5390.407055223922;5395.406505284415;5422.701751403711;5499.340079190522;5502.367227768857;5506;5506.587005974568;5536.640118392292;5461.208124821958;5471.699056551848;5490.203920431595;5491.533219676292;5535.114381698909;5361.571074314045;5426.131818908955;5468.91796623051;5487.341519017744;5494.09347457665;4359.411479450234;4361.454818147731;4365;4394.868153955386;4395.758233297135;3871.2064026874423;3901.921961560753;3912.217556488714;3927.842886284574;3942.980285098556;5066.493350664978;5168.9483105168465;5176.9741151294;5199.272101905736;5241.685498870003;5414.3232095988005;5519.282493275929;5646.491815736604;5659.349174844946;5673.659580425187;5183.585313174937;5186.040582492251;5186.222066689951;5190.92213616796;5191.766370513336;4779.641526885497;4780.737059461693;4786;4815.807367705253;4825.686330388514;5553.833384998376;5617.466340697709;5656.8868622627315;5673.517750991129;5770.88458230833;5514.09017512106;5536.141898005815;5541.196526503715;5544.944550554443;5545.861353466195;5681.17622944679;5740.827775166752;5742.397048309867;5745.339285981999;5750.194972703908;5410.675359478444;5412.134058550613;5500.037493438552;5500.119980803052;5510.393856675682;5496.862578435571;5506;5520.641158324856;5528.170774383874;5531;5419.647722897999;5425.403205647454;5429.891402172014;5434.2120392542065;5489.149181876815;5196.350456192976;5350.571954243574;5364.4099149094345;5371.301730774974;5541.584381171348;5365.141577347606;5375.758090885842;5386.057439947915;5387.595930305164;5389.191621256686;5400.054990376747;5448.318960129984;5466.590005749665;5514.834554963358;5537.36320323168;5176.844694659167;5299.284596579412;5310.097283461743;5335.146376579729;5452.563794896319 16:06:52 INFO - PROCESS | 4543 | |1;ext.html;528.5216878724756;540.9999999999999;561.0518224201097;563.0231548263762;569.4419468920457;27936.301592460197;28415.715842841564;28437.57343639847;28512.429751404958;35520.11199720008;37745;37999;38083.4287485688;38227.617723822754;38238.61761382385;8311.459755115928;8578.78553036176;8582.828343433128;8585.871211931826;8590.1839325264;7379;7584.089909210902;8272.627731752056;9797.461139637353;10036.648717294896;9678.903210967906;9754.219662427024;9755.560999754993;9762;9766.755831104208;7395.371393431565;9902.900970990306;10118.84821727671;10130.290879638429;10130.848037279373;7255.637218139072;9772.804543909153;10018.448985305806;10033.99660033998;10040.397576145382;35380.82309588461;35684.286314273566;35789.64210357889;35813;35862.282754345026;3316.8341582920757;3409.1136304560746;3423.7945723256435;3531.4861959999675;3698.3158115748674;28467.715322846714;29579.556306655417;30034.399312013855;30105.09684709462;30219.09342719722;3911.198204368098;3980.5820388859333;4040.4949381327333;4052.49343832021;4090.979545102285;264171;265858.75553689076;266023;266065;266364;51704.74147629238;51914;51970.74014629978;51974.740126299126;56781.43218567844;60259.096113558335;60377.39622603806;60385.39614603798;60476.697616511636;60524.697376512835;13069.085164038615;13268.93365533166;13288.534901278408;13290.667733306746;13353.599392018255;10039.347442416365;10053.798924021625;10073.345232559861;10077.54651040705;10078.445685487222;17942.282308707767;18940.71588926167;18994.71507927382;19106.71339929902;19233.230670773013;5911.640322725797;7006.334398232159;7245.492815502864;7265.818354541073;7358.1538123116525;914.6570036236411;962.9807403851884;990.4898977026837;1004.3070281505737;1079.5843600213818;25929;25952.351191219994;26005.21984340435;26020.73979260221;26051.478970420485;1009.5059312217959;1030.8247597908373;1047.6490375724065;1072.3780207479642;1117.675873996532;12538.623841284776;13020.544280950311;13125.54060607874;13187.60437186886;13228.735425291441;234988;236120;236132.3867613225;238530;238941;44199;44923;44943;45080.3237951431;45163.096738065055;1285.4087119924939;1299.27240745183;1303.6284658872175;1326.2970625568464;1359.8504164541891;27638.723612763613;28402;28942.276443089093;29029.709702903547;29070.563941541317;41378.793106034274;42092.78953605212;45346.77326613346;45589.31616025696;45596.088078238914;379562;385756;386241;387993;388503.11496884667;29747.256318592208;30116;30131.54802677918;30157;30175.69824301729;40777.388339174344;41245.38131928084;41545;41981.37027944522;42402.78798605987;35449.82275088608;35789.747358842316;36121.81939090288;36342.27315453729;36412.27175456529;35306.47040294449;35872.64127358693;35973.64026359703;36039.63960360363;36101.63898361088;34669.653303467654;34779.30441391107;35543;35683;35724.46413303854;27589.172324829484;28858;29921.401571968872;31679.71455765964;32726.672733273313;22835.543289134457;23063.76936230616;23245.418864527845;25021.624675630243;25252 16:06:52 INFO - PROCESS | 4543 | |2;jquery.html;353.7629788042012;358.33707640864407;359.1559834389187;360.2506785885358;361.6979821848756;13409.396577154028;13832.585022449317;16148.838511614895;16739;17954.820451795462;15677.764833527477;15769.526914192593;16577.17114144294;16605.33578656852;16633.584160395996;4919.335889654895;5358.973205133969;5360.222767698681;5361.329833770778;5362.91955620666;3978.38335058065;4075.246079475292;5217.869553261161;5689.544836413077;5916.3787802280785;5645.943540564604;5771.884562308751;5829.0964900440285;5895.734691938873;5896.557758168155;5664.1220610805185;5688.374278829326;5698.544116470673;5716.942830571704;5718.857028574277;4933.1120398328285;5043.873903152414;5054.444011158769;5055.064813009605;5055.51972562606;19565.1195696194;19685.507862303413;19709.31017414398;20009.499762505908;20018.499537511532;2064.1640135745047;2135.9679604805783;2175.9020844062056;2206.6910632511463;2265.9773402265932;12112.576059837951;12476.18904771187;12619.898532976818;12835.614931552069;12931.288779117145;1768.1689605885213;1793.1930631215876;1808.3580328983285;1816.3461153984556;1818.770065453819;53540.73229633866;54615.45384546144;55089.72455137699;55276.9999999996;56058.43941560573;10069.841968173607;10099.596016159418;10139.087482126644;10168.593256269667;10230;13107.672308192181;13205.273709945854;13212.207267563977;13236;13243.20540767557;6848.897266541006;7016.473764467685;7017.298270173044;7033.156021277479;7094.432445404458;6559.639219842859;6566.4418524425955;6567.376099270562;6596.604203747695;6708.563943343667;3986.940195897064;4353.477582690098;4410.669199810027;4411.955880441219;4458.665600080007;1707.3256063854956;1767.9734803977951;1857.4056301983505;1867.9626407471774;1877.2678655324435;856.0839901305668;892.2148509311894;952.4523399045577;1021.5147804792664;1046.215338496128;13381.464741410235;15431.228438578026;15643.530694079196;16137.757933631005;16530.83469165293;888.9244414224863;914.0356923445828;921.3964853021282;1008.6772232885406;1017.4200705597887;8825.602847871862;9055.547222638841;9065.093490650883;9114.954425227832;9165.58754856033;463568;489136;498924;498954;501140;46252.07495850064;67069;67192.6640366795;67817;67938.32061679418;1074.9247552671395;1078.1105587890022;1083.6532309660834;1086.5708045322212;1089.5859573362072;14312.642183945069;14342.569722908329;14364.353604087632;14688;14693.118412895261;31068.53397199089;31398.843005784824;31911.68088319087;31932.680673192972;32009.8399508001;40223.1955360897;40686.186276273715;40983.79508102559;41250.143743531014;41388.586114138474;7035.12060992376;7243.0946131733535;7248.238934911758;7258.673359698932;7299.452541059336;7860.253275938777;8943.731688049369;9563.474008929297;10565.260431769702;10820.67537973862;9204.769880753034;9209.125133112344;9225.21585665226;9321.207697345802;9453.858192127262;8945;9021;9294.907050929403;9398.107179817905;9555.331126821056;8829.293656507592;8857.291416686778;9369.344145909721;9880.258980576342;9964.202863770735;7252.129744430702;7277;7299.087614048244;7335.596542190231;7353.448491363062;8483.490990540588;9851.704448866545;9921.305508614329;9963.053509916546;10047.547860346156 16:06:52 INFO - PROCESS | 4543 | |3;mootools.html;1265.177634537551;1427.8786303164231;1437.7771445425956;1607.9035257884527;1840.0523730278908;6958.095447591812;7608.695652173913;7757.456978011542;7767.33977611903;7777.611119444034;7448.627568621574;7635.045619297588;7646.273604007624;7647.426443016768;7647.541147531152;2303.608386574278;2325.546518428907;2330.860148391102;2332.0904847109555;2333.0784340185614;2235.228846048111;2260.400993736661;2264.3546589222074;2283.212291759349;2302.3668491164894;2314.6065168921323;2334.9182778602753;2345.343303874909;2347.1432926981615;2361.8464799788044;2335.7897789198882;2347.931691080554;2356.234223877238;2362.1024010875844;2363.326451961191;2339.801116905065;2343.168175297779;2344.6365813298967;2344.777246161629;2345.1088586337255;7713.96143019287;7750.302472777421;7765.184655611192;7767.262110099531;7775.533467991882;4807.134715751164;4881.925976285212;4886.364772579542;4920.926186107211;4923.483034281385;3124.875004999797;3156.1478400831647;3218.6298575663864;3224.1294850390263;3238.4170849247125;1030.7114008077751;1044.4151275285788;1045.8222102242562;1056.714687034504;1094.0645747885603;10933.56265749369;10948.781024379468;10958.726031849188;10970.670879873616;10972.725681857937;4296.162248361539;4384.69307148503;4424.668149888771;4437.400950871655;4450.109978004347;9312.627494900127;9338.346315757968;9340.813183736287;9341.439513629068;9347.859782103274;4761.95238047622;4837.564619184291;4842;4872.171730805771;4874.4150701916;4001.9399709004388;4108.36320370343;4111.773852438085;4313.654907607384;4457.04173602677;3729.7948612826012;3739.2147648994014;3752.962470375261;3773.1510410157493;3782.5650050244576;1224.6815827884814;1261.4197469164103;1269.1243042300782;1269.257484371653;1277.3230188000375;1226.969325766845;1260.6281147061638;1264.3741348032784;1353.5330311042674;1354.634248752851;7143.642817859086;7160.10498687664;7196.10048743907;7272.745453909088;7349.55902645843;468.20405310971483;473.80100357849784;475.9928601070987;478.76779761815493;483.166537722426;5707.400722924116;5808.94191058084;5810.331811841692;5811.4479124483105;5813.244278243801;8946.593148476306;9271.721848344561;9444.622215111318;9548.045195480396;9565.426074435556;9425.670101546413;9485.288603354777;9506.667266645634;9507.66723164686;9514.95335513093;7078.68145933424;7237.023001894782;7244.203137654854;7253.238409966983;7313.89029164563;7137.179224389261;7225.205227424874;7237.203907570058;7248.238934911969;7255;7889.6449659766595;8061.153578874134;8264.752057438287;8279.213474719892;8291.253787159185;8183.140770219279;8218.424710270223;8234.341252699889;8349.83300334002;8357.414980951518;6205.131281620667;6358.682065896779;6420.582662126947;6588.341165883373;6779.288174741581;7302.087239095114;7326.780196594111;7354.191038985814;7363.447741419523;7391.852162956819;6723.562968407234;6837.829054273683;6864.176298844171;6993.300669932966;6999.545029573062;6745.494087943326;6773.62745049027;6873.896891546531;6881.208661004049;6891.724331026703;6737.59574425536;6869.965650171717;6902.93097069043;6915.928031155179;6927.618980955895;6447.194100737408;6506.3493650634555;6506.4144227017905;6507.577007494498;6520.739170433129;6533.575317604341;6552.8361790955605;6582.901256481253;6601.273859875314;6603.933960660332 16:06:52 INFO - PROCESS | 4543 | |4;prototype.html;336.6095329417875;340.83810190159664;341.6839423533232;343.203767259957;351.9560054993124;5708.600397972144;5776.855578610532;5892.793752218673;5964.82105536834;5977.5815692901515;5698.8005419810215;5826.912596311059;5849.298084229898;5865.970670146654;5870.354261031283;4513.07481966197;4534.002519445717;4537.818487260505;4539.319102134673;4540.818367265306;4366.8034938427845;4435.62297204738;4444.888877778065;4452.933206001912;4534.659900507459;4455.46534415869;4465.776711164445;4480.439945006874;4483.260262056773;4486.618637415823;4447.3773671685985;4454.198244316021;4459.10817836434;4465.352523884018;4473.507914129443;3936.8228429720443;3939.6454319111417;3940.6256405641707;3958.970667626409;3980.6218409251073;5492.1212605982855;5600.1599760035915;5601.859953501154;5650.237217975562;5665.036943719578;5227.6079294052715;5234.6335756497065;5237.031149237365;5237.450067742909;5245.921311180336;5914.9112763308585;6253.280872699606;6256.0615907613765;6259.937400626027;6262.812115636538;4204.0540878302445;4220.008298049956;4265.466816647919;4268.445102136702;4280.59334363235;6370.267419246753;6459.257185423689;6468;6481.967590162019;6486.935130648681;4388.210122178039;4390.253656878338;4392.824287028547;4394.692371533962;4399.890002749957;4302.204092242882;4444.355568442557;4449.354843547732;4451.3545535897765;4452.398926144929;5171.81898633546;5172.120739474299;5173.896522069537;5179.093658609729;5181.145111056633;6258.780942666985;6262.497000719885;6266.937330626726;6276.811695649138;6281.309056003836;1561.2349948525368;1572.7247731646917;1573.417835400909;1574.66931944293;1575.086449859102;1054;1060.7984482948216;1063.3407287481812;1081.4268437728017;1096.2928910852456;5858.8535286617325;5865.589408741347;5870.236869207061;5878.5297176225795;5880.323762767251;6370.872582548323;6387.616742995435;6430.356964303626;6440.937245354464;6451.096846441502;5935.258092738408;5943.286805583271;5945.732442040118;5946.435088666656;5952.464278214981;3371.3088816792497;3462.4633181856852;3465.341585098823;3496.650334966483;3507.877224297137;6749.527533072736;6755.020522024279;6760.121184246115;6765.391114799691;6773.221079575814;6638.900416493757;6643.2360278568585;6643.701033453506;6647.966760166169;6653.301403352675;6538.411542961157;6757.493188010919;6759.628220447923;6764.526483146133;6767.221769496472;6202.2867370253;6266.467350275278;6474.902876456763;6486.9351306488215;6493.545451818327;6226.564140510302;6236.532260080421;6341.985282354801;6418.935810641834;6439.098526206241;6727.697253623501;6738.6967586459705;6743.426808721314;6748.257691654012;6749.257581665915;4319.33050377192;4460.710053846619;4503.392042074278;4504.819807207675;4505;6452.645104519296;6455.3221911700475;6466.482681385572;6472.644004579794;6478.9633658616285;6297.590656607306;6301.117843501822;6307.180066591313;6309.589876658186;6310.11658367838;6172.629642221481;6225.688715564113;6274.215723034621;6279.497640188865;6288.308286088435;6153.784617538365;6159.014557670751;6162.630242185483;6163.106349579376;6169;6255.937440625536;6281.309056003744;6295;6297.1813664223355;6305.90541141874;6038.577299589162;6124.265088189446;6132.78535251264;6136.478399336106;6142.293636231891 16:06:52 INFO - PROCESS | 4543 | |5;yui.html;771.607234065765;861.1173547114205;866.8699695045742;873.1181506678261;874.366084588673;13369.7326053479;13515;13559.728805423885;13563.593092207237;13581.728365432686;13343.466261349535;13375.732485350287;13395.263260520667;13417.383610196015;13442.731145377087;626.6741294526867;791.3352783661709;802.626778547975;802.8233788566536;803.2529747335005;635.0632816595507;786.429838367185;798.4051881348404;802.3701394405393;803.4094940218935;827.7806381308958;835.9205875441824;836.3184005035906;836.4354061008825;837.4933165434916;655.9180102487189;658.042548092529;814.2590242878952;832.7751507093081;832.937529685273;613.0283500651473;615.3200713211882;615.5537235504269;616.5868867858545;616.981490555284;13427.328633568282;13471.191728496224;13488.5279015234;13534.526291579847;13565.660858478519;11942.223755455867;12415;13116.934415327862;13123.737525249442;13190.142640728322;10203.591856325738;10322.432266225354;10455.059044686015;10976.286541374786;11090.168237382228;3545.149164200599;3612.747107702462;3617.457381392786;3710.4805327254207;3714.2571485702697;45538.77230613826;45681;45895.770521147184;46121.308180377324;47272.290915636295;9154.542272886329;9331.720048398423;9606.711798645912;10122.190224782;10335.741606459898;18021.18904649294;18083.186256618483;18137.27450901949;18498.537536561424;18515.72226416605;7034.753783617549;7193.928060719326;7232.0598322217775;7267.527610705287;7271.89092163618;4015.056461731491;4034;4082.0203151243486;4143.502779666459;4161.479815023122;3009.052148573236;3038.8328641924904;3069.0946170879643;3078.98460507696;3079.260977365461;539.5225225675306;543.8749087709849;551.5780427972604;551.7544692611749;557.6096732287415;545.5799034743305;568.4003376437815;667.8096742428481;673.161913417798;686.1800148822109;7664.693412263447;7734.92265077342;7763.611819409007;7824.517617345409;7998.680052797939;645.6932956845461;655.505093654288;678.718331892269;688.6074937285699;738.8928605352194;3812.6377994090517;4007.438958545806;4080.959190408117;4158.958410415918;4368.170047690928;55207.72396137994;56072;56149.71925140348;56208.437915621136;56233.15650265249;45594.316085258746;50374;50803;50987.7450612752;53541.732291339074;41556.376654350206;41741.37387939183;42213;43409.78295108441;44837.327440088426;8153.633086511002;8182.67269309221;8216.63025163881;8284.544350060805;8563.61463734121;32364.35271294514;32626.83686581552;32706.345873082882;33738.83130584331;33830.492542612374;39994.40008399818;40019.599804001584;41823.58176418319;43358.78320608377;43663.12673746571;10570.260081794508;10597.9470102649;10837.241393102408;11123.332600044023;11372.601958931398;11361.147913906656;11551.42242887869;11597.304161750322;11967.281963082243;12062.698432539257;11221.158413118885;11413.144014199135;11636.301821890713;11835.644930652094;11867.406629668309;11646;12121.515139394325;12143.75712485763;12158.817617735918;12422.775587604028;10786.460676966277;11122;11179;11239.60661376848;11400.486978086172;8815.250703690259;8962.327825413251;9152.588133533874;9198.310126740387;9207.907920920972;9208.723738287863;9228.630854765734;9586.424814511152;9669.226461883172;10149.898501014895 16:06:52 INFO - PROCESS | 4543 | __end_tp_report 16:06:52 INFO - PROCESS | 4543 | __start_cc_report 16:06:52 INFO - PROCESS | 4543 | _x_x_mozilla_cycle_collect,951 16:06:52 INFO - PROCESS | 4543 | __end_cc_report 16:06:52 INFO - PROCESS | 4543 | __startTimestamp1452384412833__endTimestamp 16:06:52 INFO - PROCESS | 4543 | 16:06:52 INFO - PROCESS | 4543 | ------- Summary: start ------- 16:06:52 INFO - PROCESS | 4543 | Number of tests: 6 16:06:52 INFO - PROCESS | 4543 | 16:06:52 INFO - PROCESS | 4543 | [#0] dojo.html Cycles:165 Average:5137.14 Median:5361.57 stddev:544.76 (10.2%) stddev-sans-first:521.74 16:06:52 INFO - PROCESS | 4543 | Values: 3070.3 3160.8 3168.5 3189.3 3217.5 4961.2 5089.2 5141.4 5170.8 5209.1 5100.0 5105.9 5182.0 5198.0 5205.6 4497.0 4616.6 4666.0 4737.5 4812.4 4606.6 4707.9 4748.4 4758.1 4861.8 4575.7 4660.7 4664.4 4667.0 4668.1 4743.1 4753.9 4757.2 4760.0 4766.1 4601.4 4673.4 4679.8 4726.5 4730.6 5036.7 5054.7 5057.3 5057.3 5065.8 5553.3 5586.7 5588.3 5597.5 5646.8 5396.0 5456.2 5493.2 5505.3 5583.0 5119.6 5121.2 5125.6 5128.5 5140.6 5578.5 5591.6 5630.9 5632.7 5636.0 5278.7 5385.4 5390.4 5395.4 5422.7 5499.3 5502.4 5506.0 5506.6 5536.6 5461.2 5471.7 5490.2 5491.5 5535.1 5361.6 5426.1 5468.9 5487.3 5494.1 4359.4 4361.5 4365.0 4394.9 4395.8 3871.2 3901.9 3912.2 3927.8 3943.0 5066.5 5168.9 5177.0 5199.3 5241.7 5414.3 5519.3 5646.5 5659.3 5673.7 5183.6 5186.0 5186.2 5190.9 5191.8 4779.6 4780.7 4786.0 4815.8 4825.7 5553.8 5617.5 5656.9 5673.5 5770.9 5514.1 5536.1 5541.2 5544.9 5545.9 5681.2 5740.8 5742.4 5745.3 5750.2 5410.7 5412.1 5500.0 5500.1 5510.4 5496.9 5506.0 5520.6 5528.2 5531.0 5419.6 5425.4 5429.9 5434.2 5489.1 5196.4 5350.6 5364.4 5371.3 5541.6 5365.1 5375.8 5386.1 5387.6 5389.2 5400.1 5448.3 5466.6 5514.8 5537.4 5176.8 5299.3 5310.1 5335.1 5452.6 16:06:52 INFO - PROCESS | 4543 | 16:06:52 INFO - PROCESS | 4543 | [#1] ext.html Cycles:180 Average:45290.35 Median:35415.32 stddev:79672.92 (225.0%) stddev-sans-first:79825.53 16:06:52 INFO - PROCESS | 4543 | Values: 528.5 541.0 561.1 563.0 569.4 27936.3 28415.7 28437.6 28512.4 35520.1 37745.0 37999.0 38083.4 38227.6 38238.6 8311.5 8578.8 8582.8 8585.9 8590.2 7379.0 7584.1 8272.6 9797.5 10036.6 9678.9 9754.2 9755.6 9762.0 9766.8 7395.4 9902.9 10118.8 10130.3 10130.8 7255.6 9772.8 10018.4 10034.0 10040.4 35380.8 35684.3 35789.6 35813.0 35862.3 3316.8 3409.1 3423.8 3531.5 3698.3 28467.7 29579.6 30034.4 30105.1 30219.1 3911.2 3980.6 4040.5 4052.5 4091.0 264171.0 265858.8 266023.0 266065.0 266364.0 51704.7 51914.0 51970.7 51974.7 56781.4 60259.1 60377.4 60385.4 60476.7 60524.7 13069.1 13268.9 13288.5 13290.7 13353.6 10039.3 10053.8 10073.3 10077.5 10078.4 17942.3 18940.7 18994.7 19106.7 19233.2 5911.6 7006.3 7245.5 7265.8 7358.2 914.7 963.0 990.5 1004.3 1079.6 25929.0 25952.4 26005.2 26020.7 26051.5 1009.5 1030.8 1047.6 1072.4 1117.7 12538.6 13020.5 13125.5 13187.6 13228.7 234988.0 236120.0 236132.4 238530.0 238941.0 44199.0 44923.0 44943.0 45080.3 45163.1 1285.4 1299.3 1303.6 1326.3 1359.9 27638.7 28402.0 28942.3 29029.7 29070.6 41378.8 42092.8 45346.8 45589.3 45596.1 379562.0 385756.0 386241.0 387993.0 388503.1 29747.3 30116.0 30131.5 30157.0 30175.7 40777.4 41245.4 41545.0 41981.4 42402.8 35449.8 35789.7 36121.8 36342.3 36412.3 35306.5 35872.6 35973.6 36039.6 36101.6 34669.7 34779.3 35543.0 35683.0 35724.5 27589.2 28858.0 29921.4 31679.7 32726.7 22835.5 23063.8 23245.4 25021.6 25252.0 16:06:52 INFO - PROCESS | 4543 | 16:06:52 INFO - PROCESS | 4543 | [#2] jquery.html Cycles:180 Average:25641.93 Median:476352.00 stddev:80052.61 (16.8%) stddev-sans-first:80254.65 16:06:52 INFO - PROCESS | 4543 | Values: 353.8 358.3 359.2 360.3 361.7 13409.4 13832.6 16148.8 16739.0 17954.8 15677.8 15769.5 16577.2 16605.3 16633.6 4919.3 5359.0 5360.2 5361.3 5362.9 3978.4 4075.2 5217.9 5689.5 5916.4 5645.9 5771.9 5829.1 5895.7 5896.6 5664.1 5688.4 5698.5 5716.9 5718.9 4933.1 5043.9 5054.4 5055.1 5055.5 19565.1 19685.5 19709.3 20009.5 20018.5 2064.2 2136.0 2175.9 2206.7 2266.0 12112.6 12476.2 12619.9 12835.6 12931.3 1768.2 1793.2 1808.4 1816.3 1818.8 53540.7 54615.5 55089.7 55277.0 56058.4 10069.8 10099.6 10139.1 10168.6 10230.0 13107.7 13205.3 13212.2 13236.0 13243.2 6848.9 7016.5 7017.3 7033.2 7094.4 6559.6 6566.4 6567.4 6596.6 6708.6 3986.9 4353.5 4410.7 4412.0 4458.7 1707.3 1768.0 1857.4 1868.0 1877.3 856.1 892.2 952.5 1021.5 1046.2 13381.5 15431.2 15643.5 16137.8 16530.8 888.9 914.0 921.4 1008.7 1017.4 8825.6 9055.5 9065.1 9115.0 9165.6 463568.0 489136.0 498924.0 498954.0 501140.0 46252.1 67069.0 67192.7 67817.0 67938.3 1074.9 1078.1 1083.7 1086.6 1089.6 14312.6 14342.6 14364.4 14688.0 14693.1 31068.5 31398.8 31911.7 31932.7 32009.8 40223.2 40686.2 40983.8 41250.1 41388.6 7035.1 7243.1 7248.2 7258.7 7299.5 7860.3 8943.7 9563.5 10565.3 10820.7 9204.8 9209.1 9225.2 9321.2 9453.9 8945.0 9021.0 9294.9 9398.1 9555.3 8829.3 8857.3 9369.3 9880.3 9964.2 7252.1 7277.0 7299.1 7335.6 7353.4 8483.5 9851.7 9921.3 9963.1 10047.5 16:06:52 INFO - PROCESS | 4543 | 16:06:52 INFO - PROCESS | 4543 | [#3] mootools.html Cycles:180 Average:5442.53 Median:6281.91 stddev:2817.15 (44.8%) stddev-sans-first:2807.55 16:06:52 INFO - PROCESS | 4543 | Values: 1265.2 1427.9 1437.8 1607.9 1840.1 6958.1 7608.7 7757.5 7767.3 7777.6 7448.6 7635.0 7646.3 7647.4 7647.5 2303.6 2325.5 2330.9 2332.1 2333.1 2235.2 2260.4 2264.4 2283.2 2302.4 2314.6 2334.9 2345.3 2347.1 2361.8 2335.8 2347.9 2356.2 2362.1 2363.3 2339.8 2343.2 2344.6 2344.8 2345.1 7714.0 7750.3 7765.2 7767.3 7775.5 4807.1 4881.9 4886.4 4920.9 4923.5 3124.9 3156.1 3218.6 3224.1 3238.4 1030.7 1044.4 1045.8 1056.7 1094.1 10933.6 10948.8 10958.7 10970.7 10972.7 4296.2 4384.7 4424.7 4437.4 4450.1 9312.6 9338.3 9340.8 9341.4 9347.9 4762.0 4837.6 4842.0 4872.2 4874.4 4001.9 4108.4 4111.8 4313.7 4457.0 3729.8 3739.2 3753.0 3773.2 3782.6 1224.7 1261.4 1269.1 1269.3 1277.3 1227.0 1260.6 1264.4 1353.5 1354.6 7143.6 7160.1 7196.1 7272.7 7349.6 468.2 473.8 476.0 478.8 483.2 5707.4 5808.9 5810.3 5811.4 5813.2 8946.6 9271.7 9444.6 9548.0 9565.4 9425.7 9485.3 9506.7 9507.7 9515.0 7078.7 7237.0 7244.2 7253.2 7313.9 7137.2 7225.2 7237.2 7248.2 7255.0 7889.6 8061.2 8264.8 8279.2 8291.3 8183.1 8218.4 8234.3 8349.8 8357.4 6205.1 6358.7 6420.6 6588.3 6779.3 7302.1 7326.8 7354.2 7363.4 7391.9 6723.6 6837.8 6864.2 6993.3 6999.5 6745.5 6773.6 6873.9 6881.2 6891.7 6737.6 6870.0 6902.9 6915.9 6927.6 6447.2 6506.3 6506.4 6507.6 6520.7 6533.6 6552.8 6582.9 6601.3 6603.9 16:06:52 INFO - PROCESS | 4543 | 16:06:52 INFO - PROCESS | 4543 | [#4] prototype.html Cycles:180 Average:5217.76 Median:5879.43 stddev:1578.44 (26.8%) stddev-sans-first:1539.76 16:06:52 INFO - PROCESS | 4543 | Values: 336.6 340.8 341.7 343.2 352.0 5708.6 5776.9 5892.8 5964.8 5977.6 5698.8 5826.9 5849.3 5866.0 5870.4 4513.1 4534.0 4537.8 4539.3 4540.8 4366.8 4435.6 4444.9 4452.9 4534.7 4455.5 4465.8 4480.4 4483.3 4486.6 4447.4 4454.2 4459.1 4465.4 4473.5 3936.8 3939.6 3940.6 3959.0 3980.6 5492.1 5600.2 5601.9 5650.2 5665.0 5227.6 5234.6 5237.0 5237.5 5245.9 5914.9 6253.3 6256.1 6259.9 6262.8 4204.1 4220.0 4265.5 4268.4 4280.6 6370.3 6459.3 6468.0 6482.0 6486.9 4388.2 4390.3 4392.8 4394.7 4399.9 4302.2 4444.4 4449.4 4451.4 4452.4 5171.8 5172.1 5173.9 5179.1 5181.1 6258.8 6262.5 6266.9 6276.8 6281.3 1561.2 1572.7 1573.4 1574.7 1575.1 1054.0 1060.8 1063.3 1081.4 1096.3 5858.9 5865.6 5870.2 5878.5 5880.3 6370.9 6387.6 6430.4 6440.9 6451.1 5935.3 5943.3 5945.7 5946.4 5952.5 3371.3 3462.5 3465.3 3496.7 3507.9 6749.5 6755.0 6760.1 6765.4 6773.2 6638.9 6643.2 6643.7 6648.0 6653.3 6538.4 6757.5 6759.6 6764.5 6767.2 6202.3 6266.5 6474.9 6486.9 6493.5 6226.6 6236.5 6342.0 6418.9 6439.1 6727.7 6738.7 6743.4 6748.3 6749.3 4319.3 4460.7 4503.4 4504.8 4505.0 6452.6 6455.3 6466.5 6472.6 6479.0 6297.6 6301.1 6307.2 6309.6 6310.1 6172.6 6225.7 6274.2 6279.5 6288.3 6153.8 6159.0 6162.6 6163.1 6169.0 6255.9 6281.3 6295.0 6297.2 6305.9 6038.6 6124.3 6132.8 6136.5 6142.3 16:06:52 INFO - PROCESS | 4543 | 16:06:52 INFO - PROCESS | 4543 | [#5] yui.html Cycles:180 Average:13397.99 Median:24015.65 stddev:15248.28 (63.5%) stddev-sans-first:15261.58 16:06:52 INFO - PROCESS | 4543 | Values: 771.6 861.1 866.9 873.1 874.4 13369.7 13515.0 13559.7 13563.6 13581.7 13343.5 13375.7 13395.3 13417.4 13442.7 626.7 791.3 802.6 802.8 803.3 635.1 786.4 798.4 802.4 803.4 827.8 835.9 836.3 836.4 837.5 655.9 658.0 814.3 832.8 832.9 613.0 615.3 615.6 616.6 617.0 13427.3 13471.2 13488.5 13534.5 13565.7 11942.2 12415.0 13116.9 13123.7 13190.1 10203.6 10322.4 10455.1 10976.3 11090.2 3545.1 3612.7 3617.5 3710.5 3714.3 45538.8 45681.0 45895.8 46121.3 47272.3 9154.5 9331.7 9606.7 10122.2 10335.7 18021.2 18083.2 18137.3 18498.5 18515.7 7034.8 7193.9 7232.1 7267.5 7271.9 4015.1 4034.0 4082.0 4143.5 4161.5 3009.1 3038.8 3069.1 3079.0 3079.3 539.5 543.9 551.6 551.8 557.6 545.6 568.4 667.8 673.2 686.2 7664.7 7734.9 7763.6 7824.5 7998.7 645.7 655.5 678.7 688.6 738.9 3812.6 4007.4 4081.0 4159.0 4368.2 55207.7 56072.0 56149.7 56208.4 56233.2 45594.3 50374.0 50803.0 50987.7 53541.7 41556.4 41741.4 42213.0 43409.8 44837.3 8153.6 8182.7 8216.6 8284.5 8563.6 32364.4 32626.8 32706.3 33738.8 33830.5 39994.4 40019.6 41823.6 43358.8 43663.1 10570.3 10597.9 10837.2 11123.3 11372.6 11361.1 11551.4 11597.3 11967.3 12062.7 11221.2 11413.1 11636.3 11835.6 11867.4 11646.0 12121.5 12143.8 12158.8 12422.8 10786.5 11122.0 11179.0 11239.6 11400.5 8815.3 8962.3 9152.6 9198.3 9207.9 9208.7 9228.6 9586.4 9669.2 10149.9 16:06:52 INFO - PROCESS | 4543 | -------- Summary: end -------- 16:06:52 INFO - PROCESS | 4543 | 16:06:53 INFO - TEST-INFO | 4543: exit 0 16:06:53 INFO - TEST-OK | dromaeo_css | took 1112897ms 16:06:53 INFO - TEST-START | kraken 16:06:53 INFO - operating with platform_type : linux_ 16:06:53 INFO - Initialising browser for kraken test... 16:06:53 INFO - TEST-INFO | started process 4734 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpiqtLXK/profile http://localhost:33424/getInfo.html) 16:06:57 INFO - PROCESS | 4734 | __metrics Screen width/height:1600/1200 16:06:57 INFO - PROCESS | 4734 | colorDepth:24 16:06:57 INFO - PROCESS | 4734 | Browser inner width/height: 1024/697 16:06:57 INFO - PROCESS | 4734 | __metrics 16:06:57 INFO - PROCESS | 4734 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 16:06:57 INFO - PROCESS | 4734 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:06:58 INFO - TEST-INFO | 4734: exit 0 16:06:58 INFO - Browser initialized. 16:06:58 INFO - Running cycle 1/1 for kraken test... 16:06:58 INFO - TEST-INFO | started process 4816 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpiqtLXK/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:07:10 INFO - PROCESS | 4816 | RSS: Main: 148865024 16:07:10 INFO - PROCESS | 4816 | 16:07:12 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33424/tests/kraken/driver.html?testName=audio-beat-detection) 16:07:13 INFO - PROCESS | 4816 | RSS: Main: 186515456 16:07:13 INFO - PROCESS | 4816 | 16:07:16 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33424/tests/kraken/driver.html?testName=audio-dft) 16:07:16 INFO - PROCESS | 4816 | RSS: Main: 175722496 16:07:16 INFO - PROCESS | 4816 | 16:07:19 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33424/tests/kraken/driver.html?testName=audio-fft) 16:07:20 INFO - PROCESS | 4816 | RSS: Main: 160464896 16:07:20 INFO - PROCESS | 4816 | 16:07:22 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33424/tests/kraken/driver.html?testName=audio-oscillator) 16:07:22 INFO - PROCESS | 4816 | RSS: Main: 162201600 16:07:22 INFO - PROCESS | 4816 | 16:07:24 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33424/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:07:25 INFO - PROCESS | 4816 | RSS: Main: 164683776 16:07:25 INFO - PROCESS | 4816 | 16:07:28 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33424/tests/kraken/driver.html?testName=imaging-darkroom) 16:07:28 INFO - PROCESS | 4816 | RSS: Main: 166203392 16:07:28 INFO - PROCESS | 4816 | 16:07:32 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33424/tests/kraken/driver.html?testName=imaging-desaturate) 16:07:32 INFO - PROCESS | 4816 | RSS: Main: 165134336 16:07:32 INFO - PROCESS | 4816 | 16:07:35 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33424/tests/kraken/driver.html?testName=json-parse-financial) 16:07:36 INFO - PROCESS | 4816 | RSS: Main: 164900864 16:07:36 INFO - PROCESS | 4816 | 16:07:37 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33424/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:07:38 INFO - PROCESS | 4816 | RSS: Main: 175853568 16:07:38 INFO - PROCESS | 4816 | 16:07:40 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33424/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:07:40 INFO - PROCESS | 4816 | RSS: Main: 174243840 16:07:40 INFO - PROCESS | 4816 | 16:07:42 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33424/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:07:43 INFO - PROCESS | 4816 | RSS: Main: 186454016 16:07:43 INFO - PROCESS | 4816 | 16:07:45 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33424/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:07:46 INFO - PROCESS | 4816 | RSS: Main: 190607360 16:07:46 INFO - PROCESS | 4816 | 16:07:48 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33424/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:07:49 INFO - PROCESS | 4816 | RSS: Main: 191188992 16:07:49 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | Cycle 1(1): loaded http://localhost:33424/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33424/tests/kraken/driver.html?testName=ai-astar) 16:07:51 INFO - PROCESS | 4816 | RSS: Main: 248283136 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | __start_tp_report 16:07:51 INFO - PROCESS | 4816 | _x_x_mozilla_page_load 16:07:51 INFO - PROCESS | 4816 | _x_x_mozilla_page_load_details 16:07:51 INFO - PROCESS | 4816 | |i|pagename|runs| 16:07:51 INFO - PROCESS | 4816 | |0;ai-astar;109;111;106;111;112;113;101;110;110;109 16:07:51 INFO - PROCESS | 4816 | |1;audio-beat-detection;155;125;152;167;127;153;147;126;164;128 16:07:51 INFO - PROCESS | 4816 | |2;audio-dft;159;160;162;161;161;161;161;162;162;162 16:07:51 INFO - PROCESS | 4816 | |3;audio-fft;72;75;85;93;74;73;73;113;92;71 16:07:51 INFO - PROCESS | 4816 | |4;audio-oscillator;95;95;143;95;96;96;142;95;96;137 16:07:51 INFO - PROCESS | 4816 | |5;imaging-gaussian-blur;110;109;109;110;108;109;110;109;110;109 16:07:51 INFO - PROCESS | 4816 | |6;imaging-darkroom;147;146;147;145;145;145;146;145;146;146 16:07:51 INFO - PROCESS | 4816 | |7;imaging-desaturate;96;96;96;96;96;95;96;96;96;96 16:07:51 INFO - PROCESS | 4816 | |8;json-parse-financial;56;55;57;56;55;55;55;65;54;54 16:07:51 INFO - PROCESS | 4816 | |9;json-stringify-tinderbox;68;67;68;68;67;67;67;67;67;68 16:07:51 INFO - PROCESS | 4816 | |10;stanford-crypto-aes;84;93;87;93;85;85;84;85;86;86 16:07:51 INFO - PROCESS | 4816 | |11;stanford-crypto-ccm;124;118;113;113;115;123;117;117;120;120 16:07:51 INFO - PROCESS | 4816 | |12;stanford-crypto-pbkdf2;157;151;152;155;154;150;151;152;150;140 16:07:51 INFO - PROCESS | 4816 | |13;stanford-crypto-sha256-iterative;66;67;67;66;68;70;67;67;72;68 16:07:51 INFO - PROCESS | 4816 | __end_tp_report 16:07:51 INFO - PROCESS | 4816 | __start_cc_report 16:07:51 INFO - PROCESS | 4816 | _x_x_mozilla_cycle_collect,964 16:07:51 INFO - PROCESS | 4816 | __end_cc_report 16:07:51 INFO - PROCESS | 4816 | __startTimestamp1452384471506__endTimestamp 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | ------- Summary: start ------- 16:07:51 INFO - PROCESS | 4816 | Number of tests: 14 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#0] ai-astar Cycles:10 Average:109.20 Median:110.50 stddev:3.46 (3.1%) stddev-sans-first:3.67 16:07:51 INFO - PROCESS | 4816 | Values: 109.0 111.0 106.0 111.0 112.0 113.0 101.0 110.0 110.0 109.0 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#1] audio-beat-detection Cycles:10 Average:144.40 Median:152.50 stddev:16.44 (10.8%) stddev-sans-first:16.98 16:07:51 INFO - PROCESS | 4816 | Values: 155.0 125.0 152.0 167.0 127.0 153.0 147.0 126.0 164.0 128.0 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:0.99 (0.6%) stddev-sans-first:0.71 16:07:51 INFO - PROCESS | 4816 | Values: 159.0 160.0 162.0 161.0 161.0 161.0 161.0 162.0 162.0 162.0 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#3] audio-fft Cycles:10 Average:82.10 Median:74.50 stddev:13.69 (18.4%) stddev-sans-first:14.02 16:07:51 INFO - PROCESS | 4816 | Values: 72.0 75.0 85.0 93.0 74.0 73.0 73.0 113.0 92.0 71.0 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#4] audio-oscillator Cycles:10 Average:109.00 Median:95.00 stddev:21.91 (23.1%) stddev-sans-first:22.64 16:07:51 INFO - PROCESS | 4816 | Values: 95.0 95.0 143.0 95.0 96.0 96.0 142.0 95.0 96.0 137.0 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.50 stddev:0.67 (0.6%) stddev-sans-first:0.67 16:07:51 INFO - PROCESS | 4816 | Values: 110.0 109.0 109.0 110.0 108.0 109.0 110.0 109.0 110.0 109.0 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.79 (0.5%) stddev-sans-first:0.71 16:07:51 INFO - PROCESS | 4816 | Values: 147.0 146.0 147.0 145.0 145.0 145.0 146.0 145.0 146.0 146.0 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:07:51 INFO - PROCESS | 4816 | Values: 96.0 96.0 96.0 96.0 96.0 95.0 96.0 96.0 96.0 96.0 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#8] json-parse-financial Cycles:10 Average:56.20 Median:55.50 stddev:3.22 (5.8%) stddev-sans-first:3.42 16:07:51 INFO - PROCESS | 4816 | Values: 56.0 55.0 57.0 56.0 55.0 55.0 55.0 65.0 54.0 54.0 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:67.50 stddev:0.52 (0.8%) stddev-sans-first:0.50 16:07:51 INFO - PROCESS | 4816 | Values: 68.0 67.0 68.0 68.0 67.0 67.0 67.0 67.0 67.0 68.0 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:86.00 stddev:3.39 (3.9%) stddev-sans-first:3.44 16:07:51 INFO - PROCESS | 4816 | Values: 84.0 93.0 87.0 93.0 85.0 85.0 84.0 85.0 86.0 86.0 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#11] stanford-crypto-ccm Cycles:10 Average:118.00 Median:119.00 stddev:3.80 (3.2%) stddev-sans-first:3.35 16:07:51 INFO - PROCESS | 4816 | Values: 124.0 118.0 113.0 113.0 115.0 123.0 117.0 117.0 120.0 120.0 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.20 Median:152.00 stddev:4.54 (3.0%) stddev-sans-first:4.30 16:07:51 INFO - PROCESS | 4816 | Values: 157.0 151.0 152.0 155.0 154.0 150.0 151.0 152.0 150.0 140.0 16:07:51 INFO - PROCESS | 4816 | 16:07:51 INFO - PROCESS | 4816 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.50 stddev:1.87 (2.8%) stddev-sans-first:1.87 16:07:51 INFO - PROCESS | 4816 | Values: 66.0 67.0 67.0 66.0 68.0 70.0 67.0 67.0 72.0 68.0 16:07:51 INFO - PROCESS | 4816 | -------- Summary: end -------- 16:07:51 INFO - PROCESS | 4816 | 16:07:52 INFO - TEST-INFO | 4816: exit 0 16:07:52 INFO - TEST-OK | kraken | took 58818ms 16:07:52 INFO - TEST-START | v8_7 16:07:52 INFO - operating with platform_type : linux_ 16:07:52 INFO - Initialising browser for v8_7 test... 16:07:52 INFO - TEST-INFO | started process 5064 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_rGs2v/profile http://localhost:33424/getInfo.html) 16:07:55 INFO - PROCESS | 5064 | __metrics Screen width/height:1600/1200 16:07:55 INFO - PROCESS | 5064 | colorDepth:24 16:07:55 INFO - PROCESS | 5064 | Browser inner width/height: 1024/697 16:07:55 INFO - PROCESS | 5064 | __metrics 16:07:56 INFO - PROCESS | 5064 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 16:07:56 INFO - PROCESS | 5064 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:07:57 INFO - TEST-INFO | 5064: exit 0 16:07:57 INFO - Browser initialized. 16:07:57 INFO - Running cycle 1/1 for v8_7 test... 16:07:57 INFO - TEST-INFO | started process 5147 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp_rGs2v/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:08:09 INFO - PROCESS | 5147 | RSS: Main: 148615168 16:08:09 INFO - PROCESS | 5147 | 16:08:31 INFO - PROCESS | 5147 | Cycle 1(1): loaded http://localhost:33424/tests/v8_7/run.html (next: http://localhost:33424/tests/v8_7/run.html) 16:08:31 INFO - PROCESS | 5147 | RSS: Main: 564015104 16:08:31 INFO - PROCESS | 5147 | 16:08:31 INFO - PROCESS | 5147 | __start_tp_report 16:08:31 INFO - PROCESS | 5147 | _x_x_mozilla_page_load 16:08:31 INFO - PROCESS | 5147 | _x_x_mozilla_page_load_details 16:08:31 INFO - PROCESS | 5147 | |i|pagename|runs| 16:08:31 INFO - PROCESS | 5147 | |0;Richards;170.76502732240436 16:08:31 INFO - PROCESS | 5147 | |1;DeltaBlue;190.94901661256444 16:08:31 INFO - PROCESS | 5147 | |2;Encrypt;281.29395218002816 16:08:31 INFO - PROCESS | 5147 | |3;Decrypt;5246.073298429319 16:08:31 INFO - PROCESS | 5147 | |4;RayTrace;2032.520325203252 16:08:31 INFO - PROCESS | 5147 | |5;Earley;796.8127490039841 16:08:31 INFO - PROCESS | 5147 | |6;Boyer;12407.407407407407 16:08:31 INFO - PROCESS | 5147 | |7;RegExp;42291.666666666664 16:08:31 INFO - PROCESS | 5147 | |8;Splay;572.3234624145786 16:08:31 INFO - PROCESS | 5147 | |9;NavierStokes;4835.748792270531 16:08:31 INFO - PROCESS | 5147 | __end_tp_report 16:08:31 INFO - PROCESS | 5147 | __startTimestamp1452384511643__endTimestamp 16:08:31 INFO - PROCESS | 5147 | 16:08:31 INFO - PROCESS | 5147 | ------- Summary: start ------- 16:08:31 INFO - PROCESS | 5147 | Number of tests: 10 16:08:31 INFO - PROCESS | 5147 | 16:08:31 INFO - PROCESS | 5147 | [#0] Richards Cycles:1 Average:170.77 Median:170.77 stddev:0.00 (0.0%) 16:08:31 INFO - PROCESS | 5147 | Values: 170.8 16:08:31 INFO - PROCESS | 5147 | 16:08:31 INFO - PROCESS | 5147 | [#1] DeltaBlue Cycles:1 Average:190.95 Median:190.95 stddev:0.00 (0.0%) 16:08:31 INFO - PROCESS | 5147 | Values: 190.9 16:08:31 INFO - PROCESS | 5147 | 16:08:31 INFO - PROCESS | 5147 | [#2] Encrypt Cycles:1 Average:281.29 Median:281.29 stddev:0.00 (0.0%) 16:08:31 INFO - PROCESS | 5147 | Values: 281.3 16:08:31 INFO - PROCESS | 5147 | 16:08:31 INFO - PROCESS | 5147 | [#3] Decrypt Cycles:1 Average:5246.07 Median:5246.07 stddev:0.00 (0.0%) 16:08:31 INFO - PROCESS | 5147 | Values: 5246.1 16:08:31 INFO - PROCESS | 5147 | 16:08:31 INFO - PROCESS | 5147 | [#4] RayTrace Cycles:1 Average:2032.52 Median:2032.52 stddev:0.00 (0.0%) 16:08:31 INFO - PROCESS | 5147 | Values: 2032.5 16:08:31 INFO - PROCESS | 5147 | 16:08:31 INFO - PROCESS | 5147 | [#5] Earley Cycles:1 Average:796.81 Median:796.81 stddev:0.00 (0.0%) 16:08:31 INFO - PROCESS | 5147 | Values: 796.8 16:08:31 INFO - PROCESS | 5147 | 16:08:31 INFO - PROCESS | 5147 | [#6] Boyer Cycles:1 Average:12407.41 Median:12407.41 stddev:0.00 (0.0%) 16:08:31 INFO - PROCESS | 5147 | Values: 12407.4 16:08:31 INFO - PROCESS | 5147 | 16:08:31 INFO - PROCESS | 5147 | [#7] RegExp Cycles:1 Average:42291.67 Median:42291.67 stddev:0.00 (0.0%) 16:08:31 INFO - PROCESS | 5147 | Values: 42291.7 16:08:31 INFO - PROCESS | 5147 | 16:08:31 INFO - PROCESS | 5147 | [#8] Splay Cycles:1 Average:572.32 Median:572.32 stddev:0.00 (0.0%) 16:08:31 INFO - PROCESS | 5147 | Values: 572.3 16:08:31 INFO - PROCESS | 5147 | 16:08:31 INFO - PROCESS | 5147 | [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 16:08:31 INFO - PROCESS | 5147 | Values: 4835.7 16:08:31 INFO - PROCESS | 5147 | -------- Summary: end -------- 16:08:31 INFO - PROCESS | 5147 | 16:08:32 INFO - TEST-INFO | 5147: exit 0 16:08:32 INFO - TEST-OK | v8_7 | took 40118ms 16:08:32 INFO - SUITE-END | took 1211s 16:08:32 INFO - Completed test suite (00:20:11) 16:08:32 INFO - Working with test: dromaeo_css 16:08:32 INFO - Generating results file: dromaeo_css 16:08:32 INFO - Working with test: kraken 16:08:32 INFO - Generating results file: kraken 16:08:32 INFO - javascript benchmark 16:08:32 INFO - Working with test: v8_7 16:08:32 INFO - Generating results file: v8_7 16:08:32 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:08:32 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 16:08:32 INFO - process_Request line: dromaeo_css 6733.05 graph.html#tests=[[72,1,35]] 16:08:32 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:08:32 INFO - process_Request line: kraken 1504.20 graph.html#tests=[[232,1,35]] 16:08:32 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:08:32 INFO - process_Request line: v8_7 18822.24 graph.html#tests=[[230,1,35]] 16:08:32 INFO - RETURN: dromaeo_css: 6733.05 16:08:32 INFO - RETURN: kraken: 1504.20 16:08:32 INFO - RETURN: v8_7: 18822.24 16:08:32 INFO - javascript benchmark 16:08:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3070.2631368471566, 3160.778745487814, 3168.5088811234236, 3189.314297426053, 3217.5173723941407, 4961.20620700688, 5089.185730283156, 5141.357330333703, 5170.767315470804, 5209.062368773619, 5099.954509325588, 5105.897882042357, 5182.0, 5198.0, 5205.583553315735, 4496.9550304497025, 4616.584507394334, 4665.976670116653, 4737.4551926528375, 4812.398450193726, 4606.585407313341, 4707.858764237061, 4748.430188377382, 4758.072175925696, 4861.805527778903, 4575.702579332333, 4660.743659098782, 4664.416947881515, 4667.0, 4668.089722504079, 4743.09881122589, 4753.857384278477, 4757.167495688275, 4760.024195040043, 4766.142094423002, 4601.401817763669, 4673.439187297512, 4679.789409476582, 4726.480087190406, 4730.62155027597, 5036.6726162799305, 5054.69671819692, 5057.342545469065, 5057.342545469102, 5065.797368105308, 5553.278073850414, 5586.664800112007, 5588.273524441837, 5597.524210442118, 5646.774129034834, 5395.973020134874, 5456.208849716809, 5493.176023596453, 5505.339359276913, 5583.022970980062, 5119.641625086283, 5121.155009423441, 5125.6155788315655, 5128.512791284859, 5140.563052140573, 5578.470045345685, 5591.580631452576, 5630.915536266959, 5632.690202038845, 5635.971820140873, 5278.656887302313, 5385.380681221632, 5390.407055223922, 5395.406505284415, 5422.701751403711, 5499.340079190522, 5502.367227768857, 5506.0, 5506.587005974568, 5536.640118392292, 5461.208124821958, 5471.699056551848, 5490.203920431595, 5491.533219676292, 5535.114381698909, 5361.571074314045, 5426.131818908955, 5468.91796623051, 5487.341519017744, 5494.09347457665, 4359.411479450234, 4361.454818147731, 4365.0, 4394.868153955386, 4395.758233297135, 3871.2064026874423, 3901.921961560753, 3912.217556488714, 3927.842886284574, 3942.980285098556, 5066.493350664978, 5168.9483105168465, 5176.9741151294, 5199.272101905736, 5241.685498870003, 5414.3232095988005, 5519.282493275929, 5646.491815736604, 5659.349174844946, 5673.659580425187, 5183.585313174937, 5186.040582492251, 5186.222066689951, 5190.92213616796, 5191.766370513336, 4779.641526885497, 4780.737059461693, 4786.0, 4815.807367705253, 4825.686330388514, 5553.833384998376, 5617.466340697709, 5656.8868622627315, 5673.517750991129, 5770.88458230833, 5514.09017512106, 5536.141898005815, 5541.196526503715, 5544.944550554443, 5545.861353466195, 5681.17622944679, 5740.827775166752, 5742.397048309867, 5745.339285981999, 5750.194972703908, 5410.675359478444, 5412.134058550613, 5500.037493438552, 5500.119980803052, 5510.393856675682, 5496.862578435571, 5506.0, 5520.641158324856, 5528.170774383874, 5531.0, 5419.647722897999, 5425.403205647454, 5429.891402172014, 5434.2120392542065, 5489.149181876815, 5196.350456192976, 5350.571954243574, 5364.4099149094345, 5371.301730774974, 5541.584381171348, 5365.141577347606, 5375.758090885842, 5386.057439947915, 5387.595930305164, 5389.191621256686, 5400.054990376747, 5448.318960129984, 5466.590005749665, 5514.834554963358, 5537.36320323168, 5176.844694659167, 5299.284596579412, 5310.097283461743, 5335.146376579729, 5452.563794896319], "unit": "score", "name": "dojo.html", "value": 5103.759936560084}, {"lowerIsBetter": false, "replicates": [528.5216878724756, 540.9999999999999, 561.0518224201097, 563.0231548263762, 569.4419468920457, 27936.301592460197, 28415.715842841564, 28437.57343639847, 28512.429751404958, 35520.11199720008, 37745.0, 37999.0, 38083.4287485688, 38227.617723822754, 38238.61761382385, 8311.459755115928, 8578.78553036176, 8582.828343433128, 8585.871211931826, 8590.1839325264, 7379.0, 7584.089909210902, 8272.627731752056, 9797.461139637353, 10036.648717294896, 9678.903210967906, 9754.219662427024, 9755.560999754993, 9762.0, 9766.755831104208, 7395.371393431565, 9902.900970990306, 10118.84821727671, 10130.290879638429, 10130.848037279373, 7255.637218139072, 9772.804543909153, 10018.448985305806, 10033.99660033998, 10040.397576145382, 35380.82309588461, 35684.286314273566, 35789.64210357889, 35813.0, 35862.282754345026, 3316.8341582920757, 3409.1136304560746, 3423.7945723256435, 3531.4861959999675, 3698.3158115748674, 28467.715322846714, 29579.556306655417, 30034.399312013855, 30105.09684709462, 30219.09342719722, 3911.198204368098, 3980.5820388859333, 4040.4949381327333, 4052.49343832021, 4090.979545102285, 264171.0, 265858.75553689076, 266023.0, 266065.0, 266364.0, 51704.74147629238, 51914.0, 51970.74014629978, 51974.740126299126, 56781.43218567844, 60259.096113558335, 60377.39622603806, 60385.39614603798, 60476.697616511636, 60524.697376512835, 13069.085164038615, 13268.93365533166, 13288.534901278408, 13290.667733306746, 13353.599392018255, 10039.347442416365, 10053.798924021625, 10073.345232559861, 10077.54651040705, 10078.445685487222, 17942.282308707767, 18940.71588926167, 18994.71507927382, 19106.71339929902, 19233.230670773013, 5911.640322725797, 7006.334398232159, 7245.492815502864, 7265.818354541073, 7358.1538123116525, 914.6570036236411, 962.9807403851884, 990.4898977026837, 1004.3070281505737, 1079.5843600213818, 25929.0, 25952.351191219994, 26005.21984340435, 26020.73979260221, 26051.478970420485, 1009.5059312217959, 1030.8247597908373, 1047.6490375724065, 1072.3780207479642, 1117.675873996532, 12538.623841284776, 13020.544280950311, 13125.54060607874, 13187.60437186886, 13228.735425291441, 234988.0, 236120.0, 236132.3867613225, 238530.0, 238941.0, 44199.0, 44923.0, 44943.0, 45080.3237951431, 45163.096738065055, 1285.4087119924939, 1299.27240745183, 1303.6284658872175, 1326.2970625568464, 1359.8504164541891, 27638.723612763613, 28402.0, 28942.276443089093, 29029.709702903547, 29070.563941541317, 41378.793106034274, 42092.78953605212, 45346.77326613346, 45589.31616025696, 45596.088078238914, 379562.0, 385756.0, 386241.0, 387993.0, 388503.11496884667, 29747.256318592208, 30116.0, 30131.54802677918, 30157.0, 30175.69824301729, 40777.388339174344, 41245.38131928084, 41545.0, 41981.37027944522, 42402.78798605987, 35449.82275088608, 35789.747358842316, 36121.81939090288, 36342.27315453729, 36412.27175456529, 35306.47040294449, 35872.64127358693, 35973.64026359703, 36039.63960360363, 36101.63898361088, 34669.653303467654, 34779.30441391107, 35543.0, 35683.0, 35724.46413303854, 27589.172324829484, 28858.0, 29921.401571968872, 31679.71455765964, 32726.672733273313, 22835.543289134457, 23063.76936230616, 23245.418864527845, 25021.624675630243, 25252.0], "unit": "score", "name": "ext.html", "value": 17716.982328513266}, {"lowerIsBetter": false, "replicates": [353.7629788042012, 358.33707640864407, 359.1559834389187, 360.2506785885358, 361.6979821848756, 13409.396577154028, 13832.585022449317, 16148.838511614895, 16739.0, 17954.820451795462, 15677.764833527477, 15769.526914192593, 16577.17114144294, 16605.33578656852, 16633.584160395996, 4919.335889654895, 5358.973205133969, 5360.222767698681, 5361.329833770778, 5362.91955620666, 3978.38335058065, 4075.246079475292, 5217.869553261161, 5689.544836413077, 5916.3787802280785, 5645.943540564604, 5771.884562308751, 5829.0964900440285, 5895.734691938873, 5896.557758168155, 5664.1220610805185, 5688.374278829326, 5698.544116470673, 5716.942830571704, 5718.857028574277, 4933.1120398328285, 5043.873903152414, 5054.444011158769, 5055.064813009605, 5055.51972562606, 19565.1195696194, 19685.507862303413, 19709.31017414398, 20009.499762505908, 20018.499537511532, 2064.1640135745047, 2135.9679604805783, 2175.9020844062056, 2206.6910632511463, 2265.9773402265932, 12112.576059837951, 12476.18904771187, 12619.898532976818, 12835.614931552069, 12931.288779117145, 1768.1689605885213, 1793.1930631215876, 1808.3580328983285, 1816.3461153984556, 1818.770065453819, 53540.73229633866, 54615.45384546144, 55089.72455137699, 55276.9999999996, 56058.43941560573, 10069.841968173607, 10099.596016159418, 10139.087482126644, 10168.593256269667, 10230.0, 13107.672308192181, 13205.273709945854, 13212.207267563977, 13236.0, 13243.20540767557, 6848.897266541006, 7016.473764467685, 7017.298270173044, 7033.156021277479, 7094.432445404458, 6559.639219842859, 6566.4418524425955, 6567.376099270562, 6596.604203747695, 6708.563943343667, 3986.940195897064, 4353.477582690098, 4410.669199810027, 4411.955880441219, 4458.665600080007, 1707.3256063854956, 1767.9734803977951, 1857.4056301983505, 1867.9626407471774, 1877.2678655324435, 856.0839901305668, 892.2148509311894, 952.4523399045577, 1021.5147804792664, 1046.215338496128, 13381.464741410235, 15431.228438578026, 15643.530694079196, 16137.757933631005, 16530.83469165293, 888.9244414224863, 914.0356923445828, 921.3964853021282, 1008.6772232885406, 1017.4200705597887, 8825.602847871862, 9055.547222638841, 9065.093490650883, 9114.954425227832, 9165.58754856033, 463568.0, 489136.0, 498924.0, 498954.0, 501140.0, 46252.07495850064, 67069.0, 67192.6640366795, 67817.0, 67938.32061679418, 1074.9247552671395, 1078.1105587890022, 1083.6532309660834, 1086.5708045322212, 1089.5859573362072, 14312.642183945069, 14342.569722908329, 14364.353604087632, 14688.0, 14693.118412895261, 31068.53397199089, 31398.843005784824, 31911.68088319087, 31932.680673192972, 32009.8399508001, 40223.1955360897, 40686.186276273715, 40983.79508102559, 41250.143743531014, 41388.586114138474, 7035.12060992376, 7243.0946131733535, 7248.238934911758, 7258.673359698932, 7299.452541059336, 7860.253275938777, 8943.731688049369, 9563.474008929297, 10565.260431769702, 10820.67537973862, 9204.769880753034, 9209.125133112344, 9225.21585665226, 9321.207697345802, 9453.858192127262, 8945.0, 9021.0, 9294.907050929403, 9398.107179817905, 9555.331126821056, 8829.293656507592, 8857.291416686778, 9369.344145909721, 9880.258980576342, 9964.202863770735, 7252.129744430702, 7277.0, 7299.087614048244, 7335.596542190231, 7353.448491363062, 8483.490990540588, 9851.704448866545, 9921.305508614329, 9963.053509916546, 10047.547860346156], "unit": "score", "name": "jquery.html", "value": 7999.280339138975}, {"lowerIsBetter": false, "replicates": [1265.177634537551, 1427.8786303164231, 1437.7771445425956, 1607.9035257884527, 1840.0523730278908, 6958.095447591812, 7608.695652173913, 7757.456978011542, 7767.33977611903, 7777.611119444034, 7448.627568621574, 7635.045619297588, 7646.273604007624, 7647.426443016768, 7647.541147531152, 2303.608386574278, 2325.546518428907, 2330.860148391102, 2332.0904847109555, 2333.0784340185614, 2235.228846048111, 2260.400993736661, 2264.3546589222074, 2283.212291759349, 2302.3668491164894, 2314.6065168921323, 2334.9182778602753, 2345.343303874909, 2347.1432926981615, 2361.8464799788044, 2335.7897789198882, 2347.931691080554, 2356.234223877238, 2362.1024010875844, 2363.326451961191, 2339.801116905065, 2343.168175297779, 2344.6365813298967, 2344.777246161629, 2345.1088586337255, 7713.96143019287, 7750.302472777421, 7765.184655611192, 7767.262110099531, 7775.533467991882, 4807.134715751164, 4881.925976285212, 4886.364772579542, 4920.926186107211, 4923.483034281385, 3124.875004999797, 3156.1478400831647, 3218.6298575663864, 3224.1294850390263, 3238.4170849247125, 1030.7114008077751, 1044.4151275285788, 1045.8222102242562, 1056.714687034504, 1094.0645747885603, 10933.56265749369, 10948.781024379468, 10958.726031849188, 10970.670879873616, 10972.725681857937, 4296.162248361539, 4384.69307148503, 4424.668149888771, 4437.400950871655, 4450.109978004347, 9312.627494900127, 9338.346315757968, 9340.813183736287, 9341.439513629068, 9347.859782103274, 4761.95238047622, 4837.564619184291, 4842.0, 4872.171730805771, 4874.4150701916, 4001.9399709004388, 4108.36320370343, 4111.773852438085, 4313.654907607384, 4457.04173602677, 3729.7948612826012, 3739.2147648994014, 3752.962470375261, 3773.1510410157493, 3782.5650050244576, 1224.6815827884814, 1261.4197469164103, 1269.1243042300782, 1269.257484371653, 1277.3230188000375, 1226.969325766845, 1260.6281147061638, 1264.3741348032784, 1353.5330311042674, 1354.634248752851, 7143.642817859086, 7160.10498687664, 7196.10048743907, 7272.745453909088, 7349.55902645843, 468.20405310971483, 473.80100357849784, 475.9928601070987, 478.76779761815493, 483.166537722426, 5707.400722924116, 5808.94191058084, 5810.331811841692, 5811.4479124483105, 5813.244278243801, 8946.593148476306, 9271.721848344561, 9444.622215111318, 9548.045195480396, 9565.426074435556, 9425.670101546413, 9485.288603354777, 9506.667266645634, 9507.66723164686, 9514.95335513093, 7078.68145933424, 7237.023001894782, 7244.203137654854, 7253.238409966983, 7313.89029164563, 7137.179224389261, 7225.205227424874, 7237.203907570058, 7248.238934911969, 7255.0, 7889.6449659766595, 8061.153578874134, 8264.752057438287, 8279.213474719892, 8291.253787159185, 8183.140770219279, 8218.424710270223, 8234.341252699889, 8349.83300334002, 8357.414980951518, 6205.131281620667, 6358.682065896779, 6420.582662126947, 6588.341165883373, 6779.288174741581, 7302.087239095114, 7326.780196594111, 7354.191038985814, 7363.447741419523, 7391.852162956819, 6723.562968407234, 6837.829054273683, 6864.176298844171, 6993.300669932966, 6999.545029573062, 6745.494087943326, 6773.62745049027, 6873.896891546531, 6881.208661004049, 6891.724331026703, 6737.59574425536, 6869.965650171717, 6902.93097069043, 6915.928031155179, 6927.618980955895, 6447.194100737408, 6506.3493650634555, 6506.4144227017905, 6507.577007494498, 6520.739170433129, 6533.575317604341, 6552.8361790955605, 6582.901256481253, 6601.273859875314, 6603.933960660332], "unit": "score", "name": "mootools.html", "value": 4421.176088525796}, {"lowerIsBetter": false, "replicates": [336.6095329417875, 340.83810190159664, 341.6839423533232, 343.203767259957, 351.9560054993124, 5708.600397972144, 5776.855578610532, 5892.793752218673, 5964.82105536834, 5977.5815692901515, 5698.8005419810215, 5826.912596311059, 5849.298084229898, 5865.970670146654, 5870.354261031283, 4513.07481966197, 4534.002519445717, 4537.818487260505, 4539.319102134673, 4540.818367265306, 4366.8034938427845, 4435.62297204738, 4444.888877778065, 4452.933206001912, 4534.659900507459, 4455.46534415869, 4465.776711164445, 4480.439945006874, 4483.260262056773, 4486.618637415823, 4447.3773671685985, 4454.198244316021, 4459.10817836434, 4465.352523884018, 4473.507914129443, 3936.8228429720443, 3939.6454319111417, 3940.6256405641707, 3958.970667626409, 3980.6218409251073, 5492.1212605982855, 5600.1599760035915, 5601.859953501154, 5650.237217975562, 5665.036943719578, 5227.6079294052715, 5234.6335756497065, 5237.031149237365, 5237.450067742909, 5245.921311180336, 5914.9112763308585, 6253.280872699606, 6256.0615907613765, 6259.937400626027, 6262.812115636538, 4204.0540878302445, 4220.008298049956, 4265.466816647919, 4268.445102136702, 4280.59334363235, 6370.267419246753, 6459.257185423689, 6468.0, 6481.967590162019, 6486.935130648681, 4388.210122178039, 4390.253656878338, 4392.824287028547, 4394.692371533962, 4399.890002749957, 4302.204092242882, 4444.355568442557, 4449.354843547732, 4451.3545535897765, 4452.398926144929, 5171.81898633546, 5172.120739474299, 5173.896522069537, 5179.093658609729, 5181.145111056633, 6258.780942666985, 6262.497000719885, 6266.937330626726, 6276.811695649138, 6281.309056003836, 1561.2349948525368, 1572.7247731646917, 1573.417835400909, 1574.66931944293, 1575.086449859102, 1054.0, 1060.7984482948216, 1063.3407287481812, 1081.4268437728017, 1096.2928910852456, 5858.8535286617325, 5865.589408741347, 5870.236869207061, 5878.5297176225795, 5880.323762767251, 6370.872582548323, 6387.616742995435, 6430.356964303626, 6440.937245354464, 6451.096846441502, 5935.258092738408, 5943.286805583271, 5945.732442040118, 5946.435088666656, 5952.464278214981, 3371.3088816792497, 3462.4633181856852, 3465.341585098823, 3496.650334966483, 3507.877224297137, 6749.527533072736, 6755.020522024279, 6760.121184246115, 6765.391114799691, 6773.221079575814, 6638.900416493757, 6643.2360278568585, 6643.701033453506, 6647.966760166169, 6653.301403352675, 6538.411542961157, 6757.493188010919, 6759.628220447923, 6764.526483146133, 6767.221769496472, 6202.2867370253, 6266.467350275278, 6474.902876456763, 6486.9351306488215, 6493.545451818327, 6226.564140510302, 6236.532260080421, 6341.985282354801, 6418.935810641834, 6439.098526206241, 6727.697253623501, 6738.6967586459705, 6743.426808721314, 6748.257691654012, 6749.257581665915, 4319.33050377192, 4460.710053846619, 4503.392042074278, 4504.819807207675, 4505.0, 6452.645104519296, 6455.3221911700475, 6466.482681385572, 6472.644004579794, 6478.9633658616285, 6297.590656607306, 6301.117843501822, 6307.180066591313, 6309.589876658186, 6310.11658367838, 6172.629642221481, 6225.688715564113, 6274.215723034621, 6279.497640188865, 6288.308286088435, 6153.784617538365, 6159.014557670751, 6162.630242185483, 6163.106349579376, 6169.0, 6255.937440625536, 6281.309056003744, 6295.0, 6297.1813664223355, 6305.90541141874, 6038.577299589162, 6124.265088189446, 6132.78535251264, 6136.478399336106, 6142.293636231891], "unit": "score", "name": "prototype.html", "value": 4709.6619788467}, {"lowerIsBetter": false, "replicates": [771.607234065765, 861.1173547114205, 866.8699695045742, 873.1181506678261, 874.366084588673, 13369.7326053479, 13515.0, 13559.728805423885, 13563.593092207237, 13581.728365432686, 13343.466261349535, 13375.732485350287, 13395.263260520667, 13417.383610196015, 13442.731145377087, 626.6741294526867, 791.3352783661709, 802.626778547975, 802.8233788566536, 803.2529747335005, 635.0632816595507, 786.429838367185, 798.4051881348404, 802.3701394405393, 803.4094940218935, 827.7806381308958, 835.9205875441824, 836.3184005035906, 836.4354061008825, 837.4933165434916, 655.9180102487189, 658.042548092529, 814.2590242878952, 832.7751507093081, 832.937529685273, 613.0283500651473, 615.3200713211882, 615.5537235504269, 616.5868867858545, 616.981490555284, 13427.328633568282, 13471.191728496224, 13488.5279015234, 13534.526291579847, 13565.660858478519, 11942.223755455867, 12415.0, 13116.934415327862, 13123.737525249442, 13190.142640728322, 10203.591856325738, 10322.432266225354, 10455.059044686015, 10976.286541374786, 11090.168237382228, 3545.149164200599, 3612.747107702462, 3617.457381392786, 3710.4805327254207, 3714.2571485702697, 45538.77230613826, 45681.0, 45895.770521147184, 46121.308180377324, 47272.290915636295, 9154.542272886329, 9331.720048398423, 9606.711798645912, 10122.190224782, 10335.741606459898, 18021.18904649294, 18083.186256618483, 18137.27450901949, 18498.537536561424, 18515.72226416605, 7034.753783617549, 7193.928060719326, 7232.0598322217775, 7267.527610705287, 7271.89092163618, 4015.056461731491, 4034.0, 4082.0203151243486, 4143.502779666459, 4161.479815023122, 3009.052148573236, 3038.8328641924904, 3069.0946170879643, 3078.98460507696, 3079.260977365461, 539.5225225675306, 543.8749087709849, 551.5780427972604, 551.7544692611749, 557.6096732287415, 545.5799034743305, 568.4003376437815, 667.8096742428481, 673.161913417798, 686.1800148822109, 7664.693412263447, 7734.92265077342, 7763.611819409007, 7824.517617345409, 7998.680052797939, 645.6932956845461, 655.505093654288, 678.718331892269, 688.6074937285699, 738.8928605352194, 3812.6377994090517, 4007.438958545806, 4080.959190408117, 4158.958410415918, 4368.170047690928, 55207.72396137994, 56072.0, 56149.71925140348, 56208.437915621136, 56233.15650265249, 45594.316085258746, 50374.0, 50803.0, 50987.7450612752, 53541.732291339074, 41556.376654350206, 41741.37387939183, 42213.0, 43409.78295108441, 44837.327440088426, 8153.633086511002, 8182.67269309221, 8216.63025163881, 8284.544350060805, 8563.61463734121, 32364.35271294514, 32626.83686581552, 32706.345873082882, 33738.83130584331, 33830.492542612374, 39994.40008399818, 40019.599804001584, 41823.58176418319, 43358.78320608377, 43663.12673746571, 10570.260081794508, 10597.9470102649, 10837.241393102408, 11123.332600044023, 11372.601958931398, 11361.147913906656, 11551.42242887869, 11597.304161750322, 11967.281963082243, 12062.698432539257, 11221.158413118885, 11413.144014199135, 11636.301821890713, 11835.644930652094, 11867.406629668309, 11646.0, 12121.515139394325, 12143.75712485763, 12158.817617735918, 12422.775587604028, 10786.460676966277, 11122.0, 11179.0, 11239.60661376848, 11400.486978086172, 8815.250703690259, 8962.327825413251, 9152.588133533874, 9198.310126740387, 9207.907920920972, 9208.723738287863, 9228.630854765734, 9586.424814511152, 9669.226461883172, 10149.898501014895], "unit": "score", "name": "yui.html", "value": 6185.488911371332}], "name": "dromaeo_css", "value": 6733.04737790256}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 106.0, 111.0, 112.0, 113.0, 101.0, 110.0, 110.0, 109.0], "unit": "score", "name": "ai-astar", "value": 109.2}, {"lowerIsBetter": true, "replicates": [155.0, 125.0, 152.0, 167.0, 127.0, 153.0, 147.0, 126.0, 164.0, 128.0], "unit": "score", "name": "audio-beat-detection", "value": 144.4}, {"lowerIsBetter": true, "replicates": [159.0, 160.0, 162.0, 161.0, 161.0, 161.0, 161.0, 162.0, 162.0, 162.0], "unit": "score", "name": "audio-dft", "value": 161.1}, {"lowerIsBetter": true, "replicates": [72.0, 75.0, 85.0, 93.0, 74.0, 73.0, 73.0, 113.0, 92.0, 71.0], "unit": "score", "name": "audio-fft", "value": 82.1}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 143.0, 95.0, 96.0, 96.0, 142.0, 95.0, 96.0, 137.0], "unit": "score", "name": "audio-oscillator", "value": 109.0}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 110.0, 108.0, 109.0, 110.0, 109.0, 110.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 147.0, 145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 145.8}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [56.0, 55.0, 57.0, 56.0, 55.0, 55.0, 55.0, 65.0, 54.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 56.2}, {"lowerIsBetter": true, "replicates": [68.0, 67.0, 68.0, 68.0, 67.0, 67.0, 67.0, 67.0, 67.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.4}, {"lowerIsBetter": true, "replicates": [84.0, 93.0, 87.0, 93.0, 85.0, 85.0, 84.0, 85.0, 86.0, 86.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.8}, {"lowerIsBetter": true, "replicates": [124.0, 118.0, 113.0, 113.0, 115.0, 123.0, 117.0, 117.0, 120.0, 120.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 118.0}, {"lowerIsBetter": true, "replicates": [157.0, 151.0, 152.0, 155.0, 154.0, 150.0, 151.0, 152.0, 150.0, 140.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 151.2}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 67.0, 66.0, 68.0, 70.0, 67.0, 67.0, 72.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.8}], "name": "kraken", "value": 1504.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [170.76502732240436], "unit": "score", "name": "Richards", "value": 206.72851200000008}, {"lowerIsBetter": false, "replicates": [190.94901661256444], "unit": "score", "name": "DeltaBlue", "value": 346.2599660000002}, {"lowerIsBetter": false, "replicates": [281.29395218002816], "unit": "score", "name": "Encrypt", "value": 946.2734550000002}, {"lowerIsBetter": false, "replicates": [5246.073298429319], "unit": "score", "name": "Decrypt", "value": 50.739092814371276}, {"lowerIsBetter": false, "replicates": [2032.520325203252], "unit": "score", "name": "RayTrace", "value": 364.0745880000001}, {"lowerIsBetter": false, "replicates": [796.8127490039841], "unit": "score", "name": "Earley", "value": 836.4110649999999}, {"lowerIsBetter": false, "replicates": [12407.407407407407], "unit": "score", "name": "Boyer", "value": 53.71492835820899}, {"lowerIsBetter": false, "replicates": [42291.666666666664], "unit": "score", "name": "RegExp", "value": 21.540532019704436}, {"lowerIsBetter": false, "replicates": [572.3234624145786], "unit": "score", "name": "Splay", "value": 142.38626467661697}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 18822.241932796544}]} 16:08:32 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "18822.24"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1504.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6733.05"}}} 16:08:32 INFO - Return code: 0 16:08:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:08:32 INFO - # TBPL SUCCESS # 16:08:32 INFO - Running post-action listener: _resource_record_post_action 16:08:32 INFO - Running post-run listener: _resource_record_post_run 16:08:34 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 16384; Write bytes: 568840192; Read time: 220; Write time: 504624 16:08:34 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 256831488; Read time: 0; Write time: 285512 16:08:34 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 16384; Write bytes: 312008704; Read time: 220; Write time: 219112 16:08:34 INFO - Running post-run listener: _upload_blobber_files 16:08:34 INFO - Blob upload gear active. 16:08:34 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 16:08:34 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 16:08:34 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', 'Firefox', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 16:08:34 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 Firefox -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 16:08:34 INFO - (blobuploader) - INFO - Open directory for files ... 16:08:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 16:08:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:08:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:35 INFO - (blobuploader) - INFO - Done attempting. 16:08:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:08:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:08:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:36 INFO - (blobuploader) - INFO - Done attempting. 16:08:36 INFO - (blobuploader) - INFO - Iteration through files over. 16:08:36 INFO - Return code: 0 16:08:36 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 16:08:36 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 16:08:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89f02be600687b59f17bd7fcc6d4a488e236a7ae3a0986c6c137d380f3f87f633ecc27d448af86fd950046c56fa2768e223e0b2f90580c7640ab90e002f9cf93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02aad2c6eae5f1c6110331e2a061d371573b62d22397ea2aeeb67a107e4c0ea5198744e57fae88c415b286c23a8a20630b507efa1740fceda1fdf5a0c4f15c20"} 16:08:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 16:08:36 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 16:08:36 INFO - Contents: 16:08:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89f02be600687b59f17bd7fcc6d4a488e236a7ae3a0986c6c137d380f3f87f633ecc27d448af86fd950046c56fa2768e223e0b2f90580c7640ab90e002f9cf93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02aad2c6eae5f1c6110331e2a061d371573b62d22397ea2aeeb67a107e4c0ea5198744e57fae88c415b286c23a8a20630b507efa1740fceda1fdf5a0c4f15c20"} 16:08:36 INFO - Copying logs to upload dir... 16:08:36 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1269.647840 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-01-09 16:08:35.441543) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-09 16:08:35.445228) ========= 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=6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89f02be600687b59f17bd7fcc6d4a488e236a7ae3a0986c6c137d380f3f87f633ecc27d448af86fd950046c56fa2768e223e0b2f90580c7640ab90e002f9cf93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02aad2c6eae5f1c6110331e2a061d371573b62d22397ea2aeeb67a107e4c0ea5198744e57fae88c415b286c23a8a20630b507efa1740fceda1fdf5a0c4f15c20"} build_url:https://queue.taskcluster.net/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011310 build_url: 'https://queue.taskcluster.net/v1/task/C1Jw8qSZQJODDUFi1LFjmg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89f02be600687b59f17bd7fcc6d4a488e236a7ae3a0986c6c137d380f3f87f633ecc27d448af86fd950046c56fa2768e223e0b2f90580c7640ab90e002f9cf93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02aad2c6eae5f1c6110331e2a061d371573b62d22397ea2aeeb67a107e4c0ea5198744e57fae88c415b286c23a8a20630b507efa1740fceda1fdf5a0c4f15c20"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-09 16:08:35.470557) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 16:08:35.470972) ========= 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=6d15e502d8e29d5d2676d664000001c3-1452382400.33972-1485297735 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004553 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 16:08:35.558707) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-09 16:08:35.559064) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-09 16:08:35.559386) ========= ========= Total master_lag: 0.24 =========