builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-022 starttime: 1456424373.14 results: success (0) buildid: 20160225080027 builduid: 54145201b5994270ad10195001ba91fd revision: fc6338c484fe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.140932) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.141363) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.141681) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.009219 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.168149) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.168698) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.191691) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.192306) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004583 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.242831) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.243134) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.243971) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.244676) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-25 10:19:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.7M=0s 2016-02-25 10:19:33 (43.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.177383 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.438946) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.439658) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017483 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.493523) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:33.494306) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fc6338c484fe --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-beta --rev fc6338c484fe --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=9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-25 10:19:33,719 Setting DEBUG logging. 2016-02-25 10:19:33,719 attempt 1/10 2016-02-25 10:19:33,719 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/fc6338c484fe?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-25 10:19:34,006 unpacking tar archive at: mozilla-beta-fc6338c484fe/testing/mozharness/ program finished with exit code 0 elapsedTime=0.587867 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:34.099498) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:34.099896) ========= echo fc6338c484fe in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'fc6338c484fe'] 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=9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False fc6338c484fe program finished with exit code 0 elapsedTime=0.004684 script_repo_revision: 'fc6338c484fe' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:34.149920) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:34.150281) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-25 10:19:34.159905) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-02-25 10:19:34.160242) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:19:34 INFO - MultiFileLogger online at 20160225 10:19:34 in /builds/slave/test-pgo 10:19:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:19:34 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 10:19:34 INFO - {'append_to_log': False, 10:19:34 INFO - 'base_work_dir': '/builds/slave/test-pgo', 10:19:34 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:19:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 10:19:34 INFO - 'branch': 'Mozilla-Beta', 10:19:34 INFO - 'buildbot_json_path': 'buildprops.json', 10:19:34 INFO - 'config_files': ('talos/linux_config.py',), 10:19:34 INFO - 'default_actions': ('clobber', 10:19:34 INFO - 'read-buildbot-config', 10:19:34 INFO - 'download-and-extract', 10:19:34 INFO - 'populate-webroot', 10:19:34 INFO - 'create-virtualenv', 10:19:34 INFO - 'install', 10:19:34 INFO - 'run-tests'), 10:19:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:19:34 INFO - 'download_minidump_stackwalk': True, 10:19:34 INFO - 'download_symbols': 'ondemand', 10:19:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:19:34 INFO - 'tooltool.py': '/tools/tooltool.py', 10:19:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:19:34 INFO - '/tools/misc-python/virtualenv.py')}, 10:19:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:19:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:19:34 INFO - 'installer_path': 'installer.exe', 10:19:34 INFO - 'log_level': 'info', 10:19:34 INFO - 'log_name': 'talos', 10:19:34 INFO - 'log_to_console': True, 10:19:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:19:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:19:34 INFO - 'opt_config_files': (), 10:19:34 INFO - 'pip_index': False, 10:19:34 INFO - 'sps_profile': False, 10:19:34 INFO - 'sps_profile_interval': 0, 10:19:34 INFO - 'suite': 'dromaeojs', 10:19:34 INFO - 'system_bits': '32', 10:19:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:19:34 INFO - 'title': 'talos-linux64-ix-022', 10:19:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:19:34 INFO - 'use_talos_json': True, 10:19:34 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 10:19:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:19:34 INFO - 'work_dir': 'build'} 10:19:34 INFO - ##### 10:19:34 INFO - ##### Running clobber step. 10:19:34 INFO - ##### 10:19:34 INFO - Running pre-action listener: _resource_record_pre_action 10:19:34 INFO - Running main action method: clobber 10:19:34 INFO - rmtree: /builds/slave/test-pgo/build 10:19:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 10:19:35 INFO - Running post-action listener: _resource_record_post_action 10:19:35 INFO - ##### 10:19:35 INFO - ##### Running read-buildbot-config step. 10:19:35 INFO - ##### 10:19:35 INFO - Running pre-action listener: _resource_record_pre_action 10:19:35 INFO - Running main action method: read_buildbot_config 10:19:35 INFO - Using buildbot properties: 10:19:35 INFO - { 10:19:35 INFO - "properties": { 10:19:35 INFO - "buildnumber": 100, 10:19:35 INFO - "product": "firefox", 10:19:35 INFO - "script_repo_revision": "production", 10:19:35 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 10:19:35 INFO - "repository": "", 10:19:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 10:19:35 INFO - "buildid": "20160225080027", 10:19:35 INFO - "slavename": "talos-linux64-ix-022", 10:19:35 INFO - "pgo_build": "True", 10:19:35 INFO - "basedir": "/builds/slave/test-pgo", 10:19:35 INFO - "project": "", 10:19:35 INFO - "platform": "ubuntu64_hw", 10:19:35 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 10:19:35 INFO - "slavebuilddir": "test", 10:19:35 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 10:19:35 INFO - "branch": "mozilla-beta", 10:19:35 INFO - "repo_path": "releases/mozilla-beta", 10:19:35 INFO - "stage_platform": "linux64-pgo", 10:19:35 INFO - "builduid": "54145201b5994270ad10195001ba91fd", 10:19:35 INFO - "revision": "fc6338c484fe" 10:19:35 INFO - }, 10:19:35 INFO - "sourcestamp": { 10:19:35 INFO - "repository": "", 10:19:35 INFO - "hasPatch": false, 10:19:35 INFO - "project": "", 10:19:35 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:19:35 INFO - "changes": [ 10:19:35 INFO - { 10:19:35 INFO - "category": null, 10:19:35 INFO - "files": [ 10:19:35 INFO - { 10:19:35 INFO - "url": null, 10:19:35 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.tar.bz2" 10:19:35 INFO - } 10:19:35 INFO - ], 10:19:35 INFO - "repository": "", 10:19:35 INFO - "rev": "fc6338c484fe", 10:19:35 INFO - "who": "sendchange", 10:19:35 INFO - "when": 1456424277, 10:19:35 INFO - "number": 7654246, 10:19:35 INFO - "comments": "Bug 1238682 - As a desktop client user, when in a Hello session opening a new tab should open about:home r=mikedeboer, a=sylvestre", 10:19:35 INFO - "project": "", 10:19:35 INFO - "at": "Thu 25 Feb 2016 10:17:57", 10:19:35 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 10:19:35 INFO - "revlink": "", 10:19:35 INFO - "properties": [ 10:19:35 INFO - [ 10:19:35 INFO - "buildid", 10:19:35 INFO - "20160225080027", 10:19:35 INFO - "Change" 10:19:35 INFO - ], 10:19:35 INFO - [ 10:19:35 INFO - "builduid", 10:19:35 INFO - "54145201b5994270ad10195001ba91fd", 10:19:35 INFO - "Change" 10:19:35 INFO - ], 10:19:35 INFO - [ 10:19:35 INFO - "pgo_build", 10:19:35 INFO - "True", 10:19:35 INFO - "Change" 10:19:35 INFO - ] 10:19:35 INFO - ], 10:19:35 INFO - "revision": "fc6338c484fe" 10:19:35 INFO - } 10:19:35 INFO - ], 10:19:35 INFO - "revision": "fc6338c484fe" 10:19:35 INFO - } 10:19:35 INFO - } 10:19:35 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.tar.bz2. 10:19:35 INFO - Running post-action listener: _resource_record_post_action 10:19:35 INFO - ##### 10:19:35 INFO - ##### Running download-and-extract step. 10:19:35 INFO - ##### 10:19:35 INFO - Running pre-action listener: _resource_record_pre_action 10:19:35 INFO - Running main action method: download_and_extract 10:19:35 INFO - mkdir: /builds/slave/test-pgo/build/tests 10:19: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')]} 10:19:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/test_packages.json 10:19:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 10:19:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 10:19:35 INFO - Downloaded 1270 bytes. 10:19:35 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 10:19:35 INFO - Using the following test package requirements: 10:19:35 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:19:35 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:19:35 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 10:19:35 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:19:35 INFO - u'jsshell-linux-x86_64.zip'], 10:19:35 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:19:35 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 10:19:35 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:19:35 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:19:35 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 10:19:35 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:19:35 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 10:19:35 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:19:35 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 10:19:35 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 10:19:35 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 10:19:35 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 10:19:35 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:19: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')]} 10:19:35 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:19:35 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:19:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:19:35 INFO - Downloaded 21584449 bytes. 10:19:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 10:19:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:19:35 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 10:19:36 INFO - Return code: 0 10:19:36 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 10:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:36 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:19:36 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:19:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 10:19:37 INFO - Downloaded 21584449 bytes. 10:19:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 10:19:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 10:19:37 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 10:19:39 INFO - Return code: 0 10:19:39 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')]} 10:19:39 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:19:39 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:19:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 10:19:39 INFO - Downloaded 11132038 bytes. 10:19:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 10:19:39 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 10:19:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 10:19:39 INFO - Return code: 0 10:19:39 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')]} 10:19:39 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:19:39 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:19:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 10:19:42 INFO - Downloaded 57944313 bytes. 10:19:42 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:19:42 INFO - mkdir: /builds/slave/test-pgo/properties 10:19:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 10:19:42 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 10:19:42 INFO - Contents: 10:19:42 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.tar.bz2 10:19:42 INFO - Running post-action listener: _resource_record_post_action 10:19:42 INFO - Running post-action listener: set_extra_try_arguments 10:19:42 INFO - ##### 10:19:42 INFO - ##### Running populate-webroot step. 10:19:42 INFO - ##### 10:19:42 INFO - Running pre-action listener: _resource_record_pre_action 10:19:42 INFO - Running main action method: populate_webroot 10:19:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:19:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:19:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:19:42 INFO - '--tppagecycles', 10:19:42 INFO - '7'], 10:19:42 INFO - 'tests': ['tsvgm']}}, 10:19:42 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 10:19:42 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 10:19:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:19:42 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:19:42 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 10:19:42 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 10:19:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:19:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:42 INFO - 'talos_options': ['--e10s'], 10:19:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:19:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:42 INFO - 'tests': ['damp', 'tps']}, 10:19:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:42 INFO - 'talos_options': ['--e10s'], 10:19:42 INFO - 'tests': ['damp', 'tps']}, 10:19:42 INFO - 'g3': {'tests': ['dromaeo_dom']}, 10:19:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:19:42 INFO - 'other': {'tests': ['a11yr', 10:19:42 INFO - 'ts_paint', 10:19:42 INFO - 'tpaint', 10:19:42 INFO - 'sessionrestore', 10:19:42 INFO - 'sessionrestore_no_auto_restore']}, 10:19:42 INFO - 'other-e10s': {'talos_options': ['--e10s'], 10:19:42 INFO - 'tests': ['a11yr', 10:19:42 INFO - 'ts_paint', 10:19:42 INFO - 'tpaint', 10:19:42 INFO - 'sessionrestore', 10:19:42 INFO - 'sessionrestore_no_auto_restore']}, 10:19:42 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 10:19:42 INFO - 'tests': ['a11yr', 10:19:42 INFO - 'ts_paint', 10:19:42 INFO - 'tpaint', 10:19:42 INFO - 'sessionrestore', 10:19:42 INFO - 'sessionrestore_no_auto_restore']}, 10:19:42 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 10:19:42 INFO - 'tests': ['a11yr', 10:19:42 INFO - 'ts_paint', 10:19:42 INFO - 'tpaint', 10:19:42 INFO - 'sessionrestore', 10:19:42 INFO - 'sessionrestore_no_auto_restore']}, 10:19:42 INFO - 'other_l64': {'tests': ['a11yr', 10:19:42 INFO - 'ts_paint', 10:19:42 INFO - 'tpaint', 10:19:42 INFO - 'sessionrestore', 10:19:42 INFO - 'sessionrestore_no_auto_restore']}, 10:19:42 INFO - 'other_nol64': {'tests': ['a11yr', 10:19:42 INFO - 'ts_paint', 10:19:42 INFO - 'tpaint', 10:19:42 INFO - 'sessionrestore', 10:19:42 INFO - 'sessionrestore_no_auto_restore']}, 10:19:42 INFO - 'svgr': {'tests': ['tsvgx', 10:19:42 INFO - 'tsvgr_opacity', 10:19:42 INFO - 'tart', 10:19:42 INFO - 'tscrollx', 10:19:42 INFO - 'cart']}, 10:19:42 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 10:19:42 INFO - 'tests': ['tsvgx', 10:19:42 INFO - 'tsvgr_opacity', 10:19:42 INFO - 'tart', 10:19:42 INFO - 'tscrollx', 10:19:42 INFO - 'cart']}, 10:19:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:42 INFO - 'tests': ['tp5o']}, 10:19:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:42 INFO - 'talos_options': ['--e10s'], 10:19:42 INFO - 'tests': ['tp5o']}, 10:19:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:42 INFO - 'talos_options': ['--xperf_path', 10:19:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:19:42 INFO - 'tests': ['tp5n']}, 10:19:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:19:42 INFO - 'talos_options': ['--e10s', 10:19:42 INFO - '--xperf_path', 10:19:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:19:42 INFO - 'tests': ['tp5n']}}, 10:19:42 INFO - 'talos.zip': {'path': '', 10:19:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:19:42 INFO - Running post-action listener: _resource_record_post_action 10:19:42 INFO - ##### 10:19:42 INFO - ##### Running create-virtualenv step. 10:19:42 INFO - ##### 10:19:42 INFO - Running pre-action listener: _resource_record_pre_action 10:19:42 INFO - Running main action method: create_virtualenv 10:19:42 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 10:19:42 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 10:19:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 10:19:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:19:42 INFO - Using real prefix '/usr' 10:19:42 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 10:19:43 INFO - Installing distribute.............................................................................................................................................................................................done. 10:19:45 INFO - Installing pip.................done. 10:19:45 INFO - Return code: 0 10:19:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 10:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:45 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 0x7f11272cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2185600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214350>, '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 0x2215250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22156e0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', '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 10:19:45 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 10:19:45 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 10:19:45 INFO - Using env: {'DISPLAY': ':0', 10:19:45 INFO - 'HOME': '/home/cltbld', 10:19:45 INFO - 'LANG': 'en_US.UTF-8', 10:19:45 INFO - 'LANGUAGE': 'en_US:en', 10:19:45 INFO - 'LOGNAME': 'cltbld', 10:19:45 INFO - 'MAIL': '/var/mail/cltbld', 10:19:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:45 INFO - 'MOZ_NO_REMOTE': '1', 10:19:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:19:45 INFO - 'NO_EM_RESTART': '1', 10:19:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:19:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:19:45 INFO - 'PWD': '/builds/slave/test-pgo', 10:19:45 INFO - 'SHELL': '/bin/bash', 10:19:45 INFO - 'SHLVL': '1', 10:19:45 INFO - 'TERM': 'linux', 10:19:45 INFO - 'TMOUT': '86400', 10:19:45 INFO - 'USER': 'cltbld', 10:19:45 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', 10:19:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:45 INFO - '_': '/tools/buildbot/bin/python'} 10:19:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:45 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 10:19:45 INFO - Cleaning up... 10:19:45 INFO - Return code: 0 10:19:45 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 10:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:45 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 0x7f11272cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2185600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214350>, '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 0x2215250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22156e0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', '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 10:19:45 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 10:19:45 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 10:19:45 INFO - Using env: {'DISPLAY': ':0', 10:19:45 INFO - 'HOME': '/home/cltbld', 10:19:45 INFO - 'LANG': 'en_US.UTF-8', 10:19:45 INFO - 'LANGUAGE': 'en_US:en', 10:19:45 INFO - 'LOGNAME': 'cltbld', 10:19:45 INFO - 'MAIL': '/var/mail/cltbld', 10:19:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:45 INFO - 'MOZ_NO_REMOTE': '1', 10:19:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:19:45 INFO - 'NO_EM_RESTART': '1', 10:19:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:19:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:19:45 INFO - 'PWD': '/builds/slave/test-pgo', 10:19:45 INFO - 'SHELL': '/bin/bash', 10:19:45 INFO - 'SHLVL': '1', 10:19:45 INFO - 'TERM': 'linux', 10:19:45 INFO - 'TMOUT': '86400', 10:19:45 INFO - 'USER': 'cltbld', 10:19:45 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', 10:19:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:45 INFO - '_': '/tools/buildbot/bin/python'} 10:19:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:45 INFO - Downloading/unpacking psutil>=0.7.1 10:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:47 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 10:19:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:19:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:19:47 INFO - Installing collected packages: psutil 10:19:47 INFO - Running setup.py install for psutil 10:19:48 INFO - building 'psutil._psutil_linux' extension 10:19:48 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 10:19:48 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 10:19:48 INFO - building 'psutil._psutil_posix' extension 10:19:48 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 10:19:48 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 10:19:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:19:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:19:48 INFO - Successfully installed psutil 10:19:48 INFO - Cleaning up... 10:19:48 INFO - Return code: 0 10:19:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 10:19:48 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')]} 10:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:48 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')]} 10:19:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:48 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 0x7f11272cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2185600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214350>, '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 0x2215250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22156e0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', '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 10:19:48 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 10:19:48 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 10:19:48 INFO - Using env: {'DISPLAY': ':0', 10:19:48 INFO - 'HOME': '/home/cltbld', 10:19:48 INFO - 'LANG': 'en_US.UTF-8', 10:19:48 INFO - 'LANGUAGE': 'en_US:en', 10:19:48 INFO - 'LOGNAME': 'cltbld', 10:19:48 INFO - 'MAIL': '/var/mail/cltbld', 10:19:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:48 INFO - 'MOZ_NO_REMOTE': '1', 10:19:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:19:48 INFO - 'NO_EM_RESTART': '1', 10:19:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:19:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:19:48 INFO - 'PWD': '/builds/slave/test-pgo', 10:19:48 INFO - 'SHELL': '/bin/bash', 10:19:48 INFO - 'SHLVL': '1', 10:19:48 INFO - 'TERM': 'linux', 10:19:48 INFO - 'TMOUT': '86400', 10:19:48 INFO - 'USER': 'cltbld', 10:19:48 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', 10:19:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:48 INFO - '_': '/tools/buildbot/bin/python'} 10:19:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:19:48 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 10:19:48 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 10:19:48 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 10:19:48 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 10:19:48 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 10:19:48 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 10:19:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:19:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:19:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:19:50 INFO - Installing collected packages: mozsystemmonitor 10:19:50 INFO - Running setup.py install for mozsystemmonitor 10:19:50 INFO - Successfully installed mozsystemmonitor 10:19:50 INFO - Cleaning up... 10:19:50 INFO - Return code: 0 10:19:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 10:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:50 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 0x7f11272cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2185600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214350>, '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 0x2215250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22156e0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', '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 10:19:50 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 10:19:50 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 10:19:50 INFO - Using env: {'DISPLAY': ':0', 10:19:50 INFO - 'HOME': '/home/cltbld', 10:19:50 INFO - 'LANG': 'en_US.UTF-8', 10:19:50 INFO - 'LANGUAGE': 'en_US:en', 10:19:50 INFO - 'LOGNAME': 'cltbld', 10:19:50 INFO - 'MAIL': '/var/mail/cltbld', 10:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:50 INFO - 'MOZ_NO_REMOTE': '1', 10:19:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:19:50 INFO - 'NO_EM_RESTART': '1', 10:19:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:19:50 INFO - 'PWD': '/builds/slave/test-pgo', 10:19:50 INFO - 'SHELL': '/bin/bash', 10:19:50 INFO - 'SHLVL': '1', 10:19:50 INFO - 'TERM': 'linux', 10:19:50 INFO - 'TMOUT': '86400', 10:19:50 INFO - 'USER': 'cltbld', 10:19:50 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', 10:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:50 INFO - '_': '/tools/buildbot/bin/python'} 10:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:50 INFO - Downloading/unpacking blobuploader==1.2.4 10:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:52 INFO - Downloading blobuploader-1.2.4.tar.gz 10:19:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:19:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 10:19:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:19:53 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 10:19:53 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 10:19:53 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 10:19:53 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 10:19:53 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 10:19:53 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 10:19:53 INFO - Downloading docopt-0.6.1.tar.gz 10:19:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 10:19:53 INFO - Installing collected packages: blobuploader, requests, docopt 10:19:53 INFO - Running setup.py install for blobuploader 10:19:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:19:53 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 10:19:53 INFO - Running setup.py install for requests 10:19:53 INFO - Running setup.py install for docopt 10:19:54 INFO - Successfully installed blobuploader requests docopt 10:19:54 INFO - Cleaning up... 10:19:54 INFO - Return code: 0 10:19:54 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:54 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 0x7f11272cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2185600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214350>, '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 0x2215250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22156e0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', '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 10:19:54 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 10:19:54 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 10:19:54 INFO - Using env: {'DISPLAY': ':0', 10:19:54 INFO - 'HOME': '/home/cltbld', 10:19:54 INFO - 'LANG': 'en_US.UTF-8', 10:19:54 INFO - 'LANGUAGE': 'en_US:en', 10:19:54 INFO - 'LOGNAME': 'cltbld', 10:19:54 INFO - 'MAIL': '/var/mail/cltbld', 10:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:54 INFO - 'MOZ_NO_REMOTE': '1', 10:19:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:19:54 INFO - 'NO_EM_RESTART': '1', 10:19:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:19:54 INFO - 'PWD': '/builds/slave/test-pgo', 10:19:54 INFO - 'SHELL': '/bin/bash', 10:19:54 INFO - 'SHLVL': '1', 10:19:54 INFO - 'TERM': 'linux', 10:19:54 INFO - 'TMOUT': '86400', 10:19:54 INFO - 'USER': 'cltbld', 10:19:54 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', 10:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:54 INFO - '_': '/tools/buildbot/bin/python'} 10:19:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:19:54 INFO - Running setup.py (path:/tmp/pip-bzw2vr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:19:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:19:54 INFO - Running setup.py (path:/tmp/pip-DV8bN3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:19:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:19:54 INFO - Running setup.py (path:/tmp/pip-ZEEGND-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:19:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:19:54 INFO - Running setup.py (path:/tmp/pip-cdCB8F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:19:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:19:54 INFO - Running setup.py (path:/tmp/pip-Qs_rtN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:19:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:19:54 INFO - Running setup.py (path:/tmp/pip-2Lv6_y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:19:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:19:54 INFO - Running setup.py (path:/tmp/pip-Y2pyTx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:19:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:19:54 INFO - Running setup.py (path:/tmp/pip-oOJsfX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:19:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:19:54 INFO - Running setup.py (path:/tmp/pip-g48Mwn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:19:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:19:55 INFO - Running setup.py (path:/tmp/pip-UntmMp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:19:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:19:55 INFO - Running setup.py (path:/tmp/pip-3STF_M-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:19:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:19:55 INFO - Running setup.py (path:/tmp/pip-0RkhOD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:19:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:19:55 INFO - Running setup.py (path:/tmp/pip-6ypRKj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:19:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:19:55 INFO - Running setup.py (path:/tmp/pip-_RdEdb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:19:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:19:55 INFO - Running setup.py (path:/tmp/pip-IYYnVB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:19:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:19:55 INFO - Running setup.py (path:/tmp/pip-zEJRAq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:19:55 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:19:55 INFO - Running setup.py (path:/tmp/pip-UOYLfN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:19:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:19:55 INFO - Running setup.py install for manifestparser 10:19:55 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 10:19:55 INFO - Running setup.py install for mozcrash 10:19:55 INFO - Running setup.py install for mozdebug 10:19:55 INFO - Running setup.py install for mozdevice 10:19:56 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 10:19:56 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 10:19:56 INFO - Running setup.py install for mozfile 10:19:56 INFO - Running setup.py install for mozhttpd 10:19:56 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 10:19:56 INFO - Running setup.py install for mozinfo 10:19:56 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 10:19:56 INFO - Running setup.py install for mozInstall 10:19:56 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 10:19:56 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 10:19:56 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 10:19:56 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 10:19:56 INFO - Running setup.py install for mozleak 10:19:56 INFO - Running setup.py install for mozlog 10:19:56 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 10:19:56 INFO - Running setup.py install for moznetwork 10:19:56 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 10:19:56 INFO - Running setup.py install for mozprocess 10:19:56 INFO - Running setup.py install for mozprofile 10:19:56 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 10:19:56 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 10:19:56 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 10:19:56 INFO - Running setup.py install for mozrunner 10:19:57 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 10:19:57 INFO - Running setup.py install for mozscreenshot 10:19:57 INFO - Running setup.py install for moztest 10:19:57 INFO - Running setup.py install for mozversion 10:19:57 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 10:19:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:19:57 INFO - Cleaning up... 10:19:57 INFO - Return code: 0 10:19:57 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:19:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:19:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:19:57 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 0x7f11272cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2185600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214350>, '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 0x2215250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22156e0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', '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 10:19:57 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 10:19:57 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 10:19:57 INFO - Using env: {'DISPLAY': ':0', 10:19:57 INFO - 'HOME': '/home/cltbld', 10:19:57 INFO - 'LANG': 'en_US.UTF-8', 10:19:57 INFO - 'LANGUAGE': 'en_US:en', 10:19:57 INFO - 'LOGNAME': 'cltbld', 10:19:57 INFO - 'MAIL': '/var/mail/cltbld', 10:19:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:57 INFO - 'MOZ_NO_REMOTE': '1', 10:19:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:19:57 INFO - 'NO_EM_RESTART': '1', 10:19:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:19:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:19:57 INFO - 'PWD': '/builds/slave/test-pgo', 10:19:57 INFO - 'SHELL': '/bin/bash', 10:19:57 INFO - 'SHLVL': '1', 10:19:57 INFO - 'TERM': 'linux', 10:19:57 INFO - 'TMOUT': '86400', 10:19:57 INFO - 'USER': 'cltbld', 10:19:57 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', 10:19:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:57 INFO - '_': '/tools/buildbot/bin/python'} 10:19:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:19:57 INFO - Running setup.py (path:/tmp/pip-2i3zcZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 10:19:57 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)) 10:19:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:19:57 INFO - Running setup.py (path:/tmp/pip-PDRA1t-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 10:19:57 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)) 10:19:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:19:57 INFO - Running setup.py (path:/tmp/pip-7S4irY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 10:19:57 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)) 10:19:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:19:57 INFO - Running setup.py (path:/tmp/pip-TQUy4s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 10:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:19:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 10:19:57 INFO - Running setup.py (path:/tmp/pip-VfwFQa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 10:19:57 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)) 10:19:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:19:57 INFO - Running setup.py (path:/tmp/pip-xlc0qZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 10:19:58 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)) 10:19:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:19:58 INFO - Running setup.py (path:/tmp/pip-ExtkZJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 10:19:58 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)) 10:19:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:19:58 INFO - Running setup.py (path:/tmp/pip-J9JgiF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 10:19:58 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)) 10:19:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 10:19:58 INFO - Running setup.py (path:/tmp/pip-4sWPV0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 10:19:58 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)) 10:19:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 10:19:58 INFO - Running setup.py (path:/tmp/pip-Ak98Cw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 10:19:58 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)) 10:19:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:19:58 INFO - Running setup.py (path:/tmp/pip-Ht4h0S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 10:19:58 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)) 10:19:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:19:58 INFO - Running setup.py (path:/tmp/pip-CM0PWs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 10:19:58 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)) 10:19:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:19:58 INFO - Running setup.py (path:/tmp/pip-6LuKwH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 10:19:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 10:19:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:19:58 INFO - Running setup.py (path:/tmp/pip-ZVIhZJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 10:19:58 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)) 10:19:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:19:58 INFO - Running setup.py (path:/tmp/pip-K64pep-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 10:19:58 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)) 10:19:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 10:19:58 INFO - Running setup.py (path:/tmp/pip-vgbLzI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 10:19:58 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)) 10:19:58 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 10:19:58 INFO - Running setup.py (path:/tmp/pip-Tz7D02-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 10:19:58 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)) 10:19:58 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)) 10:19:58 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)) 10:19:58 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)) 10:19:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:19:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 10:19:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 10:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:19:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:00 INFO - Downloading blessings-1.6.tar.gz 10:20:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 10:20:00 INFO - Installing collected packages: blessings 10:20:00 INFO - Running setup.py install for blessings 10:20:00 INFO - Successfully installed blessings 10:20:00 INFO - Cleaning up... 10:20:00 INFO - Return code: 0 10:20:00 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 10:20:00 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:20:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:20:01 INFO - Reading from file tmpfile_stdout 10:20:01 INFO - Current package versions: 10:20:01 INFO - argparse == 1.2.1 10:20:01 INFO - blessings == 1.6 10:20:01 INFO - blobuploader == 1.2.4 10:20:01 INFO - docopt == 0.6.1 10:20:01 INFO - manifestparser == 1.1 10:20:01 INFO - mozInstall == 1.12 10:20:01 INFO - mozcrash == 0.16 10:20:01 INFO - mozdebug == 0.1 10:20:01 INFO - mozdevice == 0.47 10:20:01 INFO - mozfile == 1.2 10:20:01 INFO - mozhttpd == 0.7 10:20:01 INFO - mozinfo == 0.9 10:20:01 INFO - mozleak == 0.1 10:20:01 INFO - mozlog == 3.1 10:20:01 INFO - moznetwork == 0.27 10:20:01 INFO - mozprocess == 0.22 10:20:01 INFO - mozprofile == 0.27 10:20:01 INFO - mozrunner == 6.11 10:20:01 INFO - mozscreenshot == 0.1 10:20:01 INFO - mozsystemmonitor == 0.0 10:20:01 INFO - moztest == 0.7 10:20:01 INFO - mozversion == 1.4 10:20:01 INFO - psutil == 3.1.1 10:20:01 INFO - requests == 1.2.3 10:20:01 INFO - wsgiref == 0.1.2 10:20:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 10:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:01 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 0x7f11272cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2185600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214350>, '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 0x2215250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22156e0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', '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 10:20:01 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 10:20:01 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 10:20:01 INFO - Using env: {'DISPLAY': ':0', 10:20:01 INFO - 'HOME': '/home/cltbld', 10:20:01 INFO - 'LANG': 'en_US.UTF-8', 10:20:01 INFO - 'LANGUAGE': 'en_US:en', 10:20:01 INFO - 'LOGNAME': 'cltbld', 10:20:01 INFO - 'MAIL': '/var/mail/cltbld', 10:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:01 INFO - 'MOZ_NO_REMOTE': '1', 10:20:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:01 INFO - 'NO_EM_RESTART': '1', 10:20:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:20:01 INFO - 'PWD': '/builds/slave/test-pgo', 10:20:01 INFO - 'SHELL': '/bin/bash', 10:20:01 INFO - 'SHLVL': '1', 10:20:01 INFO - 'TERM': 'linux', 10:20:01 INFO - 'TMOUT': '86400', 10:20:01 INFO - 'USER': 'cltbld', 10:20:01 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', 10:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:01 INFO - '_': '/tools/buildbot/bin/python'} 10:20:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:01 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 1)) 10:20:01 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 2)) 10:20:01 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 3)) 10:20:01 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 4)) 10:20:01 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 5)) 10:20:01 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 6)) 10:20:01 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 7)) 10:20:01 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 8)) 10:20:01 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.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:20:01 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 3)) 10:20:01 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.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 10:20:01 INFO - Cleaning up... 10:20:01 INFO - Return code: 0 10:20:01 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 10:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f11272cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2185600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214350>, '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 0x2215250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22156e0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', '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 10:20:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 10:20:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:20:01 INFO - Using env: {'DISPLAY': ':0', 10:20:01 INFO - 'HOME': '/home/cltbld', 10:20:01 INFO - 'LANG': 'en_US.UTF-8', 10:20:01 INFO - 'LANGUAGE': 'en_US:en', 10:20:01 INFO - 'LOGNAME': 'cltbld', 10:20:01 INFO - 'MAIL': '/var/mail/cltbld', 10:20:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:01 INFO - 'MOZ_NO_REMOTE': '1', 10:20:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:01 INFO - 'NO_EM_RESTART': '1', 10:20:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:20:01 INFO - 'PWD': '/builds/slave/test-pgo', 10:20:01 INFO - 'SHELL': '/bin/bash', 10:20:01 INFO - 'SHLVL': '1', 10:20:01 INFO - 'TERM': 'linux', 10:20:01 INFO - 'TMOUT': '86400', 10:20:01 INFO - 'USER': 'cltbld', 10:20:01 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', 10:20:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:01 INFO - '_': '/tools/buildbot/bin/python'} 10:20:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:01 INFO - Downloading/unpacking jsonschema 10:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:20:03 INFO - Downloading/unpacking functools32 (from jsonschema) 10:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:03 INFO - Downloading functools32-3.2.3-2.tar.gz 10:20:03 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 10:20:03 INFO - warning: no files found matching '*.txt' 10:20:03 INFO - no previously-included directories found matching 'build' 10:20:03 INFO - no previously-included directories found matching 'dist' 10:20:03 INFO - no previously-included directories found matching '.git*' 10:20:03 INFO - Installing collected packages: jsonschema, functools32 10:20:03 INFO - Running setup.py install for functools32 10:20:03 INFO - warning: no files found matching '*.txt' 10:20:03 INFO - no previously-included directories found matching 'build' 10:20:03 INFO - no previously-included directories found matching 'dist' 10:20:03 INFO - no previously-included directories found matching '.git*' 10:20:03 INFO - Successfully installed jsonschema functools32 10:20:03 INFO - Cleaning up... 10:20:03 INFO - Return code: 0 10:20:03 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 10:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:20:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:20:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:20:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f11272cef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20b88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2185600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2214350>, '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 0x2215250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22156e0>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', '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 10:20:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 10:20:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 10:20:03 INFO - Using env: {'DISPLAY': ':0', 10:20:03 INFO - 'HOME': '/home/cltbld', 10:20:03 INFO - 'LANG': 'en_US.UTF-8', 10:20:03 INFO - 'LANGUAGE': 'en_US:en', 10:20:03 INFO - 'LOGNAME': 'cltbld', 10:20:03 INFO - 'MAIL': '/var/mail/cltbld', 10:20:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:03 INFO - 'MOZ_NO_REMOTE': '1', 10:20:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:03 INFO - 'NO_EM_RESTART': '1', 10:20:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:20:03 INFO - 'PWD': '/builds/slave/test-pgo', 10:20:03 INFO - 'SHELL': '/bin/bash', 10:20:03 INFO - 'SHLVL': '1', 10:20:03 INFO - 'TERM': 'linux', 10:20:03 INFO - 'TMOUT': '86400', 10:20:03 INFO - 'USER': 'cltbld', 10:20:03 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', 10:20:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:03 INFO - '_': '/tools/buildbot/bin/python'} 10:20:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:20:04 INFO - Downloading/unpacking flake8 10:20:04 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 10:20:04 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 10:20:04 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 10:20:04 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 10:20:04 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 10:20:04 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 10:20:05 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:20:05 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:20:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:06 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:20:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:20:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:20:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 10:20:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:20:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:20:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:20:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:20:06 INFO - no previously-included directories found matching 'docs/_build' 10:20:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:20:06 INFO - Running setup.py install for pep8 10:20:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:20:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:20:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:20:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:20:06 INFO - no previously-included directories found matching 'docs/_build' 10:20:06 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 10:20:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:20:06 INFO - Cleaning up... 10:20:06 INFO - Return code: 0 10:20:06 INFO - Running post-action listener: _resource_record_post_action 10:20:06 INFO - Running post-action listener: _start_resource_monitoring 10:20:06 INFO - Starting resource monitoring. 10:20:06 INFO - ##### 10:20:06 INFO - ##### Running install step. 10:20:06 INFO - ##### 10:20:06 INFO - Running pre-action listener: _resource_record_pre_action 10:20:06 INFO - Running main action method: install 10:20:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 10:20:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 10:20:07 INFO - Reading from file tmpfile_stdout 10:20:07 INFO - Detecting whether we're running mozinstall >=1.0... 10:20:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 10:20:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 10:20:07 INFO - Reading from file tmpfile_stdout 10:20:07 INFO - Output received: 10:20:07 INFO - Usage: mozinstall [options] installer 10:20:07 INFO - Options: 10:20:07 INFO - -h, --help show this help message and exit 10:20:07 INFO - -d DEST, --destination=DEST 10:20:07 INFO - Directory to install application into. [default: 10:20:07 INFO - "/builds/slave/test-pgo"] 10:20:07 INFO - --app=APP Application being installed. [default: firefox] 10:20:07 INFO - mkdir: /builds/slave/test-pgo/build/application 10:20:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 10:20:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 10:20:21 INFO - Reading from file tmpfile_stdout 10:20:21 INFO - Output received: 10:20:21 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 10:20:21 INFO - Running post-action listener: _resource_record_post_action 10:20:21 INFO - ##### 10:20:21 INFO - ##### Running run-tests step. 10:20:21 INFO - ##### 10:20:21 INFO - Running pre-action listener: _resource_record_pre_action 10:20:21 INFO - Running main action method: run_tests 10:20:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 10:20:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 10:20:21 INFO - Python 2.7.3 10:20:21 INFO - Return code: 0 10:20:21 INFO - grabbing minidump binary from tooltool 10:20:21 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')]} 10:20:21 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 0x2214350>, '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 0x2215250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22156e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 10:20:21 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 10:20:21 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 10:20:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:20:21 INFO - Return code: 0 10:20:21 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 10:20:21 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 10:20:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 10:20:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 10:20:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 10:20:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 10:20:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 10:20:22 INFO - Return code: 0 10:20:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 10:20:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --webServer localhost 10:20:22 INFO - Using env: {'DISPLAY': ':0', 10:20:22 INFO - 'HOME': '/home/cltbld', 10:20:22 INFO - 'LANG': 'en_US.UTF-8', 10:20:22 INFO - 'LANGUAGE': 'en_US:en', 10:20:22 INFO - 'LOGNAME': 'cltbld', 10:20:22 INFO - 'MAIL': '/var/mail/cltbld', 10:20:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:20:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 10:20:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:20:22 INFO - 'MOZ_NO_REMOTE': '1', 10:20:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 10:20:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:20:22 INFO - 'NO_EM_RESTART': '1', 10:20:22 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:20:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 10:20:22 INFO - 'PWD': '/builds/slave/test-pgo', 10:20:22 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 10:20:22 INFO - 'SHELL': '/bin/bash', 10:20:22 INFO - 'SHLVL': '1', 10:20:22 INFO - 'TERM': 'linux', 10:20:22 INFO - 'TMOUT': '86400', 10:20:22 INFO - 'USER': 'cltbld', 10:20:22 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825', 10:20:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:20:22 INFO - '_': '/tools/buildbot/bin/python'} 10:20:22 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost'] with output_timeout 3600 10:20:23 INFO - mozversion INFO | application_buildid: 20160225080027 10:20:23 INFO - mozversion INFO | application_changeset: fc6338c484fe1152ba0c5856e783115b0fd91db8 10:20:23 INFO - mozversion INFO | application_display_name: Nightly 10:20:23 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:20:23 INFO - mozversion INFO | application_name: Firefox 10:20:23 INFO - mozversion INFO | application_remotingname: firefox 10:20:23 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:20:23 INFO - mozversion INFO | application_vendor: Mozilla 10:20:23 INFO - mozversion INFO | application_version: 45.0 10:20:23 INFO - mozversion INFO | platform_buildid: 20160225080027 10:20:23 INFO - mozversion INFO | platform_changeset: fc6338c484fe1152ba0c5856e783115b0fd91db8 10:20:23 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:20:23 INFO - mozversion INFO | platform_version: 45.0 10:20:23 INFO - 2016-02-25 10:20:23,078 DEBUG : using testdate: 1456424423 10:20:23 INFO - 2016-02-25 10:20:23,078 DEBUG : actual date: 1456424423 10:20:23 INFO - 2016-02-25 10:20:23,169 INFO : starting webserver on 'localhost:33825' 10:20:23 INFO - 2016-02-25 10:20:23,170 INFO : Starting test suite talos-linux64-ix-022 10:20:23 INFO - 2016-02-25 10:20:23,170 INFO : Starting test dromaeo_css 10:20:23 INFO - 2016-02-25 10:20:23,170 DEBUG : operating with platform_type : linux_ 10:20:23 INFO - 2016-02-25 10:20:23,171 INFO : Initialising browser for dromaeo_css test... 10:20:23 INFO - 2016-02-25 10:20:23,187 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLGRLVC/profile http://localhost:33825/getInfo.html 10:20:27 INFO - 2016-02-25 10:20:27,206 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:20:27 INFO - 2016-02-25 10:20:27,206 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:20:27 INFO - 2016-02-25 10:20:27,206 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:20:27 INFO - 2016-02-25 10:20:27,206 DEBUG : BROWSER_OUTPUT: __metrics 10:20:27 INFO - 2016-02-25 10:20:27,363 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:20:28 INFO - 2016-02-25 10:20:28,617 INFO : Browser initialized. 10:20:28 INFO - 2016-02-25 10:20:28,618 INFO : Running cycle 1/1 for dromaeo_css test... 10:20:28 INFO - 2016-02-25 10:20:28,618 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpLGRLVC/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:20:40 INFO - 2016-02-25 10:20:40,316 DEBUG : BROWSER_OUTPUT: RSS: Main: 134447104 10:20:40 INFO - 2016-02-25 10:20:40,316 DEBUG : BROWSER_OUTPUT: 10:21:31 INFO - 2016-02-25 10:21:31,342 DEBUG : BROWSER_OUTPUT: 1456424491340 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] 10:23:39 INFO - 2016-02-25 10:23:39,733 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33825/tests/dromaeo/cssquery-ext.html) 10:23:40 INFO - 2016-02-25 10:23:40,722 DEBUG : BROWSER_OUTPUT: RSS: Main: 215953408 10:23:40 INFO - 2016-02-25 10:23:40,722 DEBUG : BROWSER_OUTPUT: 10:26:42 INFO - 2016-02-25 10:26:42,845 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/dromaeo/cssquery-ext.html (next: http://localhost:33825/tests/dromaeo/cssquery-jquery.html) 10:26:43 INFO - 2016-02-25 10:26:43,419 DEBUG : BROWSER_OUTPUT: RSS: Main: 218972160 10:26:43 INFO - 2016-02-25 10:26:43,419 DEBUG : BROWSER_OUTPUT: 10:29:45 INFO - 2016-02-25 10:29:45,090 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33825/tests/dromaeo/cssquery-mootools.html) 10:29:45 INFO - 2016-02-25 10:29:45,660 DEBUG : BROWSER_OUTPUT: RSS: Main: 225308672 10:29:45 INFO - 2016-02-25 10:29:45,660 DEBUG : BROWSER_OUTPUT: 10:32:47 INFO - 2016-02-25 10:32:47,332 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33825/tests/dromaeo/cssquery-prototype.html) 10:32:47 INFO - 2016-02-25 10:32:47,898 DEBUG : BROWSER_OUTPUT: RSS: Main: 206364672 10:32:47 INFO - 2016-02-25 10:32:47,898 DEBUG : BROWSER_OUTPUT: 10:35:50 INFO - 2016-02-25 10:35:50,238 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33825/tests/dromaeo/cssquery-yui.html) 10:35:50 INFO - 2016-02-25 10:35:50,812 DEBUG : BROWSER_OUTPUT: RSS: Main: 203714560 10:35:50 INFO - 2016-02-25 10:35:50,812 DEBUG : BROWSER_OUTPUT: 10:38:52 INFO - 2016-02-25 10:38:52,894 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/dromaeo/cssquery-yui.html (next: http://localhost:33825/tests/dromaeo/cssquery-dojo.html) 10:38:53 INFO - 2016-02-25 10:38:53,153 DEBUG : BROWSER_OUTPUT: RSS: Main: 237346816 10:38:53 INFO - 2016-02-25 10:38:53,153 DEBUG : BROWSER_OUTPUT: 10:38:53 INFO - 2016-02-25 10:38:53,153 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:38:53 INFO - 2016-02-25 10:38:53,153 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:38:53 INFO - 2016-02-25 10:38:53,153 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:38:53 INFO - 2016-02-25 10:38:53,153 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:38:53 INFO - 2016-02-25 10:38:53,153 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3274.9345013099737;3343.21434462901;3378.983105084474;3386.4073787087254;3393.5079413485037;5561.360443548993;5571.498565129137;5573.27547418836;5594.49649531542;5597.7201139942945;5521.641093328941;5569;5589.524890384312;5591.7483713232905;5603.3836278009385;4985.302057711923;5059;5069.949300506984;5085.110105731482;5162.174052151657;5073.7209453480045;5139.691618502902;5139.897202055975;5142.768575414097;5148.073346797575;4996.275540046691;5055.646104772667;5110.080185566597;5154.639175257734;5154.716490593016;5054.393472783271;5132.820351287724;5142.999999999981;5148.948510514884;5172.560332371753;4837.443693975203;5101.362329708786;5102.438731739506;5105.285260063594;5189.195674670432;5291.047611429941;5370.221317908881;5370.973145134289;5385.892282154335;5394.73026348681;5945.613535120204;5964.850878728023;6012.128241405016;6019.69901504923;6044.788432404889;5747.252857128588;5752.712364381806;6061.909071363933;6063.302720187191;6086.2087928569445;5523.861903452405;5583.190437386556;5587.580931430159;5595.832125036255;5634.690092044936;5899.941000589939;5937.52499800015;5947.405259474104;5950.7917222896995;5955.880882382328;5743.712814359265;5746.626469279484;5755.913661295084;5769.624974376652;5850.063989761617;5990.700464976734;6007.339192688801;6032.487238584769;6035.39646035393;6048.516118710492;5985;6004.609700369462;6008.729607167688;6012.248468941382;6020.247469066367;5787.102999035156;5904.7342869570975;5946.197263369476;6009.729562169713;6026.156338112668;4580.702254353457;4583.037562111994;4596.770161491912;4597.517260687647;4609.1703493371515;4087.529934057562;4108.589141085928;4111.280525907954;4126.546079931205;4147.149834283965;5461.563074953994;5557.499825015768;5635.746391412396;5639.520640745501;5729.54163666914;5966.254218222723;5968.283805943314;5969.955257829863;5978.521718262528;6014.609050411709;5599.132134519156;5602.551795856321;5603.3836278009385;5603.5237004854225;5619.100943848965;5122.718250496258;5170.663906846043;5191.76637051326;5209.947900520946;5259.289995850588;6190.969045154746;6226.719797609027;6228.128062071313;6231.781887633911;6240.251169859555;6064.545159112996;6076.179715738495;6078.45293923549;6094.939050609614;6095.542834287357;5793.217915581343;5977.671228082323;6102.224189113237;6203.193584833943;6237.345078766845;5890.764369425175;5917.822465326047;5922.052471604523;5922.111683247547;5939;5996.67018313997;6000.639961602144;6007.038873780174;6009.819705408845;6011.969940150271;5710.457506536873;5770.538356931352;5807.216025836627;5830.562707797017;5863.970680146572;5780.682062486603;5892.469677729025;5911.674857882857;5923.170756094237;5923.4076592340425;5512.6968016757855;5802.274715660543;5830.708464576669;5839.065749480063;5846.006178949674;5639.125935480007;5735.139729040678;5743.339515955551;5763.6541807491685;5764.654120752768;5697.800576979786;5777.191193232867;5791.305043394819;5792.884142317214;5793.275840519935 10:38:53 INFO - 2016-02-25 10:38:53,153 DEBUG : BROWSER_OUTPUT: |1;ext.html;628.845932746477;631.7725618777239;632.2728861808919;637.8501052252719;641.9101325814383;33011.33977320455;33559.16102097449;42141.578584214185;43046.13907721852;43067;45652.543474565246;46177.30734038984;46180.07639847201;46270.305945410764;46273.76863115688;7973.920260797376;8996.325275604308;9166.083391660814;9168.358214924958;9170.358074934757;8158.184181581824;10727.141828653688;11015.504302306415;11020.779584408307;11027.22809403342;10605.628802991896;10686.358818470877;10701.892981070207;10706.46467676613;10708.1968852336;8316.916830831675;8543.316534677211;10575.841362379571;10692.144628429745;10825.783484330348;10466.215033872413;10596.205284603686;10602.522886470128;10605.575776968912;10610.628627997983;42917.785411073055;43401.34897976533;43528;43535.34696979548;43652.56347436517;3691.9446208306895;3725.5715592706915;4118.567550407224;4315.741055536678;4406.228909940747;32548.186295342806;32972.67027329744;33110.50334244988;33514.49728254078;33634;3722.1253005543676;3747.344214762433;3750.3811871041244;3782.829772660237;3810.40938654509;309067;309654;310161.44919275487;310771;311151;55713.72143139259;56848;62316.37683623196;62334.68832655808;63241.36758632447;56908.43091569031;56929.430705692415;57210;57222.42777572254;57247.14129288064;15734.9213253933;16002.91998540023;16006.599835003984;16018.199090045684;16024.679506409806;11331.546738130382;11421.486033128529;11439.59961401347;11440.828387574193;11509.654710358702;20543.178272869216;20619.278325258543;20647.58704825925;20651.8967405162;20745;7642.961785191039;7801.804954876174;7860.606969651609;7862.764117076496;7998.720044798521;972.5331840716495;1097.2154909239932;1105.4251789069638;1110.7556337605713;1116.213069785799;29866.850665746966;30057.398852023274;30137.698623013926;30455.69544304573;30787;1126.3748619516084;1158.7045303447735;1178.793711100554;1246.6571692784557;1255.6233130061128;12752.74494510123;15772.92113539425;16135.354585816436;16336.264868080727;16451.67096658084;337383;342866;344004.6798596046;344373;344860;55977.72011139918;56228;56245.43754562484;56266;56269.43730562724;1458.4020551573803;1468.1411374346128;1478.4899209772395;1484.1540322016347;1502.6543894904232;29842.55236171416;29891.505424729112;30295.545566816076;30627.54058689166;30642;48686.75656621694;48838.26742598935;48993.51006490032;49059.754701226266;49224.50775492199;308958;312292;312639;312712;313077;30179.245518861328;32347.51478727868;33264.66735332616;34433.13917152091;34508.13729656779;41160.176796464504;41444.79277603713;41893;42480.57519424766;42871.7856410716;36657.08357291089;36821.26357472889;37014.44478332773;37046.07384815401;37494.250114998096;36510.8174459126;36846.631533685395;37228.62771372252;38026.80986595049;38670.80664596659;34817.651823482454;35070.82464587661;35609.1097722559;35676.28647426985;35739.82130089333;32627;33551.66448335583;33667;34171.14572135716;34271.828640856635;26054.34864128412;26213;26501.469970600094;26559.07043253477;26662.20013399601 10:38:53 INFO - 2016-02-25 10:38:53,154 DEBUG : BROWSER_OUTPUT: |2;jquery.html;373.7738668105796;376.5387400434468;377.22103855538313;378.2548379692006;378.6138139098118;16519;16728.665426691474;18282.451526454228;18375.0812459377;18472.815271847277;17507.47477575675;17643.823561764377;17652.117394130306;17805.287788488444;17933.193006314716;4242.618164365206;4369.431973843396;5390.326209223847;5398.325209348832;5549.084401073838;5883.588148829584;6053.455189032986;6101.237345331833;6106.786262480814;6107.81676549702;5943.078822782476;6017.39826017397;6017.939820601781;6018.916595012918;6027.758889644409;5996.070609055581;5999.370066143036;6022.668753218615;6028.1861948636815;6029.668368239723;5302.973485132588;5346.465353464661;5350.8394748157225;5355.250264962909;5355.705436201008;22096.558068838534;22288.88855555728;22645.88677056604;22727.090916363326;23691.88154059236;2335.9532809343887;2455.3493324269084;2566.4225549251455;2578.6003169508567;2672.50558646651;11176.329420234812;14380.424783008668;14626.926865365711;15211.163386013764;15352.309146088453;1686.4519031314871;1695.0507715679137;1703.735920932245;1706.5221737913405;1727.3781438682065;74093.25906740918;77278.61360693217;78330.60834695847;79121;79554;16932.57668558296;17160.485185444457;17161.828381716274;17167.742483862752;17349.13254337278;27672.58491122635;27757.583636245472;27776.58335124975;28182.71817281842;28288.15135545937;8514.659413623509;8896.733098007071;10174.389536627827;10191.337563058378;10231.693049208536;8392.874106888401;8517.95741021291;8619.61211745473;8627.568621569022;8648.43785153963;7363.447741419415;8211.34309255258;8249.958750206331;8363.832723345498;8390.496570205807;3029.666736658966;3077.923051923675;3118.3919135768306;3196.9200769980935;3252.105625992868;912.3112050402036;972.5817898303656;1010.0555980158575;1044.6187141693226;1086.9619563315246;15848.366065357257;17070.829291707174;17944.9102754488;18088.638227235646;18266.725999110025;942.2414955960518;942.3403617467663;983.1937810994915;1007.8488226766044;1101.8347247912716;9305.627774889064;10251.12865406434;10430.165586753194;10485.423301718327;10514.421706806199;663386;667682;671760;682158;698185;90392;92600;94594;94648;95047.52476237575;1107.839363292318;1128.0975219824109;1134.3817619397448;1153.4694040741185;1178.776032553812;15680.92159539195;15696.430356964212;16198.838011619733;16989;17213.31146754116;38661.80669096749;39160.608393915696;39741.6405823033;39795.80102099471;40277.79861100676;54160.45839541554;54985;56324.71837640786;58619.4138058614;58648;10128.949355253177;10494.475276236006;10586.735331616772;10600.0989915858;10611.09805666527;18757.155927983546;18973.90513047426;19032.714509282094;19172.80827191766;19362.612747745246;15442.92278538645;15443.459478918185;15750.842491574938;15833.287502062205;15911.68176636484;11834.171607987702;14600.612941770514;15107.327868279224;15261.313240904408;15717.842821571638;15858.52424427269;16032;16108.919455402647;16294.348226070824;16336.591585210464;10142.239332049978;10192.286539942133;10196.38821670702;10268.178545716175;10541.156707463231;15371.38514459409;15377;15573;15872.761908571612;15908.602284942968 10:38:53 INFO - 2016-02-25 10:38:53,154 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1519.3618680154334;1521.216573464665;1700.982990170098;1774.6716857381386;1808.077880281057;7398.334149926505;7806.687732490701;7819.530828150308;7820.257075577825;7829.295363417305;7514.736984205554;7600.20197879223;7609.201033891445;7611.885821712679;7624.542527448357;2226.9331920042423;2266.705328307318;2288.233441796992;2295.724513058427;2296.334063121693;2240.596692595332;2285.702858628376;2288.199130304397;2288.5079707862806;2288.530851175505;1878.5867109236015;1915.329634627883;2236.0608544411307;2272.556851413967;2279.076973825603;2294.5869743446174;2312.5490529346785;2341.262502311766;2343.0744855781954;2344.7772461616205;2268.761780013108;2314.9189778357845;2318.8376813623054;2319.9188028418926;2320.11835502508;6799.966000169968;6929.792106236821;7278.1630112537205;7411.184769675331;7432;4500.34744961982;4590.104929538741;4603.470600880908;4603.792829322689;4609.539046095397;2943.1170648805487;2945.806948185988;2972.4055188962293;2972.4649563078638;2993.3863557970562;1031.2265800649513;1041.7343577387794;1046.9633562825265;1080.4219742437758;1095.638439315024;10273.897261027443;10337.431441270726;10340.276180667357;10341.172706183488;10350.948245258725;4193.32906734927;4194.224068547329;4249.745015299092;4265.573442655709;4268;9093.636254549743;9107.81784364309;9110.49892255919;9111.589978450986;9112.908870911338;4720.669553131248;4721.3390125382475;4743.430788305356;4748.358971538867;4750.026244619845;4320.805563749639;4321.805518751664;4323.416338794285;4323.632491238217;4327.6321512671775;3496.9125771855906;3564.518789963322;3580.0691820126435;3582.570091588974;3600.6399360064306;1141.668916014363;1143.7883991461606;1152.1474109159162;1158.8667303260233;1163.5462169753803;1421.5593166118535;1432.8208973878266;1437.4322142753554;1439.8344190418236;1474.6608280095425;7279.344858962613;7338.77983660491;7342.265773422615;7350.816229594196;7352.889706654404;476.94515130759714;486.5183468366292;496.5158970004217;497.3410231443352;499.7701057513512;5459.454054594509;5755.712214389264;5856;5858.707064646751;5860.09168578871;7395.889061664079;7421.0723659542555;7508.812279693051;7517.097948246245;7791.727289544839;8805.603747831236;8970.775730606656;9097.09029097085;9133.49765762876;9173.128552787475;7235.855282894313;7266.92733072673;7275.527090739085;7349.522281051822;7351.081114860643;7084.716611335489;7345.853082938418;7391;7428.442866784904;7429.59137247435;7886.605669716377;8021;8047.235512626291;8067.3142782864115;8101.351891848756;8402.07577166499;8422.199891010345;8555.443896146731;8569.914300856912;8605.418374487754;6705.4635629150525;6751.831204219934;6763.154605674291;6770.356816102462;6770.559913605797;7220.169680486812;7263.201047884834;7289.927100728924;7292.781216563511;7302.0142280791415;6851.554648947803;6928.1339832520935;6937.791866244021;6963.79108626742;6993.510454268152;6693.330666933268;6828.044073829568;6836.145481814773;6913.619750913748;6955.026296318419;6816.318368163343;6951.687174077279;6955.965220173866;6965.199002114619;6968.059311992816;6405.455536279282;6758.256591774999;6768.390844823989;6774.593524388552;6774.593524388552;6624.735010599715;6847.007183958199;6853.657317134223;6857.965710171417;6865.073215115895 10:38:53 INFO - 2016-02-25 10:38:53,154 DEBUG : BROWSER_OUTPUT: |4;prototype.html;353.72409520573945;359.0449404583806;359.63317416235424;360.6988164882323;360.7060245899593;6029.1257767623665;6141.416565426283;6149.13912052313;6161.2298462692215;6234.283057448395;5833.5333173346135;5896.292444906607;5951.613145145562;6059.606125601833;6062.818115456544;4700.224462963607;4727.952720472803;4742.264948932912;4749.643776716743;4756.714597124166;4711.858644240679;4718.245080787075;4718.882027949295;4725.503822098682;4743.146233677954;4494.775261236941;4611.677182597219;4621.145088158684;4698.553637404441;4716.952830471703;4683.086798074377;4696.694714843541;4699.271612900006;4701.78841952113;4714.0336231072715;4129.607687269705;4134.91730165395;4154.646855017327;4160.45914031177;4162.604552567501;5730.455606717397;5802.390748971339;5809.738561764689;5830.82507524775;5840.649561026352;5484.643498172606;5496.1755736639825;5499.780008799643;5502.064649009638;5502.367227768817;6374.235091788969;6375.139356186948;6381.329960354143;6381.393767592118;6385.138006369127;4613.192691279046;4614.330922016323;4619.284010978304;4620;4629.560191781776;6493.415592596689;6641.468682505436;6641.966790166067;6690.498212634072;6699.799006029875;4600.769961501912;4636.86089417318;4637.118947399983;4638.652101092364;4642.0715856828765;4685.578297953132;4693.95306046942;4696.7416792076765;4705;4706;5415.241866138744;5425.403205647375;5425.620206585501;5430.891382172414;5431.076716958127;6350.618962862243;6486.2216534016225;6489.3510648934725;6490.448311893542;6492.480601551864;1630.5842010287538;1649.175412293853;1650.2903751387018;1650.4058538925976;1652.1986836384344;1091.263397206898;1104.8397982292522;1107.291333546531;1122.4668282565879;1125.9099272058215;6280.591761535486;6283.654399008007;6291.02489114188;6293.615404610979;6298;6683.565568238049;6688.83277918056;6690.498212633975;6700.095487109178;6700.296468870796;6089.908651370233;6097.8475538111015;6261.373862613793;6263.217097862767;6265.028920517327;3591.676749092594;3640.8361623726996;3644.0889777555612;3651;3654.780713157166;6999.09011828459;7010.859782804316;7011.824704382431;7014.649267536603;7023.543469674455;6778.118844550276;6898.517103802686;6931.410830079399;6939.7224111036;6942.618156001367;6990.405815505739;6997.195322537871;6998.160220773438;6998.195207551095;6998.335158159966;6649.368310010541;6753.696083676148;6763.5941843489545;6769.932300677127;6771.255161932085;6687.431568316748;6688.531802773759;6692.59844409337;6694.163229596301;6694.196696396463;6929.480288978448;6954.930450695429;6996.790096297119;7004.089468369079;7016.719331226694;4646.047560250073;4653.9301910470695;4658.464276608098;4662.953370466252;4663.347131401674;6569.605823650596;6719.596824190564;6722.7562900863495;6732.293109223461;6732.394084532416;6588.341165883373;6592.143021407186;6592.670366481752;6594.868102638016;6602.4057834793175;6341.365863413622;6478.546501744832;6516.543841931019;6518.315576864551;6521.543491955517;6423.164988551458;6433.710483028181;6459.386358295955;6475.320091390525;6476.70854811544;6540.247871494839;6605.174353205849;6608.537402381787;6609.966950165219;6611.603303801595;6241.21984751906;6443.71003304843;6450.516211284266;6453;6461.2892581816905 10:38:53 INFO - 2016-02-25 10:38:53,154 DEBUG : BROWSER_OUTPUT: |5;yui.html;866.5234121233322;866.9523176225313;899.6941040046387;944.787422829863;947.6493697331986;12152.756944861108;13024.348782560857;13049.804252936214;13060.542880999166;13081.67295817605;12100.213486123395;12143.149979501417;12155.878441215586;12171.878281217185;12196.756064878718;738.8706976279157;743.8772602520578;870.8519551676198;871.172386233078;882.7175303902753;871.0592560035146;883.659790980474;884.5798245833209;885.7342797160826;886.3042511628373;786.2805532937356;913.3150137396952;918.5085979001236;919.2232563483846;919.7240827751648;786.3512602103259;920.1350730313517;929.0662883801804;930.7068273493828;930.8510638297907;691.0498065160405;691.0774116554406;691.4572060932144;693.1681981622073;693.8438851258477;12517.56188533397;12644.557440489538;12663.873361266362;12722.61832145037;12730.427130779044;11695.532178712841;12327.69180770488;12453.564125255572;12488.75022499554;12524.436400362005;10360.171186305153;10369.896301036968;10376.896231037745;10497.63258285964;10586.52360643773;3524.9118772030647;3572.2676851245433;3577.105723569108;3582.0686621478344;3586.4082426399614;48759.26861097087;49460.258096128586;49611.50388496105;49670.50329496731;49849.75075124637;9768.36505627132;9807.26445516589;9847.359921605073;9863.063009014133;9997.80004399908;19507.902460487607;19525.707114393295;19579.11893964775;19590.510237244183;20194.596108077756;7100.218975912646;7124.145102587619;7167.4266058715175;7227.27727227273;7254.492185546961;4079.204555111784;4121.340585506305;4129.215449064668;4137.586241375839;4143.129942712064;3238.1904523869034;3276.820344675915;3283.3761585298716;3284.4416449203213;3285.3593549258144;538.9137737961986;544.9455054494598;558.5587385965123;572.0561074227558;593.489598944907;547.2775935764831;558.6927190045459;639.3079963212485;650.6851010938827;689.1076056506836;8338.416310858182;9018.909810901938;9041.81916361669;9086.545672716338;9110.908890911138;620.9472194863397;642.0497663458108;646.2600322630561;714.9177844547839;721.3688022979893;4341.261985462479;4349.064951035542;4411.117776444659;4457.64338852891;4489.281714925596;62617.68691156606;63138.68430657817;63399.36600633935;63475.36524634787;63488.36511634917;54438.18342724862;55243;55367.72316138393;55571;55622;47777.28334074992;50589.49410505922;50631;50741;51258.23112653313;8713.302935764998;8870.467771933447;9037.09629037091;9366;9578.808423831624;37934.27417742703;38002.619973799905;38613.2277354457;38727.61272387353;38767.80616097014;47771;48347.274790878866;49102;49397.50602493929;50238.74880625573;11257.662270131908;11558.190926635054;11877.643670689893;12106.636800895987;12257;12791.424385902843;12848.935755321163;12949;12955.287459189834;13039.95680345589;11435.582334503933;11884.82172767392;11942.223755455867;12197.390130493617;12243.265404075784;11765.235259708434;11804.409779511161;12137.939310303393;12241.387930603612;12497.18768280059;10788;11239.719007024889;11314.943425282821;11659.358735269641;11687.123465740273;10453;10600.310979786289;10716;10793.838092428778;10817.459127043774;9374.390664606779;9403.529823508934;9489.525523723923;9545.665901693408;9720.416774993522 10:38:53 INFO - 2016-02-25 10:38:53,155 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:38:53 INFO - 2016-02-25 10:38:53,155 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:38:53 INFO - 2016-02-25 10:38:53,155 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,767 10:38:53 INFO - 2016-02-25 10:38:53,155 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:38:53 INFO - 2016-02-25 10:38:53,155 DEBUG : BROWSER_OUTPUT: __startTimestamp1456425533146__endTimestamp 10:38:53 INFO - 2016-02-25 10:38:53,156 DEBUG : BROWSER_OUTPUT: 10:38:53 INFO - 2016-02-25 10:38:53,156 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:38:53 INFO - 2016-02-25 10:38:53,156 DEBUG : BROWSER_OUTPUT: Number of tests: 6 10:38:53 INFO - 2016-02-25 10:38:53,156 DEBUG : BROWSER_OUTPUT: 10:38:53 INFO - 2016-02-25 10:38:53,156 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5542.32 Median:5746.63 stddev:606.67 (10.6%) stddev-sans-first:581.87 10:38:53 INFO - 2016-02-25 10:38:53,157 DEBUG : BROWSER_OUTPUT: Values: 3274.9 3343.2 3379.0 3386.4 3393.5 5561.4 5571.5 5573.3 5594.5 5597.7 5521.6 5569.0 5589.5 5591.7 5603.4 4985.3 5059.0 5069.9 5085.1 5162.2 5073.7 5139.7 5139.9 5142.8 5148.1 4996.3 5055.6 5110.1 5154.6 5154.7 5054.4 5132.8 5143.0 5148.9 5172.6 4837.4 5101.4 5102.4 5105.3 5189.2 5291.0 5370.2 5371.0 5385.9 5394.7 5945.6 5964.9 6012.1 6019.7 6044.8 5747.3 5752.7 6061.9 6063.3 6086.2 5523.9 5583.2 5587.6 5595.8 5634.7 5899.9 5937.5 5947.4 5950.8 5955.9 5743.7 5746.6 5755.9 5769.6 5850.1 5990.7 6007.3 6032.5 6035.4 6048.5 5985.0 6004.6 6008.7 6012.2 6020.2 5787.1 5904.7 5946.2 6009.7 6026.2 4580.7 4583.0 4596.8 4597.5 4609.2 4087.5 4108.6 4111.3 4126.5 4147.1 5461.6 5557.5 5635.7 5639.5 5729.5 5966.3 5968.3 5970.0 5978.5 6014.6 5599.1 5602.6 5603.4 5603.5 5619.1 5122.7 5170.7 5191.8 5209.9 5259.3 6191.0 6226.7 6228.1 6231.8 6240.3 6064.5 6076.2 6078.5 6094.9 6095.5 5793.2 5977.7 6102.2 6203.2 6237.3 5890.8 5917.8 5922.1 5922.1 5939.0 5996.7 6000.6 6007.0 6009.8 6012.0 5710.5 5770.5 5807.2 5830.6 5864.0 5780.7 5892.5 5911.7 5923.2 5923.4 5512.7 5802.3 5830.7 5839.1 5846.0 5639.1 5735.1 5743.3 5763.7 5764.7 5697.8 5777.2 5791.3 5792.9 5793.3 10:38:53 INFO - 2016-02-25 10:38:53,157 DEBUG : BROWSER_OUTPUT: 10:38:53 INFO - 2016-02-25 10:38:53,157 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:49666.92 Median:34221.49 stddev:84138.14 (245.9%) stddev-sans-first:84293.60 10:38:53 INFO - 2016-02-25 10:38:53,157 DEBUG : BROWSER_OUTPUT: Values: 628.8 631.8 632.3 637.9 641.9 33011.3 33559.2 42141.6 43046.1 43067.0 45652.5 46177.3 46180.1 46270.3 46273.8 7973.9 8996.3 9166.1 9168.4 9170.4 8158.2 10727.1 11015.5 11020.8 11027.2 10605.6 10686.4 10701.9 10706.5 10708.2 8316.9 8543.3 10575.8 10692.1 10825.8 10466.2 10596.2 10602.5 10605.6 10610.6 42917.8 43401.3 43528.0 43535.3 43652.6 3691.9 3725.6 4118.6 4315.7 4406.2 32548.2 32972.7 33110.5 33514.5 33634.0 3722.1 3747.3 3750.4 3782.8 3810.4 309067.0 309654.0 310161.4 310771.0 311151.0 55713.7 56848.0 62316.4 62334.7 63241.4 56908.4 56929.4 57210.0 57222.4 57247.1 15734.9 16002.9 16006.6 16018.2 16024.7 11331.5 11421.5 11439.6 11440.8 11509.7 20543.2 20619.3 20647.6 20651.9 20745.0 7643.0 7801.8 7860.6 7862.8 7998.7 972.5 1097.2 1105.4 1110.8 1116.2 29866.9 30057.4 30137.7 30455.7 30787.0 1126.4 1158.7 1178.8 1246.7 1255.6 12752.7 15772.9 16135.4 16336.3 16451.7 337383.0 342866.0 344004.7 344373.0 344860.0 55977.7 56228.0 56245.4 56266.0 56269.4 1458.4 1468.1 1478.5 1484.2 1502.7 29842.6 29891.5 30295.5 30627.5 30642.0 48686.8 48838.3 48993.5 49059.8 49224.5 308958.0 312292.0 312639.0 312712.0 313077.0 30179.2 32347.5 33264.7 34433.1 34508.1 41160.2 41444.8 41893.0 42480.6 42871.8 36657.1 36821.3 37014.4 37046.1 37494.3 36510.8 36846.6 37228.6 38026.8 38670.8 34817.7 35070.8 35609.1 35676.3 35739.8 32627.0 33551.7 33667.0 34171.1 34271.8 26054.3 26213.0 26501.5 26559.1 26662.2 10:38:53 INFO - 2016-02-25 10:38:53,157 DEBUG : BROWSER_OUTPUT: 10:38:53 INFO - 2016-02-25 10:38:53,157 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:35345.87 Median:12531.52 stddev:110560.48 (882.3%) stddev-sans-first:110839.44 10:38:53 INFO - 2016-02-25 10:38:53,158 DEBUG : BROWSER_OUTPUT: Values: 373.8 376.5 377.2 378.3 378.6 16519.0 16728.7 18282.5 18375.1 18472.8 17507.5 17643.8 17652.1 17805.3 17933.2 4242.6 4369.4 5390.3 5398.3 5549.1 5883.6 6053.5 6101.2 6106.8 6107.8 5943.1 6017.4 6017.9 6018.9 6027.8 5996.1 5999.4 6022.7 6028.2 6029.7 5303.0 5346.5 5350.8 5355.3 5355.7 22096.6 22288.9 22645.9 22727.1 23691.9 2336.0 2455.3 2566.4 2578.6 2672.5 11176.3 14380.4 14626.9 15211.2 15352.3 1686.5 1695.1 1703.7 1706.5 1727.4 74093.3 77278.6 78330.6 79121.0 79554.0 16932.6 17160.5 17161.8 17167.7 17349.1 27672.6 27757.6 27776.6 28182.7 28288.2 8514.7 8896.7 10174.4 10191.3 10231.7 8392.9 8518.0 8619.6 8627.6 8648.4 7363.4 8211.3 8250.0 8363.8 8390.5 3029.7 3077.9 3118.4 3196.9 3252.1 912.3 972.6 1010.1 1044.6 1087.0 15848.4 17070.8 17944.9 18088.6 18266.7 942.2 942.3 983.2 1007.8 1101.8 9305.6 10251.1 10430.2 10485.4 10514.4 663386.0 667682.0 671760.0 682158.0 698185.0 90392.0 92600.0 94594.0 94648.0 95047.5 1107.8 1128.1 1134.4 1153.5 1178.8 15680.9 15696.4 16198.8 16989.0 17213.3 38661.8 39160.6 39741.6 39795.8 40277.8 54160.5 54985.0 56324.7 58619.4 58648.0 10128.9 10494.5 10586.7 10600.1 10611.1 18757.2 18973.9 19032.7 19172.8 19362.6 15442.9 15443.5 15750.8 15833.3 15911.7 11834.2 14600.6 15107.3 15261.3 15717.8 15858.5 16032.0 16108.9 16294.3 16336.6 10142.2 10192.3 10196.4 10268.2 10541.2 15371.4 15377.0 15573.0 15872.8 15908.6 10:38:53 INFO - 2016-02-25 10:38:53,158 DEBUG : BROWSER_OUTPUT: 10:38:53 INFO - 2016-02-25 10:38:53,159 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5347.72 Median:6515.10 stddev:2724.58 (41.8%) stddev-sans-first:2717.02 10:38:53 INFO - 2016-02-25 10:38:53,159 DEBUG : BROWSER_OUTPUT: Values: 1519.4 1521.2 1701.0 1774.7 1808.1 7398.3 7806.7 7819.5 7820.3 7829.3 7514.7 7600.2 7609.2 7611.9 7624.5 2226.9 2266.7 2288.2 2295.7 2296.3 2240.6 2285.7 2288.2 2288.5 2288.5 1878.6 1915.3 2236.1 2272.6 2279.1 2294.6 2312.5 2341.3 2343.1 2344.8 2268.8 2314.9 2318.8 2319.9 2320.1 6800.0 6929.8 7278.2 7411.2 7432.0 4500.3 4590.1 4603.5 4603.8 4609.5 2943.1 2945.8 2972.4 2972.5 2993.4 1031.2 1041.7 1047.0 1080.4 1095.6 10273.9 10337.4 10340.3 10341.2 10350.9 4193.3 4194.2 4249.7 4265.6 4268.0 9093.6 9107.8 9110.5 9111.6 9112.9 4720.7 4721.3 4743.4 4748.4 4750.0 4320.8 4321.8 4323.4 4323.6 4327.6 3496.9 3564.5 3580.1 3582.6 3600.6 1141.7 1143.8 1152.1 1158.9 1163.5 1421.6 1432.8 1437.4 1439.8 1474.7 7279.3 7338.8 7342.3 7350.8 7352.9 476.9 486.5 496.5 497.3 499.8 5459.5 5755.7 5856.0 5858.7 5860.1 7395.9 7421.1 7508.8 7517.1 7791.7 8805.6 8970.8 9097.1 9133.5 9173.1 7235.9 7266.9 7275.5 7349.5 7351.1 7084.7 7345.9 7391.0 7428.4 7429.6 7886.6 8021.0 8047.2 8067.3 8101.4 8402.1 8422.2 8555.4 8569.9 8605.4 6705.5 6751.8 6763.2 6770.4 6770.6 7220.2 7263.2 7289.9 7292.8 7302.0 6851.6 6928.1 6937.8 6963.8 6993.5 6693.3 6828.0 6836.1 6913.6 6955.0 6816.3 6951.7 6956.0 6965.2 6968.1 6405.5 6758.3 6768.4 6774.6 6774.6 6624.7 6847.0 6853.7 6858.0 6865.1 10:38:53 INFO - 2016-02-25 10:38:53,159 DEBUG : BROWSER_OUTPUT: 10:38:53 INFO - 2016-02-25 10:38:53,159 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5453.24 Median:6197.76 stddev:1634.89 (26.4%) stddev-sans-first:1594.05 10:38:53 INFO - 2016-02-25 10:38:53,159 DEBUG : BROWSER_OUTPUT: Values: 353.7 359.0 359.6 360.7 360.7 6029.1 6141.4 6149.1 6161.2 6234.3 5833.5 5896.3 5951.6 6059.6 6062.8 4700.2 4728.0 4742.3 4749.6 4756.7 4711.9 4718.2 4718.9 4725.5 4743.1 4494.8 4611.7 4621.1 4698.6 4717.0 4683.1 4696.7 4699.3 4701.8 4714.0 4129.6 4134.9 4154.6 4160.5 4162.6 5730.5 5802.4 5809.7 5830.8 5840.6 5484.6 5496.2 5499.8 5502.1 5502.4 6374.2 6375.1 6381.3 6381.4 6385.1 4613.2 4614.3 4619.3 4620.0 4629.6 6493.4 6641.5 6642.0 6690.5 6699.8 4600.8 4636.9 4637.1 4638.7 4642.1 4685.6 4694.0 4696.7 4705.0 4706.0 5415.2 5425.4 5425.6 5430.9 5431.1 6350.6 6486.2 6489.4 6490.4 6492.5 1630.6 1649.2 1650.3 1650.4 1652.2 1091.3 1104.8 1107.3 1122.5 1125.9 6280.6 6283.7 6291.0 6293.6 6298.0 6683.6 6688.8 6690.5 6700.1 6700.3 6089.9 6097.8 6261.4 6263.2 6265.0 3591.7 3640.8 3644.1 3651.0 3654.8 6999.1 7010.9 7011.8 7014.6 7023.5 6778.1 6898.5 6931.4 6939.7 6942.6 6990.4 6997.2 6998.2 6998.2 6998.3 6649.4 6753.7 6763.6 6769.9 6771.3 6687.4 6688.5 6692.6 6694.2 6694.2 6929.5 6954.9 6996.8 7004.1 7016.7 4646.0 4653.9 4658.5 4663.0 4663.3 6569.6 6719.6 6722.8 6732.3 6732.4 6588.3 6592.1 6592.7 6594.9 6602.4 6341.4 6478.5 6516.5 6518.3 6521.5 6423.2 6433.7 6459.4 6475.3 6476.7 6540.2 6605.2 6608.5 6610.0 6611.6 6241.2 6443.7 6450.5 6453.0 6461.3 10:38:53 INFO - 2016-02-25 10:38:53,159 DEBUG : BROWSER_OUTPUT: 10:38:53 INFO - 2016-02-25 10:38:53,159 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14535.49 Median:49428.88 stddev:17299.34 (35.0%) stddev-sans-first:17317.42 10:38:53 INFO - 2016-02-25 10:38:53,160 DEBUG : BROWSER_OUTPUT: Values: 866.5 867.0 899.7 944.8 947.6 12152.8 13024.3 13049.8 13060.5 13081.7 12100.2 12143.1 12155.9 12171.9 12196.8 738.9 743.9 870.9 871.2 882.7 871.1 883.7 884.6 885.7 886.3 786.3 913.3 918.5 919.2 919.7 786.4 920.1 929.1 930.7 930.9 691.0 691.1 691.5 693.2 693.8 12517.6 12644.6 12663.9 12722.6 12730.4 11695.5 12327.7 12453.6 12488.8 12524.4 10360.2 10369.9 10376.9 10497.6 10586.5 3524.9 3572.3 3577.1 3582.1 3586.4 48759.3 49460.3 49611.5 49670.5 49849.8 9768.4 9807.3 9847.4 9863.1 9997.8 19507.9 19525.7 19579.1 19590.5 20194.6 7100.2 7124.1 7167.4 7227.3 7254.5 4079.2 4121.3 4129.2 4137.6 4143.1 3238.2 3276.8 3283.4 3284.4 3285.4 538.9 544.9 558.6 572.1 593.5 547.3 558.7 639.3 650.7 689.1 8338.4 9018.9 9041.8 9086.5 9110.9 620.9 642.0 646.3 714.9 721.4 4341.3 4349.1 4411.1 4457.6 4489.3 62617.7 63138.7 63399.4 63475.4 63488.4 54438.2 55243.0 55367.7 55571.0 55622.0 47777.3 50589.5 50631.0 50741.0 51258.2 8713.3 8870.5 9037.1 9366.0 9578.8 37934.3 38002.6 38613.2 38727.6 38767.8 47771.0 48347.3 49102.0 49397.5 50238.7 11257.7 11558.2 11877.6 12106.6 12257.0 12791.4 12848.9 12949.0 12955.3 13040.0 11435.6 11884.8 11942.2 12197.4 12243.3 11765.2 11804.4 12137.9 12241.4 12497.2 10788.0 11239.7 11314.9 11659.4 11687.1 10453.0 10600.3 10716.0 10793.8 10817.5 9374.4 9403.5 9489.5 9545.7 9720.4 10:38:53 INFO - 2016-02-25 10:38:53,160 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:38:53 INFO - 2016-02-25 10:38:53,161 DEBUG : BROWSER_OUTPUT: 10:38:53 INFO - 2016-02-25 10:38:53,725 INFO : Browser exited with error code: 0 10:38:53 INFO - 2016-02-25 10:38:53,744 INFO : Completed test dromaeo_css (00:18:30) 10:38:53 INFO - 2016-02-25 10:38:53,744 INFO : Starting test kraken 10:38:53 INFO - 2016-02-25 10:38:53,744 DEBUG : operating with platform_type : linux_ 10:38:53 INFO - 2016-02-25 10:38:53,744 INFO : Initialising browser for kraken test... 10:38:53 INFO - 2016-02-25 10:38:53,751 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpX1aKtg/profile http://localhost:33825/getInfo.html 10:38:57 INFO - 2016-02-25 10:38:57,970 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:38:57 INFO - 2016-02-25 10:38:57,970 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:38:57 INFO - 2016-02-25 10:38:57,970 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:38:57 INFO - 2016-02-25 10:38:57,970 DEBUG : BROWSER_OUTPUT: __metrics 10:38:58 INFO - 2016-02-25 10:38:58,139 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:38:58 INFO - 2016-02-25 10:38:58,981 INFO : Browser initialized. 10:38:58 INFO - 2016-02-25 10:38:58,982 INFO : Running cycle 1/1 for kraken test... 10:38:58 INFO - 2016-02-25 10:38:58,982 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpX1aKtg/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:39:10 INFO - 2016-02-25 10:39:10,752 DEBUG : BROWSER_OUTPUT: RSS: Main: 132653056 10:39:10 INFO - 2016-02-25 10:39:10,752 DEBUG : BROWSER_OUTPUT: 10:39:13 INFO - 2016-02-25 10:39:13,207 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33825/tests/kraken/driver.html?testName=audio-beat-detection) 10:39:13 INFO - 2016-02-25 10:39:13,789 DEBUG : BROWSER_OUTPUT: RSS: Main: 165744640 10:39:13 INFO - 2016-02-25 10:39:13,789 DEBUG : BROWSER_OUTPUT: 10:39:16 INFO - 2016-02-25 10:39:16,345 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33825/tests/kraken/driver.html?testName=audio-dft) 10:39:16 INFO - 2016-02-25 10:39:16,907 DEBUG : BROWSER_OUTPUT: RSS: Main: 164864000 10:39:16 INFO - 2016-02-25 10:39:16,908 DEBUG : BROWSER_OUTPUT: 10:39:19 INFO - 2016-02-25 10:39:19,679 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33825/tests/kraken/driver.html?testName=audio-fft) 10:39:20 INFO - 2016-02-25 10:39:20,241 DEBUG : BROWSER_OUTPUT: RSS: Main: 161849344 10:39:20 INFO - 2016-02-25 10:39:20,241 DEBUG : BROWSER_OUTPUT: 10:39:22 INFO - 2016-02-25 10:39:22,239 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33825/tests/kraken/driver.html?testName=audio-oscillator) 10:39:22 INFO - 2016-02-25 10:39:22,804 DEBUG : BROWSER_OUTPUT: RSS: Main: 161001472 10:39:22 INFO - 2016-02-25 10:39:22,804 DEBUG : BROWSER_OUTPUT: 10:39:24 INFO - 2016-02-25 10:39:24,979 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33825/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:39:25 INFO - 2016-02-25 10:39:25,541 DEBUG : BROWSER_OUTPUT: RSS: Main: 160235520 10:39:25 INFO - 2016-02-25 10:39:25,542 DEBUG : BROWSER_OUTPUT: 10:39:28 INFO - 2016-02-25 10:39:28,394 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33825/tests/kraken/driver.html?testName=imaging-darkroom) 10:39:28 INFO - 2016-02-25 10:39:28,956 DEBUG : BROWSER_OUTPUT: RSS: Main: 159563776 10:39:28 INFO - 2016-02-25 10:39:28,957 DEBUG : BROWSER_OUTPUT: 10:39:32 INFO - 2016-02-25 10:39:32,287 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33825/tests/kraken/driver.html?testName=imaging-desaturate) 10:39:32 INFO - 2016-02-25 10:39:32,857 DEBUG : BROWSER_OUTPUT: RSS: Main: 160075776 10:39:32 INFO - 2016-02-25 10:39:32,858 DEBUG : BROWSER_OUTPUT: 10:39:35 INFO - 2016-02-25 10:39:35,567 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33825/tests/kraken/driver.html?testName=json-parse-financial) 10:39:36 INFO - 2016-02-25 10:39:36,141 DEBUG : BROWSER_OUTPUT: RSS: Main: 162222080 10:39:36 INFO - 2016-02-25 10:39:36,141 DEBUG : BROWSER_OUTPUT: 10:39:37 INFO - 2016-02-25 10:39:37,654 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33825/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:39:38 INFO - 2016-02-25 10:39:38,216 DEBUG : BROWSER_OUTPUT: RSS: Main: 168435712 10:39:38 INFO - 2016-02-25 10:39:38,216 DEBUG : BROWSER_OUTPUT: 10:39:40 INFO - 2016-02-25 10:39:40,006 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33825/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:39:40 INFO - 2016-02-25 10:39:40,593 DEBUG : BROWSER_OUTPUT: RSS: Main: 166187008 10:39:40 INFO - 2016-02-25 10:39:40,593 DEBUG : BROWSER_OUTPUT: 10:39:42 INFO - 2016-02-25 10:39:42,558 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33825/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:39:43 INFO - 2016-02-25 10:39:43,125 DEBUG : BROWSER_OUTPUT: RSS: Main: 171036672 10:39:43 INFO - 2016-02-25 10:39:43,125 DEBUG : BROWSER_OUTPUT: 10:39:45 INFO - 2016-02-25 10:39:45,486 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33825/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:39:46 INFO - 2016-02-25 10:39:46,067 DEBUG : BROWSER_OUTPUT: RSS: Main: 174784512 10:39:46 INFO - 2016-02-25 10:39:46,068 DEBUG : BROWSER_OUTPUT: 10:39:48 INFO - 2016-02-25 10:39:48,541 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33825/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:39:49 INFO - 2016-02-25 10:39:49,103 DEBUG : BROWSER_OUTPUT: RSS: Main: 174628864 10:39:49 INFO - 2016-02-25 10:39:49,104 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,712 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33825/tests/kraken/driver.html?testName=ai-astar) 10:39:50 INFO - 2016-02-25 10:39:50,962 DEBUG : BROWSER_OUTPUT: RSS: Main: 232558592 10:39:50 INFO - 2016-02-25 10:39:50,962 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,962 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:39:50 INFO - 2016-02-25 10:39:50,962 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:39:50 INFO - 2016-02-25 10:39:50,963 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:39:50 INFO - 2016-02-25 10:39:50,963 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:39:50 INFO - 2016-02-25 10:39:50,963 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;111;105;109;112;111;100;109;111;109 10:39:50 INFO - 2016-02-25 10:39:50,963 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;152;133;152;163;121;145;143;122;161;123 10:39:50 INFO - 2016-02-25 10:39:50,963 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;161;160;160;161;161;161;163;165;159 10:39:50 INFO - 2016-02-25 10:39:50,963 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;71;141;72;71;74;71;159;70 10:39:50 INFO - 2016-02-25 10:39:50,963 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;136;91;92;92;134;92;93;136 10:39:50 INFO - 2016-02-25 10:39:50,964 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;111;110;110;110;110;111;110;110 10:39:50 INFO - 2016-02-25 10:39:50,964 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;147;147;145;146;145;145;146;148;145 10:39:50 INFO - 2016-02-25 10:39:50,964 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;96;96;95;95;96;95;95;95 10:39:50 INFO - 2016-02-25 10:39:50,964 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;55;58;56;56;56;55;56;54;54 10:39:50 INFO - 2016-02-25 10:39:50,964 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;65;65;65;65;65;69;66;66;65;66 10:39:50 INFO - 2016-02-25 10:39:50,964 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;74;78;77;76;76;77;77;77;84 10:39:50 INFO - 2016-02-25 10:39:50,964 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;113;117;125;115;124;114;113;112;125;114 10:39:50 INFO - 2016-02-25 10:39:50,964 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;139;141;149;143;131;136;139;139;142;151 10:39:50 INFO - 2016-02-25 10:39:50,965 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;62;64;64;62;63;64;62;62;61 10:39:50 INFO - 2016-02-25 10:39:50,965 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:39:50 INFO - 2016-02-25 10:39:50,965 DEBUG : BROWSER_OUTPUT: __start_cc_report 10:39:50 INFO - 2016-02-25 10:39:50,965 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,873 10:39:50 INFO - 2016-02-25 10:39:50,965 DEBUG : BROWSER_OUTPUT: __end_cc_report 10:39:50 INFO - 2016-02-25 10:39:50,965 DEBUG : BROWSER_OUTPUT: __startTimestamp1456425590960__endTimestamp 10:39:50 INFO - 2016-02-25 10:39:50,965 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,966 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:39:50 INFO - 2016-02-25 10:39:50,966 DEBUG : BROWSER_OUTPUT: Number of tests: 14 10:39:50 INFO - 2016-02-25 10:39:50,967 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,967 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.50 Median:110.00 stddev:3.60 (3.3%) stddev-sans-first:3.81 10:39:50 INFO - 2016-02-25 10:39:50,967 DEBUG : BROWSER_OUTPUT: Values: 108.0 111.0 105.0 109.0 112.0 111.0 100.0 109.0 111.0 109.0 10:39:50 INFO - 2016-02-25 10:39:50,967 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,967 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:141.50 Median:148.50 stddev:15.96 (10.7%) stddev-sans-first:16.47 10:39:50 INFO - 2016-02-25 10:39:50,967 DEBUG : BROWSER_OUTPUT: Values: 152.0 133.0 152.0 163.0 121.0 145.0 143.0 122.0 161.0 123.0 10:39:50 INFO - 2016-02-25 10:39:50,967 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,967 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.73 (1.1%) stddev-sans-first:1.79 10:39:50 INFO - 2016-02-25 10:39:50,968 DEBUG : BROWSER_OUTPUT: Values: 160.0 161.0 160.0 160.0 161.0 161.0 161.0 163.0 165.0 159.0 10:39:50 INFO - 2016-02-25 10:39:50,968 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,968 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:87.10 Median:71.00 stddev:33.44 (47.1%) stddev-sans-first:34.96 10:39:50 INFO - 2016-02-25 10:39:50,968 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 71.0 141.0 72.0 71.0 74.0 71.0 159.0 70.0 10:39:50 INFO - 2016-02-25 10:39:50,968 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,968 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:105.20 Median:92.00 stddev:20.81 (22.6%) stddev-sans-first:21.60 10:39:50 INFO - 2016-02-25 10:39:50,969 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 136.0 91.0 92.0 92.0 134.0 92.0 93.0 136.0 10:39:50 INFO - 2016-02-25 10:39:50,969 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,969 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.20 Median:110.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 10:39:50 INFO - 2016-02-25 10:39:50,969 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 111.0 110.0 110.0 110.0 110.0 111.0 110.0 110.0 10:39:50 INFO - 2016-02-25 10:39:50,969 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,969 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:1.05 (0.7%) stddev-sans-first:1.12 10:39:50 INFO - 2016-02-25 10:39:50,969 DEBUG : BROWSER_OUTPUT: Values: 146.0 147.0 147.0 145.0 146.0 145.0 145.0 146.0 148.0 145.0 10:39:50 INFO - 2016-02-25 10:39:50,969 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,969 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 10:39:50 INFO - 2016-02-25 10:39:50,970 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 96.0 96.0 95.0 95.0 96.0 95.0 95.0 95.0 10:39:50 INFO - 2016-02-25 10:39:50,970 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,970 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.40 Median:56.00 stddev:1.26 (2.3%) stddev-sans-first:1.24 10:39:50 INFO - 2016-02-25 10:39:50,970 DEBUG : BROWSER_OUTPUT: Values: 54.0 55.0 58.0 56.0 56.0 56.0 55.0 56.0 54.0 54.0 10:39:50 INFO - 2016-02-25 10:39:50,970 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,970 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:65.50 stddev:1.25 (1.9%) stddev-sans-first:1.30 10:39:50 INFO - 2016-02-25 10:39:50,970 DEBUG : BROWSER_OUTPUT: Values: 65.0 65.0 65.0 65.0 65.0 69.0 66.0 66.0 65.0 66.0 10:39:50 INFO - 2016-02-25 10:39:50,970 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,970 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:77.00 stddev:3.33 (4.3%) stddev-sans-first:2.74 10:39:50 INFO - 2016-02-25 10:39:50,970 DEBUG : BROWSER_OUTPUT: Values: 84.0 74.0 78.0 77.0 76.0 76.0 77.0 77.0 77.0 84.0 10:39:50 INFO - 2016-02-25 10:39:50,970 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,971 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.20 Median:116.00 stddev:5.33 (4.6%) stddev-sans-first:5.43 10:39:50 INFO - 2016-02-25 10:39:50,971 DEBUG : BROWSER_OUTPUT: Values: 113.0 117.0 125.0 115.0 124.0 114.0 113.0 112.0 125.0 114.0 10:39:50 INFO - 2016-02-25 10:39:50,971 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,971 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:141.00 Median:141.50 stddev:5.83 (4.1%) stddev-sans-first:6.14 10:39:50 INFO - 2016-02-25 10:39:50,971 DEBUG : BROWSER_OUTPUT: Values: 139.0 141.0 149.0 143.0 131.0 136.0 139.0 139.0 142.0 151.0 10:39:50 INFO - 2016-02-25 10:39:50,971 DEBUG : BROWSER_OUTPUT: 10:39:50 INFO - 2016-02-25 10:39:50,971 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:1.06 (1.7%) stddev-sans-first:1.12 10:39:50 INFO - 2016-02-25 10:39:50,971 DEBUG : BROWSER_OUTPUT: Values: 63.0 62.0 64.0 64.0 62.0 63.0 64.0 62.0 62.0 61.0 10:39:50 INFO - 2016-02-25 10:39:50,971 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:39:50 INFO - 2016-02-25 10:39:50,971 DEBUG : BROWSER_OUTPUT: 10:39:51 INFO - 2016-02-25 10:39:51,657 INFO : Browser exited with error code: 0 10:39:51 INFO - 2016-02-25 10:39:51,711 INFO : Completed test kraken (00:00:57) 10:39:51 INFO - 2016-02-25 10:39:51,711 INFO : Starting test v8_7 10:39:51 INFO - 2016-02-25 10:39:51,712 DEBUG : operating with platform_type : linux_ 10:39:51 INFO - 2016-02-25 10:39:51,712 INFO : Initialising browser for v8_7 test... 10:39:51 INFO - 2016-02-25 10:39:51,718 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpbuVeBZ/profile http://localhost:33825/getInfo.html 10:39:55 INFO - 2016-02-25 10:39:55,963 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 10:39:55 INFO - 2016-02-25 10:39:55,963 DEBUG : BROWSER_OUTPUT: colorDepth:24 10:39:55 INFO - 2016-02-25 10:39:55,963 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 10:39:55 INFO - 2016-02-25 10:39:55,963 DEBUG : BROWSER_OUTPUT: __metrics 10:39:56 INFO - 2016-02-25 10:39:56,100 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:39:56 INFO - 2016-02-25 10:39:56,877 INFO : Browser initialized. 10:39:56 INFO - 2016-02-25 10:39:56,878 INFO : Running cycle 1/1 for v8_7 test... 10:39:56 INFO - 2016-02-25 10:39:56,878 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpbuVeBZ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 10:40:08 INFO - 2016-02-25 10:40:08,582 DEBUG : BROWSER_OUTPUT: RSS: Main: 132464640 10:40:08 INFO - 2016-02-25 10:40:08,583 DEBUG : BROWSER_OUTPUT: 10:40:29 INFO - 2016-02-25 10:40:29,938 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:33825/tests/v8_7/run.html (next: http://localhost:33825/tests/v8_7/run.html) 10:40:30 INFO - 2016-02-25 10:40:30,188 DEBUG : BROWSER_OUTPUT: RSS: Main: 424878080 10:40:30 INFO - 2016-02-25 10:40:30,188 DEBUG : BROWSER_OUTPUT: 10:40:30 INFO - 2016-02-25 10:40:30,188 DEBUG : BROWSER_OUTPUT: __start_tp_report 10:40:30 INFO - 2016-02-25 10:40:30,188 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 10:40:30 INFO - 2016-02-25 10:40:30,188 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 10:40:30 INFO - 2016-02-25 10:40:30,189 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 10:40:30 INFO - 2016-02-25 10:40:30,189 DEBUG : BROWSER_OUTPUT: |0;Richards;167.7008217340265 10:40:30 INFO - 2016-02-25 10:40:30,189 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;164.9620587264929 10:40:30 INFO - 2016-02-25 10:40:30,189 DEBUG : BROWSER_OUTPUT: |2;Encrypt;277.54648903691367 10:40:30 INFO - 2016-02-25 10:40:30,190 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5138.461538461538 10:40:30 INFO - 2016-02-25 10:40:30,190 DEBUG : BROWSER_OUTPUT: |4;RayTrace;968.054211035818 10:40:30 INFO - 2016-02-25 10:40:30,190 DEBUG : BROWSER_OUTPUT: |5;Earley;541.7118093174431 10:40:30 INFO - 2016-02-25 10:40:30,191 DEBUG : BROWSER_OUTPUT: |6;Boyer;10489.583333333334 10:40:30 INFO - 2016-02-25 10:40:30,191 DEBUG : BROWSER_OUTPUT: |7;RegExp;24190.47619047619 10:40:30 INFO - 2016-02-25 10:40:30,191 DEBUG : BROWSER_OUTPUT: |8;Splay;514.40329218107 10:40:30 INFO - 2016-02-25 10:40:30,191 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 10:40:30 INFO - 2016-02-25 10:40:30,192 DEBUG : BROWSER_OUTPUT: __end_tp_report 10:40:30 INFO - 2016-02-25 10:40:30,192 DEBUG : BROWSER_OUTPUT: __startTimestamp1456425630185__endTimestamp 10:40:30 INFO - 2016-02-25 10:40:30,192 DEBUG : BROWSER_OUTPUT: 10:40:30 INFO - 2016-02-25 10:40:30,192 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 10:40:30 INFO - 2016-02-25 10:40:30,193 DEBUG : BROWSER_OUTPUT: Number of tests: 10 10:40:30 INFO - 2016-02-25 10:40:30,193 DEBUG : BROWSER_OUTPUT: 10:40:30 INFO - 2016-02-25 10:40:30,193 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.70 Median:167.70 stddev:0.00 (0.0%) 10:40:30 INFO - 2016-02-25 10:40:30,193 DEBUG : BROWSER_OUTPUT: Values: 167.7 10:40:30 INFO - 2016-02-25 10:40:30,193 DEBUG : BROWSER_OUTPUT: 10:40:30 INFO - 2016-02-25 10:40:30,194 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:164.96 Median:164.96 stddev:0.00 (0.0%) 10:40:30 INFO - 2016-02-25 10:40:30,194 DEBUG : BROWSER_OUTPUT: Values: 165.0 10:40:30 INFO - 2016-02-25 10:40:30,194 DEBUG : BROWSER_OUTPUT: 10:40:30 INFO - 2016-02-25 10:40:30,194 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:277.55 Median:277.55 stddev:0.00 (0.0%) 10:40:30 INFO - 2016-02-25 10:40:30,194 DEBUG : BROWSER_OUTPUT: Values: 277.5 10:40:30 INFO - 2016-02-25 10:40:30,194 DEBUG : BROWSER_OUTPUT: 10:40:30 INFO - 2016-02-25 10:40:30,194 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5138.46 Median:5138.46 stddev:0.00 (0.0%) 10:40:30 INFO - 2016-02-25 10:40:30,195 DEBUG : BROWSER_OUTPUT: Values: 5138.5 10:40:30 INFO - 2016-02-25 10:40:30,195 DEBUG : BROWSER_OUTPUT: 10:40:30 INFO - 2016-02-25 10:40:30,195 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:968.05 Median:968.05 stddev:0.00 (0.0%) 10:40:30 INFO - 2016-02-25 10:40:30,195 DEBUG : BROWSER_OUTPUT: Values: 968.1 10:40:30 INFO - 2016-02-25 10:40:30,195 DEBUG : BROWSER_OUTPUT: 10:40:30 INFO - 2016-02-25 10:40:30,195 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:541.71 Median:541.71 stddev:0.00 (0.0%) 10:40:30 INFO - 2016-02-25 10:40:30,195 DEBUG : BROWSER_OUTPUT: Values: 541.7 10:40:30 INFO - 2016-02-25 10:40:30,195 DEBUG : BROWSER_OUTPUT: 10:40:30 INFO - 2016-02-25 10:40:30,196 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10489.58 Median:10489.58 stddev:0.00 (0.0%) 10:40:30 INFO - 2016-02-25 10:40:30,196 DEBUG : BROWSER_OUTPUT: Values: 10489.6 10:40:30 INFO - 2016-02-25 10:40:30,196 DEBUG : BROWSER_OUTPUT: 10:40:30 INFO - 2016-02-25 10:40:30,196 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24190.48 Median:24190.48 stddev:0.00 (0.0%) 10:40:30 INFO - 2016-02-25 10:40:30,196 DEBUG : BROWSER_OUTPUT: Values: 24190.5 10:40:30 INFO - 2016-02-25 10:40:30,196 DEBUG : BROWSER_OUTPUT: 10:40:30 INFO - 2016-02-25 10:40:30,196 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:514.40 Median:514.40 stddev:0.00 (0.0%) 10:40:30 INFO - 2016-02-25 10:40:30,196 DEBUG : BROWSER_OUTPUT: Values: 514.4 10:40:30 INFO - 2016-02-25 10:40:30,196 DEBUG : BROWSER_OUTPUT: 10:40:30 INFO - 2016-02-25 10:40:30,196 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 10:40:30 INFO - 2016-02-25 10:40:30,197 DEBUG : BROWSER_OUTPUT: Values: 4840.6 10:40:30 INFO - 2016-02-25 10:40:30,197 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 10:40:30 INFO - 2016-02-25 10:40:30,197 DEBUG : BROWSER_OUTPUT: 10:40:30 INFO - 2016-02-25 10:40:30,870 INFO : Browser exited with error code: 0 10:40:30 INFO - 2016-02-25 10:40:30,881 INFO : Completed test v8_7 (00:00:39) 10:40:31 INFO - 2016-02-25 10:40:31,212 INFO : Completed test suite (00:20:08) 10:40:31 INFO - 2016-02-25 10:40:31,212 DEBUG : Working with test: dromaeo_css 10:40:31 INFO - 2016-02-25 10:40:31,212 DEBUG : Generating results file: dromaeo_css 10:40:31 INFO - 2016-02-25 10:40:31,213 DEBUG : Working with test: kraken 10:40:31 INFO - 2016-02-25 10:40:31,213 DEBUG : Generating results file: kraken 10:40:31 INFO - 2016-02-25 10:40:31,214 INFO : javascript benchmark 10:40:31 INFO - 2016-02-25 10:40:31,214 DEBUG : Working with test: v8_7 10:40:31 INFO - 2016-02-25 10:40:31,214 DEBUG : Generating results file: v8_7 10:40:31 INFO - 2016-02-25 10:40:31,214 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:40:31 INFO - 2016-02-25 10:40:31,272 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 10:40:31 INFO - 2016-02-25 10:40:31,272 DEBUG : process_Request line: dromaeo_css 7353.62 graph.html#tests=[[72,53,35]] 10:40:31 INFO - 2016-02-25 10:40:31,272 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:40:31 INFO - 2016-02-25 10:40:31,312 DEBUG : process_Request line: kraken 1475.00 graph.html#tests=[[232,53,35]] 10:40:31 INFO - 2016-02-25 10:40:31,312 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 10:40:31 INFO - 2016-02-25 10:40:31,350 DEBUG : process_Request line: v8_7 23321.11 graph.html#tests=[[230,53,35]] 10:40:31 INFO - 2016-02-25 10:40:31,351 INFO : javascript benchmark 10:40:31 INFO - 2016-02-25 10:40:31,355 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3274.9345013099737, 3343.21434462901, 3378.983105084474, 3386.4073787087254, 3393.5079413485037, 5561.360443548993, 5571.498565129137, 5573.27547418836, 5594.49649531542, 5597.7201139942945, 5521.641093328941, 5569.0, 5589.524890384312, 5591.7483713232905, 5603.3836278009385, 4985.302057711923, 5059.0, 5069.949300506984, 5085.110105731482, 5162.174052151657, 5073.7209453480045, 5139.691618502902, 5139.897202055975, 5142.768575414097, 5148.073346797575, 4996.275540046691, 5055.646104772667, 5110.080185566597, 5154.639175257734, 5154.716490593016, 5054.393472783271, 5132.820351287724, 5142.999999999981, 5148.948510514884, 5172.560332371753, 4837.443693975203, 5101.362329708786, 5102.438731739506, 5105.285260063594, 5189.195674670432, 5291.047611429941, 5370.221317908881, 5370.973145134289, 5385.892282154335, 5394.73026348681, 5945.613535120204, 5964.850878728023, 6012.128241405016, 6019.69901504923, 6044.788432404889, 5747.252857128588, 5752.712364381806, 6061.909071363933, 6063.302720187191, 6086.2087928569445, 5523.861903452405, 5583.190437386556, 5587.580931430159, 5595.832125036255, 5634.690092044936, 5899.941000589939, 5937.52499800015, 5947.405259474104, 5950.7917222896995, 5955.880882382328, 5743.712814359265, 5746.626469279484, 5755.913661295084, 5769.624974376652, 5850.063989761617, 5990.700464976734, 6007.339192688801, 6032.487238584769, 6035.39646035393, 6048.516118710492, 5985.0, 6004.609700369462, 6008.729607167688, 6012.248468941382, 6020.247469066367, 5787.102999035156, 5904.7342869570975, 5946.197263369476, 6009.729562169713, 6026.156338112668, 4580.702254353457, 4583.037562111994, 4596.770161491912, 4597.517260687647, 4609.1703493371515, 4087.529934057562, 4108.589141085928, 4111.280525907954, 4126.546079931205, 4147.149834283965, 5461.563074953994, 5557.499825015768, 5635.746391412396, 5639.520640745501, 5729.54163666914, 5966.254218222723, 5968.283805943314, 5969.955257829863, 5978.521718262528, 6014.609050411709, 5599.132134519156, 5602.551795856321, 5603.3836278009385, 5603.5237004854225, 5619.100943848965, 5122.718250496258, 5170.663906846043, 5191.76637051326, 5209.947900520946, 5259.289995850588, 6190.969045154746, 6226.719797609027, 6228.128062071313, 6231.781887633911, 6240.251169859555, 6064.545159112996, 6076.179715738495, 6078.45293923549, 6094.939050609614, 6095.542834287357, 5793.217915581343, 5977.671228082323, 6102.224189113237, 6203.193584833943, 6237.345078766845, 5890.764369425175, 5917.822465326047, 5922.052471604523, 5922.111683247547, 5939.0, 5996.67018313997, 6000.639961602144, 6007.038873780174, 6009.819705408845, 6011.969940150271, 5710.457506536873, 5770.538356931352, 5807.216025836627, 5830.562707797017, 5863.970680146572, 5780.682062486603, 5892.469677729025, 5911.674857882857, 5923.170756094237, 5923.4076592340425, 5512.6968016757855, 5802.274715660543, 5830.708464576669, 5839.065749480063, 5846.006178949674, 5639.125935480007, 5735.139729040678, 5743.339515955551, 5763.6541807491685, 5764.654120752768, 5697.800576979786, 5777.191193232867, 5791.305043394819, 5792.884142317214, 5793.275840519935], "unit": "ms", "name": "dojo.html", "value": 5503.637425785846}, {"lowerIsBetter": false, "replicates": [628.845932746477, 631.7725618777239, 632.2728861808919, 637.8501052252719, 641.9101325814383, 33011.33977320455, 33559.16102097449, 42141.578584214185, 43046.13907721852, 43067.0, 45652.543474565246, 46177.30734038984, 46180.07639847201, 46270.305945410764, 46273.76863115688, 7973.920260797376, 8996.325275604308, 9166.083391660814, 9168.358214924958, 9170.358074934757, 8158.184181581824, 10727.141828653688, 11015.504302306415, 11020.779584408307, 11027.22809403342, 10605.628802991896, 10686.358818470877, 10701.892981070207, 10706.46467676613, 10708.1968852336, 8316.916830831675, 8543.316534677211, 10575.841362379571, 10692.144628429745, 10825.783484330348, 10466.215033872413, 10596.205284603686, 10602.522886470128, 10605.575776968912, 10610.628627997983, 42917.785411073055, 43401.34897976533, 43528.0, 43535.34696979548, 43652.56347436517, 3691.9446208306895, 3725.5715592706915, 4118.567550407224, 4315.741055536678, 4406.228909940747, 32548.186295342806, 32972.67027329744, 33110.50334244988, 33514.49728254078, 33634.0, 3722.1253005543676, 3747.344214762433, 3750.3811871041244, 3782.829772660237, 3810.40938654509, 309067.0, 309654.0, 310161.44919275487, 310771.0, 311151.0, 55713.72143139259, 56848.0, 62316.37683623196, 62334.68832655808, 63241.36758632447, 56908.43091569031, 56929.430705692415, 57210.0, 57222.42777572254, 57247.14129288064, 15734.9213253933, 16002.91998540023, 16006.599835003984, 16018.199090045684, 16024.679506409806, 11331.546738130382, 11421.486033128529, 11439.59961401347, 11440.828387574193, 11509.654710358702, 20543.178272869216, 20619.278325258543, 20647.58704825925, 20651.8967405162, 20745.0, 7642.961785191039, 7801.804954876174, 7860.606969651609, 7862.764117076496, 7998.720044798521, 972.5331840716495, 1097.2154909239932, 1105.4251789069638, 1110.7556337605713, 1116.213069785799, 29866.850665746966, 30057.398852023274, 30137.698623013926, 30455.69544304573, 30787.0, 1126.3748619516084, 1158.7045303447735, 1178.793711100554, 1246.6571692784557, 1255.6233130061128, 12752.74494510123, 15772.92113539425, 16135.354585816436, 16336.264868080727, 16451.67096658084, 337383.0, 342866.0, 344004.6798596046, 344373.0, 344860.0, 55977.72011139918, 56228.0, 56245.43754562484, 56266.0, 56269.43730562724, 1458.4020551573803, 1468.1411374346128, 1478.4899209772395, 1484.1540322016347, 1502.6543894904232, 29842.55236171416, 29891.505424729112, 30295.545566816076, 30627.54058689166, 30642.0, 48686.75656621694, 48838.26742598935, 48993.51006490032, 49059.754701226266, 49224.50775492199, 308958.0, 312292.0, 312639.0, 312712.0, 313077.0, 30179.245518861328, 32347.51478727868, 33264.66735332616, 34433.13917152091, 34508.13729656779, 41160.176796464504, 41444.79277603713, 41893.0, 42480.57519424766, 42871.7856410716, 36657.08357291089, 36821.26357472889, 37014.44478332773, 37046.07384815401, 37494.250114998096, 36510.8174459126, 36846.631533685395, 37228.62771372252, 38026.80986595049, 38670.80664596659, 34817.651823482454, 35070.82464587661, 35609.1097722559, 35676.28647426985, 35739.82130089333, 32627.0, 33551.66448335583, 33667.0, 34171.14572135716, 34271.828640856635, 26054.34864128412, 26213.0, 26501.469970600094, 26559.07043253477, 26662.20013399601], "unit": "ms", "name": "ext.html", "value": 19682.149083347704}, {"lowerIsBetter": false, "replicates": [373.7738668105796, 376.5387400434468, 377.22103855538313, 378.2548379692006, 378.6138139098118, 16519.0, 16728.665426691474, 18282.451526454228, 18375.0812459377, 18472.815271847277, 17507.47477575675, 17643.823561764377, 17652.117394130306, 17805.287788488444, 17933.193006314716, 4242.618164365206, 4369.431973843396, 5390.326209223847, 5398.325209348832, 5549.084401073838, 5883.588148829584, 6053.455189032986, 6101.237345331833, 6106.786262480814, 6107.81676549702, 5943.078822782476, 6017.39826017397, 6017.939820601781, 6018.916595012918, 6027.758889644409, 5996.070609055581, 5999.370066143036, 6022.668753218615, 6028.1861948636815, 6029.668368239723, 5302.973485132588, 5346.465353464661, 5350.8394748157225, 5355.250264962909, 5355.705436201008, 22096.558068838534, 22288.88855555728, 22645.88677056604, 22727.090916363326, 23691.88154059236, 2335.9532809343887, 2455.3493324269084, 2566.4225549251455, 2578.6003169508567, 2672.50558646651, 11176.329420234812, 14380.424783008668, 14626.926865365711, 15211.163386013764, 15352.309146088453, 1686.4519031314871, 1695.0507715679137, 1703.735920932245, 1706.5221737913405, 1727.3781438682065, 74093.25906740918, 77278.61360693217, 78330.60834695847, 79121.0, 79554.0, 16932.57668558296, 17160.485185444457, 17161.828381716274, 17167.742483862752, 17349.13254337278, 27672.58491122635, 27757.583636245472, 27776.58335124975, 28182.71817281842, 28288.15135545937, 8514.659413623509, 8896.733098007071, 10174.389536627827, 10191.337563058378, 10231.693049208536, 8392.874106888401, 8517.95741021291, 8619.61211745473, 8627.568621569022, 8648.43785153963, 7363.447741419415, 8211.34309255258, 8249.958750206331, 8363.832723345498, 8390.496570205807, 3029.666736658966, 3077.923051923675, 3118.3919135768306, 3196.9200769980935, 3252.105625992868, 912.3112050402036, 972.5817898303656, 1010.0555980158575, 1044.6187141693226, 1086.9619563315246, 15848.366065357257, 17070.829291707174, 17944.9102754488, 18088.638227235646, 18266.725999110025, 942.2414955960518, 942.3403617467663, 983.1937810994915, 1007.8488226766044, 1101.8347247912716, 9305.627774889064, 10251.12865406434, 10430.165586753194, 10485.423301718327, 10514.421706806199, 663386.0, 667682.0, 671760.0, 682158.0, 698185.0, 90392.0, 92600.0, 94594.0, 94648.0, 95047.52476237575, 1107.839363292318, 1128.0975219824109, 1134.3817619397448, 1153.4694040741185, 1178.776032553812, 15680.92159539195, 15696.430356964212, 16198.838011619733, 16989.0, 17213.31146754116, 38661.80669096749, 39160.608393915696, 39741.6405823033, 39795.80102099471, 40277.79861100676, 54160.45839541554, 54985.0, 56324.71837640786, 58619.4138058614, 58648.0, 10128.949355253177, 10494.475276236006, 10586.735331616772, 10600.0989915858, 10611.09805666527, 18757.155927983546, 18973.90513047426, 19032.714509282094, 19172.80827191766, 19362.612747745246, 15442.92278538645, 15443.459478918185, 15750.842491574938, 15833.287502062205, 15911.68176636484, 11834.171607987702, 14600.612941770514, 15107.327868279224, 15261.313240904408, 15717.842821571638, 15858.52424427269, 16032.0, 16108.919455402647, 16294.348226070824, 16336.591585210464, 10142.239332049978, 10192.286539942133, 10196.38821670702, 10268.178545716175, 10541.156707463231, 15371.38514459409, 15377.0, 15573.0, 15872.761908571612, 15908.602284942968], "unit": "ms", "name": "jquery.html", "value": 10399.349001218201}, {"lowerIsBetter": false, "replicates": [1519.3618680154334, 1521.216573464665, 1700.982990170098, 1774.6716857381386, 1808.077880281057, 7398.334149926505, 7806.687732490701, 7819.530828150308, 7820.257075577825, 7829.295363417305, 7514.736984205554, 7600.20197879223, 7609.201033891445, 7611.885821712679, 7624.542527448357, 2226.9331920042423, 2266.705328307318, 2288.233441796992, 2295.724513058427, 2296.334063121693, 2240.596692595332, 2285.702858628376, 2288.199130304397, 2288.5079707862806, 2288.530851175505, 1878.5867109236015, 1915.329634627883, 2236.0608544411307, 2272.556851413967, 2279.076973825603, 2294.5869743446174, 2312.5490529346785, 2341.262502311766, 2343.0744855781954, 2344.7772461616205, 2268.761780013108, 2314.9189778357845, 2318.8376813623054, 2319.9188028418926, 2320.11835502508, 6799.966000169968, 6929.792106236821, 7278.1630112537205, 7411.184769675331, 7432.0, 4500.34744961982, 4590.104929538741, 4603.470600880908, 4603.792829322689, 4609.539046095397, 2943.1170648805487, 2945.806948185988, 2972.4055188962293, 2972.4649563078638, 2993.3863557970562, 1031.2265800649513, 1041.7343577387794, 1046.9633562825265, 1080.4219742437758, 1095.638439315024, 10273.897261027443, 10337.431441270726, 10340.276180667357, 10341.172706183488, 10350.948245258725, 4193.32906734927, 4194.224068547329, 4249.745015299092, 4265.573442655709, 4268.0, 9093.636254549743, 9107.81784364309, 9110.49892255919, 9111.589978450986, 9112.908870911338, 4720.669553131248, 4721.3390125382475, 4743.430788305356, 4748.358971538867, 4750.026244619845, 4320.805563749639, 4321.805518751664, 4323.416338794285, 4323.632491238217, 4327.6321512671775, 3496.9125771855906, 3564.518789963322, 3580.0691820126435, 3582.570091588974, 3600.6399360064306, 1141.668916014363, 1143.7883991461606, 1152.1474109159162, 1158.8667303260233, 1163.5462169753803, 1421.5593166118535, 1432.8208973878266, 1437.4322142753554, 1439.8344190418236, 1474.6608280095425, 7279.344858962613, 7338.77983660491, 7342.265773422615, 7350.816229594196, 7352.889706654404, 476.94515130759714, 486.5183468366292, 496.5158970004217, 497.3410231443352, 499.7701057513512, 5459.454054594509, 5755.712214389264, 5856.0, 5858.707064646751, 5860.09168578871, 7395.889061664079, 7421.0723659542555, 7508.812279693051, 7517.097948246245, 7791.727289544839, 8805.603747831236, 8970.775730606656, 9097.09029097085, 9133.49765762876, 9173.128552787475, 7235.855282894313, 7266.92733072673, 7275.527090739085, 7349.522281051822, 7351.081114860643, 7084.716611335489, 7345.853082938418, 7391.0, 7428.442866784904, 7429.59137247435, 7886.605669716377, 8021.0, 8047.235512626291, 8067.3142782864115, 8101.351891848756, 8402.07577166499, 8422.199891010345, 8555.443896146731, 8569.914300856912, 8605.418374487754, 6705.4635629150525, 6751.831204219934, 6763.154605674291, 6770.356816102462, 6770.559913605797, 7220.169680486812, 7263.201047884834, 7289.927100728924, 7292.781216563511, 7302.0142280791415, 6851.554648947803, 6928.1339832520935, 6937.791866244021, 6963.79108626742, 6993.510454268152, 6693.330666933268, 6828.044073829568, 6836.145481814773, 6913.619750913748, 6955.026296318419, 6816.318368163343, 6951.687174077279, 6955.965220173866, 6965.199002114619, 6968.059311992816, 6405.455536279282, 6758.256591774999, 6768.390844823989, 6774.593524388552, 6774.593524388552, 6624.735010599715, 6847.007183958199, 6853.657317134223, 6857.965710171417, 6865.073215115895], "unit": "ms", "name": "mootools.html", "value": 4364.9483160914715}, {"lowerIsBetter": false, "replicates": [353.72409520573945, 359.0449404583806, 359.63317416235424, 360.6988164882323, 360.7060245899593, 6029.1257767623665, 6141.416565426283, 6149.13912052313, 6161.2298462692215, 6234.283057448395, 5833.5333173346135, 5896.292444906607, 5951.613145145562, 6059.606125601833, 6062.818115456544, 4700.224462963607, 4727.952720472803, 4742.264948932912, 4749.643776716743, 4756.714597124166, 4711.858644240679, 4718.245080787075, 4718.882027949295, 4725.503822098682, 4743.146233677954, 4494.775261236941, 4611.677182597219, 4621.145088158684, 4698.553637404441, 4716.952830471703, 4683.086798074377, 4696.694714843541, 4699.271612900006, 4701.78841952113, 4714.0336231072715, 4129.607687269705, 4134.91730165395, 4154.646855017327, 4160.45914031177, 4162.604552567501, 5730.455606717397, 5802.390748971339, 5809.738561764689, 5830.82507524775, 5840.649561026352, 5484.643498172606, 5496.1755736639825, 5499.780008799643, 5502.064649009638, 5502.367227768817, 6374.235091788969, 6375.139356186948, 6381.329960354143, 6381.393767592118, 6385.138006369127, 4613.192691279046, 4614.330922016323, 4619.284010978304, 4620.0, 4629.560191781776, 6493.415592596689, 6641.468682505436, 6641.966790166067, 6690.498212634072, 6699.799006029875, 4600.769961501912, 4636.86089417318, 4637.118947399983, 4638.652101092364, 4642.0715856828765, 4685.578297953132, 4693.95306046942, 4696.7416792076765, 4705.0, 4706.0, 5415.241866138744, 5425.403205647375, 5425.620206585501, 5430.891382172414, 5431.076716958127, 6350.618962862243, 6486.2216534016225, 6489.3510648934725, 6490.448311893542, 6492.480601551864, 1630.5842010287538, 1649.175412293853, 1650.2903751387018, 1650.4058538925976, 1652.1986836384344, 1091.263397206898, 1104.8397982292522, 1107.291333546531, 1122.4668282565879, 1125.9099272058215, 6280.591761535486, 6283.654399008007, 6291.02489114188, 6293.615404610979, 6298.0, 6683.565568238049, 6688.83277918056, 6690.498212633975, 6700.095487109178, 6700.296468870796, 6089.908651370233, 6097.8475538111015, 6261.373862613793, 6263.217097862767, 6265.028920517327, 3591.676749092594, 3640.8361623726996, 3644.0889777555612, 3651.0, 3654.780713157166, 6999.09011828459, 7010.859782804316, 7011.824704382431, 7014.649267536603, 7023.543469674455, 6778.118844550276, 6898.517103802686, 6931.410830079399, 6939.7224111036, 6942.618156001367, 6990.405815505739, 6997.195322537871, 6998.160220773438, 6998.195207551095, 6998.335158159966, 6649.368310010541, 6753.696083676148, 6763.5941843489545, 6769.932300677127, 6771.255161932085, 6687.431568316748, 6688.531802773759, 6692.59844409337, 6694.163229596301, 6694.196696396463, 6929.480288978448, 6954.930450695429, 6996.790096297119, 7004.089468369079, 7016.719331226694, 4646.047560250073, 4653.9301910470695, 4658.464276608098, 4662.953370466252, 4663.347131401674, 6569.605823650596, 6719.596824190564, 6722.7562900863495, 6732.293109223461, 6732.394084532416, 6588.341165883373, 6592.143021407186, 6592.670366481752, 6594.868102638016, 6602.4057834793175, 6341.365863413622, 6478.546501744832, 6516.543841931019, 6518.315576864551, 6521.543491955517, 6423.164988551458, 6433.710483028181, 6459.386358295955, 6475.320091390525, 6476.70854811544, 6540.247871494839, 6605.174353205849, 6608.537402381787, 6609.966950165219, 6611.603303801595, 6241.21984751906, 6443.71003304843, 6450.516211284266, 6453.0, 6461.2892581816905], "unit": "ms", "name": "prototype.html", "value": 4925.636865997901}, {"lowerIsBetter": false, "replicates": [866.5234121233322, 866.9523176225313, 899.6941040046387, 944.787422829863, 947.6493697331986, 12152.756944861108, 13024.348782560857, 13049.804252936214, 13060.542880999166, 13081.67295817605, 12100.213486123395, 12143.149979501417, 12155.878441215586, 12171.878281217185, 12196.756064878718, 738.8706976279157, 743.8772602520578, 870.8519551676198, 871.172386233078, 882.7175303902753, 871.0592560035146, 883.659790980474, 884.5798245833209, 885.7342797160826, 886.3042511628373, 786.2805532937356, 913.3150137396952, 918.5085979001236, 919.2232563483846, 919.7240827751648, 786.3512602103259, 920.1350730313517, 929.0662883801804, 930.7068273493828, 930.8510638297907, 691.0498065160405, 691.0774116554406, 691.4572060932144, 693.1681981622073, 693.8438851258477, 12517.56188533397, 12644.557440489538, 12663.873361266362, 12722.61832145037, 12730.427130779044, 11695.532178712841, 12327.69180770488, 12453.564125255572, 12488.75022499554, 12524.436400362005, 10360.171186305153, 10369.896301036968, 10376.896231037745, 10497.63258285964, 10586.52360643773, 3524.9118772030647, 3572.2676851245433, 3577.105723569108, 3582.0686621478344, 3586.4082426399614, 48759.26861097087, 49460.258096128586, 49611.50388496105, 49670.50329496731, 49849.75075124637, 9768.36505627132, 9807.26445516589, 9847.359921605073, 9863.063009014133, 9997.80004399908, 19507.902460487607, 19525.707114393295, 19579.11893964775, 19590.510237244183, 20194.596108077756, 7100.218975912646, 7124.145102587619, 7167.4266058715175, 7227.27727227273, 7254.492185546961, 4079.204555111784, 4121.340585506305, 4129.215449064668, 4137.586241375839, 4143.129942712064, 3238.1904523869034, 3276.820344675915, 3283.3761585298716, 3284.4416449203213, 3285.3593549258144, 538.9137737961986, 544.9455054494598, 558.5587385965123, 572.0561074227558, 593.489598944907, 547.2775935764831, 558.6927190045459, 639.3079963212485, 650.6851010938827, 689.1076056506836, 8338.416310858182, 9018.909810901938, 9041.81916361669, 9086.545672716338, 9110.908890911138, 620.9472194863397, 642.0497663458108, 646.2600322630561, 714.9177844547839, 721.3688022979893, 4341.261985462479, 4349.064951035542, 4411.117776444659, 4457.64338852891, 4489.281714925596, 62617.68691156606, 63138.68430657817, 63399.36600633935, 63475.36524634787, 63488.36511634917, 54438.18342724862, 55243.0, 55367.72316138393, 55571.0, 55622.0, 47777.28334074992, 50589.49410505922, 50631.0, 50741.0, 51258.23112653313, 8713.302935764998, 8870.467771933447, 9037.09629037091, 9366.0, 9578.808423831624, 37934.27417742703, 38002.619973799905, 38613.2277354457, 38727.61272387353, 38767.80616097014, 47771.0, 48347.274790878866, 49102.0, 49397.50602493929, 50238.74880625573, 11257.662270131908, 11558.190926635054, 11877.643670689893, 12106.636800895987, 12257.0, 12791.424385902843, 12848.935755321163, 12949.0, 12955.287459189834, 13039.95680345589, 11435.582334503933, 11884.82172767392, 11942.223755455867, 12197.390130493617, 12243.265404075784, 11765.235259708434, 11804.409779511161, 12137.939310303393, 12241.387930603612, 12497.18768280059, 10788.0, 11239.719007024889, 11314.943425282821, 11659.358735269641, 11687.123465740273, 10453.0, 10600.310979786289, 10716.0, 10793.838092428778, 10817.459127043774, 9374.390664606779, 9403.529823508934, 9489.525523723923, 9545.665901693408, 9720.416774993522], "unit": "ms", "name": "yui.html", "value": 6528.215673629546}], "name": "dromaeo_css", "value": 7353.619830676446}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 111.0, 105.0, 109.0, 112.0, 111.0, 100.0, 109.0, 111.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 108.5}, {"lowerIsBetter": true, "replicates": [152.0, 133.0, 152.0, 163.0, 121.0, 145.0, 143.0, 122.0, 161.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 141.5}, {"lowerIsBetter": true, "replicates": [160.0, 161.0, 160.0, 160.0, 161.0, 161.0, 161.0, 163.0, 165.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 161.1}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 71.0, 141.0, 72.0, 71.0, 74.0, 71.0, 159.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 87.1}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 136.0, 91.0, 92.0, 92.0, 134.0, 92.0, 93.0, 136.0], "unit": "ms", "name": "audio-oscillator", "value": 105.2}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 111.0, 110.0, 110.0, 110.0, 110.0, 111.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.2}, {"lowerIsBetter": true, "replicates": [146.0, 147.0, 147.0, 145.0, 146.0, 145.0, 145.0, 146.0, 148.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.0}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [54.0, 55.0, 58.0, 56.0, 56.0, 56.0, 55.0, 56.0, 54.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 55.4}, {"lowerIsBetter": true, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 69.0, 66.0, 66.0, 65.0, 66.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 65.7}, {"lowerIsBetter": true, "replicates": [84.0, 74.0, 78.0, 77.0, 76.0, 76.0, 77.0, 77.0, 77.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.0}, {"lowerIsBetter": true, "replicates": [113.0, 117.0, 125.0, 115.0, 124.0, 114.0, 113.0, 112.0, 125.0, 114.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.2}, {"lowerIsBetter": true, "replicates": [139.0, 141.0, 149.0, 143.0, 131.0, 136.0, 139.0, 139.0, 142.0, 151.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 141.0}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 64.0, 64.0, 62.0, 63.0, 64.0, 62.0, 62.0, 61.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 62.7}], "name": "kraken", "value": 1475.0000000000002}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.7008217340265], "unit": "score", "name": "Richards", "value": 210.50582599999998}, {"lowerIsBetter": false, "replicates": [164.9620587264929], "unit": "score", "name": "DeltaBlue", "value": 400.8073159999999}, {"lowerIsBetter": false, "replicates": [277.54648903691367], "unit": "score", "name": "Encrypt", "value": 959.0501430000003}, {"lowerIsBetter": false, "replicates": [5138.461538461538], "unit": "score", "name": "Decrypt", "value": 51.80169161676644}, {"lowerIsBetter": false, "replicates": [968.054211035818], "unit": "score", "name": "RayTrace", "value": 764.4086370000001}, {"lowerIsBetter": false, "replicates": [541.7118093174431], "unit": "score", "name": "Earley", "value": 1230.2906979999998}, {"lowerIsBetter": false, "replicates": [10489.583333333334], "unit": "score", "name": "Boyer", "value": 63.5356981132075}, {"lowerIsBetter": false, "replicates": [24190.47619047619], "unit": "score", "name": "RegExp", "value": 37.65882874015749}, {"lowerIsBetter": false, "replicates": [514.40329218107], "unit": "score", "name": "Splay", "value": 158.41850399999996}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 23321.10677313973}]} 10:40:31 INFO - RETURN: dromaeo_css: 7353.62 10:40:31 INFO - RETURN: kraken: 1475.00 10:40:31 INFO - RETURN: v8_7: 23321.11 10:40:31 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23321.11"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1475.00"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7353.62"}}} 10:40:31 INFO - Return code: 0 10:40:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 10:40:31 INFO - # TBPL SUCCESS # 10:40:31 INFO - Running post-action listener: _resource_record_post_action 10:40:31 INFO - Running post-run listener: _resource_record_post_run 10:40:32 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 12288; Write bytes: 518410240; Read time: 184; Write time: 525796 10:40:32 INFO - install - Wall time: 15s; CPU: 16.0%; Read bytes: 4096; Write bytes: 255983616; Read time: 96; Write time: 373544 10:40:32 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 8192; Write bytes: 261828608; Read time: 88; Write time: 152252 10:40:32 INFO - Running post-run listener: _upload_blobber_files 10:40:32 INFO - Blob upload gear active. 10:40:32 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 10:40:32 INFO - Copying logs to upload dir... 10:40:32 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1258.255101 ========= master_lag: 2.71 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-02-25 10:40:35.129492) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 10:40:35.136673) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009989 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456416027/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.44 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-25 10:40:35.589581) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:40:35.589949) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1456423995.708253-1310425825 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004607 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-25 10:40:35.609832) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 10:40:35.610150) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-25 10:40:35.613494) ========= ========= Total master_lag: 3.35 =========