builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-042 starttime: 1456969744.95 results: success (0) buildid: 20160302124722 builduid: 51b451f0948547329e8addbdd0fef435 revision: 3a34e8b89130c938dd12596e3c6274d5fb994825 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:04.945789) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:04.946310) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:04.946650) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006165 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:04.969511) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:04.969859) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:04.983628) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:04.984047) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004560 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:05.033509) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:05.033855) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:05.034304) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:05.034629) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-03-02 17:49:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.9M=0s 2016-03-02 17:49:05 (46.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.385680 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:05.433455) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:05.433864) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018960 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:05.483599) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:05.484004) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3a34e8b89130c938dd12596e3c6274d5fb994825 --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 3a34e8b89130c938dd12596e3c6274d5fb994825 --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=74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-03-02 17:49:05,805 truncating revision to first 12 chars 2016-03-02 17:49:05,805 Setting DEBUG logging. 2016-03-02 17:49:05,805 attempt 1/10 2016-03-02 17:49:05,805 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3a34e8b89130?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-02 17:49:06,057 unpacking tar archive at: mozilla-aurora-3a34e8b89130/testing/mozharness/ program finished with exit code 0 elapsedTime=0.542158 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:06.039651) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:06.040122) ========= echo 3a34e8b89130c938dd12596e3c6274d5fb994825 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'3a34e8b89130c938dd12596e3c6274d5fb994825'] 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=74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 3a34e8b89130c938dd12596e3c6274d5fb994825 program finished with exit code 0 elapsedTime=0.004637 script_repo_revision: '3a34e8b89130c938dd12596e3c6274d5fb994825' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:06.091552) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:06.091915) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-02 17:49:06.103029) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 4 secs) (at 2016-03-02 17:49:06.103584) ========= /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=74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:49:06 INFO - MultiFileLogger online at 20160302 17:49:06 in /builds/slave/test-pgo 17:49:06 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 17:49:06 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 17:49:06 INFO - {'append_to_log': False, 17:49:06 INFO - 'base_work_dir': '/builds/slave/test-pgo', 17:49:06 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:49:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 17:49:06 INFO - 'branch': 'Mozilla-Aurora', 17:49:06 INFO - 'buildbot_json_path': 'buildprops.json', 17:49:06 INFO - 'config_files': ('talos/linux_config.py',), 17:49:06 INFO - 'default_actions': ('clobber', 17:49:06 INFO - 'read-buildbot-config', 17:49:06 INFO - 'download-and-extract', 17:49:06 INFO - 'populate-webroot', 17:49:06 INFO - 'create-virtualenv', 17:49:06 INFO - 'install', 17:49:06 INFO - 'run-tests'), 17:49:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:49:06 INFO - 'download_minidump_stackwalk': True, 17:49:06 INFO - 'download_symbols': 'ondemand', 17:49:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:49:06 INFO - 'tooltool.py': '/tools/tooltool.py', 17:49:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:49:06 INFO - '/tools/misc-python/virtualenv.py')}, 17:49:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:49:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:49:06 INFO - 'installer_path': 'installer.exe', 17:49:06 INFO - 'log_level': 'info', 17:49:06 INFO - 'log_name': 'talos', 17:49:06 INFO - 'log_to_console': True, 17:49:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:49:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:49:06 INFO - 'opt_config_files': (), 17:49:06 INFO - 'pip_index': False, 17:49:06 INFO - 'sps_profile': False, 17:49:06 INFO - 'sps_profile_interval': 0, 17:49:06 INFO - 'suite': 'dromaeojs-e10s', 17:49:06 INFO - 'system_bits': '32', 17:49:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:49:06 INFO - 'title': 'talos-linux64-ix-042', 17:49:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:49:06 INFO - 'use_talos_json': True, 17:49:06 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 17:49:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:49:06 INFO - 'work_dir': 'build'} 17:49:06 INFO - ##### 17:49:06 INFO - ##### Running clobber step. 17:49:06 INFO - ##### 17:49:06 INFO - Running pre-action listener: _resource_record_pre_action 17:49:06 INFO - Running main action method: clobber 17:49:06 INFO - rmtree: /builds/slave/test-pgo/build 17:49:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 17:49:06 INFO - Running post-action listener: _resource_record_post_action 17:49:06 INFO - ##### 17:49:06 INFO - ##### Running read-buildbot-config step. 17:49:06 INFO - ##### 17:49:06 INFO - Running pre-action listener: _resource_record_pre_action 17:49:06 INFO - Running main action method: read_buildbot_config 17:49:06 INFO - Using buildbot properties: 17:49:06 INFO - { 17:49:06 INFO - "project": "", 17:49:06 INFO - "product": "firefox", 17:49:06 INFO - "script_repo_revision": "production", 17:49:06 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 17:49:06 INFO - "repository": "", 17:49:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 17:49:06 INFO - "buildid": "20160302124722", 17:49:06 INFO - "pgo_build": "True", 17:49:06 INFO - "basedir": "/builds/slave/test-pgo", 17:49:06 INFO - "buildnumber": 104, 17:49:06 INFO - "slavename": "talos-linux64-ix-042", 17:49:06 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:49:06 INFO - "platform": "ubuntu64_hw", 17:49:06 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 17:49:06 INFO - "branch": "mozilla-aurora", 17:49:06 INFO - "repo_path": "releases/mozilla-aurora", 17:49:06 INFO - "revision": "3a34e8b89130c938dd12596e3c6274d5fb994825", 17:49:06 INFO - "stage_platform": "linux64-pgo", 17:49:06 INFO - "builduid": "51b451f0948547329e8addbdd0fef435", 17:49:06 INFO - "slavebuilddir": "test" 17:49:06 INFO - } 17:49:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2. 17:49:06 INFO - Running post-action listener: _resource_record_post_action 17:49:06 INFO - ##### 17:49:06 INFO - ##### Running download-and-extract step. 17:49:06 INFO - ##### 17:49:06 INFO - Running pre-action listener: _resource_record_pre_action 17:49:06 INFO - Running main action method: download_and_extract 17:49:06 INFO - mkdir: /builds/slave/test-pgo/build/tests 17:49:06 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')]} 17:49:06 INFO - https://queue.taskcluster.net/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 17:49:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/test_packages.json 17:49:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/test_packages.json 17:49:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 17:49:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 17:49:07 INFO - Downloaded 1448 bytes. 17:49:07 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 17:49:07 INFO - Using the following test package requirements: 17:49:07 INFO - {u'common': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 17:49:07 INFO - u'cppunittest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 17:49:07 INFO - u'firefox-46.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 17:49:07 INFO - u'gtest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 17:49:07 INFO - u'firefox-46.0a2.en-US.linux-x86_64.gtest.tests.zip'], 17:49:07 INFO - u'jittest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 17:49:07 INFO - u'jsshell-linux-x86_64.zip'], 17:49:07 INFO - u'mochitest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 17:49:07 INFO - u'firefox-46.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 17:49:07 INFO - u'mozbase': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 17:49:07 INFO - u'reftest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 17:49:07 INFO - u'firefox-46.0a2.en-US.linux-x86_64.reftest.tests.zip'], 17:49:07 INFO - u'talos': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 17:49:07 INFO - u'firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip'], 17:49:07 INFO - u'web-platform': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 17:49:07 INFO - u'firefox-46.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 17:49:07 INFO - u'webapprt': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 17:49:07 INFO - u'xpcshell': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 17:49:07 INFO - u'firefox-46.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 17:49:07 INFO - Downloading packages: [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:49: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')]} 17:49:07 INFO - https://queue.taskcluster.net/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:49:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 17:49:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 17:49:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/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 17:49:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/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 17:49:39 WARNING - Socket error when accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip: The read operation timed out 17:49:39 INFO - retry: attempt #1 caught exception: The read operation timed out 17:49:39 INFO - retry: Failed, sleeping 30 seconds before retrying 17:50:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/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 17:50:10 INFO - Downloaded 23089445 bytes. 17:50:10 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'] 17:50:10 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 17:50:11 INFO - Return code: 0 17:50:11 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 17:50:11 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')]} 17:50:11 INFO - https://queue.taskcluster.net/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:50:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 17:50:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 17:50:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/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 17:50:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/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 17:50:12 INFO - Downloaded 23089445 bytes. 17:50:12 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'] 17:50:12 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 17:50:14 INFO - Return code: 0 17:50: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')]} 17:50:14 INFO - https://queue.taskcluster.net/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:50:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip 17:50:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip 17:50:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/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 17:50:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/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 17:50:15 INFO - Downloaded 10706668 bytes. 17:50:15 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'] 17:50:15 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 17:50:15 INFO - Return code: 0 17:50:15 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')]} 17:50:15 INFO - https://queue.taskcluster.net/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:50:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 17:50:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 17:50:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/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 17:50:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/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 17:50:16 INFO - Downloaded 59963116 bytes. 17:50:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 17:50:16 INFO - mkdir: /builds/slave/test-pgo/properties 17:50:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 17:50:16 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 17:50:16 INFO - Contents: 17:50:16 INFO - build_url:https://queue.taskcluster.net/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 17:50:17 INFO - Running post-action listener: _resource_record_post_action 17:50:17 INFO - Running post-action listener: set_extra_try_arguments 17:50:17 INFO - ##### 17:50:17 INFO - ##### Running populate-webroot step. 17:50:17 INFO - ##### 17:50:17 INFO - Running pre-action listener: _resource_record_pre_action 17:50:17 INFO - Running main action method: populate_webroot 17:50:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:50:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:50:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:50:17 INFO - '--tppagecycles', 17:50:17 INFO - '7'], 17:50:17 INFO - 'tests': ['tsvgm']}}, 17:50:17 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 17:50:17 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 17:50:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:50:17 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:50:17 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 17:50:17 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 17:50:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:50:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:17 INFO - 'talos_options': ['--e10s'], 17:50:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:50:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:17 INFO - 'tests': ['damp', 'tps']}, 17:50:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:17 INFO - 'talos_options': ['--e10s'], 17:50:17 INFO - 'tests': ['damp', 'tps']}, 17:50:17 INFO - 'g3': {'tests': ['dromaeo_dom']}, 17:50:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:50:17 INFO - 'other': {'tests': ['a11yr', 17:50:17 INFO - 'ts_paint', 17:50:17 INFO - 'tpaint', 17:50:17 INFO - 'sessionrestore', 17:50:17 INFO - 'sessionrestore_no_auto_restore']}, 17:50:17 INFO - 'other-e10s': {'talos_options': ['--e10s'], 17:50:17 INFO - 'tests': ['a11yr', 17:50:17 INFO - 'ts_paint', 17:50:17 INFO - 'tpaint', 17:50:17 INFO - 'sessionrestore', 17:50:17 INFO - 'sessionrestore_no_auto_restore']}, 17:50:17 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 17:50:17 INFO - 'tests': ['a11yr', 17:50:17 INFO - 'ts_paint', 17:50:17 INFO - 'tpaint', 17:50:17 INFO - 'sessionrestore', 17:50:17 INFO - 'sessionrestore_no_auto_restore']}, 17:50:17 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 17:50:17 INFO - 'tests': ['a11yr', 17:50:17 INFO - 'ts_paint', 17:50:17 INFO - 'tpaint', 17:50:17 INFO - 'sessionrestore', 17:50:17 INFO - 'sessionrestore_no_auto_restore']}, 17:50:17 INFO - 'other_l64': {'tests': ['a11yr', 17:50:17 INFO - 'ts_paint', 17:50:17 INFO - 'tpaint', 17:50:17 INFO - 'sessionrestore', 17:50:17 INFO - 'sessionrestore_no_auto_restore']}, 17:50:17 INFO - 'other_nol64': {'tests': ['a11yr', 17:50:17 INFO - 'ts_paint', 17:50:17 INFO - 'tpaint', 17:50:17 INFO - 'sessionrestore', 17:50:17 INFO - 'sessionrestore_no_auto_restore']}, 17:50:17 INFO - 'svgr': {'tests': ['tsvgx', 17:50:17 INFO - 'tsvgr_opacity', 17:50:17 INFO - 'tart', 17:50:17 INFO - 'tscrollx', 17:50:17 INFO - 'cart']}, 17:50:17 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 17:50:17 INFO - 'tests': ['tsvgx', 17:50:17 INFO - 'tsvgr_opacity', 17:50:17 INFO - 'tart', 17:50:17 INFO - 'tscrollx', 17:50:17 INFO - 'cart']}, 17:50:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:17 INFO - 'tests': ['tp5o']}, 17:50:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:17 INFO - 'talos_options': ['--e10s'], 17:50:17 INFO - 'tests': ['tp5o']}, 17:50:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:17 INFO - 'talos_options': ['--xperf_path', 17:50:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:50:17 INFO - 'tests': ['tp5n']}, 17:50:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:50:17 INFO - 'talos_options': ['--e10s', 17:50:17 INFO - '--xperf_path', 17:50:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:50:17 INFO - 'tests': ['tp5n']}}, 17:50:17 INFO - 'talos.zip': {'path': '', 17:50:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:50:17 INFO - Running post-action listener: _resource_record_post_action 17:50:17 INFO - ##### 17:50:17 INFO - ##### Running create-virtualenv step. 17:50:17 INFO - ##### 17:50:17 INFO - Running pre-action listener: _resource_record_pre_action 17:50:17 INFO - Running main action method: create_virtualenv 17:50:17 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 17:50:17 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 17:50:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 17:50:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:50:17 INFO - Using real prefix '/usr' 17:50:17 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 17:50:18 INFO - Installing distribute.............................................................................................................................................................................................done. 17:50:20 INFO - Installing pip.................done. 17:50:20 INFO - Return code: 0 17:50:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 17:50:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:20 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 0x7f6aad32ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d0230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19627f0>, '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 0x18ce2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce760>, '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': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', '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 17:50:20 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 17:50:20 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 17:50:20 INFO - Using env: {'DISPLAY': ':0', 17:50:20 INFO - 'HOME': '/home/cltbld', 17:50:20 INFO - 'LANG': 'en_US.UTF-8', 17:50:20 INFO - 'LANGUAGE': 'en_US:en', 17:50:20 INFO - 'LOGNAME': 'cltbld', 17:50:20 INFO - 'MAIL': '/var/mail/cltbld', 17:50:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:20 INFO - 'MOZ_NO_REMOTE': '1', 17:50:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:20 INFO - 'NO_EM_RESTART': '1', 17:50:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:50:20 INFO - 'PWD': '/builds/slave/test-pgo', 17:50:20 INFO - 'SHELL': '/bin/bash', 17:50:20 INFO - 'SHLVL': '1', 17:50:20 INFO - 'TERM': 'linux', 17:50:20 INFO - 'TMOUT': '86400', 17:50:20 INFO - 'USER': 'cltbld', 17:50:20 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', 17:50:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:20 INFO - '_': '/tools/buildbot/bin/python'} 17:50:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:20 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 17:50:20 INFO - Cleaning up... 17:50:20 INFO - Return code: 0 17:50:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 17:50:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:20 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 0x7f6aad32ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d0230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19627f0>, '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 0x18ce2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce760>, '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': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', '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 17:50:20 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 17:50:20 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 17:50:20 INFO - Using env: {'DISPLAY': ':0', 17:50:20 INFO - 'HOME': '/home/cltbld', 17:50:20 INFO - 'LANG': 'en_US.UTF-8', 17:50:20 INFO - 'LANGUAGE': 'en_US:en', 17:50:20 INFO - 'LOGNAME': 'cltbld', 17:50:20 INFO - 'MAIL': '/var/mail/cltbld', 17:50:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:20 INFO - 'MOZ_NO_REMOTE': '1', 17:50:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:20 INFO - 'NO_EM_RESTART': '1', 17:50:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:50:20 INFO - 'PWD': '/builds/slave/test-pgo', 17:50:20 INFO - 'SHELL': '/bin/bash', 17:50:20 INFO - 'SHLVL': '1', 17:50:20 INFO - 'TERM': 'linux', 17:50:20 INFO - 'TMOUT': '86400', 17:50:20 INFO - 'USER': 'cltbld', 17:50:20 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', 17:50:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:20 INFO - '_': '/tools/buildbot/bin/python'} 17:50:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:20 INFO - Downloading/unpacking psutil>=0.7.1 17:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 17:50:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:50:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:50:22 INFO - Installing collected packages: psutil 17:50:22 INFO - Running setup.py install for psutil 17:50:22 INFO - building 'psutil._psutil_linux' extension 17:50:22 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 17:50:22 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 17:50:22 INFO - building 'psutil._psutil_posix' extension 17:50:22 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 17:50:22 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 17:50:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:50:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:50:22 INFO - Successfully installed psutil 17:50:22 INFO - Cleaning up... 17:50:22 INFO - Return code: 0 17:50:22 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 17:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:22 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 0x7f6aad32ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d0230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19627f0>, '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 0x18ce2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce760>, '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': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', '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 17:50:22 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 17:50:22 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 17:50:22 INFO - Using env: {'DISPLAY': ':0', 17:50:22 INFO - 'HOME': '/home/cltbld', 17:50:22 INFO - 'LANG': 'en_US.UTF-8', 17:50:22 INFO - 'LANGUAGE': 'en_US:en', 17:50:22 INFO - 'LOGNAME': 'cltbld', 17:50:22 INFO - 'MAIL': '/var/mail/cltbld', 17:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:22 INFO - 'MOZ_NO_REMOTE': '1', 17:50:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:22 INFO - 'NO_EM_RESTART': '1', 17:50:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:50:22 INFO - 'PWD': '/builds/slave/test-pgo', 17:50:22 INFO - 'SHELL': '/bin/bash', 17:50:22 INFO - 'SHLVL': '1', 17:50:22 INFO - 'TERM': 'linux', 17:50:22 INFO - 'TMOUT': '86400', 17:50:22 INFO - 'USER': 'cltbld', 17:50:22 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', 17:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:22 INFO - '_': '/tools/buildbot/bin/python'} 17:50:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:23 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:50:23 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 17:50:23 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 17:50:23 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 17:50:23 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 17:50:23 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 17:50:23 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 17:50:24 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:50:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:50:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:50:25 INFO - Installing collected packages: mozsystemmonitor 17:50:25 INFO - Running setup.py install for mozsystemmonitor 17:50:25 INFO - Successfully installed mozsystemmonitor 17:50:25 INFO - Cleaning up... 17:50:25 INFO - Return code: 0 17:50:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 17:50:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:25 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 0x7f6aad32ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d0230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19627f0>, '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 0x18ce2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce760>, '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': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', '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 17:50:25 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 17:50:25 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 17:50:25 INFO - Using env: {'DISPLAY': ':0', 17:50:25 INFO - 'HOME': '/home/cltbld', 17:50:25 INFO - 'LANG': 'en_US.UTF-8', 17:50:25 INFO - 'LANGUAGE': 'en_US:en', 17:50:25 INFO - 'LOGNAME': 'cltbld', 17:50:25 INFO - 'MAIL': '/var/mail/cltbld', 17:50:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:25 INFO - 'MOZ_NO_REMOTE': '1', 17:50:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:25 INFO - 'NO_EM_RESTART': '1', 17:50:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:50:25 INFO - 'PWD': '/builds/slave/test-pgo', 17:50:25 INFO - 'SHELL': '/bin/bash', 17:50:25 INFO - 'SHLVL': '1', 17:50:25 INFO - 'TERM': 'linux', 17:50:25 INFO - 'TMOUT': '86400', 17:50:25 INFO - 'USER': 'cltbld', 17:50:25 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', 17:50:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:25 INFO - '_': '/tools/buildbot/bin/python'} 17:50:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:25 INFO - Downloading/unpacking blobuploader==1.2.4 17:50:25 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 17:50:25 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 17:50:25 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 17:50:25 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 17:50:25 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 17:50:25 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 17:50:27 INFO - Downloading blobuploader-1.2.4.tar.gz 17:50:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:50:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:50:27 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 17:50:27 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 17:50:27 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 17:50:27 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 17:50:27 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 17:50:27 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 17:50:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 17:50:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:50:27 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 17:50:27 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 17:50:27 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 17:50:27 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 17:50:27 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 17:50:27 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 17:50:27 INFO - Downloading docopt-0.6.1.tar.gz 17:50:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 17:50:27 INFO - Installing collected packages: blobuploader, requests, docopt 17:50:27 INFO - Running setup.py install for blobuploader 17:50:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:50:28 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 17:50:28 INFO - Running setup.py install for requests 17:50:28 INFO - Running setup.py install for docopt 17:50:28 INFO - Successfully installed blobuploader requests docopt 17:50:28 INFO - Cleaning up... 17:50:28 INFO - Return code: 0 17:50:28 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:50: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')]} 17:50:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50: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')]} 17:50:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:28 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 0x7f6aad32ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d0230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19627f0>, '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 0x18ce2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce760>, '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': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', '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 17:50:28 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 17:50:28 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 17:50:28 INFO - Using env: {'DISPLAY': ':0', 17:50:28 INFO - 'HOME': '/home/cltbld', 17:50:28 INFO - 'LANG': 'en_US.UTF-8', 17:50:28 INFO - 'LANGUAGE': 'en_US:en', 17:50:28 INFO - 'LOGNAME': 'cltbld', 17:50:28 INFO - 'MAIL': '/var/mail/cltbld', 17:50:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:28 INFO - 'MOZ_NO_REMOTE': '1', 17:50:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:28 INFO - 'NO_EM_RESTART': '1', 17:50:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:50:28 INFO - 'PWD': '/builds/slave/test-pgo', 17:50:28 INFO - 'SHELL': '/bin/bash', 17:50:28 INFO - 'SHLVL': '1', 17:50:28 INFO - 'TERM': 'linux', 17:50:28 INFO - 'TMOUT': '86400', 17:50:28 INFO - 'USER': 'cltbld', 17:50:28 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', 17:50:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:28 INFO - '_': '/tools/buildbot/bin/python'} 17:50:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:50:28 INFO - Running setup.py (path:/tmp/pip-k8D_h5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:50:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:50:28 INFO - Running setup.py (path:/tmp/pip-sjH2rV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:50:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:50:28 INFO - Running setup.py (path:/tmp/pip-rrRRKC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:50:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:50:29 INFO - Running setup.py (path:/tmp/pip-aGulmd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:50:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 17:50:29 INFO - Running setup.py (path:/tmp/pip-iGx9ZY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 17:50:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:50:29 INFO - Running setup.py (path:/tmp/pip-mbJlYh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:50:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:50:29 INFO - Running setup.py (path:/tmp/pip-1_TzEg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:50:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:50:29 INFO - Running setup.py (path:/tmp/pip-bEJ5rx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:50:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 17:50:29 INFO - Running setup.py (path:/tmp/pip-W2e9a5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 17:50:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 17:50:29 INFO - Running setup.py (path:/tmp/pip-mKgJy6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 17:50:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:50:29 INFO - Running setup.py (path:/tmp/pip-0qPazi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:50:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:50:29 INFO - Running setup.py (path:/tmp/pip-YgWYkY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:50:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:50:29 INFO - Running setup.py (path:/tmp/pip-lk70kY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:50:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:50:29 INFO - Running setup.py (path:/tmp/pip-UlaszU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:50:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:50:29 INFO - Running setup.py (path:/tmp/pip-nZqhRf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:50:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 17:50:29 INFO - Running setup.py (path:/tmp/pip-eAz99D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 17:50:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 17:50:29 INFO - Running setup.py (path:/tmp/pip-aYg3Wp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 17:50:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:50:30 INFO - Running setup.py install for manifestparser 17:50:30 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 17:50:30 INFO - Running setup.py install for mozcrash 17:50:30 INFO - Running setup.py install for mozdebug 17:50:30 INFO - Running setup.py install for mozdevice 17:50:30 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 17:50:30 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 17:50:30 INFO - Running setup.py install for mozfile 17:50:30 INFO - Running setup.py install for mozhttpd 17:50:30 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 17:50:30 INFO - Running setup.py install for mozinfo 17:50:30 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 17:50:30 INFO - Running setup.py install for mozInstall 17:50:30 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 17:50:30 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 17:50:30 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 17:50:30 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 17:50:30 INFO - Running setup.py install for mozleak 17:50:30 INFO - Running setup.py install for mozlog 17:50:31 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 17:50:31 INFO - Running setup.py install for moznetwork 17:50:31 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 17:50:31 INFO - Running setup.py install for mozprocess 17:50:31 INFO - Running setup.py install for mozprofile 17:50:31 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 17:50:31 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 17:50:31 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 17:50:31 INFO - Running setup.py install for mozrunner 17:50:31 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 17:50:31 INFO - Running setup.py install for mozscreenshot 17:50:31 INFO - Running setup.py install for moztest 17:50:31 INFO - Running setup.py install for mozversion 17:50:31 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 17:50:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:50:31 INFO - Cleaning up... 17:50:31 INFO - Return code: 0 17:50:31 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:50:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:31 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 0x7f6aad32ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d0230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19627f0>, '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 0x18ce2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce760>, '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': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', '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 17:50:31 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 17:50:31 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 17:50:31 INFO - Using env: {'DISPLAY': ':0', 17:50:31 INFO - 'HOME': '/home/cltbld', 17:50:31 INFO - 'LANG': 'en_US.UTF-8', 17:50:31 INFO - 'LANGUAGE': 'en_US:en', 17:50:31 INFO - 'LOGNAME': 'cltbld', 17:50:31 INFO - 'MAIL': '/var/mail/cltbld', 17:50:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:31 INFO - 'MOZ_NO_REMOTE': '1', 17:50:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:31 INFO - 'NO_EM_RESTART': '1', 17:50:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:50:31 INFO - 'PWD': '/builds/slave/test-pgo', 17:50:31 INFO - 'SHELL': '/bin/bash', 17:50:31 INFO - 'SHLVL': '1', 17:50:31 INFO - 'TERM': 'linux', 17:50:31 INFO - 'TMOUT': '86400', 17:50:31 INFO - 'USER': 'cltbld', 17:50:31 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', 17:50:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:31 INFO - '_': '/tools/buildbot/bin/python'} 17:50:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:50:32 INFO - Running setup.py (path:/tmp/pip-G3EzHs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 17:50:32 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)) 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:50:32 INFO - Running setup.py (path:/tmp/pip-wBEz5c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 17:50:32 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)) 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:50:32 INFO - Running setup.py (path:/tmp/pip-hMQasL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 17:50:32 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)) 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:50:32 INFO - Running setup.py (path:/tmp/pip-Cq7Xrx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 17:50:32 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)) 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 17:50:32 INFO - Running setup.py (path:/tmp/pip-_8SMyq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 17:50:32 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)) 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:50:32 INFO - Running setup.py (path:/tmp/pip-N93Bbu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 17:50:32 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)) 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:50:32 INFO - Running setup.py (path:/tmp/pip-UozK5g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 17:50:32 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)) 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:50:32 INFO - Running setup.py (path:/tmp/pip-3Uf_FM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 17:50:32 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)) 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 17:50:32 INFO - Running setup.py (path:/tmp/pip-ZI7Zoj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 17:50:32 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)) 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 17:50:32 INFO - Running setup.py (path:/tmp/pip-x3I4wg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 17:50:32 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)) 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:50:32 INFO - Running setup.py (path:/tmp/pip-6x8Xv1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 17:50:32 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)) 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:50:32 INFO - Running setup.py (path:/tmp/pip-JEUhJ1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 17:50:32 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)) 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:50:32 INFO - Running setup.py (path:/tmp/pip-W8i12Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 17:50:32 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)) 17:50:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:50:32 INFO - Running setup.py (path:/tmp/pip-GIgleT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 17:50:33 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)) 17:50:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:50:33 INFO - Running setup.py (path:/tmp/pip-E_2kao-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 17:50:33 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)) 17:50:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 17:50:33 INFO - Running setup.py (path:/tmp/pip-byQLyQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 17:50:33 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)) 17:50:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 17:50:33 INFO - Running setup.py (path:/tmp/pip-uiqrXG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 17:50:33 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)) 17:50:33 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)) 17:50:33 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)) 17:50:33 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)) 17:50:33 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)) 17:50:33 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)) 17:50:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 17:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:35 INFO - Downloading blessings-1.6.tar.gz 17:50:35 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 17:50:35 INFO - Installing collected packages: blessings 17:50:35 INFO - Running setup.py install for blessings 17:50:35 INFO - Successfully installed blessings 17:50:35 INFO - Cleaning up... 17:50:35 INFO - Return code: 0 17:50:35 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 17:50:35 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 17:50:35 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 17:50:35 INFO - Reading from file tmpfile_stdout 17:50:35 INFO - Current package versions: 17:50:35 INFO - argparse == 1.2.1 17:50:35 INFO - blessings == 1.6 17:50:35 INFO - blobuploader == 1.2.4 17:50:35 INFO - docopt == 0.6.1 17:50:35 INFO - manifestparser == 1.1 17:50:35 INFO - mozInstall == 1.12 17:50:35 INFO - mozcrash == 0.16 17:50:35 INFO - mozdebug == 0.1 17:50:35 INFO - mozdevice == 0.48 17:50:35 INFO - mozfile == 1.2 17:50:35 INFO - mozhttpd == 0.7 17:50:35 INFO - mozinfo == 0.9 17:50:35 INFO - mozleak == 0.1 17:50:35 INFO - mozlog == 3.1 17:50:35 INFO - moznetwork == 0.27 17:50:35 INFO - mozprocess == 0.22 17:50:35 INFO - mozprofile == 0.28 17:50:35 INFO - mozrunner == 6.11 17:50:35 INFO - mozscreenshot == 0.1 17:50:35 INFO - mozsystemmonitor == 0.0 17:50:35 INFO - moztest == 0.7 17:50:35 INFO - mozversion == 1.4 17:50:35 INFO - psutil == 3.1.1 17:50:35 INFO - requests == 1.2.3 17:50:35 INFO - wsgiref == 0.1.2 17:50:35 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 17:50: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')]} 17:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50: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')]} 17:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:35 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 0x7f6aad32ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d0230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19627f0>, '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 0x18ce2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce760>, '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': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', '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 17:50:35 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 17:50:35 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 17:50:35 INFO - Using env: {'DISPLAY': ':0', 17:50:35 INFO - 'HOME': '/home/cltbld', 17:50:35 INFO - 'LANG': 'en_US.UTF-8', 17:50:35 INFO - 'LANGUAGE': 'en_US:en', 17:50:35 INFO - 'LOGNAME': 'cltbld', 17:50:35 INFO - 'MAIL': '/var/mail/cltbld', 17:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:35 INFO - 'MOZ_NO_REMOTE': '1', 17:50:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:35 INFO - 'NO_EM_RESTART': '1', 17:50:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:50:35 INFO - 'PWD': '/builds/slave/test-pgo', 17:50:35 INFO - 'SHELL': '/bin/bash', 17:50:35 INFO - 'SHLVL': '1', 17:50:35 INFO - 'TERM': 'linux', 17:50:35 INFO - 'TMOUT': '86400', 17:50:35 INFO - 'USER': 'cltbld', 17:50:35 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', 17:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:35 INFO - '_': '/tools/buildbot/bin/python'} 17:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:35 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)) 17:50:35 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)) 17:50:35 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)) 17:50:35 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)) 17:50:35 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)) 17:50:35 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)) 17:50:35 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)) 17:50:35 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)) 17:50:35 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)) 17:50:35 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)) 17:50:35 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)) 17:50:35 INFO - Cleaning up... 17:50:35 INFO - Return code: 0 17:50:35 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 17:50: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')]} 17:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50: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')]} 17:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:35 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 0x7f6aad32ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d0230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19627f0>, '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 0x18ce2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce760>, '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': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', '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 17:50:35 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 17:50:35 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 17:50:35 INFO - Using env: {'DISPLAY': ':0', 17:50:35 INFO - 'HOME': '/home/cltbld', 17:50:35 INFO - 'LANG': 'en_US.UTF-8', 17:50:35 INFO - 'LANGUAGE': 'en_US:en', 17:50:35 INFO - 'LOGNAME': 'cltbld', 17:50:35 INFO - 'MAIL': '/var/mail/cltbld', 17:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:35 INFO - 'MOZ_NO_REMOTE': '1', 17:50:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:35 INFO - 'NO_EM_RESTART': '1', 17:50:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:50:35 INFO - 'PWD': '/builds/slave/test-pgo', 17:50:35 INFO - 'SHELL': '/bin/bash', 17:50:35 INFO - 'SHLVL': '1', 17:50:35 INFO - 'TERM': 'linux', 17:50:35 INFO - 'TMOUT': '86400', 17:50:35 INFO - 'USER': 'cltbld', 17:50:35 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', 17:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:35 INFO - '_': '/tools/buildbot/bin/python'} 17:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:35 INFO - Downloading/unpacking jsonschema 17:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:50:37 INFO - Downloading/unpacking functools32 (from jsonschema) 17:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:37 INFO - Downloading functools32-3.2.3-2.tar.gz 17:50:37 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 17:50:38 INFO - warning: no files found matching '*.txt' 17:50:38 INFO - no previously-included directories found matching 'build' 17:50:38 INFO - no previously-included directories found matching 'dist' 17:50:38 INFO - no previously-included directories found matching '.git*' 17:50:38 INFO - Installing collected packages: jsonschema, functools32 17:50:38 INFO - Running setup.py install for functools32 17:50:38 INFO - warning: no files found matching '*.txt' 17:50:38 INFO - no previously-included directories found matching 'build' 17:50:38 INFO - no previously-included directories found matching 'dist' 17:50:38 INFO - no previously-included directories found matching '.git*' 17:50:38 INFO - Successfully installed jsonschema functools32 17:50:38 INFO - Cleaning up... 17:50:38 INFO - Return code: 0 17:50:38 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 17:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:50:38 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 0x7f6aad32ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d0230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19627f0>, '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 0x18ce2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce760>, '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': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', '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 17:50:38 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 17:50:38 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 17:50:38 INFO - Using env: {'DISPLAY': ':0', 17:50:38 INFO - 'HOME': '/home/cltbld', 17:50:38 INFO - 'LANG': 'en_US.UTF-8', 17:50:38 INFO - 'LANGUAGE': 'en_US:en', 17:50:38 INFO - 'LOGNAME': 'cltbld', 17:50:38 INFO - 'MAIL': '/var/mail/cltbld', 17:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:38 INFO - 'MOZ_NO_REMOTE': '1', 17:50:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:38 INFO - 'NO_EM_RESTART': '1', 17:50:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:50:38 INFO - 'PWD': '/builds/slave/test-pgo', 17:50:38 INFO - 'SHELL': '/bin/bash', 17:50:38 INFO - 'SHLVL': '1', 17:50:38 INFO - 'TERM': 'linux', 17:50:38 INFO - 'TMOUT': '86400', 17:50:38 INFO - 'USER': 'cltbld', 17:50:38 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', 17:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:38 INFO - '_': '/tools/buildbot/bin/python'} 17:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:50:38 INFO - Downloading/unpacking flake8 17:50:38 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 17:50:38 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 17:50:38 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 17:50:38 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 17:50:38 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 17:50:38 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 17:50:40 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:50:40 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:50:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:50:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:50:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 17:50:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:50:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:50:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:50:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:50:40 INFO - no previously-included directories found matching 'docs/_build' 17:50:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:50:41 INFO - Running setup.py install for pep8 17:50:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:50:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:50:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:50:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:50:41 INFO - no previously-included directories found matching 'docs/_build' 17:50:41 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 17:50:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:50:41 INFO - Cleaning up... 17:50:41 INFO - Return code: 0 17:50:41 INFO - Running post-action listener: _resource_record_post_action 17:50:41 INFO - Running post-action listener: _start_resource_monitoring 17:50:41 INFO - Starting resource monitoring. 17:50:41 INFO - ##### 17:50:41 INFO - ##### Running install step. 17:50:41 INFO - ##### 17:50:41 INFO - Running pre-action listener: _resource_record_pre_action 17:50:41 INFO - Running main action method: install 17:50:41 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 17:50:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 17:50:41 INFO - Reading from file tmpfile_stdout 17:50:41 INFO - Detecting whether we're running mozinstall >=1.0... 17:50:41 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 17:50:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 17:50:41 INFO - Reading from file tmpfile_stdout 17:50:41 INFO - Output received: 17:50:41 INFO - Usage: mozinstall [options] installer 17:50:41 INFO - Options: 17:50:41 INFO - -h, --help show this help message and exit 17:50:41 INFO - -d DEST, --destination=DEST 17:50:41 INFO - Directory to install application into. [default: 17:50:41 INFO - "/builds/slave/test-pgo"] 17:50:41 INFO - --app=APP Application being installed. [default: firefox] 17:50:41 INFO - mkdir: /builds/slave/test-pgo/build/application 17:50:41 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'] 17:50:41 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 17:50:56 INFO - Reading from file tmpfile_stdout 17:50:56 INFO - Output received: 17:50:56 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 17:50:56 INFO - Running post-action listener: _resource_record_post_action 17:50:56 INFO - ##### 17:50:56 INFO - ##### Running run-tests step. 17:50:56 INFO - ##### 17:50:56 INFO - Running pre-action listener: _resource_record_pre_action 17:50:56 INFO - Running main action method: run_tests 17:50:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 17:50:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 17:50:56 INFO - Python 2.7.3 17:50:56 INFO - Return code: 0 17:50:56 INFO - grabbing minidump binary from tooltool 17:50:56 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')]} 17:50:56 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 0x19627f0>, '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 0x18ce2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18ce760>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 17:50:56 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 17:50:56 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 17:50:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:50:56 INFO - Return code: 0 17:50:56 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 17:50:56 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 17:50:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 17:50:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 17:50:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 17:50:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 17:50:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 17:50:57 INFO - Return code: 0 17:50:57 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/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 17:50:57 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/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-042 --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 17:50:57 INFO - Using env: {'DISPLAY': ':0', 17:50:57 INFO - 'HOME': '/home/cltbld', 17:50:57 INFO - 'LANG': 'en_US.UTF-8', 17:50:57 INFO - 'LANGUAGE': 'en_US:en', 17:50:57 INFO - 'LOGNAME': 'cltbld', 17:50:57 INFO - 'MAIL': '/var/mail/cltbld', 17:50:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 17:50:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 17:50:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:50:57 INFO - 'MOZ_NO_REMOTE': '1', 17:50:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 17:50:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:50:57 INFO - 'NO_EM_RESTART': '1', 17:50:57 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:50:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 17:50:57 INFO - 'PWD': '/builds/slave/test-pgo', 17:50:57 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 17:50:57 INFO - 'SHELL': '/bin/bash', 17:50:57 INFO - 'SHLVL': '1', 17:50:57 INFO - 'TERM': 'linux', 17:50:57 INFO - 'TMOUT': '86400', 17:50:57 INFO - 'USER': 'cltbld', 17:50:57 INFO - 'XDG_SESSION_COOKIE': '74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290', 17:50:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:50:57 INFO - '_': '/tools/buildbot/bin/python'} 17:50:57 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/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-042', '--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 17:50:57 INFO - mozversion application_buildid: 20160302124722 17:50:57 INFO - mozversion application_changeset: 3a34e8b89130c938dd12596e3c6274d5fb994825 17:50:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:50:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:50:57 INFO - mozversion application_name: Firefox 17:50:57 INFO - mozversion application_remotingname: firefox-dev 17:50:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:50:57 INFO - mozversion application_vendor: Mozilla 17:50:57 INFO - mozversion application_version: 46.0a2 17:50:57 INFO - mozversion platform_buildid: 20160302124722 17:50:57 INFO - mozversion platform_changeset: 3a34e8b89130c938dd12596e3c6274d5fb994825 17:50:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:50:57 INFO - mozversion platform_version: 46.0a2 17:50:57 INFO - using testdate: 1456969857 17:50:57 INFO - actual date: 1456969857 17:50:57 INFO - starting webserver on 'localhost:57969' 17:50:57 INFO - SUITE-START | Running 3 tests 17:50:57 INFO - TEST-START | dromaeo_css 17:50:57 INFO - operating with platform_type : linux_ 17:50:57 INFO - Initialising browser for dromaeo_css test... 17:50:57 INFO - TEST-INFO | started process 7379 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgI8Qyr/profile http://localhost:57969/getInfo.html) 17:51:01 INFO - PROCESS | 7379 | 17:51:01 INFO - PROCESS | 7379 | (process:7456): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:51:02 INFO - PROCESS | 7379 | __metrics Screen width/height:1600/1200 17:51:02 INFO - PROCESS | 7379 | colorDepth:24 17:51:02 INFO - PROCESS | 7379 | Browser inner width/height: 1024/697 17:51:02 INFO - PROCESS | 7379 | __metrics 17:51:03 INFO - TEST-INFO | 7379: exit 0 17:51:03 INFO - Browser initialized. 17:51:03 INFO - Running cycle 1/1 for dromaeo_css test... 17:51:03 INFO - TEST-INFO | started process 7505 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpgI8Qyr/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:51:04 INFO - PROCESS | 7505 | 17:51:04 INFO - PROCESS | 7505 | (process:7567): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:51:15 INFO - PROCESS | 7505 | RSS: Main: 140935168 17:51:15 INFO - PROCESS | 7505 | 17:52:04 INFO - PROCESS | 7505 | 1456969924696 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] 17:54:12 INFO - PROCESS | 7505 | Cycle 1(1): loaded http://localhost:57969/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57969/tests/dromaeo/cssquery-ext.html) 17:54:13 INFO - PROCESS | 7505 | RSS: Main: 146685952 17:54:13 INFO - PROCESS | 7505 | 17:57:15 INFO - PROCESS | 7505 | Cycle 1(1): loaded http://localhost:57969/tests/dromaeo/cssquery-ext.html (next: http://localhost:57969/tests/dromaeo/cssquery-jquery.html) 17:57:15 INFO - PROCESS | 7505 | RSS: Main: 145608704 17:57:15 INFO - PROCESS | 7505 | 18:00:17 INFO - PROCESS | 7505 | Cycle 1(1): loaded http://localhost:57969/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57969/tests/dromaeo/cssquery-mootools.html) 18:00:18 INFO - PROCESS | 7505 | RSS: Main: 144678912 18:00:18 INFO - PROCESS | 7505 | 18:03:19 INFO - PROCESS | 7505 | Cycle 1(1): loaded http://localhost:57969/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57969/tests/dromaeo/cssquery-prototype.html) 18:03:20 INFO - PROCESS | 7505 | RSS: Main: 143937536 18:03:20 INFO - PROCESS | 7505 | 18:06:22 INFO - PROCESS | 7505 | Cycle 1(1): loaded http://localhost:57969/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57969/tests/dromaeo/cssquery-yui.html) 18:06:23 INFO - PROCESS | 7505 | RSS: Main: 144904192 18:06:23 INFO - PROCESS | 7505 | 18:09:25 INFO - PROCESS | 7505 | Cycle 1(1): loaded http://localhost:57969/tests/dromaeo/cssquery-yui.html (next: http://localhost:57969/tests/dromaeo/cssquery-dojo.html) 18:09:25 INFO - PROCESS | 7505 | RSS: Main: 146792448 18:09:25 INFO - PROCESS | 7505 | 18:09:25 INFO - PROCESS | 7505 | __start_tp_report 18:09:25 INFO - PROCESS | 7505 | _x_x_mozilla_page_load 18:09:25 INFO - PROCESS | 7505 | _x_x_mozilla_page_load_details 18:09:25 INFO - PROCESS | 7505 | |i|pagename|runs| 18:09:25 INFO - PROCESS | 7505 | |0;dojo.html;3289.3750187464366;3351.4637657974727;3377.9831100844494;3406.812625305608;3414.1293970037636;5456.945430545693;5462.644928079677;5466.671999680022;5477.04151773439;5501.779928802853;5468.9726551367185;5499.202615620733;5504.64219825712;5506.476884695948;5524.281843360358;4938.48145944676;4988.152014157602;5049.924251136217;5090.465501122384;5143.15138002231;4867.464578896319;4984.37695288089;5006.349174607296;5007.899842003158;5092.898142037157;5018.021485810268;5041.647084704072;5067.619928505358;5086.720230387327;5097.668651537657;5019.673721208128;5023.648344615878;5038.722870242135;5047.570956468668;5055.46917573655;4827.927581086286;4888.560029597354;4931.901361972777;4953.653244272902;4976.527229913152;5244.475552444763;5297.602679799031;5306.336707911511;5308.6549374290935;5321.840344789663;5911.763529458816;5933.881322373529;5934.673592952386;5936.614120082181;5947.316058653267;5805.448482394166;5881.294244690622;5917.615355001911;5963.671998040106;5975.312839023481;5606.971965140189;5609.663420194761;5611.438856114438;5615.0454422747825;5627.887442251173;5971.462568368781;6002.159697642333;6044;6046.244219472566;6046.455818976313;5686.459786320375;5741.598088133874;5833;5837.416258374214;5938.762449502058;5922.911156332659;5950.940490595125;5951.940480595139;5956.285245770535;5974.701264936736;5876.588638795243;5887.499562537232;5902.1146827976145;5907.438793314628;5911.674857882857;5779.219805326311;5789.073748200268;5902.1146827976145;5931.377205393458;6000.7899723510345;4478.193925093515;4498.415206023196;4506.571875671806;4511.661625378109;4534.047849951481;4008.7594744315434;4055.8174882130374;4058.7158898876796;4075.735077219971;4099.364598487239;5419.810306639249;5517.889642207214;5531.976584331912;5632.690202038845;5688.288963879515;5977.342492325841;5979.222701048836;5988.371221021817;5989.760409583568;5996.670183139882;5416.91874621881;5471.945280547144;5615.157726341082;5619.662820230799;5639.351474580394;5056.646034777604;5087.898242035212;5092.414372347153;5136.383633963948;5159.045576568347;5938.317093534212;6000.249968753906;6007.639541627516;6033.939660603425;6038.275406951194;5782.219400381063;5859.414058594107;5893.528517718487;5915.792947246911;5924.022536281465;6019.668918209541;6103.176071230505;6109.2974307955155;6119.388061193845;6123.7550497981365;5784.50831679312;5873.911891321548;5885.47030767233;5903.557233207441;5921.703914804329;5764.164196191613;5831.912521312099;5915.881682366243;5951.315598706205;5970.5223582114195;5697.743601537858;5699.344575373885;5701.914471282851;5731.684757338219;5737.540996720336;5651.773929042793;5664.688442135722;5667.631603945731;5697.886042279214;5740.655560666373;5628.774849005994;5686.459786320293;5693.060644993529;5739.62692424991;5770.076787713946;5642.520385767091;5705.714714264187;5725.312962444366;5732.799352022659;5781.479666830005;5523.364813046551;5586.692731899784;5645.576581756302;5646.153077038477;5670.432956704297 18:09:25 INFO - PROCESS | 7505 | |1;ext.html;554.603458527153;569.45901393676;583.0147051482993;592.0881841963377;596.5853731656499;31815.363692726132;32667;38841.417378739345;39255.60744392555;39329.803350983275;42686.35970460438;42692.57307426925;43235;43425;43438.56561434393;8659.350548708857;8771.736847894541;8783.253423459013;8812.911870881273;8861.867071993925;8624.266937310336;8732.825343493127;8737.432066915662;8788.47269163849;8792.34057445691;8799.648014079428;8890.511021893793;9091.54542272887;9097.818043639123;9112.407693499901;8930.687425940094;8978.595963181673;8980.595873185723;8997.640094396216;9017;8845.911540884574;8977.910220897773;8983.056779038172;9015.098490150998;9018.639254429814;42573.57426425727;43009.78495107536;43059;43077.35383969243;43121.56878431207;3630.8547658093644;3667.2665466906724;3700.888973330804;3704.685101766353;4160.625543701051;27643.308917277027;28133.15600531967;28381.432371352457;31020.224494387596;32050.198745031328;4249.893752656209;4255.97872010638;4285.764282964436;4301.182775272689;4358.891027724332;301445;306555;306708;308837;308902;60172.69913650404;61686.38313616896;66447.66776166088;68183.65908170526;68269.65865170643;61695.383046168965;61776.69111654413;61994.38005619976;62143.37856621466;62473;17211.225494852762;17373.565660858327;17398.39105631297;17459.47621571355;17530.386436474662;13353.132046416951;13503.054786164874;13574.117682350616;13636.522721704692;13686.384112714952;21248.681269780966;21880.23419180321;21893.890530547244;21931.561368772534;21990.780092199195;8348.415610907177;8494.787630309169;8661.22049015579;8672.26285765717;8791.07693692166;1030.3920686794827;1056.989430105689;1129.971750706239;1172.6247600767842;1288.7873500872251;29787.40425191484;30153.547696784564;30722.23194420113;30827.845860770554;30883.0735077948;1160.7272291011604;1256.7172386213213;1260.6659235302652;1276.1258537901567;1278.2905487454552;16005;16055.839441605434;16159.676806463805;16166.75749863753;16207.865449418663;434481;552753;556256;560058;574780;55499;56393.718031410404;56430.7178464105;56493;57028;1449.6955639315652;1470.691154857492;1471.3011319623092;1479.5857159995219;1492.8208614966056;31133.221669458446;32441.67558324481;32536.02391928246;32901.83549082239;33570.832145839115;50027.499725002286;50607.240891385925;50676.23985640292;50789.238161428344;50860.491395087054;254324;255321;256515;256811;257283;42979.785101074296;43154.35268470913;45462.09075818531;45515.772421137684;45819.77090114528;42400.15199695927;42627.3605895918;42938.78530607327;43470.565294347914;43766.78116609396;36099;37203;37469.625303746616;41796.79101604473;43373.61410225641;40405.1918961625;40600;40774;41280;41379.1724165509;40185.79907100446;40999.59000409958;41160;41171.38242926419;41392;34951.82524087363;35317;35497;35502.8224858874;35653.465198021535;27048.864755676095;27520.862395687895;27629.723702762716;27904.720952790212;27955.72044279531 18:09:25 INFO - PROCESS | 7505 | |2;jquery.html;391.7434080677157;392.6740805131741;393.13902553408036;393.94681717968064;394;15576.376944922218;15970.600734981603;17208.82791172088;20087.59824803505;20274.39176824696;11981.281123132618;12225.266484010966;13296.800547991788;17679.469615911545;17680.911595442038;4721.740304283263;5199.14214154664;5374.973125134388;5797.159411885295;5802.796902108406;5317.7607007684555;5515.806946756864;5519.779208831642;5523.1163013917785;5649.350324712649;5515.61390702649;5529.225908372831;6277.560570760049;6600.537962342638;6610.107635469222;6408.455281301071;6429.099926010363;6708.597484150967;6977.965110174468;6979.40675042622;6517.771877984248;6660.333966603349;6673.4661227101715;6680.532362734611;6680.933190668128;21340;21611.351659450243;22449.21427750021;22691.77308226913;22730.545389092127;2404.2186289456;2469.7530246975157;2504.18613950465;2506.711728151268;2603.4923189977962;7060.505764596429;7099.751508697223;12451.377431128498;14311.284435778169;14645.487407940778;2097.1716172112083;2099.7795231500777;2116.2381542644634;2149.344449942767;2161.53526991696;67391.66304168497;69360;69769;70732;70800;15707.214639267992;16361.672766544603;16418.753718694228;16422.507324780276;16432;25448.236552903443;25538;25549.74450255511;25625.871870640527;25795.355116122246;7902.723404680809;8187.959060204661;8466.915330846612;8478.236958673626;8488.787780305542;8086.1913808618665;8091.757247282591;8102.797430064177;8119.269265766109;8122.675092996332;4562.657800664963;4730.550597693214;4731.526847315241;4836.347093142451;4913.656044076952;1993.531520092777;2011.336259034515;2027.3107143571256;2037.3582321568658;2047.3550831488033;931.1573026411108;978.192990782593;1002.2483137646765;1026.384169498295;1091.3397394576325;16981.405650802164;17184.656306873792;17222.483325500256;17383.956962582284;17976.460706178834;1005.4620777883797;1006.9798604028025;1021.1218352217087;1023.7031260934394;1052.0478966535297;9789.070038346346;9806.460644664468;10162.237832162617;10205.591776328863;10444.268901176996;557214;566809;578002;580103;599902;75222.62388688022;77728.22271777209;79449;79756;81099.59450202712;1202.8496437945257;1224.136983426682;1226.466487078124;1227.061298106949;1238.5603110895684;16039.93608638285;16153.75769363437;16172.676546468769;16190.433334833224;16291.511254662379;34008.829955850066;34775.65224347724;35069;35296.11759705925;35513;43650.34524482199;45837.31244031409;46219.3067103987;46250.07499850052;46458.53541464677;7462.962685186756;7595.506292090997;7645.159032506531;7677.155512893689;7738.7678369649;10553.155747540024;10658.786824263627;10984.945075274572;11079.72300692489;11204.26333918244;9466.95266523663;9647.864444055851;9961.950190249003;10149.441780701836;10391.53238104272;7698.345640620386;10291.33106348085;10297.897021029694;10345.74135646615;10367.896321036995;7722.652480638497;7807.375409967075;7945.920540794518;7998.920010799818;8211.466254693425;10317.123044541298;10523.894761052597;10756.515956781806;10768.19238557127;10806.621768238389;10539.63111291101;10778.838317425088;10828.458577070958;11045.116390688887;11064.723381915517 18:09:25 INFO - PROCESS | 7505 | |3;mootools.html;1541.560655213264;1570.1835045776197;1790.1765188013512;1882.5293676580854;1901.0494752623697;7528.84942301155;7638.885416718754;8210.507369557823;8433.367497437694;8445.746627601175;7951.085625153095;8046.316063134641;8062.234087761653;8110.959445202766;8116.75649730509;2467.9629805552977;2487.266256454341;2497.1010436242937;2497.1634502441702;2499.6250562415603;2424.2969538833718;2441.5849305618;2442.133042769809;2444.706635203769;2451.9754802451926;2431.416460049593;2452.754724527542;2452.889619967106;2462.5813611685967;2479.442125521761;2422.8061755059553;2437.5612389769835;2449.865257410842;2454.202384225125;2457.2505385857307;2396.209250947183;2411.4574220800355;2414.8913298901593;2417.9395515112087;2483.3295010347106;8263.669453221864;8345.707900223524;8380.20388063139;8395.95802020992;8419.579021048923;5167.888903885682;5226.947730522684;5235.921461178085;5243.8164664236565;5300.469953004668;3168.5088811234295;3222.7905186162825;3243.2216268095485;3372.629010808809;3387.0854869185428;1062.7555662197738;1075.0485820049234;1075.8063548561256;1079.632924805562;1100.7468282295038;11601;11771.64685059441;11779.116566257566;11834.112441566916;11853.703657408547;4775.283707443842;4783.210770222874;4797.736124513185;4806.759662016955;4873.80504779812;9987.500624968869;10016.248781341428;10125.088742013255;10127.7468063299;10134.594616215416;5191.299174611455;5226.111561034633;5264.499872512105;5280.709560974106;5300.9734951325;4652.278896771006;4725;4740.360051393087;4743.075100355467;4749.4063242094735;3888.358420860583;3912.1002169501176;3928.8821335359985;3941.664958478504;3948.9012774680286;1184.2775906696909;1185.3184418959133;1195.713028873064;1203.1638011581867;1210.4855436439655;1374.5807528703842;1374.8144000559796;1387.5837248825312;1391.304347826087;1401.222321611495;7701.114371847198;7841.882371764428;7888.802779930547;7890.842183156419;7902.920970790334;549.2612436273206;552.9308836395451;563.4759673503681;563.5547917145491;563.8505795964073;5780.653160810365;5782.074868021096;5827;5830.854228644318;5845.064789633723;9551.570179341947;9790.706278811647;9812.95093524542;9870.703878883645;9952.651657192107;9500.572474238676;9621;9682.56428460721;9712.417254964725;9776.902230977741;7919.247671471201;7927.762167134995;7953.6023198840985;7985.8802117969435;8023.83952320962;7505.774826755206;7514.586697731677;7547.622618869144;7604.961975190088;7847.2152784721075;9002.684906028258;9298.535073246447;9306.72079837606;9316.95341523288;9340.486273255035;9432.62269509212;9435.905640943503;9548.283878708986;9639.132478077006;9778.902210978084;6958.686859091252;7117.893231601426;7154.821129471805;7529.1717911028645;7698.883661869109;7619.9238007619215;7646.388288936761;7687.577183254974;7696.345810606162;7707.92292077072;7088.822779430556;7317.341439270491;7351.522151060164;7356.154042285206;7533.58565278893;7100.467464940253;7165.8925116122255;7169.892451613126;7281.089863767029;7303.050603421521;7068.3285087916565;7070.929290707027;7114.715411383694;7129.287071292829;7129.893051604334;6745.797626071029;6776.627285499345;6779.8983015253825;6788.93211067883;6809.2509823918335;6832.180138383425;6956.513044086865;6962.895556566555;6963.721451141898;6997.370236678723 18:09:25 INFO - PROCESS | 7505 | |4;prototype.html;354.29495304344357;366.3369301564168;371.9126005388735;375.06608544723645;375.9116607597216;6211.844703882405;6341.080543321215;6367.395097465745;6440.645764482952;6440.742370305182;6139.570230083896;6258.092576576394;6264.060390941361;6296.212973378328;6296.842578935529;4839.830605928793;4861.489543597924;4885.193942999401;4885.267209918505;4888.315635810972;4863.732494712789;4893.119238537062;4893.779779909894;4896.412430508326;4902.534259245366;4743.739094349809;4863;4875.756212189394;4877.804887804484;4878.097551952882;4838.250071238964;4857.392825896763;4857.757112144396;4866.197077482211;4868.488808675092;4284.914301713949;4297.935530967006;4300.505441874162;4302.784860756981;4311.439512863339;5921.703914804242;5961.463468287875;5980.790672326491;6004.819855404345;6020.307664618581;5733.9678857805575;5734.913976290359;5743.597948143632;5756.107803290497;5757.280339957505;6693.899591506131;6702.564333318367;6703.095082163894;6710.825605519063;6733.393994540465;4806.134895718769;4828.106800241966;4837.1776797944085;4845.4185497740145;4845.951540484585;6938;7006.649667516604;7058.7647161747;7079.575225486487;7080.610566418845;4925.605951523869;4966.354373931365;4975.800967961241;4981.452040275567;4982.252662100642;5046.142155833517;5047.97476012625;5049.545540901337;5054.646174767731;5065.696058236517;5660.745266463019;5755.884882302331;5756;5760.452756988079;5765.221695071195;6748.966255168692;6813.7955861324235;6828.317168283232;6831.829204269933;6842.452603791685;1726.5510967148632;1784.9375271865422;1786.3211979447726;1786.490850107713;1791.426743442086;1207.4144040140525;1214.319980810731;1218.670958841108;1219.8231256467761;1239.6652903716126;6327.367263273636;6364.968175159094;6378.394052564999;6400.935990640034;6402.327755585597;6904.965475172592;6905.447564194852;6915.827104322432;6923.376896079276;6931.618760968196;6260.342664020303;6382.393672601095;6390.169277993924;6392.744290228339;6398.616083035033;3714.331420344364;3718.665320121148;3729.4778730977687;3743.082944678571;3755.6432138946757;7075.787726368218;7121.359077683033;7127.394171495377;7150.034745309421;7151.392131668762;6929.826754331081;7106.786796396116;7120.465965052642;7127.714891404389;7137.464690148259;7062.92937070633;7089.3265139812665;7114.217436082027;7119.21688614252;7123.679434425463;6832.487563432862;6853.348931851466;6854.485913556404;6907.654617269217;6907.758228461979;6694.698738556679;6821.965890170517;6833.658317084027;6836.897446538405;6843.863122737418;7043.823904402431;7062.329078737512;7104.644767761694;7104.715811367694;7119.394851437479;4825.855224343275;4877.6585639004925;4881.633877459134;4886.975565122151;4887;6822.965885170543;6832.59004459734;6928.584284942718;6991.615461149686;6997.860042799217;6806.115205023315;6806.9319306808275;6838.692258848463;6846.178458585002;6846.246912839683;6763.256041835492;6797.4901882358035;6816.113905192294;6842.144731908512;6843.965780171067;6592.80221593353;6718.462522998051;6726.428253598499;6738.66306684654;6756.831079223059;6877.65611719422;6882.518223724492;6887.276835932155;6896.689648965708;6898.655067246718;6476.838079048061;6630;6700.66496675174;6700.899486507804;6722.865542689216 18:09:25 INFO - PROCESS | 7505 | |5;yui.html;830.8089139497915;847.2882778466087;854.8418542569625;862.4911302331623;870.8650159225318;13769.655758606028;13832.446702131927;13859.029867909263;13875.30623468825;13993.790093148598;13460.596182114552;13482.730345393085;13508.121972071809;13528.25594592297;13539.796903046437;863.3956230638547;904.3488688144525;909.2862103248918;909.4043401571948;914.5930061122802;900.8783814185099;903.7966457547066;904.2901322461869;906.3519583497798;907.918287354138;943.5848226780229;945.1635302757076;945.3666043750703;950.1638558068889;951.6288647427509;935.7520257131865;936.536414474836;941.3269512298705;944.2163004706111;944.4239014201331;697.5919087333916;700.3066963705894;700.5866538742166;702.0627462337785;702.6486756621689;14576.344064517121;14583.489577864724;14682.486112986102;14698.853011469855;14716.55850324492;13423.597292081253;13445.32773361328;13445.932770336085;13510.932445337809;13790.586282411543;10205.285630005903;11994.969500118787;12017.218880772809;12344.5062197512;12599.748005039848;3801.6958643308744;3825.3114439400897;3835.7506762060375;3849.0377405648587;3854.9036274093373;50665.49334506645;50892;50967;51044;51162.74418627883;10706.25056246071;10717.410542420086;10875.238733288594;11066.687831107805;11202.383868887126;18826.870387776777;18889.149988250563;18929.621407571773;18967.336143235196;18984.52538686544;8046.114927357986;8231.629576669064;8242.793930151794;8257.174282571816;8322.750317490485;4394;4491.88770280739;4534.342520334599;4562.338460923214;4595.770211489412;3208.069659798679;3214.2125179331215;3220.500822372536;3241.2383089973837;3243.7242834359345;598.455405580928;599.7570983751588;602.135934933372;615.9507239420835;618.0482057631287;647;662.7978466567744;664.833791552112;678.1624693503519;699.0946723992442;8915.42049766763;9028.368014239071;9099.863502047474;9137.086291370942;9147.131022552847;571.9714014299352;620.9130721698966;651.4267444648774;658.7562601837351;703.9824004399828;4364.8908777280185;4398.318260669537;4486.7083639563325;4529;4560.657950653648;58351.41648583545;58516.7074164635;62176;62439.6878015607;63623;49519.75240123848;50177.49822501728;50272;50542.24186637203;51217.23174152391;48102.75948620235;49244.75377623089;49808;49889.501104988485;49902;9394.15452609241;9448.244140468609;9517.191038761506;9665.081817227352;9669.371490853353;35215.82392088023;35417.64582354143;35812.28375432425;35990.99999999895;36211.45682814707;43008.56991430045;46552.7672361636;46826.53173468222;46998.76500617475;47793;11266.887331126583;11535.769284614093;11771.23486973344;11908.880911190778;11984.161108722654;13426.932865335611;13440.731185376433;13669.931650341685;13790.555317002978;13839.654008649864;11953.880461195624;12574.937125314314;12842;13127.868721312665;13303.534376296782;12153.635390938285;12237.938810305892;12283.815742764044;12353.505859765508;12632.621021369374;11741.706457338634;11786.292822430682;11997.040236780862;12024.398780061138;12485.812712809133;10594.735131621772;10808.243422960317;10813.297135686154;10830.945845270724;11021.889781102087;10433.478326083818;10612.946935265274;10884.346939183675;11444.42777861087;11455.713607159887 18:09:25 INFO - PROCESS | 7505 | __end_tp_report 18:09:25 INFO - PROCESS | 7505 | __start_cc_report 18:09:25 INFO - PROCESS | 7505 | _x_x_mozilla_cycle_collect,297 18:09:25 INFO - PROCESS | 7505 | __end_cc_report 18:09:25 INFO - PROCESS | 7505 | __startTimestamp1456970965737__endTimestamp 18:09:25 INFO - PROCESS | 7505 | 18:09:25 INFO - PROCESS | 7505 | ------- Summary: start ------- 18:09:25 INFO - PROCESS | 7505 | Number of tests: 6 18:09:25 INFO - PROCESS | 7505 | 18:09:25 INFO - PROCESS | 7505 | [#0] dojo.html Cycles:165 Average:5473.87 Median:5667.63 stddev:599.31 (10.6%) stddev-sans-first:576.12 18:09:25 INFO - PROCESS | 7505 | Values: 3289.4 3351.5 3378.0 3406.8 3414.1 5456.9 5462.6 5466.7 5477.0 5501.8 5469.0 5499.2 5504.6 5506.5 5524.3 4938.5 4988.2 5049.9 5090.5 5143.2 4867.5 4984.4 5006.3 5007.9 5092.9 5018.0 5041.6 5067.6 5086.7 5097.7 5019.7 5023.6 5038.7 5047.6 5055.5 4827.9 4888.6 4931.9 4953.7 4976.5 5244.5 5297.6 5306.3 5308.7 5321.8 5911.8 5933.9 5934.7 5936.6 5947.3 5805.4 5881.3 5917.6 5963.7 5975.3 5607.0 5609.7 5611.4 5615.0 5627.9 5971.5 6002.2 6044.0 6046.2 6046.5 5686.5 5741.6 5833.0 5837.4 5938.8 5922.9 5950.9 5951.9 5956.3 5974.7 5876.6 5887.5 5902.1 5907.4 5911.7 5779.2 5789.1 5902.1 5931.4 6000.8 4478.2 4498.4 4506.6 4511.7 4534.0 4008.8 4055.8 4058.7 4075.7 4099.4 5419.8 5517.9 5532.0 5632.7 5688.3 5977.3 5979.2 5988.4 5989.8 5996.7 5416.9 5471.9 5615.2 5619.7 5639.4 5056.6 5087.9 5092.4 5136.4 5159.0 5938.3 6000.2 6007.6 6033.9 6038.3 5782.2 5859.4 5893.5 5915.8 5924.0 6019.7 6103.2 6109.3 6119.4 6123.8 5784.5 5873.9 5885.5 5903.6 5921.7 5764.2 5831.9 5915.9 5951.3 5970.5 5697.7 5699.3 5701.9 5731.7 5737.5 5651.8 5664.7 5667.6 5697.9 5740.7 5628.8 5686.5 5693.1 5739.6 5770.1 5642.5 5705.7 5725.3 5732.8 5781.5 5523.4 5586.7 5645.6 5646.2 5670.4 18:09:25 INFO - PROCESS | 7505 | 18:09:25 INFO - PROCESS | 7505 | [#1] ext.html Cycles:180 Average:54252.89 Median:41385.59 stddev:102658.57 (248.1%) stddev-sans-first:102867.38 18:09:25 INFO - PROCESS | 7505 | Values: 554.6 569.5 583.0 592.1 596.6 31815.4 32667.0 38841.4 39255.6 39329.8 42686.4 42692.6 43235.0 43425.0 43438.6 8659.4 8771.7 8783.3 8812.9 8861.9 8624.3 8732.8 8737.4 8788.5 8792.3 8799.6 8890.5 9091.5 9097.8 9112.4 8930.7 8978.6 8980.6 8997.6 9017.0 8845.9 8977.9 8983.1 9015.1 9018.6 42573.6 43009.8 43059.0 43077.4 43121.6 3630.9 3667.3 3700.9 3704.7 4160.6 27643.3 28133.2 28381.4 31020.2 32050.2 4249.9 4256.0 4285.8 4301.2 4358.9 301445.0 306555.0 306708.0 308837.0 308902.0 60172.7 61686.4 66447.7 68183.7 68269.7 61695.4 61776.7 61994.4 62143.4 62473.0 17211.2 17373.6 17398.4 17459.5 17530.4 13353.1 13503.1 13574.1 13636.5 13686.4 21248.7 21880.2 21893.9 21931.6 21990.8 8348.4 8494.8 8661.2 8672.3 8791.1 1030.4 1057.0 1130.0 1172.6 1288.8 29787.4 30153.5 30722.2 30827.8 30883.1 1160.7 1256.7 1260.7 1276.1 1278.3 16005.0 16055.8 16159.7 16166.8 16207.9 434481.0 552753.0 556256.0 560058.0 574780.0 55499.0 56393.7 56430.7 56493.0 57028.0 1449.7 1470.7 1471.3 1479.6 1492.8 31133.2 32441.7 32536.0 32901.8 33570.8 50027.5 50607.2 50676.2 50789.2 50860.5 254324.0 255321.0 256515.0 256811.0 257283.0 42979.8 43154.4 45462.1 45515.8 45819.8 42400.2 42627.4 42938.8 43470.6 43766.8 36099.0 37203.0 37469.6 41796.8 43373.6 40405.2 40600.0 40774.0 41280.0 41379.2 40185.8 40999.6 41160.0 41171.4 41392.0 34951.8 35317.0 35497.0 35502.8 35653.5 27048.9 27520.9 27629.7 27904.7 27955.7 18:09:25 INFO - PROCESS | 7505 | 18:09:25 INFO - PROCESS | 7505 | [#2] jquery.html Cycles:180 Average:30163.19 Median:34392.24 stddev:94221.55 (274.0%) stddev-sans-first:94459.35 18:09:25 INFO - PROCESS | 7505 | Values: 391.7 392.7 393.1 393.9 394.0 15576.4 15970.6 17208.8 20087.6 20274.4 11981.3 12225.3 13296.8 17679.5 17680.9 4721.7 5199.1 5375.0 5797.2 5802.8 5317.8 5515.8 5519.8 5523.1 5649.4 5515.6 5529.2 6277.6 6600.5 6610.1 6408.5 6429.1 6708.6 6978.0 6979.4 6517.8 6660.3 6673.5 6680.5 6680.9 21340.0 21611.4 22449.2 22691.8 22730.5 2404.2 2469.8 2504.2 2506.7 2603.5 7060.5 7099.8 12451.4 14311.3 14645.5 2097.2 2099.8 2116.2 2149.3 2161.5 67391.7 69360.0 69769.0 70732.0 70800.0 15707.2 16361.7 16418.8 16422.5 16432.0 25448.2 25538.0 25549.7 25625.9 25795.4 7902.7 8188.0 8466.9 8478.2 8488.8 8086.2 8091.8 8102.8 8119.3 8122.7 4562.7 4730.6 4731.5 4836.3 4913.7 1993.5 2011.3 2027.3 2037.4 2047.4 931.2 978.2 1002.2 1026.4 1091.3 16981.4 17184.7 17222.5 17384.0 17976.5 1005.5 1007.0 1021.1 1023.7 1052.0 9789.1 9806.5 10162.2 10205.6 10444.3 557214.0 566809.0 578002.0 580103.0 599902.0 75222.6 77728.2 79449.0 79756.0 81099.6 1202.8 1224.1 1226.5 1227.1 1238.6 16039.9 16153.8 16172.7 16190.4 16291.5 34008.8 34775.7 35069.0 35296.1 35513.0 43650.3 45837.3 46219.3 46250.1 46458.5 7463.0 7595.5 7645.2 7677.2 7738.8 10553.2 10658.8 10984.9 11079.7 11204.3 9467.0 9647.9 9962.0 10149.4 10391.5 7698.3 10291.3 10297.9 10345.7 10367.9 7722.7 7807.4 7945.9 7998.9 8211.5 10317.1 10523.9 10756.5 10768.2 10806.6 10539.6 10778.8 10828.5 11045.1 11064.7 18:09:25 INFO - PROCESS | 7505 | 18:09:25 INFO - PROCESS | 7505 | [#3] mootools.html Cycles:180 Average:5821.36 Median:5804.54 stddev:3035.10 (52.3%) stddev-sans-first:3026.57 18:09:25 INFO - PROCESS | 7505 | Values: 1541.6 1570.2 1790.2 1882.5 1901.0 7528.8 7638.9 8210.5 8433.4 8445.7 7951.1 8046.3 8062.2 8111.0 8116.8 2468.0 2487.3 2497.1 2497.2 2499.6 2424.3 2441.6 2442.1 2444.7 2452.0 2431.4 2452.8 2452.9 2462.6 2479.4 2422.8 2437.6 2449.9 2454.2 2457.3 2396.2 2411.5 2414.9 2417.9 2483.3 8263.7 8345.7 8380.2 8396.0 8419.6 5167.9 5226.9 5235.9 5243.8 5300.5 3168.5 3222.8 3243.2 3372.6 3387.1 1062.8 1075.0 1075.8 1079.6 1100.7 11601.0 11771.6 11779.1 11834.1 11853.7 4775.3 4783.2 4797.7 4806.8 4873.8 9987.5 10016.2 10125.1 10127.7 10134.6 5191.3 5226.1 5264.5 5280.7 5301.0 4652.3 4725.0 4740.4 4743.1 4749.4 3888.4 3912.1 3928.9 3941.7 3948.9 1184.3 1185.3 1195.7 1203.2 1210.5 1374.6 1374.8 1387.6 1391.3 1401.2 7701.1 7841.9 7888.8 7890.8 7902.9 549.3 552.9 563.5 563.6 563.9 5780.7 5782.1 5827.0 5830.9 5845.1 9551.6 9790.7 9813.0 9870.7 9952.7 9500.6 9621.0 9682.6 9712.4 9776.9 7919.2 7927.8 7953.6 7985.9 8023.8 7505.8 7514.6 7547.6 7605.0 7847.2 9002.7 9298.5 9306.7 9317.0 9340.5 9432.6 9435.9 9548.3 9639.1 9778.9 6958.7 7117.9 7154.8 7529.2 7698.9 7619.9 7646.4 7687.6 7696.3 7707.9 7088.8 7317.3 7351.5 7356.2 7533.6 7100.5 7165.9 7169.9 7281.1 7303.1 7068.3 7070.9 7114.7 7129.3 7129.9 6745.8 6776.6 6779.9 6788.9 6809.3 6832.2 6956.5 6962.9 6963.7 6997.4 18:09:25 INFO - PROCESS | 7505 | 18:09:25 INFO - PROCESS | 7505 | [#4] prototype.html Cycles:180 Average:5659.56 Median:6372.89 stddev:1670.27 (26.2%) stddev-sans-first:1626.79 18:09:25 INFO - PROCESS | 7505 | Values: 354.3 366.3 371.9 375.1 375.9 6211.8 6341.1 6367.4 6440.6 6440.7 6139.6 6258.1 6264.1 6296.2 6296.8 4839.8 4861.5 4885.2 4885.3 4888.3 4863.7 4893.1 4893.8 4896.4 4902.5 4743.7 4863.0 4875.8 4877.8 4878.1 4838.3 4857.4 4857.8 4866.2 4868.5 4284.9 4297.9 4300.5 4302.8 4311.4 5921.7 5961.5 5980.8 6004.8 6020.3 5734.0 5734.9 5743.6 5756.1 5757.3 6693.9 6702.6 6703.1 6710.8 6733.4 4806.1 4828.1 4837.2 4845.4 4846.0 6938.0 7006.6 7058.8 7079.6 7080.6 4925.6 4966.4 4975.8 4981.5 4982.3 5046.1 5048.0 5049.5 5054.6 5065.7 5660.7 5755.9 5756.0 5760.5 5765.2 6749.0 6813.8 6828.3 6831.8 6842.5 1726.6 1784.9 1786.3 1786.5 1791.4 1207.4 1214.3 1218.7 1219.8 1239.7 6327.4 6365.0 6378.4 6400.9 6402.3 6905.0 6905.4 6915.8 6923.4 6931.6 6260.3 6382.4 6390.2 6392.7 6398.6 3714.3 3718.7 3729.5 3743.1 3755.6 7075.8 7121.4 7127.4 7150.0 7151.4 6929.8 7106.8 7120.5 7127.7 7137.5 7062.9 7089.3 7114.2 7119.2 7123.7 6832.5 6853.3 6854.5 6907.7 6907.8 6694.7 6822.0 6833.7 6836.9 6843.9 7043.8 7062.3 7104.6 7104.7 7119.4 4825.9 4877.7 4881.6 4887.0 4887.0 6823.0 6832.6 6928.6 6991.6 6997.9 6806.1 6806.9 6838.7 6846.2 6846.2 6763.3 6797.5 6816.1 6842.1 6844.0 6592.8 6718.5 6726.4 6738.7 6756.8 6877.7 6882.5 6887.3 6896.7 6898.7 6476.8 6630.0 6700.7 6700.9 6722.9 18:09:25 INFO - PROCESS | 7505 | 18:09:25 INFO - PROCESS | 7505 | [#5] yui.html Cycles:180 Average:14586.59 Median:4510.44 stddev:16526.48 (366.4%) stddev-sans-first:16540.55 18:09:25 INFO - PROCESS | 7505 | Values: 830.8 847.3 854.8 862.5 870.9 13769.7 13832.4 13859.0 13875.3 13993.8 13460.6 13482.7 13508.1 13528.3 13539.8 863.4 904.3 909.3 909.4 914.6 900.9 903.8 904.3 906.4 907.9 943.6 945.2 945.4 950.2 951.6 935.8 936.5 941.3 944.2 944.4 697.6 700.3 700.6 702.1 702.6 14576.3 14583.5 14682.5 14698.9 14716.6 13423.6 13445.3 13445.9 13510.9 13790.6 10205.3 11995.0 12017.2 12344.5 12599.7 3801.7 3825.3 3835.8 3849.0 3854.9 50665.5 50892.0 50967.0 51044.0 51162.7 10706.3 10717.4 10875.2 11066.7 11202.4 18826.9 18889.1 18929.6 18967.3 18984.5 8046.1 8231.6 8242.8 8257.2 8322.8 4394.0 4491.9 4534.3 4562.3 4595.8 3208.1 3214.2 3220.5 3241.2 3243.7 598.5 599.8 602.1 616.0 618.0 647.0 662.8 664.8 678.2 699.1 8915.4 9028.4 9099.9 9137.1 9147.1 572.0 620.9 651.4 658.8 704.0 4364.9 4398.3 4486.7 4529.0 4560.7 58351.4 58516.7 62176.0 62439.7 63623.0 49519.8 50177.5 50272.0 50542.2 51217.2 48102.8 49244.8 49808.0 49889.5 49902.0 9394.2 9448.2 9517.2 9665.1 9669.4 35215.8 35417.6 35812.3 35991.0 36211.5 43008.6 46552.8 46826.5 46998.8 47793.0 11266.9 11535.8 11771.2 11908.9 11984.2 13426.9 13440.7 13669.9 13790.6 13839.7 11953.9 12574.9 12842.0 13127.9 13303.5 12153.6 12237.9 12283.8 12353.5 12632.6 11741.7 11786.3 11997.0 12024.4 12485.8 10594.7 10808.2 10813.3 10830.9 11021.9 10433.5 10612.9 10884.3 11444.4 11455.7 18:09:25 INFO - PROCESS | 7505 | -------- Summary: end -------- 18:09:25 INFO - PROCESS | 7505 | 18:09:26 INFO - TEST-INFO | 7505: exit 0 18:09:26 INFO - TEST-OK | dromaeo_css | took 1108500ms 18:09:26 INFO - TEST-START | kraken 18:09:26 INFO - operating with platform_type : linux_ 18:09:26 INFO - Initialising browser for kraken test... 18:09:26 INFO - TEST-INFO | started process 7751 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpC9oTkL/profile http://localhost:57969/getInfo.html) 18:09:29 INFO - PROCESS | 7751 | 18:09:29 INFO - PROCESS | 7751 | (process:7829): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:09:30 INFO - PROCESS | 7751 | __metrics Screen width/height:1600/1200 18:09:30 INFO - PROCESS | 7751 | colorDepth:24 18:09:30 INFO - PROCESS | 7751 | Browser inner width/height: 1024/697 18:09:30 INFO - PROCESS | 7751 | __metrics 18:09:31 INFO - TEST-INFO | 7751: exit 0 18:09:31 INFO - Browser initialized. 18:09:31 INFO - Running cycle 1/1 for kraken test... 18:09:31 INFO - TEST-INFO | started process 7878 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpC9oTkL/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:09:32 INFO - PROCESS | 7878 | 18:09:32 INFO - PROCESS | 7878 | (process:7942): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:09:43 INFO - PROCESS | 7878 | RSS: Main: 140959744 18:09:43 INFO - PROCESS | 7878 | 18:09:45 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57969/tests/kraken/driver.html?testName=audio-beat-detection) 18:09:46 INFO - PROCESS | 7878 | RSS: Main: 147054592 18:09:46 INFO - PROCESS | 7878 | 18:09:49 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57969/tests/kraken/driver.html?testName=audio-dft) 18:09:49 INFO - PROCESS | 7878 | RSS: Main: 145305600 18:09:49 INFO - PROCESS | 7878 | 18:09:52 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57969/tests/kraken/driver.html?testName=audio-fft) 18:09:53 INFO - PROCESS | 7878 | RSS: Main: 147140608 18:09:53 INFO - PROCESS | 7878 | 18:09:55 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57969/tests/kraken/driver.html?testName=audio-oscillator) 18:09:55 INFO - PROCESS | 7878 | RSS: Main: 146980864 18:09:55 INFO - PROCESS | 7878 | 18:09:57 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57969/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:09:58 INFO - PROCESS | 7878 | RSS: Main: 145510400 18:09:58 INFO - PROCESS | 7878 | 18:10:01 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57969/tests/kraken/driver.html?testName=imaging-darkroom) 18:10:02 INFO - PROCESS | 7878 | RSS: Main: 145416192 18:10:02 INFO - PROCESS | 7878 | 18:10:05 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57969/tests/kraken/driver.html?testName=imaging-desaturate) 18:10:05 INFO - PROCESS | 7878 | RSS: Main: 145911808 18:10:05 INFO - PROCESS | 7878 | 18:10:08 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57969/tests/kraken/driver.html?testName=json-parse-financial) 18:10:09 INFO - PROCESS | 7878 | RSS: Main: 145682432 18:10:09 INFO - PROCESS | 7878 | 18:10:10 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57969/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:10:11 INFO - PROCESS | 7878 | RSS: Main: 145952768 18:10:11 INFO - PROCESS | 7878 | 18:10:13 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57969/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:10:13 INFO - PROCESS | 7878 | RSS: Main: 145682432 18:10:13 INFO - PROCESS | 7878 | 18:10:15 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57969/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:10:16 INFO - PROCESS | 7878 | RSS: Main: 145879040 18:10:16 INFO - PROCESS | 7878 | 18:10:18 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:10:19 INFO - PROCESS | 7878 | RSS: Main: 147808256 18:10:19 INFO - PROCESS | 7878 | 18:10:21 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:10:22 INFO - PROCESS | 7878 | RSS: Main: 147333120 18:10:22 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | Cycle 1(1): loaded http://localhost:57969/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57969/tests/kraken/driver.html?testName=ai-astar) 18:10:24 INFO - PROCESS | 7878 | RSS: Main: 148410368 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | __start_tp_report 18:10:24 INFO - PROCESS | 7878 | _x_x_mozilla_page_load 18:10:24 INFO - PROCESS | 7878 | _x_x_mozilla_page_load_details 18:10:24 INFO - PROCESS | 7878 | |i|pagename|runs| 18:10:24 INFO - PROCESS | 7878 | |0;ai-astar;111;106;111;111;113;122;109;111;113;110 18:10:24 INFO - PROCESS | 7878 | |1;audio-beat-detection;153;125;149;162;125;155;152;135;162;130 18:10:24 INFO - PROCESS | 7878 | |2;audio-dft;161;162;162;163;161;161;164;162;164;160 18:10:24 INFO - PROCESS | 7878 | |3;audio-fft;74;77;76;154;72;73;76;73;145;74 18:10:24 INFO - PROCESS | 7878 | |4;audio-oscillator;98;96;142;95;95;96;147;95;95;139 18:10:24 INFO - PROCESS | 7878 | |5;imaging-gaussian-blur;110;110;110;110;109;109;109;110;110;110 18:10:24 INFO - PROCESS | 7878 | |6;imaging-darkroom;147;146;150;148;148;147;146;148;148;146 18:10:24 INFO - PROCESS | 7878 | |7;imaging-desaturate;97;97;96;96;97;97;98;97;96;96 18:10:24 INFO - PROCESS | 7878 | |8;json-parse-financial;59;56;63;58;60;58;55;59;55;55 18:10:24 INFO - PROCESS | 7878 | |9;json-stringify-tinderbox;64;66;65;65;65;65;65;66;65;65 18:10:24 INFO - PROCESS | 7878 | |10;stanford-crypto-aes;79;86;86;79;78;78;80;78;78;79 18:10:24 INFO - PROCESS | 7878 | |11;stanford-crypto-ccm;118;124;112;117;122;112;112;110;115;124 18:10:24 INFO - PROCESS | 7878 | |12;stanford-crypto-pbkdf2;130;150;140;135;136;142;140;134;126;127 18:10:24 INFO - PROCESS | 7878 | |13;stanford-crypto-sha256-iterative;71;66;65;66;64;66;64;64;64;66 18:10:24 INFO - PROCESS | 7878 | __end_tp_report 18:10:24 INFO - PROCESS | 7878 | __start_cc_report 18:10:24 INFO - PROCESS | 7878 | _x_x_mozilla_cycle_collect,714 18:10:24 INFO - PROCESS | 7878 | __end_cc_report 18:10:24 INFO - PROCESS | 7878 | __startTimestamp1456971024376__endTimestamp 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | ------- Summary: start ------- 18:10:24 INFO - PROCESS | 7878 | Number of tests: 14 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#0] ai-astar Cycles:10 Average:111.70 Median:111.00 stddev:4.14 (3.7%) stddev-sans-first:4.38 18:10:24 INFO - PROCESS | 7878 | Values: 111.0 106.0 111.0 111.0 113.0 122.0 109.0 111.0 113.0 110.0 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#1] audio-beat-detection Cycles:10 Average:144.80 Median:152.50 stddev:14.65 (9.6%) stddev-sans-first:15.24 18:10:24 INFO - PROCESS | 7878 | Values: 153.0 125.0 149.0 162.0 125.0 155.0 152.0 135.0 162.0 130.0 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.33 (0.8%) stddev-sans-first:1.36 18:10:24 INFO - PROCESS | 7878 | Values: 161.0 162.0 162.0 163.0 161.0 161.0 164.0 162.0 164.0 160.0 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#3] audio-fft Cycles:10 Average:89.40 Median:74.00 stddev:31.78 (43.0%) stddev-sans-first:33.22 18:10:24 INFO - PROCESS | 7878 | Values: 74.0 77.0 76.0 154.0 72.0 73.0 76.0 73.0 145.0 74.0 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#4] audio-oscillator Cycles:10 Average:109.80 Median:95.00 stddev:22.78 (24.0%) stddev-sans-first:23.76 18:10:24 INFO - PROCESS | 7878 | Values: 98.0 96.0 142.0 95.0 95.0 96.0 147.0 95.0 95.0 139.0 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 18:10:24 INFO - PROCESS | 7878 | Values: 110.0 110.0 110.0 110.0 109.0 109.0 109.0 110.0 110.0 110.0 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:148.00 stddev:1.26 (0.9%) stddev-sans-first:1.33 18:10:24 INFO - PROCESS | 7878 | Values: 147.0 146.0 150.0 148.0 148.0 147.0 146.0 148.0 148.0 146.0 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:97.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 18:10:24 INFO - PROCESS | 7878 | Values: 97.0 97.0 96.0 96.0 97.0 97.0 98.0 97.0 96.0 96.0 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#8] json-parse-financial Cycles:10 Average:57.80 Median:58.50 stddev:2.62 (4.5%) stddev-sans-first:2.74 18:10:24 INFO - PROCESS | 7878 | Values: 59.0 56.0 63.0 58.0 60.0 58.0 55.0 59.0 55.0 55.0 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:0.57 (0.9%) stddev-sans-first:0.44 18:10:24 INFO - PROCESS | 7878 | Values: 64.0 66.0 65.0 65.0 65.0 65.0 65.0 66.0 65.0 65.0 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:79.00 stddev:3.18 (4.0%) stddev-sans-first:3.35 18:10:24 INFO - PROCESS | 7878 | Values: 79.0 86.0 86.0 79.0 78.0 78.0 80.0 78.0 78.0 79.0 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#11] stanford-crypto-ccm Cycles:10 Average:116.60 Median:117.50 stddev:5.27 (4.5%) stddev-sans-first:5.57 18:10:24 INFO - PROCESS | 7878 | Values: 118.0 124.0 112.0 117.0 122.0 112.0 112.0 110.0 115.0 124.0 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.00 Median:138.00 stddev:7.35 (5.3%) stddev-sans-first:7.47 18:10:24 INFO - PROCESS | 7878 | Values: 130.0 150.0 140.0 135.0 136.0 142.0 140.0 134.0 126.0 127.0 18:10:24 INFO - PROCESS | 7878 | 18:10:24 INFO - PROCESS | 7878 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:2.12 (3.2%) stddev-sans-first:1.00 18:10:24 INFO - PROCESS | 7878 | Values: 71.0 66.0 65.0 66.0 64.0 66.0 64.0 64.0 64.0 66.0 18:10:24 INFO - PROCESS | 7878 | -------- Summary: end -------- 18:10:24 INFO - PROCESS | 7878 | 18:10:25 INFO - TEST-INFO | 7878: exit 0 18:10:25 INFO - TEST-OK | kraken | took 58867ms 18:10:25 INFO - TEST-START | v8_7 18:10:25 INFO - operating with platform_type : linux_ 18:10:25 INFO - Initialising browser for v8_7 test... 18:10:25 INFO - TEST-INFO | started process 8170 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpcqb8pT/profile http://localhost:57969/getInfo.html) 18:10:28 INFO - PROCESS | 8170 | 18:10:28 INFO - PROCESS | 8170 | (process:8251): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:10:29 INFO - PROCESS | 8170 | __metrics Screen width/height:1600/1200 18:10:29 INFO - PROCESS | 8170 | colorDepth:24 18:10:29 INFO - PROCESS | 8170 | Browser inner width/height: 1024/697 18:10:29 INFO - PROCESS | 8170 | __metrics 18:10:30 INFO - TEST-INFO | 8170: exit 0 18:10:30 INFO - Browser initialized. 18:10:30 INFO - Running cycle 1/1 for v8_7 test... 18:10:30 INFO - TEST-INFO | started process 8301 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpcqb8pT/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:10:31 INFO - PROCESS | 8301 | 18:10:31 INFO - PROCESS | 8301 | (process:8366): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 18:10:42 INFO - PROCESS | 8301 | RSS: Main: 140980224 18:10:42 INFO - PROCESS | 8301 | 18:11:04 INFO - PROCESS | 8301 | Cycle 1(1): loaded http://localhost:57969/tests/v8_7/run.html (next: http://localhost:57969/tests/v8_7/run.html) 18:11:05 INFO - PROCESS | 8301 | RSS: Main: 144560128 18:11:05 INFO - PROCESS | 8301 | 18:11:05 INFO - PROCESS | 8301 | __start_tp_report 18:11:05 INFO - PROCESS | 8301 | _x_x_mozilla_page_load 18:11:05 INFO - PROCESS | 8301 | _x_x_mozilla_page_load_details 18:11:05 INFO - PROCESS | 8301 | |i|pagename|runs| 18:11:05 INFO - PROCESS | 8301 | |0;Richards;168.49199663016006 18:11:05 INFO - PROCESS | 8301 | |1;DeltaBlue;167.0564650851988 18:11:05 INFO - PROCESS | 8301 | |2;Encrypt;278.55153203342616 18:11:05 INFO - PROCESS | 8301 | |3;Decrypt;5186.5284974093265 18:11:05 INFO - PROCESS | 8301 | |4;RayTrace;936.3295880149813 18:11:05 INFO - PROCESS | 8301 | |5;Earley;564.0157924421884 18:11:05 INFO - PROCESS | 8301 | |6;Boyer;11000 18:11:05 INFO - PROCESS | 8301 | |7;RegExp;39365.38461538462 18:11:05 INFO - PROCESS | 8301 | |8;Splay;495.5401387512388 18:11:05 INFO - PROCESS | 8301 | |9;NavierStokes;4822.115384615385 18:11:05 INFO - PROCESS | 8301 | __end_tp_report 18:11:05 INFO - PROCESS | 8301 | __startTimestamp1456971065058__endTimestamp 18:11:05 INFO - PROCESS | 8301 | 18:11:05 INFO - PROCESS | 8301 | ------- Summary: start ------- 18:11:05 INFO - PROCESS | 8301 | Number of tests: 10 18:11:05 INFO - PROCESS | 8301 | 18:11:05 INFO - PROCESS | 8301 | [#0] Richards Cycles:1 Average:168.49 Median:168.49 stddev:0.00 (0.0%) 18:11:05 INFO - PROCESS | 8301 | Values: 168.5 18:11:05 INFO - PROCESS | 8301 | 18:11:05 INFO - PROCESS | 8301 | [#1] DeltaBlue Cycles:1 Average:167.06 Median:167.06 stddev:0.00 (0.0%) 18:11:05 INFO - PROCESS | 8301 | Values: 167.1 18:11:05 INFO - PROCESS | 8301 | 18:11:05 INFO - PROCESS | 8301 | [#2] Encrypt Cycles:1 Average:278.55 Median:278.55 stddev:0.00 (0.0%) 18:11:05 INFO - PROCESS | 8301 | Values: 278.6 18:11:05 INFO - PROCESS | 8301 | 18:11:05 INFO - PROCESS | 8301 | [#3] Decrypt Cycles:1 Average:5186.53 Median:5186.53 stddev:0.00 (0.0%) 18:11:05 INFO - PROCESS | 8301 | Values: 5186.5 18:11:05 INFO - PROCESS | 8301 | 18:11:05 INFO - PROCESS | 8301 | [#4] RayTrace Cycles:1 Average:936.33 Median:936.33 stddev:0.00 (0.0%) 18:11:05 INFO - PROCESS | 8301 | Values: 936.3 18:11:05 INFO - PROCESS | 8301 | 18:11:05 INFO - PROCESS | 8301 | [#5] Earley Cycles:1 Average:564.02 Median:564.02 stddev:0.00 (0.0%) 18:11:05 INFO - PROCESS | 8301 | Values: 564.0 18:11:05 INFO - PROCESS | 8301 | 18:11:05 INFO - PROCESS | 8301 | [#6] Boyer Cycles:1 Average:11000.00 Median:11000.00 stddev:0.00 (0.0%) 18:11:05 INFO - PROCESS | 8301 | Values: 11000.0 18:11:05 INFO - PROCESS | 8301 | 18:11:05 INFO - PROCESS | 8301 | [#7] RegExp Cycles:1 Average:39365.38 Median:39365.38 stddev:0.00 (0.0%) 18:11:05 INFO - PROCESS | 8301 | Values: 39365.4 18:11:05 INFO - PROCESS | 8301 | 18:11:05 INFO - PROCESS | 8301 | [#8] Splay Cycles:1 Average:495.54 Median:495.54 stddev:0.00 (0.0%) 18:11:05 INFO - PROCESS | 8301 | Values: 495.5 18:11:05 INFO - PROCESS | 8301 | 18:11:05 INFO - PROCESS | 8301 | [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 18:11:05 INFO - PROCESS | 8301 | Values: 4822.1 18:11:05 INFO - PROCESS | 8301 | -------- Summary: end -------- 18:11:05 INFO - PROCESS | 8301 | 18:11:05 INFO - TEST-INFO | 8301: exit 0 18:11:05 INFO - TEST-OK | v8_7 | took 40705ms 18:11:05 INFO - SUITE-END | took 1208s 18:11:06 INFO - Completed test suite (00:20:08) 18:11:06 INFO - Working with test: dromaeo_css 18:11:06 INFO - Generating results file: dromaeo_css 18:11:06 INFO - Working with test: kraken 18:11:06 INFO - Generating results file: kraken 18:11:06 INFO - javascript benchmark 18:11:06 INFO - Working with test: v8_7 18:11:06 INFO - Generating results file: v8_7 18:11:06 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:11:06 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,52,43]] 18:11:06 INFO - process_Request line: dromaeo_css 7399.32 graph.html#tests=[[72,52,43]] 18:11:06 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:11:06 INFO - process_Request line: kraken 1492.70 graph.html#tests=[[232,52,43]] 18:11:06 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:11:06 INFO - process_Request line: v8_7 22148.34 graph.html#tests=[[230,52,43]] 18:11:06 INFO - RETURN: dromaeo_css: 7399.32 18:11:06 INFO - RETURN: kraken: 1492.70 18:11:06 INFO - RETURN: v8_7: 22148.34 18:11:06 INFO - javascript benchmark 18:11:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3289.3750187464366, 3351.4637657974727, 3377.9831100844494, 3406.812625305608, 3414.1293970037636, 5456.945430545693, 5462.644928079677, 5466.671999680022, 5477.04151773439, 5501.779928802853, 5468.9726551367185, 5499.202615620733, 5504.64219825712, 5506.476884695948, 5524.281843360358, 4938.48145944676, 4988.152014157602, 5049.924251136217, 5090.465501122384, 5143.15138002231, 4867.464578896319, 4984.37695288089, 5006.349174607296, 5007.899842003158, 5092.898142037157, 5018.021485810268, 5041.647084704072, 5067.619928505358, 5086.720230387327, 5097.668651537657, 5019.673721208128, 5023.648344615878, 5038.722870242135, 5047.570956468668, 5055.46917573655, 4827.927581086286, 4888.560029597354, 4931.901361972777, 4953.653244272902, 4976.527229913152, 5244.475552444763, 5297.602679799031, 5306.336707911511, 5308.6549374290935, 5321.840344789663, 5911.763529458816, 5933.881322373529, 5934.673592952386, 5936.614120082181, 5947.316058653267, 5805.448482394166, 5881.294244690622, 5917.615355001911, 5963.671998040106, 5975.312839023481, 5606.971965140189, 5609.663420194761, 5611.438856114438, 5615.0454422747825, 5627.887442251173, 5971.462568368781, 6002.159697642333, 6044.0, 6046.244219472566, 6046.455818976313, 5686.459786320375, 5741.598088133874, 5833.0, 5837.416258374214, 5938.762449502058, 5922.911156332659, 5950.940490595125, 5951.940480595139, 5956.285245770535, 5974.701264936736, 5876.588638795243, 5887.499562537232, 5902.1146827976145, 5907.438793314628, 5911.674857882857, 5779.219805326311, 5789.073748200268, 5902.1146827976145, 5931.377205393458, 6000.7899723510345, 4478.193925093515, 4498.415206023196, 4506.571875671806, 4511.661625378109, 4534.047849951481, 4008.7594744315434, 4055.8174882130374, 4058.7158898876796, 4075.735077219971, 4099.364598487239, 5419.810306639249, 5517.889642207214, 5531.976584331912, 5632.690202038845, 5688.288963879515, 5977.342492325841, 5979.222701048836, 5988.371221021817, 5989.760409583568, 5996.670183139882, 5416.91874621881, 5471.945280547144, 5615.157726341082, 5619.662820230799, 5639.351474580394, 5056.646034777604, 5087.898242035212, 5092.414372347153, 5136.383633963948, 5159.045576568347, 5938.317093534212, 6000.249968753906, 6007.639541627516, 6033.939660603425, 6038.275406951194, 5782.219400381063, 5859.414058594107, 5893.528517718487, 5915.792947246911, 5924.022536281465, 6019.668918209541, 6103.176071230505, 6109.2974307955155, 6119.388061193845, 6123.7550497981365, 5784.50831679312, 5873.911891321548, 5885.47030767233, 5903.557233207441, 5921.703914804329, 5764.164196191613, 5831.912521312099, 5915.881682366243, 5951.315598706205, 5970.5223582114195, 5697.743601537858, 5699.344575373885, 5701.914471282851, 5731.684757338219, 5737.540996720336, 5651.773929042793, 5664.688442135722, 5667.631603945731, 5697.886042279214, 5740.655560666373, 5628.774849005994, 5686.459786320293, 5693.060644993529, 5739.62692424991, 5770.076787713946, 5642.520385767091, 5705.714714264187, 5725.312962444366, 5732.799352022659, 5781.479666830005, 5523.364813046551, 5586.692731899784, 5645.576581756302, 5646.153077038477, 5670.432956704297], "unit": "score", "name": "dojo.html", "value": 5435.84653321964}, {"lowerIsBetter": false, "replicates": [554.603458527153, 569.45901393676, 583.0147051482993, 592.0881841963377, 596.5853731656499, 31815.363692726132, 32667.0, 38841.417378739345, 39255.60744392555, 39329.803350983275, 42686.35970460438, 42692.57307426925, 43235.0, 43425.0, 43438.56561434393, 8659.350548708857, 8771.736847894541, 8783.253423459013, 8812.911870881273, 8861.867071993925, 8624.266937310336, 8732.825343493127, 8737.432066915662, 8788.47269163849, 8792.34057445691, 8799.648014079428, 8890.511021893793, 9091.54542272887, 9097.818043639123, 9112.407693499901, 8930.687425940094, 8978.595963181673, 8980.595873185723, 8997.640094396216, 9017.0, 8845.911540884574, 8977.910220897773, 8983.056779038172, 9015.098490150998, 9018.639254429814, 42573.57426425727, 43009.78495107536, 43059.0, 43077.35383969243, 43121.56878431207, 3630.8547658093644, 3667.2665466906724, 3700.888973330804, 3704.685101766353, 4160.625543701051, 27643.308917277027, 28133.15600531967, 28381.432371352457, 31020.224494387596, 32050.198745031328, 4249.893752656209, 4255.97872010638, 4285.764282964436, 4301.182775272689, 4358.891027724332, 301445.0, 306555.0, 306708.0, 308837.0, 308902.0, 60172.69913650404, 61686.38313616896, 66447.66776166088, 68183.65908170526, 68269.65865170643, 61695.383046168965, 61776.69111654413, 61994.38005619976, 62143.37856621466, 62473.0, 17211.225494852762, 17373.565660858327, 17398.39105631297, 17459.47621571355, 17530.386436474662, 13353.132046416951, 13503.054786164874, 13574.117682350616, 13636.522721704692, 13686.384112714952, 21248.681269780966, 21880.23419180321, 21893.890530547244, 21931.561368772534, 21990.780092199195, 8348.415610907177, 8494.787630309169, 8661.22049015579, 8672.26285765717, 8791.07693692166, 1030.3920686794827, 1056.989430105689, 1129.971750706239, 1172.6247600767842, 1288.7873500872251, 29787.40425191484, 30153.547696784564, 30722.23194420113, 30827.845860770554, 30883.0735077948, 1160.7272291011604, 1256.7172386213213, 1260.6659235302652, 1276.1258537901567, 1278.2905487454552, 16005.0, 16055.839441605434, 16159.676806463805, 16166.75749863753, 16207.865449418663, 434481.0, 552753.0, 556256.0, 560058.0, 574780.0, 55499.0, 56393.718031410404, 56430.7178464105, 56493.0, 57028.0, 1449.6955639315652, 1470.691154857492, 1471.3011319623092, 1479.5857159995219, 1492.8208614966056, 31133.221669458446, 32441.67558324481, 32536.02391928246, 32901.83549082239, 33570.832145839115, 50027.499725002286, 50607.240891385925, 50676.23985640292, 50789.238161428344, 50860.491395087054, 254324.0, 255321.0, 256515.0, 256811.0, 257283.0, 42979.785101074296, 43154.35268470913, 45462.09075818531, 45515.772421137684, 45819.77090114528, 42400.15199695927, 42627.3605895918, 42938.78530607327, 43470.565294347914, 43766.78116609396, 36099.0, 37203.0, 37469.625303746616, 41796.79101604473, 43373.61410225641, 40405.1918961625, 40600.0, 40774.0, 41280.0, 41379.1724165509, 40185.79907100446, 40999.59000409958, 41160.0, 41171.38242926419, 41392.0, 34951.82524087363, 35317.0, 35497.0, 35502.8224858874, 35653.465198021535, 27048.864755676095, 27520.862395687895, 27629.723702762716, 27904.720952790212, 27955.72044279531], "unit": "score", "name": "ext.html", "value": 20154.729193371}, {"lowerIsBetter": false, "replicates": [391.7434080677157, 392.6740805131741, 393.13902553408036, 393.94681717968064, 394.0, 15576.376944922218, 15970.600734981603, 17208.82791172088, 20087.59824803505, 20274.39176824696, 11981.281123132618, 12225.266484010966, 13296.800547991788, 17679.469615911545, 17680.911595442038, 4721.740304283263, 5199.14214154664, 5374.973125134388, 5797.159411885295, 5802.796902108406, 5317.7607007684555, 5515.806946756864, 5519.779208831642, 5523.1163013917785, 5649.350324712649, 5515.61390702649, 5529.225908372831, 6277.560570760049, 6600.537962342638, 6610.107635469222, 6408.455281301071, 6429.099926010363, 6708.597484150967, 6977.965110174468, 6979.40675042622, 6517.771877984248, 6660.333966603349, 6673.4661227101715, 6680.532362734611, 6680.933190668128, 21340.0, 21611.351659450243, 22449.21427750021, 22691.77308226913, 22730.545389092127, 2404.2186289456, 2469.7530246975157, 2504.18613950465, 2506.711728151268, 2603.4923189977962, 7060.505764596429, 7099.751508697223, 12451.377431128498, 14311.284435778169, 14645.487407940778, 2097.1716172112083, 2099.7795231500777, 2116.2381542644634, 2149.344449942767, 2161.53526991696, 67391.66304168497, 69360.0, 69769.0, 70732.0, 70800.0, 15707.214639267992, 16361.672766544603, 16418.753718694228, 16422.507324780276, 16432.0, 25448.236552903443, 25538.0, 25549.74450255511, 25625.871870640527, 25795.355116122246, 7902.723404680809, 8187.959060204661, 8466.915330846612, 8478.236958673626, 8488.787780305542, 8086.1913808618665, 8091.757247282591, 8102.797430064177, 8119.269265766109, 8122.675092996332, 4562.657800664963, 4730.550597693214, 4731.526847315241, 4836.347093142451, 4913.656044076952, 1993.531520092777, 2011.336259034515, 2027.3107143571256, 2037.3582321568658, 2047.3550831488033, 931.1573026411108, 978.192990782593, 1002.2483137646765, 1026.384169498295, 1091.3397394576325, 16981.405650802164, 17184.656306873792, 17222.483325500256, 17383.956962582284, 17976.460706178834, 1005.4620777883797, 1006.9798604028025, 1021.1218352217087, 1023.7031260934394, 1052.0478966535297, 9789.070038346346, 9806.460644664468, 10162.237832162617, 10205.591776328863, 10444.268901176996, 557214.0, 566809.0, 578002.0, 580103.0, 599902.0, 75222.62388688022, 77728.22271777209, 79449.0, 79756.0, 81099.59450202712, 1202.8496437945257, 1224.136983426682, 1226.466487078124, 1227.061298106949, 1238.5603110895684, 16039.93608638285, 16153.75769363437, 16172.676546468769, 16190.433334833224, 16291.511254662379, 34008.829955850066, 34775.65224347724, 35069.0, 35296.11759705925, 35513.0, 43650.34524482199, 45837.31244031409, 46219.3067103987, 46250.07499850052, 46458.53541464677, 7462.962685186756, 7595.506292090997, 7645.159032506531, 7677.155512893689, 7738.7678369649, 10553.155747540024, 10658.786824263627, 10984.945075274572, 11079.72300692489, 11204.26333918244, 9466.95266523663, 9647.864444055851, 9961.950190249003, 10149.441780701836, 10391.53238104272, 7698.345640620386, 10291.33106348085, 10297.897021029694, 10345.74135646615, 10367.896321036995, 7722.652480638497, 7807.375409967075, 7945.920540794518, 7998.920010799818, 8211.466254693425, 10317.123044541298, 10523.894761052597, 10756.515956781806, 10768.19238557127, 10806.621768238389, 10539.63111291101, 10778.838317425088, 10828.458577070958, 11045.116390688887, 11064.723381915517], "unit": "score", "name": "jquery.html", "value": 9135.716776320636}, {"lowerIsBetter": false, "replicates": [1541.560655213264, 1570.1835045776197, 1790.1765188013512, 1882.5293676580854, 1901.0494752623697, 7528.84942301155, 7638.885416718754, 8210.507369557823, 8433.367497437694, 8445.746627601175, 7951.085625153095, 8046.316063134641, 8062.234087761653, 8110.959445202766, 8116.75649730509, 2467.9629805552977, 2487.266256454341, 2497.1010436242937, 2497.1634502441702, 2499.6250562415603, 2424.2969538833718, 2441.5849305618, 2442.133042769809, 2444.706635203769, 2451.9754802451926, 2431.416460049593, 2452.754724527542, 2452.889619967106, 2462.5813611685967, 2479.442125521761, 2422.8061755059553, 2437.5612389769835, 2449.865257410842, 2454.202384225125, 2457.2505385857307, 2396.209250947183, 2411.4574220800355, 2414.8913298901593, 2417.9395515112087, 2483.3295010347106, 8263.669453221864, 8345.707900223524, 8380.20388063139, 8395.95802020992, 8419.579021048923, 5167.888903885682, 5226.947730522684, 5235.921461178085, 5243.8164664236565, 5300.469953004668, 3168.5088811234295, 3222.7905186162825, 3243.2216268095485, 3372.629010808809, 3387.0854869185428, 1062.7555662197738, 1075.0485820049234, 1075.8063548561256, 1079.632924805562, 1100.7468282295038, 11601.0, 11771.64685059441, 11779.116566257566, 11834.112441566916, 11853.703657408547, 4775.283707443842, 4783.210770222874, 4797.736124513185, 4806.759662016955, 4873.80504779812, 9987.500624968869, 10016.248781341428, 10125.088742013255, 10127.7468063299, 10134.594616215416, 5191.299174611455, 5226.111561034633, 5264.499872512105, 5280.709560974106, 5300.9734951325, 4652.278896771006, 4725.0, 4740.360051393087, 4743.075100355467, 4749.4063242094735, 3888.358420860583, 3912.1002169501176, 3928.8821335359985, 3941.664958478504, 3948.9012774680286, 1184.2775906696909, 1185.3184418959133, 1195.713028873064, 1203.1638011581867, 1210.4855436439655, 1374.5807528703842, 1374.8144000559796, 1387.5837248825312, 1391.304347826087, 1401.222321611495, 7701.114371847198, 7841.882371764428, 7888.802779930547, 7890.842183156419, 7902.920970790334, 549.2612436273206, 552.9308836395451, 563.4759673503681, 563.5547917145491, 563.8505795964073, 5780.653160810365, 5782.074868021096, 5827.0, 5830.854228644318, 5845.064789633723, 9551.570179341947, 9790.706278811647, 9812.95093524542, 9870.703878883645, 9952.651657192107, 9500.572474238676, 9621.0, 9682.56428460721, 9712.417254964725, 9776.902230977741, 7919.247671471201, 7927.762167134995, 7953.6023198840985, 7985.8802117969435, 8023.83952320962, 7505.774826755206, 7514.586697731677, 7547.622618869144, 7604.961975190088, 7847.2152784721075, 9002.684906028258, 9298.535073246447, 9306.72079837606, 9316.95341523288, 9340.486273255035, 9432.62269509212, 9435.905640943503, 9548.283878708986, 9639.132478077006, 9778.902210978084, 6958.686859091252, 7117.893231601426, 7154.821129471805, 7529.1717911028645, 7698.883661869109, 7619.9238007619215, 7646.388288936761, 7687.577183254974, 7696.345810606162, 7707.92292077072, 7088.822779430556, 7317.341439270491, 7351.522151060164, 7356.154042285206, 7533.58565278893, 7100.467464940253, 7165.8925116122255, 7169.892451613126, 7281.089863767029, 7303.050603421521, 7068.3285087916565, 7070.929290707027, 7114.715411383694, 7129.287071292829, 7129.893051604334, 6745.797626071029, 6776.627285499345, 6779.8983015253825, 6788.93211067883, 6809.2509823918335, 6832.180138383425, 6956.513044086865, 6962.895556566555, 6963.721451141898, 6997.370236678723], "unit": "score", "name": "mootools.html", "value": 4719.188232068309}, {"lowerIsBetter": false, "replicates": [354.29495304344357, 366.3369301564168, 371.9126005388735, 375.06608544723645, 375.9116607597216, 6211.844703882405, 6341.080543321215, 6367.395097465745, 6440.645764482952, 6440.742370305182, 6139.570230083896, 6258.092576576394, 6264.060390941361, 6296.212973378328, 6296.842578935529, 4839.830605928793, 4861.489543597924, 4885.193942999401, 4885.267209918505, 4888.315635810972, 4863.732494712789, 4893.119238537062, 4893.779779909894, 4896.412430508326, 4902.534259245366, 4743.739094349809, 4863.0, 4875.756212189394, 4877.804887804484, 4878.097551952882, 4838.250071238964, 4857.392825896763, 4857.757112144396, 4866.197077482211, 4868.488808675092, 4284.914301713949, 4297.935530967006, 4300.505441874162, 4302.784860756981, 4311.439512863339, 5921.703914804242, 5961.463468287875, 5980.790672326491, 6004.819855404345, 6020.307664618581, 5733.9678857805575, 5734.913976290359, 5743.597948143632, 5756.107803290497, 5757.280339957505, 6693.899591506131, 6702.564333318367, 6703.095082163894, 6710.825605519063, 6733.393994540465, 4806.134895718769, 4828.106800241966, 4837.1776797944085, 4845.4185497740145, 4845.951540484585, 6938.0, 7006.649667516604, 7058.7647161747, 7079.575225486487, 7080.610566418845, 4925.605951523869, 4966.354373931365, 4975.800967961241, 4981.452040275567, 4982.252662100642, 5046.142155833517, 5047.97476012625, 5049.545540901337, 5054.646174767731, 5065.696058236517, 5660.745266463019, 5755.884882302331, 5756.0, 5760.452756988079, 5765.221695071195, 6748.966255168692, 6813.7955861324235, 6828.317168283232, 6831.829204269933, 6842.452603791685, 1726.5510967148632, 1784.9375271865422, 1786.3211979447726, 1786.490850107713, 1791.426743442086, 1207.4144040140525, 1214.319980810731, 1218.670958841108, 1219.8231256467761, 1239.6652903716126, 6327.367263273636, 6364.968175159094, 6378.394052564999, 6400.935990640034, 6402.327755585597, 6904.965475172592, 6905.447564194852, 6915.827104322432, 6923.376896079276, 6931.618760968196, 6260.342664020303, 6382.393672601095, 6390.169277993924, 6392.744290228339, 6398.616083035033, 3714.331420344364, 3718.665320121148, 3729.4778730977687, 3743.082944678571, 3755.6432138946757, 7075.787726368218, 7121.359077683033, 7127.394171495377, 7150.034745309421, 7151.392131668762, 6929.826754331081, 7106.786796396116, 7120.465965052642, 7127.714891404389, 7137.464690148259, 7062.92937070633, 7089.3265139812665, 7114.217436082027, 7119.21688614252, 7123.679434425463, 6832.487563432862, 6853.348931851466, 6854.485913556404, 6907.654617269217, 6907.758228461979, 6694.698738556679, 6821.965890170517, 6833.658317084027, 6836.897446538405, 6843.863122737418, 7043.823904402431, 7062.329078737512, 7104.644767761694, 7104.715811367694, 7119.394851437479, 4825.855224343275, 4877.6585639004925, 4881.633877459134, 4886.975565122151, 4887.0, 6822.965885170543, 6832.59004459734, 6928.584284942718, 6991.615461149686, 6997.860042799217, 6806.115205023315, 6806.9319306808275, 6838.692258848463, 6846.178458585002, 6846.246912839683, 6763.256041835492, 6797.4901882358035, 6816.113905192294, 6842.144731908512, 6843.965780171067, 6592.80221593353, 6718.462522998051, 6726.428253598499, 6738.66306684654, 6756.831079223059, 6877.65611719422, 6882.518223724492, 6887.276835932155, 6896.689648965708, 6898.655067246718, 6476.838079048061, 6630.0, 6700.66496675174, 6700.899486507804, 6722.865542689216], "unit": "score", "name": "prototype.html", "value": 5124.718989160809}, {"lowerIsBetter": false, "replicates": [830.8089139497915, 847.2882778466087, 854.8418542569625, 862.4911302331623, 870.8650159225318, 13769.655758606028, 13832.446702131927, 13859.029867909263, 13875.30623468825, 13993.790093148598, 13460.596182114552, 13482.730345393085, 13508.121972071809, 13528.25594592297, 13539.796903046437, 863.3956230638547, 904.3488688144525, 909.2862103248918, 909.4043401571948, 914.5930061122802, 900.8783814185099, 903.7966457547066, 904.2901322461869, 906.3519583497798, 907.918287354138, 943.5848226780229, 945.1635302757076, 945.3666043750703, 950.1638558068889, 951.6288647427509, 935.7520257131865, 936.536414474836, 941.3269512298705, 944.2163004706111, 944.4239014201331, 697.5919087333916, 700.3066963705894, 700.5866538742166, 702.0627462337785, 702.6486756621689, 14576.344064517121, 14583.489577864724, 14682.486112986102, 14698.853011469855, 14716.55850324492, 13423.597292081253, 13445.32773361328, 13445.932770336085, 13510.932445337809, 13790.586282411543, 10205.285630005903, 11994.969500118787, 12017.218880772809, 12344.5062197512, 12599.748005039848, 3801.6958643308744, 3825.3114439400897, 3835.7506762060375, 3849.0377405648587, 3854.9036274093373, 50665.49334506645, 50892.0, 50967.0, 51044.0, 51162.74418627883, 10706.25056246071, 10717.410542420086, 10875.238733288594, 11066.687831107805, 11202.383868887126, 18826.870387776777, 18889.149988250563, 18929.621407571773, 18967.336143235196, 18984.52538686544, 8046.114927357986, 8231.629576669064, 8242.793930151794, 8257.174282571816, 8322.750317490485, 4394.0, 4491.88770280739, 4534.342520334599, 4562.338460923214, 4595.770211489412, 3208.069659798679, 3214.2125179331215, 3220.500822372536, 3241.2383089973837, 3243.7242834359345, 598.455405580928, 599.7570983751588, 602.135934933372, 615.9507239420835, 618.0482057631287, 647.0, 662.7978466567744, 664.833791552112, 678.1624693503519, 699.0946723992442, 8915.42049766763, 9028.368014239071, 9099.863502047474, 9137.086291370942, 9147.131022552847, 571.9714014299352, 620.9130721698966, 651.4267444648774, 658.7562601837351, 703.9824004399828, 4364.8908777280185, 4398.318260669537, 4486.7083639563325, 4529.0, 4560.657950653648, 58351.41648583545, 58516.7074164635, 62176.0, 62439.6878015607, 63623.0, 49519.75240123848, 50177.49822501728, 50272.0, 50542.24186637203, 51217.23174152391, 48102.75948620235, 49244.75377623089, 49808.0, 49889.501104988485, 49902.0, 9394.15452609241, 9448.244140468609, 9517.191038761506, 9665.081817227352, 9669.371490853353, 35215.82392088023, 35417.64582354143, 35812.28375432425, 35990.99999999895, 36211.45682814707, 43008.56991430045, 46552.7672361636, 46826.53173468222, 46998.76500617475, 47793.0, 11266.887331126583, 11535.769284614093, 11771.23486973344, 11908.880911190778, 11984.161108722654, 13426.932865335611, 13440.731185376433, 13669.931650341685, 13790.555317002978, 13839.654008649864, 11953.880461195624, 12574.937125314314, 12842.0, 13127.868721312665, 13303.534376296782, 12153.635390938285, 12237.938810305892, 12283.815742764044, 12353.505859765508, 12632.621021369374, 11741.706457338634, 11786.292822430682, 11997.040236780862, 12024.398780061138, 12485.812712809133, 10594.735131621772, 10808.243422960317, 10813.297135686154, 10830.945845270724, 11021.889781102087, 10433.478326083818, 10612.946935265274, 10884.346939183675, 11444.42777861087, 11455.713607159887], "unit": "score", "name": "yui.html", "value": 6779.367003575905}], "name": "dromaeo_css", "value": 7399.320188220479}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 106.0, 111.0, 111.0, 113.0, 122.0, 109.0, 111.0, 113.0, 110.0], "unit": "score", "name": "ai-astar", "value": 111.7}, {"lowerIsBetter": true, "replicates": [153.0, 125.0, 149.0, 162.0, 125.0, 155.0, 152.0, 135.0, 162.0, 130.0], "unit": "score", "name": "audio-beat-detection", "value": 144.8}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 162.0, 163.0, 161.0, 161.0, 164.0, 162.0, 164.0, 160.0], "unit": "score", "name": "audio-dft", "value": 162.0}, {"lowerIsBetter": true, "replicates": [74.0, 77.0, 76.0, 154.0, 72.0, 73.0, 76.0, 73.0, 145.0, 74.0], "unit": "score", "name": "audio-fft", "value": 89.4}, {"lowerIsBetter": true, "replicates": [98.0, 96.0, 142.0, 95.0, 95.0, 96.0, 147.0, 95.0, 95.0, 139.0], "unit": "score", "name": "audio-oscillator", "value": 109.8}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 110.0, 109.0, 109.0, 109.0, 110.0, 110.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 150.0, 148.0, 148.0, 147.0, 146.0, 148.0, 148.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 147.4}, {"lowerIsBetter": true, "replicates": [97.0, 97.0, 96.0, 96.0, 97.0, 97.0, 98.0, 97.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.7}, {"lowerIsBetter": true, "replicates": [59.0, 56.0, 63.0, 58.0, 60.0, 58.0, 55.0, 59.0, 55.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 57.8}, {"lowerIsBetter": true, "replicates": [64.0, 66.0, 65.0, 65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 65.1}, {"lowerIsBetter": true, "replicates": [79.0, 86.0, 86.0, 79.0, 78.0, 78.0, 80.0, 78.0, 78.0, 79.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.1}, {"lowerIsBetter": true, "replicates": [118.0, 124.0, 112.0, 117.0, 122.0, 112.0, 112.0, 110.0, 115.0, 124.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 116.6}, {"lowerIsBetter": true, "replicates": [130.0, 150.0, 140.0, 135.0, 136.0, 142.0, 140.0, 134.0, 126.0, 127.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 136.0}, {"lowerIsBetter": true, "replicates": [71.0, 66.0, 65.0, 66.0, 64.0, 66.0, 64.0, 64.0, 64.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.6}], "name": "kraken", "value": 1492.6999999999996}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.49199663016006], "unit": "score", "name": "Richards", "value": 209.51736999999997}, {"lowerIsBetter": false, "replicates": [167.0564650851988], "unit": "score", "name": "DeltaBlue", "value": 395.7823480000001}, {"lowerIsBetter": false, "replicates": [278.55153203342616], "unit": "score", "name": "Encrypt", "value": 955.5897900000004}, {"lowerIsBetter": false, "replicates": [5186.5284974093265], "unit": "score", "name": "Decrypt", "value": 51.32161138861136}, {"lowerIsBetter": false, "replicates": [936.3295880149813], "unit": "score", "name": "RayTrace", "value": 790.3082519999997}, {"lowerIsBetter": false, "replicates": [564.0157924421884], "unit": "score", "name": "Earley", "value": 1181.6388990000003}, {"lowerIsBetter": false, "replicates": [11000.0], "unit": "score", "name": "Boyer", "value": 60.58754545454549}, {"lowerIsBetter": false, "replicates": [39365.38461538462], "unit": "score", "name": "RegExp", "value": 23.141778212017584}, {"lowerIsBetter": false, "replicates": [495.5401387512388], "unit": "score", "name": "Splay", "value": 164.44883800000005}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 22148.33970872898}]} 18:11:06 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,43]]", "result": "22148.34"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,43]]", "result": "1492.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,43]]", "result": "7399.32"}}} 18:11:06 INFO - Return code: 0 18:11:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 18:11:06 INFO - # TBPL SUCCESS # 18:11:06 INFO - Running post-action listener: _resource_record_post_action 18:11:06 INFO - Running post-run listener: _resource_record_post_run 18:11:07 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 0; Write bytes: 501325824; Read time: 0; Write time: 604476 18:11:07 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 248528896; Read time: 0; Write time: 440864 18:11:07 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 0; Write bytes: 252796928; Read time: 0; Write time: 163612 18:11:07 INFO - Running post-run listener: _upload_blobber_files 18:11:07 INFO - Blob upload gear active. 18:11:07 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 18:11:07 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 18:11:07 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'] 18:11:07 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 18:11:07 INFO - (blobuploader) - INFO - Open directory for files ... 18:11:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:11:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:11:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:11:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:11:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:11:08 INFO - (blobuploader) - INFO - Done attempting. 18:11:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:11:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:11:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:11:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:11:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:11:10 INFO - (blobuploader) - INFO - Done attempting. 18:11:10 INFO - (blobuploader) - INFO - Iteration through files over. 18:11:10 INFO - Return code: 0 18:11:10 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 18:11:10 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 18:11:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd9b63c0eb77452bbf616d9a6c48a8fadb709fe825f06c7f87e8d271611dc3d43a14f9c955efa69f3f678dff32be7f31d9beeab815901ca3f21e395b021037a9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b68bff12b56cd442dc861669c3348f12632b987ba24421fc0827f94d1cdd7e99761981ec3fd7e55229222b79e50e569c3efd5b59f8c833e0a8f528535afad1dc"} 18:11:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 18:11:10 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 18:11:10 INFO - Contents: 18:11:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dd9b63c0eb77452bbf616d9a6c48a8fadb709fe825f06c7f87e8d271611dc3d43a14f9c955efa69f3f678dff32be7f31d9beeab815901ca3f21e395b021037a9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b68bff12b56cd442dc861669c3348f12632b987ba24421fc0827f94d1cdd7e99761981ec3fd7e55229222b79e50e569c3efd5b59f8c833e0a8f528535afad1dc"} 18:11:10 INFO - Running post-run listener: copy_logs_to_upload_dir 18:11:10 INFO - Copying logs to upload dir... 18:11:10 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 18:11:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1324.437838 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 4 secs) (at 2016-03-02 18:11:10.556171) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-02 18:11:10.559746) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290 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/dd9b63c0eb77452bbf616d9a6c48a8fadb709fe825f06c7f87e8d271611dc3d43a14f9c955efa69f3f678dff32be7f31d9beeab815901ca3f21e395b021037a9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b68bff12b56cd442dc861669c3348f12632b987ba24421fc0827f94d1cdd7e99761981ec3fd7e55229222b79e50e569c3efd5b59f8c833e0a8f528535afad1dc"} build_url:https://queue.taskcluster.net/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011439 build_url: 'https://queue.taskcluster.net/v1/task/GW2DxWa2SpCRvx2dtKDmXQ/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/dd9b63c0eb77452bbf616d9a6c48a8fadb709fe825f06c7f87e8d271611dc3d43a14f9c955efa69f3f678dff32be7f31d9beeab815901ca3f21e395b021037a9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b68bff12b56cd442dc861669c3348f12632b987ba24421fc0827f94d1cdd7e99761981ec3fd7e55229222b79e50e569c3efd5b59f8c833e0a8f528535afad1dc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-02 18:11:10.588810) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 18:11:10.589431) ========= 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=74d6aec007b0fa31a864ebd7000001f8-1456969744.558986-1026242290 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004443 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-02 18:11:10.670501) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-02 18:11:10.670944) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-02 18:11:10.671389) ========= ========= Total master_lag: 0.27 =========