builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1452730044.72 results: success (0) buildid: 20160113133026 builduid: ef2f09112877407f9bd4ea07d5d77d19 revision: 589761e5c8173337e82b7635f7a42cc3bcc9884e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:24.716682) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:24.717075) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:24.717353) ========= bash -c pwd in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006332 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:24.742320) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:24.742604) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:24.761557) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:24.761811) ========= rm -rf properties in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004548 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:24.817980) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:24.818263) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:24.818596) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:24.818860) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-13 16:07:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 31.0M=0s 2016-01-13 16:07:25 (31.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092876 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:24.923756) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:24.924030) ========= rm -rf scripts in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017139 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:25.008386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-13 16:07:25.008703) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 589761e5c8173337e82b7635f7a42cc3bcc9884e --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 589761e5c8173337e82b7635f7a42cc3bcc9884e --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-13 16:07:25,531 truncating revision to first 12 chars 2016-01-13 16:07:25,531 Setting DEBUG logging. 2016-01-13 16:07:25,531 attempt 1/10 2016-01-13 16:07:25,531 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/589761e5c817?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-13 16:07:26,347 unpacking tar archive at: mozilla-inbound-589761e5c817/testing/mozharness/ program finished with exit code 0 elapsedTime=1.109578 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-13 16:07:26.131038) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:26.131353) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:26.139509) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:26.139762) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-13 16:07:26.140173) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-01-13 16:07:26.140452) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:07:26 INFO - MultiFileLogger online at 20160113 16:07:26 in /builds/slave/test-pgo 16:07:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 16:07:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 16:07:26 INFO - {'append_to_log': False, 16:07:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 16:07:26 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 16:07:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 16:07:26 INFO - 'branch': 'Mozilla-Inbound', 16:07:26 INFO - 'buildbot_json_path': 'buildprops.json', 16:07:26 INFO - 'config_files': ('talos/linux_config.py',), 16:07:26 INFO - 'default_actions': ('clobber', 16:07:26 INFO - 'read-buildbot-config', 16:07:26 INFO - 'download-and-extract', 16:07:26 INFO - 'populate-webroot', 16:07:26 INFO - 'create-virtualenv', 16:07:26 INFO - 'install', 16:07:26 INFO - 'run-tests'), 16:07:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:07:26 INFO - 'download_minidump_stackwalk': True, 16:07:26 INFO - 'download_symbols': 'ondemand', 16:07:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:07:26 INFO - 'tooltool.py': '/tools/tooltool.py', 16:07:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:07:26 INFO - '/tools/misc-python/virtualenv.py')}, 16:07:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:07:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:07:26 INFO - 'installer_path': 'installer.exe', 16:07:26 INFO - 'log_level': 'info', 16:07:26 INFO - 'log_name': 'talos', 16:07:26 INFO - 'log_to_console': True, 16:07:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:07:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:07:26 INFO - 'opt_config_files': (), 16:07:26 INFO - 'pip_index': False, 16:07:26 INFO - 'sps_profile': False, 16:07:26 INFO - 'sps_profile_interval': 0, 16:07:26 INFO - 'suite': 'dromaeojs', 16:07:26 INFO - 'system_bits': '32', 16:07:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:07:26 INFO - 'title': 'talos-linux64-ix-023', 16:07:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:07:26 INFO - 'use_talos_json': True, 16:07:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 16:07:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:07:26 INFO - 'work_dir': 'build'} 16:07:26 INFO - ##### 16:07:26 INFO - ##### Running clobber step. 16:07:26 INFO - ##### 16:07:26 INFO - Running pre-action listener: _resource_record_pre_action 16:07:26 INFO - Running main action method: clobber 16:07:26 INFO - rmtree: /builds/slave/test-pgo/build 16:07:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 16:07:27 INFO - Running post-action listener: _resource_record_post_action 16:07:27 INFO - ##### 16:07:27 INFO - ##### Running read-buildbot-config step. 16:07:27 INFO - ##### 16:07:27 INFO - Running pre-action listener: _resource_record_pre_action 16:07:27 INFO - Running main action method: read_buildbot_config 16:07:27 INFO - Using buildbot properties: 16:07:27 INFO - { 16:07:27 INFO - "project": "", 16:07:27 INFO - "product": "firefox", 16:07:27 INFO - "script_repo_revision": "production", 16:07:27 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 16:07:27 INFO - "repository": "", 16:07:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 16:07:27 INFO - "buildid": "20160113133026", 16:07:27 INFO - "pgo_build": "True", 16:07:27 INFO - "basedir": "/builds/slave/test-pgo", 16:07:27 INFO - "buildnumber": 1440, 16:07:27 INFO - "slavename": "talos-linux64-ix-023", 16:07:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:07:27 INFO - "platform": "ubuntu64_hw", 16:07:27 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 16:07:27 INFO - "branch": "mozilla-inbound", 16:07:27 INFO - "repo_path": "integration/mozilla-inbound", 16:07:27 INFO - "revision": "589761e5c8173337e82b7635f7a42cc3bcc9884e", 16:07:27 INFO - "stage_platform": "linux64-pgo", 16:07:27 INFO - "builduid": "ef2f09112877407f9bd4ea07d5d77d19", 16:07:27 INFO - "slavebuilddir": "test" 16:07:27 INFO - } 16:07:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 16:07:27 INFO - Running post-action listener: _resource_record_post_action 16:07:27 INFO - ##### 16:07:27 INFO - ##### Running download-and-extract step. 16:07:27 INFO - ##### 16:07:27 INFO - Running pre-action listener: _resource_record_pre_action 16:07:27 INFO - Running main action method: download_and_extract 16:07:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 16:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:27 INFO - https://queue.taskcluster.net/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 16:07:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/test_packages.json 16:07:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/test_packages.json 16:07:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 16:07:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 16:07:28 INFO - Downloaded 1448 bytes. 16:07:28 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 16:07:28 INFO - Using the following test package requirements: 16:07:28 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 16:07:28 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:07:28 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:07:28 INFO - u'gtest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:07:28 INFO - u'firefox-46.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:07:28 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:07:28 INFO - u'jsshell-linux-x86_64.zip'], 16:07:28 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:07:28 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:07:28 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 16:07:28 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:07:28 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:07:28 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:07:28 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:07:28 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:07:28 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:07:28 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 16:07:28 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 16:07:28 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:07:28 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:07:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:28 INFO - https://queue.taskcluster.net/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:07:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:07:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:07:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:07:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:07:31 INFO - Downloaded 24031379 bytes. 16:07:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 16:07:31 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 16:07:33 INFO - Return code: 0 16:07:33 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:07:33 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')]} 16:07:33 INFO - https://queue.taskcluster.net/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:07:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:07:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:07:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 16:07:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:07:37 INFO - Downloaded 24031379 bytes. 16:07:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 16:07:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 16:07:38 INFO - Return code: 0 16:07:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:38 INFO - https://queue.taskcluster.net/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:07:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:07:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:07:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 16:07:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:07:41 INFO - Downloaded 11132279 bytes. 16:07:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 16:07:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 16:07:41 INFO - Return code: 0 16:07:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:41 INFO - https://queue.taskcluster.net/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:07:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:07:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:07:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:07:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:07:45 INFO - Downloaded 55870345 bytes. 16:07:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:07:45 INFO - mkdir: /builds/slave/test-pgo/properties 16:07:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:07:45 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:07:45 INFO - Contents: 16:07:45 INFO - build_url:https://queue.taskcluster.net/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 16:07:45 INFO - Running post-action listener: _resource_record_post_action 16:07:45 INFO - Running post-action listener: set_extra_try_arguments 16:07:45 INFO - ##### 16:07:45 INFO - ##### Running populate-webroot step. 16:07:45 INFO - ##### 16:07:45 INFO - Running pre-action listener: _resource_record_pre_action 16:07:45 INFO - Running main action method: populate_webroot 16:07:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:07:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:07:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:07:45 INFO - '--tppagecycles', 16:07:45 INFO - '7'], 16:07:45 INFO - 'tests': ['tsvgm']}}, 16:07:45 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:07:45 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:07:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:07:45 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:07:45 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:07:45 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:07:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:07:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:45 INFO - 'talos_options': ['--e10s'], 16:07:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:07:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:45 INFO - 'tests': ['damp', 'tps']}, 16:07:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:45 INFO - 'talos_options': ['--e10s'], 16:07:45 INFO - 'tests': ['damp', 'tps']}, 16:07:45 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:07:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:07:45 INFO - 'other': {'tests': ['a11yr', 16:07:45 INFO - 'ts_paint', 16:07:45 INFO - 'tpaint', 16:07:45 INFO - 'sessionrestore', 16:07:45 INFO - 'sessionrestore_no_auto_restore']}, 16:07:45 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:07:45 INFO - 'tests': ['a11yr', 16:07:45 INFO - 'ts_paint', 16:07:45 INFO - 'tpaint', 16:07:45 INFO - 'sessionrestore', 16:07:45 INFO - 'sessionrestore_no_auto_restore']}, 16:07:45 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:07:45 INFO - 'tests': ['a11yr', 16:07:45 INFO - 'ts_paint', 16:07:45 INFO - 'tpaint', 16:07:45 INFO - 'sessionrestore', 16:07:45 INFO - 'sessionrestore_no_auto_restore']}, 16:07:45 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:07:45 INFO - 'tests': ['a11yr', 16:07:45 INFO - 'ts_paint', 16:07:45 INFO - 'tpaint', 16:07:45 INFO - 'sessionrestore', 16:07:45 INFO - 'sessionrestore_no_auto_restore']}, 16:07:45 INFO - 'other_l64': {'tests': ['a11yr', 16:07:45 INFO - 'ts_paint', 16:07:45 INFO - 'tpaint', 16:07:45 INFO - 'sessionrestore', 16:07:45 INFO - 'sessionrestore_no_auto_restore']}, 16:07:45 INFO - 'other_nol64': {'tests': ['a11yr', 16:07:45 INFO - 'ts_paint', 16:07:45 INFO - 'tpaint', 16:07:45 INFO - 'sessionrestore', 16:07:45 INFO - 'sessionrestore_no_auto_restore']}, 16:07:45 INFO - 'svgr': {'tests': ['tsvgx', 16:07:45 INFO - 'tsvgr_opacity', 16:07:45 INFO - 'tart', 16:07:45 INFO - 'tscrollx', 16:07:45 INFO - 'cart']}, 16:07:45 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:07:45 INFO - 'tests': ['tsvgx', 16:07:45 INFO - 'tsvgr_opacity', 16:07:45 INFO - 'tart', 16:07:45 INFO - 'tscrollx', 16:07:45 INFO - 'cart']}, 16:07:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:45 INFO - 'tests': ['tp5o']}, 16:07:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:45 INFO - 'talos_options': ['--e10s'], 16:07:45 INFO - 'tests': ['tp5o']}, 16:07:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:45 INFO - 'talos_options': ['--xperf_path', 16:07:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:07:45 INFO - 'tests': ['tp5n']}, 16:07:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:07:45 INFO - 'talos_options': ['--e10s', 16:07:45 INFO - '--xperf_path', 16:07:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:07:45 INFO - 'tests': ['tp5n']}}, 16:07:45 INFO - 'talos.zip': {'path': '', 16:07:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:07:45 INFO - Running post-action listener: _resource_record_post_action 16:07:45 INFO - ##### 16:07:45 INFO - ##### Running create-virtualenv step. 16:07:45 INFO - ##### 16:07:45 INFO - Running pre-action listener: _resource_record_pre_action 16:07:45 INFO - Running main action method: create_virtualenv 16:07:45 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:07:45 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 16:07:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:07:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:07:45 INFO - Using real prefix '/usr' 16:07:45 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:07:45 INFO - Installing distribute.............................................................................................................................................................................................done. 16:07:47 INFO - Installing pip.................done. 16:07:47 INFO - Return code: 0 16:07:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:47 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fc998f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x255d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ec540>, '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 0x25ed440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ed8f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', '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 16:07:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 16:07:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:07:47 INFO - Using env: {'DISPLAY': ':0', 16:07:47 INFO - 'HOME': '/home/cltbld', 16:07:47 INFO - 'LANG': 'en_US.UTF-8', 16:07:47 INFO - 'LANGUAGE': 'en_US:en', 16:07:47 INFO - 'LOGNAME': 'cltbld', 16:07:47 INFO - 'MAIL': '/var/mail/cltbld', 16:07:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:47 INFO - 'MOZ_NO_REMOTE': '1', 16:07:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:07:47 INFO - 'NO_EM_RESTART': '1', 16:07:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:07:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:07:47 INFO - 'PWD': '/builds/slave/test-pgo', 16:07:47 INFO - 'SHELL': '/bin/bash', 16:07:47 INFO - 'SHLVL': '1', 16:07:47 INFO - 'TERM': 'linux', 16:07:47 INFO - 'TMOUT': '86400', 16:07:47 INFO - 'USER': 'cltbld', 16:07:47 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', 16:07:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:47 INFO - '_': '/tools/buildbot/bin/python'} 16:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:48 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 16:07:48 INFO - Cleaning up... 16:07:48 INFO - Return code: 0 16:07:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:07: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')]} 16:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07: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')]} 16:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:48 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fc998f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x255d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ec540>, '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 0x25ed440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ed8f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', '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 16:07:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 16:07:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:07:48 INFO - Using env: {'DISPLAY': ':0', 16:07:48 INFO - 'HOME': '/home/cltbld', 16:07:48 INFO - 'LANG': 'en_US.UTF-8', 16:07:48 INFO - 'LANGUAGE': 'en_US:en', 16:07:48 INFO - 'LOGNAME': 'cltbld', 16:07:48 INFO - 'MAIL': '/var/mail/cltbld', 16:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:48 INFO - 'MOZ_NO_REMOTE': '1', 16:07:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:07:48 INFO - 'NO_EM_RESTART': '1', 16:07:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:07:48 INFO - 'PWD': '/builds/slave/test-pgo', 16:07:48 INFO - 'SHELL': '/bin/bash', 16:07:48 INFO - 'SHLVL': '1', 16:07:48 INFO - 'TERM': 'linux', 16:07:48 INFO - 'TMOUT': '86400', 16:07:48 INFO - 'USER': 'cltbld', 16:07:48 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', 16:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:48 INFO - '_': '/tools/buildbot/bin/python'} 16:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:48 INFO - Downloading/unpacking psutil>=0.7.1 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07:50 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 16:07:50 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 16:07:50 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:07:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:07:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:07:50 INFO - Installing collected packages: psutil 16:07:50 INFO - Running setup.py install for psutil 16:07:50 INFO - building 'psutil._psutil_linux' extension 16:07:50 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 16:07:50 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 16:07:50 INFO - building 'psutil._psutil_posix' extension 16:07:50 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 16:07:50 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 16:07:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:07:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:07:50 INFO - Successfully installed psutil 16:07:50 INFO - Cleaning up... 16:07:50 INFO - Return code: 0 16:07:50 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:07: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')]} 16:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07: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')]} 16:07:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:50 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa3fc998f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x255d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ec540>, '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 0x25ed440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ed8f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', '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 16:07:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 16:07:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:07:50 INFO - Using env: {'DISPLAY': ':0', 16:07:50 INFO - 'HOME': '/home/cltbld', 16:07:50 INFO - 'LANG': 'en_US.UTF-8', 16:07:50 INFO - 'LANGUAGE': 'en_US:en', 16:07:50 INFO - 'LOGNAME': 'cltbld', 16:07:50 INFO - 'MAIL': '/var/mail/cltbld', 16:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:50 INFO - 'MOZ_NO_REMOTE': '1', 16:07:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:07:50 INFO - 'NO_EM_RESTART': '1', 16:07:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:07:50 INFO - 'PWD': '/builds/slave/test-pgo', 16:07:50 INFO - 'SHELL': '/bin/bash', 16:07:50 INFO - 'SHLVL': '1', 16:07:50 INFO - 'TERM': 'linux', 16:07:50 INFO - 'TMOUT': '86400', 16:07:50 INFO - 'USER': 'cltbld', 16:07:50 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', 16:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:50 INFO - '_': '/tools/buildbot/bin/python'} 16:07:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:50 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07:52 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:07:52 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 16:07:52 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:07:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:07:52 INFO - Installing collected packages: mozsystemmonitor 16:07:52 INFO - Running setup.py install for mozsystemmonitor 16:07:52 INFO - Successfully installed mozsystemmonitor 16:07:52 INFO - Cleaning up... 16:07:52 INFO - Return code: 0 16:07:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 16:07:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fa3fc998f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x255d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ec540>, '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 0x25ed440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ed8f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', '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 16:07:52 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 16:07:52 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:07:52 INFO - Using env: {'DISPLAY': ':0', 16:07:52 INFO - 'HOME': '/home/cltbld', 16:07:52 INFO - 'LANG': 'en_US.UTF-8', 16:07:52 INFO - 'LANGUAGE': 'en_US:en', 16:07:52 INFO - 'LOGNAME': 'cltbld', 16:07:52 INFO - 'MAIL': '/var/mail/cltbld', 16:07:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:52 INFO - 'MOZ_NO_REMOTE': '1', 16:07:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:07:52 INFO - 'NO_EM_RESTART': '1', 16:07:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:07:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:07:52 INFO - 'PWD': '/builds/slave/test-pgo', 16:07:52 INFO - 'SHELL': '/bin/bash', 16:07:52 INFO - 'SHLVL': '1', 16:07:52 INFO - 'TERM': 'linux', 16:07:52 INFO - 'TMOUT': '86400', 16:07:52 INFO - 'USER': 'cltbld', 16:07:52 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', 16:07:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:52 INFO - '_': '/tools/buildbot/bin/python'} 16:07:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:53 INFO - Downloading/unpacking blobuploader==1.2.4 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07: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 16:07:54 INFO - Downloading blobuploader-1.2.4.tar.gz 16:07:54 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 16:07:54 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:07:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:07:54 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 16:07:54 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 16:07:54 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 16:07:54 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 16:07:54 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 16:07:54 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 16:07:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 16:07:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:07:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:07:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:07:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:07:55 INFO - Downloading docopt-0.6.1.tar.gz 16:07:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 16:07:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:07:55 INFO - Installing collected packages: blobuploader, requests, docopt 16:07:55 INFO - Running setup.py install for blobuploader 16:07:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:07:55 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:07:55 INFO - Running setup.py install for requests 16:07:55 INFO - Running setup.py install for docopt 16:07:55 INFO - Successfully installed blobuploader requests docopt 16:07:55 INFO - Cleaning up... 16:07:56 INFO - Return code: 0 16:07:56 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:07:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fc998f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x255d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ec540>, '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 0x25ed440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ed8f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', '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 16:07:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 16:07:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:07:56 INFO - Using env: {'DISPLAY': ':0', 16:07:56 INFO - 'HOME': '/home/cltbld', 16:07:56 INFO - 'LANG': 'en_US.UTF-8', 16:07:56 INFO - 'LANGUAGE': 'en_US:en', 16:07:56 INFO - 'LOGNAME': 'cltbld', 16:07:56 INFO - 'MAIL': '/var/mail/cltbld', 16:07:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:56 INFO - 'MOZ_NO_REMOTE': '1', 16:07:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:07:56 INFO - 'NO_EM_RESTART': '1', 16:07:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:07:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:07:56 INFO - 'PWD': '/builds/slave/test-pgo', 16:07:56 INFO - 'SHELL': '/bin/bash', 16:07:56 INFO - 'SHLVL': '1', 16:07:56 INFO - 'TERM': 'linux', 16:07:56 INFO - 'TMOUT': '86400', 16:07:56 INFO - 'USER': 'cltbld', 16:07:56 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', 16:07:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:56 INFO - '_': '/tools/buildbot/bin/python'} 16:07:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:07:56 INFO - Running setup.py (path:/tmp/pip-sbrI4T-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:07:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:07:56 INFO - Running setup.py (path:/tmp/pip-vTOOKg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:07:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:07:56 INFO - Running setup.py (path:/tmp/pip-ndGprK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:07:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:07:56 INFO - Running setup.py (path:/tmp/pip-NAhbAr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:07:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:07:56 INFO - Running setup.py (path:/tmp/pip-Zbo4Vi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:07:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:07:56 INFO - Running setup.py (path:/tmp/pip-25c7bw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:07:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:07:56 INFO - Running setup.py (path:/tmp/pip-7c5_EA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:07:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:07:56 INFO - Running setup.py (path:/tmp/pip-AcmaDy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:07:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:07:56 INFO - Running setup.py (path:/tmp/pip-Fta0ma-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:07:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:07:56 INFO - Running setup.py (path:/tmp/pip-f6Dg76-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:07:56 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:07:56 INFO - Running setup.py (path:/tmp/pip-CHeMXr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:07:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:07:57 INFO - Running setup.py (path:/tmp/pip-znxdwm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:07:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:07:57 INFO - Running setup.py (path:/tmp/pip-JBxBYh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:07:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:07:57 INFO - Running setup.py (path:/tmp/pip-jlLYXK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:07:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:07:57 INFO - Running setup.py (path:/tmp/pip-LQNuL5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:07:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:07:57 INFO - Running setup.py (path:/tmp/pip-O43tVJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:07:57 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:07:57 INFO - Running setup.py (path:/tmp/pip-FiNBI6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:07:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:07:57 INFO - Running setup.py install for manifestparser 16:07:57 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:07:57 INFO - Running setup.py install for mozcrash 16:07:57 INFO - Running setup.py install for mozdebug 16:07:57 INFO - Running setup.py install for mozdevice 16:07:57 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:07:57 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:07:57 INFO - Running setup.py install for mozfile 16:07:57 INFO - Running setup.py install for mozhttpd 16:07:58 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:07:58 INFO - Running setup.py install for mozinfo 16:07:58 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:07:58 INFO - Running setup.py install for mozInstall 16:07:58 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:07:58 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:07:58 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:07:58 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:07:58 INFO - Running setup.py install for mozleak 16:07:58 INFO - Running setup.py install for mozlog 16:07:58 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:07:58 INFO - Running setup.py install for moznetwork 16:07:58 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:07:58 INFO - Running setup.py install for mozprocess 16:07:58 INFO - Running setup.py install for mozprofile 16:07:58 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:07:58 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:07:58 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:07:58 INFO - Running setup.py install for mozrunner 16:07:58 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:07:58 INFO - Running setup.py install for mozscreenshot 16:07:59 INFO - Running setup.py install for moztest 16:07:59 INFO - Running setup.py install for mozversion 16:07:59 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:07:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:07:59 INFO - Cleaning up... 16:07:59 INFO - Return code: 0 16:07:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:07:59 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')]} 16:07:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:07:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:59 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')]} 16:07:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:07:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:07:59 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fc998f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x255d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ec540>, '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 0x25ed440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ed8f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', '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 16:07:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 16:07:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:07:59 INFO - Using env: {'DISPLAY': ':0', 16:07:59 INFO - 'HOME': '/home/cltbld', 16:07:59 INFO - 'LANG': 'en_US.UTF-8', 16:07:59 INFO - 'LANGUAGE': 'en_US:en', 16:07:59 INFO - 'LOGNAME': 'cltbld', 16:07:59 INFO - 'MAIL': '/var/mail/cltbld', 16:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:07:59 INFO - 'MOZ_NO_REMOTE': '1', 16:07:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:07:59 INFO - 'NO_EM_RESTART': '1', 16:07:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:07:59 INFO - 'PWD': '/builds/slave/test-pgo', 16:07:59 INFO - 'SHELL': '/bin/bash', 16:07:59 INFO - 'SHLVL': '1', 16:07:59 INFO - 'TERM': 'linux', 16:07:59 INFO - 'TMOUT': '86400', 16:07:59 INFO - 'USER': 'cltbld', 16:07:59 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', 16:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:07:59 INFO - '_': '/tools/buildbot/bin/python'} 16:07:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:07:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:07:59 INFO - Running setup.py (path:/tmp/pip-W6ECL_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:07:59 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)) 16:07:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:07:59 INFO - Running setup.py (path:/tmp/pip-YJfC8D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:07:59 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)) 16:07:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:07:59 INFO - Running setup.py (path:/tmp/pip-Ojz3rM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:07:59 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)) 16:07:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:07:59 INFO - Running setup.py (path:/tmp/pip-AQQY5U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:07:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 16:07:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:07:59 INFO - Running setup.py (path:/tmp/pip-jUNl14-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:07:59 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)) 16:07:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:07:59 INFO - Running setup.py (path:/tmp/pip-7DWQ6Z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:07:59 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)) 16:07:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:07:59 INFO - Running setup.py (path:/tmp/pip-LaKBYA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:07:59 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)) 16:07:59 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:07:59 INFO - Running setup.py (path:/tmp/pip-ivUX3y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:08:00 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)) 16:08:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:08:00 INFO - Running setup.py (path:/tmp/pip-5f3jD4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:08:00 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)) 16:08:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:08:00 INFO - Running setup.py (path:/tmp/pip-BHbp9D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:08:00 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)) 16:08:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:08:00 INFO - Running setup.py (path:/tmp/pip-MFMe_5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:08:00 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)) 16:08:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:08:00 INFO - Running setup.py (path:/tmp/pip-tnVhWN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:08:00 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)) 16:08:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:08:00 INFO - Running setup.py (path:/tmp/pip-80MLPG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:08:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 16:08:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:08:00 INFO - Running setup.py (path:/tmp/pip-w769tz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:08:00 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)) 16:08:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:08:00 INFO - Running setup.py (path:/tmp/pip-H0OuQW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:08:00 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)) 16:08:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:08:00 INFO - Running setup.py (path:/tmp/pip-_z5G8z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:08:00 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)) 16:08:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:08:00 INFO - Running setup.py (path:/tmp/pip-qz46fP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:08:00 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)) 16:08:00 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)) 16:08:00 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)) 16:08:00 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)) 16:08:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 16:08:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 16:08:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:02 INFO - Downloading blessings-1.5.1.tar.gz 16:08:02 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 16:08:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:08:02 INFO - Installing collected packages: blessings 16:08:02 INFO - Running setup.py install for blessings 16:08:02 INFO - Successfully installed blessings 16:08:02 INFO - Cleaning up... 16:08:02 INFO - Return code: 0 16:08:02 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:08:02 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:08:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:08:02 INFO - Reading from file tmpfile_stdout 16:08:02 INFO - Current package versions: 16:08:02 INFO - argparse == 1.2.1 16:08:02 INFO - blessings == 1.5.1 16:08:02 INFO - blobuploader == 1.2.4 16:08:02 INFO - docopt == 0.6.1 16:08:02 INFO - manifestparser == 1.1 16:08:02 INFO - mozInstall == 1.12 16:08:02 INFO - mozcrash == 0.16 16:08:02 INFO - mozdebug == 0.1 16:08:02 INFO - mozdevice == 0.48 16:08:02 INFO - mozfile == 1.2 16:08:02 INFO - mozhttpd == 0.7 16:08:02 INFO - mozinfo == 0.9 16:08:02 INFO - mozleak == 0.1 16:08:02 INFO - mozlog == 3.1 16:08:02 INFO - moznetwork == 0.27 16:08:02 INFO - mozprocess == 0.22 16:08:02 INFO - mozprofile == 0.28 16:08:02 INFO - mozrunner == 6.11 16:08:02 INFO - mozscreenshot == 0.1 16:08:02 INFO - mozsystemmonitor == 0.0 16:08:02 INFO - moztest == 0.7 16:08:02 INFO - mozversion == 1.4 16:08:02 INFO - psutil == 3.1.1 16:08:02 INFO - requests == 1.2.3 16:08:02 INFO - wsgiref == 0.1.2 16:08:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:08:02 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')]} 16:08:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:02 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')]} 16:08:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fc998f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x255d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ec540>, '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 0x25ed440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ed8f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', '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 16:08:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 16:08:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:08:02 INFO - Using env: {'DISPLAY': ':0', 16:08:02 INFO - 'HOME': '/home/cltbld', 16:08:02 INFO - 'LANG': 'en_US.UTF-8', 16:08:02 INFO - 'LANGUAGE': 'en_US:en', 16:08:02 INFO - 'LOGNAME': 'cltbld', 16:08:02 INFO - 'MAIL': '/var/mail/cltbld', 16:08:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:02 INFO - 'MOZ_NO_REMOTE': '1', 16:08:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:02 INFO - 'NO_EM_RESTART': '1', 16:08:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:08:02 INFO - 'PWD': '/builds/slave/test-pgo', 16:08:02 INFO - 'SHELL': '/bin/bash', 16:08:02 INFO - 'SHLVL': '1', 16:08:02 INFO - 'TERM': 'linux', 16:08:02 INFO - 'TMOUT': '86400', 16:08:02 INFO - 'USER': 'cltbld', 16:08:02 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', 16:08:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:02 INFO - '_': '/tools/buildbot/bin/python'} 16:08:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 16:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 16:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 16:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 16:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 16:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 16:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 16:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 16:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 9)) 16:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 16:08:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 16:08:03 INFO - Cleaning up... 16:08:03 INFO - Return code: 0 16:08:03 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 16:08: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')]} 16:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08: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')]} 16:08:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fc998f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x255d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ec540>, '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 0x25ed440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ed8f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', '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 16:08:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 16:08:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:08:03 INFO - Using env: {'DISPLAY': ':0', 16:08:03 INFO - 'HOME': '/home/cltbld', 16:08:03 INFO - 'LANG': 'en_US.UTF-8', 16:08:03 INFO - 'LANGUAGE': 'en_US:en', 16:08:03 INFO - 'LOGNAME': 'cltbld', 16:08:03 INFO - 'MAIL': '/var/mail/cltbld', 16:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:03 INFO - 'MOZ_NO_REMOTE': '1', 16:08:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:03 INFO - 'NO_EM_RESTART': '1', 16:08:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:08:03 INFO - 'PWD': '/builds/slave/test-pgo', 16:08:03 INFO - 'SHELL': '/bin/bash', 16:08:03 INFO - 'SHLVL': '1', 16:08:03 INFO - 'TERM': 'linux', 16:08:03 INFO - 'TMOUT': '86400', 16:08:03 INFO - 'USER': 'cltbld', 16:08:03 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', 16:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:03 INFO - '_': '/tools/buildbot/bin/python'} 16:08:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:03 INFO - Downloading/unpacking jsonschema 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:08:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 16:08:05 INFO - Downloading/unpacking functools32 (from jsonschema) 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08:05 INFO - Downloading functools32-3.2.3-2.tar.gz 16:08:05 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 16:08:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 16:08:05 INFO - warning: no files found matching '*.txt' 16:08:05 INFO - no previously-included directories found matching 'build' 16:08:05 INFO - no previously-included directories found matching 'dist' 16:08:05 INFO - no previously-included directories found matching '.git*' 16:08:05 INFO - Installing collected packages: jsonschema, functools32 16:08:05 INFO - Running setup.py install for functools32 16:08:05 INFO - warning: no files found matching '*.txt' 16:08:05 INFO - no previously-included directories found matching 'build' 16:08:05 INFO - no previously-included directories found matching 'dist' 16:08:05 INFO - no previously-included directories found matching '.git*' 16:08:05 INFO - Successfully installed jsonschema functools32 16:08:05 INFO - Cleaning up... 16:08:05 INFO - Return code: 0 16:08:05 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 16:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3fc998f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24908a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x255d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ec540>, '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 0x25ed440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ed8f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test-pgo/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', '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 16:08:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 16:08:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:08:05 INFO - Using env: {'DISPLAY': ':0', 16:08:05 INFO - 'HOME': '/home/cltbld', 16:08:05 INFO - 'LANG': 'en_US.UTF-8', 16:08:05 INFO - 'LANGUAGE': 'en_US:en', 16:08:05 INFO - 'LOGNAME': 'cltbld', 16:08:05 INFO - 'MAIL': '/var/mail/cltbld', 16:08:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:05 INFO - 'MOZ_NO_REMOTE': '1', 16:08:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:05 INFO - 'NO_EM_RESTART': '1', 16:08:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:08:05 INFO - 'PWD': '/builds/slave/test-pgo', 16:08:05 INFO - 'SHELL': '/bin/bash', 16:08:05 INFO - 'SHLVL': '1', 16:08:05 INFO - 'TERM': 'linux', 16:08:05 INFO - 'TMOUT': '86400', 16:08:05 INFO - 'USER': 'cltbld', 16:08:05 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', 16:08:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:05 INFO - '_': '/tools/buildbot/bin/python'} 16:08:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:05 INFO - Downloading/unpacking flake8 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08: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 16:08:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:08:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 16:08:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:08:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 16:08:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 16:08:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 16:08:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 16:08:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:08:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:08:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:08:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:08:08 INFO - no previously-included directories found matching 'docs/_build' 16:08:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:08:08 INFO - Running setup.py install for pep8 16:08:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:08:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:08:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:08:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:08:08 INFO - no previously-included directories found matching 'docs/_build' 16:08:08 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 16:08:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:08:08 INFO - Cleaning up... 16:08:08 INFO - Return code: 0 16:08:08 INFO - Running post-action listener: _resource_record_post_action 16:08:08 INFO - Running post-action listener: _start_resource_monitoring 16:08:08 INFO - Starting resource monitoring. 16:08:08 INFO - ##### 16:08:08 INFO - ##### Running install step. 16:08:08 INFO - ##### 16:08:08 INFO - Running pre-action listener: _resource_record_pre_action 16:08:08 INFO - Running main action method: install 16:08:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:08:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:08:08 INFO - Reading from file tmpfile_stdout 16:08:08 INFO - Detecting whether we're running mozinstall >=1.0... 16:08:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:08:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:08:08 INFO - Reading from file tmpfile_stdout 16:08:08 INFO - Output received: 16:08:08 INFO - Usage: mozinstall [options] installer 16:08:08 INFO - Options: 16:08:08 INFO - -h, --help show this help message and exit 16:08:08 INFO - -d DEST, --destination=DEST 16:08:08 INFO - Directory to install application into. [default: 16:08:08 INFO - "/builds/slave/test-pgo"] 16:08:08 INFO - --app=APP Application being installed. [default: firefox] 16:08:08 INFO - mkdir: /builds/slave/test-pgo/build/application 16:08:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 16:08:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 16:08:24 INFO - Reading from file tmpfile_stdout 16:08:24 INFO - Output received: 16:08:24 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:08:24 INFO - Running post-action listener: _resource_record_post_action 16:08:24 INFO - ##### 16:08:24 INFO - ##### Running run-tests step. 16:08:24 INFO - ##### 16:08:24 INFO - Running pre-action listener: _resource_record_pre_action 16:08:24 INFO - Running main action method: run_tests 16:08:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:08:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:08:24 INFO - Python 2.7.3 16:08:24 INFO - Return code: 0 16:08:24 INFO - grabbing minidump binary from tooltool 16:08:24 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')]} 16:08:24 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 0x25ec540>, '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 0x25ed440>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ed8f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:08:24 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 16:08:24 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 16:08:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:08:24 INFO - Return code: 0 16:08:24 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:08:24 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:08:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:08:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:08:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:08:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 16:08:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 16:08:25 INFO - Return code: 0 16:08:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 16:08:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 16:08:25 INFO - Using env: {'DISPLAY': ':0', 16:08:25 INFO - 'HOME': '/home/cltbld', 16:08:25 INFO - 'LANG': 'en_US.UTF-8', 16:08:25 INFO - 'LANGUAGE': 'en_US:en', 16:08:25 INFO - 'LOGNAME': 'cltbld', 16:08:25 INFO - 'MAIL': '/var/mail/cltbld', 16:08:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:08:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:25 INFO - 'MOZ_NO_REMOTE': '1', 16:08:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:08:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:25 INFO - 'NO_EM_RESTART': '1', 16:08:25 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:08:25 INFO - 'PWD': '/builds/slave/test-pgo', 16:08:25 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:08:25 INFO - 'SHELL': '/bin/bash', 16:08:25 INFO - 'SHLVL': '1', 16:08:25 INFO - 'TERM': 'linux', 16:08:25 INFO - 'TMOUT': '86400', 16:08:25 INFO - 'USER': 'cltbld', 16:08:25 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230', 16:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:25 INFO - '_': '/tools/buildbot/bin/python'} 16:08:25 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:08:25 INFO - mozversion application_buildid: 20160113133026 16:08:25 INFO - mozversion application_changeset: 589761e5c8173337e82b7635f7a42cc3bcc9884e 16:08:25 INFO - mozversion application_display_name: Nightly 16:08:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:08:25 INFO - mozversion application_name: Firefox 16:08:25 INFO - mozversion application_remotingname: firefox 16:08:25 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:08:25 INFO - mozversion application_vendor: Mozilla 16:08:25 INFO - mozversion application_version: 46.0a1 16:08:25 INFO - mozversion platform_buildid: 20160113133026 16:08:25 INFO - mozversion platform_changeset: 589761e5c8173337e82b7635f7a42cc3bcc9884e 16:08:25 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 16:08:25 INFO - mozversion platform_version: 46.0a1 16:08:25 INFO - using testdate: 1452730105 16:08:25 INFO - actual date: 1452730105 16:08:25 INFO - starting webserver on 'localhost:39121' 16:08:25 INFO - SUITE-START | Running 3 tests 16:08:25 INFO - TEST-START | dromaeo_css 16:08:25 INFO - operating with platform_type : linux_ 16:08:25 INFO - Initialising browser for dromaeo_css test... 16:08:25 INFO - TEST-INFO | started process 21809 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdsZnfx/profile http://localhost:39121/getInfo.html) 16:08:29 INFO - PROCESS | 21809 | __metrics Screen width/height:1600/1200 16:08:29 INFO - PROCESS | 21809 | colorDepth:24 16:08:29 INFO - PROCESS | 21809 | Browser inner width/height: 1024/697 16:08:29 INFO - PROCESS | 21809 | __metrics 16:08:30 INFO - PROCESS | 21809 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 16:08:30 INFO - PROCESS | 21809 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:08:31 INFO - TEST-INFO | 21809: exit 0 16:08:31 INFO - Browser initialized. 16:08:31 INFO - Running cycle 1/1 for dromaeo_css test... 16:08:31 INFO - TEST-INFO | started process 21900 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpdsZnfx/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:08:42 INFO - PROCESS | 21900 | RSS: Main: 149966848 16:08:42 INFO - PROCESS | 21900 | 16:09:33 INFO - PROCESS | 21900 | 1452730173799 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] 16:11:42 INFO - PROCESS | 21900 | Cycle 1(1): loaded http://localhost:39121/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39121/tests/dromaeo/cssquery-ext.html) 16:11:44 INFO - PROCESS | 21900 | RSS: Main: 263675904 16:11:44 INFO - PROCESS | 21900 | 16:14:46 INFO - PROCESS | 21900 | Cycle 1(1): loaded http://localhost:39121/tests/dromaeo/cssquery-ext.html (next: http://localhost:39121/tests/dromaeo/cssquery-jquery.html) 16:14:46 INFO - PROCESS | 21900 | RSS: Main: 231989248 16:14:46 INFO - PROCESS | 21900 | 16:17:48 INFO - PROCESS | 21900 | Cycle 1(1): loaded http://localhost:39121/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39121/tests/dromaeo/cssquery-mootools.html) 16:17:49 INFO - PROCESS | 21900 | RSS: Main: 233553920 16:17:49 INFO - PROCESS | 21900 | 16:20:51 INFO - PROCESS | 21900 | Cycle 1(1): loaded http://localhost:39121/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39121/tests/dromaeo/cssquery-prototype.html) 16:20:52 INFO - PROCESS | 21900 | RSS: Main: 212983808 16:20:52 INFO - PROCESS | 21900 | 16:23:54 INFO - PROCESS | 21900 | Cycle 1(1): loaded http://localhost:39121/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39121/tests/dromaeo/cssquery-yui.html) 16:23:55 INFO - PROCESS | 21900 | RSS: Main: 216211456 16:23:55 INFO - PROCESS | 21900 | 16:26:57 INFO - PROCESS | 21900 | Cycle 1(1): loaded http://localhost:39121/tests/dromaeo/cssquery-yui.html (next: http://localhost:39121/tests/dromaeo/cssquery-dojo.html) 16:26:58 INFO - PROCESS | 21900 | RSS: Main: 293011456 16:26:58 INFO - PROCESS | 21900 | 16:26:58 INFO - PROCESS | 21900 | __start_tp_report 16:26:58 INFO - PROCESS | 21900 | _x_x_mozilla_page_load 16:26:58 INFO - PROCESS | 21900 | _x_x_mozilla_page_load_details 16:26:58 INFO - PROCESS | 21900 | |i|pagename|runs| 16:26:58 INFO - PROCESS | 21900 | |0;dojo.html;3117.8752849886005;3131.749460043197;3167.461531539638;3176.4917613181897;3207.134073800073;4912.705237685733;4997.700137991714;5034.874128146794;5059.215821547661;5109.412417571975;4967.205247160455;4968.35411396518;5041.369828771403;5041.521055499722;5060.671056381333;4518.4351956005585;4549.135664223803;4560.315952607102;4593.770311484413;4643.651726120537;4617.861464156063;4685.601723853477;4688.812447502096;4691.601213896823;4705.482396936334;4499.955000449986;4521.7739113044545;4604.654650901196;4637.466691330506;4678;4580.839670611546;4599.103174880899;4620.330052142454;4640.419947506562;4651.674382793206;4532.977335113336;4542.295944128666;4544;4544.318352247156;4578.633709303248;4734.479207287196;4860.1737704590305;4882.194437917739;4904.2643603459765;4914.926276105862;5260.8684782880355;5280.815171469018;5283.920741188886;5285.07511185541;5331.333583302087;5303.443138470444;5313.362396512443;5314.627976041678;5331.866703332409;5333.333333333333;4968.080905032544;5029.06962211992;5044.798208071636;5068.391792984866;5080.187170052774;5405.189221616789;5417.404085550586;5448.210009548593;5457.863553411117;5504.504594586427;5261.947380526222;5266.236395722675;5270.1040823060175;5272.894542109137;5316.362036555638;5335.573154147659;5364.490373414519;5406.91889621656;5420.593455490854;5421.83734487966;5387.6228663993925;5392.191171324254;5395.406505284415;5396.487333703292;5420.807422367152;5377.919331210035;5385.7307134643115;5388.811391601275;5391.514763671288;5440;4175.62419382257;4181.038361176947;4239.894002649959;4241.045764702917;4261.936070958938;3817.3892177251505;3820.2932457495453;3828.6554210120667;3849.1147036181833;3860.517435320585;5020.1214787412055;5066.214736715815;5078.619103567247;5080.872978175576;5107.106256405114;5293.391260005072;5364.061289274361;5372.1941708744;5404.351477822608;5446.264754258124;5000.74996250186;5042.773075211624;5049.924251136236;5059.97470012655;5095.719735414514;4689.531046895351;4692.131955588227;4694.389729335165;4697.13103075932;4740.146773580788;5499.1476321170285;5543.334799824047;5576.0241957657245;5588.636738612058;5631.493165615114;5368.597355198453;5410.567154627542;5417.945820541744;5418.078926582411;5428.7285635718845;5411.404745478073;5588.748506317144;5609.747561359667;5616.466435688603;5650.3219613646625;5283.788648454172;5317.574594032545;5350.036993341236;5354.732263386893;5399.541039011728;5430.104032834538;5470.124780035176;5471.972640136773;5472.425395333592;5476.205950137288;5144.382674079135;5279.683219006872;5358.437364076716;5361.678299302054;5368.651037682538;5217.713025783618;5240.790368385222;5257.2902658140665;5260.89478210426;5296.258523806593;5167.741612919414;5188.455212202664;5190.506901844319;5191.377034755854;5235.790568377222;5148.227765835155;5234.11020126587;5273.26174335601;5287.127623942007;5288.233206185005;5164.0962831505085;5170.37955445349;5233.215017747369;5251.317328747238;5256.921146182887 16:26:58 INFO - PROCESS | 21900 | |1;ext.html;536.305484397705;546.7621584610692;549.3984087424269;553.139867506028;591.7810410148246;28060.15819525415;28571.428571428558;28578.42843143136;28584.714152858465;28586.14241572756;28635.856820715868;35381.46927796085;36350.63649363506;36396.45405318922;36430.08924776882;8384.706535271282;8532;8545;8547.05982341945;8554.914450855475;8347.916520834775;8368.828364029041;8418.410711250215;8423.410361274713;8426.410151289412;8437.367197460184;8517.914820851774;8526.275266602346;8526.531040792754;8690;8593.011803642567;8648.65405383784;8683.218510334036;8703.390762646617;8704.6518139275;8663.783405414915;8682.696105636336;8771.03518612952;8796.516191609522;8797.428167169177;38126;38547.61452385468;38551.22897542034;38569;38609.42085868714;3029.12155474912;3162.873485060618;3195.504696772004;3487.110786749388;3896.844126234947;29602.851985740148;29702.554461683092;30077.699223007927;30309.69690303091;31019.379612407625;3973.6821054315874;4064.593540645941;4067.145899361108;4069.877903662895;4073.103917138225;193158;194505;194780;196450;196469.99999999857;50374.74812625913;51955.22067168995;56924.430755691916;58288.70855645695;58328.83396324518;52624;52858.73570632199;52971.205431918555;53007;53046.73476632592;13728.45086196561;14382.35279412429;14480.275986200648;14509.637259068608;14510.564683059525;9969.003099690119;10030.799384012278;10788.190885683605;10915.781684366268;11021.944890275498;18145.63708725818;18551.44345669632;18564.535886602942;18576.814231857777;18616.81383186178;7163.283671632899;7342.669579868919;7453.85092298151;7463.328300452985;7512.511686740344;895.1764376773384;928.9581968811419;951.5860600638745;1002.9348092373972;1107.9335239885472;28659.856700716362;28661.426771464456;28733.425331493254;29089;29163.41673166525;983.758979050123;1032.9896701033044;1066.2536224642781;1085.3054045410943;1119.8936101070385;15021.399144034334;15039.398424062914;15059.698806023818;15378.3079761411;15459.690806184039;312035;316113;316329;316393;316927;51591.74204128955;51602.48397516052;51924.74037629788;52025.317721405256;52106.478935210165;1238.2446707508414;1258.1696080586769;1270.5934101087564;1271.8473783146082;1283.4352884730874;27319.590206146528;28355.858220708764;28646;28708.425831482837;28759.137225883256;45620.31569526393;45957.08085838331;45968.62094137181;46001.769991151166;46131.077378452916;226252;226277;226312;226468;226740;27591;29807.850960745058;31776.36447271088;31788.523172151974;31919.840400797846;37935;38016.619833801306;38456.615433845305;39057.80471097626;39337.40993885037;30835.537466938462;32077;32245.51631725479;33351.83324083364;35567.28865422729;30993;35437.46843797294;35615.82192089126;35819.28361432809;35978.46032309465;31799.6820031806;32438.83780581082;33182.83408582942;33236.174925131636;33333.66666333403;29058.854705726335;29859.70140298569;29997.100086997423;30107.096787095546;30339.696603033688;23090.191843285404;23189.768102319435;23281.301560953198;23523.647145292492;23635.763642363356 16:26:58 INFO - PROCESS | 21900 | |2;jquery.html;361.4181168319004;362.72432950957267;363.14479401010635;363.4675200830782;365.5138665574786;13561.93219033906;13998.720025599481;15962.760558591615;16174.676506469877;16920.91539542302;15586.922065389686;15804.525864224062;16826.411075612355;16847.83152168478;16895.408660696878;5612.971935140318;5899.203607512974;5904.822855314326;5906.232189815318;5907.700305932688;4247.63895068918;4675.532446755331;5819.447152520504;6589.143411356541;6618.735250589971;6201.565890387675;6471.320511346312;6568.1789776277965;6600.867982640344;6607.207135143765;6320.905186422161;6378.681065946707;6405.19935008124;6414.550981431302;6422.325655806167;6309.337519560472;6317.273513545955;6317.989121740499;6322.0516922462;6322.778702745428;19917.30289439877;21028.002339777697;21034.5793084139;21243.893780531154;22361.664575031387;2080.3446914221972;2088.33173384517;2124.6706760452153;2133.178726190428;2148.785121487854;13489.258090805002;13657.521986730517;13720.45118195271;13796.310184490736;13868.295682341948;1919.4049844548235;1977.3771262052408;1989.2341448542415;1995.0623207092642;2014.7380840490641;60263;60692;61776.69111654413;62926.68536657333;63551.36448635547;14611.323303714702;14759.335829887681;14811.777823332659;14831.77752333716;15145.545633631009;12486.75026499465;12829.23024618526;22860.885695571415;23038.424039398815;23088;7220.711171553079;7360.28715289897;7398.741044063432;7425.108986921604;7435.888461673079;7116.252793456716;7123.679434425359;7137.750178743823;7157.6779044943105;7168.354848063699;4062.1469491407133;4227.894302642459;4362.476502819682;4442.311441726538;4455.910881782346;1761.8854774439621;1819.5451137215696;1825.7535232743676;1826.6894627913287;1827.7806663200502;456.20392415235625;871.7123349294718;875.4309698695898;919.7792529792802;1024.7899180667946;9059.864102038475;10191;10867.67396978076;10908.945455272673;10928;449.60434817360516;453.41372021997915;454.9181147393435;474.7222874618318;482.48133256749134;5849.151872978395;5982.1325907744;6103.1455596216565;6107.992181290127;6109.755609775648;486328;489417;490748;494634;494829;63539;66507;66879.66560167167;66907.66546167238;69037.65481172562;970.9029097090375;1069.2996087562658;1111.3276467736903;1121.2319561100671;1153.8557680290132;14125.858741412865;14957.177355245565;14965.551033469013;14991.325390357242;15127.794020560166;31625.8418707905;32534.674653253165;32561.837190814844;32782;33178.668213318524;40547.797261013504;40780.79609601933;41953.79023104987;42140.367894480994;42550;6667.966660166668;6944.51388402807;7012.801144947101;7114.181869085121;7136.322049405506;10102.595896164072;10201.948990255001;10446.791064178826;10538.525766340379;10625.9468702656;9389.953050234979;9484.95257523708;9582.616695332108;9746.610135594497;9850.211983041196;9456.33805633599;9503.382280151767;9947.253955953187;10069;10112.342697724624;2971.316597182617;2995.7902946793515;6877.177547949897;8871.112888711077;9242.907570924204;7279.235680253709;7288.708451661874;7299.744508942162;7433.331000210007;7532.811679708051;8355.164483551596;9126.954365228132;9209.263258939403;9243.445393276425;9306.162445379678 16:26:58 INFO - PROCESS | 21900 | |3;mootools.html;1343.5499107798887;1455.3741890986876;1484.7995520604723;1635.2723038247977;1785.7410675452052;6924.065251191088;7451.888221676672;7526.510776799504;7551.131619863717;7553.735619253327;7434.107907051161;7564.470487065908;7588.317051465367;7602.581857997808;7618.9619051904665;2004.2984955265613;2051.4050925231663;2054.681524363726;2307.826912981529;2323.2449453927456;2048.3752455501067;2300.6894069300683;2331.80179684727;2339.1462116327502;2342.437814924423;2266.909323627053;2303.355060583031;2310.688057112285;2311.7457079721303;2316.085146367184;1963.862529622927;2010.2160157538572;2315.976840231593;2318.07277089164;2323.8140948724144;2301.4361481437154;2337.789598936104;2343.261872510171;2343.7539058398793;2347.4248809041724;7614.505057171322;7687.654055567457;7689.961550192269;7690.5770182639935;7690.807729806744;4576.725396476222;4701.48283688794;4709.905801883978;4710.481846996828;4714.104320179189;3073.0959243514885;3100.58142150809;3142.5600415941785;3163;3164.240582260264;1074.3500182389698;1080.5029686344212;1936.244864502845;1953.9316123935737;1979.2281010405952;10702.197335199891;10764.623238186705;10780.245382823205;10792.190585706103;10796.514156862882;4247.97876010618;4308.590683885026;4382.758948257812;4384.671149663788;4387.363832244307;8784.824303513893;8787.692430764893;8799.868001979976;8806.163414475615;8815.647374105092;4906.607471402279;4915.066137433876;4917.729524876095;4929.285253638202;4929.7781599828095;4553.817847286072;4557.544245575482;4558.772061396917;4567.680262381601;4570.223062079388;3462.9480557791653;3534.6818786309354;3562.1450851795903;3564.429691249388;3577.83899724513;1040.9687709368732;1077.5420446310286;1089.0198821061108;1092.5138313450439;1092.6066616018227;1296.5332480307081;1325.615571484278;1342.9059965802496;1356.0846428660614;1358.7078778062764;7395.075615548057;7427.85144297111;7438.070241219848;7447.478676492702;7455.627218639155;453.66882176011694;458.5803989349708;459.37754342865605;459.9034202817379;459.94710608280474;5242.056429842589;5293.947060529345;5352.812651557255;5356.491133342326;5849.5027922626205;8983.640654373883;9126.406783559047;9133.40632858862;9141.954290228507;9233.861492077624;9273.72178834636;9366.484843333683;9388.577514011886;9494.667686630935;9507.952460237793;7377;7437.516561423598;7497.250274972459;7521;7524.2851929066655;7223.458240631869;7240.167380751281;7272.818179545554;7274.236205198378;7275.818104547428;8178.141295163922;8385.035720891932;8662.610182541926;8673.262772664395;8673.436226645248;8647.221750042274;8729.956350218208;8962.955185224033;8963.103689630985;8965.50689712072;6450.322716114741;6558.081868538504;6563;6674.199096108498;6885.345892140239;7403.888941665771;7494.063242094739;7500.36246919018;7506.099268087864;7509.098908131059;6980.301969803182;7011.368976792113;7050.682719277747;7101.502894797314;7158.4273258140265;7026.402755765817;7037.929620703932;7042.647867606497;7108.644567771487;7164.140303163445;7001.124859392576;7040.788776336718;7096.361327480551;7100.112485939258;7111.146662400545;6472.676366181766;6520.608763474207;6521.445677117308;6521.478281737544;6524.934750652623;6243.937560624518;6435.678216089083;6496.317886621837;6503.089567460464;6511.641859697762 16:26:58 INFO - PROCESS | 21900 | |4;prototype.html;327.6772379206483;336.59776567002433;342.3632044397421;348.6147806673627;351.83287938229347;5630.690312032836;5744.971275143624;5750.19497270382;5751.194832723417;5772.538196944245;5530.5299049580835;5679.233303504026;5681.119426488901;5683.175939488771;5710;4458.398116254297;4460.5316441773475;4465.129299786543;4472.843450479226;4476.104779044188;4270.658347332206;4351.108022855307;4388.122375524892;4459.576340247671;4499.572540608637;4403.955960440403;4405.493368262659;4425;4441.578050085237;4442.8667139985855;4442.489113751928;4463.821447142111;4465.151621191963;4466.888327791815;4467.7319360838455;3946.625070618287;3953.367461206193;3955.44623752672;3956.3274243378696;3966.4446977423186;5396.271503347037;5497.890042199174;5509.944900550983;5549.112142057292;5555;5123.615728820353;5124.4106927703415;5131.281620573122;5135.281060651511;5135.871603209912;5784.942150578482;6076.574639775217;6083.726232319512;6088.117223002684;6096.695165241765;4134.255833949888;4157.9605098725315;4285.5071666758095;4296.656267498593;4298.61312481875;6244.875102497925;6290.245170579514;6302.054691796221;6303.621782693007;6322.74709011639;4341.782910854445;4369.43197384338;4374.846880359172;4375.299952007664;4377.4528183977;4276.18752437036;4435.35687325336;4438.334249862546;4438.400815889878;4496.910061798746;5092.719900405513;5098.362704661918;5098.592112631055;5101.668391554611;5114.948850511447;6098;6101.633901965896;6102.450779429784;6104.450599445982;6108.511319094462;1539.8768098552314;1557.166915700095;1558.6103474131467;1558.6570954390015;1559.485369827954;1052.2266134391216;1056.9048785609332;1061.883192848786;1066.898644628759;1085.451846817368;5773.682447465346;5774.105013722879;5775.624584402084;5778.248827652378;5779.971100144472;6226.097215903759;6230.968845155745;6239.75040998355;6242.906356404566;6248.937510624835;5746.827595172152;5750.424957504216;5765.4811067004175;5774.82675519735;5775.971120144372;3267.591551056118;3369.0735047862036;3371.2077661749468;3372.696457318853;3373.274745929588;6439.839004024937;6502.317256688074;6509.674516274167;6516.609003459713;6517.283098859121;6401.967990160019;6406.5515413920575;6408.231012278557;6412.743490260337;6414.903776443357;6302.180716506917;6382.170317858649;6417.069524918954;6428.325025872309;6443.71003304843;6053.424924632153;6192.690365481798;6213.875722485434;6214.4407003369915;6216.316205217513;6112;6164.815055548341;6226.750929962751;6234;6235.438810507076;6486.837829054311;6493.123428337114;6493.610383376824;6496.577722448026;6498.967505162444;4182.079942412664;4322.135572885499;4330.155619654137;4330.891727706832;4334.696571240109;6316.810495684953;6320.367963203827;6327.367263273636;6327.620342779448;6333;6155.507559395327;6155.722992465439;6158.538109641884;6160.691965401802;6163.722632481638;5961.940380596138;6129.478994285536;6145.8156255312415;6149.477294429845;6154.969225153845;6074.969625151846;6095.725692343944;6097.908531372122;6100.54245931548;6102.755889764359;6155.599886007395;6162.137300777984;6166.845828854314;6170.629762214281;6178.196834411498;5916.319623243382;6032.366601506778;6034.758609655741;6035.275766907824;6039.214902062704 16:26:58 INFO - PROCESS | 21900 | |5;yui.html;709.2375696126663;737.9631018449077;754.313015120953;788.6490511722278;814.6130587970711;13562.457501699933;13693.3837977291;13801.378937947791;13819.447222111103;13843.584692459217;11796.882031179686;11861.940690296537;11865.406729663526;11936.16446848721;11938.940305298484;659.1299484680223;666.0109737040491;796.474326944217;812.3866480807012;817.6075483767795;803.3211935914143;804.7263930263709;807.1565214351017;808.5957021489255;808.6846130009301;840.6595328891779;842.873568964654;844.2654890245495;844.9915500844975;845.8562044452458;840.0549381945311;841.2765022081005;841.5665932045001;842.212531283246;843.0389356134012;611.9296280927706;617.1113596421184;617.4196255519797;621.9533534984894;626.4362074133271;12551.246925184518;12572.182808117443;12601.495940162475;12604.81092783609;12625.810612840814;11819.645410637695;11935.224210426295;11944.343061131634;12014.279143251433;12078.69803254917;10360.32657877243;10553.788924221473;10664.360138391721;10789.784204315949;11110.44447777616;3612.71098312137;3656.232191239843;3664.3221004114325;3757.6242375762204;3765.3787125124322;44952.77523612394;45506.77246613779;45551.31673024907;45708.3143752844;45826.08347833058;9497.90502094984;9610.711678649652;9613.615455381707;9698.70903872885;10136.44249566281;18052;18067.186976586087;18233.452996410128;18247.27010919575;18276.268949241883;7587.734429295058;7726.227377262341;7849;8036.196380361917;8243.587820608946;4018.1361007383553;4135.29699951009;4154.148399578079;4217.7891105444605;4222.134462435193;3159;3188.3623275345026;3196.5844440222622;3201.6478187399366;3202.8718851246153;571.5998800839429;572.8052462162884;580.0516156084832;587.0694948506665;590.7105518296004;533.6584585865049;548.3611592494779;595.538457695281;607.8298076538576;634.9808557265615;8761.123887611187;8813.735587932373;8817.16236957488;8864.622931329961;9030.45817250967;632.772202007277;658.1079396734389;666.1107421592131;670.1522573943964;696.3176087434342;3971.960280397159;4047.1500984793524;4173.561776013536;4240.448741663564;4247.575242475789;52354.21468677973;52841.471585284424;53163.46836531663;53460.19809702857;53727.19409208865;49562.25656615154;49589.25616115761;53208.20187697188;54353.72823135859;54507.72746136323;37798.62201377951;38395.42406863899;44370.112597747866;44395;44884.77557612256;8685.199994039676;8813.33899957493;8935.955320223617;9100.635974561208;9174.082591740773;31991.360172796878;32927.341453171284;33115.840945566786;33766.831165844014;33867.44391838821;37351;37556.43665344967;37905.62094379131;37976.24047519089;38201.42697859479;10524.105451036748;10916;10977.835332470178;11171.66485005451;11480.311181329147;12350.62948111558;12676.239425634123;12750.61748147557;12864.935675321563;13267.071305008556;11510.194286399872;11674.124440666814;11910;12193.26840389544;12234.143609947574;11402.315861048364;11576.421178941187;11794.705132371759;11860.051195904134;12094.39528023613;10781.73045673833;10916.836247456453;11577.189596728149;11679.941600291944;11852.822207666719;9564.76088097803;9608.279379046739;9853.16248118918;9951.402915825074;10076.496175191358;9598.760030999281;9613.711588652352;9813.607455701693;10193;10336.534855931352 16:26:58 INFO - PROCESS | 21900 | __end_tp_report 16:26:58 INFO - PROCESS | 21900 | __start_cc_report 16:26:58 INFO - PROCESS | 21900 | _x_x_mozilla_cycle_collect,980 16:26:58 INFO - PROCESS | 21900 | __end_cc_report 16:26:58 INFO - PROCESS | 21900 | __startTimestamp1452731218139__endTimestamp 16:26:58 INFO - PROCESS | 21900 | 16:26:58 INFO - PROCESS | 21900 | ------- Summary: start ------- 16:26:58 INFO - PROCESS | 21900 | Number of tests: 6 16:26:58 INFO - PROCESS | 21900 | 16:26:58 INFO - PROCESS | 21900 | [#0] dojo.html Cycles:165 Average:5013.46 Median:5191.38 stddev:519.37 (10.0%) stddev-sans-first:499.22 16:26:58 INFO - PROCESS | 21900 | Values: 3117.9 3131.7 3167.5 3176.5 3207.1 4912.7 4997.7 5034.9 5059.2 5109.4 4967.2 4968.4 5041.4 5041.5 5060.7 4518.4 4549.1 4560.3 4593.8 4643.7 4617.9 4685.6 4688.8 4691.6 4705.5 4500.0 4521.8 4604.7 4637.5 4678.0 4580.8 4599.1 4620.3 4640.4 4651.7 4533.0 4542.3 4544.0 4544.3 4578.6 4734.5 4860.2 4882.2 4904.3 4914.9 5260.9 5280.8 5283.9 5285.1 5331.3 5303.4 5313.4 5314.6 5331.9 5333.3 4968.1 5029.1 5044.8 5068.4 5080.2 5405.2 5417.4 5448.2 5457.9 5504.5 5261.9 5266.2 5270.1 5272.9 5316.4 5335.6 5364.5 5406.9 5420.6 5421.8 5387.6 5392.2 5395.4 5396.5 5420.8 5377.9 5385.7 5388.8 5391.5 5440.0 4175.6 4181.0 4239.9 4241.0 4261.9 3817.4 3820.3 3828.7 3849.1 3860.5 5020.1 5066.2 5078.6 5080.9 5107.1 5293.4 5364.1 5372.2 5404.4 5446.3 5000.7 5042.8 5049.9 5060.0 5095.7 4689.5 4692.1 4694.4 4697.1 4740.1 5499.1 5543.3 5576.0 5588.6 5631.5 5368.6 5410.6 5417.9 5418.1 5428.7 5411.4 5588.7 5609.7 5616.5 5650.3 5283.8 5317.6 5350.0 5354.7 5399.5 5430.1 5470.1 5472.0 5472.4 5476.2 5144.4 5279.7 5358.4 5361.7 5368.7 5217.7 5240.8 5257.3 5260.9 5296.3 5167.7 5188.5 5190.5 5191.4 5235.8 5148.2 5234.1 5273.3 5287.1 5288.2 5164.1 5170.4 5233.2 5251.3 5256.9 16:26:58 INFO - PROCESS | 21900 | 16:26:58 INFO - PROCESS | 21900 | [#1] ext.html Cycles:180 Average:40962.55 Median:33209.50 stddev:65537.12 (197.3%) stddev-sans-first:65650.68 16:26:58 INFO - PROCESS | 21900 | Values: 536.3 546.8 549.4 553.1 591.8 28060.2 28571.4 28578.4 28584.7 28586.1 28635.9 35381.5 36350.6 36396.5 36430.1 8384.7 8532.0 8545.0 8547.1 8554.9 8347.9 8368.8 8418.4 8423.4 8426.4 8437.4 8517.9 8526.3 8526.5 8690.0 8593.0 8648.7 8683.2 8703.4 8704.7 8663.8 8682.7 8771.0 8796.5 8797.4 38126.0 38547.6 38551.2 38569.0 38609.4 3029.1 3162.9 3195.5 3487.1 3896.8 29602.9 29702.6 30077.7 30309.7 31019.4 3973.7 4064.6 4067.1 4069.9 4073.1 193158.0 194505.0 194780.0 196450.0 196470.0 50374.7 51955.2 56924.4 58288.7 58328.8 52624.0 52858.7 52971.2 53007.0 53046.7 13728.5 14382.4 14480.3 14509.6 14510.6 9969.0 10030.8 10788.2 10915.8 11021.9 18145.6 18551.4 18564.5 18576.8 18616.8 7163.3 7342.7 7453.9 7463.3 7512.5 895.2 929.0 951.6 1002.9 1107.9 28659.9 28661.4 28733.4 29089.0 29163.4 983.8 1033.0 1066.3 1085.3 1119.9 15021.4 15039.4 15059.7 15378.3 15459.7 312035.0 316113.0 316329.0 316393.0 316927.0 51591.7 51602.5 51924.7 52025.3 52106.5 1238.2 1258.2 1270.6 1271.8 1283.4 27319.6 28355.9 28646.0 28708.4 28759.1 45620.3 45957.1 45968.6 46001.8 46131.1 226252.0 226277.0 226312.0 226468.0 226740.0 27591.0 29807.9 31776.4 31788.5 31919.8 37935.0 38016.6 38456.6 39057.8 39337.4 30835.5 32077.0 32245.5 33351.8 35567.3 30993.0 35437.5 35615.8 35819.3 35978.5 31799.7 32438.8 33182.8 33236.2 33333.7 29058.9 29859.7 29997.1 30107.1 30339.7 23090.2 23189.8 23281.3 23523.6 23635.8 16:26:58 INFO - PROCESS | 21900 | 16:26:58 INFO - PROCESS | 21900 | [#2] jquery.html Cycles:180 Average:26186.62 Median:454.17 stddev:80258.74 (17671.7%) stddev-sans-first:80460.46 16:26:58 INFO - PROCESS | 21900 | Values: 361.4 362.7 363.1 363.5 365.5 13561.9 13998.7 15962.8 16174.7 16920.9 15586.9 15804.5 16826.4 16847.8 16895.4 5613.0 5899.2 5904.8 5906.2 5907.7 4247.6 4675.5 5819.4 6589.1 6618.7 6201.6 6471.3 6568.2 6600.9 6607.2 6320.9 6378.7 6405.2 6414.6 6422.3 6309.3 6317.3 6318.0 6322.1 6322.8 19917.3 21028.0 21034.6 21243.9 22361.7 2080.3 2088.3 2124.7 2133.2 2148.8 13489.3 13657.5 13720.5 13796.3 13868.3 1919.4 1977.4 1989.2 1995.1 2014.7 60263.0 60692.0 61776.7 62926.7 63551.4 14611.3 14759.3 14811.8 14831.8 15145.5 12486.8 12829.2 22860.9 23038.4 23088.0 7220.7 7360.3 7398.7 7425.1 7435.9 7116.3 7123.7 7137.8 7157.7 7168.4 4062.1 4227.9 4362.5 4442.3 4455.9 1761.9 1819.5 1825.8 1826.7 1827.8 456.2 871.7 875.4 919.8 1024.8 9059.9 10191.0 10867.7 10908.9 10928.0 449.6 453.4 454.9 474.7 482.5 5849.2 5982.1 6103.1 6108.0 6109.8 486328.0 489417.0 490748.0 494634.0 494829.0 63539.0 66507.0 66879.7 66907.7 69037.7 970.9 1069.3 1111.3 1121.2 1153.9 14125.9 14957.2 14965.6 14991.3 15127.8 31625.8 32534.7 32561.8 32782.0 33178.7 40547.8 40780.8 41953.8 42140.4 42550.0 6668.0 6944.5 7012.8 7114.2 7136.3 10102.6 10201.9 10446.8 10538.5 10625.9 9390.0 9485.0 9582.6 9746.6 9850.2 9456.3 9503.4 9947.3 10069.0 10112.3 2971.3 2995.8 6877.2 8871.1 9242.9 7279.2 7288.7 7299.7 7433.3 7532.8 8355.2 9127.0 9209.3 9243.4 9306.2 16:26:58 INFO - PROCESS | 21900 | 16:26:58 INFO - PROCESS | 21900 | [#3] mootools.html Cycles:180 Average:5455.16 Median:6339.81 stddev:2818.09 (44.5%) stddev-sans-first:2809.05 16:26:58 INFO - PROCESS | 21900 | Values: 1343.5 1455.4 1484.8 1635.3 1785.7 6924.1 7451.9 7526.5 7551.1 7553.7 7434.1 7564.5 7588.3 7602.6 7619.0 2004.3 2051.4 2054.7 2307.8 2323.2 2048.4 2300.7 2331.8 2339.1 2342.4 2266.9 2303.4 2310.7 2311.7 2316.1 1963.9 2010.2 2316.0 2318.1 2323.8 2301.4 2337.8 2343.3 2343.8 2347.4 7614.5 7687.7 7690.0 7690.6 7690.8 4576.7 4701.5 4709.9 4710.5 4714.1 3073.1 3100.6 3142.6 3163.0 3164.2 1074.4 1080.5 1936.2 1953.9 1979.2 10702.2 10764.6 10780.2 10792.2 10796.5 4248.0 4308.6 4382.8 4384.7 4387.4 8784.8 8787.7 8799.9 8806.2 8815.6 4906.6 4915.1 4917.7 4929.3 4929.8 4553.8 4557.5 4558.8 4567.7 4570.2 3462.9 3534.7 3562.1 3564.4 3577.8 1041.0 1077.5 1089.0 1092.5 1092.6 1296.5 1325.6 1342.9 1356.1 1358.7 7395.1 7427.9 7438.1 7447.5 7455.6 453.7 458.6 459.4 459.9 459.9 5242.1 5293.9 5352.8 5356.5 5849.5 8983.6 9126.4 9133.4 9142.0 9233.9 9273.7 9366.5 9388.6 9494.7 9508.0 7377.0 7437.5 7497.3 7521.0 7524.3 7223.5 7240.2 7272.8 7274.2 7275.8 8178.1 8385.0 8662.6 8673.3 8673.4 8647.2 8730.0 8963.0 8963.1 8965.5 6450.3 6558.1 6563.0 6674.2 6885.3 7403.9 7494.1 7500.4 7506.1 7509.1 6980.3 7011.4 7050.7 7101.5 7158.4 7026.4 7037.9 7042.6 7108.6 7164.1 7001.1 7040.8 7096.4 7100.1 7111.1 6472.7 6520.6 6521.4 6521.5 6524.9 6243.9 6435.7 6496.3 6503.1 6511.6 16:26:58 INFO - PROCESS | 21900 | 16:26:58 INFO - PROCESS | 21900 | [#4] prototype.html Cycles:180 Average:5102.29 Median:5758.34 stddev:1519.67 (26.4%) stddev-sans-first:1481.07 16:26:58 INFO - PROCESS | 21900 | Values: 327.7 336.6 342.4 348.6 351.8 5630.7 5745.0 5750.2 5751.2 5772.5 5530.5 5679.2 5681.1 5683.2 5710.0 4458.4 4460.5 4465.1 4472.8 4476.1 4270.7 4351.1 4388.1 4459.6 4499.6 4404.0 4405.5 4425.0 4441.6 4442.9 4442.5 4463.8 4465.2 4466.9 4467.7 3946.6 3953.4 3955.4 3956.3 3966.4 5396.3 5497.9 5509.9 5549.1 5555.0 5123.6 5124.4 5131.3 5135.3 5135.9 5784.9 6076.6 6083.7 6088.1 6096.7 4134.3 4158.0 4285.5 4296.7 4298.6 6244.9 6290.2 6302.1 6303.6 6322.7 4341.8 4369.4 4374.8 4375.3 4377.5 4276.2 4435.4 4438.3 4438.4 4496.9 5092.7 5098.4 5098.6 5101.7 5114.9 6098.0 6101.6 6102.5 6104.5 6108.5 1539.9 1557.2 1558.6 1558.7 1559.5 1052.2 1056.9 1061.9 1066.9 1085.5 5773.7 5774.1 5775.6 5778.2 5780.0 6226.1 6231.0 6239.8 6242.9 6248.9 5746.8 5750.4 5765.5 5774.8 5776.0 3267.6 3369.1 3371.2 3372.7 3373.3 6439.8 6502.3 6509.7 6516.6 6517.3 6402.0 6406.6 6408.2 6412.7 6414.9 6302.2 6382.2 6417.1 6428.3 6443.7 6053.4 6192.7 6213.9 6214.4 6216.3 6112.0 6164.8 6226.8 6234.0 6235.4 6486.8 6493.1 6493.6 6496.6 6499.0 4182.1 4322.1 4330.2 4330.9 4334.7 6316.8 6320.4 6327.4 6327.6 6333.0 6155.5 6155.7 6158.5 6160.7 6163.7 5961.9 6129.5 6145.8 6149.5 6155.0 6075.0 6095.7 6097.9 6100.5 6102.8 6155.6 6162.1 6166.8 6170.6 6178.2 5916.3 6032.4 6034.8 6035.3 6039.2 16:26:58 INFO - PROCESS | 21900 | 16:26:58 INFO - PROCESS | 21900 | [#5] yui.html Cycles:180 Average:13287.62 Median:24308.84 stddev:14865.96 (61.2%) stddev-sans-first:14877.65 16:26:58 INFO - PROCESS | 21900 | Values: 709.2 738.0 754.3 788.6 814.6 13562.5 13693.4 13801.4 13819.4 13843.6 11796.9 11861.9 11865.4 11936.2 11938.9 659.1 666.0 796.5 812.4 817.6 803.3 804.7 807.2 808.6 808.7 840.7 842.9 844.3 845.0 845.9 840.1 841.3 841.6 842.2 843.0 611.9 617.1 617.4 622.0 626.4 12551.2 12572.2 12601.5 12604.8 12625.8 11819.6 11935.2 11944.3 12014.3 12078.7 10360.3 10553.8 10664.4 10789.8 11110.4 3612.7 3656.2 3664.3 3757.6 3765.4 44952.8 45506.8 45551.3 45708.3 45826.1 9497.9 9610.7 9613.6 9698.7 10136.4 18052.0 18067.2 18233.5 18247.3 18276.3 7587.7 7726.2 7849.0 8036.2 8243.6 4018.1 4135.3 4154.1 4217.8 4222.1 3159.0 3188.4 3196.6 3201.6 3202.9 571.6 572.8 580.1 587.1 590.7 533.7 548.4 595.5 607.8 635.0 8761.1 8813.7 8817.2 8864.6 9030.5 632.8 658.1 666.1 670.2 696.3 3972.0 4047.2 4173.6 4240.4 4247.6 52354.2 52841.5 53163.5 53460.2 53727.2 49562.3 49589.3 53208.2 54353.7 54507.7 37798.6 38395.4 44370.1 44395.0 44884.8 8685.2 8813.3 8936.0 9100.6 9174.1 31991.4 32927.3 33115.8 33766.8 33867.4 37351.0 37556.4 37905.6 37976.2 38201.4 10524.1 10916.0 10977.8 11171.7 11480.3 12350.6 12676.2 12750.6 12864.9 13267.1 11510.2 11674.1 11910.0 12193.3 12234.1 11402.3 11576.4 11794.7 11860.1 12094.4 10781.7 10916.8 11577.2 11679.9 11852.8 9564.8 9608.3 9853.2 9951.4 10076.5 9598.8 9613.7 9813.6 10193.0 10336.5 16:26:58 INFO - PROCESS | 21900 | -------- Summary: end -------- 16:26:58 INFO - PROCESS | 21900 | 16:26:58 INFO - TEST-INFO | 21900: exit 0 16:26:58 INFO - TEST-OK | dromaeo_css | took 1112939ms 16:26:58 INFO - TEST-START | kraken 16:26:58 INFO - operating with platform_type : linux_ 16:26:58 INFO - Initialising browser for kraken test... 16:26:58 INFO - TEST-INFO | started process 22094 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8cCFjK/profile http://localhost:39121/getInfo.html) 16:27:02 INFO - PROCESS | 22094 | __metrics Screen width/height:1600/1200 16:27:02 INFO - PROCESS | 22094 | colorDepth:24 16:27:02 INFO - PROCESS | 22094 | Browser inner width/height: 1024/697 16:27:02 INFO - PROCESS | 22094 | __metrics 16:27:02 INFO - PROCESS | 22094 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 16:27:02 INFO - PROCESS | 22094 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:27:03 INFO - TEST-INFO | 22094: exit 0 16:27:03 INFO - Browser initialized. 16:27:03 INFO - Running cycle 1/1 for kraken test... 16:27:03 INFO - TEST-INFO | started process 22177 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8cCFjK/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:27:15 INFO - PROCESS | 22177 | RSS: Main: 150487040 16:27:15 INFO - PROCESS | 22177 | 16:27:18 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39121/tests/kraken/driver.html?testName=audio-beat-detection) 16:27:18 INFO - PROCESS | 22177 | RSS: Main: 186978304 16:27:18 INFO - PROCESS | 22177 | 16:27:21 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39121/tests/kraken/driver.html?testName=audio-dft) 16:27:21 INFO - PROCESS | 22177 | RSS: Main: 178200576 16:27:21 INFO - PROCESS | 22177 | 16:27:24 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39121/tests/kraken/driver.html?testName=audio-fft) 16:27:25 INFO - PROCESS | 22177 | RSS: Main: 161550336 16:27:25 INFO - PROCESS | 22177 | 16:27:27 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39121/tests/kraken/driver.html?testName=audio-oscillator) 16:27:27 INFO - PROCESS | 22177 | RSS: Main: 164933632 16:27:27 INFO - PROCESS | 22177 | 16:27:30 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39121/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:27:30 INFO - PROCESS | 22177 | RSS: Main: 165806080 16:27:30 INFO - PROCESS | 22177 | 16:27:33 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39121/tests/kraken/driver.html?testName=imaging-darkroom) 16:27:34 INFO - PROCESS | 22177 | RSS: Main: 165576704 16:27:34 INFO - PROCESS | 22177 | 16:27:37 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39121/tests/kraken/driver.html?testName=imaging-desaturate) 16:27:38 INFO - PROCESS | 22177 | RSS: Main: 166010880 16:27:38 INFO - PROCESS | 22177 | 16:27:40 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39121/tests/kraken/driver.html?testName=json-parse-financial) 16:27:41 INFO - PROCESS | 22177 | RSS: Main: 166031360 16:27:41 INFO - PROCESS | 22177 | 16:27:43 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39121/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:27:43 INFO - PROCESS | 22177 | RSS: Main: 176488448 16:27:43 INFO - PROCESS | 22177 | 16:27:45 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39121/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:27:46 INFO - PROCESS | 22177 | RSS: Main: 174383104 16:27:46 INFO - PROCESS | 22177 | 16:27:48 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39121/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:27:48 INFO - PROCESS | 22177 | RSS: Main: 184668160 16:27:48 INFO - PROCESS | 22177 | 16:27:51 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39121/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:27:51 INFO - PROCESS | 22177 | RSS: Main: 188948480 16:27:51 INFO - PROCESS | 22177 | 16:27:54 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39121/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:27:54 INFO - PROCESS | 22177 | RSS: Main: 189747200 16:27:54 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | Cycle 1(1): loaded http://localhost:39121/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39121/tests/kraken/driver.html?testName=ai-astar) 16:27:56 INFO - PROCESS | 22177 | RSS: Main: 248057856 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | __start_tp_report 16:27:56 INFO - PROCESS | 22177 | _x_x_mozilla_page_load 16:27:56 INFO - PROCESS | 22177 | _x_x_mozilla_page_load_details 16:27:56 INFO - PROCESS | 22177 | |i|pagename|runs| 16:27:56 INFO - PROCESS | 22177 | |0;ai-astar;109;110;104;109;110;111;99;113;111;110 16:27:56 INFO - PROCESS | 22177 | |1;audio-beat-detection;152;124;146;161;127;147;148;126;173;129 16:27:56 INFO - PROCESS | 22177 | |2;audio-dft;161;161;161;159;160;162;159;163;162;163 16:27:56 INFO - PROCESS | 22177 | |3;audio-fft;72;74;128;98;74;74;72;109;91;70 16:27:56 INFO - PROCESS | 22177 | |4;audio-oscillator;98;97;146;96;97;96;142;97;97;153 16:27:56 INFO - PROCESS | 22177 | |5;imaging-gaussian-blur;108;109;109;109;110;109;109;109;109;109 16:27:56 INFO - PROCESS | 22177 | |6;imaging-darkroom;148;145;146;146;145;146;145;147;146;146 16:27:56 INFO - PROCESS | 22177 | |7;imaging-desaturate;95;97;95;96;96;96;96;96;96;96 16:27:56 INFO - PROCESS | 22177 | |8;json-parse-financial;59;57;55;55;56;57;55;67;54;54 16:27:56 INFO - PROCESS | 22177 | |9;json-stringify-tinderbox;75;76;76;77;76;77;77;77;76;76 16:27:56 INFO - PROCESS | 22177 | |10;stanford-crypto-aes;81;83;83;82;83;81;82;84;83;83 16:27:56 INFO - PROCESS | 22177 | |11;stanford-crypto-ccm;121;117;113;113;125;117;121;111;114;114 16:27:56 INFO - PROCESS | 22177 | |12;stanford-crypto-pbkdf2;149;135;145;140;149;130;143;153;153;142 16:27:56 INFO - PROCESS | 22177 | |13;stanford-crypto-sha256-iterative;66;65;67;66;66;66;66;67;66;66 16:27:56 INFO - PROCESS | 22177 | __end_tp_report 16:27:56 INFO - PROCESS | 22177 | __start_cc_report 16:27:56 INFO - PROCESS | 22177 | _x_x_mozilla_cycle_collect,944 16:27:56 INFO - PROCESS | 22177 | __end_cc_report 16:27:56 INFO - PROCESS | 22177 | __startTimestamp1452731276679__endTimestamp 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | ------- Summary: start ------- 16:27:56 INFO - PROCESS | 22177 | Number of tests: 14 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#0] ai-astar Cycles:10 Average:108.60 Median:110.50 stddev:4.09 (3.7%) stddev-sans-first:4.33 16:27:56 INFO - PROCESS | 22177 | Values: 109.0 110.0 104.0 109.0 110.0 111.0 99.0 113.0 111.0 110.0 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#1] audio-beat-detection Cycles:10 Average:143.30 Median:147.50 stddev:16.52 (11.2%) stddev-sans-first:17.22 16:27:56 INFO - PROCESS | 22177 | Values: 152.0 124.0 146.0 161.0 127.0 147.0 148.0 126.0 173.0 129.0 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:1.45 (0.9%) stddev-sans-first:1.54 16:27:56 INFO - PROCESS | 22177 | Values: 161.0 161.0 161.0 159.0 160.0 162.0 159.0 163.0 162.0 163.0 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#3] audio-fft Cycles:10 Average:86.20 Median:74.00 stddev:19.84 (26.8%) stddev-sans-first:20.36 16:27:56 INFO - PROCESS | 22177 | Values: 72.0 74.0 128.0 98.0 74.0 74.0 72.0 109.0 91.0 70.0 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#4] audio-oscillator Cycles:10 Average:111.90 Median:97.00 stddev:24.37 (25.1%) stddev-sans-first:25.32 16:27:56 INFO - PROCESS | 22177 | Values: 98.0 97.0 146.0 96.0 97.0 96.0 142.0 97.0 97.0 153.0 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 16:27:56 INFO - PROCESS | 22177 | Values: 108.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.94 (0.6%) stddev-sans-first:0.67 16:27:56 INFO - PROCESS | 22177 | Values: 148.0 145.0 146.0 146.0 145.0 146.0 145.0 147.0 146.0 146.0 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 16:27:56 INFO - PROCESS | 22177 | Values: 95.0 97.0 95.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:56.50 stddev:3.87 (6.9%) stddev-sans-first:4.03 16:27:56 INFO - PROCESS | 22177 | Values: 59.0 57.0 55.0 55.0 56.0 57.0 55.0 67.0 54.0 54.0 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#9] json-stringify-tinderbox Cycles:10 Average:76.30 Median:76.50 stddev:0.67 (0.9%) stddev-sans-first:0.53 16:27:56 INFO - PROCESS | 22177 | Values: 75.0 76.0 76.0 77.0 76.0 77.0 77.0 77.0 76.0 76.0 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.00 stddev:0.97 (1.2%) stddev-sans-first:0.87 16:27:56 INFO - PROCESS | 22177 | Values: 81.0 83.0 83.0 82.0 83.0 81.0 82.0 84.0 83.0 83.0 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#11] stanford-crypto-ccm Cycles:10 Average:116.60 Median:117.00 stddev:4.48 (3.8%) stddev-sans-first:4.46 16:27:56 INFO - PROCESS | 22177 | Values: 121.0 117.0 113.0 113.0 125.0 117.0 121.0 111.0 114.0 114.0 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:143.90 Median:147.00 stddev:7.53 (5.1%) stddev-sans-first:7.76 16:27:56 INFO - PROCESS | 22177 | Values: 149.0 135.0 145.0 140.0 149.0 130.0 143.0 153.0 153.0 142.0 16:27:56 INFO - PROCESS | 22177 | 16:27:56 INFO - PROCESS | 22177 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 16:27:56 INFO - PROCESS | 22177 | Values: 66.0 65.0 67.0 66.0 66.0 66.0 66.0 67.0 66.0 66.0 16:27:56 INFO - PROCESS | 22177 | -------- Summary: end -------- 16:27:56 INFO - PROCESS | 22177 | 16:27:57 INFO - TEST-INFO | 22177: exit 0 16:27:57 INFO - TEST-OK | kraken | took 58736ms 16:27:57 INFO - TEST-START | v8_7 16:27:57 INFO - operating with platform_type : linux_ 16:27:57 INFO - Initialising browser for v8_7 test... 16:27:57 INFO - TEST-INFO | started process 22429 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplJwKSC/profile http://localhost:39121/getInfo.html) 16:28:01 INFO - PROCESS | 22429 | __metrics Screen width/height:1600/1200 16:28:01 INFO - PROCESS | 22429 | colorDepth:24 16:28:01 INFO - PROCESS | 22429 | Browser inner width/height: 1024/697 16:28:01 INFO - PROCESS | 22429 | __metrics 16:28:01 INFO - PROCESS | 22429 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 16:28:01 INFO - PROCESS | 22429 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:28:02 INFO - TEST-INFO | 22429: exit 0 16:28:02 INFO - Browser initialized. 16:28:02 INFO - Running cycle 1/1 for v8_7 test... 16:28:02 INFO - TEST-INFO | started process 22512 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplJwKSC/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:14 INFO - PROCESS | 22512 | RSS: Main: 149831680 16:28:14 INFO - PROCESS | 22512 | 16:28:36 INFO - PROCESS | 22512 | Cycle 1(1): loaded http://localhost:39121/tests/v8_7/run.html (next: http://localhost:39121/tests/v8_7/run.html) 16:28:36 INFO - PROCESS | 22512 | RSS: Main: 588292096 16:28:36 INFO - PROCESS | 22512 | 16:28:36 INFO - PROCESS | 22512 | __start_tp_report 16:28:36 INFO - PROCESS | 22512 | _x_x_mozilla_page_load 16:28:36 INFO - PROCESS | 22512 | _x_x_mozilla_page_load_details 16:28:36 INFO - PROCESS | 22512 | |i|pagename|runs| 16:28:36 INFO - PROCESS | 22512 | |0;Richards;167.98252981689905 16:28:36 INFO - PROCESS | 22512 | |1;DeltaBlue;189.10741301059002 16:28:36 INFO - PROCESS | 22512 | |2;Encrypt;279.2516056967328 16:28:36 INFO - PROCESS | 22512 | |3;Decrypt;5186.5284974093265 16:28:36 INFO - PROCESS | 22512 | |4;RayTrace;1970.4724409448818 16:28:36 INFO - PROCESS | 22512 | |5;Earley;769.2307692307693 16:28:36 INFO - PROCESS | 22512 | |6;Boyer;12444.444444444445 16:28:36 INFO - PROCESS | 22512 | |7;RegExp;42142.857142857145 16:28:36 INFO - PROCESS | 22512 | |8;Splay;602.9940119760479 16:28:36 INFO - PROCESS | 22512 | |9;NavierStokes;4830.917874396136 16:28:36 INFO - PROCESS | 22512 | __end_tp_report 16:28:36 INFO - PROCESS | 22512 | __startTimestamp1452731316966__endTimestamp 16:28:36 INFO - PROCESS | 22512 | 16:28:36 INFO - PROCESS | 22512 | ------- Summary: start ------- 16:28:36 INFO - PROCESS | 22512 | Number of tests: 10 16:28:36 INFO - PROCESS | 22512 | 16:28:36 INFO - PROCESS | 22512 | [#0] Richards Cycles:1 Average:167.98 Median:167.98 stddev:0.00 (0.0%) 16:28:36 INFO - PROCESS | 22512 | Values: 168.0 16:28:36 INFO - PROCESS | 22512 | 16:28:36 INFO - PROCESS | 22512 | [#1] DeltaBlue Cycles:1 Average:189.11 Median:189.11 stddev:0.00 (0.0%) 16:28:36 INFO - PROCESS | 22512 | Values: 189.1 16:28:36 INFO - PROCESS | 22512 | 16:28:36 INFO - PROCESS | 22512 | [#2] Encrypt Cycles:1 Average:279.25 Median:279.25 stddev:0.00 (0.0%) 16:28:36 INFO - PROCESS | 22512 | Values: 279.3 16:28:36 INFO - PROCESS | 22512 | 16:28:36 INFO - PROCESS | 22512 | [#3] Decrypt Cycles:1 Average:5186.53 Median:5186.53 stddev:0.00 (0.0%) 16:28:36 INFO - PROCESS | 22512 | Values: 5186.5 16:28:36 INFO - PROCESS | 22512 | 16:28:36 INFO - PROCESS | 22512 | [#4] RayTrace Cycles:1 Average:1970.47 Median:1970.47 stddev:0.00 (0.0%) 16:28:36 INFO - PROCESS | 22512 | Values: 1970.5 16:28:36 INFO - PROCESS | 22512 | 16:28:36 INFO - PROCESS | 22512 | [#5] Earley Cycles:1 Average:769.23 Median:769.23 stddev:0.00 (0.0%) 16:28:36 INFO - PROCESS | 22512 | Values: 769.2 16:28:36 INFO - PROCESS | 22512 | 16:28:36 INFO - PROCESS | 22512 | [#6] Boyer Cycles:1 Average:12444.44 Median:12444.44 stddev:0.00 (0.0%) 16:28:36 INFO - PROCESS | 22512 | Values: 12444.4 16:28:36 INFO - PROCESS | 22512 | 16:28:36 INFO - PROCESS | 22512 | [#7] RegExp Cycles:1 Average:42142.86 Median:42142.86 stddev:0.00 (0.0%) 16:28:36 INFO - PROCESS | 22512 | Values: 42142.9 16:28:36 INFO - PROCESS | 22512 | 16:28:36 INFO - PROCESS | 22512 | [#8] Splay Cycles:1 Average:602.99 Median:602.99 stddev:0.00 (0.0%) 16:28:36 INFO - PROCESS | 22512 | Values: 603.0 16:28:36 INFO - PROCESS | 22512 | 16:28:36 INFO - PROCESS | 22512 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 16:28:36 INFO - PROCESS | 22512 | Values: 4830.9 16:28:36 INFO - PROCESS | 22512 | -------- Summary: end -------- 16:28:36 INFO - PROCESS | 22512 | 16:28:37 INFO - TEST-INFO | 22512: exit 0 16:28:37 INFO - TEST-OK | v8_7 | took 40203ms 16:28:37 INFO - SUITE-END | took 1211s 16:28:37 INFO - Completed test suite (00:20:11) 16:28:37 INFO - Working with test: dromaeo_css 16:28:37 INFO - Generating results file: dromaeo_css 16:28:37 INFO - Working with test: kraken 16:28:37 INFO - Generating results file: kraken 16:28:37 INFO - javascript benchmark 16:28:37 INFO - Working with test: v8_7 16:28:37 INFO - Generating results file: v8_7 16:28:37 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:28:37 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,63,35]] 16:28:37 INFO - process_Request line: dromaeo_css 6671.51 graph.html#tests=[[72,63,35]] 16:28:37 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:28:37 INFO - process_Request line: kraken 1504.30 graph.html#tests=[[232,63,35]] 16:28:37 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:28:38 INFO - process_Request line: v8_7 18936.51 graph.html#tests=[[230,63,35]] 16:28:38 INFO - RETURN: dromaeo_css: 6671.51 16:28:38 INFO - RETURN: kraken: 1504.30 16:28:38 INFO - RETURN: v8_7: 18936.51 16:28:38 INFO - javascript benchmark 16:28:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3117.8752849886005, 3131.749460043197, 3167.461531539638, 3176.4917613181897, 3207.134073800073, 4912.705237685733, 4997.700137991714, 5034.874128146794, 5059.215821547661, 5109.412417571975, 4967.205247160455, 4968.35411396518, 5041.369828771403, 5041.521055499722, 5060.671056381333, 4518.4351956005585, 4549.135664223803, 4560.315952607102, 4593.770311484413, 4643.651726120537, 4617.861464156063, 4685.601723853477, 4688.812447502096, 4691.601213896823, 4705.482396936334, 4499.955000449986, 4521.7739113044545, 4604.654650901196, 4637.466691330506, 4678.0, 4580.839670611546, 4599.103174880899, 4620.330052142454, 4640.419947506562, 4651.674382793206, 4532.977335113336, 4542.295944128666, 4544.0, 4544.318352247156, 4578.633709303248, 4734.479207287196, 4860.1737704590305, 4882.194437917739, 4904.2643603459765, 4914.926276105862, 5260.8684782880355, 5280.815171469018, 5283.920741188886, 5285.07511185541, 5331.333583302087, 5303.443138470444, 5313.362396512443, 5314.627976041678, 5331.866703332409, 5333.333333333333, 4968.080905032544, 5029.06962211992, 5044.798208071636, 5068.391792984866, 5080.187170052774, 5405.189221616789, 5417.404085550586, 5448.210009548593, 5457.863553411117, 5504.504594586427, 5261.947380526222, 5266.236395722675, 5270.1040823060175, 5272.894542109137, 5316.362036555638, 5335.573154147659, 5364.490373414519, 5406.91889621656, 5420.593455490854, 5421.83734487966, 5387.6228663993925, 5392.191171324254, 5395.406505284415, 5396.487333703292, 5420.807422367152, 5377.919331210035, 5385.7307134643115, 5388.811391601275, 5391.514763671288, 5440.0, 4175.62419382257, 4181.038361176947, 4239.894002649959, 4241.045764702917, 4261.936070958938, 3817.3892177251505, 3820.2932457495453, 3828.6554210120667, 3849.1147036181833, 3860.517435320585, 5020.1214787412055, 5066.214736715815, 5078.619103567247, 5080.872978175576, 5107.106256405114, 5293.391260005072, 5364.061289274361, 5372.1941708744, 5404.351477822608, 5446.264754258124, 5000.74996250186, 5042.773075211624, 5049.924251136236, 5059.97470012655, 5095.719735414514, 4689.531046895351, 4692.131955588227, 4694.389729335165, 4697.13103075932, 4740.146773580788, 5499.1476321170285, 5543.334799824047, 5576.0241957657245, 5588.636738612058, 5631.493165615114, 5368.597355198453, 5410.567154627542, 5417.945820541744, 5418.078926582411, 5428.7285635718845, 5411.404745478073, 5588.748506317144, 5609.747561359667, 5616.466435688603, 5650.3219613646625, 5283.788648454172, 5317.574594032545, 5350.036993341236, 5354.732263386893, 5399.541039011728, 5430.104032834538, 5470.124780035176, 5471.972640136773, 5472.425395333592, 5476.205950137288, 5144.382674079135, 5279.683219006872, 5358.437364076716, 5361.678299302054, 5368.651037682538, 5217.713025783618, 5240.790368385222, 5257.2902658140665, 5260.89478210426, 5296.258523806593, 5167.741612919414, 5188.455212202664, 5190.506901844319, 5191.377034755854, 5235.790568377222, 5148.227765835155, 5234.11020126587, 5273.26174335601, 5287.127623942007, 5288.233206185005, 5164.0962831505085, 5170.37955445349, 5233.215017747369, 5251.317328747238, 5256.921146182887], "unit": "score", "name": "dojo.html", "value": 4982.58089340408}, {"lowerIsBetter": false, "replicates": [536.305484397705, 546.7621584610692, 549.3984087424269, 553.139867506028, 591.7810410148246, 28060.15819525415, 28571.428571428558, 28578.42843143136, 28584.714152858465, 28586.14241572756, 28635.856820715868, 35381.46927796085, 36350.63649363506, 36396.45405318922, 36430.08924776882, 8384.706535271282, 8532.0, 8545.0, 8547.05982341945, 8554.914450855475, 8347.916520834775, 8368.828364029041, 8418.410711250215, 8423.410361274713, 8426.410151289412, 8437.367197460184, 8517.914820851774, 8526.275266602346, 8526.531040792754, 8690.0, 8593.011803642567, 8648.65405383784, 8683.218510334036, 8703.390762646617, 8704.6518139275, 8663.783405414915, 8682.696105636336, 8771.03518612952, 8796.516191609522, 8797.428167169177, 38126.0, 38547.61452385468, 38551.22897542034, 38569.0, 38609.42085868714, 3029.12155474912, 3162.873485060618, 3195.504696772004, 3487.110786749388, 3896.844126234947, 29602.851985740148, 29702.554461683092, 30077.699223007927, 30309.69690303091, 31019.379612407625, 3973.6821054315874, 4064.593540645941, 4067.145899361108, 4069.877903662895, 4073.103917138225, 193158.0, 194505.0, 194780.0, 196450.0, 196469.99999999857, 50374.74812625913, 51955.22067168995, 56924.430755691916, 58288.70855645695, 58328.83396324518, 52624.0, 52858.73570632199, 52971.205431918555, 53007.0, 53046.73476632592, 13728.45086196561, 14382.35279412429, 14480.275986200648, 14509.637259068608, 14510.564683059525, 9969.003099690119, 10030.799384012278, 10788.190885683605, 10915.781684366268, 11021.944890275498, 18145.63708725818, 18551.44345669632, 18564.535886602942, 18576.814231857777, 18616.81383186178, 7163.283671632899, 7342.669579868919, 7453.85092298151, 7463.328300452985, 7512.511686740344, 895.1764376773384, 928.9581968811419, 951.5860600638745, 1002.9348092373972, 1107.9335239885472, 28659.856700716362, 28661.426771464456, 28733.425331493254, 29089.0, 29163.41673166525, 983.758979050123, 1032.9896701033044, 1066.2536224642781, 1085.3054045410943, 1119.8936101070385, 15021.399144034334, 15039.398424062914, 15059.698806023818, 15378.3079761411, 15459.690806184039, 312035.0, 316113.0, 316329.0, 316393.0, 316927.0, 51591.74204128955, 51602.48397516052, 51924.74037629788, 52025.317721405256, 52106.478935210165, 1238.2446707508414, 1258.1696080586769, 1270.5934101087564, 1271.8473783146082, 1283.4352884730874, 27319.590206146528, 28355.858220708764, 28646.0, 28708.425831482837, 28759.137225883256, 45620.31569526393, 45957.08085838331, 45968.62094137181, 46001.769991151166, 46131.077378452916, 226252.0, 226277.0, 226312.0, 226468.0, 226740.0, 27591.0, 29807.850960745058, 31776.36447271088, 31788.523172151974, 31919.840400797846, 37935.0, 38016.619833801306, 38456.615433845305, 39057.80471097626, 39337.40993885037, 30835.537466938462, 32077.0, 32245.51631725479, 33351.83324083364, 35567.28865422729, 30993.0, 35437.46843797294, 35615.82192089126, 35819.28361432809, 35978.46032309465, 31799.6820031806, 32438.83780581082, 33182.83408582942, 33236.174925131636, 33333.66666333403, 29058.854705726335, 29859.70140298569, 29997.100086997423, 30107.096787095546, 30339.696603033688, 23090.191843285404, 23189.768102319435, 23281.301560953198, 23523.647145292492, 23635.763642363356], "unit": "score", "name": "ext.html", "value": 17336.91848933511}, {"lowerIsBetter": false, "replicates": [361.4181168319004, 362.72432950957267, 363.14479401010635, 363.4675200830782, 365.5138665574786, 13561.93219033906, 13998.720025599481, 15962.760558591615, 16174.676506469877, 16920.91539542302, 15586.922065389686, 15804.525864224062, 16826.411075612355, 16847.83152168478, 16895.408660696878, 5612.971935140318, 5899.203607512974, 5904.822855314326, 5906.232189815318, 5907.700305932688, 4247.63895068918, 4675.532446755331, 5819.447152520504, 6589.143411356541, 6618.735250589971, 6201.565890387675, 6471.320511346312, 6568.1789776277965, 6600.867982640344, 6607.207135143765, 6320.905186422161, 6378.681065946707, 6405.19935008124, 6414.550981431302, 6422.325655806167, 6309.337519560472, 6317.273513545955, 6317.989121740499, 6322.0516922462, 6322.778702745428, 19917.30289439877, 21028.002339777697, 21034.5793084139, 21243.893780531154, 22361.664575031387, 2080.3446914221972, 2088.33173384517, 2124.6706760452153, 2133.178726190428, 2148.785121487854, 13489.258090805002, 13657.521986730517, 13720.45118195271, 13796.310184490736, 13868.295682341948, 1919.4049844548235, 1977.3771262052408, 1989.2341448542415, 1995.0623207092642, 2014.7380840490641, 60263.0, 60692.0, 61776.69111654413, 62926.68536657333, 63551.36448635547, 14611.323303714702, 14759.335829887681, 14811.777823332659, 14831.77752333716, 15145.545633631009, 12486.75026499465, 12829.23024618526, 22860.885695571415, 23038.424039398815, 23088.0, 7220.711171553079, 7360.28715289897, 7398.741044063432, 7425.108986921604, 7435.888461673079, 7116.252793456716, 7123.679434425359, 7137.750178743823, 7157.6779044943105, 7168.354848063699, 4062.1469491407133, 4227.894302642459, 4362.476502819682, 4442.311441726538, 4455.910881782346, 1761.8854774439621, 1819.5451137215696, 1825.7535232743676, 1826.6894627913287, 1827.7806663200502, 456.20392415235625, 871.7123349294718, 875.4309698695898, 919.7792529792802, 1024.7899180667946, 9059.864102038475, 10191.0, 10867.67396978076, 10908.945455272673, 10928.0, 449.60434817360516, 453.41372021997915, 454.9181147393435, 474.7222874618318, 482.48133256749134, 5849.151872978395, 5982.1325907744, 6103.1455596216565, 6107.992181290127, 6109.755609775648, 486328.0, 489417.0, 490748.0, 494634.0, 494829.0, 63539.0, 66507.0, 66879.66560167167, 66907.66546167238, 69037.65481172562, 970.9029097090375, 1069.2996087562658, 1111.3276467736903, 1121.2319561100671, 1153.8557680290132, 14125.858741412865, 14957.177355245565, 14965.551033469013, 14991.325390357242, 15127.794020560166, 31625.8418707905, 32534.674653253165, 32561.837190814844, 32782.0, 33178.668213318524, 40547.797261013504, 40780.79609601933, 41953.79023104987, 42140.367894480994, 42550.0, 6667.966660166668, 6944.51388402807, 7012.801144947101, 7114.181869085121, 7136.322049405506, 10102.595896164072, 10201.948990255001, 10446.791064178826, 10538.525766340379, 10625.9468702656, 9389.953050234979, 9484.95257523708, 9582.616695332108, 9746.610135594497, 9850.211983041196, 9456.33805633599, 9503.382280151767, 9947.253955953187, 10069.0, 10112.342697724624, 2971.316597182617, 2995.7902946793515, 6877.177547949897, 8871.112888711077, 9242.907570924204, 7279.235680253709, 7288.708451661874, 7299.744508942162, 7433.331000210007, 7532.811679708051, 8355.164483551596, 9126.954365228132, 9209.263258939403, 9243.445393276425, 9306.162445379678], "unit": "score", "name": "jquery.html", "value": 8002.195129345551}, {"lowerIsBetter": false, "replicates": [1343.5499107798887, 1455.3741890986876, 1484.7995520604723, 1635.2723038247977, 1785.7410675452052, 6924.065251191088, 7451.888221676672, 7526.510776799504, 7551.131619863717, 7553.735619253327, 7434.107907051161, 7564.470487065908, 7588.317051465367, 7602.581857997808, 7618.9619051904665, 2004.2984955265613, 2051.4050925231663, 2054.681524363726, 2307.826912981529, 2323.2449453927456, 2048.3752455501067, 2300.6894069300683, 2331.80179684727, 2339.1462116327502, 2342.437814924423, 2266.909323627053, 2303.355060583031, 2310.688057112285, 2311.7457079721303, 2316.085146367184, 1963.862529622927, 2010.2160157538572, 2315.976840231593, 2318.07277089164, 2323.8140948724144, 2301.4361481437154, 2337.789598936104, 2343.261872510171, 2343.7539058398793, 2347.4248809041724, 7614.505057171322, 7687.654055567457, 7689.961550192269, 7690.5770182639935, 7690.807729806744, 4576.725396476222, 4701.48283688794, 4709.905801883978, 4710.481846996828, 4714.104320179189, 3073.0959243514885, 3100.58142150809, 3142.5600415941785, 3163.0, 3164.240582260264, 1074.3500182389698, 1080.5029686344212, 1936.244864502845, 1953.9316123935737, 1979.2281010405952, 10702.197335199891, 10764.623238186705, 10780.245382823205, 10792.190585706103, 10796.514156862882, 4247.97876010618, 4308.590683885026, 4382.758948257812, 4384.671149663788, 4387.363832244307, 8784.824303513893, 8787.692430764893, 8799.868001979976, 8806.163414475615, 8815.647374105092, 4906.607471402279, 4915.066137433876, 4917.729524876095, 4929.285253638202, 4929.7781599828095, 4553.817847286072, 4557.544245575482, 4558.772061396917, 4567.680262381601, 4570.223062079388, 3462.9480557791653, 3534.6818786309354, 3562.1450851795903, 3564.429691249388, 3577.83899724513, 1040.9687709368732, 1077.5420446310286, 1089.0198821061108, 1092.5138313450439, 1092.6066616018227, 1296.5332480307081, 1325.615571484278, 1342.9059965802496, 1356.0846428660614, 1358.7078778062764, 7395.075615548057, 7427.85144297111, 7438.070241219848, 7447.478676492702, 7455.627218639155, 453.66882176011694, 458.5803989349708, 459.37754342865605, 459.9034202817379, 459.94710608280474, 5242.056429842589, 5293.947060529345, 5352.812651557255, 5356.491133342326, 5849.5027922626205, 8983.640654373883, 9126.406783559047, 9133.40632858862, 9141.954290228507, 9233.861492077624, 9273.72178834636, 9366.484843333683, 9388.577514011886, 9494.667686630935, 9507.952460237793, 7377.0, 7437.516561423598, 7497.250274972459, 7521.0, 7524.2851929066655, 7223.458240631869, 7240.167380751281, 7272.818179545554, 7274.236205198378, 7275.818104547428, 8178.141295163922, 8385.035720891932, 8662.610182541926, 8673.262772664395, 8673.436226645248, 8647.221750042274, 8729.956350218208, 8962.955185224033, 8963.103689630985, 8965.50689712072, 6450.322716114741, 6558.081868538504, 6563.0, 6674.199096108498, 6885.345892140239, 7403.888941665771, 7494.063242094739, 7500.36246919018, 7506.099268087864, 7509.098908131059, 6980.301969803182, 7011.368976792113, 7050.682719277747, 7101.502894797314, 7158.4273258140265, 7026.402755765817, 7037.929620703932, 7042.647867606497, 7108.644567771487, 7164.140303163445, 7001.124859392576, 7040.788776336718, 7096.361327480551, 7100.112485939258, 7111.146662400545, 6472.676366181766, 6520.608763474207, 6521.445677117308, 6521.478281737544, 6524.934750652623, 6243.937560624518, 6435.678216089083, 6496.317886621837, 6503.089567460464, 6511.641859697762], "unit": "score", "name": "mootools.html", "value": 4438.995234299412}, {"lowerIsBetter": false, "replicates": [327.6772379206483, 336.59776567002433, 342.3632044397421, 348.6147806673627, 351.83287938229347, 5630.690312032836, 5744.971275143624, 5750.19497270382, 5751.194832723417, 5772.538196944245, 5530.5299049580835, 5679.233303504026, 5681.119426488901, 5683.175939488771, 5710.0, 4458.398116254297, 4460.5316441773475, 4465.129299786543, 4472.843450479226, 4476.104779044188, 4270.658347332206, 4351.108022855307, 4388.122375524892, 4459.576340247671, 4499.572540608637, 4403.955960440403, 4405.493368262659, 4425.0, 4441.578050085237, 4442.8667139985855, 4442.489113751928, 4463.821447142111, 4465.151621191963, 4466.888327791815, 4467.7319360838455, 3946.625070618287, 3953.367461206193, 3955.44623752672, 3956.3274243378696, 3966.4446977423186, 5396.271503347037, 5497.890042199174, 5509.944900550983, 5549.112142057292, 5555.0, 5123.615728820353, 5124.4106927703415, 5131.281620573122, 5135.281060651511, 5135.871603209912, 5784.942150578482, 6076.574639775217, 6083.726232319512, 6088.117223002684, 6096.695165241765, 4134.255833949888, 4157.9605098725315, 4285.5071666758095, 4296.656267498593, 4298.61312481875, 6244.875102497925, 6290.245170579514, 6302.054691796221, 6303.621782693007, 6322.74709011639, 4341.782910854445, 4369.43197384338, 4374.846880359172, 4375.299952007664, 4377.4528183977, 4276.18752437036, 4435.35687325336, 4438.334249862546, 4438.400815889878, 4496.910061798746, 5092.719900405513, 5098.362704661918, 5098.592112631055, 5101.668391554611, 5114.948850511447, 6098.0, 6101.633901965896, 6102.450779429784, 6104.450599445982, 6108.511319094462, 1539.8768098552314, 1557.166915700095, 1558.6103474131467, 1558.6570954390015, 1559.485369827954, 1052.2266134391216, 1056.9048785609332, 1061.883192848786, 1066.898644628759, 1085.451846817368, 5773.682447465346, 5774.105013722879, 5775.624584402084, 5778.248827652378, 5779.971100144472, 6226.097215903759, 6230.968845155745, 6239.75040998355, 6242.906356404566, 6248.937510624835, 5746.827595172152, 5750.424957504216, 5765.4811067004175, 5774.82675519735, 5775.971120144372, 3267.591551056118, 3369.0735047862036, 3371.2077661749468, 3372.696457318853, 3373.274745929588, 6439.839004024937, 6502.317256688074, 6509.674516274167, 6516.609003459713, 6517.283098859121, 6401.967990160019, 6406.5515413920575, 6408.231012278557, 6412.743490260337, 6414.903776443357, 6302.180716506917, 6382.170317858649, 6417.069524918954, 6428.325025872309, 6443.71003304843, 6053.424924632153, 6192.690365481798, 6213.875722485434, 6214.4407003369915, 6216.316205217513, 6112.0, 6164.815055548341, 6226.750929962751, 6234.0, 6235.438810507076, 6486.837829054311, 6493.123428337114, 6493.610383376824, 6496.577722448026, 6498.967505162444, 4182.079942412664, 4322.135572885499, 4330.155619654137, 4330.891727706832, 4334.696571240109, 6316.810495684953, 6320.367963203827, 6327.367263273636, 6327.620342779448, 6333.0, 6155.507559395327, 6155.722992465439, 6158.538109641884, 6160.691965401802, 6163.722632481638, 5961.940380596138, 6129.478994285536, 6145.8156255312415, 6149.477294429845, 6154.969225153845, 6074.969625151846, 6095.725692343944, 6097.908531372122, 6100.54245931548, 6102.755889764359, 6155.599886007395, 6162.137300777984, 6166.845828854314, 6170.629762214281, 6178.196834411498, 5916.319623243382, 6032.366601506778, 6034.758609655741, 6035.275766907824, 6039.214902062704], "unit": "score", "name": "prototype.html", "value": 4615.363776498934}, {"lowerIsBetter": false, "replicates": [709.2375696126663, 737.9631018449077, 754.313015120953, 788.6490511722278, 814.6130587970711, 13562.457501699933, 13693.3837977291, 13801.378937947791, 13819.447222111103, 13843.584692459217, 11796.882031179686, 11861.940690296537, 11865.406729663526, 11936.16446848721, 11938.940305298484, 659.1299484680223, 666.0109737040491, 796.474326944217, 812.3866480807012, 817.6075483767795, 803.3211935914143, 804.7263930263709, 807.1565214351017, 808.5957021489255, 808.6846130009301, 840.6595328891779, 842.873568964654, 844.2654890245495, 844.9915500844975, 845.8562044452458, 840.0549381945311, 841.2765022081005, 841.5665932045001, 842.212531283246, 843.0389356134012, 611.9296280927706, 617.1113596421184, 617.4196255519797, 621.9533534984894, 626.4362074133271, 12551.246925184518, 12572.182808117443, 12601.495940162475, 12604.81092783609, 12625.810612840814, 11819.645410637695, 11935.224210426295, 11944.343061131634, 12014.279143251433, 12078.69803254917, 10360.32657877243, 10553.788924221473, 10664.360138391721, 10789.784204315949, 11110.44447777616, 3612.71098312137, 3656.232191239843, 3664.3221004114325, 3757.6242375762204, 3765.3787125124322, 44952.77523612394, 45506.77246613779, 45551.31673024907, 45708.3143752844, 45826.08347833058, 9497.90502094984, 9610.711678649652, 9613.615455381707, 9698.70903872885, 10136.44249566281, 18052.0, 18067.186976586087, 18233.452996410128, 18247.27010919575, 18276.268949241883, 7587.734429295058, 7726.227377262341, 7849.0, 8036.196380361917, 8243.587820608946, 4018.1361007383553, 4135.29699951009, 4154.148399578079, 4217.7891105444605, 4222.134462435193, 3159.0, 3188.3623275345026, 3196.5844440222622, 3201.6478187399366, 3202.8718851246153, 571.5998800839429, 572.8052462162884, 580.0516156084832, 587.0694948506665, 590.7105518296004, 533.6584585865049, 548.3611592494779, 595.538457695281, 607.8298076538576, 634.9808557265615, 8761.123887611187, 8813.735587932373, 8817.16236957488, 8864.622931329961, 9030.45817250967, 632.772202007277, 658.1079396734389, 666.1107421592131, 670.1522573943964, 696.3176087434342, 3971.960280397159, 4047.1500984793524, 4173.561776013536, 4240.448741663564, 4247.575242475789, 52354.21468677973, 52841.471585284424, 53163.46836531663, 53460.19809702857, 53727.19409208865, 49562.25656615154, 49589.25616115761, 53208.20187697188, 54353.72823135859, 54507.72746136323, 37798.62201377951, 38395.42406863899, 44370.112597747866, 44395.0, 44884.77557612256, 8685.199994039676, 8813.33899957493, 8935.955320223617, 9100.635974561208, 9174.082591740773, 31991.360172796878, 32927.341453171284, 33115.840945566786, 33766.831165844014, 33867.44391838821, 37351.0, 37556.43665344967, 37905.62094379131, 37976.24047519089, 38201.42697859479, 10524.105451036748, 10916.0, 10977.835332470178, 11171.66485005451, 11480.311181329147, 12350.62948111558, 12676.239425634123, 12750.61748147557, 12864.935675321563, 13267.071305008556, 11510.194286399872, 11674.124440666814, 11910.0, 12193.26840389544, 12234.143609947574, 11402.315861048364, 11576.421178941187, 11794.705132371759, 11860.051195904134, 12094.39528023613, 10781.73045673833, 10916.836247456453, 11577.189596728149, 11679.941600291944, 11852.822207666719, 9564.76088097803, 9608.279379046739, 9853.16248118918, 9951.402915825074, 10076.496175191358, 9598.760030999281, 9613.711588652352, 9813.607455701693, 10193.0, 10336.534855931352], "unit": "score", "name": "yui.html", "value": 6225.612544619255}], "name": "dromaeo_css", "value": 6671.512165175299}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 104.0, 109.0, 110.0, 111.0, 99.0, 113.0, 111.0, 110.0], "unit": "score", "name": "ai-astar", "value": 108.6}, {"lowerIsBetter": true, "replicates": [152.0, 124.0, 146.0, 161.0, 127.0, 147.0, 148.0, 126.0, 173.0, 129.0], "unit": "score", "name": "audio-beat-detection", "value": 143.3}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 161.0, 159.0, 160.0, 162.0, 159.0, 163.0, 162.0, 163.0], "unit": "score", "name": "audio-dft", "value": 161.1}, {"lowerIsBetter": true, "replicates": [72.0, 74.0, 128.0, 98.0, 74.0, 74.0, 72.0, 109.0, 91.0, 70.0], "unit": "score", "name": "audio-fft", "value": 86.2}, {"lowerIsBetter": true, "replicates": [98.0, 97.0, 146.0, 96.0, 97.0, 96.0, 142.0, 97.0, 97.0, 153.0], "unit": "score", "name": "audio-oscillator", "value": 111.9}, {"lowerIsBetter": true, "replicates": [108.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [148.0, 145.0, 146.0, 146.0, 145.0, 146.0, 145.0, 147.0, 146.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.0}, {"lowerIsBetter": true, "replicates": [95.0, 97.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [59.0, 57.0, 55.0, 55.0, 56.0, 57.0, 55.0, 67.0, 54.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 56.9}, {"lowerIsBetter": true, "replicates": [75.0, 76.0, 76.0, 77.0, 76.0, 77.0, 77.0, 77.0, 76.0, 76.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 76.3}, {"lowerIsBetter": true, "replicates": [81.0, 83.0, 83.0, 82.0, 83.0, 81.0, 82.0, 84.0, 83.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.5}, {"lowerIsBetter": true, "replicates": [121.0, 117.0, 113.0, 113.0, 125.0, 117.0, 121.0, 111.0, 114.0, 114.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 116.6}, {"lowerIsBetter": true, "replicates": [149.0, 135.0, 145.0, 140.0, 149.0, 130.0, 143.0, 153.0, 153.0, 142.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 143.9}, {"lowerIsBetter": true, "replicates": [66.0, 65.0, 67.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.1}], "name": "kraken", "value": 1504.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.98252981689905], "unit": "score", "name": "Richards", "value": 210.15280599999997}, {"lowerIsBetter": false, "replicates": [189.10741301059002], "unit": "score", "name": "DeltaBlue", "value": 349.631984}, {"lowerIsBetter": false, "replicates": [279.2516056967328], "unit": "score", "name": "Encrypt", "value": 953.1941609999996}, {"lowerIsBetter": false, "replicates": [5186.5284974093265], "unit": "score", "name": "Decrypt", "value": 51.32161138861136}, {"lowerIsBetter": false, "replicates": [1970.4724409448818], "unit": "score", "name": "RayTrace", "value": 375.5388731268733}, {"lowerIsBetter": false, "replicates": [769.2307692307693], "unit": "score", "name": "Earley", "value": 866.4018999999997}, {"lowerIsBetter": false, "replicates": [12444.444444444445], "unit": "score", "name": "Boyer", "value": 53.555062500000005}, {"lowerIsBetter": false, "replicates": [42142.857142857145], "unit": "score", "name": "RegExp", "value": 21.616593220338988}, {"lowerIsBetter": false, "replicates": [602.9940119760479], "unit": "score", "name": "Splay", "value": 135.14396226415099}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 18936.507726442203}]} 16:28:38 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,63,35]]", "result": "18936.51"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,63,35]]", "result": "1504.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,63,35]]", "result": "6671.51"}}} 16:28:38 INFO - Return code: 0 16:28:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:28:38 INFO - # TBPL SUCCESS # 16:28:38 INFO - Running post-action listener: _resource_record_post_action 16:28:38 INFO - Running post-run listener: _resource_record_post_run 16:28:39 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 0; Write bytes: 542769152; Read time: 0; Write time: 397452 16:28:39 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 126996480; Read time: 0; Write time: 99504 16:28:39 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 0; Write bytes: 297062400; Read time: 0; Write time: 205048 16:28:39 INFO - Running post-run listener: _upload_blobber_files 16:28:39 INFO - Blob upload gear active. 16:28:39 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 16:28:39 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:28:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 16:28:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Mozilla-Inbound -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 16:28:39 INFO - (blobuploader) - INFO - Open directory for files ... 16:28:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 16:28:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:28:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:40 INFO - (blobuploader) - INFO - Done attempting. 16:28:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:28:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:28:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:28:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:28:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:28:41 INFO - (blobuploader) - INFO - Done attempting. 16:28:41 INFO - (blobuploader) - INFO - Iteration through files over. 16:28:41 INFO - Return code: 0 16:28:41 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 16:28:41 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 16:28:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2ce1819a1eee82a47195567ebe8f9fb5d493994d09102fcf25d71f084f302e740ff5e3c208ed695ec28ceaa4f90b9b4eb841e57c274a999fa93d30775b5af744", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/71a9d9bb3e4a342979d8330f85e4f3c95f2b48411e865ab0ddd3c5cd4dd21d7d1068c745deb3db9f6e779fc15e60cb05a0dd638ebea09050ea49731cca8bc112"} 16:28:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 16:28:41 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 16:28:41 INFO - Contents: 16:28:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2ce1819a1eee82a47195567ebe8f9fb5d493994d09102fcf25d71f084f302e740ff5e3c208ed695ec28ceaa4f90b9b4eb841e57c274a999fa93d30775b5af744", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/71a9d9bb3e4a342979d8330f85e4f3c95f2b48411e865ab0ddd3c5cd4dd21d7d1068c745deb3db9f6e779fc15e60cb05a0dd638ebea09050ea49731cca8bc112"} 16:28:41 INFO - Copying logs to upload dir... 16:28:41 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1274.851805 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-01-13 16:28:41.005909) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-13 16:28:41.009486) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test-pgo/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2ce1819a1eee82a47195567ebe8f9fb5d493994d09102fcf25d71f084f302e740ff5e3c208ed695ec28ceaa4f90b9b4eb841e57c274a999fa93d30775b5af744", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/71a9d9bb3e4a342979d8330f85e4f3c95f2b48411e865ab0ddd3c5cd4dd21d7d1068c745deb3db9f6e779fc15e60cb05a0dd638ebea09050ea49731cca8bc112"} build_url:https://queue.taskcluster.net/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011232 build_url: 'https://queue.taskcluster.net/v1/task/U5Lprep0Qduhf-DVEFccsw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/2ce1819a1eee82a47195567ebe8f9fb5d493994d09102fcf25d71f084f302e740ff5e3c208ed695ec28ceaa4f90b9b4eb841e57c274a999fa93d30775b5af744", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/71a9d9bb3e4a342979d8330f85e4f3c95f2b48411e865ab0ddd3c5cd4dd21d7d1068c745deb3db9f6e779fc15e60cb05a0dd638ebea09050ea49731cca8bc112"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-13 16:28:41.034052) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:28:41.034417) ========= rm -f oauth.txt in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1452730020.602633-82869230 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004475 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-13 16:28:41.122687) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-13 16:28:41.122998) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-13 16:28:41.123306) ========= ========= Total master_lag: 0.27 =========