builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-005 starttime: 1456570756.65 results: success (0) buildid: 20160222065616 builduid: 9e95d762599340abad2cb4ba43c2f2b7 revision: 0c7dfc6d57d70816a6b07393f67f8b3747e6486d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:16.653032) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:16.653416) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:16.653697) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.009249 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.08 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:16.743486) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:16.743752) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:16.899263) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:16.899561) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004616 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:16.964840) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:16.965111) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:16.965452) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:16.965722) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-27 02:59:17-- 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% 65.1M=0s 2016-02-27 02:59:17 (65.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.178375 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:17.157318) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:17.157588) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015027 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:17.209421) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:17.209715) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0c7dfc6d57d70816a6b07393f67f8b3747e6486d --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0c7dfc6d57d70816a6b07393f67f8b3747e6486d --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=7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-27 02:59:17,760 truncating revision to first 12 chars 2016-02-27 02:59:17,760 Setting DEBUG logging. 2016-02-27 02:59:17,760 attempt 1/10 2016-02-27 02:59:17,760 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0c7dfc6d57d7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-27 02:59:18,163 unpacking tar archive at: mozilla-aurora-0c7dfc6d57d7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.739464 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:17.962089) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:17.962399) ========= echo 0c7dfc6d57d70816a6b07393f67f8b3747e6486d in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'0c7dfc6d57d70816a6b07393f67f8b3747e6486d'] 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=7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 0c7dfc6d57d70816a6b07393f67f8b3747e6486d program finished with exit code 0 elapsedTime=0.003833 script_repo_revision: '0c7dfc6d57d70816a6b07393f67f8b3747e6486d' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:18.012100) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:18.012407) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:18.022275) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 29 secs) (at 2016-02-27 02:59:18.022540) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:59:18 INFO - MultiFileLogger online at 20160227 02:59:18 in /builds/slave/test-pgo 02:59:18 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:59:18 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 02:59:18 INFO - {'append_to_log': False, 02:59:18 INFO - 'base_work_dir': '/builds/slave/test-pgo', 02:59:18 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:59:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 02:59:18 INFO - 'branch': 'Mozilla-Aurora', 02:59:18 INFO - 'buildbot_json_path': 'buildprops.json', 02:59:18 INFO - 'config_files': ('talos/linux_config.py',), 02:59:18 INFO - 'default_actions': ('clobber', 02:59:18 INFO - 'read-buildbot-config', 02:59:18 INFO - 'download-and-extract', 02:59:18 INFO - 'populate-webroot', 02:59:18 INFO - 'create-virtualenv', 02:59:18 INFO - 'install', 02:59:18 INFO - 'run-tests'), 02:59:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:59:18 INFO - 'download_minidump_stackwalk': True, 02:59:18 INFO - 'download_symbols': 'ondemand', 02:59:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:59:18 INFO - 'tooltool.py': '/tools/tooltool.py', 02:59:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:59:18 INFO - '/tools/misc-python/virtualenv.py')}, 02:59:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:59:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:59:18 INFO - 'installer_path': 'installer.exe', 02:59:18 INFO - 'log_level': 'info', 02:59:18 INFO - 'log_name': 'talos', 02:59:18 INFO - 'log_to_console': True, 02:59:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:59:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:59:18 INFO - 'opt_config_files': (), 02:59:18 INFO - 'pip_index': False, 02:59:18 INFO - 'sps_profile': False, 02:59:18 INFO - 'sps_profile_interval': 0, 02:59:18 INFO - 'suite': 'dromaeojs-e10s', 02:59:18 INFO - 'system_bits': '32', 02:59:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:59:18 INFO - 'title': 'talos-linux64-ix-005', 02:59:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:59:18 INFO - 'use_talos_json': True, 02:59:18 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 02:59:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:59:18 INFO - 'work_dir': 'build'} 02:59:18 INFO - ##### 02:59:18 INFO - ##### Running clobber step. 02:59:18 INFO - ##### 02:59:18 INFO - Running pre-action listener: _resource_record_pre_action 02:59:18 INFO - Running main action method: clobber 02:59:18 INFO - rmtree: /builds/slave/test-pgo/build 02:59:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 02:59:19 INFO - Running post-action listener: _resource_record_post_action 02:59:19 INFO - ##### 02:59:19 INFO - ##### Running read-buildbot-config step. 02:59:19 INFO - ##### 02:59:19 INFO - Running pre-action listener: _resource_record_pre_action 02:59:19 INFO - Running main action method: read_buildbot_config 02:59:19 INFO - Using buildbot properties: 02:59:19 INFO - { 02:59:19 INFO - "project": "", 02:59:19 INFO - "product": "firefox", 02:59:19 INFO - "script_repo_revision": "production", 02:59:19 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 02:59:19 INFO - "repository": "", 02:59:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 02:59:19 INFO - "buildid": "20160222065616", 02:59:19 INFO - "pgo_build": "True", 02:59:19 INFO - "basedir": "/builds/slave/test-pgo", 02:59:19 INFO - "buildnumber": 89, 02:59:19 INFO - "slavename": "talos-linux64-ix-005", 02:59:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 02:59:19 INFO - "platform": "ubuntu64_hw", 02:59:19 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 02:59:19 INFO - "branch": "mozilla-aurora", 02:59:19 INFO - "repo_path": "releases/mozilla-aurora", 02:59:19 INFO - "revision": "0c7dfc6d57d70816a6b07393f67f8b3747e6486d", 02:59:19 INFO - "stage_platform": "linux64-pgo", 02:59:19 INFO - "builduid": "9e95d762599340abad2cb4ba43c2f2b7", 02:59:19 INFO - "slavebuilddir": "test" 02:59:19 INFO - } 02:59:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2. 02:59:19 INFO - Running post-action listener: _resource_record_post_action 02:59:19 INFO - ##### 02:59:19 INFO - ##### Running download-and-extract step. 02:59:19 INFO - ##### 02:59:19 INFO - Running pre-action listener: _resource_record_pre_action 02:59:19 INFO - Running main action method: download_and_extract 02:59:19 INFO - mkdir: /builds/slave/test-pgo/build/tests 02:59:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:19 INFO - https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 02:59:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/test_packages.json 02:59:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/test_packages.json 02:59:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 02:59:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 02:59:28 INFO - Downloaded 1448 bytes. 02:59:28 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 02:59:28 INFO - Using the following test package requirements: 02:59:28 INFO - {u'common': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 02:59:28 INFO - u'cppunittest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:28 INFO - u'firefox-46.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 02:59:28 INFO - u'gtest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:28 INFO - u'firefox-46.0a2.en-US.linux-x86_64.gtest.tests.zip'], 02:59:28 INFO - u'jittest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:28 INFO - u'jsshell-linux-x86_64.zip'], 02:59:28 INFO - u'mochitest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:28 INFO - u'firefox-46.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 02:59:28 INFO - u'mozbase': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 02:59:28 INFO - u'reftest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:28 INFO - u'firefox-46.0a2.en-US.linux-x86_64.reftest.tests.zip'], 02:59:28 INFO - u'talos': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:28 INFO - u'firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip'], 02:59:28 INFO - u'web-platform': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:28 INFO - u'firefox-46.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 02:59:28 INFO - u'webapprt': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 02:59:28 INFO - u'xpcshell': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:28 INFO - u'firefox-46.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 02:59:28 INFO - Downloading packages: [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:59:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:28 INFO - https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:59:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 02:59:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 02:59:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 02:59:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:59:40 INFO - Downloaded 23079161 bytes. 02:59:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 02:59:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 02:59:41 INFO - Return code: 0 02:59:41 INFO - Downloading packages: [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:59:41 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')]} 02:59:41 INFO - https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:59:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 02:59:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 02:59:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 02:59:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:00:11 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip: timed out 03:00:11 INFO - retry: attempt #1 caught exception: timed out 03:00:11 INFO - retry: Failed, sleeping 30 seconds before retrying 03:00:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:00:51 INFO - Downloaded 23079161 bytes. 03:00:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 03:00:51 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 03:00:52 INFO - Return code: 0 03:00: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')]} 03:00:52 INFO - https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:00:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip 03:00:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip 03:00:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip 03:00:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:00:53 INFO - Downloaded 10706572 bytes. 03:00:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 03:00:53 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 03:00:53 INFO - Return code: 0 03:00:53 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')]} 03:00:53 INFO - https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:00:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 03:00:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 03:00:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 03:00:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:00:55 INFO - Downloaded 59960416 bytes. 03:00:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 03:00:55 INFO - mkdir: /builds/slave/test-pgo/properties 03:00:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 03:00:55 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 03:00:55 INFO - Contents: 03:00:55 INFO - build_url:https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 03:00:55 INFO - Running post-action listener: _resource_record_post_action 03:00:55 INFO - Running post-action listener: set_extra_try_arguments 03:00:55 INFO - ##### 03:00:55 INFO - ##### Running populate-webroot step. 03:00:55 INFO - ##### 03:00:55 INFO - Running pre-action listener: _resource_record_pre_action 03:00:55 INFO - Running main action method: populate_webroot 03:00:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:00:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:00:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:00:55 INFO - '--tppagecycles', 03:00:55 INFO - '7'], 03:00:55 INFO - 'tests': ['tsvgm']}}, 03:00:55 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:00:55 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:00:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:00:55 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:00:55 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:00:55 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:00:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'talos_options': ['--e10s'], 03:00:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'tests': ['damp', 'tps']}, 03:00:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'talos_options': ['--e10s'], 03:00:55 INFO - 'tests': ['damp', 'tps']}, 03:00:55 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:00:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:00:55 INFO - 'other': {'tests': ['a11yr', 03:00:55 INFO - 'ts_paint', 03:00:55 INFO - 'tpaint', 03:00:55 INFO - 'sessionrestore', 03:00:55 INFO - 'sessionrestore_no_auto_restore']}, 03:00:55 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:00:55 INFO - 'tests': ['a11yr', 03:00:55 INFO - 'ts_paint', 03:00:55 INFO - 'tpaint', 03:00:55 INFO - 'sessionrestore', 03:00:55 INFO - 'sessionrestore_no_auto_restore']}, 03:00:55 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:00:55 INFO - 'tests': ['a11yr', 03:00:55 INFO - 'ts_paint', 03:00:55 INFO - 'tpaint', 03:00:55 INFO - 'sessionrestore', 03:00:55 INFO - 'sessionrestore_no_auto_restore']}, 03:00:55 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:00:55 INFO - 'tests': ['a11yr', 03:00:55 INFO - 'ts_paint', 03:00:55 INFO - 'tpaint', 03:00:55 INFO - 'sessionrestore', 03:00:55 INFO - 'sessionrestore_no_auto_restore']}, 03:00:55 INFO - 'other_l64': {'tests': ['a11yr', 03:00:55 INFO - 'ts_paint', 03:00:55 INFO - 'tpaint', 03:00:55 INFO - 'sessionrestore', 03:00:55 INFO - 'sessionrestore_no_auto_restore']}, 03:00:55 INFO - 'other_nol64': {'tests': ['a11yr', 03:00:55 INFO - 'ts_paint', 03:00:55 INFO - 'tpaint', 03:00:55 INFO - 'sessionrestore', 03:00:55 INFO - 'sessionrestore_no_auto_restore']}, 03:00:55 INFO - 'svgr': {'tests': ['tsvgx', 03:00:55 INFO - 'tsvgr_opacity', 03:00:55 INFO - 'tart', 03:00:55 INFO - 'tscrollx', 03:00:55 INFO - 'cart']}, 03:00:55 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:00:55 INFO - 'tests': ['tsvgx', 03:00:55 INFO - 'tsvgr_opacity', 03:00:55 INFO - 'tart', 03:00:55 INFO - 'tscrollx', 03:00:55 INFO - 'cart']}, 03:00:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'tests': ['tp5o']}, 03:00:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'talos_options': ['--e10s'], 03:00:55 INFO - 'tests': ['tp5o']}, 03:00:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'talos_options': ['--xperf_path', 03:00:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:55 INFO - 'tests': ['tp5n']}, 03:00:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:55 INFO - 'talos_options': ['--e10s', 03:00:55 INFO - '--xperf_path', 03:00:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:55 INFO - 'tests': ['tp5n']}}, 03:00:55 INFO - 'talos.zip': {'path': '', 03:00:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:00:55 INFO - Running post-action listener: _resource_record_post_action 03:00:55 INFO - ##### 03:00:55 INFO - ##### Running create-virtualenv step. 03:00:55 INFO - ##### 03:00:55 INFO - Running pre-action listener: _resource_record_pre_action 03:00:55 INFO - Running main action method: create_virtualenv 03:00:55 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 03:00:55 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 03:00:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 03:00:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:00:56 INFO - Using real prefix '/usr' 03:00:56 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 03:00:56 INFO - Installing distribute.............................................................................................................................................................................................done. 03:00:58 INFO - Installing pip.................done. 03:00:58 INFO - Return code: 0 03:00:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 03:00: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')]} 03:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00: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')]} 03:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e747af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ae5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b777f0>, '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 0x2ae32d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae3760>, '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': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', '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 03:00:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 03:00:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:00:58 INFO - Using env: {'DISPLAY': ':0', 03:00:58 INFO - 'HOME': '/home/cltbld', 03:00:58 INFO - 'LANG': 'en_US.UTF-8', 03:00:58 INFO - 'LANGUAGE': 'en_US:en', 03:00:58 INFO - 'LOGNAME': 'cltbld', 03:00:58 INFO - 'MAIL': '/var/mail/cltbld', 03:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:58 INFO - 'MOZ_NO_REMOTE': '1', 03:00:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:58 INFO - 'NO_EM_RESTART': '1', 03:00:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:00:58 INFO - 'PWD': '/builds/slave/test-pgo', 03:00:58 INFO - 'SHELL': '/bin/bash', 03:00:58 INFO - 'SHLVL': '1', 03:00:58 INFO - 'TERM': 'linux', 03:00:58 INFO - 'TMOUT': '86400', 03:00:58 INFO - 'USER': 'cltbld', 03:00:58 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', 03:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:58 INFO - '_': '/tools/buildbot/bin/python'} 03:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:58 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 03:00:58 INFO - Cleaning up... 03:00:58 INFO - Return code: 0 03:00:58 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 03:00: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')]} 03:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00: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')]} 03:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e747af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ae5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b777f0>, '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 0x2ae32d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae3760>, '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': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', '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 03:00:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 03:00:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 03:00:58 INFO - Using env: {'DISPLAY': ':0', 03:00:58 INFO - 'HOME': '/home/cltbld', 03:00:58 INFO - 'LANG': 'en_US.UTF-8', 03:00:58 INFO - 'LANGUAGE': 'en_US:en', 03:00:58 INFO - 'LOGNAME': 'cltbld', 03:00:58 INFO - 'MAIL': '/var/mail/cltbld', 03:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:58 INFO - 'MOZ_NO_REMOTE': '1', 03:00:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:58 INFO - 'NO_EM_RESTART': '1', 03:00:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:00:58 INFO - 'PWD': '/builds/slave/test-pgo', 03:00:58 INFO - 'SHELL': '/bin/bash', 03:00:58 INFO - 'SHLVL': '1', 03:00:58 INFO - 'TERM': 'linux', 03:00:58 INFO - 'TMOUT': '86400', 03:00:58 INFO - 'USER': 'cltbld', 03:00:58 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', 03:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:58 INFO - '_': '/tools/buildbot/bin/python'} 03:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:59 INFO - Downloading/unpacking psutil>=0.7.1 03:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 03:01:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:00 INFO - Installing collected packages: psutil 03:01:00 INFO - Running setup.py install for psutil 03:01:01 INFO - building 'psutil._psutil_linux' extension 03:01:01 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 03:01:01 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 03:01:01 INFO - building 'psutil._psutil_posix' extension 03:01:01 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 03:01:01 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 03:01:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:01:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:01:01 INFO - Successfully installed psutil 03:01:01 INFO - Cleaning up... 03:01:01 INFO - Return code: 0 03:01:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 03:01: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')]} 03:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01: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')]} 03:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e747af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ae5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b777f0>, '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 0x2ae32d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae3760>, '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': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', '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 03:01:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 03:01:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:01:01 INFO - Using env: {'DISPLAY': ':0', 03:01:01 INFO - 'HOME': '/home/cltbld', 03:01:01 INFO - 'LANG': 'en_US.UTF-8', 03:01:01 INFO - 'LANGUAGE': 'en_US:en', 03:01:01 INFO - 'LOGNAME': 'cltbld', 03:01:01 INFO - 'MAIL': '/var/mail/cltbld', 03:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:01 INFO - 'MOZ_NO_REMOTE': '1', 03:01:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:01 INFO - 'NO_EM_RESTART': '1', 03:01:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:01 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:01 INFO - 'SHELL': '/bin/bash', 03:01:01 INFO - 'SHLVL': '1', 03:01:01 INFO - 'TERM': 'linux', 03:01:01 INFO - 'TMOUT': '86400', 03:01:01 INFO - 'USER': 'cltbld', 03:01:01 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', 03:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:01 INFO - '_': '/tools/buildbot/bin/python'} 03:01:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:01:01 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 03:01:01 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 03:01:01 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 03:01:01 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 03:01:01 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 03:01:01 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 03:01:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:01:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:01:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:01:03 INFO - Installing collected packages: mozsystemmonitor 03:01:03 INFO - Running setup.py install for mozsystemmonitor 03:01:03 INFO - Successfully installed mozsystemmonitor 03:01:03 INFO - Cleaning up... 03:01:03 INFO - Return code: 0 03:01:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 03:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e747af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ae5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b777f0>, '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 0x2ae32d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae3760>, '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': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', '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 03:01:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 03:01:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:01:03 INFO - Using env: {'DISPLAY': ':0', 03:01:03 INFO - 'HOME': '/home/cltbld', 03:01:03 INFO - 'LANG': 'en_US.UTF-8', 03:01:03 INFO - 'LANGUAGE': 'en_US:en', 03:01:03 INFO - 'LOGNAME': 'cltbld', 03:01:03 INFO - 'MAIL': '/var/mail/cltbld', 03:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:03 INFO - 'MOZ_NO_REMOTE': '1', 03:01:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:03 INFO - 'NO_EM_RESTART': '1', 03:01:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:03 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:03 INFO - 'SHELL': '/bin/bash', 03:01:03 INFO - 'SHLVL': '1', 03:01:03 INFO - 'TERM': 'linux', 03:01:03 INFO - 'TMOUT': '86400', 03:01:03 INFO - 'USER': 'cltbld', 03:01:03 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', 03:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:03 INFO - '_': '/tools/buildbot/bin/python'} 03:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:03 INFO - Downloading/unpacking blobuploader==1.2.4 03:01:03 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 03:01:03 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 03:01:03 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 03:01:03 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 03:01:03 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 03:01:03 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 03:01:05 INFO - Downloading blobuploader-1.2.4.tar.gz 03:01:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:01:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:01: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 03:01: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 03:01: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 03:01: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 03:01: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 03:01: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 03:01:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 03:01:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:01:06 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 03:01:06 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 03:01:06 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 03:01:06 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 03:01:06 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 03:01:06 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 03:01:06 INFO - Downloading docopt-0.6.1.tar.gz 03:01:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 03:01:06 INFO - Installing collected packages: blobuploader, requests, docopt 03:01:06 INFO - Running setup.py install for blobuploader 03:01:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:01:06 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 03:01:06 INFO - Running setup.py install for requests 03:01:07 INFO - Running setup.py install for docopt 03:01:07 INFO - Successfully installed blobuploader requests docopt 03:01:07 INFO - Cleaning up... 03:01:07 INFO - Return code: 0 03:01:07 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:01: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')]} 03:01:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01: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')]} 03:01:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e747af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ae5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b777f0>, '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 0x2ae32d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae3760>, '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': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', '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 03:01:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 03:01:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:01:07 INFO - Using env: {'DISPLAY': ':0', 03:01:07 INFO - 'HOME': '/home/cltbld', 03:01:07 INFO - 'LANG': 'en_US.UTF-8', 03:01:07 INFO - 'LANGUAGE': 'en_US:en', 03:01:07 INFO - 'LOGNAME': 'cltbld', 03:01:07 INFO - 'MAIL': '/var/mail/cltbld', 03:01:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:07 INFO - 'MOZ_NO_REMOTE': '1', 03:01:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:07 INFO - 'NO_EM_RESTART': '1', 03:01:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:07 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:07 INFO - 'SHELL': '/bin/bash', 03:01:07 INFO - 'SHLVL': '1', 03:01:07 INFO - 'TERM': 'linux', 03:01:07 INFO - 'TMOUT': '86400', 03:01:07 INFO - 'USER': 'cltbld', 03:01:07 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', 03:01:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:07 INFO - '_': '/tools/buildbot/bin/python'} 03:01:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:01:07 INFO - Running setup.py (path:/tmp/pip-ls41tn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:01:07 INFO - Running setup.py (path:/tmp/pip-RpDCEu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:01:07 INFO - Running setup.py (path:/tmp/pip-SZCS27-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:01:07 INFO - Running setup.py (path:/tmp/pip-ZCuYvR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:01:07 INFO - Running setup.py (path:/tmp/pip-1Y4sZI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:01:07 INFO - Running setup.py (path:/tmp/pip-fSKdO0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:01:07 INFO - Running setup.py (path:/tmp/pip-vrn9O3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:01:07 INFO - Running setup.py (path:/tmp/pip-9LeOzf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:01:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:01:07 INFO - Running setup.py (path:/tmp/pip-cZ1oE8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:01:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:01:08 INFO - Running setup.py (path:/tmp/pip-Ob3vzD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:01:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:01:08 INFO - Running setup.py (path:/tmp/pip-D2VH4C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:01:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:01:08 INFO - Running setup.py (path:/tmp/pip-MVdgBW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:01:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:01:08 INFO - Running setup.py (path:/tmp/pip-Bcf4On-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:01:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:01:08 INFO - Running setup.py (path:/tmp/pip-4zKRtA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:01:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:01:08 INFO - Running setup.py (path:/tmp/pip-AsYTgP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:01:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:01:08 INFO - Running setup.py (path:/tmp/pip-pOm8Ol-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:01:08 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:01:08 INFO - Running setup.py (path:/tmp/pip-4aip8i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:01:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:01:08 INFO - Running setup.py install for manifestparser 03:01:08 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 03:01:08 INFO - Running setup.py install for mozcrash 03:01:08 INFO - Running setup.py install for mozdebug 03:01:08 INFO - Running setup.py install for mozdevice 03:01:09 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 03:01:09 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 03:01:09 INFO - Running setup.py install for mozfile 03:01:09 INFO - Running setup.py install for mozhttpd 03:01:09 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 03:01:09 INFO - Running setup.py install for mozinfo 03:01:09 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 03:01:09 INFO - Running setup.py install for mozInstall 03:01:09 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 03:01:09 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 03:01:09 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 03:01:09 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 03:01:09 INFO - Running setup.py install for mozleak 03:01:09 INFO - Running setup.py install for mozlog 03:01:09 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 03:01:09 INFO - Running setup.py install for moznetwork 03:01:09 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 03:01:09 INFO - Running setup.py install for mozprocess 03:01:09 INFO - Running setup.py install for mozprofile 03:01:09 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 03:01:09 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 03:01:09 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 03:01:10 INFO - Running setup.py install for mozrunner 03:01:10 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 03:01:10 INFO - Running setup.py install for mozscreenshot 03:01:10 INFO - Running setup.py install for moztest 03:01:10 INFO - Running setup.py install for mozversion 03:01:10 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 03:01:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:01:10 INFO - Cleaning up... 03:01:10 INFO - Return code: 0 03:01:10 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e747af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ae5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b777f0>, '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 0x2ae32d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae3760>, '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': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', '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 03:01:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 03:01:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:01:10 INFO - Using env: {'DISPLAY': ':0', 03:01:10 INFO - 'HOME': '/home/cltbld', 03:01:10 INFO - 'LANG': 'en_US.UTF-8', 03:01:10 INFO - 'LANGUAGE': 'en_US:en', 03:01:10 INFO - 'LOGNAME': 'cltbld', 03:01:10 INFO - 'MAIL': '/var/mail/cltbld', 03:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:10 INFO - 'MOZ_NO_REMOTE': '1', 03:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:10 INFO - 'NO_EM_RESTART': '1', 03:01:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:10 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:10 INFO - 'SHELL': '/bin/bash', 03:01:10 INFO - 'SHLVL': '1', 03:01:10 INFO - 'TERM': 'linux', 03:01:10 INFO - 'TMOUT': '86400', 03:01:10 INFO - 'USER': 'cltbld', 03:01:10 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', 03:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:10 INFO - '_': '/tools/buildbot/bin/python'} 03:01:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:01:10 INFO - Running setup.py (path:/tmp/pip-2HJMZw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:01:10 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)) 03:01:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:01:10 INFO - Running setup.py (path:/tmp/pip-LFmyPN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:01:10 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)) 03:01:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:01:10 INFO - Running setup.py (path:/tmp/pip-K3ksQy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:01:10 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)) 03:01:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:01:10 INFO - Running setup.py (path:/tmp/pip-Br3z5o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:01:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 03:01:10 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:01:10 INFO - Running setup.py (path:/tmp/pip-YDPopN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:01:11 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)) 03:01:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:01:11 INFO - Running setup.py (path:/tmp/pip-kXfifY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:01:11 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)) 03:01:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:01:11 INFO - Running setup.py (path:/tmp/pip-6DIAJF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:01:11 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)) 03:01:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:01:11 INFO - Running setup.py (path:/tmp/pip-jNAmg5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:01:11 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)) 03:01:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:01:11 INFO - Running setup.py (path:/tmp/pip-ubLJaK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:01:11 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)) 03:01:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:01:11 INFO - Running setup.py (path:/tmp/pip-xaoQWR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:01:11 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)) 03:01:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:01:11 INFO - Running setup.py (path:/tmp/pip-tAgWBG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:01:11 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)) 03:01:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:01:11 INFO - Running setup.py (path:/tmp/pip-oDD1dj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:01:11 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)) 03:01:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:01:11 INFO - Running setup.py (path:/tmp/pip-vnU2bz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:01:11 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)) 03:01:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:01:11 INFO - Running setup.py (path:/tmp/pip-t3Afgj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:01:11 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)) 03:01:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:01:11 INFO - Running setup.py (path:/tmp/pip-XFqTxk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:01:11 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)) 03:01:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:01:11 INFO - Running setup.py (path:/tmp/pip-ZdWZc4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:01:11 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)) 03:01:11 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:01:11 INFO - Running setup.py (path:/tmp/pip-g5wvX5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:01:11 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)) 03:01:11 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)) 03:01:11 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)) 03:01:11 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)) 03:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 03:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 03:01:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 03:01:11 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 03:01:11 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 03:01:11 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 03:01:11 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 03:01:11 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 03:01:11 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 03:01:13 INFO - Downloading blessings-1.6.tar.gz 03:01:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 03:01:13 INFO - Installing collected packages: blessings 03:01:13 INFO - Running setup.py install for blessings 03:01:13 INFO - Successfully installed blessings 03:01:13 INFO - Cleaning up... 03:01:13 INFO - Return code: 0 03:01:13 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 03:01:13 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:01:13 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:01:14 INFO - Reading from file tmpfile_stdout 03:01:14 INFO - Current package versions: 03:01:14 INFO - argparse == 1.2.1 03:01:14 INFO - blessings == 1.6 03:01:14 INFO - blobuploader == 1.2.4 03:01:14 INFO - docopt == 0.6.1 03:01:14 INFO - manifestparser == 1.1 03:01:14 INFO - mozInstall == 1.12 03:01:14 INFO - mozcrash == 0.16 03:01:14 INFO - mozdebug == 0.1 03:01:14 INFO - mozdevice == 0.48 03:01:14 INFO - mozfile == 1.2 03:01:14 INFO - mozhttpd == 0.7 03:01:14 INFO - mozinfo == 0.9 03:01:14 INFO - mozleak == 0.1 03:01:14 INFO - mozlog == 3.1 03:01:14 INFO - moznetwork == 0.27 03:01:14 INFO - mozprocess == 0.22 03:01:14 INFO - mozprofile == 0.28 03:01:14 INFO - mozrunner == 6.11 03:01:14 INFO - mozscreenshot == 0.1 03:01:14 INFO - mozsystemmonitor == 0.0 03:01:14 INFO - moztest == 0.7 03:01:14 INFO - mozversion == 1.4 03:01:14 INFO - psutil == 3.1.1 03:01:14 INFO - requests == 1.2.3 03:01:14 INFO - wsgiref == 0.1.2 03:01:14 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e747af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ae5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b777f0>, '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 0x2ae32d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae3760>, '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': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', '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 03:01:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 03:01:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:01:14 INFO - Using env: {'DISPLAY': ':0', 03:01:14 INFO - 'HOME': '/home/cltbld', 03:01:14 INFO - 'LANG': 'en_US.UTF-8', 03:01:14 INFO - 'LANGUAGE': 'en_US:en', 03:01:14 INFO - 'LOGNAME': 'cltbld', 03:01:14 INFO - 'MAIL': '/var/mail/cltbld', 03:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:14 INFO - 'MOZ_NO_REMOTE': '1', 03:01:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:14 INFO - 'NO_EM_RESTART': '1', 03:01:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:14 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:14 INFO - 'SHELL': '/bin/bash', 03:01:14 INFO - 'SHLVL': '1', 03:01:14 INFO - 'TERM': 'linux', 03:01:14 INFO - 'TMOUT': '86400', 03:01:14 INFO - 'USER': 'cltbld', 03:01:14 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', 03:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:14 INFO - '_': '/tools/buildbot/bin/python'} 03:01:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:14 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)) 03:01:14 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)) 03:01:14 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)) 03:01:14 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)) 03:01:14 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)) 03:01:14 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)) 03:01:14 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)) 03:01:14 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)) 03:01:14 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)) 03:01:14 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)) 03:01:14 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)) 03:01:14 INFO - Cleaning up... 03:01:14 INFO - Return code: 0 03:01:14 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 03:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:14 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e747af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ae5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b777f0>, '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 0x2ae32d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae3760>, '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': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', '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 03:01:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 03:01:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:01:14 INFO - Using env: {'DISPLAY': ':0', 03:01:14 INFO - 'HOME': '/home/cltbld', 03:01:14 INFO - 'LANG': 'en_US.UTF-8', 03:01:14 INFO - 'LANGUAGE': 'en_US:en', 03:01:14 INFO - 'LOGNAME': 'cltbld', 03:01:14 INFO - 'MAIL': '/var/mail/cltbld', 03:01:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:14 INFO - 'MOZ_NO_REMOTE': '1', 03:01:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:14 INFO - 'NO_EM_RESTART': '1', 03:01:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:14 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:14 INFO - 'SHELL': '/bin/bash', 03:01:14 INFO - 'SHLVL': '1', 03:01:14 INFO - 'TERM': 'linux', 03:01:14 INFO - 'TMOUT': '86400', 03:01:14 INFO - 'USER': 'cltbld', 03:01:14 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', 03:01:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:14 INFO - '_': '/tools/buildbot/bin/python'} 03:01:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:14 INFO - Downloading/unpacking jsonschema 03:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:01:16 INFO - Downloading/unpacking functools32 (from jsonschema) 03:01:16 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 03:01:16 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 03:01:16 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 03:01:16 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 03:01:16 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 03:01:16 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 03:01:16 INFO - Downloading functools32-3.2.3-2.tar.gz 03:01:16 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 03:01:16 INFO - warning: no files found matching '*.txt' 03:01:16 INFO - no previously-included directories found matching 'build' 03:01:16 INFO - no previously-included directories found matching 'dist' 03:01:16 INFO - no previously-included directories found matching '.git*' 03:01:16 INFO - Installing collected packages: jsonschema, functools32 03:01:16 INFO - Running setup.py install for functools32 03:01:16 INFO - warning: no files found matching '*.txt' 03:01:16 INFO - no previously-included directories found matching 'build' 03:01:16 INFO - no previously-included directories found matching 'dist' 03:01:16 INFO - no previously-included directories found matching '.git*' 03:01:17 INFO - Successfully installed jsonschema functools32 03:01:17 INFO - Cleaning up... 03:01:17 INFO - Return code: 0 03:01:17 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 03:01:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:17 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5e747af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a168a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ae5230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b777f0>, '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 0x2ae32d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae3760>, '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': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', '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 03:01:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 03:01:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:01:17 INFO - Using env: {'DISPLAY': ':0', 03:01:17 INFO - 'HOME': '/home/cltbld', 03:01:17 INFO - 'LANG': 'en_US.UTF-8', 03:01:17 INFO - 'LANGUAGE': 'en_US:en', 03:01:17 INFO - 'LOGNAME': 'cltbld', 03:01:17 INFO - 'MAIL': '/var/mail/cltbld', 03:01:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:17 INFO - 'MOZ_NO_REMOTE': '1', 03:01:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:17 INFO - 'NO_EM_RESTART': '1', 03:01:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:17 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:17 INFO - 'SHELL': '/bin/bash', 03:01:17 INFO - 'SHLVL': '1', 03:01:17 INFO - 'TERM': 'linux', 03:01:17 INFO - 'TMOUT': '86400', 03:01:17 INFO - 'USER': 'cltbld', 03:01:17 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', 03:01:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:17 INFO - '_': '/tools/buildbot/bin/python'} 03:01:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:17 INFO - Downloading/unpacking flake8 03:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:01:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:01:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 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 03:01:19 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 03:01:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:01:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:01:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:01:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:01:19 INFO - no previously-included directories found matching 'docs/_build' 03:01:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:01:19 INFO - Running setup.py install for pep8 03:01:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:01:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:01:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:01:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:01:19 INFO - no previously-included directories found matching 'docs/_build' 03:01:19 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 03:01:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:01:19 INFO - Cleaning up... 03:01:20 INFO - Return code: 0 03:01:20 INFO - Running post-action listener: _resource_record_post_action 03:01:20 INFO - Running post-action listener: _start_resource_monitoring 03:01:20 INFO - Starting resource monitoring. 03:01:20 INFO - ##### 03:01:20 INFO - ##### Running install step. 03:01:20 INFO - ##### 03:01:20 INFO - Running pre-action listener: _resource_record_pre_action 03:01:20 INFO - Running main action method: install 03:01:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:01:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:01:20 INFO - Reading from file tmpfile_stdout 03:01:20 INFO - Detecting whether we're running mozinstall >=1.0... 03:01:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 03:01:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 03:01:20 INFO - Reading from file tmpfile_stdout 03:01:20 INFO - Output received: 03:01:20 INFO - Usage: mozinstall [options] installer 03:01:20 INFO - Options: 03:01:20 INFO - -h, --help show this help message and exit 03:01:20 INFO - -d DEST, --destination=DEST 03:01:20 INFO - Directory to install application into. [default: 03:01:20 INFO - "/builds/slave/test-pgo"] 03:01:20 INFO - --app=APP Application being installed. [default: firefox] 03:01:20 INFO - mkdir: /builds/slave/test-pgo/build/application 03:01:20 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 03:01:20 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 03:01:35 INFO - Reading from file tmpfile_stdout 03:01:35 INFO - Output received: 03:01:35 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 03:01:35 INFO - Running post-action listener: _resource_record_post_action 03:01:35 INFO - ##### 03:01:35 INFO - ##### Running run-tests step. 03:01:35 INFO - ##### 03:01:35 INFO - Running pre-action listener: _resource_record_pre_action 03:01:35 INFO - Running main action method: run_tests 03:01:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 03:01:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 03:01:35 INFO - Python 2.7.3 03:01:35 INFO - Return code: 0 03:01:35 INFO - grabbing minidump binary from tooltool 03:01:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:35 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 0x2b777f0>, '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 0x2ae32d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2ae3760>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 03:01:35 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 03:01:35 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 03:01:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:01:35 INFO - Return code: 0 03:01:35 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 03:01:35 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 03:01:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 03:01:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 03:01:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 03:01:35 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 03:01:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 03:01:36 INFO - Return code: 0 03:01:36 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 03:01:36 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 03:01:36 INFO - Using env: {'DISPLAY': ':0', 03:01:36 INFO - 'HOME': '/home/cltbld', 03:01:36 INFO - 'LANG': 'en_US.UTF-8', 03:01:36 INFO - 'LANGUAGE': 'en_US:en', 03:01:36 INFO - 'LOGNAME': 'cltbld', 03:01:36 INFO - 'MAIL': '/var/mail/cltbld', 03:01:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:01:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 03:01:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:36 INFO - 'MOZ_NO_REMOTE': '1', 03:01:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:01:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:36 INFO - 'NO_EM_RESTART': '1', 03:01:36 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:36 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:36 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 03:01:36 INFO - 'SHELL': '/bin/bash', 03:01:36 INFO - 'SHLVL': '1', 03:01:36 INFO - 'TERM': 'linux', 03:01:36 INFO - 'TMOUT': '86400', 03:01:36 INFO - 'USER': 'cltbld', 03:01:36 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170', 03:01:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:36 INFO - '_': '/tools/buildbot/bin/python'} 03:01:36 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 03:01:36 INFO - mozversion application_buildid: 20160222065616 03:01:36 INFO - mozversion application_changeset: 0c7dfc6d57d70816a6b07393f67f8b3747e6486d 03:01:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:01:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:01:36 INFO - mozversion application_name: Firefox 03:01:36 INFO - mozversion application_remotingname: firefox-dev 03:01:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:01:36 INFO - mozversion application_vendor: Mozilla 03:01:36 INFO - mozversion application_version: 46.0a2 03:01:36 INFO - mozversion platform_buildid: 20160222065616 03:01:36 INFO - mozversion platform_changeset: 0c7dfc6d57d70816a6b07393f67f8b3747e6486d 03:01:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:01:36 INFO - mozversion platform_version: 46.0a2 03:01:36 INFO - using testdate: 1456570896 03:01:36 INFO - actual date: 1456570896 03:01:36 INFO - starting webserver on 'localhost:40267' 03:01:36 INFO - SUITE-START | Running 3 tests 03:01:36 INFO - TEST-START | dromaeo_css 03:01:36 INFO - operating with platform_type : linux_ 03:01:36 INFO - Initialising browser for dromaeo_css test... 03:01:36 INFO - TEST-INFO | started process 8637 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEcc99R/profile http://localhost:40267/getInfo.html) 03:01:40 INFO - PROCESS | 8637 | 03:01:40 INFO - PROCESS | 8637 | (process:8715): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 03:01:40 INFO - PROCESS | 8637 | __metrics Screen width/height:1600/1200 03:01:40 INFO - PROCESS | 8637 | colorDepth:24 03:01:40 INFO - PROCESS | 8637 | Browser inner width/height: 1024/697 03:01:40 INFO - PROCESS | 8637 | __metrics 03:01:41 INFO - TEST-INFO | 8637: exit 0 03:01:41 INFO - Browser initialized. 03:01:41 INFO - Running cycle 1/1 for dromaeo_css test... 03:01:41 INFO - TEST-INFO | started process 8765 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEcc99R/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:43 INFO - PROCESS | 8765 | 03:01:43 INFO - PROCESS | 8765 | (process:8828): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 03:01:53 INFO - PROCESS | 8765 | RSS: Main: 140820480 03:01:53 INFO - PROCESS | 8765 | 03:02:43 INFO - PROCESS | 8765 | 1456570963321 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] 03:04:51 INFO - PROCESS | 8765 | Cycle 1(1): loaded http://localhost:40267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40267/tests/dromaeo/cssquery-ext.html) 03:04:52 INFO - PROCESS | 8765 | RSS: Main: 146259968 03:04:52 INFO - PROCESS | 8765 | 03:07:53 INFO - PROCESS | 8765 | Cycle 1(1): loaded http://localhost:40267/tests/dromaeo/cssquery-ext.html (next: http://localhost:40267/tests/dromaeo/cssquery-jquery.html) 03:07:54 INFO - PROCESS | 8765 | RSS: Main: 143319040 03:07:54 INFO - PROCESS | 8765 | 03:10:56 INFO - PROCESS | 8765 | Cycle 1(1): loaded http://localhost:40267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40267/tests/dromaeo/cssquery-mootools.html) 03:10:56 INFO - PROCESS | 8765 | RSS: Main: 144031744 03:10:56 INFO - PROCESS | 8765 | 03:13:58 INFO - PROCESS | 8765 | Cycle 1(1): loaded http://localhost:40267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40267/tests/dromaeo/cssquery-prototype.html) 03:13:58 INFO - PROCESS | 8765 | RSS: Main: 143495168 03:13:58 INFO - PROCESS | 8765 | 03:17:01 INFO - PROCESS | 8765 | Cycle 1(1): loaded http://localhost:40267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40267/tests/dromaeo/cssquery-yui.html) 03:17:01 INFO - PROCESS | 8765 | RSS: Main: 145158144 03:17:01 INFO - PROCESS | 8765 | 03:20:04 INFO - PROCESS | 8765 | Cycle 1(1): loaded http://localhost:40267/tests/dromaeo/cssquery-yui.html (next: http://localhost:40267/tests/dromaeo/cssquery-dojo.html) 03:20:04 INFO - PROCESS | 8765 | RSS: Main: 146853888 03:20:04 INFO - PROCESS | 8765 | 03:20:04 INFO - PROCESS | 8765 | __start_tp_report 03:20:04 INFO - PROCESS | 8765 | _x_x_mozilla_page_load 03:20:04 INFO - PROCESS | 8765 | _x_x_mozilla_page_load_details 03:20:04 INFO - PROCESS | 8765 | |i|pagename|runs| 03:20:04 INFO - PROCESS | 8765 | |0;dojo.html;3260.2990357073227;3326.085326535204;3326.5841769778776;3366.124807550037;3366.8148251846155;5287.206918962158;5318.734063296834;5330.227117068028;5365.061114304991;5382.031234377815;5362.410134885159;5366.356037275523;5384.6769193848395;5384.973075134619;5391.16436952272;4953.603711703073;4995.450500444948;5043.722595257259;5058.822941197059;5069.670471419346;4831.202851529493;4922.532359425867;4969.155243608595;4971.577415919651;5007.599392048645;4972.328735620681;5006.54941055305;5013.874653133665;5015.297858299823;5018.749062546876;4982.252662100677;4982.800687972477;5005.549500544968;5014.59883209346;5017.197248440269;4828.444728856191;4840.273958906157;4902.558769710708;4951.133551628451;4975.4527002029745;5243.10867152585;5266.420693723688;5275.498827611371;5283.735813209324;5300.178472336794;5815.040518314515;5840.88318233633;5844.970775146139;5847.386024467455;5850.6489610623075;5617.157426386034;5709.3434255060365;5743.10981797822;5752.856178595527;5858.266459853472;5441.673499590037;5452.100403433429;5455.563554915597;5458.181272809111;5461.590380721462;5878.500327472213;5885.5585831062845;5890.6171098879295;5893.499052580493;5896.734646940898;5654.293213348332;5675.631083979528;5676.858078547987;5726.198332233491;5745.109508026263;5754.539636829044;5755.769769209269;5758.3665796762325;5775.16260142277;5784.3926387729525;5660.122680984454;5701.116326969326;5711.771529138871;5727.398623144593;5758.62568933018;5686.687232202185;5744.368119506851;5794.971025144931;5795.768169273182;5827.009408400582;4444.2222611042935;4458.375827384169;4460.799264033127;4463.218936686132;4464.821407143743;3981.5620281768984;3982.5818289079807;4022.7787471688753;4036.7981600920425;4037.9192416151513;5509.559235261169;5511.200875872976;5534.833954981357;5576.33084029919;5588.133839254922;5699.230603868507;5743.282089738783;5781.682007489629;5790.189373487715;5794;5396.865078373072;5475.917861232085;5480.862978425571;5485.588580856452;5496.203050557647;5058.595312375001;5074.06129865976;5075.0357432087785;5078.644494885322;5083.313752643346;5970.641761494324;5998.400159983967;6014.398560143951;6022.849428764316;6023.488003519662;5722.914156287576;5794.275715535558;5807.564432667482;5870.148828419855;5898.351181370132;5893.793717219876;5907.3797251288;5914.467697907209;5915.349311575637;5926.496247818984;5656.6888821115235;5691.943080569141;5696.572757043155;5725;5725.370209277059;5737.426257374229;5776.075827867521;5825.737841797045;5864.208331875142;5892.469677728854;5567.610267281251;5615.2700148980375;5657.688827114548;5663.745131469176;5671.064274394678;5589.80435684749;5663.660180389025;5670.773169073191;5671.432856714296;5672.773089076556;5527.5577953764405;5538.667679939216;5593.720313984366;5615.382307946041;5641.633293835887;5660.603757736919;5742.310922689304;5744.281964754406;5754.5684073695475;5792.362840087671;5515.724213789214;5586.050371436947;5658.349289831722;5667.319921609434;5683.60214784961 03:20:04 INFO - PROCESS | 8765 | |1;ext.html;553.3232329886041;572.1960645293362;582.9972447390487;589.022223109638;610.2646311195005;40917.181656366854;42423;42490.78754606222;42540.787296063514;42563.361549576744;40581.59418405815;40708.389374159415;41067;41067.58932410675;41167.17665646685;7604.125525564548;7624.27569380908;7647.388208943299;7651.349635281008;7675.385969122484;8770.079141690127;8828.470291782482;8844.823103537925;8870.225954809031;8882.733517994438;8854.955725221365;8953.686620968267;8963;8975.102489751005;8989.101089890992;9059.818803623924;9064.864027039599;9124.361294709406;9124.543772811367;9167.449953002775;8993.14565116313;9060.365774395796;9080.228180604656;9123.178913897782;9162.496062716547;43573.564264357265;44068.55931440677;44122.558774412166;44341.77829110866;44352.11295774099;3613.819309034564;3695.57500887396;3755.117547376364;3762.6613604775703;3984.980075099635;32608.510872336934;33136.3372732544;33769.32461350784;34012.31975360479;34129.488057679155;4226.112516371566;4288.9142217155795;4290.120525292307;4292.399064131024;4392.912141757179;303530;304328;305294.4735276332;305565;306586;67932.32067679359;68036.6598167006;68181.3181868175;68193.65903170452;68428.6578567104;71254.2874571258;72146.63926680334;72260;72263.63868180626;72287;18549.90725046366;19208.80791192098;19238.61522769565;19247.422577322704;19302.61394772125;14370.928145359207;14390.784138237936;14441.855581444051;14454.783178252335;14488.275586220647;22749.43126421853;23177;23209.651855222186;23355.532889342117;23456.7654323458;8661.956690216635;8900.821983560292;9023.458592484472;9055.456672599665;9088.09181062067;1090.536521978156;1114.9665510034713;1154.5612667186583;1199.8680145184023;1236.9381530923417;31421;33546.66453335436;33727.494087588704;33918;33970.660293397246;1191.6603767926272;1201.1111777284746;1221.2001139253803;1236.9918516409277;1280.5454063807401;15081.773773393408;15232.009919355207;15380.30788614515;15742.68514629701;15769.44806931752;433249;539345;551226;552384;556788;54925.45074549203;56295;56316.43683563194;56501.43498565044;56726.14910776342;1390.5897760160979;1392.4360633943068;1444.3861358922416;1472.4183947340955;1489.865912067919;32204.516932246504;33844;33899.03137083014;34096.82951585326;34351.4847277286;52475;52959.470405295455;53072;53156.73421633022;53205.20192197197;324884;327148;327456;329934;330630;34893.476597850546;35423.64576354204;35517;35577.64422355847;35607.10982225465;47231.29153062638;47717;47798.283025755336;48204.276935845286;48834.511654883;40136.59863401329;40431.79784101061;40845.59154408418;41191.382129268684;41885.79057104695;40755.7962210187;40755.7962210187;40775.38836917508;41053.589464104974;43148.13703725971;37014.62985370112;37185.814070929475;37661;37710.81144594369;37977.62022379741;31233.687663123077;31445.213869653442;31901.840490797396;32026.83986580052;34189.82905085458;27225.72774272232;27490.862545687145;27705;27846.16461506158;27864.303392415353 03:20:04 INFO - PROCESS | 8765 | |2;jquery.html;396.3697720624207;399.0582225946765;399.43479975834197;399.6183644619389;400.21957183492196;10569.788604227911;10899.83650245247;17045.403410880623;17520.91239543802;17642.64714705707;18065.187066582035;18269.903805771628;18383.908080459612;18472.44582662519;18744.43766686998;6035.51715862731;6339.746410143589;6344.904826427607;6357.237131544198;6373.948298530737;4583;6126.938730612704;6163.322034576193;6905.551139175937;6908.965455172717;6682.532222744409;6897.517173797836;6945.756898508554;6954.756583519578;6967.651617419108;6755.898661520081;6761.425278851303;7001.12485939255;7051.753188638399;7068.575885446839;6534.869302613921;6751.189857217117;6754.932450675479;6780.254172041072;6786.932130678729;23083.192088277;23312.76687233123;23713.881430592908;23724;24074.759252407428;2509.5482813093636;2518.4837108392917;2550.9489810203877;2565.422779874532;2584.2118153963033;13754.105983111165;14046.92976535121;14514.854851451455;15293.61765955849;15470.071795692294;1983.8512111591688;2020.1717295908607;2055.5683306505653;2071.4096482502414;2081.2923605973892;66251.33748662547;67119.66440167816;68453;68586;69520.6523967377;15849.841501585068;15987.680246395006;16001.439949601709;16044.598885027968;16074.839251607335;25284.620730689054;25344.239672809847;25620.103296384535;25644.230673079837;25784.74215257861;7965.243301886311;8368.539730314891;8395.454295470898;8406;8475.74572762818;7978.441509094424;8008.719694810654;8049.074356448966;8051.637676304464;8063.314618257396;4645.140648979949;4697.553732395417;4775.71345719258;4777.092352453023;4798.736069516141;1940.6506828770957;2033.572949680554;2041.5508588110888;2044.6217449771839;2054.8356131509445;1058.333250052462;1087.1628845788698;1111.6387174168267;1123.5730422439422;1123.8145705958425;16897.493075207763;17047.659046818993;17173.1851888595;17426;18114.547136321435;1077.9622713205001;1107.645553422913;1110.9500072496758;1119.3339962722168;1167.6963989362828;10431.947840260902;10509.421981790922;10613.150947924147;10650;10673.893261067446;602596;621886;625912;642686;645634;71343;79610.60194698989;79955.60022199969;81090.18909810945;81861.59069204735;1205.5780476833213;1214.5627574073326;1237.208186760474;1239.6281115665265;1247.2017908538542;15287.694246114794;15331.156786376856;15501.379944802533;15621.297041633381;15680.607984800472;32143.839280803448;32390;32744.50883236801;33416.49875251922;33725;41669.37495937624;43024.78487607542;43444.348334775634;49410.752946235036;52275.47724522858;7719.614019299125;7950.403719720928;7996.480228785111;8043.115257321573;8086.393520485869;8047.229609485904;10837.295575787548;10906.182036347464;11171.441427928734;11746.823797643214;8290.46112002718;9298.907010929805;9953.651622193189;10155.949220253851;10324.690259292232;7680.423968202295;7696.961515192388;7795;7940.126586075412;8111.472754270953;7312.670929808065;7657.923420765721;7683.039620047494;7887.64505597261;8244.670213191404;9874.308798384043;10072.244581656258;10265;10281.588736450458;10405.895941040493;10393.63622273247;10506.159507239556;10574.259801814107;10635.14918806478;10753.946230269112 03:20:04 INFO - PROCESS | 8765 | |3;mootools.html;1328.8870446012088;1368.931553422329;1518.8557087076724;1912.2351059576158;1937.854660900433;7844.490108142981;7976.601169941501;8031.4377993540475;8147.59262036898;8147.91852081479;8097.554634495101;8110.188981101887;8188.590570471468;8202.548859812709;8269.09040005599;2422.878856057199;2430.829841911067;2442.670239517669;2449.7917676997386;2452.16626347042;2360.173939121311;2395.2694428199393;2416.32342943976;2420.165043060141;2423.9151629692965;2268.3648578398074;2297.6783250344965;2384.0463814474174;2449.289705985262;2458.3362492127117;2308.607536718745;2314.594945884472;2329.9417514562188;2347.319277409549;2356.7643235676464;2342.367560758603;2354.1407386304045;2356.0929042318658;2356.352003199117;2357.905683772647;8013.639386227634;8024.759257222293;8091.352691784702;8101.068377136587;8116.2695357418115;5016.548510634024;5114.462981386975;5132.897342053138;5154.871128221787;5157.071727089123;3216.96783032169;3261.0705948804716;3305.074579117851;3333.8833140840197;3347.966520334814;1116.5707893895744;1123.870754863193;1175.0658226709786;1206.7707135644287;1220.6704189868685;11029.44852757367;11080.445977701083;11140.554377824878;11147.108231341474;11150.107991360672;4433.46798384196;4535.863924082217;4545.136424079415;4558.156741002925;4579.244424669959;9014.954925225464;9024.909750902538;9042.728718138467;9045.095490450902;9052.909470905339;5032.547070763649;5054.7978080876355;5062.607782859743;5072.442031376546;5081.110805609003;4653.6509761768;4700.247960326331;4708.058388322349;4708.4820669726305;4731.432228132646;3585.121645196891;3626;3639.1448009717697;3649.087728067983;3692.095436617992;1146.30919393895;1152.7694461107812;1155.1913660437726;1155.4742592120565;1199.962032841603;1337.5586056601298;1341.8926485881302;1352.4049418255936;1386.244496749274;1414.6604814844568;7425.480216384801;7760.30157285847;7778.338841198562;7790.883136752953;7791.727289544839;1202.290648517379;1222.419350808359;1225.4179264849304;1232.778099942001;1264.7027948432112;6045.395460454008;6076.513878889678;6135.171751813537;6139;6153.4154255345675;9936.205103591696;10135.746606334753;10139.391636501834;10189.592416303265;10201.897981020094;9780.510974451248;9921.751956201153;10015.949920250352;10051.59793608262;10129.594816207416;7562.470627056054;7678.57767822764;7721.92278077212;7731.072271327476;7747.418943579254;7042.366187043191;7091.078159839188;7108.040414544177;7151.8569628608175;7184.928150718426;8409.789755256168;8590.35572332065;8742.475451472932;8769.035406105195;8848.91151088481;8759.299256059627;8788.780280493038;8813.779655508408;8820.691275805317;9170.724878253663;6805.829854253683;6834.829129271808;6849.24658287578;6885.173779146535;6892.758753443605;7587.430942679211;7651.196624354585;7673.769786906401;7695.4997925134685;7700.114486834086;6543.770968016097;7157.391621712004;7163.892541611985;7186.24544422849;7398.297161769839;6960.686769095301;6974.407175390149;7040.542364746275;7059.082319298458;7156.105486814148;7231.240719724564;7304.780856574312;7330.376917962033;7367.77896663101;7370.226126256666;6747.966260168864;6775.457963362821;6780.254172040973;6784.3894049535775;6799.3200679931615;6751;6776;6813.659317034029;6845.965770171118;6963.7214511421 03:20:04 INFO - PROCESS | 8765 | |4;prototype.html;362.26279521174416;374.2683054628202;386.4022267922141;388.3611459149699;393.5159753503191;6432.388923052303;6513.055606936997;6514.7719829805965;6566.802995910124;6622.602643841361;6428.550001499897;6442.7745028924;6476.6761661916835;6494.220693516772;6496.675166241693;4984.850454486371;4986.700797952117;4988.02733466974;4990.326305948705;4998.750062496879;5012.223105418648;5017.096922553938;5027.396712394499;5028.321176641143;5030.396352437712;4783.832565860195;4819.710817350952;4899.020195960804;4917.877053073684;4925.729084900329;4881.487443818383;4921.212605983062;4926.014797040606;4929.334539837112;4937.925931111036;4307.806148723315;4310.288802347609;4319.784010799479;4320.65434765218;4336.414584031147;6167.938320616781;6170.938290617081;6173.166622505949;6178.011518157074;6192.87614247717;5647.265855438808;5780.421957804186;5799.855003624901;5801.216835727165;5813.5349172066135;6857.485688573327;6861.519693621449;6872.831618624846;6882.277360877086;6888.4144847688;4782.665213435061;4808;4820.638452116105;4828.46886842447;4830.492798256168;6974.79075627732;7151.74968876092;7162.319579639926;7210.206877243551;7224.4942854000765;4880.194767863334;4943.1596628573225;4945.851624451272;4949.554540091409;4975.90048199034;5034.798608055637;5038.924416133761;5039.44566097729;5044.394672639307;5048.217526283432;5692.6299790513485;5799.2750906136735;5810.709464526757;5813.82558523245;5817.069268916953;6931.480138989596;6938.653067346612;6941.201761797458;6948.826279343057;6958.47811414146;1730.4376077774523;1768.9734653980202;1772.9468115956543;1774.1838754173225;1779.1904683369037;1184.5439505790332;1201.435325397062;1202.8977536909285;1223.1254652923203;1226.4480983557257;6646.468282537386;6683.598984060972;6688.632125233159;6695.296993815677;6696.46428285736;7124;7139.178994415709;7143.357097861113;7207.207207207203;7213.89179162313;6576.013597960345;6675.065490831287;6685.331466853373;6726.730930762715;6751.291114433012;3827.6363745444137;3893.279743247508;3901.941470877939;3907.5506316773367;3925.548561915359;7390.22402647725;7412;7415.666295016629;7422.109346878409;7429.2570742926355;7251.782446526613;7349.742759003409;7375.520591161558;7394.2975417335265;7439.776806695808;7331.74338898136;7366.484346095722;7412.666430010662;7418.332350088518;7436.739714110088;7088.362047415757;7187.7843664690145;7191.244919283609;7218.27817218274;7238;7039;7041.718331266692;7090.680919358744;7096.574205547684;7111.857762844817;7436.479446438698;7438.702451901863;7440.2931721486375;7440.995465612073;7441.665125069277;4916.852494425173;4940.75296235194;4963.205887058053;4994.2508623706735;5011.09800235961;7168.569885806868;7178.353948144692;7192.568445893263;7209.675564599501;7219.963900180465;6930.06444130036;7000.439964802702;7004.894926576208;7005.894911576229;7017.771889919141;6865.965670171618;6899.931000689929;6989.37095661393;6997.1253593300835;7058.153021637231;6804.55770374924;6871.622060786504;6878.27778083314;6879.277675843964;6891.586504809728;6938.5836849789175;7024.051753013482;7028.262032486516;7028.437724982092;7046.682899269443;6774.3225677431865;6808.421284190899;6815.829604259735;6825;6849.589024658537 03:20:04 INFO - PROCESS | 8765 | |5;yui.html;850.124371896946;854.069064719456;856.2379482260789;959.160734357437;966.1497881863959;14269.286535673227;14349.28253587319;14415.135091894494;14450.566483005501;14550.345234464448;13079.869201307984;13087.280199589019;13126.474941002372;13214.801777973338;13254.801177982337;897.3045889435691;905.3798148268424;907.7957459571577;913.1142791492242;913.2830727878617;896.8699538566904;899.0919171636629;908.4140729229676;909.2135302962922;911.0479548871413;961.4087336288168;961.4519723757461;963.5712108111893;963.6964356227766;964.5418426247511;943.3962264150949;947.1191791633778;947.4883562876073;948.6300342866288;952.319091849331;702.2521015118933;703.4513079797766;706.2901783707392;708.4332533972802;710.6730903784265;13086.934565327112;13112;13175.275359855204;13185.208887466782;13208.471661133543;11891.702707432298;11910.821337679941;11956.521739130425;12002.279863208236;12359.62921112359;9921.107100361001;9944.602215911355;10105.646302379455;10200.38797672142;10326.432046237454;3860.401637746154;3866.5360156781367;3900.843966241347;4538.455385353745;4571.268597024494;47007.76496117497;51224;51636.741816290676;51998.48001519974;52392.73803630996;11307;11344.319340839576;11352.432378381212;11539.48072336747;11642.126840486828;19334.516637084187;19356.128974196195;19366.4190074298;19391.22435102608;19438.222471101;7999.640016199285;8062.355011599058;8107.391945604103;8109.148539403278;8470.449420787629;4379.343098535245;4434.645228381722;4470.6870519064005;4492.842750503717;4533.206688829442;3088.2279430142466;3116.049604870491;3131.2171957010746;3137.7803553751473;3142.9371412571622;567.0218872445082;590.8729623131047;590.9822705318848;593.3087952535249;593.7981086430548;604.7762327938691;645.3127419298432;705.78473565563;764.9043869516311;771.5987686403039;9556;9954.30319877616;10080.69757907264;10090.596376145018;10095.848562271573;628.7610707930957;676.3507033248137;745.0909889934271;749.0561892016099;760.3916866506772;4429.800658970354;4591.127685739699;4693.131770622445;4708.576228139484;4731.408573928259;60619.39380606226;61487;61521;61910;61964;55103.44896551063;55675;56168;56354;56835.43164568384;45554.5444545557;46729.5327046732;47205.76397117993;48124.51875481271;51954.96090078177;9318.906810931803;9534.09426104519;9564.426134431957;9608.926281331691;9828.771403574554;35377.29245415129;36821.44767828538;37449;37475.81262093672;38681;47373.289400659705;47686.28470572875;47705.28442073302;47745.52254477411;49355.25967110568;12198.817017744563;12327.50689972391;12340.197887137307;12473.625791226277;12502.437390317276;13009.479620815062;13637.249951252776;13691.89741544734;13955.790663139858;14241.64395890111;12682;12684;13188.736225275632;13363.26502042397;13937.860621394062;12288.938555307166;12342.197757145757;12689.873101269239;12949.223046617233;13638.659033524242;12092.576759813364;12098.395080246128;12232.571859984857;12634.115611907078;12656.050796190506;10052.94677560088;10197.694069177936;10396.16830653561;10583.417912014913;10585.206109541661;10651.201159913193;10899.509522071685;11024.834627480433;11046.502907369348;11170.664880053286 03:20:04 INFO - PROCESS | 8765 | __end_tp_report 03:20:04 INFO - PROCESS | 8765 | __start_cc_report 03:20:04 INFO - PROCESS | 8765 | _x_x_mozilla_cycle_collect,300 03:20:04 INFO - PROCESS | 8765 | __end_cc_report 03:20:04 INFO - PROCESS | 8765 | __startTimestamp1456572004252__endTimestamp 03:20:04 INFO - PROCESS | 8765 | 03:20:04 INFO - PROCESS | 8765 | ------- Summary: start ------- 03:20:04 INFO - PROCESS | 8765 | Number of tests: 6 03:20:04 INFO - PROCESS | 8765 | 03:20:04 INFO - PROCESS | 8765 | [#0] dojo.html Cycles:165 Average:5389.52 Median:5615.27 stddev:572.08 (10.2%) stddev-sans-first:548.91 03:20:04 INFO - PROCESS | 8765 | Values: 3260.3 3326.1 3326.6 3366.1 3366.8 5287.2 5318.7 5330.2 5365.1 5382.0 5362.4 5366.4 5384.7 5385.0 5391.2 4953.6 4995.5 5043.7 5058.8 5069.7 4831.2 4922.5 4969.2 4971.6 5007.6 4972.3 5006.5 5013.9 5015.3 5018.7 4982.3 4982.8 5005.5 5014.6 5017.2 4828.4 4840.3 4902.6 4951.1 4975.5 5243.1 5266.4 5275.5 5283.7 5300.2 5815.0 5840.9 5845.0 5847.4 5850.6 5617.2 5709.3 5743.1 5752.9 5858.3 5441.7 5452.1 5455.6 5458.2 5461.6 5878.5 5885.6 5890.6 5893.5 5896.7 5654.3 5675.6 5676.9 5726.2 5745.1 5754.5 5755.8 5758.4 5775.2 5784.4 5660.1 5701.1 5711.8 5727.4 5758.6 5686.7 5744.4 5795.0 5795.8 5827.0 4444.2 4458.4 4460.8 4463.2 4464.8 3981.6 3982.6 4022.8 4036.8 4037.9 5509.6 5511.2 5534.8 5576.3 5588.1 5699.2 5743.3 5781.7 5790.2 5794.0 5396.9 5475.9 5480.9 5485.6 5496.2 5058.6 5074.1 5075.0 5078.6 5083.3 5970.6 5998.4 6014.4 6022.8 6023.5 5722.9 5794.3 5807.6 5870.1 5898.4 5893.8 5907.4 5914.5 5915.3 5926.5 5656.7 5691.9 5696.6 5725.0 5725.4 5737.4 5776.1 5825.7 5864.2 5892.5 5567.6 5615.3 5657.7 5663.7 5671.1 5589.8 5663.7 5670.8 5671.4 5672.8 5527.6 5538.7 5593.7 5615.4 5641.6 5660.6 5742.3 5744.3 5754.6 5792.4 5515.7 5586.1 5658.3 5667.3 5683.6 03:20:04 INFO - PROCESS | 8765 | 03:20:04 INFO - PROCESS | 8765 | [#1] ext.html Cycles:180 Average:56576.95 Median:40765.59 stddev:105900.59 (259.8%) stddev-sans-first:106114.13 03:20:04 INFO - PROCESS | 8765 | Values: 553.3 572.2 583.0 589.0 610.3 40917.2 42423.0 42490.8 42540.8 42563.4 40581.6 40708.4 41067.0 41067.6 41167.2 7604.1 7624.3 7647.4 7651.3 7675.4 8770.1 8828.5 8844.8 8870.2 8882.7 8855.0 8953.7 8963.0 8975.1 8989.1 9059.8 9064.9 9124.4 9124.5 9167.4 8993.1 9060.4 9080.2 9123.2 9162.5 43573.6 44068.6 44122.6 44341.8 44352.1 3613.8 3695.6 3755.1 3762.7 3985.0 32608.5 33136.3 33769.3 34012.3 34129.5 4226.1 4288.9 4290.1 4292.4 4392.9 303530.0 304328.0 305294.5 305565.0 306586.0 67932.3 68036.7 68181.3 68193.7 68428.7 71254.3 72146.6 72260.0 72263.6 72287.0 18549.9 19208.8 19238.6 19247.4 19302.6 14370.9 14390.8 14441.9 14454.8 14488.3 22749.4 23177.0 23209.7 23355.5 23456.8 8662.0 8900.8 9023.5 9055.5 9088.1 1090.5 1115.0 1154.6 1199.9 1236.9 31421.0 33546.7 33727.5 33918.0 33970.7 1191.7 1201.1 1221.2 1237.0 1280.5 15081.8 15232.0 15380.3 15742.7 15769.4 433249.0 539345.0 551226.0 552384.0 556788.0 54925.5 56295.0 56316.4 56501.4 56726.1 1390.6 1392.4 1444.4 1472.4 1489.9 32204.5 33844.0 33899.0 34096.8 34351.5 52475.0 52959.5 53072.0 53156.7 53205.2 324884.0 327148.0 327456.0 329934.0 330630.0 34893.5 35423.6 35517.0 35577.6 35607.1 47231.3 47717.0 47798.3 48204.3 48834.5 40136.6 40431.8 40845.6 41191.4 41885.8 40755.8 40755.8 40775.4 41053.6 43148.1 37014.6 37185.8 37661.0 37710.8 37977.6 31233.7 31445.2 31901.8 32026.8 34189.8 27225.7 27490.9 27705.0 27846.2 27864.3 03:20:04 INFO - PROCESS | 8765 | 03:20:04 INFO - PROCESS | 8765 | [#2] jquery.html Cycles:180 Average:31626.80 Median:32266.92 stddev:102516.58 (317.7%) stddev-sans-first:102777.34 03:20:04 INFO - PROCESS | 8765 | Values: 396.4 399.1 399.4 399.6 400.2 10569.8 10899.8 17045.4 17520.9 17642.6 18065.2 18269.9 18383.9 18472.4 18744.4 6035.5 6339.7 6344.9 6357.2 6373.9 4583.0 6126.9 6163.3 6905.6 6909.0 6682.5 6897.5 6945.8 6954.8 6967.7 6755.9 6761.4 7001.1 7051.8 7068.6 6534.9 6751.2 6754.9 6780.3 6786.9 23083.2 23312.8 23713.9 23724.0 24074.8 2509.5 2518.5 2550.9 2565.4 2584.2 13754.1 14046.9 14514.9 15293.6 15470.1 1983.9 2020.2 2055.6 2071.4 2081.3 66251.3 67119.7 68453.0 68586.0 69520.7 15849.8 15987.7 16001.4 16044.6 16074.8 25284.6 25344.2 25620.1 25644.2 25784.7 7965.2 8368.5 8395.5 8406.0 8475.7 7978.4 8008.7 8049.1 8051.6 8063.3 4645.1 4697.6 4775.7 4777.1 4798.7 1940.7 2033.6 2041.6 2044.6 2054.8 1058.3 1087.2 1111.6 1123.6 1123.8 16897.5 17047.7 17173.2 17426.0 18114.5 1078.0 1107.6 1111.0 1119.3 1167.7 10431.9 10509.4 10613.2 10650.0 10673.9 602596.0 621886.0 625912.0 642686.0 645634.0 71343.0 79610.6 79955.6 81090.2 81861.6 1205.6 1214.6 1237.2 1239.6 1247.2 15287.7 15331.2 15501.4 15621.3 15680.6 32143.8 32390.0 32744.5 33416.5 33725.0 41669.4 43024.8 43444.3 49410.8 52275.5 7719.6 7950.4 7996.5 8043.1 8086.4 8047.2 10837.3 10906.2 11171.4 11746.8 8290.5 9298.9 9953.7 10155.9 10324.7 7680.4 7697.0 7795.0 7940.1 8111.5 7312.7 7657.9 7683.0 7887.6 8244.7 9874.3 10072.2 10265.0 10281.6 10405.9 10393.6 10506.2 10574.3 10635.1 10753.9 03:20:04 INFO - PROCESS | 8765 | 03:20:04 INFO - PROCESS | 8765 | [#3] mootools.html Cycles:180 Average:5695.37 Median:5155.97 stddev:2912.19 (56.5%) stddev-sans-first:2901.86 03:20:04 INFO - PROCESS | 8765 | Values: 1328.9 1368.9 1518.9 1912.2 1937.9 7844.5 7976.6 8031.4 8147.6 8147.9 8097.6 8110.2 8188.6 8202.5 8269.1 2422.9 2430.8 2442.7 2449.8 2452.2 2360.2 2395.3 2416.3 2420.2 2423.9 2268.4 2297.7 2384.0 2449.3 2458.3 2308.6 2314.6 2329.9 2347.3 2356.8 2342.4 2354.1 2356.1 2356.4 2357.9 8013.6 8024.8 8091.4 8101.1 8116.3 5016.5 5114.5 5132.9 5154.9 5157.1 3217.0 3261.1 3305.1 3333.9 3348.0 1116.6 1123.9 1175.1 1206.8 1220.7 11029.4 11080.4 11140.6 11147.1 11150.1 4433.5 4535.9 4545.1 4558.2 4579.2 9015.0 9024.9 9042.7 9045.1 9052.9 5032.5 5054.8 5062.6 5072.4 5081.1 4653.7 4700.2 4708.1 4708.5 4731.4 3585.1 3626.0 3639.1 3649.1 3692.1 1146.3 1152.8 1155.2 1155.5 1200.0 1337.6 1341.9 1352.4 1386.2 1414.7 7425.5 7760.3 7778.3 7790.9 7791.7 1202.3 1222.4 1225.4 1232.8 1264.7 6045.4 6076.5 6135.2 6139.0 6153.4 9936.2 10135.7 10139.4 10189.6 10201.9 9780.5 9921.8 10015.9 10051.6 10129.6 7562.5 7678.6 7721.9 7731.1 7747.4 7042.4 7091.1 7108.0 7151.9 7184.9 8409.8 8590.4 8742.5 8769.0 8848.9 8759.3 8788.8 8813.8 8820.7 9170.7 6805.8 6834.8 6849.2 6885.2 6892.8 7587.4 7651.2 7673.8 7695.5 7700.1 6543.8 7157.4 7163.9 7186.2 7398.3 6960.7 6974.4 7040.5 7059.1 7156.1 7231.2 7304.8 7330.4 7367.8 7370.2 6748.0 6775.5 6780.3 6784.4 6799.3 6751.0 6776.0 6813.7 6846.0 6963.7 03:20:04 INFO - PROCESS | 8765 | 03:20:04 INFO - PROCESS | 8765 | [#4] prototype.html Cycles:180 Average:5807.10 Median:6634.54 stddev:1748.80 (26.4%) stddev-sans-first:1705.28 03:20:04 INFO - PROCESS | 8765 | Values: 362.3 374.3 386.4 388.4 393.5 6432.4 6513.1 6514.8 6566.8 6622.6 6428.6 6442.8 6476.7 6494.2 6496.7 4984.9 4986.7 4988.0 4990.3 4998.8 5012.2 5017.1 5027.4 5028.3 5030.4 4783.8 4819.7 4899.0 4917.9 4925.7 4881.5 4921.2 4926.0 4929.3 4937.9 4307.8 4310.3 4319.8 4320.7 4336.4 6167.9 6170.9 6173.2 6178.0 6192.9 5647.3 5780.4 5799.9 5801.2 5813.5 6857.5 6861.5 6872.8 6882.3 6888.4 4782.7 4808.0 4820.6 4828.5 4830.5 6974.8 7151.7 7162.3 7210.2 7224.5 4880.2 4943.2 4945.9 4949.6 4975.9 5034.8 5038.9 5039.4 5044.4 5048.2 5692.6 5799.3 5810.7 5813.8 5817.1 6931.5 6938.7 6941.2 6948.8 6958.5 1730.4 1769.0 1772.9 1774.2 1779.2 1184.5 1201.4 1202.9 1223.1 1226.4 6646.5 6683.6 6688.6 6695.3 6696.5 7124.0 7139.2 7143.4 7207.2 7213.9 6576.0 6675.1 6685.3 6726.7 6751.3 3827.6 3893.3 3901.9 3907.6 3925.5 7390.2 7412.0 7415.7 7422.1 7429.3 7251.8 7349.7 7375.5 7394.3 7439.8 7331.7 7366.5 7412.7 7418.3 7436.7 7088.4 7187.8 7191.2 7218.3 7238.0 7039.0 7041.7 7090.7 7096.6 7111.9 7436.5 7438.7 7440.3 7441.0 7441.7 4916.9 4940.8 4963.2 4994.3 5011.1 7168.6 7178.4 7192.6 7209.7 7220.0 6930.1 7000.4 7004.9 7005.9 7017.8 6866.0 6899.9 6989.4 6997.1 7058.2 6804.6 6871.6 6878.3 6879.3 6891.6 6938.6 7024.1 7028.3 7028.4 7046.7 6774.3 6808.4 6815.8 6825.0 6849.6 03:20:04 INFO - PROCESS | 8765 | 03:20:04 INFO - PROCESS | 8765 | [#5] yui.html Cycles:180 Average:14781.55 Median:25046.50 stddev:16984.26 (67.8%) stddev-sans-first:16999.68 03:20:04 INFO - PROCESS | 8765 | Values: 850.1 854.1 856.2 959.2 966.1 14269.3 14349.3 14415.1 14450.6 14550.3 13079.9 13087.3 13126.5 13214.8 13254.8 897.3 905.4 907.8 913.1 913.3 896.9 899.1 908.4 909.2 911.0 961.4 961.5 963.6 963.7 964.5 943.4 947.1 947.5 948.6 952.3 702.3 703.5 706.3 708.4 710.7 13086.9 13112.0 13175.3 13185.2 13208.5 11891.7 11910.8 11956.5 12002.3 12359.6 9921.1 9944.6 10105.6 10200.4 10326.4 3860.4 3866.5 3900.8 4538.5 4571.3 47007.8 51224.0 51636.7 51998.5 52392.7 11307.0 11344.3 11352.4 11539.5 11642.1 19334.5 19356.1 19366.4 19391.2 19438.2 7999.6 8062.4 8107.4 8109.1 8470.4 4379.3 4434.6 4470.7 4492.8 4533.2 3088.2 3116.0 3131.2 3137.8 3142.9 567.0 590.9 591.0 593.3 593.8 604.8 645.3 705.8 764.9 771.6 9556.0 9954.3 10080.7 10090.6 10095.8 628.8 676.4 745.1 749.1 760.4 4429.8 4591.1 4693.1 4708.6 4731.4 60619.4 61487.0 61521.0 61910.0 61964.0 55103.4 55675.0 56168.0 56354.0 56835.4 45554.5 46729.5 47205.8 48124.5 51955.0 9318.9 9534.1 9564.4 9608.9 9828.8 35377.3 36821.4 37449.0 37475.8 38681.0 47373.3 47686.3 47705.3 47745.5 49355.3 12198.8 12327.5 12340.2 12473.6 12502.4 13009.5 13637.2 13691.9 13955.8 14241.6 12682.0 12684.0 13188.7 13363.3 13937.9 12288.9 12342.2 12689.9 12949.2 13638.7 12092.6 12098.4 12232.6 12634.1 12656.1 10052.9 10197.7 10396.2 10583.4 10585.2 10651.2 10899.5 11024.8 11046.5 11170.7 03:20:04 INFO - PROCESS | 8765 | -------- Summary: end -------- 03:20:04 INFO - PROCESS | 8765 | 03:20:04 INFO - TEST-INFO | 8765: exit 0 03:20:04 INFO - TEST-OK | dromaeo_css | took 1108184ms 03:20:04 INFO - TEST-START | kraken 03:20:04 INFO - operating with platform_type : linux_ 03:20:04 INFO - Initialising browser for kraken test... 03:20:04 INFO - TEST-INFO | started process 9014 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuwKA8G/profile http://localhost:40267/getInfo.html) 03:20:08 INFO - PROCESS | 9014 | 03:20:08 INFO - PROCESS | 9014 | (process:9092): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 03:20:09 INFO - PROCESS | 9014 | __metrics Screen width/height:1600/1200 03:20:09 INFO - PROCESS | 9014 | colorDepth:24 03:20:09 INFO - PROCESS | 9014 | Browser inner width/height: 1024/697 03:20:09 INFO - PROCESS | 9014 | __metrics 03:20:10 INFO - TEST-INFO | 9014: exit 0 03:20:10 INFO - Browser initialized. 03:20:10 INFO - Running cycle 1/1 for kraken test... 03:20:10 INFO - TEST-INFO | started process 9141 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuwKA8G/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:20:11 INFO - PROCESS | 9141 | 03:20:11 INFO - PROCESS | 9141 | (process:9206): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 03:20:21 INFO - PROCESS | 9141 | RSS: Main: 141144064 03:20:21 INFO - PROCESS | 9141 | 03:20:24 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40267/tests/kraken/driver.html?testName=audio-beat-detection) 03:20:24 INFO - PROCESS | 9141 | RSS: Main: 146067456 03:20:24 INFO - PROCESS | 9141 | 03:20:27 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40267/tests/kraken/driver.html?testName=audio-dft) 03:20:28 INFO - PROCESS | 9141 | RSS: Main: 145170432 03:20:28 INFO - PROCESS | 9141 | 03:20:30 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40267/tests/kraken/driver.html?testName=audio-fft) 03:20:31 INFO - PROCESS | 9141 | RSS: Main: 145616896 03:20:31 INFO - PROCESS | 9141 | 03:20:33 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40267/tests/kraken/driver.html?testName=audio-oscillator) 03:20:34 INFO - PROCESS | 9141 | RSS: Main: 144723968 03:20:34 INFO - PROCESS | 9141 | 03:20:36 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:20:36 INFO - PROCESS | 9141 | RSS: Main: 147169280 03:20:36 INFO - PROCESS | 9141 | 03:20:39 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40267/tests/kraken/driver.html?testName=imaging-darkroom) 03:20:40 INFO - PROCESS | 9141 | RSS: Main: 145231872 03:20:40 INFO - PROCESS | 9141 | 03:20:43 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40267/tests/kraken/driver.html?testName=imaging-desaturate) 03:20:44 INFO - PROCESS | 9141 | RSS: Main: 147009536 03:20:44 INFO - PROCESS | 9141 | 03:20:47 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40267/tests/kraken/driver.html?testName=json-parse-financial) 03:20:47 INFO - PROCESS | 9141 | RSS: Main: 146227200 03:20:47 INFO - PROCESS | 9141 | 03:20:49 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:20:49 INFO - PROCESS | 9141 | RSS: Main: 146067456 03:20:49 INFO - PROCESS | 9141 | 03:20:51 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:20:52 INFO - PROCESS | 9141 | RSS: Main: 146911232 03:20:52 INFO - PROCESS | 9141 | 03:20:54 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:20:54 INFO - PROCESS | 9141 | RSS: Main: 146034688 03:20:54 INFO - PROCESS | 9141 | 03:20:57 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:20:57 INFO - PROCESS | 9141 | RSS: Main: 145289216 03:20:57 INFO - PROCESS | 9141 | 03:21:00 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:21:00 INFO - PROCESS | 9141 | RSS: Main: 147103744 03:21:00 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | Cycle 1(1): loaded http://localhost:40267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40267/tests/kraken/driver.html?testName=ai-astar) 03:21:02 INFO - PROCESS | 9141 | RSS: Main: 147832832 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | __start_tp_report 03:21:02 INFO - PROCESS | 9141 | _x_x_mozilla_page_load 03:21:02 INFO - PROCESS | 9141 | _x_x_mozilla_page_load_details 03:21:02 INFO - PROCESS | 9141 | |i|pagename|runs| 03:21:02 INFO - PROCESS | 9141 | |0;ai-astar;112;106;114;113;111;122;112;110;111;110 03:21:02 INFO - PROCESS | 9141 | |1;audio-beat-detection;151;126;151;168;128;154;148;127;159;125 03:21:02 INFO - PROCESS | 9141 | |2;audio-dft;162;162;162;160;162;163;161;163;164;162 03:21:02 INFO - PROCESS | 9141 | |3;audio-fft;72;74;76;170;74;72;73;73;160;74 03:21:02 INFO - PROCESS | 9141 | |4;audio-oscillator;95;95;144;96;98;98;134;95;95;136 03:21:02 INFO - PROCESS | 9141 | |5;imaging-gaussian-blur;110;110;110;110;110;110;109;110;112;109 03:21:02 INFO - PROCESS | 9141 | |6;imaging-darkroom;148;149;146;146;147;147;147;147;147;147 03:21:02 INFO - PROCESS | 9141 | |7;imaging-desaturate;96;96;96;97;96;97;97;96;96;97 03:21:02 INFO - PROCESS | 9141 | |8;json-parse-financial;54;55;57;54;55;58;55;64;53;53 03:21:02 INFO - PROCESS | 9141 | |9;json-stringify-tinderbox;63;64;64;65;64;64;64;64;64;64 03:21:02 INFO - PROCESS | 9141 | |10;stanford-crypto-aes;86;81;81;82;89;81;83;81;81;81 03:21:02 INFO - PROCESS | 9141 | |11;stanford-crypto-ccm;115;113;112;108;109;113;117;112;125;128 03:21:02 INFO - PROCESS | 9141 | |12;stanford-crypto-pbkdf2;141;140;140;138;136;125;139;144;139;135 03:21:02 INFO - PROCESS | 9141 | |13;stanford-crypto-sha256-iterative;69;68;68;67;65;71;64;64;64;63 03:21:02 INFO - PROCESS | 9141 | __end_tp_report 03:21:02 INFO - PROCESS | 9141 | __start_cc_report 03:21:02 INFO - PROCESS | 9141 | _x_x_mozilla_cycle_collect,729 03:21:02 INFO - PROCESS | 9141 | __end_cc_report 03:21:02 INFO - PROCESS | 9141 | __startTimestamp1456572062797__endTimestamp 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | ------- Summary: start ------- 03:21:02 INFO - PROCESS | 9141 | Number of tests: 14 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#0] ai-astar Cycles:10 Average:112.10 Median:112.00 stddev:4.09 (3.7%) stddev-sans-first:4.34 03:21:02 INFO - PROCESS | 9141 | Values: 112.0 106.0 114.0 113.0 111.0 122.0 112.0 110.0 111.0 110.0 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#1] audio-beat-detection Cycles:10 Average:143.70 Median:151.00 stddev:15.79 (10.5%) stddev-sans-first:16.53 03:21:02 INFO - PROCESS | 9141 | Values: 151.0 126.0 151.0 168.0 128.0 154.0 148.0 127.0 159.0 125.0 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#2] audio-dft Cycles:10 Average:162.10 Median:162.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 03:21:02 INFO - PROCESS | 9141 | Values: 162.0 162.0 162.0 160.0 162.0 163.0 161.0 163.0 164.0 162.0 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#3] audio-fft Cycles:10 Average:91.80 Median:73.50 stddev:38.67 (52.6%) stddev-sans-first:40.35 03:21:02 INFO - PROCESS | 9141 | Values: 72.0 74.0 76.0 170.0 74.0 72.0 73.0 73.0 160.0 74.0 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#4] audio-oscillator Cycles:10 Average:108.60 Median:95.00 stddev:20.47 (21.6%) stddev-sans-first:21.12 03:21:02 INFO - PROCESS | 9141 | Values: 95.0 95.0 144.0 96.0 98.0 98.0 134.0 95.0 95.0 136.0 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 03:21:02 INFO - PROCESS | 9141 | Values: 110.0 110.0 110.0 110.0 110.0 110.0 109.0 110.0 112.0 109.0 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.88 (0.6%) stddev-sans-first:0.87 03:21:02 INFO - PROCESS | 9141 | Values: 148.0 149.0 146.0 146.0 147.0 147.0 147.0 147.0 147.0 147.0 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 03:21:02 INFO - PROCESS | 9141 | Values: 96.0 96.0 96.0 97.0 96.0 97.0 97.0 96.0 96.0 97.0 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:55.00 stddev:3.29 (6.0%) stddev-sans-first:3.43 03:21:02 INFO - PROCESS | 9141 | Values: 54.0 55.0 57.0 54.0 55.0 58.0 55.0 64.0 53.0 53.0 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#9] json-stringify-tinderbox Cycles:10 Average:64.00 Median:64.00 stddev:0.47 (0.7%) stddev-sans-first:0.33 03:21:02 INFO - PROCESS | 9141 | Values: 63.0 64.0 64.0 65.0 64.0 64.0 64.0 64.0 64.0 64.0 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:81.50 stddev:2.76 (3.4%) stddev-sans-first:2.64 03:21:02 INFO - PROCESS | 9141 | Values: 86.0 81.0 81.0 82.0 89.0 81.0 83.0 81.0 81.0 81.0 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#11] stanford-crypto-ccm Cycles:10 Average:115.20 Median:114.00 stddev:6.53 (5.7%) stddev-sans-first:6.92 03:21:02 INFO - PROCESS | 9141 | Values: 115.0 113.0 112.0 108.0 109.0 113.0 117.0 112.0 125.0 128.0 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.70 Median:139.50 stddev:5.12 (3.7%) stddev-sans-first:5.29 03:21:02 INFO - PROCESS | 9141 | Values: 141.0 140.0 140.0 138.0 136.0 125.0 139.0 144.0 139.0 135.0 03:21:02 INFO - PROCESS | 9141 | 03:21:02 INFO - PROCESS | 9141 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:67.50 stddev:2.67 (4.0%) stddev-sans-first:2.65 03:21:02 INFO - PROCESS | 9141 | Values: 69.0 68.0 68.0 67.0 65.0 71.0 64.0 64.0 64.0 63.0 03:21:02 INFO - PROCESS | 9141 | -------- Summary: end -------- 03:21:02 INFO - PROCESS | 9141 | 03:21:03 INFO - TEST-INFO | 9141: exit 0 03:21:03 INFO - TEST-OK | kraken | took 58775ms 03:21:03 INFO - TEST-START | v8_7 03:21:03 INFO - operating with platform_type : linux_ 03:21:03 INFO - Initialising browser for v8_7 test... 03:21:03 INFO - TEST-INFO | started process 9434 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgBVSQV/profile http://localhost:40267/getInfo.html) 03:21:07 INFO - PROCESS | 9434 | 03:21:07 INFO - PROCESS | 9434 | (process:9514): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 03:21:07 INFO - PROCESS | 9434 | __metrics Screen width/height:1600/1200 03:21:07 INFO - PROCESS | 9434 | colorDepth:24 03:21:07 INFO - PROCESS | 9434 | Browser inner width/height: 1024/697 03:21:07 INFO - PROCESS | 9434 | __metrics 03:21:08 INFO - TEST-INFO | 9434: exit 0 03:21:08 INFO - Browser initialized. 03:21:08 INFO - Running cycle 1/1 for v8_7 test... 03:21:08 INFO - TEST-INFO | started process 9563 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgBVSQV/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:21:10 INFO - PROCESS | 9563 | 03:21:10 INFO - PROCESS | 9563 | (process:9625): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 03:21:20 INFO - PROCESS | 9563 | RSS: Main: 141250560 03:21:20 INFO - PROCESS | 9563 | 03:21:42 INFO - PROCESS | 9563 | Cycle 1(1): loaded http://localhost:40267/tests/v8_7/run.html (next: http://localhost:40267/tests/v8_7/run.html) 03:21:43 INFO - PROCESS | 9563 | RSS: Main: 144916480 03:21:43 INFO - PROCESS | 9563 | 03:21:43 INFO - PROCESS | 9563 | __start_tp_report 03:21:43 INFO - PROCESS | 9563 | _x_x_mozilla_page_load 03:21:43 INFO - PROCESS | 9563 | _x_x_mozilla_page_load_details 03:21:43 INFO - PROCESS | 9563 | |i|pagename|runs| 03:21:43 INFO - PROCESS | 9563 | |0;Richards;165.6451880072884 03:21:43 INFO - PROCESS | 9563 | |1;DeltaBlue;169.80811682798438 03:21:43 INFO - PROCESS | 9563 | |2;Encrypt;278.55153203342616 03:21:43 INFO - PROCESS | 9563 | |3;Decrypt;5191.709844559586 03:21:43 INFO - PROCESS | 9563 | |4;RayTrace;916.5902841429881 03:21:43 INFO - PROCESS | 9563 | |5;Earley;568.1818181818181 03:21:43 INFO - PROCESS | 9563 | |6;Boyer;11188.888888888889 03:21:43 INFO - PROCESS | 9563 | |7;RegExp;38528.301886792455 03:21:43 INFO - PROCESS | 9563 | |8;Splay;515.9958720330237 03:21:43 INFO - PROCESS | 9563 | |9;NavierStokes;4840.579710144928 03:21:43 INFO - PROCESS | 9563 | __end_tp_report 03:21:43 INFO - PROCESS | 9563 | __startTimestamp1456572103191__endTimestamp 03:21:43 INFO - PROCESS | 9563 | 03:21:43 INFO - PROCESS | 9563 | ------- Summary: start ------- 03:21:43 INFO - PROCESS | 9563 | Number of tests: 10 03:21:43 INFO - PROCESS | 9563 | 03:21:43 INFO - PROCESS | 9563 | [#0] Richards Cycles:1 Average:165.65 Median:165.65 stddev:0.00 (0.0%) 03:21:43 INFO - PROCESS | 9563 | Values: 165.6 03:21:43 INFO - PROCESS | 9563 | 03:21:43 INFO - PROCESS | 9563 | [#1] DeltaBlue Cycles:1 Average:169.81 Median:169.81 stddev:0.00 (0.0%) 03:21:43 INFO - PROCESS | 9563 | Values: 169.8 03:21:43 INFO - PROCESS | 9563 | 03:21:43 INFO - PROCESS | 9563 | [#2] Encrypt Cycles:1 Average:278.55 Median:278.55 stddev:0.00 (0.0%) 03:21:43 INFO - PROCESS | 9563 | Values: 278.6 03:21:43 INFO - PROCESS | 9563 | 03:21:43 INFO - PROCESS | 9563 | [#3] Decrypt Cycles:1 Average:5191.71 Median:5191.71 stddev:0.00 (0.0%) 03:21:43 INFO - PROCESS | 9563 | Values: 5191.7 03:21:43 INFO - PROCESS | 9563 | 03:21:43 INFO - PROCESS | 9563 | [#4] RayTrace Cycles:1 Average:916.59 Median:916.59 stddev:0.00 (0.0%) 03:21:43 INFO - PROCESS | 9563 | Values: 916.6 03:21:43 INFO - PROCESS | 9563 | 03:21:43 INFO - PROCESS | 9563 | [#5] Earley Cycles:1 Average:568.18 Median:568.18 stddev:0.00 (0.0%) 03:21:43 INFO - PROCESS | 9563 | Values: 568.2 03:21:43 INFO - PROCESS | 9563 | 03:21:43 INFO - PROCESS | 9563 | [#6] Boyer Cycles:1 Average:11188.89 Median:11188.89 stddev:0.00 (0.0%) 03:21:43 INFO - PROCESS | 9563 | Values: 11188.9 03:21:43 INFO - PROCESS | 9563 | 03:21:43 INFO - PROCESS | 9563 | [#7] RegExp Cycles:1 Average:38528.30 Median:38528.30 stddev:0.00 (0.0%) 03:21:43 INFO - PROCESS | 9563 | Values: 38528.3 03:21:43 INFO - PROCESS | 9563 | 03:21:43 INFO - PROCESS | 9563 | [#8] Splay Cycles:1 Average:516.00 Median:516.00 stddev:0.00 (0.0%) 03:21:43 INFO - PROCESS | 9563 | Values: 516.0 03:21:43 INFO - PROCESS | 9563 | 03:21:43 INFO - PROCESS | 9563 | [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 03:21:43 INFO - PROCESS | 9563 | Values: 4840.6 03:21:43 INFO - PROCESS | 9563 | -------- Summary: end -------- 03:21:43 INFO - PROCESS | 9563 | 03:21:43 INFO - TEST-INFO | 9563: exit 0 03:21:44 INFO - TEST-OK | v8_7 | took 40334ms 03:21:44 INFO - SUITE-END | took 1207s 03:21:44 INFO - Completed test suite (00:20:07) 03:21:44 INFO - Working with test: dromaeo_css 03:21:44 INFO - Generating results file: dromaeo_css 03:21:44 INFO - Working with test: kraken 03:21:44 INFO - Generating results file: kraken 03:21:44 INFO - javascript benchmark 03:21:44 INFO - Working with test: v8_7 03:21:44 INFO - Generating results file: v8_7 03:21:44 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:21:44 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,52,43]] 03:21:44 INFO - process_Request line: dromaeo_css 7464.80 graph.html#tests=[[72,52,43]] 03:21:44 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:21:44 INFO - process_Request line: kraken 1493.40 graph.html#tests=[[232,52,43]] 03:21:44 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:21:44 INFO - process_Request line: v8_7 22089.39 graph.html#tests=[[230,52,43]] 03:21:44 INFO - RETURN: dromaeo_css: 7464.80 03:21:44 INFO - RETURN: kraken: 1493.40 03:21:44 INFO - RETURN: v8_7: 22089.39 03:21:44 INFO - javascript benchmark 03:21:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3260.2990357073227, 3326.085326535204, 3326.5841769778776, 3366.124807550037, 3366.8148251846155, 5287.206918962158, 5318.734063296834, 5330.227117068028, 5365.061114304991, 5382.031234377815, 5362.410134885159, 5366.356037275523, 5384.6769193848395, 5384.973075134619, 5391.16436952272, 4953.603711703073, 4995.450500444948, 5043.722595257259, 5058.822941197059, 5069.670471419346, 4831.202851529493, 4922.532359425867, 4969.155243608595, 4971.577415919651, 5007.599392048645, 4972.328735620681, 5006.54941055305, 5013.874653133665, 5015.297858299823, 5018.749062546876, 4982.252662100677, 4982.800687972477, 5005.549500544968, 5014.59883209346, 5017.197248440269, 4828.444728856191, 4840.273958906157, 4902.558769710708, 4951.133551628451, 4975.4527002029745, 5243.10867152585, 5266.420693723688, 5275.498827611371, 5283.735813209324, 5300.178472336794, 5815.040518314515, 5840.88318233633, 5844.970775146139, 5847.386024467455, 5850.6489610623075, 5617.157426386034, 5709.3434255060365, 5743.10981797822, 5752.856178595527, 5858.266459853472, 5441.673499590037, 5452.100403433429, 5455.563554915597, 5458.181272809111, 5461.590380721462, 5878.500327472213, 5885.5585831062845, 5890.6171098879295, 5893.499052580493, 5896.734646940898, 5654.293213348332, 5675.631083979528, 5676.858078547987, 5726.198332233491, 5745.109508026263, 5754.539636829044, 5755.769769209269, 5758.3665796762325, 5775.16260142277, 5784.3926387729525, 5660.122680984454, 5701.116326969326, 5711.771529138871, 5727.398623144593, 5758.62568933018, 5686.687232202185, 5744.368119506851, 5794.971025144931, 5795.768169273182, 5827.009408400582, 4444.2222611042935, 4458.375827384169, 4460.799264033127, 4463.218936686132, 4464.821407143743, 3981.5620281768984, 3982.5818289079807, 4022.7787471688753, 4036.7981600920425, 4037.9192416151513, 5509.559235261169, 5511.200875872976, 5534.833954981357, 5576.33084029919, 5588.133839254922, 5699.230603868507, 5743.282089738783, 5781.682007489629, 5790.189373487715, 5794.0, 5396.865078373072, 5475.917861232085, 5480.862978425571, 5485.588580856452, 5496.203050557647, 5058.595312375001, 5074.06129865976, 5075.0357432087785, 5078.644494885322, 5083.313752643346, 5970.641761494324, 5998.400159983967, 6014.398560143951, 6022.849428764316, 6023.488003519662, 5722.914156287576, 5794.275715535558, 5807.564432667482, 5870.148828419855, 5898.351181370132, 5893.793717219876, 5907.3797251288, 5914.467697907209, 5915.349311575637, 5926.496247818984, 5656.6888821115235, 5691.943080569141, 5696.572757043155, 5725.0, 5725.370209277059, 5737.426257374229, 5776.075827867521, 5825.737841797045, 5864.208331875142, 5892.469677728854, 5567.610267281251, 5615.2700148980375, 5657.688827114548, 5663.745131469176, 5671.064274394678, 5589.80435684749, 5663.660180389025, 5670.773169073191, 5671.432856714296, 5672.773089076556, 5527.5577953764405, 5538.667679939216, 5593.720313984366, 5615.382307946041, 5641.633293835887, 5660.603757736919, 5742.310922689304, 5744.281964754406, 5754.5684073695475, 5792.362840087671, 5515.724213789214, 5586.050371436947, 5658.349289831722, 5667.319921609434, 5683.60214784961], "unit": "score", "name": "dojo.html", "value": 5354.062081266619}, {"lowerIsBetter": false, "replicates": [553.3232329886041, 572.1960645293362, 582.9972447390487, 589.022223109638, 610.2646311195005, 40917.181656366854, 42423.0, 42490.78754606222, 42540.787296063514, 42563.361549576744, 40581.59418405815, 40708.389374159415, 41067.0, 41067.58932410675, 41167.17665646685, 7604.125525564548, 7624.27569380908, 7647.388208943299, 7651.349635281008, 7675.385969122484, 8770.079141690127, 8828.470291782482, 8844.823103537925, 8870.225954809031, 8882.733517994438, 8854.955725221365, 8953.686620968267, 8963.0, 8975.102489751005, 8989.101089890992, 9059.818803623924, 9064.864027039599, 9124.361294709406, 9124.543772811367, 9167.449953002775, 8993.14565116313, 9060.365774395796, 9080.228180604656, 9123.178913897782, 9162.496062716547, 43573.564264357265, 44068.55931440677, 44122.558774412166, 44341.77829110866, 44352.11295774099, 3613.819309034564, 3695.57500887396, 3755.117547376364, 3762.6613604775703, 3984.980075099635, 32608.510872336934, 33136.3372732544, 33769.32461350784, 34012.31975360479, 34129.488057679155, 4226.112516371566, 4288.9142217155795, 4290.120525292307, 4292.399064131024, 4392.912141757179, 303530.0, 304328.0, 305294.4735276332, 305565.0, 306586.0, 67932.32067679359, 68036.6598167006, 68181.3181868175, 68193.65903170452, 68428.6578567104, 71254.2874571258, 72146.63926680334, 72260.0, 72263.63868180626, 72287.0, 18549.90725046366, 19208.80791192098, 19238.61522769565, 19247.422577322704, 19302.61394772125, 14370.928145359207, 14390.784138237936, 14441.855581444051, 14454.783178252335, 14488.275586220647, 22749.43126421853, 23177.0, 23209.651855222186, 23355.532889342117, 23456.7654323458, 8661.956690216635, 8900.821983560292, 9023.458592484472, 9055.456672599665, 9088.09181062067, 1090.536521978156, 1114.9665510034713, 1154.5612667186583, 1199.8680145184023, 1236.9381530923417, 31421.0, 33546.66453335436, 33727.494087588704, 33918.0, 33970.660293397246, 1191.6603767926272, 1201.1111777284746, 1221.2001139253803, 1236.9918516409277, 1280.5454063807401, 15081.773773393408, 15232.009919355207, 15380.30788614515, 15742.68514629701, 15769.44806931752, 433249.0, 539345.0, 551226.0, 552384.0, 556788.0, 54925.45074549203, 56295.0, 56316.43683563194, 56501.43498565044, 56726.14910776342, 1390.5897760160979, 1392.4360633943068, 1444.3861358922416, 1472.4183947340955, 1489.865912067919, 32204.516932246504, 33844.0, 33899.03137083014, 34096.82951585326, 34351.4847277286, 52475.0, 52959.470405295455, 53072.0, 53156.73421633022, 53205.20192197197, 324884.0, 327148.0, 327456.0, 329934.0, 330630.0, 34893.476597850546, 35423.64576354204, 35517.0, 35577.64422355847, 35607.10982225465, 47231.29153062638, 47717.0, 47798.283025755336, 48204.276935845286, 48834.511654883, 40136.59863401329, 40431.79784101061, 40845.59154408418, 41191.382129268684, 41885.79057104695, 40755.7962210187, 40755.7962210187, 40775.38836917508, 41053.589464104974, 43148.13703725971, 37014.62985370112, 37185.814070929475, 37661.0, 37710.81144594369, 37977.62022379741, 31233.687663123077, 31445.213869653442, 31901.840490797396, 32026.83986580052, 34189.82905085458, 27225.72774272232, 27490.862545687145, 27705.0, 27846.16461506158, 27864.303392415353], "unit": "score", "name": "ext.html", "value": 20540.834685899765}, {"lowerIsBetter": false, "replicates": [396.3697720624207, 399.0582225946765, 399.43479975834197, 399.6183644619389, 400.21957183492196, 10569.788604227911, 10899.83650245247, 17045.403410880623, 17520.91239543802, 17642.64714705707, 18065.187066582035, 18269.903805771628, 18383.908080459612, 18472.44582662519, 18744.43766686998, 6035.51715862731, 6339.746410143589, 6344.904826427607, 6357.237131544198, 6373.948298530737, 4583.0, 6126.938730612704, 6163.322034576193, 6905.551139175937, 6908.965455172717, 6682.532222744409, 6897.517173797836, 6945.756898508554, 6954.756583519578, 6967.651617419108, 6755.898661520081, 6761.425278851303, 7001.12485939255, 7051.753188638399, 7068.575885446839, 6534.869302613921, 6751.189857217117, 6754.932450675479, 6780.254172041072, 6786.932130678729, 23083.192088277, 23312.76687233123, 23713.881430592908, 23724.0, 24074.759252407428, 2509.5482813093636, 2518.4837108392917, 2550.9489810203877, 2565.422779874532, 2584.2118153963033, 13754.105983111165, 14046.92976535121, 14514.854851451455, 15293.61765955849, 15470.071795692294, 1983.8512111591688, 2020.1717295908607, 2055.5683306505653, 2071.4096482502414, 2081.2923605973892, 66251.33748662547, 67119.66440167816, 68453.0, 68586.0, 69520.6523967377, 15849.841501585068, 15987.680246395006, 16001.439949601709, 16044.598885027968, 16074.839251607335, 25284.620730689054, 25344.239672809847, 25620.103296384535, 25644.230673079837, 25784.74215257861, 7965.243301886311, 8368.539730314891, 8395.454295470898, 8406.0, 8475.74572762818, 7978.441509094424, 8008.719694810654, 8049.074356448966, 8051.637676304464, 8063.314618257396, 4645.140648979949, 4697.553732395417, 4775.71345719258, 4777.092352453023, 4798.736069516141, 1940.6506828770957, 2033.572949680554, 2041.5508588110888, 2044.6217449771839, 2054.8356131509445, 1058.333250052462, 1087.1628845788698, 1111.6387174168267, 1123.5730422439422, 1123.8145705958425, 16897.493075207763, 17047.659046818993, 17173.1851888595, 17426.0, 18114.547136321435, 1077.9622713205001, 1107.645553422913, 1110.9500072496758, 1119.3339962722168, 1167.6963989362828, 10431.947840260902, 10509.421981790922, 10613.150947924147, 10650.0, 10673.893261067446, 602596.0, 621886.0, 625912.0, 642686.0, 645634.0, 71343.0, 79610.60194698989, 79955.60022199969, 81090.18909810945, 81861.59069204735, 1205.5780476833213, 1214.5627574073326, 1237.208186760474, 1239.6281115665265, 1247.2017908538542, 15287.694246114794, 15331.156786376856, 15501.379944802533, 15621.297041633381, 15680.607984800472, 32143.839280803448, 32390.0, 32744.50883236801, 33416.49875251922, 33725.0, 41669.37495937624, 43024.78487607542, 43444.348334775634, 49410.752946235036, 52275.47724522858, 7719.614019299125, 7950.403719720928, 7996.480228785111, 8043.115257321573, 8086.393520485869, 8047.229609485904, 10837.295575787548, 10906.182036347464, 11171.441427928734, 11746.823797643214, 8290.46112002718, 9298.907010929805, 9953.651622193189, 10155.949220253851, 10324.690259292232, 7680.423968202295, 7696.961515192388, 7795.0, 7940.126586075412, 8111.472754270953, 7312.670929808065, 7657.923420765721, 7683.039620047494, 7887.64505597261, 8244.670213191404, 9874.308798384043, 10072.244581656258, 10265.0, 10281.588736450458, 10405.895941040493, 10393.63622273247, 10506.159507239556, 10574.259801814107, 10635.14918806478, 10753.946230269112], "unit": "score", "name": "jquery.html", "value": 9297.242729977243}, {"lowerIsBetter": false, "replicates": [1328.8870446012088, 1368.931553422329, 1518.8557087076724, 1912.2351059576158, 1937.854660900433, 7844.490108142981, 7976.601169941501, 8031.4377993540475, 8147.59262036898, 8147.91852081479, 8097.554634495101, 8110.188981101887, 8188.590570471468, 8202.548859812709, 8269.09040005599, 2422.878856057199, 2430.829841911067, 2442.670239517669, 2449.7917676997386, 2452.16626347042, 2360.173939121311, 2395.2694428199393, 2416.32342943976, 2420.165043060141, 2423.9151629692965, 2268.3648578398074, 2297.6783250344965, 2384.0463814474174, 2449.289705985262, 2458.3362492127117, 2308.607536718745, 2314.594945884472, 2329.9417514562188, 2347.319277409549, 2356.7643235676464, 2342.367560758603, 2354.1407386304045, 2356.0929042318658, 2356.352003199117, 2357.905683772647, 8013.639386227634, 8024.759257222293, 8091.352691784702, 8101.068377136587, 8116.2695357418115, 5016.548510634024, 5114.462981386975, 5132.897342053138, 5154.871128221787, 5157.071727089123, 3216.96783032169, 3261.0705948804716, 3305.074579117851, 3333.8833140840197, 3347.966520334814, 1116.5707893895744, 1123.870754863193, 1175.0658226709786, 1206.7707135644287, 1220.6704189868685, 11029.44852757367, 11080.445977701083, 11140.554377824878, 11147.108231341474, 11150.107991360672, 4433.46798384196, 4535.863924082217, 4545.136424079415, 4558.156741002925, 4579.244424669959, 9014.954925225464, 9024.909750902538, 9042.728718138467, 9045.095490450902, 9052.909470905339, 5032.547070763649, 5054.7978080876355, 5062.607782859743, 5072.442031376546, 5081.110805609003, 4653.6509761768, 4700.247960326331, 4708.058388322349, 4708.4820669726305, 4731.432228132646, 3585.121645196891, 3626.0, 3639.1448009717697, 3649.087728067983, 3692.095436617992, 1146.30919393895, 1152.7694461107812, 1155.1913660437726, 1155.4742592120565, 1199.962032841603, 1337.5586056601298, 1341.8926485881302, 1352.4049418255936, 1386.244496749274, 1414.6604814844568, 7425.480216384801, 7760.30157285847, 7778.338841198562, 7790.883136752953, 7791.727289544839, 1202.290648517379, 1222.419350808359, 1225.4179264849304, 1232.778099942001, 1264.7027948432112, 6045.395460454008, 6076.513878889678, 6135.171751813537, 6139.0, 6153.4154255345675, 9936.205103591696, 10135.746606334753, 10139.391636501834, 10189.592416303265, 10201.897981020094, 9780.510974451248, 9921.751956201153, 10015.949920250352, 10051.59793608262, 10129.594816207416, 7562.470627056054, 7678.57767822764, 7721.92278077212, 7731.072271327476, 7747.418943579254, 7042.366187043191, 7091.078159839188, 7108.040414544177, 7151.8569628608175, 7184.928150718426, 8409.789755256168, 8590.35572332065, 8742.475451472932, 8769.035406105195, 8848.91151088481, 8759.299256059627, 8788.780280493038, 8813.779655508408, 8820.691275805317, 9170.724878253663, 6805.829854253683, 6834.829129271808, 6849.24658287578, 6885.173779146535, 6892.758753443605, 7587.430942679211, 7651.196624354585, 7673.769786906401, 7695.4997925134685, 7700.114486834086, 6543.770968016097, 7157.391621712004, 7163.892541611985, 7186.24544422849, 7398.297161769839, 6960.686769095301, 6974.407175390149, 7040.542364746275, 7059.082319298458, 7156.105486814148, 7231.240719724564, 7304.780856574312, 7330.376917962033, 7367.77896663101, 7370.226126256666, 6747.966260168864, 6775.457963362821, 6780.254172040973, 6784.3894049535775, 6799.3200679931615, 6751.0, 6776.0, 6813.659317034029, 6845.965770171118, 6963.7214511421], "unit": "score", "name": "mootools.html", "value": 4714.82289596584}, {"lowerIsBetter": false, "replicates": [362.26279521174416, 374.2683054628202, 386.4022267922141, 388.3611459149699, 393.5159753503191, 6432.388923052303, 6513.055606936997, 6514.7719829805965, 6566.802995910124, 6622.602643841361, 6428.550001499897, 6442.7745028924, 6476.6761661916835, 6494.220693516772, 6496.675166241693, 4984.850454486371, 4986.700797952117, 4988.02733466974, 4990.326305948705, 4998.750062496879, 5012.223105418648, 5017.096922553938, 5027.396712394499, 5028.321176641143, 5030.396352437712, 4783.832565860195, 4819.710817350952, 4899.020195960804, 4917.877053073684, 4925.729084900329, 4881.487443818383, 4921.212605983062, 4926.014797040606, 4929.334539837112, 4937.925931111036, 4307.806148723315, 4310.288802347609, 4319.784010799479, 4320.65434765218, 4336.414584031147, 6167.938320616781, 6170.938290617081, 6173.166622505949, 6178.011518157074, 6192.87614247717, 5647.265855438808, 5780.421957804186, 5799.855003624901, 5801.216835727165, 5813.5349172066135, 6857.485688573327, 6861.519693621449, 6872.831618624846, 6882.277360877086, 6888.4144847688, 4782.665213435061, 4808.0, 4820.638452116105, 4828.46886842447, 4830.492798256168, 6974.79075627732, 7151.74968876092, 7162.319579639926, 7210.206877243551, 7224.4942854000765, 4880.194767863334, 4943.1596628573225, 4945.851624451272, 4949.554540091409, 4975.90048199034, 5034.798608055637, 5038.924416133761, 5039.44566097729, 5044.394672639307, 5048.217526283432, 5692.6299790513485, 5799.2750906136735, 5810.709464526757, 5813.82558523245, 5817.069268916953, 6931.480138989596, 6938.653067346612, 6941.201761797458, 6948.826279343057, 6958.47811414146, 1730.4376077774523, 1768.9734653980202, 1772.9468115956543, 1774.1838754173225, 1779.1904683369037, 1184.5439505790332, 1201.435325397062, 1202.8977536909285, 1223.1254652923203, 1226.4480983557257, 6646.468282537386, 6683.598984060972, 6688.632125233159, 6695.296993815677, 6696.46428285736, 7124.0, 7139.178994415709, 7143.357097861113, 7207.207207207203, 7213.89179162313, 6576.013597960345, 6675.065490831287, 6685.331466853373, 6726.730930762715, 6751.291114433012, 3827.6363745444137, 3893.279743247508, 3901.941470877939, 3907.5506316773367, 3925.548561915359, 7390.22402647725, 7412.0, 7415.666295016629, 7422.109346878409, 7429.2570742926355, 7251.782446526613, 7349.742759003409, 7375.520591161558, 7394.2975417335265, 7439.776806695808, 7331.74338898136, 7366.484346095722, 7412.666430010662, 7418.332350088518, 7436.739714110088, 7088.362047415757, 7187.7843664690145, 7191.244919283609, 7218.27817218274, 7238.0, 7039.0, 7041.718331266692, 7090.680919358744, 7096.574205547684, 7111.857762844817, 7436.479446438698, 7438.702451901863, 7440.2931721486375, 7440.995465612073, 7441.665125069277, 4916.852494425173, 4940.75296235194, 4963.205887058053, 4994.2508623706735, 5011.09800235961, 7168.569885806868, 7178.353948144692, 7192.568445893263, 7209.675564599501, 7219.963900180465, 6930.06444130036, 7000.439964802702, 7004.894926576208, 7005.894911576229, 7017.771889919141, 6865.965670171618, 6899.931000689929, 6989.37095661393, 6997.1253593300835, 7058.153021637231, 6804.55770374924, 6871.622060786504, 6878.27778083314, 6879.277675843964, 6891.586504809728, 6938.5836849789175, 7024.051753013482, 7028.262032486516, 7028.437724982092, 7046.682899269443, 6774.3225677431865, 6808.421284190899, 6815.829604259735, 6825.0, 6849.589024658537], "unit": "score", "name": "prototype.html", "value": 5245.015973028913}, {"lowerIsBetter": false, "replicates": [850.124371896946, 854.069064719456, 856.2379482260789, 959.160734357437, 966.1497881863959, 14269.286535673227, 14349.28253587319, 14415.135091894494, 14450.566483005501, 14550.345234464448, 13079.869201307984, 13087.280199589019, 13126.474941002372, 13214.801777973338, 13254.801177982337, 897.3045889435691, 905.3798148268424, 907.7957459571577, 913.1142791492242, 913.2830727878617, 896.8699538566904, 899.0919171636629, 908.4140729229676, 909.2135302962922, 911.0479548871413, 961.4087336288168, 961.4519723757461, 963.5712108111893, 963.6964356227766, 964.5418426247511, 943.3962264150949, 947.1191791633778, 947.4883562876073, 948.6300342866288, 952.319091849331, 702.2521015118933, 703.4513079797766, 706.2901783707392, 708.4332533972802, 710.6730903784265, 13086.934565327112, 13112.0, 13175.275359855204, 13185.208887466782, 13208.471661133543, 11891.702707432298, 11910.821337679941, 11956.521739130425, 12002.279863208236, 12359.62921112359, 9921.107100361001, 9944.602215911355, 10105.646302379455, 10200.38797672142, 10326.432046237454, 3860.401637746154, 3866.5360156781367, 3900.843966241347, 4538.455385353745, 4571.268597024494, 47007.76496117497, 51224.0, 51636.741816290676, 51998.48001519974, 52392.73803630996, 11307.0, 11344.319340839576, 11352.432378381212, 11539.48072336747, 11642.126840486828, 19334.516637084187, 19356.128974196195, 19366.4190074298, 19391.22435102608, 19438.222471101, 7999.640016199285, 8062.355011599058, 8107.391945604103, 8109.148539403278, 8470.449420787629, 4379.343098535245, 4434.645228381722, 4470.6870519064005, 4492.842750503717, 4533.206688829442, 3088.2279430142466, 3116.049604870491, 3131.2171957010746, 3137.7803553751473, 3142.9371412571622, 567.0218872445082, 590.8729623131047, 590.9822705318848, 593.3087952535249, 593.7981086430548, 604.7762327938691, 645.3127419298432, 705.78473565563, 764.9043869516311, 771.5987686403039, 9556.0, 9954.30319877616, 10080.69757907264, 10090.596376145018, 10095.848562271573, 628.7610707930957, 676.3507033248137, 745.0909889934271, 749.0561892016099, 760.3916866506772, 4429.800658970354, 4591.127685739699, 4693.131770622445, 4708.576228139484, 4731.408573928259, 60619.39380606226, 61487.0, 61521.0, 61910.0, 61964.0, 55103.44896551063, 55675.0, 56168.0, 56354.0, 56835.43164568384, 45554.5444545557, 46729.5327046732, 47205.76397117993, 48124.51875481271, 51954.96090078177, 9318.906810931803, 9534.09426104519, 9564.426134431957, 9608.926281331691, 9828.771403574554, 35377.29245415129, 36821.44767828538, 37449.0, 37475.81262093672, 38681.0, 47373.289400659705, 47686.28470572875, 47705.28442073302, 47745.52254477411, 49355.25967110568, 12198.817017744563, 12327.50689972391, 12340.197887137307, 12473.625791226277, 12502.437390317276, 13009.479620815062, 13637.249951252776, 13691.89741544734, 13955.790663139858, 14241.64395890111, 12682.0, 12684.0, 13188.736225275632, 13363.26502042397, 13937.860621394062, 12288.938555307166, 12342.197757145757, 12689.873101269239, 12949.223046617233, 13638.659033524242, 12092.576759813364, 12098.395080246128, 12232.571859984857, 12634.115611907078, 12656.050796190506, 10052.94677560088, 10197.694069177936, 10396.16830653561, 10583.417912014913, 10585.206109541661, 10651.201159913193, 10899.509522071685, 11024.834627480433, 11046.502907369348, 11170.664880053286], "unit": "score", "name": "yui.html", "value": 6842.300154663973}], "name": "dromaeo_css", "value": 7464.797691599743}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 106.0, 114.0, 113.0, 111.0, 122.0, 112.0, 110.0, 111.0, 110.0], "unit": "score", "name": "ai-astar", "value": 112.1}, {"lowerIsBetter": true, "replicates": [151.0, 126.0, 151.0, 168.0, 128.0, 154.0, 148.0, 127.0, 159.0, 125.0], "unit": "score", "name": "audio-beat-detection", "value": 143.7}, {"lowerIsBetter": true, "replicates": [162.0, 162.0, 162.0, 160.0, 162.0, 163.0, 161.0, 163.0, 164.0, 162.0], "unit": "score", "name": "audio-dft", "value": 162.1}, {"lowerIsBetter": true, "replicates": [72.0, 74.0, 76.0, 170.0, 74.0, 72.0, 73.0, 73.0, 160.0, 74.0], "unit": "score", "name": "audio-fft", "value": 91.8}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 144.0, 96.0, 98.0, 98.0, 134.0, 95.0, 95.0, 136.0], "unit": "score", "name": "audio-oscillator", "value": 108.6}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0, 112.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 110.0}, {"lowerIsBetter": true, "replicates": [148.0, 149.0, 146.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 147.1}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 97.0, 96.0, 97.0, 97.0, 96.0, 96.0, 97.0], "unit": "score", "name": "imaging-desaturate", "value": 96.4}, {"lowerIsBetter": true, "replicates": [54.0, 55.0, 57.0, 54.0, 55.0, 58.0, 55.0, 64.0, 53.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 55.8}, {"lowerIsBetter": true, "replicates": [63.0, 64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0, 64.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 64.0}, {"lowerIsBetter": true, "replicates": [86.0, 81.0, 81.0, 82.0, 89.0, 81.0, 83.0, 81.0, 81.0, 81.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.6}, {"lowerIsBetter": true, "replicates": [115.0, 113.0, 112.0, 108.0, 109.0, 113.0, 117.0, 112.0, 125.0, 128.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 115.2}, {"lowerIsBetter": true, "replicates": [141.0, 140.0, 140.0, 138.0, 136.0, 125.0, 139.0, 144.0, 139.0, 135.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 137.7}, {"lowerIsBetter": true, "replicates": [69.0, 68.0, 68.0, 67.0, 65.0, 71.0, 64.0, 64.0, 64.0, 63.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.3}], "name": "kraken", "value": 1493.3999999999999}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [165.6451880072884], "unit": "score", "name": "Richards", "value": 213.11817399999993}, {"lowerIsBetter": false, "replicates": [169.80811682798438], "unit": "score", "name": "DeltaBlue", "value": 389.3689020000002}, {"lowerIsBetter": false, "replicates": [278.55153203342616], "unit": "score", "name": "Encrypt", "value": 955.5897900000004}, {"lowerIsBetter": false, "replicates": [5191.709844559586], "unit": "score", "name": "Decrypt", "value": 51.270392215568904}, {"lowerIsBetter": false, "replicates": [916.5902841429881], "unit": "score", "name": "RayTrace", "value": 807.327999}, {"lowerIsBetter": false, "replicates": [568.1818181818181], "unit": "score", "name": "Earley", "value": 1172.97488}, {"lowerIsBetter": false, "replicates": [11188.888888888889], "unit": "score", "name": "Boyer", "value": 59.56471698113206}, {"lowerIsBetter": false, "replicates": [38528.301886792455], "unit": "score", "name": "RegExp", "value": 23.644566601371217}, {"lowerIsBetter": false, "replicates": [515.9958720330237], "unit": "score", "name": "Splay", "value": 157.92955799999993}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 22089.386990826468}]} 03:21:44 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,43]]", "result": "22089.39"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,43]]", "result": "1493.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,43]]", "result": "7464.80"}}} 03:21:44 INFO - Return code: 0 03:21:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 03:21:44 INFO - # TBPL SUCCESS # 03:21:44 INFO - Running post-action listener: _resource_record_post_action 03:21:44 INFO - Running post-run listener: _resource_record_post_run 03:21:45 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 4096; Write bytes: 501698560; Read time: 12; Write time: 369732 03:21:45 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 242348032; Read time: 0; Write time: 217248 03:21:45 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 4096; Write bytes: 259350528; Read time: 12; Write time: 152484 03:21:45 INFO - Running post-run listener: _upload_blobber_files 03:21:45 INFO - Blob upload gear active. 03:21:45 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 03:21:45 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 03:21:45 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 03:21:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Mozilla-Aurora -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 03:21:46 INFO - (blobuploader) - INFO - Open directory for files ... 03:21:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:21:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:21:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:46 INFO - (blobuploader) - INFO - Done attempting. 03:21:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:21:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:21:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:47 INFO - (blobuploader) - INFO - Done attempting. 03:21:47 INFO - (blobuploader) - INFO - Iteration through files over. 03:21:47 INFO - Return code: 0 03:21:47 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 03:21:47 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 03:21:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c96dcfee545d0a5b526423fa24e7383b317e78e4b0194925ba001b7d1f51f31165b8f29ba3bfeceadb13553ac061cf590a8a068fa0bc901b452888c9f97c8b7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/405ba7b43ef7d2c0cafff9a46ab9e37a630d76d6d63191a49caa0e777c8c0bd60139b15eacd3a0eb3bd822b0ebbfd7718048fc20aaa7b5cbcc56b4d72b321b8e"} 03:21:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 03:21:47 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 03:21:47 INFO - Contents: 03:21:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c96dcfee545d0a5b526423fa24e7383b317e78e4b0194925ba001b7d1f51f31165b8f29ba3bfeceadb13553ac061cf590a8a068fa0bc901b452888c9f97c8b7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/405ba7b43ef7d2c0cafff9a46ab9e37a630d76d6d63191a49caa0e777c8c0bd60139b15eacd3a0eb3bd822b0ebbfd7718048fc20aaa7b5cbcc56b4d72b321b8e"} 03:21:47 INFO - Running post-run listener: copy_logs_to_upload_dir 03:21:47 INFO - Copying logs to upload dir... 03:21:47 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 03:21:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1349.288548 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 29 secs) (at 2016-02-27 03:21:47.326094) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-27 03:21:47.329613) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c96dcfee545d0a5b526423fa24e7383b317e78e4b0194925ba001b7d1f51f31165b8f29ba3bfeceadb13553ac061cf590a8a068fa0bc901b452888c9f97c8b7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/405ba7b43ef7d2c0cafff9a46ab9e37a630d76d6d63191a49caa0e777c8c0bd60139b15eacd3a0eb3bd822b0ebbfd7718048fc20aaa7b5cbcc56b4d72b321b8e"} build_url:https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011297 build_url: 'https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1c96dcfee545d0a5b526423fa24e7383b317e78e4b0194925ba001b7d1f51f31165b8f29ba3bfeceadb13553ac061cf590a8a068fa0bc901b452888c9f97c8b7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/405ba7b43ef7d2c0cafff9a46ab9e37a630d76d6d63191a49caa0e777c8c0bd60139b15eacd3a0eb3bd822b0ebbfd7718048fc20aaa7b5cbcc56b4d72b321b8e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-27 03:21:47.355151) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 03:21:47.355560) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1456570545.144458-229352170 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004603 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 03:21:47.436849) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-27 03:21:47.437150) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-27 03:21:47.437445) ========= ========= Total master_lag: 0.36 =========