builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-033 starttime: 1450766844.57 results: success (0) buildid: 20151221203241 builduid: 7a868b5b4f864b73bff11462f35970b3 revision: b96e844b0289c5363b5988214d6343b05a7996e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:24.566839) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:24.567304) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:24.567594) ========= 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=a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006357 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:24.602243) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:24.602543) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:24.691814) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:24.692121) ========= 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=a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004587 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:24.770432) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:24.770723) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:24.771121) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:24.771389) ========= 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=a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-21 22:47:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 216M=0s 2015-12-21 22:47:25 (216 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.237970 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:25.026012) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:25.026323) ========= 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=a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020333 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:25.075494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:25.075844) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b96e844b0289c5363b5988214d6343b05a7996e8 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b96e844b0289c5363b5988214d6343b05a7996e8 --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=a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-21 22:47:25,220 truncating revision to first 12 chars 2015-12-21 22:47:25,220 Setting DEBUG logging. 2015-12-21 22:47:25,220 attempt 1/10 2015-12-21 22:47:25,220 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b96e844b0289?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-21 22:47:25,587 unpacking tar archive at: fx-team-b96e844b0289/testing/mozharness/ program finished with exit code 0 elapsedTime=0.625525 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:25.714167) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:25.714483) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:25.724720) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:25.724980) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-21 22:47:25.725389) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2015-12-21 22:47:25.725682) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:47:25 INFO - MultiFileLogger online at 20151221 22:47:25 in /builds/slave/test-pgo 22:47:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:47:25 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 22:47:25 INFO - {'append_to_log': False, 22:47:25 INFO - 'base_work_dir': '/builds/slave/test-pgo', 22:47:25 INFO - 'blob_upload_branch': 'Fx-Team', 22:47:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 22:47:25 INFO - 'branch': 'Fx-Team', 22:47:25 INFO - 'buildbot_json_path': 'buildprops.json', 22:47:25 INFO - 'config_files': ('talos/linux_config.py',), 22:47:25 INFO - 'default_actions': ('clobber', 22:47:25 INFO - 'read-buildbot-config', 22:47:25 INFO - 'download-and-extract', 22:47:25 INFO - 'populate-webroot', 22:47:25 INFO - 'create-virtualenv', 22:47:25 INFO - 'install', 22:47:25 INFO - 'run-tests'), 22:47:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:47:25 INFO - 'download_minidump_stackwalk': True, 22:47:25 INFO - 'download_symbols': 'ondemand', 22:47:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:47:25 INFO - 'tooltool.py': '/tools/tooltool.py', 22:47:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:47:25 INFO - '/tools/misc-python/virtualenv.py')}, 22:47:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:47:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:47:25 INFO - 'installer_path': 'installer.exe', 22:47:25 INFO - 'log_level': 'info', 22:47:25 INFO - 'log_name': 'talos', 22:47:25 INFO - 'log_to_console': True, 22:47:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:47:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:47:25 INFO - 'opt_config_files': (), 22:47:25 INFO - 'pip_index': False, 22:47:25 INFO - 'sps_profile': False, 22:47:25 INFO - 'sps_profile_interval': 0, 22:47:25 INFO - 'suite': 'dromaeojs-e10s', 22:47:25 INFO - 'system_bits': '64', 22:47:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:47:25 INFO - 'title': 'talos-linux64-ix-033', 22:47:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:47:25 INFO - 'use_talos_json': True, 22:47:25 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 22:47:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:47:25 INFO - 'work_dir': 'build'} 22:47:25 INFO - ##### 22:47:25 INFO - ##### Running clobber step. 22:47:25 INFO - ##### 22:47:25 INFO - Running pre-action listener: _resource_record_pre_action 22:47:25 INFO - Running main action method: clobber 22:47:25 INFO - rmtree: /builds/slave/test-pgo/build 22:47:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 22:47:26 INFO - Running post-action listener: _resource_record_post_action 22:47:26 INFO - ##### 22:47:26 INFO - ##### Running read-buildbot-config step. 22:47:26 INFO - ##### 22:47:26 INFO - Running pre-action listener: _resource_record_pre_action 22:47:26 INFO - Running main action method: read_buildbot_config 22:47:26 INFO - Using buildbot properties: 22:47:26 INFO - { 22:47:26 INFO - "project": "", 22:47:26 INFO - "product": "firefox", 22:47:26 INFO - "script_repo_revision": "production", 22:47:26 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 22:47:26 INFO - "repository": "", 22:47:26 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 22:47:26 INFO - "buildid": "20151221203241", 22:47:26 INFO - "pgo_build": "True", 22:47:26 INFO - "basedir": "/builds/slave/test-pgo", 22:47:26 INFO - "buildnumber": 398, 22:47:26 INFO - "slavename": "talos-linux64-ix-033", 22:47:26 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:47:26 INFO - "platform": "ubuntu64_hw", 22:47:26 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 22:47:26 INFO - "branch": "fx-team", 22:47:26 INFO - "repo_path": "integration/fx-team", 22:47:26 INFO - "revision": "b96e844b0289c5363b5988214d6343b05a7996e8", 22:47:26 INFO - "stage_platform": "linux64-pgo", 22:47:26 INFO - "builduid": "7a868b5b4f864b73bff11462f35970b3", 22:47:26 INFO - "slavebuilddir": "test" 22:47:26 INFO - } 22:47:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 22:47:26 INFO - Running post-action listener: _resource_record_post_action 22:47:26 INFO - ##### 22:47:26 INFO - ##### Running download-and-extract step. 22:47:26 INFO - ##### 22:47:26 INFO - Running pre-action listener: _resource_record_pre_action 22:47:26 INFO - Running main action method: download_and_extract 22:47:26 INFO - mkdir: /builds/slave/test-pgo/build/tests 22:47:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:47:26 INFO - https://queue.taskcluster.net/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:47:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/test_packages.json 22:47:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/test_packages.json 22:47:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 22:47:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 22:47:34 INFO - Downloaded 1302 bytes. 22:47:34 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 22:47:34 INFO - Using the following test package requirements: 22:47:34 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:47:34 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:47:34 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:34 INFO - u'jsshell-linux-x86_64.zip'], 22:47:34 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:47:34 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:47:34 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:47:34 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:47:34 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:47:34 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:47:34 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:47:34 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:47:34 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22: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')]} 22:47:34 INFO - https://queue.taskcluster.net/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:47:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:47:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:47:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/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 22:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/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 22:47:35 INFO - Downloaded 23175746 bytes. 22:47:35 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 22:47:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:47:35 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 22:47:36 INFO - Return code: 0 22:47:36 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 22:47:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:47:36 INFO - https://queue.taskcluster.net/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:47:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:47:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:47:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/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 22:47:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/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 22:47:43 INFO - Downloaded 23175746 bytes. 22:47:43 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 22:47:43 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:47:43 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 22:47:44 INFO - Return code: 0 22:47:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:47:44 INFO - https://queue.taskcluster.net/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:47:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:47:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:47:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/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 22:47:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/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 22:47:45 INFO - Downloaded 11132277 bytes. 22:47:45 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 22:47:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:47:45 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 22:47:45 INFO - Return code: 0 22: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')]} 22:47:45 INFO - https://queue.taskcluster.net/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:47:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:47:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:47:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/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 22:47:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XX-D3zUJQECVFWyjmqiRgQ/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 22:47:47 INFO - Downloaded 54877061 bytes. 22:47:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:47:47 INFO - mkdir: /builds/slave/test-pgo/properties 22:47:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 22:47:47 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 22:47:47 INFO - Contents: 22:47:47 INFO - build_url:https://queue.taskcluster.net/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:47:47 INFO - Running post-action listener: _resource_record_post_action 22:47:47 INFO - Running post-action listener: set_extra_try_arguments 22:47:47 INFO - ##### 22:47:47 INFO - ##### Running populate-webroot step. 22:47:47 INFO - ##### 22:47:47 INFO - Running pre-action listener: _resource_record_pre_action 22:47:47 INFO - Running main action method: populate_webroot 22:47:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:47:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:47:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:47:47 INFO - '--tppagecycles', 22:47:47 INFO - '7'], 22:47:47 INFO - 'tests': ['tsvgm']}}, 22:47:47 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:47:47 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:47:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:47:47 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:47:47 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:47:47 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:47:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:47:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:47 INFO - 'talos_options': ['--e10s'], 22:47:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:47:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:47 INFO - 'tests': ['damp', 'tps']}, 22:47:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:47 INFO - 'talos_options': ['--e10s'], 22:47:47 INFO - 'tests': ['damp', 'tps']}, 22:47:47 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:47:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:47:47 INFO - 'other': {'tests': ['a11yr', 22:47:47 INFO - 'ts_paint', 22:47:47 INFO - 'tpaint', 22:47:47 INFO - 'sessionrestore', 22:47:47 INFO - 'sessionrestore_no_auto_restore']}, 22:47:47 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:47:47 INFO - 'tests': ['a11yr', 22:47:47 INFO - 'ts_paint', 22:47:47 INFO - 'tpaint', 22:47:47 INFO - 'sessionrestore', 22:47:47 INFO - 'sessionrestore_no_auto_restore']}, 22:47:47 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:47:47 INFO - 'tests': ['a11yr', 22:47:47 INFO - 'ts_paint', 22:47:47 INFO - 'tpaint', 22:47:47 INFO - 'sessionrestore', 22:47:47 INFO - 'sessionrestore_no_auto_restore']}, 22:47:47 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:47:47 INFO - 'tests': ['a11yr', 22:47:47 INFO - 'ts_paint', 22:47:47 INFO - 'tpaint', 22:47:47 INFO - 'sessionrestore', 22:47:47 INFO - 'sessionrestore_no_auto_restore']}, 22:47:47 INFO - 'other_l64': {'tests': ['a11yr', 22:47:47 INFO - 'ts_paint', 22:47:47 INFO - 'tpaint', 22:47:47 INFO - 'sessionrestore', 22:47:47 INFO - 'sessionrestore_no_auto_restore']}, 22:47:47 INFO - 'other_nol64': {'tests': ['a11yr', 22:47:47 INFO - 'ts_paint', 22:47:47 INFO - 'tpaint', 22:47:47 INFO - 'sessionrestore', 22:47:47 INFO - 'sessionrestore_no_auto_restore']}, 22:47:47 INFO - 'svgr': {'tests': ['tsvgx', 22:47:47 INFO - 'tsvgr_opacity', 22:47:47 INFO - 'tart', 22:47:47 INFO - 'tscrollx', 22:47:47 INFO - 'cart']}, 22:47:47 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:47:47 INFO - 'tests': ['tsvgx', 22:47:47 INFO - 'tsvgr_opacity', 22:47:47 INFO - 'tart', 22:47:47 INFO - 'tscrollx', 22:47:47 INFO - 'cart']}, 22:47:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:47 INFO - 'tests': ['tp5o']}, 22:47:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:47 INFO - 'talos_options': ['--e10s'], 22:47:47 INFO - 'tests': ['tp5o']}, 22:47:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:47 INFO - 'talos_options': ['--xperf_path', 22:47:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:47:47 INFO - 'tests': ['tp5n']}, 22:47:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:47:47 INFO - 'talos_options': ['--e10s', 22:47:47 INFO - '--xperf_path', 22:47:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:47:47 INFO - 'tests': ['tp5n']}}, 22:47:47 INFO - 'talos.zip': {'path': '', 22:47:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:47:47 INFO - Running post-action listener: _resource_record_post_action 22:47:47 INFO - ##### 22:47:47 INFO - ##### Running create-virtualenv step. 22:47:47 INFO - ##### 22:47:47 INFO - Running pre-action listener: _resource_record_pre_action 22:47:47 INFO - Running main action method: create_virtualenv 22:47:47 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 22:47:47 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 22:47:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 22:47:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:47:47 INFO - Using real prefix '/usr' 22:47:47 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 22:47:47 INFO - Installing distribute.............................................................................................................................................................................................done. 22:47:49 INFO - Installing pip.................done. 22:47:49 INFO - Return code: 0 22:47:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 22:47:49 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')]} 22:47:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:49 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')]} 22:47:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:49 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 0x7fd713973f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b42630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd3840>, '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 0x2b406b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40b60>, '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': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', '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 22:47:49 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 22:47:49 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 22:47:49 INFO - Using env: {'DISPLAY': ':0', 22:47:49 INFO - 'HOME': '/home/cltbld', 22:47:49 INFO - 'LANG': 'en_US.UTF-8', 22:47:49 INFO - 'LANGUAGE': 'en_US:en', 22:47:49 INFO - 'LOGNAME': 'cltbld', 22:47:49 INFO - 'MAIL': '/var/mail/cltbld', 22:47:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:49 INFO - 'MOZ_NO_REMOTE': '1', 22:47:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:49 INFO - 'NO_EM_RESTART': '1', 22:47:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:47:49 INFO - 'PWD': '/builds/slave/test-pgo', 22:47:49 INFO - 'SHELL': '/bin/bash', 22:47:49 INFO - 'SHLVL': '1', 22:47:49 INFO - 'TERM': 'linux', 22:47:49 INFO - 'TMOUT': '86400', 22:47:49 INFO - 'USER': 'cltbld', 22:47:49 INFO - 'XDG_SESSION_COOKIE': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', 22:47:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:49 INFO - '_': '/tools/buildbot/bin/python'} 22:47:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:50 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 22:47:50 INFO - Cleaning up... 22:47:50 INFO - Return code: 0 22:47:50 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 22: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')]} 22:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22: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')]} 22:47:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:50 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 0x7fd713973f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b42630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd3840>, '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 0x2b406b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40b60>, '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': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', '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 22:47:50 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 22:47:50 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 22:47:50 INFO - Using env: {'DISPLAY': ':0', 22:47:50 INFO - 'HOME': '/home/cltbld', 22:47:50 INFO - 'LANG': 'en_US.UTF-8', 22:47:50 INFO - 'LANGUAGE': 'en_US:en', 22:47:50 INFO - 'LOGNAME': 'cltbld', 22:47:50 INFO - 'MAIL': '/var/mail/cltbld', 22:47:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:50 INFO - 'MOZ_NO_REMOTE': '1', 22:47:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:50 INFO - 'NO_EM_RESTART': '1', 22:47:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:47:50 INFO - 'PWD': '/builds/slave/test-pgo', 22:47:50 INFO - 'SHELL': '/bin/bash', 22:47:50 INFO - 'SHLVL': '1', 22:47:50 INFO - 'TERM': 'linux', 22:47:50 INFO - 'TMOUT': '86400', 22:47:50 INFO - 'USER': 'cltbld', 22:47:50 INFO - 'XDG_SESSION_COOKIE': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', 22:47:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:50 INFO - '_': '/tools/buildbot/bin/python'} 22:47:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:50 INFO - Downloading/unpacking psutil>=0.7.1 22:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:47:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:47:52 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 22:47:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 22:47:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:47:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:47:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:47:52 INFO - Installing collected packages: psutil 22:47:52 INFO - Running setup.py install for psutil 22:47:52 INFO - building 'psutil._psutil_linux' extension 22:47:52 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 22:47:52 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 22:47:52 INFO - building 'psutil._psutil_posix' extension 22:47:52 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 22:47:52 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 22:47:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:47:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:47:52 INFO - Successfully installed psutil 22:47:52 INFO - Cleaning up... 22:47:52 INFO - Return code: 0 22:47:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:47:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:52 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 0x7fd713973f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b42630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd3840>, '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 0x2b406b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40b60>, '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': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', '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 22:47:52 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 22:47:52 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 22:47:52 INFO - Using env: {'DISPLAY': ':0', 22:47:52 INFO - 'HOME': '/home/cltbld', 22:47:52 INFO - 'LANG': 'en_US.UTF-8', 22:47:52 INFO - 'LANGUAGE': 'en_US:en', 22:47:52 INFO - 'LOGNAME': 'cltbld', 22:47:52 INFO - 'MAIL': '/var/mail/cltbld', 22:47:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:52 INFO - 'MOZ_NO_REMOTE': '1', 22:47:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:52 INFO - 'NO_EM_RESTART': '1', 22:47:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:47:52 INFO - 'PWD': '/builds/slave/test-pgo', 22:47:52 INFO - 'SHELL': '/bin/bash', 22:47:52 INFO - 'SHLVL': '1', 22:47:52 INFO - 'TERM': 'linux', 22:47:52 INFO - 'TMOUT': '86400', 22:47:52 INFO - 'USER': 'cltbld', 22:47:52 INFO - 'XDG_SESSION_COOKIE': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', 22:47:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:52 INFO - '_': '/tools/buildbot/bin/python'} 22:47:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:47:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:47:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:47:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 22:47:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:47:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:47:54 INFO - Installing collected packages: mozsystemmonitor 22:47:54 INFO - Running setup.py install for mozsystemmonitor 22:47:54 INFO - Successfully installed mozsystemmonitor 22:47:54 INFO - Cleaning up... 22:47:54 INFO - Return code: 0 22:47:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22: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')]} 22:47:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22: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')]} 22:47:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22: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', '--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 0x7fd713973f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b42630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd3840>, '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 0x2b406b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40b60>, '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': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', '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 22: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', '--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 22: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 --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 22:47:54 INFO - Using env: {'DISPLAY': ':0', 22:47:54 INFO - 'HOME': '/home/cltbld', 22:47:54 INFO - 'LANG': 'en_US.UTF-8', 22:47:54 INFO - 'LANGUAGE': 'en_US:en', 22:47:54 INFO - 'LOGNAME': 'cltbld', 22:47:54 INFO - 'MAIL': '/var/mail/cltbld', 22:47:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:54 INFO - 'MOZ_NO_REMOTE': '1', 22:47:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:54 INFO - 'NO_EM_RESTART': '1', 22:47:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:47:54 INFO - 'PWD': '/builds/slave/test-pgo', 22:47:54 INFO - 'SHELL': '/bin/bash', 22:47:54 INFO - 'SHLVL': '1', 22:47:54 INFO - 'TERM': 'linux', 22:47:54 INFO - 'TMOUT': '86400', 22:47:54 INFO - 'USER': 'cltbld', 22:47:54 INFO - 'XDG_SESSION_COOKIE': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', 22:47:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:54 INFO - '_': '/tools/buildbot/bin/python'} 22:47:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:55 INFO - Downloading/unpacking blobuploader==1.2.4 22: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 22: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 22: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 22: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 22: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 22: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 22:47:56 INFO - Downloading blobuploader-1.2.4.tar.gz 22:47:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 22:47:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:47:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:47:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:47:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 22:47:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 22:47:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:47:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:47:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:47:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:47:57 INFO - Downloading docopt-0.6.1.tar.gz 22:47:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 22:47:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 22:47:57 INFO - Installing collected packages: blobuploader, requests, docopt 22:47:57 INFO - Running setup.py install for blobuploader 22:47:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:47:57 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 22:47:57 INFO - Running setup.py install for requests 22:47:58 INFO - Running setup.py install for docopt 22:47:58 INFO - Successfully installed blobuploader requests docopt 22:47:58 INFO - Cleaning up... 22:47:58 INFO - Return code: 0 22:47:58 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:47:58 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')]} 22:47:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:47:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:58 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')]} 22:47:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:47:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:47:58 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 0x7fd713973f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b42630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd3840>, '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 0x2b406b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40b60>, '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': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', '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 22:47:58 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 22:47:58 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 22:47:58 INFO - Using env: {'DISPLAY': ':0', 22:47:58 INFO - 'HOME': '/home/cltbld', 22:47:58 INFO - 'LANG': 'en_US.UTF-8', 22:47:58 INFO - 'LANGUAGE': 'en_US:en', 22:47:58 INFO - 'LOGNAME': 'cltbld', 22:47:58 INFO - 'MAIL': '/var/mail/cltbld', 22:47:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:47:58 INFO - 'MOZ_NO_REMOTE': '1', 22:47:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:47:58 INFO - 'NO_EM_RESTART': '1', 22:47:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:47:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:47:58 INFO - 'PWD': '/builds/slave/test-pgo', 22:47:58 INFO - 'SHELL': '/bin/bash', 22:47:58 INFO - 'SHLVL': '1', 22:47:58 INFO - 'TERM': 'linux', 22:47:58 INFO - 'TMOUT': '86400', 22:47:58 INFO - 'USER': 'cltbld', 22:47:58 INFO - 'XDG_SESSION_COOKIE': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', 22:47:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:47:58 INFO - '_': '/tools/buildbot/bin/python'} 22:47:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:47:58 INFO - Running setup.py (path:/tmp/pip-wPBnN8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:47:58 INFO - Running setup.py (path:/tmp/pip-2Rnvpg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:47:58 INFO - Running setup.py (path:/tmp/pip-SFUIOU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:47:58 INFO - Running setup.py (path:/tmp/pip-ET6rr_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:47:58 INFO - Running setup.py (path:/tmp/pip-RQrFUK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:47:58 INFO - Running setup.py (path:/tmp/pip-qL2eol-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:47:58 INFO - Running setup.py (path:/tmp/pip-ySuDOX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:47:58 INFO - Running setup.py (path:/tmp/pip-N_akdG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:47:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:47:58 INFO - Running setup.py (path:/tmp/pip-F4CTg1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:47:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:47:59 INFO - Running setup.py (path:/tmp/pip-0CitWT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:47:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:47:59 INFO - Running setup.py (path:/tmp/pip-ta22U1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:47:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:47:59 INFO - Running setup.py (path:/tmp/pip-KoBDKz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:47:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:47:59 INFO - Running setup.py (path:/tmp/pip-MXtvvq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:47:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:47:59 INFO - Running setup.py (path:/tmp/pip-NFNFNW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:47:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:47:59 INFO - Running setup.py (path:/tmp/pip-fmzjfy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:47:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:47:59 INFO - Running setup.py (path:/tmp/pip-Usve5c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:47:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:47:59 INFO - Running setup.py (path:/tmp/pip-uwOJOT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:47:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:47:59 INFO - Running setup.py install for manifestparser 22:47:59 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 22:47:59 INFO - Running setup.py install for mozcrash 22:47:59 INFO - Running setup.py install for mozdebug 22:47:59 INFO - Running setup.py install for mozdevice 22:48:00 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 22:48:00 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 22:48:00 INFO - Running setup.py install for mozfile 22:48:00 INFO - Running setup.py install for mozhttpd 22:48:00 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 22:48:00 INFO - Running setup.py install for mozinfo 22:48:00 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 22:48:00 INFO - Running setup.py install for mozInstall 22:48:00 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 22:48:00 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 22:48:00 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 22:48:00 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 22:48:00 INFO - Running setup.py install for mozleak 22:48:00 INFO - Running setup.py install for mozlog 22:48:00 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 22:48:00 INFO - Running setup.py install for moznetwork 22:48:00 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 22:48:00 INFO - Running setup.py install for mozprocess 22:48:00 INFO - Running setup.py install for mozprofile 22:48:00 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 22:48:00 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 22:48:00 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 22:48:00 INFO - Running setup.py install for mozrunner 22:48:01 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 22:48:01 INFO - Running setup.py install for mozscreenshot 22:48:01 INFO - Running setup.py install for moztest 22:48:01 INFO - Running setup.py install for mozversion 22:48:01 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 22:48:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:48:01 INFO - Cleaning up... 22:48:01 INFO - Return code: 0 22:48:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:01 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 0x7fd713973f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b42630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd3840>, '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 0x2b406b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40b60>, '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': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', '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 22:48:01 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 22:48:01 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 22:48:01 INFO - Using env: {'DISPLAY': ':0', 22:48:01 INFO - 'HOME': '/home/cltbld', 22:48:01 INFO - 'LANG': 'en_US.UTF-8', 22:48:01 INFO - 'LANGUAGE': 'en_US:en', 22:48:01 INFO - 'LOGNAME': 'cltbld', 22:48:01 INFO - 'MAIL': '/var/mail/cltbld', 22:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:01 INFO - 'MOZ_NO_REMOTE': '1', 22:48:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:48:01 INFO - 'NO_EM_RESTART': '1', 22:48:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:48:01 INFO - 'PWD': '/builds/slave/test-pgo', 22:48:01 INFO - 'SHELL': '/bin/bash', 22:48:01 INFO - 'SHLVL': '1', 22:48:01 INFO - 'TERM': 'linux', 22:48:01 INFO - 'TMOUT': '86400', 22:48:01 INFO - 'USER': 'cltbld', 22:48:01 INFO - 'XDG_SESSION_COOKIE': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', 22:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:01 INFO - '_': '/tools/buildbot/bin/python'} 22:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:48:01 INFO - Running setup.py (path:/tmp/pip-lNOydA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:48:01 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)) 22:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:48:01 INFO - Running setup.py (path:/tmp/pip-qakFsO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:48:01 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)) 22:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:48:01 INFO - Running setup.py (path:/tmp/pip-A2mysR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:48:01 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)) 22:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:48:01 INFO - Running setup.py (path:/tmp/pip-g9e4DI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:48:01 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)) 22:48:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:48:01 INFO - Running setup.py (path:/tmp/pip-3UwqtK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:48:02 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)) 22:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:48:02 INFO - Running setup.py (path:/tmp/pip-jXL36M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:48:02 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)) 22:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:48:02 INFO - Running setup.py (path:/tmp/pip-6yAZuG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:48:02 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)) 22:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:48:02 INFO - Running setup.py (path:/tmp/pip-cOfhxy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:48:02 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)) 22:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:48:02 INFO - Running setup.py (path:/tmp/pip-NcSFzH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:48:02 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)) 22:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:48:02 INFO - Running setup.py (path:/tmp/pip-bIC_ya-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:48:02 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)) 22:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:48:02 INFO - Running setup.py (path:/tmp/pip-gKhnzN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:48:02 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)) 22:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:48:02 INFO - Running setup.py (path:/tmp/pip-1F81eg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:48:02 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)) 22:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:48:02 INFO - Running setup.py (path:/tmp/pip-IO09Kr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:48:02 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)) 22:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:48:02 INFO - Running setup.py (path:/tmp/pip-1V303J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:48:02 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)) 22:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:48:02 INFO - Running setup.py (path:/tmp/pip-P5cApi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:48:02 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)) 22:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:48:02 INFO - Running setup.py (path:/tmp/pip-fMsjRs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:48:02 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)) 22:48:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:48:02 INFO - Running setup.py (path:/tmp/pip-g9CsER-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:48:02 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)) 22:48:02 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)) 22:48:02 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)) 22:48:02 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)) 22:48:02 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)) 22:48:02 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)) 22:48:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22: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 22: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 22: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 22: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 22: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 22: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 22:48:04 INFO - Downloading blessings-1.5.1.tar.gz 22:48:04 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 22:48:04 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 22:48:04 INFO - Installing collected packages: blessings 22:48:04 INFO - Running setup.py install for blessings 22:48:05 INFO - Successfully installed blessings 22:48:05 INFO - Cleaning up... 22:48:05 INFO - Return code: 0 22:48:05 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 22:48:05 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:48:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:48:05 INFO - Reading from file tmpfile_stdout 22:48:05 INFO - Current package versions: 22:48:05 INFO - argparse == 1.2.1 22:48:05 INFO - blessings == 1.5.1 22:48:05 INFO - blobuploader == 1.2.4 22:48:05 INFO - docopt == 0.6.1 22:48:05 INFO - manifestparser == 1.1 22:48:05 INFO - mozInstall == 1.12 22:48:05 INFO - mozcrash == 0.16 22:48:05 INFO - mozdebug == 0.1 22:48:05 INFO - mozdevice == 0.47 22:48:05 INFO - mozfile == 1.2 22:48:05 INFO - mozhttpd == 0.7 22:48:05 INFO - mozinfo == 0.9 22:48:05 INFO - mozleak == 0.1 22:48:05 INFO - mozlog == 3.1 22:48:05 INFO - moznetwork == 0.27 22:48:05 INFO - mozprocess == 0.22 22:48:05 INFO - mozprofile == 0.28 22:48:05 INFO - mozrunner == 6.11 22:48:05 INFO - mozscreenshot == 0.1 22:48:05 INFO - mozsystemmonitor == 0.0 22:48:05 INFO - moztest == 0.7 22:48:05 INFO - mozversion == 1.4 22:48:05 INFO - psutil == 3.1.1 22:48:05 INFO - requests == 1.2.3 22:48:05 INFO - wsgiref == 0.1.2 22:48:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:48:05 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')]} 22:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:05 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')]} 22:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:05 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 0x7fd713973f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b42630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd3840>, '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 0x2b406b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40b60>, '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': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', '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 22:48:05 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 22:48:05 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 22:48:05 INFO - Using env: {'DISPLAY': ':0', 22:48:05 INFO - 'HOME': '/home/cltbld', 22:48:05 INFO - 'LANG': 'en_US.UTF-8', 22:48:05 INFO - 'LANGUAGE': 'en_US:en', 22:48:05 INFO - 'LOGNAME': 'cltbld', 22:48:05 INFO - 'MAIL': '/var/mail/cltbld', 22:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:05 INFO - 'MOZ_NO_REMOTE': '1', 22:48:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:48:05 INFO - 'NO_EM_RESTART': '1', 22:48:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:48:05 INFO - 'PWD': '/builds/slave/test-pgo', 22:48:05 INFO - 'SHELL': '/bin/bash', 22:48:05 INFO - 'SHLVL': '1', 22:48:05 INFO - 'TERM': 'linux', 22:48:05 INFO - 'TMOUT': '86400', 22:48:05 INFO - 'USER': 'cltbld', 22:48:05 INFO - 'XDG_SESSION_COOKIE': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', 22:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:05 INFO - '_': '/tools/buildbot/bin/python'} 22:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:05 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)) 22:48:05 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)) 22:48:05 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)) 22:48:05 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)) 22:48:05 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)) 22:48:05 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)) 22:48:05 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)) 22:48:05 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)) 22:48:05 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)) 22:48:05 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)) 22:48:05 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)) 22:48:05 INFO - Cleaning up... 22:48:05 INFO - Return code: 0 22:48:05 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 22:48:05 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')]} 22:48:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:05 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')]} 22:48:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:05 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 0x7fd713973f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b42630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd3840>, '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 0x2b406b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40b60>, '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': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', '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 22:48:05 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 22:48:05 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 22:48:05 INFO - Using env: {'DISPLAY': ':0', 22:48:05 INFO - 'HOME': '/home/cltbld', 22:48:05 INFO - 'LANG': 'en_US.UTF-8', 22:48:05 INFO - 'LANGUAGE': 'en_US:en', 22:48:05 INFO - 'LOGNAME': 'cltbld', 22:48:05 INFO - 'MAIL': '/var/mail/cltbld', 22:48:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:05 INFO - 'MOZ_NO_REMOTE': '1', 22:48:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:48:05 INFO - 'NO_EM_RESTART': '1', 22:48:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:48:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:48:05 INFO - 'PWD': '/builds/slave/test-pgo', 22:48:05 INFO - 'SHELL': '/bin/bash', 22:48:05 INFO - 'SHLVL': '1', 22:48:05 INFO - 'TERM': 'linux', 22:48:05 INFO - 'TMOUT': '86400', 22:48:05 INFO - 'USER': 'cltbld', 22:48:05 INFO - 'XDG_SESSION_COOKIE': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', 22:48:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:05 INFO - '_': '/tools/buildbot/bin/python'} 22:48:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:05 INFO - Downloading/unpacking jsonschema 22:48:05 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 22:48:05 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 22:48:05 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 22:48:05 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 22:48:05 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 22:48:05 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 22:48:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:48:07 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 22:48:07 INFO - Downloading/unpacking functools32 (from jsonschema) 22:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:07 INFO - Downloading functools32-3.2.3-2.tar.gz 22:48:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 22:48:07 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 22:48:07 INFO - warning: no files found matching '*.txt' 22:48:07 INFO - no previously-included directories found matching 'build' 22:48:07 INFO - no previously-included directories found matching 'dist' 22:48:07 INFO - no previously-included directories found matching '.git*' 22:48:07 INFO - Installing collected packages: jsonschema, functools32 22:48:07 INFO - Running setup.py install for functools32 22:48:07 INFO - warning: no files found matching '*.txt' 22:48:07 INFO - no previously-included directories found matching 'build' 22:48:07 INFO - no previously-included directories found matching 'dist' 22:48:07 INFO - no previously-included directories found matching '.git*' 22:48:07 INFO - Successfully installed jsonschema functools32 22:48:07 INFO - Cleaning up... 22:48:07 INFO - Return code: 0 22:48:07 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 22:48:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:48:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:48:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:48:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd713973f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a728a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b42630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bd3840>, '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 0x2b406b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40b60>, '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': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', '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 22:48:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 22:48:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:48:07 INFO - Using env: {'DISPLAY': ':0', 22:48:07 INFO - 'HOME': '/home/cltbld', 22:48:07 INFO - 'LANG': 'en_US.UTF-8', 22:48:07 INFO - 'LANGUAGE': 'en_US:en', 22:48:07 INFO - 'LOGNAME': 'cltbld', 22:48:07 INFO - 'MAIL': '/var/mail/cltbld', 22:48:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:07 INFO - 'MOZ_NO_REMOTE': '1', 22:48:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:48:07 INFO - 'NO_EM_RESTART': '1', 22:48:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:48:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:48:07 INFO - 'PWD': '/builds/slave/test-pgo', 22:48:07 INFO - 'SHELL': '/bin/bash', 22:48:07 INFO - 'SHLVL': '1', 22:48:07 INFO - 'TERM': 'linux', 22:48:07 INFO - 'TMOUT': '86400', 22:48:07 INFO - 'USER': 'cltbld', 22:48:07 INFO - 'XDG_SESSION_COOKIE': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', 22:48:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:07 INFO - '_': '/tools/buildbot/bin/python'} 22:48:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:48:08 INFO - Downloading/unpacking flake8 22:48:08 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 22:48:08 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 22:48:08 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 22:48:08 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 22:48:08 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 22:48:08 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 22:48:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:48:09 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 22:48:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:48:09 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 22:48:09 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 22:48:09 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 22:48:09 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 22:48:09 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 22:48:09 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 22:48:10 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:48:10 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 22:48:10 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:10 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 22:48:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:48:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:48:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:48:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 22:48:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 22:48:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:48:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:48:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:48:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:48:10 INFO - no previously-included directories found matching 'docs/_build' 22:48:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:48:10 INFO - Running setup.py install for pep8 22:48:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:48:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:48:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:48:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:48:10 INFO - no previously-included directories found matching 'docs/_build' 22:48:10 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 22:48:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:48:10 INFO - Cleaning up... 22:48:10 INFO - Return code: 0 22:48:10 INFO - Running post-action listener: _resource_record_post_action 22:48:10 INFO - Running post-action listener: _start_resource_monitoring 22:48:10 INFO - Starting resource monitoring. 22:48:10 INFO - ##### 22:48:10 INFO - ##### Running install step. 22:48:10 INFO - ##### 22:48:10 INFO - Running pre-action listener: _resource_record_pre_action 22:48:10 INFO - Running main action method: install 22:48:10 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:48:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:48:11 INFO - Reading from file tmpfile_stdout 22:48:11 INFO - Detecting whether we're running mozinstall >=1.0... 22:48:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 22:48:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 22:48:11 INFO - Reading from file tmpfile_stdout 22:48:11 INFO - Output received: 22:48:11 INFO - Usage: mozinstall [options] installer 22:48:11 INFO - Options: 22:48:11 INFO - -h, --help show this help message and exit 22:48:11 INFO - -d DEST, --destination=DEST 22:48:11 INFO - Directory to install application into. [default: 22:48:11 INFO - "/builds/slave/test-pgo"] 22:48:11 INFO - --app=APP Application being installed. [default: firefox] 22:48:11 INFO - mkdir: /builds/slave/test-pgo/build/application 22:48:11 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'] 22:48:11 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 22:48:26 INFO - Reading from file tmpfile_stdout 22:48:26 INFO - Output received: 22:48:26 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:48:26 INFO - Running post-action listener: _resource_record_post_action 22:48:26 INFO - ##### 22:48:26 INFO - ##### Running run-tests step. 22:48:26 INFO - ##### 22:48:26 INFO - Running pre-action listener: _resource_record_pre_action 22:48:26 INFO - Running main action method: run_tests 22:48:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:48:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:48:26 INFO - Python 2.7.3 22:48:26 INFO - Return code: 0 22:48:26 INFO - grabbing minidump binary from tooltool 22:48:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:48:26 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 0x2bd3840>, '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 0x2b406b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b40b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:48:26 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 22:48:26 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 22:48:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:48:26 INFO - Return code: 0 22:48:26 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:48:26 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:48:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:48:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:48:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:48:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 22:48:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 22:48:27 INFO - Return code: 0 22:48:27 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test-pgo/build 22:48:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:48:27 INFO - Using env: {'DISPLAY': ':0', 22:48:27 INFO - 'HOME': '/home/cltbld', 22:48:27 INFO - 'LANG': 'en_US.UTF-8', 22:48:27 INFO - 'LANGUAGE': 'en_US:en', 22:48:27 INFO - 'LOGNAME': 'cltbld', 22:48:27 INFO - 'MAIL': '/var/mail/cltbld', 22:48:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:48:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:48:27 INFO - 'MOZ_NO_REMOTE': '1', 22:48:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:48:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:48:27 INFO - 'NO_EM_RESTART': '1', 22:48:27 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:48:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:48:27 INFO - 'PWD': '/builds/slave/test-pgo', 22:48:27 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:48:27 INFO - 'SHELL': '/bin/bash', 22:48:27 INFO - 'SHLVL': '1', 22:48:27 INFO - 'TERM': 'linux', 22:48:27 INFO - 'TMOUT': '86400', 22:48:27 INFO - 'USER': 'cltbld', 22:48:27 INFO - 'XDG_SESSION_COOKIE': 'a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314', 22:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:48:27 INFO - '_': '/tools/buildbot/bin/python'} 22:48:27 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:48:28 INFO - mozversion application_buildid: 20151221203241 22:48:28 INFO - mozversion application_changeset: b96e844b0289c5363b5988214d6343b05a7996e8 22:48:28 INFO - mozversion application_display_name: Nightly 22:48:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:48:28 INFO - mozversion application_name: Firefox 22:48:28 INFO - mozversion application_remotingname: firefox 22:48:28 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:48:28 INFO - mozversion application_vendor: Mozilla 22:48:28 INFO - mozversion application_version: 46.0a1 22:48:28 INFO - mozversion platform_buildid: 20151221203241 22:48:28 INFO - mozversion platform_changeset: b96e844b0289c5363b5988214d6343b05a7996e8 22:48:28 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:48:28 INFO - mozversion platform_version: 46.0a1 22:48:28 INFO - using testdate: 1450766908 22:48:28 INFO - actual date: 1450766908 22:48:28 INFO - starting webserver on 'localhost:43745' 22:48:28 INFO - SUITE-START | Running 3 tests 22:48:28 INFO - TEST-START | dromaeo_css 22:48:28 INFO - operating with platform_type : linux_ 22:48:28 INFO - Initialising browser for dromaeo_css test... 22:48:28 INFO - TEST-INFO | started process 14495 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpItGsEu/profile http://localhost:43745/getInfo.html) 22:48:32 INFO - PROCESS | 14495 | JavaScript error: resource://app/modules/BrowserUITelemetry.jsm, line 538: ReferenceError: assignment to undeclared variable item 22:48:33 INFO - PROCESS | 14495 | __metrics Screen width/height:1600/1200 22:48:33 INFO - PROCESS | 14495 | colorDepth:24 22:48:33 INFO - PROCESS | 14495 | Browser inner width/height: 1024/697 22:48:33 INFO - PROCESS | 14495 | __metrics 22:48:33 INFO - PROCESS | 14495 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:48:34 INFO - TEST-INFO | 14495: exit 0 22:48:34 INFO - Browser initialized. 22:48:34 INFO - Running cycle 1/1 for dromaeo_css test... 22:48:34 INFO - TEST-INFO | started process 14627 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpItGsEu/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:48:46 INFO - PROCESS | 14627 | RSS: Main: 150728704 22:48:46 INFO - PROCESS | 14627 | 22:49:36 INFO - PROCESS | 14627 | 1450766976268 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] 22:51:44 INFO - PROCESS | 14627 | Cycle 1(1): loaded http://localhost:43745/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43745/tests/dromaeo/cssquery-ext.html) 22:51:45 INFO - PROCESS | 14627 | RSS: Main: 157982720 22:51:45 INFO - PROCESS | 14627 | 22:54:47 INFO - PROCESS | 14627 | Cycle 1(1): loaded http://localhost:43745/tests/dromaeo/cssquery-ext.html (next: http://localhost:43745/tests/dromaeo/cssquery-jquery.html) 22:54:47 INFO - PROCESS | 14627 | RSS: Main: 153423872 22:54:47 INFO - PROCESS | 14627 | 22:57:49 INFO - PROCESS | 14627 | Cycle 1(1): loaded http://localhost:43745/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43745/tests/dromaeo/cssquery-mootools.html) 22:57:50 INFO - PROCESS | 14627 | RSS: Main: 153657344 22:57:50 INFO - PROCESS | 14627 | 23:00:51 INFO - PROCESS | 14627 | Cycle 1(1): loaded http://localhost:43745/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43745/tests/dromaeo/cssquery-prototype.html) 23:00:52 INFO - PROCESS | 14627 | RSS: Main: 152932352 23:00:52 INFO - PROCESS | 14627 | 23:03:54 INFO - PROCESS | 14627 | Cycle 1(1): loaded http://localhost:43745/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43745/tests/dromaeo/cssquery-yui.html) 23:03:55 INFO - PROCESS | 14627 | RSS: Main: 152399872 23:03:55 INFO - PROCESS | 14627 | 23:06:57 INFO - PROCESS | 14627 | Cycle 1(1): loaded http://localhost:43745/tests/dromaeo/cssquery-yui.html (next: http://localhost:43745/tests/dromaeo/cssquery-dojo.html) 23:06:58 INFO - PROCESS | 14627 | RSS: Main: 155361280 23:06:58 INFO - PROCESS | 14627 | 23:06:58 INFO - PROCESS | 14627 | __start_tp_report 23:06:58 INFO - PROCESS | 14627 | _x_x_mozilla_page_load 23:06:58 INFO - PROCESS | 14627 | _x_x_mozilla_page_load_details 23:06:58 INFO - PROCESS | 14627 | |i|pagename|runs| 23:06:58 INFO - PROCESS | 14627 | |0;dojo.html;3223.564818749469;3262.7879187852786;3299.6040475142986;3301.4387554115797;3309.5697559317287;5383.488568585978;5411.215373770801;5452;5471.179323101537;5471.863203419916;5442.673439593617;5457.208704737821;5508.091164957777;5509.972450137744;5681.460261275182;4986.626003049768;5052.873678158057;5131.897362052757;5157.5358217760395;5187.507186817246;92.95047545171259;5041.747912604374;5066.518680725344;5074.112030394685;5123.641345105844;5057.848264552069;5085.364329458817;5088.949110508885;5102.234664800272;5108.029474399889;4867.099586576459;5090.9236361454605;5092.337996060489;5112.948870511284;5130.050940576006;4902.142125128088;4967.180415231483;4986.800527978877;5010.649254552182;5011.899762004776;5312;5323.441038690959;5362.65142765719;5363.302770639792;5410.756515956752;5839.299284085894;5898.4101589840675;5910.379410161914;5963.4334738199805;5972.820815375546;5914.585978981473;5967.015442451991;5983.311919129313;6008.459238668541;6038.788642397539;5507.063799154113;5615.7472913718575;5621.662700237958;5627.380988091348;5635.3237611486475;5854.385289544622;5876.177335173079;5983.282006159202;6070.605410648293;6079.544034197453;5777.306723193244;5780.797672081457;5790.36306006339;5792.160136780143;5847.44449277318;5838.620489668158;5882.764689412461;5884;5891.322497912709;5920.259967504062;5703.885922281531;5821.53427725781;5824.504917082011;5845.210896528999;5945.435183657545;5931.881362372728;5944.3164036136395;5969.791057312973;5978.491828194565;5982.581219314607;4535.682502224812;4591.563801438858;4591.609713174351;4594.5864872161665;4623.9075218495445;3994.08136128692;4014.6788256939376;4080.6735461163034;4093.6520395766097;4125;5631.971840140773;5676.574256930747;5691.117876729031;5695.601307908407;5716.314042314949;6008.429199226067;6020.337762846083;6025;6029.879402411927;6035.823014512216;5390.595705322116;5455.509004189563;5509.256250406224;5523.475269849358;5531.668099914018;5026.170681837529;5080.212567052113;5136.743162841918;5146.150885103991;5192.143296356135;6081.057436097412;6081.361457046946;6096.451319381188;6102.511799056065;6121.510279177655;5844.912326315194;5942.61373010753;6001.2498437695285;6001.459868611846;6041.6375017499095;6024.3975602439405;6121.2700689216845;6166.044263139221;6167;6167.321594624498;5905.704714764159;5912;5950.16697662319;5971.910421343597;6032.065029920369;5864.32560255576;5954.553408494294;6070.453659170696;6080.48315893154;6083.482903953213;5729.197912292366;5805;5817.912731308949;5822.796202132905;5907;5744.569157313302;5790;5813.854653633693;5823.8835223294445;5873.735681894411;5451.972740136274;5602.747876345493;5639.689817059937;5681.9147712783515;5686.43135686428;5762.94237057624;5844.064949608042;5862.325832529314;5873.823785286449;5895.64626122434;5637.830865074055;5718.1136923776885;5719.428057194414;5722.828315150552;5726.5418766499415 23:06:58 INFO - PROCESS | 14627 | |1;ext.html;547.6091596565612;587.3128439725521;591.6538824787499;615.4860691322746;642.9292777794442;30103.698963010364;37563.248735025285;37825.621743782554;37839.81080094603;37929.43105853407;42660.786696066476;43247.78376108123;43514.56485435145;43564.34653480192;43567.56432435683;8662.30701543878;8793.824123517526;8798.912010879905;8839.24866386358;8849.734507964738;8520.318374530054;8587.098354672764;8613.741587752378;8635.568221588926;8640;7718.884216736754;7732.536047837148;7758.069031716202;7762.30139287464;7828.256315650005;8662.913370866274;8683.044865064869;8709.08554601764;8729.170728780788;8753.168448997336;8598.312135029182;8668.26319762821;8689.869651955225;8787.384883058188;8792.944846618382;39935.40096898549;40354.596454035374;40505.79747101275;40689.38965915484;40716.38925416121;3367.3938691035605;3487.2851065531504;3626;3855.306044911915;3956.059305968503;29951.101466956025;30285.69714302851;30653;31644.050678479452;31963.520547191813;3784.3377408953324;3806.7525610835205;3811.4663947047434;3817.1793064491267;3877.1470276539117;257587;259256.7037164802;259685;260858.69570652215;263361;53241.73379133079;54078.91842163214;59672.70163649154;59920.70039649861;62262.37737622566;55721.442785572435;55823;55924;56097.71951140218;56160.43839561552;16110.919445402933;16188.02871827694;16253.10607916576;16291.348346066261;16314.83685163157;12210.816837747441;12236.327002014797;12269.93865030669;12294.815577766341;12383.566575169825;19737.901310493355;20724.067416966274;20726.96365181735;20845.374638760863;20912;7920.32677222431;8224.958875205706;8227.629756660965;8229.79425514355;8240;819.7417813388763;892.3307519360479;895.6999405199331;1021.4535223655308;1092.8633920759905;24711.5057698845;24997;25028.874855625607;25061;26781.86609066942;996.9446869296071;1077.7682798198425;1138.9316641001567;1141.2182654881433;1170.9590164344206;14254.501092461922;14306.783923366631;14527.346269417902;14552.781708274384;15690;328058;328221;328918;329756;330369;51912;52244.73877630587;52299.21551176659;52318.738406307726;52451;1367.1591970938;1374.2098293481288;1385.6674398144573;1385.9584212473642;1388.3544151969384;28357.716422836333;29020.419591608472;29323.560146598244;29631.85184074152;29866.402671946005;46139.3079103807;46855.53144468512;46890.76554617205;46989.53010469852;47139.29291060568;289666;291924;292315;295030;296774;30764;33682.8315858429;33729.83135084309;33963.4905476423;34033.14917127092;40551.18897621972;40614.796926016366;40688.77933661995;40856.1828763429;41540.376894346;34695.82652086723;35095.649043509235;35179.824100879334;35597;36059.27881442304;35306.293874122886;35653.64346356607;35931.28137437289;36066.09834754152;36079.81960090183;34064.48903266403;35133.648663513035;35199.64800351964;35221.29557408889;35274.11814704653;29497.557536636537;32040.198995025312;32100.35799284048;32251.193720157185;32291;24389;24623.50752984966;24905.377365566004;25125.371865703502;26412.603810942466 23:06:58 INFO - PROCESS | 14627 | |2;jquery.html;360.5691199017174;362.12727327141596;363.1502284653909;365.83720244491195;372.3446733748602;16587.253573589187;17376.82623173768;17578.64842703145;18000.189991450385;18410.72383914239;10319.690409287714;10617.41604211768;15797.28912198951;15920.363185472566;15920.442784502544;4814.133455977913;4963.429205641343;5114.820981265657;5119.616028797836;5124.641275110744;4615.93076103857;5108.770105345268;5113.642045056848;5129.923051154217;5761.164631128484;5686.31764188298;5686.402927692595;5689.971550142264;5694.117411801157;5726.284214473191;5743.684097374643;5967.104934259853;5973.193618861442;5973.283206015262;5973.522118230531;5226.189940559219;5287.339082614673;5337.492938170868;5340.359156901158;5341.359036915595;11497.25267857595;11591.536338546448;11678.24091434054;11742.236754610924;11759.647210583697;2110.905009274571;2152.494163871489;2159.8596091254176;2171.1423987524995;2222.8888555572157;9075.228605568534;9196.724098276996;9312.068793120701;9621.807563848753;11537.538498460135;1844.041098628719;1854.1378259109445;1877.3616970230187;1891.933782317626;2011.1352118589;61721.6913915432;62962.05556916696;63508.36491635117;63581.364186358005;63894;10024.047715467019;10207;10241.385516869012;10249.692509224735;10290.279680422449;12303.384830758605;12557.372131393393;13088.934555327352;13106.60680179596;23528.176513821934;8836.204741573161;8890.644374225089;8895.4662720237;8940.955295223612;9056.954715226382;7998.680052797939;8027.2775450209765;8129.227723366271;8250.133735957757;8468.195521425454;7681.193474685189;7746.41901857363;7776.572288524186;7784.688612455551;7794.727184548514;3202.775805693626;3203.8878639247982;3227.193201699575;3268.591426071741;3279.475283954556;401.4740689696507;851.2849206666306;963.6193703487223;967.298708436378;1047.4710271312938;10238.436885971194;10710.946445267724;10737.194710396752;16257.268422920997;16991.915040424967;892.8035832116924;955.1451450951498;975.512243878061;1008.6167256442504;1028.2596530498029;8707.216350528483;8937.240334571505;9256.814863702688;9334.113259240361;9467.289953253534;523491;530417;536127;543388;549557;78157;83047.16952830394;83862;84211.57894210612;84744.15255847486;1128.6670432222513;1156.5894107591853;1162.5001249462644;1166.5742004168587;1172.9970874902006;15591.454299099478;15668;15725.606859828596;15790.131542765259;15959.44141955026;33340.499892501146;34381.82809085939;34942.30115397627;36005.27989440249;36354;38344.42483362807;39314;39542.40686389649;39751.60248397479;40303;9242.214411775074;9309.860352094589;9442.669506567237;9514;9523.714298569881;15385.230738462807;16739.163041848104;17084;17448.21483033292;17861.374851880122;14425.567232983027;14604.634884127981;14868.62828429298;15241.466548670744;15310;14727.705445890808;14751.188684622019;14922.925385373004;15108.169050702318;15344.616384590474;15503.069815811088;15603;15654.295556699748;15832.208389580705;15855.365785368456;9497.525123743648;9527.761805954906;9574.425534467955;9604.711858644252;9932.503374831085;10437.425941572983;10451.163906887583;10487.895121048692;13805.447782089006;14414.279286035446 23:06:58 INFO - PROCESS | 14627 | |3;mootools.html;1480.3190532355115;1709.2393884721298;1767.805541390447;1782.9732554011691;1785.5893144576746;7509.924900750998;7565.773026809191;7796.298333150015;7960.363170946318;7981.161977992307;7784.805379865506;7810.453268271223;7859.135495095535;7871.527708337504;7892.486988345753;2237.429455488852;2253.188852013274;2262.66060090986;2266.5580211858696;2269.727632684072;2307.6999990001277;2312.3063081075693;2376.310869847742;2377.9048838046456;2380.321608341623;2251.2233279518623;2254.6392577187653;2270.2962081754627;2285.131649973008;2316.9536609267802;2244.8204143668468;2284.1206135637817;2285.245868863288;2293.1629955066364;2306.273523839985;2323.9767602323927;2335.3344296875307;2359.55168517981;2361.1027809432476;2361.480474295652;7618.504797188165;7631.046119235096;7672.769816905502;7675.462717609769;7695.230476952316;4653.418322709661;4738.549837765407;4748.240281554969;4749.4063242094735;4750.928736068961;2962.5408061750463;2963.466576016316;3067.1412004638737;3068.70847269509;3107.9533806992913;912.7353067610385;925.7546750111226;945.7871978804783;952.9904700952972;970.7233438470003;10477.58089676412;10508.474576271232;10515.84226236607;10573.9471302643;10581.576736930514;4129.772862492532;4182.393552934868;4182.665386769051;4207.095474473003;4213.789310534461;8530.360222983301;8540.188682075193;8550.444221125606;8563.357748168912;8587.699430519902;4663.393758811333;4666.3700400445505;4679.391679081766;4681.063787242565;4683.391159149289;4233.957660423418;4250.298700714347;4272;4276.465441819772;4286.7856607169515;3440.8795692150657;3490.7207423406066;3502.877399291013;3505.9123521911647;3515.261795023023;1037.9879617373151;1039.0440794468955;1057.5505410200633;1062.8140075486758;1085.2511766880984;1073.3774410841693;1094.6606551968755;1136.7840110379002;1263.3430616079588;1358.5652591170929;7179.461540384387;7180.748673796392;7193.352598266181;7265.600391978492;7330.193678695339;995.5221493683073;1029.351508549609;1139.1627154041773;1142.2289954280993;1154.7113221694576;5789.884202316014;5794.985877471426;5829.941700583025;5836.066229403275;5854.268216472941;9143.817123657489;9183.58673859678;9294.86057709135;9367.156955874005;9426.670066547775;9065.77335566616;9192.080791920755;9231.123043310874;9235.953820230856;9265.629374824932;7008.544444611084;7100.325469080326;7136.892946605805;7185.353318201387;7278.745243916437;7235.746748863765;7306.744263950736;7309.378702810321;7315.158756743042;7389.039424874732;8065.83868322642;8269.131741167092;8333.458325208841;8392.66429342844;8494.957525212334;8211.096779354148;8357.791055223668;8427.747167584983;8429.283510901641;8494.405391622528;6809.080774095632;6981.162260528769;6985.615791131537;7000.61496617691;7003.124609423822;7440.2931721486375;7481.214472480311;7481.962590187014;7507.512011719221;7581.886271705818;6804.693788779417;6942.097527321416;7025.051618031501;7152.392046675883;7163.6060016699585;6842.110525631636;6894.965525172342;6918.066061081689;6925.3074692530345;6986.301369863177;6856.485763567852;6874.553154044971;6890.758823441155;6935.653217339213;6960.756373526902;6570.47436205112;6589.209294884645;6625.503087268377;6646.700898459678;6650.434713049445;6278.717457714322;6579.276279609335;6625.900611490928;6627.536072474881;6637.767678131242 23:06:58 INFO - PROCESS | 14627 | |4;prototype.html;341.6975976261009;345.5645886183408;349.3693882542014;349.7883780312911;354.31970616416464;6249.843753906154;6310.779122730705;6327.841803954903;6402.551821372505;6426.2931077581425;6165.506759459244;6310.716017779199;6310.936890631092;6331.240251169855;6348.968255158729;4815.157347464197;4827.227643577028;4841.370621819158;4845.103655823684;4849.321095046696;4804.687695299812;4838.153323168449;4849.321095046696;4852.150873597124;4853.369062021932;4702.5297470253045;4824.445188803297;4832.8884356598;4834.033193361342;4838.9032219355595;4805.27920811881;4807.423109226897;4809.615230781546;4814.831480898151;4820.975895120502;4221.97889010553;4248.277792775205;4251.341042138442;4251.74489530626;4254.42565253693;6013.218281623404;6037.698115094227;6045.697715114226;6050.54620903434;6064.211652485193;5363.704996225206;5536.390996990328;5538.529225015878;5540.473655002768;5543.528800052;6727.360900714424;6728.596284222914;6734.932650673529;6737.3262673732725;6749.7300107995625;4541.977290113528;4550.362949187116;4550.726956382595;4552.931706024412;4554.8861278467975;6755.392014718699;6926.861462770717;6959.930400696029;6980.267071957423;7024.578525288499;4647.674662773574;4664.253719404879;4665.4168228971375;4671;4687.296905464208;4672.649551283599;4721.693089949143;4726.1492931272705;4729.077829823152;4745.454272758677;5506.614536982373;5516.889662206733;5522.392536820947;5522.420145884705;5525.336959564878;6557.114789503356;6750.257471678112;6756.189257289157;6760.039588519373;6766.086578311963;1648.4972083514403;1680.983190168107;1686.9625180513895;1694.1275243249736;1696.151924037981;1123.6516679829115;1132.9037031852386;1132.9546818127183;1138.1691365303209;1145.4330106597122;6371.585846919936;6447.580907241015;6447.903281450782;6467.417932386108;6480.157579514633;6876.69054892531;6893.724251030003;6903.861922761617;6916.377526022682;6920.619365934922;6348.396902294275;6364.299927008118;6407.615543067431;6421.967890160613;6464.353564643498;3784.0729021389934;3812.5615554211067;3817.656410922975;3819.6753275971855;3832.329342365075;7162.4628152888745;7171.175314838861;7210.351068403869;7213.062301900719;7229.2409297024105;7065.540739851893;7211.170715367695;7231.819204519824;7238.819029524304;7273.854522909618;7138.143422789195;7190.137183537904;7201.243869393743;7208.441003443807;7217.675204615805;7071.858562828612;7086.751963681247;7090.822729431599;7094.929050709633;7102.644867756489;6842.657867106725;6994.370506654425;6997.510174287751;7000.824979375557;7005.54463959841;7149.9285007149265;7189.532680375759;7204.24355442671;7209.711611535689;7213.747518836815;4620.676552641283;4650.093231819763;4652.720836749806;4657.93013104797;4659.277811939155;6765.797026089225;6909.861802763816;6970.65146742671;7054.329838665319;7055.400290975324;6752.155980502437;6873.518853680194;6894.586324820527;6897.827554311182;6905.585664860125;6783.864322713617;6831.180258369028;6847.383735463832;6864.656767161722;6873.518853680194;6544.411003009752;6691.1636045494315;6708.798736037927;6721.227058888095;6730.461563074844;6876.106106206161;6885.621290829053;6891.27641597625;6892.207396149507;6894.620795856276;6603.108580341593;6643.50173736962;6650.20197576294;6693.297203793726;6700.162479690039 23:06:58 INFO - PROCESS | 14627 | |5;yui.html;722.2957616324085;826.29351904122;832.1553623072582;862.5213006781233;870.8693695945611;13573.592792216237;13711;13729.31353432328;13780.242086685228;13825.930870345659;13390.464381424756;13395;13484;13502.122362046484;13511.932440337809;825.9958700206506;829.4276948905266;857.7769779857219;858.4205661178711;860.2129051917519;845.4631309118693;852.2457625001856;852.5183271451641;853.6585365853678;856.7643897928089;867.6832955971057;873.9475631462133;874.5059041641484;874.5845723281423;880.4673172730502;865.5023361567092;867.9783005424852;868.4485351801651;869.8042940338437;871.7384784564605;645.3546453546453;645.8353119954381;647.5046589359144;648.3127884442506;649.1431310669917;13953.511627093105;14109.223992680398;14128.293585320796;14226.92886535571;14236.217008064552;12021;12277.140600157993;12509.74980500394;12606.306653134074;13060.085793994424;10352.896471035343;10652.573897044109;10822.458877056115;10924.465435894414;11089.500972456259;3588.4331673038537;3634.7819130852236;3662.9450558241647;3731.850725970985;3755.192633583765;48466.75766621179;48545.99999999964;48936.51063489355;49081.50918490805;49141.50858491405;9390.201832844285;9493.477858717837;9829.80340393188;9950.45272510019;10088.243381746399;16615.916920415322;16679.16604169811;16686.666266674598;16724.163791810362;16731.6653666926;7356.337929586363;7394.704211831574;7575.280348366897;7582.54504729718;7767.650455729506;4153.460050193456;4252.702310838274;4260.552641972548;4357.2592659248;4361.236783562864;3151.8581663824725;3173.4129186100645;3176.12656519459;3177.7616678749187;3178.7774855760335;577.2063016646789;588.6938791828226;593.8841925824423;596.8120042186697;609.2080295615682;589.2722487727652;594.3877805859885;633.3792882974618;647.2168675902211;711.0507472524134;8853.64585416589;8879.24526415262;8910.242629376446;9037.728868133967;9045.68340108093;523.8480840556272;631.2992578238152;657.6350125680293;683.7675190435176;696.8153439338579;3830.559485659129;3872.5740168581433;3901.9804900975314;3916.1971795781797;3922.1175235572337;56380.718096409255;56735.71632141813;56809.43190568124;56973.71513142408;57337;50527;50600.493995060315;50829.491705083215;50929.490705093216;52311.47688523066;46000.99999999933;46238.768806156426;46407.30389044167;47018.76490617594;47281.52718472771;8534.871976920227;8574.82850343002;8598;8630.741077767678;8681.479111253346;33566.83216583901;33719.83140084382;34300.828495857364;34696;35119.824400877835;41717.16565668609;44111.77944110259;44287.33568996532;44424.33363499615;44465.55534444614;9954.850677239703;10518.78962420732;10583.994520520539;10600.522976465923;10814.188935830001;11255.831162532731;11265.414513548174;11328.376939268419;11346.773064538827;11591.536338546364;10103.191744660557;10145.84781228297;10203.897961020592;10311.175105991353;10372.377657340583;9311.394759340621;9333.486658233862;9530.904690953279;9569.330146889652;9908.959559246176;10310.639127630497;10618.469076546296;10717.464126793786;10926.672199834018;11129.888701112885;9294.814103718023;9500.42997420157;9614.90385096168;9676.22590192797;9829;9119.669464376782;9457.810843783223;9905.455199963782;10082.344647597884;10210.336328138646 23:06:58 INFO - PROCESS | 14627 | __end_tp_report 23:06:58 INFO - PROCESS | 14627 | __start_cc_report 23:06:58 INFO - PROCESS | 14627 | _x_x_mozilla_cycle_collect,330 23:06:58 INFO - PROCESS | 14627 | __end_cc_report 23:06:58 INFO - PROCESS | 14627 | __startTimestamp1450768018177__endTimestamp 23:06:58 INFO - PROCESS | 14627 | 23:06:58 INFO - PROCESS | 14627 | ------- Summary: start ------- 23:06:58 INFO - PROCESS | 14627 | Number of tests: 6 23:06:58 INFO - PROCESS | 14627 | 23:06:58 INFO - PROCESS | 14627 | [#0] dojo.html Cycles:165 Average:5477.07 Median:5719.43 stddev:744.53 (13.0%) stddev-sans-first:725.53 23:06:58 INFO - PROCESS | 14627 | Values: 3223.6 3262.8 3299.6 3301.4 3309.6 5383.5 5411.2 5452.0 5471.2 5471.9 5442.7 5457.2 5508.1 5510.0 5681.5 4986.6 5052.9 5131.9 5157.5 5187.5 93.0 5041.7 5066.5 5074.1 5123.6 5057.8 5085.4 5088.9 5102.2 5108.0 4867.1 5090.9 5092.3 5112.9 5130.1 4902.1 4967.2 4986.8 5010.6 5011.9 5312.0 5323.4 5362.7 5363.3 5410.8 5839.3 5898.4 5910.4 5963.4 5972.8 5914.6 5967.0 5983.3 6008.5 6038.8 5507.1 5615.7 5621.7 5627.4 5635.3 5854.4 5876.2 5983.3 6070.6 6079.5 5777.3 5780.8 5790.4 5792.2 5847.4 5838.6 5882.8 5884.0 5891.3 5920.3 5703.9 5821.5 5824.5 5845.2 5945.4 5931.9 5944.3 5969.8 5978.5 5982.6 4535.7 4591.6 4591.6 4594.6 4623.9 3994.1 4014.7 4080.7 4093.7 4125.0 5632.0 5676.6 5691.1 5695.6 5716.3 6008.4 6020.3 6025.0 6029.9 6035.8 5390.6 5455.5 5509.3 5523.5 5531.7 5026.2 5080.2 5136.7 5146.2 5192.1 6081.1 6081.4 6096.5 6102.5 6121.5 5844.9 5942.6 6001.2 6001.5 6041.6 6024.4 6121.3 6166.0 6167.0 6167.3 5905.7 5912.0 5950.2 5971.9 6032.1 5864.3 5954.6 6070.5 6080.5 6083.5 5729.2 5805.0 5817.9 5822.8 5907.0 5744.6 5790.0 5813.9 5823.9 5873.7 5452.0 5602.7 5639.7 5681.9 5686.4 5762.9 5844.1 5862.3 5873.8 5895.6 5637.8 5718.1 5719.4 5722.8 5726.5 23:06:58 INFO - PROCESS | 14627 | 23:06:58 INFO - PROCESS | 14627 | [#1] ext.html Cycles:180 Average:46210.28 Median:35995.28 stddev:77207.16 (214.5%) stddev-sans-first:77347.62 23:06:58 INFO - PROCESS | 14627 | Values: 547.6 587.3 591.7 615.5 642.9 30103.7 37563.2 37825.6 37839.8 37929.4 42660.8 43247.8 43514.6 43564.3 43567.6 8662.3 8793.8 8798.9 8839.2 8849.7 8520.3 8587.1 8613.7 8635.6 8640.0 7718.9 7732.5 7758.1 7762.3 7828.3 8662.9 8683.0 8709.1 8729.2 8753.2 8598.3 8668.3 8689.9 8787.4 8792.9 39935.4 40354.6 40505.8 40689.4 40716.4 3367.4 3487.3 3626.0 3855.3 3956.1 29951.1 30285.7 30653.0 31644.1 31963.5 3784.3 3806.8 3811.5 3817.2 3877.1 257587.0 259256.7 259685.0 260858.7 263361.0 53241.7 54078.9 59672.7 59920.7 62262.4 55721.4 55823.0 55924.0 56097.7 56160.4 16110.9 16188.0 16253.1 16291.3 16314.8 12210.8 12236.3 12269.9 12294.8 12383.6 19737.9 20724.1 20727.0 20845.4 20912.0 7920.3 8225.0 8227.6 8229.8 8240.0 819.7 892.3 895.7 1021.5 1092.9 24711.5 24997.0 25028.9 25061.0 26781.9 996.9 1077.8 1138.9 1141.2 1171.0 14254.5 14306.8 14527.3 14552.8 15690.0 328058.0 328221.0 328918.0 329756.0 330369.0 51912.0 52244.7 52299.2 52318.7 52451.0 1367.2 1374.2 1385.7 1386.0 1388.4 28357.7 29020.4 29323.6 29631.9 29866.4 46139.3 46855.5 46890.8 46989.5 47139.3 289666.0 291924.0 292315.0 295030.0 296774.0 30764.0 33682.8 33729.8 33963.5 34033.1 40551.2 40614.8 40688.8 40856.2 41540.4 34695.8 35095.6 35179.8 35597.0 36059.3 35306.3 35653.6 35931.3 36066.1 36079.8 34064.5 35133.6 35199.6 35221.3 35274.1 29497.6 32040.2 32100.4 32251.2 32291.0 24389.0 24623.5 24905.4 25125.4 26412.6 23:06:58 INFO - PROCESS | 14627 | 23:06:58 INFO - PROCESS | 14627 | [#2] jquery.html Cycles:180 Average:28574.82 Median:18358.58 stddev:87728.93 (477.9%) stddev-sans-first:87949.46 23:06:58 INFO - PROCESS | 14627 | Values: 360.6 362.1 363.2 365.8 372.3 16587.3 17376.8 17578.6 18000.2 18410.7 10319.7 10617.4 15797.3 15920.4 15920.4 4814.1 4963.4 5114.8 5119.6 5124.6 4615.9 5108.8 5113.6 5129.9 5761.2 5686.3 5686.4 5690.0 5694.1 5726.3 5743.7 5967.1 5973.2 5973.3 5973.5 5226.2 5287.3 5337.5 5340.4 5341.4 11497.3 11591.5 11678.2 11742.2 11759.6 2110.9 2152.5 2159.9 2171.1 2222.9 9075.2 9196.7 9312.1 9621.8 11537.5 1844.0 1854.1 1877.4 1891.9 2011.1 61721.7 62962.1 63508.4 63581.4 63894.0 10024.0 10207.0 10241.4 10249.7 10290.3 12303.4 12557.4 13088.9 13106.6 23528.2 8836.2 8890.6 8895.5 8941.0 9057.0 7998.7 8027.3 8129.2 8250.1 8468.2 7681.2 7746.4 7776.6 7784.7 7794.7 3202.8 3203.9 3227.2 3268.6 3279.5 401.5 851.3 963.6 967.3 1047.5 10238.4 10710.9 10737.2 16257.3 16991.9 892.8 955.1 975.5 1008.6 1028.3 8707.2 8937.2 9256.8 9334.1 9467.3 523491.0 530417.0 536127.0 543388.0 549557.0 78157.0 83047.2 83862.0 84211.6 84744.2 1128.7 1156.6 1162.5 1166.6 1173.0 15591.5 15668.0 15725.6 15790.1 15959.4 33340.5 34381.8 34942.3 36005.3 36354.0 38344.4 39314.0 39542.4 39751.6 40303.0 9242.2 9309.9 9442.7 9514.0 9523.7 15385.2 16739.2 17084.0 17448.2 17861.4 14425.6 14604.6 14868.6 15241.5 15310.0 14727.7 14751.2 14922.9 15108.2 15344.6 15503.1 15603.0 15654.3 15832.2 15855.4 9497.5 9527.8 9574.4 9604.7 9932.5 10437.4 10451.2 10487.9 13805.4 14414.3 23:06:58 INFO - PROCESS | 14627 | 23:06:58 INFO - PROCESS | 14627 | [#3] mootools.html Cycles:180 Average:5426.62 Median:6632.65 stddev:2779.94 (41.9%) stddev-sans-first:2771.91 23:06:58 INFO - PROCESS | 14627 | Values: 1480.3 1709.2 1767.8 1783.0 1785.6 7509.9 7565.8 7796.3 7960.4 7981.2 7784.8 7810.5 7859.1 7871.5 7892.5 2237.4 2253.2 2262.7 2266.6 2269.7 2307.7 2312.3 2376.3 2377.9 2380.3 2251.2 2254.6 2270.3 2285.1 2317.0 2244.8 2284.1 2285.2 2293.2 2306.3 2324.0 2335.3 2359.6 2361.1 2361.5 7618.5 7631.0 7672.8 7675.5 7695.2 4653.4 4738.5 4748.2 4749.4 4750.9 2962.5 2963.5 3067.1 3068.7 3108.0 912.7 925.8 945.8 953.0 970.7 10477.6 10508.5 10515.8 10573.9 10581.6 4129.8 4182.4 4182.7 4207.1 4213.8 8530.4 8540.2 8550.4 8563.4 8587.7 4663.4 4666.4 4679.4 4681.1 4683.4 4234.0 4250.3 4272.0 4276.5 4286.8 3440.9 3490.7 3502.9 3505.9 3515.3 1038.0 1039.0 1057.6 1062.8 1085.3 1073.4 1094.7 1136.8 1263.3 1358.6 7179.5 7180.7 7193.4 7265.6 7330.2 995.5 1029.4 1139.2 1142.2 1154.7 5789.9 5795.0 5829.9 5836.1 5854.3 9143.8 9183.6 9294.9 9367.2 9426.7 9065.8 9192.1 9231.1 9236.0 9265.6 7008.5 7100.3 7136.9 7185.4 7278.7 7235.7 7306.7 7309.4 7315.2 7389.0 8065.8 8269.1 8333.5 8392.7 8495.0 8211.1 8357.8 8427.7 8429.3 8494.4 6809.1 6981.2 6985.6 7000.6 7003.1 7440.3 7481.2 7482.0 7507.5 7581.9 6804.7 6942.1 7025.1 7152.4 7163.6 6842.1 6895.0 6918.1 6925.3 6986.3 6856.5 6874.6 6890.8 6935.7 6960.8 6570.5 6589.2 6625.5 6646.7 6650.4 6278.7 6579.3 6625.9 6627.5 6637.8 23:06:58 INFO - PROCESS | 14627 | 23:06:58 INFO - PROCESS | 14627 | [#4] prototype.html Cycles:180 Average:5629.38 Median:6387.07 stddev:1719.13 (26.9%) stddev-sans-first:1677.52 23:06:58 INFO - PROCESS | 14627 | Values: 341.7 345.6 349.4 349.8 354.3 6249.8 6310.8 6327.8 6402.6 6426.3 6165.5 6310.7 6310.9 6331.2 6349.0 4815.2 4827.2 4841.4 4845.1 4849.3 4804.7 4838.2 4849.3 4852.2 4853.4 4702.5 4824.4 4832.9 4834.0 4838.9 4805.3 4807.4 4809.6 4814.8 4821.0 4222.0 4248.3 4251.3 4251.7 4254.4 6013.2 6037.7 6045.7 6050.5 6064.2 5363.7 5536.4 5538.5 5540.5 5543.5 6727.4 6728.6 6734.9 6737.3 6749.7 4542.0 4550.4 4550.7 4552.9 4554.9 6755.4 6926.9 6959.9 6980.3 7024.6 4647.7 4664.3 4665.4 4671.0 4687.3 4672.6 4721.7 4726.1 4729.1 4745.5 5506.6 5516.9 5522.4 5522.4 5525.3 6557.1 6750.3 6756.2 6760.0 6766.1 1648.5 1681.0 1687.0 1694.1 1696.2 1123.7 1132.9 1133.0 1138.2 1145.4 6371.6 6447.6 6447.9 6467.4 6480.2 6876.7 6893.7 6903.9 6916.4 6920.6 6348.4 6364.3 6407.6 6422.0 6464.4 3784.1 3812.6 3817.7 3819.7 3832.3 7162.5 7171.2 7210.4 7213.1 7229.2 7065.5 7211.2 7231.8 7238.8 7273.9 7138.1 7190.1 7201.2 7208.4 7217.7 7071.9 7086.8 7090.8 7094.9 7102.6 6842.7 6994.4 6997.5 7000.8 7005.5 7149.9 7189.5 7204.2 7209.7 7213.7 4620.7 4650.1 4652.7 4657.9 4659.3 6765.8 6909.9 6970.7 7054.3 7055.4 6752.2 6873.5 6894.6 6897.8 6905.6 6783.9 6831.2 6847.4 6864.7 6873.5 6544.4 6691.2 6708.8 6721.2 6730.5 6876.1 6885.6 6891.3 6892.2 6894.6 6603.1 6643.5 6650.2 6693.3 6700.2 23:06:58 INFO - PROCESS | 14627 | 23:06:58 INFO - PROCESS | 14627 | [#5] yui.html Cycles:180 Average:13638.04 Median:49111.51 stddev:15870.99 (32.3%) stddev-sans-first:15885.88 23:06:58 INFO - PROCESS | 14627 | Values: 722.3 826.3 832.2 862.5 870.9 13573.6 13711.0 13729.3 13780.2 13825.9 13390.5 13395.0 13484.0 13502.1 13511.9 826.0 829.4 857.8 858.4 860.2 845.5 852.2 852.5 853.7 856.8 867.7 873.9 874.5 874.6 880.5 865.5 868.0 868.4 869.8 871.7 645.4 645.8 647.5 648.3 649.1 13953.5 14109.2 14128.3 14226.9 14236.2 12021.0 12277.1 12509.7 12606.3 13060.1 10352.9 10652.6 10822.5 10924.5 11089.5 3588.4 3634.8 3662.9 3731.9 3755.2 48466.8 48546.0 48936.5 49081.5 49141.5 9390.2 9493.5 9829.8 9950.5 10088.2 16615.9 16679.2 16686.7 16724.2 16731.7 7356.3 7394.7 7575.3 7582.5 7767.7 4153.5 4252.7 4260.6 4357.3 4361.2 3151.9 3173.4 3176.1 3177.8 3178.8 577.2 588.7 593.9 596.8 609.2 589.3 594.4 633.4 647.2 711.1 8853.6 8879.2 8910.2 9037.7 9045.7 523.8 631.3 657.6 683.8 696.8 3830.6 3872.6 3902.0 3916.2 3922.1 56380.7 56735.7 56809.4 56973.7 57337.0 50527.0 50600.5 50829.5 50929.5 52311.5 46001.0 46238.8 46407.3 47018.8 47281.5 8534.9 8574.8 8598.0 8630.7 8681.5 33566.8 33719.8 34300.8 34696.0 35119.8 41717.2 44111.8 44287.3 44424.3 44465.6 9954.9 10518.8 10584.0 10600.5 10814.2 11255.8 11265.4 11328.4 11346.8 11591.5 10103.2 10145.8 10203.9 10311.2 10372.4 9311.4 9333.5 9530.9 9569.3 9909.0 10310.6 10618.5 10717.5 10926.7 11129.9 9294.8 9500.4 9614.9 9676.2 9829.0 9119.7 9457.8 9905.5 10082.3 10210.3 23:06:58 INFO - PROCESS | 14627 | -------- Summary: end -------- 23:06:58 INFO - PROCESS | 14627 | 23:06:58 INFO - TEST-INFO | 14627: exit 0 23:06:58 INFO - TEST-OK | dromaeo_css | took 1110758ms 23:06:58 INFO - TEST-START | kraken 23:06:58 INFO - operating with platform_type : linux_ 23:06:58 INFO - Initialising browser for kraken test... 23:06:58 INFO - TEST-INFO | started process 14872 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpxbtTyP/profile http://localhost:43745/getInfo.html) 23:07:03 INFO - PROCESS | 14872 | JavaScript error: resource://app/modules/BrowserUITelemetry.jsm, line 538: ReferenceError: assignment to undeclared variable item 23:07:04 INFO - PROCESS | 14872 | __metrics Screen width/height:1600/1200 23:07:04 INFO - PROCESS | 14872 | colorDepth:24 23:07:04 INFO - PROCESS | 14872 | Browser inner width/height: 1024/697 23:07:04 INFO - PROCESS | 14872 | __metrics 23:07:04 INFO - PROCESS | 14872 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 23:07:05 INFO - TEST-INFO | 14872: exit 0 23:07:05 INFO - Browser initialized. 23:07:05 INFO - Running cycle 1/1 for kraken test... 23:07:05 INFO - TEST-INFO | started process 14993 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpxbtTyP/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:07:16 INFO - PROCESS | 14993 | RSS: Main: 151109632 23:07:16 INFO - PROCESS | 14993 | 23:07:19 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43745/tests/kraken/driver.html?testName=audio-beat-detection) 23:07:20 INFO - PROCESS | 14993 | RSS: Main: 152571904 23:07:20 INFO - PROCESS | 14993 | 23:07:22 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43745/tests/kraken/driver.html?testName=audio-dft) 23:07:23 INFO - PROCESS | 14993 | RSS: Main: 152416256 23:07:23 INFO - PROCESS | 14993 | 23:07:26 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43745/tests/kraken/driver.html?testName=audio-fft) 23:07:26 INFO - PROCESS | 14993 | RSS: Main: 145440768 23:07:26 INFO - PROCESS | 14993 | 23:07:28 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43745/tests/kraken/driver.html?testName=audio-oscillator) 23:07:29 INFO - PROCESS | 14993 | RSS: Main: 146292736 23:07:29 INFO - PROCESS | 14993 | 23:07:31 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43745/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:07:32 INFO - PROCESS | 14993 | RSS: Main: 147865600 23:07:32 INFO - PROCESS | 14993 | 23:07:35 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43745/tests/kraken/driver.html?testName=imaging-darkroom) 23:07:35 INFO - PROCESS | 14993 | RSS: Main: 147841024 23:07:35 INFO - PROCESS | 14993 | 23:07:39 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43745/tests/kraken/driver.html?testName=imaging-desaturate) 23:07:39 INFO - PROCESS | 14993 | RSS: Main: 148107264 23:07:39 INFO - PROCESS | 14993 | 23:07:42 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43745/tests/kraken/driver.html?testName=json-parse-financial) 23:07:43 INFO - PROCESS | 14993 | RSS: Main: 148234240 23:07:43 INFO - PROCESS | 14993 | 23:07:44 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43745/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:07:45 INFO - PROCESS | 14993 | RSS: Main: 149389312 23:07:45 INFO - PROCESS | 14993 | 23:07:47 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43745/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:07:47 INFO - PROCESS | 14993 | RSS: Main: 148017152 23:07:47 INFO - PROCESS | 14993 | 23:07:49 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43745/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:07:50 INFO - PROCESS | 14993 | RSS: Main: 148578304 23:07:50 INFO - PROCESS | 14993 | 23:07:53 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43745/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:07:53 INFO - PROCESS | 14993 | RSS: Main: 148660224 23:07:53 INFO - PROCESS | 14993 | 23:07:56 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43745/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:07:56 INFO - PROCESS | 14993 | RSS: Main: 148131840 23:07:56 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | Cycle 1(1): loaded http://localhost:43745/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43745/tests/kraken/driver.html?testName=ai-astar) 23:07:58 INFO - PROCESS | 14993 | RSS: Main: 148496384 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | __start_tp_report 23:07:58 INFO - PROCESS | 14993 | _x_x_mozilla_page_load 23:07:58 INFO - PROCESS | 14993 | _x_x_mozilla_page_load_details 23:07:58 INFO - PROCESS | 14993 | |i|pagename|runs| 23:07:58 INFO - PROCESS | 14993 | |0;ai-astar;112;108;113;112;113;123;111;113;113;111 23:07:58 INFO - PROCESS | 14993 | |1;audio-beat-detection;156;133;149;160;126;155;154;127;171;126 23:07:58 INFO - PROCESS | 14993 | |2;audio-dft;161;164;162;166;163;161;162;164;170;162 23:07:58 INFO - PROCESS | 14993 | |3;audio-fft;72;72;73;74;71;72;72;149;71;74 23:07:58 INFO - PROCESS | 14993 | |4;audio-oscillator;94;93;136;93;95;94;137;93;94;145 23:07:58 INFO - PROCESS | 14993 | |5;imaging-gaussian-blur;110;110;110;109;109;109;110;110;109;110 23:07:58 INFO - PROCESS | 14993 | |6;imaging-darkroom;146;146;147;151;147;145;146;147;147;147 23:07:58 INFO - PROCESS | 14993 | |7;imaging-desaturate;97;96;96;96;97;96;96;96;96;97 23:07:58 INFO - PROCESS | 14993 | |8;json-parse-financial;59;60;60;58;60;61;61;60;60;58 23:07:58 INFO - PROCESS | 14993 | |9;json-stringify-tinderbox;69;69;69;68;68;69;69;69;70;69 23:07:58 INFO - PROCESS | 14993 | |10;stanford-crypto-aes;84;94;86;86;86;85;85;86;87;85 23:07:58 INFO - PROCESS | 14993 | |11;stanford-crypto-ccm;124;116;126;117;119;119;126;120;118;127 23:07:58 INFO - PROCESS | 14993 | |12;stanford-crypto-pbkdf2;148;160;147;153;161;145;158;156;159;147 23:07:58 INFO - PROCESS | 14993 | |13;stanford-crypto-sha256-iterative;67;69;72;72;68;68;67;68;69;67 23:07:58 INFO - PROCESS | 14993 | __end_tp_report 23:07:58 INFO - PROCESS | 14993 | __start_cc_report 23:07:58 INFO - PROCESS | 14993 | _x_x_mozilla_cycle_collect,739 23:07:58 INFO - PROCESS | 14993 | __end_cc_report 23:07:58 INFO - PROCESS | 14993 | __startTimestamp1450768078799__endTimestamp 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | ------- Summary: start ------- 23:07:58 INFO - PROCESS | 14993 | Number of tests: 14 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.00 stddev:3.87 (3.4%) stddev-sans-first:4.09 23:07:58 INFO - PROCESS | 14993 | Values: 112.0 108.0 113.0 112.0 113.0 123.0 111.0 113.0 113.0 111.0 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#1] audio-beat-detection Cycles:10 Average:145.70 Median:154.50 stddev:16.34 (10.6%) stddev-sans-first:16.90 23:07:58 INFO - PROCESS | 14993 | Values: 156.0 133.0 149.0 160.0 126.0 155.0 154.0 127.0 171.0 126.0 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.50 stddev:2.76 (1.7%) stddev-sans-first:2.77 23:07:58 INFO - PROCESS | 14993 | Values: 161.0 164.0 162.0 166.0 163.0 161.0 162.0 164.0 170.0 162.0 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#3] audio-fft Cycles:10 Average:80.00 Median:72.00 stddev:24.27 (33.7%) stddev-sans-first:25.57 23:07:58 INFO - PROCESS | 14993 | Values: 72.0 72.0 73.0 74.0 71.0 72.0 72.0 149.0 71.0 74.0 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#4] audio-oscillator Cycles:10 Average:107.40 Median:93.50 stddev:22.17 (23.7%) stddev-sans-first:22.98 23:07:58 INFO - PROCESS | 14993 | Values: 94.0 93.0 136.0 93.0 95.0 94.0 137.0 93.0 94.0 145.0 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#5] imaging-gaussian-blur Cycles:10 Average:109.60 Median:110.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 23:07:58 INFO - PROCESS | 14993 | Values: 110.0 110.0 110.0 109.0 109.0 109.0 110.0 110.0 109.0 110.0 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:1.60 (1.1%) stddev-sans-first:1.66 23:07:58 INFO - PROCESS | 14993 | Values: 146.0 146.0 147.0 151.0 147.0 145.0 146.0 147.0 147.0 147.0 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 23:07:58 INFO - PROCESS | 14993 | Values: 97.0 96.0 96.0 96.0 97.0 96.0 96.0 96.0 96.0 97.0 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:60.00 stddev:1.06 (1.8%) stddev-sans-first:1.09 23:07:58 INFO - PROCESS | 14993 | Values: 59.0 60.0 60.0 58.0 60.0 61.0 61.0 60.0 60.0 58.0 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:69.00 stddev:0.57 (0.8%) stddev-sans-first:0.60 23:07:58 INFO - PROCESS | 14993 | Values: 69.0 69.0 69.0 68.0 68.0 69.0 69.0 69.0 70.0 69.0 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:86.00 stddev:2.80 (3.3%) stddev-sans-first:2.83 23:07:58 INFO - PROCESS | 14993 | Values: 84.0 94.0 86.0 86.0 86.0 85.0 85.0 86.0 87.0 85.0 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#11] stanford-crypto-ccm Cycles:10 Average:121.20 Median:122.00 stddev:4.13 (3.4%) stddev-sans-first:4.26 23:07:58 INFO - PROCESS | 14993 | Values: 124.0 116.0 126.0 117.0 119.0 119.0 126.0 120.0 118.0 127.0 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:157.00 stddev:6.17 (3.9%) stddev-sans-first:6.22 23:07:58 INFO - PROCESS | 14993 | Values: 148.0 160.0 147.0 153.0 161.0 145.0 158.0 156.0 159.0 147.0 23:07:58 INFO - PROCESS | 14993 | 23:07:58 INFO - PROCESS | 14993 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:68.50 stddev:1.89 (2.8%) stddev-sans-first:1.90 23:07:58 INFO - PROCESS | 14993 | Values: 67.0 69.0 72.0 72.0 68.0 68.0 67.0 68.0 69.0 67.0 23:07:58 INFO - PROCESS | 14993 | -------- Summary: end -------- 23:07:58 INFO - PROCESS | 14993 | 23:07:59 INFO - TEST-INFO | 14993: exit 0 23:07:59 INFO - TEST-OK | kraken | took 60836ms 23:07:59 INFO - TEST-START | v8_7 23:07:59 INFO - operating with platform_type : linux_ 23:07:59 INFO - Initialising browser for v8_7 test... 23:07:59 INFO - TEST-INFO | started process 15280 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpc66Lqf/profile http://localhost:43745/getInfo.html) 23:08:03 INFO - PROCESS | 15280 | JavaScript error: resource://app/modules/BrowserUITelemetry.jsm, line 538: ReferenceError: assignment to undeclared variable item 23:08:05 INFO - PROCESS | 15280 | __metrics Screen width/height:1600/1200 23:08:05 INFO - PROCESS | 15280 | colorDepth:24 23:08:05 INFO - PROCESS | 15280 | Browser inner width/height: 1024/697 23:08:05 INFO - PROCESS | 15280 | __metrics 23:08:05 INFO - PROCESS | 15280 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 23:08:06 INFO - TEST-INFO | 15280: exit 0 23:08:06 INFO - Browser initialized. 23:08:06 INFO - Running cycle 1/1 for v8_7 test... 23:08:06 INFO - TEST-INFO | started process 15400 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpc66Lqf/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:08:17 INFO - PROCESS | 15400 | RSS: Main: 151089152 23:08:17 INFO - PROCESS | 15400 | 23:08:39 INFO - PROCESS | 15400 | Cycle 1(1): loaded http://localhost:43745/tests/v8_7/run.html (next: http://localhost:43745/tests/v8_7/run.html) 23:08:39 INFO - PROCESS | 15400 | RSS: Main: 147345408 23:08:39 INFO - PROCESS | 15400 | 23:08:39 INFO - PROCESS | 15400 | __start_tp_report 23:08:39 INFO - PROCESS | 15400 | _x_x_mozilla_page_load 23:08:39 INFO - PROCESS | 15400 | _x_x_mozilla_page_load_details 23:08:39 INFO - PROCESS | 15400 | |i|pagename|runs| 23:08:39 INFO - PROCESS | 15400 | |0;Richards;164.66326362588507 23:08:39 INFO - PROCESS | 15400 | |1;DeltaBlue;193.23671497584542 23:08:39 INFO - PROCESS | 15400 | |2;Encrypt;282.7254735651682 23:08:39 INFO - PROCESS | 15400 | |3;Decrypt;5191.709844559586 23:08:39 INFO - PROCESS | 15400 | |4;RayTrace;1057.0824524312895 23:08:39 INFO - PROCESS | 15400 | |5;Earley;720.4610951008646 23:08:39 INFO - PROCESS | 15400 | |6;Boyer;12207.317073170732 23:08:39 INFO - PROCESS | 15400 | |7;RegExp;25025 23:08:39 INFO - PROCESS | 15400 | |8;Splay;554.016620498615 23:08:39 INFO - PROCESS | 15400 | |9;NavierStokes;4822.115384615385 23:08:39 INFO - PROCESS | 15400 | __end_tp_report 23:08:39 INFO - PROCESS | 15400 | __startTimestamp1450768119564__endTimestamp 23:08:39 INFO - PROCESS | 15400 | 23:08:39 INFO - PROCESS | 15400 | ------- Summary: start ------- 23:08:39 INFO - PROCESS | 15400 | Number of tests: 10 23:08:39 INFO - PROCESS | 15400 | 23:08:39 INFO - PROCESS | 15400 | [#0] Richards Cycles:1 Average:164.66 Median:164.66 stddev:0.00 (0.0%) 23:08:39 INFO - PROCESS | 15400 | Values: 164.7 23:08:39 INFO - PROCESS | 15400 | 23:08:39 INFO - PROCESS | 15400 | [#1] DeltaBlue Cycles:1 Average:193.24 Median:193.24 stddev:0.00 (0.0%) 23:08:39 INFO - PROCESS | 15400 | Values: 193.2 23:08:39 INFO - PROCESS | 15400 | 23:08:39 INFO - PROCESS | 15400 | [#2] Encrypt Cycles:1 Average:282.73 Median:282.73 stddev:0.00 (0.0%) 23:08:39 INFO - PROCESS | 15400 | Values: 282.7 23:08:39 INFO - PROCESS | 15400 | 23:08:39 INFO - PROCESS | 15400 | [#3] Decrypt Cycles:1 Average:5191.71 Median:5191.71 stddev:0.00 (0.0%) 23:08:39 INFO - PROCESS | 15400 | Values: 5191.7 23:08:39 INFO - PROCESS | 15400 | 23:08:39 INFO - PROCESS | 15400 | [#4] RayTrace Cycles:1 Average:1057.08 Median:1057.08 stddev:0.00 (0.0%) 23:08:39 INFO - PROCESS | 15400 | Values: 1057.1 23:08:39 INFO - PROCESS | 15400 | 23:08:39 INFO - PROCESS | 15400 | [#5] Earley Cycles:1 Average:720.46 Median:720.46 stddev:0.00 (0.0%) 23:08:39 INFO - PROCESS | 15400 | Values: 720.5 23:08:39 INFO - PROCESS | 15400 | 23:08:39 INFO - PROCESS | 15400 | [#6] Boyer Cycles:1 Average:12207.32 Median:12207.32 stddev:0.00 (0.0%) 23:08:39 INFO - PROCESS | 15400 | Values: 12207.3 23:08:39 INFO - PROCESS | 15400 | 23:08:39 INFO - PROCESS | 15400 | [#7] RegExp Cycles:1 Average:25025.00 Median:25025.00 stddev:0.00 (0.0%) 23:08:39 INFO - PROCESS | 15400 | Values: 25025.0 23:08:39 INFO - PROCESS | 15400 | 23:08:39 INFO - PROCESS | 15400 | [#8] Splay Cycles:1 Average:554.02 Median:554.02 stddev:0.00 (0.0%) 23:08:39 INFO - PROCESS | 15400 | Values: 554.0 23:08:39 INFO - PROCESS | 15400 | 23:08:39 INFO - PROCESS | 15400 | [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 23:08:39 INFO - PROCESS | 15400 | Values: 4822.1 23:08:39 INFO - PROCESS | 15400 | -------- Summary: end -------- 23:08:39 INFO - PROCESS | 15400 | 23:08:40 INFO - TEST-INFO | 15400: exit 0 23:08:40 INFO - TEST-OK | v8_7 | took 40729ms 23:08:40 INFO - SUITE-END | took 1212s 23:08:40 INFO - Completed test suite (00:20:12) 23:08:40 INFO - Working with test: dromaeo_css 23:08:40 INFO - Generating results file: dromaeo_css 23:08:40 INFO - Working with test: kraken 23:08:40 INFO - Generating results file: kraken 23:08:40 INFO - javascript benchmark 23:08:40 INFO - Working with test: v8_7 23:08:40 INFO - Generating results file: v8_7 23:08:40 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:08:40 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 23:08:40 INFO - process_Request line: dromaeo_css 7100.11 graph.html#tests=[[72,64,43]] 23:08:40 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:08:40 INFO - process_Request line: kraken 1520.60 graph.html#tests=[[232,64,43]] 23:08:40 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:08:40 INFO - process_Request line: v8_7 21535.66 graph.html#tests=[[230,64,43]] 23:08:40 INFO - RETURN: dromaeo_css: 7100.11 23:08:40 INFO - RETURN: kraken: 1520.60 23:08:40 INFO - RETURN: v8_7: 21535.66 23:08:40 INFO - javascript benchmark 23:08:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3223.564818749469, 3262.7879187852786, 3299.6040475142986, 3301.4387554115797, 3309.5697559317287, 5383.488568585978, 5411.215373770801, 5452.0, 5471.179323101537, 5471.863203419916, 5442.673439593617, 5457.208704737821, 5508.091164957777, 5509.972450137744, 5681.460261275182, 4986.626003049768, 5052.873678158057, 5131.897362052757, 5157.5358217760395, 5187.507186817246, 92.95047545171259, 5041.747912604374, 5066.518680725344, 5074.112030394685, 5123.641345105844, 5057.848264552069, 5085.364329458817, 5088.949110508885, 5102.234664800272, 5108.029474399889, 4867.099586576459, 5090.9236361454605, 5092.337996060489, 5112.948870511284, 5130.050940576006, 4902.142125128088, 4967.180415231483, 4986.800527978877, 5010.649254552182, 5011.899762004776, 5312.0, 5323.441038690959, 5362.65142765719, 5363.302770639792, 5410.756515956752, 5839.299284085894, 5898.4101589840675, 5910.379410161914, 5963.4334738199805, 5972.820815375546, 5914.585978981473, 5967.015442451991, 5983.311919129313, 6008.459238668541, 6038.788642397539, 5507.063799154113, 5615.7472913718575, 5621.662700237958, 5627.380988091348, 5635.3237611486475, 5854.385289544622, 5876.177335173079, 5983.282006159202, 6070.605410648293, 6079.544034197453, 5777.306723193244, 5780.797672081457, 5790.36306006339, 5792.160136780143, 5847.44449277318, 5838.620489668158, 5882.764689412461, 5884.0, 5891.322497912709, 5920.259967504062, 5703.885922281531, 5821.53427725781, 5824.504917082011, 5845.210896528999, 5945.435183657545, 5931.881362372728, 5944.3164036136395, 5969.791057312973, 5978.491828194565, 5982.581219314607, 4535.682502224812, 4591.563801438858, 4591.609713174351, 4594.5864872161665, 4623.9075218495445, 3994.08136128692, 4014.6788256939376, 4080.6735461163034, 4093.6520395766097, 4125.0, 5631.971840140773, 5676.574256930747, 5691.117876729031, 5695.601307908407, 5716.314042314949, 6008.429199226067, 6020.337762846083, 6025.0, 6029.879402411927, 6035.823014512216, 5390.595705322116, 5455.509004189563, 5509.256250406224, 5523.475269849358, 5531.668099914018, 5026.170681837529, 5080.212567052113, 5136.743162841918, 5146.150885103991, 5192.143296356135, 6081.057436097412, 6081.361457046946, 6096.451319381188, 6102.511799056065, 6121.510279177655, 5844.912326315194, 5942.61373010753, 6001.2498437695285, 6001.459868611846, 6041.6375017499095, 6024.3975602439405, 6121.2700689216845, 6166.044263139221, 6167.0, 6167.321594624498, 5905.704714764159, 5912.0, 5950.16697662319, 5971.910421343597, 6032.065029920369, 5864.32560255576, 5954.553408494294, 6070.453659170696, 6080.48315893154, 6083.482903953213, 5729.197912292366, 5805.0, 5817.912731308949, 5822.796202132905, 5907.0, 5744.569157313302, 5790.0, 5813.854653633693, 5823.8835223294445, 5873.735681894411, 5451.972740136274, 5602.747876345493, 5639.689817059937, 5681.9147712783515, 5686.43135686428, 5762.94237057624, 5844.064949608042, 5862.325832529314, 5873.823785286449, 5895.64626122434, 5637.830865074055, 5718.1136923776885, 5719.428057194414, 5722.828315150552, 5726.5418766499415], "unit": "score", "name": "dojo.html", "value": 5430.903019137041}, {"lowerIsBetter": false, "replicates": [547.6091596565612, 587.3128439725521, 591.6538824787499, 615.4860691322746, 642.9292777794442, 30103.698963010364, 37563.248735025285, 37825.621743782554, 37839.81080094603, 37929.43105853407, 42660.786696066476, 43247.78376108123, 43514.56485435145, 43564.34653480192, 43567.56432435683, 8662.30701543878, 8793.824123517526, 8798.912010879905, 8839.24866386358, 8849.734507964738, 8520.318374530054, 8587.098354672764, 8613.741587752378, 8635.568221588926, 8640.0, 7718.884216736754, 7732.536047837148, 7758.069031716202, 7762.30139287464, 7828.256315650005, 8662.913370866274, 8683.044865064869, 8709.08554601764, 8729.170728780788, 8753.168448997336, 8598.312135029182, 8668.26319762821, 8689.869651955225, 8787.384883058188, 8792.944846618382, 39935.40096898549, 40354.596454035374, 40505.79747101275, 40689.38965915484, 40716.38925416121, 3367.3938691035605, 3487.2851065531504, 3626.0, 3855.306044911915, 3956.059305968503, 29951.101466956025, 30285.69714302851, 30653.0, 31644.050678479452, 31963.520547191813, 3784.3377408953324, 3806.7525610835205, 3811.4663947047434, 3817.1793064491267, 3877.1470276539117, 257587.0, 259256.7037164802, 259685.0, 260858.69570652215, 263361.0, 53241.73379133079, 54078.91842163214, 59672.70163649154, 59920.70039649861, 62262.37737622566, 55721.442785572435, 55823.0, 55924.0, 56097.71951140218, 56160.43839561552, 16110.919445402933, 16188.02871827694, 16253.10607916576, 16291.348346066261, 16314.83685163157, 12210.816837747441, 12236.327002014797, 12269.93865030669, 12294.815577766341, 12383.566575169825, 19737.901310493355, 20724.067416966274, 20726.96365181735, 20845.374638760863, 20912.0, 7920.32677222431, 8224.958875205706, 8227.629756660965, 8229.79425514355, 8240.0, 819.7417813388763, 892.3307519360479, 895.6999405199331, 1021.4535223655308, 1092.8633920759905, 24711.5057698845, 24997.0, 25028.874855625607, 25061.0, 26781.86609066942, 996.9446869296071, 1077.7682798198425, 1138.9316641001567, 1141.2182654881433, 1170.9590164344206, 14254.501092461922, 14306.783923366631, 14527.346269417902, 14552.781708274384, 15690.0, 328058.0, 328221.0, 328918.0, 329756.0, 330369.0, 51912.0, 52244.73877630587, 52299.21551176659, 52318.738406307726, 52451.0, 1367.1591970938, 1374.2098293481288, 1385.6674398144573, 1385.9584212473642, 1388.3544151969384, 28357.716422836333, 29020.419591608472, 29323.560146598244, 29631.85184074152, 29866.402671946005, 46139.3079103807, 46855.53144468512, 46890.76554617205, 46989.53010469852, 47139.29291060568, 289666.0, 291924.0, 292315.0, 295030.0, 296774.0, 30764.0, 33682.8315858429, 33729.83135084309, 33963.4905476423, 34033.14917127092, 40551.18897621972, 40614.796926016366, 40688.77933661995, 40856.1828763429, 41540.376894346, 34695.82652086723, 35095.649043509235, 35179.824100879334, 35597.0, 36059.27881442304, 35306.293874122886, 35653.64346356607, 35931.28137437289, 36066.09834754152, 36079.81960090183, 34064.48903266403, 35133.648663513035, 35199.64800351964, 35221.29557408889, 35274.11814704653, 29497.557536636537, 32040.198995025312, 32100.35799284048, 32251.193720157185, 32291.0, 24389.0, 24623.50752984966, 24905.377365566004, 25125.371865703502, 26412.603810942466], "unit": "score", "name": "ext.html", "value": 18430.89929270003}, {"lowerIsBetter": false, "replicates": [360.5691199017174, 362.12727327141596, 363.1502284653909, 365.83720244491195, 372.3446733748602, 16587.253573589187, 17376.82623173768, 17578.64842703145, 18000.189991450385, 18410.72383914239, 10319.690409287714, 10617.41604211768, 15797.28912198951, 15920.363185472566, 15920.442784502544, 4814.133455977913, 4963.429205641343, 5114.820981265657, 5119.616028797836, 5124.641275110744, 4615.93076103857, 5108.770105345268, 5113.642045056848, 5129.923051154217, 5761.164631128484, 5686.31764188298, 5686.402927692595, 5689.971550142264, 5694.117411801157, 5726.284214473191, 5743.684097374643, 5967.104934259853, 5973.193618861442, 5973.283206015262, 5973.522118230531, 5226.189940559219, 5287.339082614673, 5337.492938170868, 5340.359156901158, 5341.359036915595, 11497.25267857595, 11591.536338546448, 11678.24091434054, 11742.236754610924, 11759.647210583697, 2110.905009274571, 2152.494163871489, 2159.8596091254176, 2171.1423987524995, 2222.8888555572157, 9075.228605568534, 9196.724098276996, 9312.068793120701, 9621.807563848753, 11537.538498460135, 1844.041098628719, 1854.1378259109445, 1877.3616970230187, 1891.933782317626, 2011.1352118589, 61721.6913915432, 62962.05556916696, 63508.36491635117, 63581.364186358005, 63894.0, 10024.047715467019, 10207.0, 10241.385516869012, 10249.692509224735, 10290.279680422449, 12303.384830758605, 12557.372131393393, 13088.934555327352, 13106.60680179596, 23528.176513821934, 8836.204741573161, 8890.644374225089, 8895.4662720237, 8940.955295223612, 9056.954715226382, 7998.680052797939, 8027.2775450209765, 8129.227723366271, 8250.133735957757, 8468.195521425454, 7681.193474685189, 7746.41901857363, 7776.572288524186, 7784.688612455551, 7794.727184548514, 3202.775805693626, 3203.8878639247982, 3227.193201699575, 3268.591426071741, 3279.475283954556, 401.4740689696507, 851.2849206666306, 963.6193703487223, 967.298708436378, 1047.4710271312938, 10238.436885971194, 10710.946445267724, 10737.194710396752, 16257.268422920997, 16991.915040424967, 892.8035832116924, 955.1451450951498, 975.512243878061, 1008.6167256442504, 1028.2596530498029, 8707.216350528483, 8937.240334571505, 9256.814863702688, 9334.113259240361, 9467.289953253534, 523491.0, 530417.0, 536127.0, 543388.0, 549557.0, 78157.0, 83047.16952830394, 83862.0, 84211.57894210612, 84744.15255847486, 1128.6670432222513, 1156.5894107591853, 1162.5001249462644, 1166.5742004168587, 1172.9970874902006, 15591.454299099478, 15668.0, 15725.606859828596, 15790.131542765259, 15959.44141955026, 33340.499892501146, 34381.82809085939, 34942.30115397627, 36005.27989440249, 36354.0, 38344.42483362807, 39314.0, 39542.40686389649, 39751.60248397479, 40303.0, 9242.214411775074, 9309.860352094589, 9442.669506567237, 9514.0, 9523.714298569881, 15385.230738462807, 16739.163041848104, 17084.0, 17448.21483033292, 17861.374851880122, 14425.567232983027, 14604.634884127981, 14868.62828429298, 15241.466548670744, 15310.0, 14727.705445890808, 14751.188684622019, 14922.925385373004, 15108.169050702318, 15344.616384590474, 15503.069815811088, 15603.0, 15654.295556699748, 15832.208389580705, 15855.365785368456, 9497.525123743648, 9527.761805954906, 9574.425534467955, 9604.711858644252, 9932.503374831085, 10437.425941572983, 10451.163906887583, 10487.895121048692, 13805.447782089006, 14414.279286035446], "unit": "score", "name": "jquery.html", "value": 9009.935986159018}, {"lowerIsBetter": false, "replicates": [1480.3190532355115, 1709.2393884721298, 1767.805541390447, 1782.9732554011691, 1785.5893144576746, 7509.924900750998, 7565.773026809191, 7796.298333150015, 7960.363170946318, 7981.161977992307, 7784.805379865506, 7810.453268271223, 7859.135495095535, 7871.527708337504, 7892.486988345753, 2237.429455488852, 2253.188852013274, 2262.66060090986, 2266.5580211858696, 2269.727632684072, 2307.6999990001277, 2312.3063081075693, 2376.310869847742, 2377.9048838046456, 2380.321608341623, 2251.2233279518623, 2254.6392577187653, 2270.2962081754627, 2285.131649973008, 2316.9536609267802, 2244.8204143668468, 2284.1206135637817, 2285.245868863288, 2293.1629955066364, 2306.273523839985, 2323.9767602323927, 2335.3344296875307, 2359.55168517981, 2361.1027809432476, 2361.480474295652, 7618.504797188165, 7631.046119235096, 7672.769816905502, 7675.462717609769, 7695.230476952316, 4653.418322709661, 4738.549837765407, 4748.240281554969, 4749.4063242094735, 4750.928736068961, 2962.5408061750463, 2963.466576016316, 3067.1412004638737, 3068.70847269509, 3107.9533806992913, 912.7353067610385, 925.7546750111226, 945.7871978804783, 952.9904700952972, 970.7233438470003, 10477.58089676412, 10508.474576271232, 10515.84226236607, 10573.9471302643, 10581.576736930514, 4129.772862492532, 4182.393552934868, 4182.665386769051, 4207.095474473003, 4213.789310534461, 8530.360222983301, 8540.188682075193, 8550.444221125606, 8563.357748168912, 8587.699430519902, 4663.393758811333, 4666.3700400445505, 4679.391679081766, 4681.063787242565, 4683.391159149289, 4233.957660423418, 4250.298700714347, 4272.0, 4276.465441819772, 4286.7856607169515, 3440.8795692150657, 3490.7207423406066, 3502.877399291013, 3505.9123521911647, 3515.261795023023, 1037.9879617373151, 1039.0440794468955, 1057.5505410200633, 1062.8140075486758, 1085.2511766880984, 1073.3774410841693, 1094.6606551968755, 1136.7840110379002, 1263.3430616079588, 1358.5652591170929, 7179.461540384387, 7180.748673796392, 7193.352598266181, 7265.600391978492, 7330.193678695339, 995.5221493683073, 1029.351508549609, 1139.1627154041773, 1142.2289954280993, 1154.7113221694576, 5789.884202316014, 5794.985877471426, 5829.941700583025, 5836.066229403275, 5854.268216472941, 9143.817123657489, 9183.58673859678, 9294.86057709135, 9367.156955874005, 9426.670066547775, 9065.77335566616, 9192.080791920755, 9231.123043310874, 9235.953820230856, 9265.629374824932, 7008.544444611084, 7100.325469080326, 7136.892946605805, 7185.353318201387, 7278.745243916437, 7235.746748863765, 7306.744263950736, 7309.378702810321, 7315.158756743042, 7389.039424874732, 8065.83868322642, 8269.131741167092, 8333.458325208841, 8392.66429342844, 8494.957525212334, 8211.096779354148, 8357.791055223668, 8427.747167584983, 8429.283510901641, 8494.405391622528, 6809.080774095632, 6981.162260528769, 6985.615791131537, 7000.61496617691, 7003.124609423822, 7440.2931721486375, 7481.214472480311, 7481.962590187014, 7507.512011719221, 7581.886271705818, 6804.693788779417, 6942.097527321416, 7025.051618031501, 7152.392046675883, 7163.6060016699585, 6842.110525631636, 6894.965525172342, 6918.066061081689, 6925.3074692530345, 6986.301369863177, 6856.485763567852, 6874.553154044971, 6890.758823441155, 6935.653217339213, 6960.756373526902, 6570.47436205112, 6589.209294884645, 6625.503087268377, 6646.700898459678, 6650.434713049445, 6278.717457714322, 6579.276279609335, 6625.900611490928, 6627.536072474881, 6637.767678131242], "unit": "score", "name": "mootools.html", "value": 4465.050429350259}, {"lowerIsBetter": false, "replicates": [341.6975976261009, 345.5645886183408, 349.3693882542014, 349.7883780312911, 354.31970616416464, 6249.843753906154, 6310.779122730705, 6327.841803954903, 6402.551821372505, 6426.2931077581425, 6165.506759459244, 6310.716017779199, 6310.936890631092, 6331.240251169855, 6348.968255158729, 4815.157347464197, 4827.227643577028, 4841.370621819158, 4845.103655823684, 4849.321095046696, 4804.687695299812, 4838.153323168449, 4849.321095046696, 4852.150873597124, 4853.369062021932, 4702.5297470253045, 4824.445188803297, 4832.8884356598, 4834.033193361342, 4838.9032219355595, 4805.27920811881, 4807.423109226897, 4809.615230781546, 4814.831480898151, 4820.975895120502, 4221.97889010553, 4248.277792775205, 4251.341042138442, 4251.74489530626, 4254.42565253693, 6013.218281623404, 6037.698115094227, 6045.697715114226, 6050.54620903434, 6064.211652485193, 5363.704996225206, 5536.390996990328, 5538.529225015878, 5540.473655002768, 5543.528800052, 6727.360900714424, 6728.596284222914, 6734.932650673529, 6737.3262673732725, 6749.7300107995625, 4541.977290113528, 4550.362949187116, 4550.726956382595, 4552.931706024412, 4554.8861278467975, 6755.392014718699, 6926.861462770717, 6959.930400696029, 6980.267071957423, 7024.578525288499, 4647.674662773574, 4664.253719404879, 4665.4168228971375, 4671.0, 4687.296905464208, 4672.649551283599, 4721.693089949143, 4726.1492931272705, 4729.077829823152, 4745.454272758677, 5506.614536982373, 5516.889662206733, 5522.392536820947, 5522.420145884705, 5525.336959564878, 6557.114789503356, 6750.257471678112, 6756.189257289157, 6760.039588519373, 6766.086578311963, 1648.4972083514403, 1680.983190168107, 1686.9625180513895, 1694.1275243249736, 1696.151924037981, 1123.6516679829115, 1132.9037031852386, 1132.9546818127183, 1138.1691365303209, 1145.4330106597122, 6371.585846919936, 6447.580907241015, 6447.903281450782, 6467.417932386108, 6480.157579514633, 6876.69054892531, 6893.724251030003, 6903.861922761617, 6916.377526022682, 6920.619365934922, 6348.396902294275, 6364.299927008118, 6407.615543067431, 6421.967890160613, 6464.353564643498, 3784.0729021389934, 3812.5615554211067, 3817.656410922975, 3819.6753275971855, 3832.329342365075, 7162.4628152888745, 7171.175314838861, 7210.351068403869, 7213.062301900719, 7229.2409297024105, 7065.540739851893, 7211.170715367695, 7231.819204519824, 7238.819029524304, 7273.854522909618, 7138.143422789195, 7190.137183537904, 7201.243869393743, 7208.441003443807, 7217.675204615805, 7071.858562828612, 7086.751963681247, 7090.822729431599, 7094.929050709633, 7102.644867756489, 6842.657867106725, 6994.370506654425, 6997.510174287751, 7000.824979375557, 7005.54463959841, 7149.9285007149265, 7189.532680375759, 7204.24355442671, 7209.711611535689, 7213.747518836815, 4620.676552641283, 4650.093231819763, 4652.720836749806, 4657.93013104797, 4659.277811939155, 6765.797026089225, 6909.861802763816, 6970.65146742671, 7054.329838665319, 7055.400290975324, 6752.155980502437, 6873.518853680194, 6894.586324820527, 6897.827554311182, 6905.585664860125, 6783.864322713617, 6831.180258369028, 6847.383735463832, 6864.656767161722, 6873.518853680194, 6544.411003009752, 6691.1636045494315, 6708.798736037927, 6721.227058888095, 6730.461563074844, 6876.106106206161, 6885.621290829053, 6891.27641597625, 6892.207396149507, 6894.620795856276, 6603.108580341593, 6643.50173736962, 6650.20197576294, 6693.297203793726, 6700.162479690039], "unit": "score", "name": "prototype.html", "value": 5067.348167507081}, {"lowerIsBetter": false, "replicates": [722.2957616324085, 826.29351904122, 832.1553623072582, 862.5213006781233, 870.8693695945611, 13573.592792216237, 13711.0, 13729.31353432328, 13780.242086685228, 13825.930870345659, 13390.464381424756, 13395.0, 13484.0, 13502.122362046484, 13511.932440337809, 825.9958700206506, 829.4276948905266, 857.7769779857219, 858.4205661178711, 860.2129051917519, 845.4631309118693, 852.2457625001856, 852.5183271451641, 853.6585365853678, 856.7643897928089, 867.6832955971057, 873.9475631462133, 874.5059041641484, 874.5845723281423, 880.4673172730502, 865.5023361567092, 867.9783005424852, 868.4485351801651, 869.8042940338437, 871.7384784564605, 645.3546453546453, 645.8353119954381, 647.5046589359144, 648.3127884442506, 649.1431310669917, 13953.511627093105, 14109.223992680398, 14128.293585320796, 14226.92886535571, 14236.217008064552, 12021.0, 12277.140600157993, 12509.74980500394, 12606.306653134074, 13060.085793994424, 10352.896471035343, 10652.573897044109, 10822.458877056115, 10924.465435894414, 11089.500972456259, 3588.4331673038537, 3634.7819130852236, 3662.9450558241647, 3731.850725970985, 3755.192633583765, 48466.75766621179, 48545.99999999964, 48936.51063489355, 49081.50918490805, 49141.50858491405, 9390.201832844285, 9493.477858717837, 9829.80340393188, 9950.45272510019, 10088.243381746399, 16615.916920415322, 16679.16604169811, 16686.666266674598, 16724.163791810362, 16731.6653666926, 7356.337929586363, 7394.704211831574, 7575.280348366897, 7582.54504729718, 7767.650455729506, 4153.460050193456, 4252.702310838274, 4260.552641972548, 4357.2592659248, 4361.236783562864, 3151.8581663824725, 3173.4129186100645, 3176.12656519459, 3177.7616678749187, 3178.7774855760335, 577.2063016646789, 588.6938791828226, 593.8841925824423, 596.8120042186697, 609.2080295615682, 589.2722487727652, 594.3877805859885, 633.3792882974618, 647.2168675902211, 711.0507472524134, 8853.64585416589, 8879.24526415262, 8910.242629376446, 9037.728868133967, 9045.68340108093, 523.8480840556272, 631.2992578238152, 657.6350125680293, 683.7675190435176, 696.8153439338579, 3830.559485659129, 3872.5740168581433, 3901.9804900975314, 3916.1971795781797, 3922.1175235572337, 56380.718096409255, 56735.71632141813, 56809.43190568124, 56973.71513142408, 57337.0, 50527.0, 50600.493995060315, 50829.491705083215, 50929.490705093216, 52311.47688523066, 46000.99999999933, 46238.768806156426, 46407.30389044167, 47018.76490617594, 47281.52718472771, 8534.871976920227, 8574.82850343002, 8598.0, 8630.741077767678, 8681.479111253346, 33566.83216583901, 33719.83140084382, 34300.828495857364, 34696.0, 35119.824400877835, 41717.16565668609, 44111.77944110259, 44287.33568996532, 44424.33363499615, 44465.55534444614, 9954.850677239703, 10518.78962420732, 10583.994520520539, 10600.522976465923, 10814.188935830001, 11255.831162532731, 11265.414513548174, 11328.376939268419, 11346.773064538827, 11591.536338546364, 10103.191744660557, 10145.84781228297, 10203.897961020592, 10311.175105991353, 10372.377657340583, 9311.394759340621, 9333.486658233862, 9530.904690953279, 9569.330146889652, 9908.959559246176, 10310.639127630497, 10618.469076546296, 10717.464126793786, 10926.672199834018, 11129.888701112885, 9294.814103718023, 9500.42997420157, 9614.90385096168, 9676.22590192797, 9829.0, 9119.669464376782, 9457.810843783223, 9905.455199963782, 10082.344647597884, 10210.336328138646], "unit": "score", "name": "yui.html", "value": 6277.782652658765}], "name": "dromaeo_css", "value": 7100.1101993479115}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 108.0, 113.0, 112.0, 113.0, 123.0, 111.0, 113.0, 113.0, 111.0], "unit": "score", "name": "ai-astar", "value": 112.9}, {"lowerIsBetter": true, "replicates": [156.0, 133.0, 149.0, 160.0, 126.0, 155.0, 154.0, 127.0, 171.0, 126.0], "unit": "score", "name": "audio-beat-detection", "value": 145.7}, {"lowerIsBetter": true, "replicates": [161.0, 164.0, 162.0, 166.0, 163.0, 161.0, 162.0, 164.0, 170.0, 162.0], "unit": "score", "name": "audio-dft", "value": 163.5}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 73.0, 74.0, 71.0, 72.0, 72.0, 149.0, 71.0, 74.0], "unit": "score", "name": "audio-fft", "value": 80.0}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 136.0, 93.0, 95.0, 94.0, 137.0, 93.0, 94.0, 145.0], "unit": "score", "name": "audio-oscillator", "value": 107.4}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 109.0, 109.0, 109.0, 110.0, 110.0, 109.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.6}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 147.0, 151.0, 147.0, 145.0, 146.0, 147.0, 147.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 146.9}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 97.0], "unit": "score", "name": "imaging-desaturate", "value": 96.3}, {"lowerIsBetter": true, "replicates": [59.0, 60.0, 60.0, 58.0, 60.0, 61.0, 61.0, 60.0, 60.0, 58.0], "unit": "score", "name": "json-parse-financial", "value": 59.7}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 69.0, 68.0, 68.0, 69.0, 69.0, 69.0, 70.0, 69.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.9}, {"lowerIsBetter": true, "replicates": [84.0, 94.0, 86.0, 86.0, 86.0, 85.0, 85.0, 86.0, 87.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 86.4}, {"lowerIsBetter": true, "replicates": [124.0, 116.0, 126.0, 117.0, 119.0, 119.0, 126.0, 120.0, 118.0, 127.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.2}, {"lowerIsBetter": true, "replicates": [148.0, 160.0, 147.0, 153.0, 161.0, 145.0, 158.0, 156.0, 159.0, 147.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 153.4}, {"lowerIsBetter": true, "replicates": [67.0, 69.0, 72.0, 72.0, 68.0, 68.0, 67.0, 68.0, 69.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.7}], "name": "kraken", "value": 1520.6000000000004}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.66326362588507], "unit": "score", "name": "Richards", "value": 214.38904599999995}, {"lowerIsBetter": false, "replicates": [193.23671497584542], "unit": "score", "name": "DeltaBlue", "value": 342.16064999999986}, {"lowerIsBetter": false, "replicates": [282.7254735651682], "unit": "score", "name": "Encrypt", "value": 941.4821969999996}, {"lowerIsBetter": false, "replicates": [5191.709844559586], "unit": "score", "name": "Decrypt", "value": 51.270392215568904}, {"lowerIsBetter": false, "replicates": [1057.0824524312895], "unit": "score", "name": "RayTrace", "value": 700.0295939999999}, {"lowerIsBetter": false, "replicates": [720.4610951008646], "unit": "score", "name": "Earley", "value": 925.050644}, {"lowerIsBetter": false, "replicates": [12207.317073170732], "unit": "score", "name": "Boyer", "value": 54.595370629370635}, {"lowerIsBetter": false, "replicates": [25025.0], "unit": "score", "name": "RegExp", "value": 36.40299700299697}, {"lowerIsBetter": false, "replicates": [554.016620498615], "unit": "score", "name": "Splay", "value": 147.09125500000005}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 21535.65640450078}]} 23:08:40 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "21535.66"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1520.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "7100.11"}}} 23:08:40 INFO - Return code: 0 23:08:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 23:08:40 INFO - # TBPL SUCCESS # 23:08:40 INFO - Running post-action listener: _resource_record_post_action 23:08:40 INFO - Running post-run listener: _resource_record_post_run 23:08:41 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 4096; Write bytes: 554954752; Read time: 16; Write time: 715192 23:08:41 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 235327488; Read time: 0; Write time: 378628 23:08:41 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 4096; Write bytes: 319627264; Read time: 16; Write time: 336564 23:08:41 INFO - Running post-run listener: _upload_blobber_files 23:08:41 INFO - Blob upload gear active. 23:08:41 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 23:08:41 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 23:08:41 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', 'Fx-Team', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 23:08:41 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 Fx-Team -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 23:08:42 INFO - (blobuploader) - INFO - Open directory for files ... 23:08:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:08:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:08:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:43 INFO - (blobuploader) - INFO - Done attempting. 23:08:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:08:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:08:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:08:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:08:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:08:44 INFO - (blobuploader) - INFO - Done attempting. 23:08:44 INFO - (blobuploader) - INFO - Iteration through files over. 23:08:44 INFO - Return code: 0 23:08:44 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 23:08:44 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 23:08:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/05f56dd596c78967dd6b24b10477fa1817259813cefb1ff0b167da7ae4e3af5fecc55d8420f387c63e5d263f1dffdb81e02743c15c10333b7c4d2ba2ef8bc8b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adab2b33785f2698c9f86d27de8f8c60ae619e95c9eff78e7d130ed39be4b3926eff5882a4c29c54461d7bceea59b23b1cfb8ab82eb7c7925a7f225c1c121a98"} 23:08:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 23:08:44 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 23:08:44 INFO - Contents: 23:08:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/05f56dd596c78967dd6b24b10477fa1817259813cefb1ff0b167da7ae4e3af5fecc55d8420f387c63e5d263f1dffdb81e02743c15c10333b7c4d2ba2ef8bc8b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adab2b33785f2698c9f86d27de8f8c60ae619e95c9eff78e7d130ed39be4b3926eff5882a4c29c54461d7bceea59b23b1cfb8ab82eb7c7925a7f225c1c121a98"} 23:08:44 INFO - Copying logs to upload dir... 23:08:44 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1278.228868 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2015-12-21 23:08:43.977950) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-21 23:08:43.981582) ========= 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=a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/05f56dd596c78967dd6b24b10477fa1817259813cefb1ff0b167da7ae4e3af5fecc55d8420f387c63e5d263f1dffdb81e02743c15c10333b7c4d2ba2ef8bc8b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adab2b33785f2698c9f86d27de8f8c60ae619e95c9eff78e7d130ed39be4b3926eff5882a4c29c54461d7bceea59b23b1cfb8ab82eb7c7925a7f225c1c121a98"} build_url:https://queue.taskcluster.net/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011587 build_url: 'https://queue.taskcluster.net/v1/task/XX-D3zUJQECVFWyjmqiRgQ/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/05f56dd596c78967dd6b24b10477fa1817259813cefb1ff0b167da7ae4e3af5fecc55d8420f387c63e5d263f1dffdb81e02743c15c10333b7c4d2ba2ef8bc8b3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adab2b33785f2698c9f86d27de8f8c60ae619e95c9eff78e7d130ed39be4b3926eff5882a4c29c54461d7bceea59b23b1cfb8ab82eb7c7925a7f225c1c121a98"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-21 23:08:44.006719) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 23:08:44.007119) ========= 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=a194aa7faa1be204aaca7d660000020b-1450764617.910726-1405352314 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004642 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-21 23:08:44.090922) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 23:08:44.091280) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-21 23:08:44.091587) ========= ========= Total master_lag: 0.28 =========