builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-023 starttime: 1452834115.83 results: success (0) buildid: 20160114193043 builduid: 18c5842b65a44ba88e1ba11134f0bd43 revision: 9a358be6fa798f24deecac1b502742b2c37cd6bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:55.827452) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:55.827988) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:55.828344) ========= 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-1452830569.679415-1410185024 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-14 21:01:55.855049) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:55.855443) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:55.874562) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:55.875211) ========= 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-1452830569.679415-1410185024 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004714 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:55.931143) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:55.931553) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:55.932030) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:55.932439) ========= 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-1452830569.679415-1410185024 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-14 21:01:56-- 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% 64.0M=0s 2016-01-14 21:01:56 (64.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.155006 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:56.100946) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:56.101321) ========= 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-1452830569.679415-1410185024 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020327 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:56.152740) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:56.153169) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 9a358be6fa798f24deecac1b502742b2c37cd6bd --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 9a358be6fa798f24deecac1b502742b2c37cd6bd --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-1452830569.679415-1410185024 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-14 21:01:56,429 truncating revision to first 12 chars 2016-01-14 21:01:56,429 Setting DEBUG logging. 2016-01-14 21:01:56,429 attempt 1/10 2016-01-14 21:01:56,429 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/9a358be6fa79?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-14 21:01:56,721 unpacking tar archive at: mozilla-central-9a358be6fa79/testing/mozharness/ program finished with exit code 0 elapsedTime=0.616147 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:56.783289) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:56.783648) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:56.793011) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:56.793437) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-14 21:01:56.793971) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-01-14 21:01:56.794329) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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-1452830569.679415-1410185024 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:01:57 INFO - MultiFileLogger online at 20160114 21:01:57 in /builds/slave/test-pgo 21:01:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 21:01:57 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 21:01:57 INFO - {'append_to_log': False, 21:01:57 INFO - 'base_work_dir': '/builds/slave/test-pgo', 21:01:57 INFO - 'blob_upload_branch': 'Firefox', 21:01:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 21:01:57 INFO - 'branch': 'Firefox', 21:01:57 INFO - 'buildbot_json_path': 'buildprops.json', 21:01:57 INFO - 'config_files': ('talos/linux_config.py',), 21:01:57 INFO - 'default_actions': ('clobber', 21:01:57 INFO - 'read-buildbot-config', 21:01:57 INFO - 'download-and-extract', 21:01:57 INFO - 'populate-webroot', 21:01:57 INFO - 'create-virtualenv', 21:01:57 INFO - 'install', 21:01:57 INFO - 'run-tests'), 21:01:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:01:57 INFO - 'download_minidump_stackwalk': True, 21:01:57 INFO - 'download_symbols': 'ondemand', 21:01:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:01:57 INFO - 'tooltool.py': '/tools/tooltool.py', 21:01:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:01:57 INFO - '/tools/misc-python/virtualenv.py')}, 21:01:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:01:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:01:57 INFO - 'installer_path': 'installer.exe', 21:01:57 INFO - 'log_level': 'info', 21:01:57 INFO - 'log_name': 'talos', 21:01:57 INFO - 'log_to_console': True, 21:01:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:01:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:01:57 INFO - 'opt_config_files': (), 21:01:57 INFO - 'pip_index': False, 21:01:57 INFO - 'sps_profile': False, 21:01:57 INFO - 'sps_profile_interval': 0, 21:01:57 INFO - 'suite': 'dromaeojs', 21:01:57 INFO - 'system_bits': '32', 21:01:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:01:57 INFO - 'title': 'talos-linux64-ix-023', 21:01:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:01:57 INFO - 'use_talos_json': True, 21:01:57 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 21:01:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:01:57 INFO - 'work_dir': 'build'} 21:01:57 INFO - ##### 21:01:57 INFO - ##### Running clobber step. 21:01:57 INFO - ##### 21:01:57 INFO - Running pre-action listener: _resource_record_pre_action 21:01:57 INFO - Running main action method: clobber 21:01:57 INFO - rmtree: /builds/slave/test-pgo/build 21:01:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 21:01:58 INFO - Running post-action listener: _resource_record_post_action 21:01:58 INFO - ##### 21:01:58 INFO - ##### Running read-buildbot-config step. 21:01:58 INFO - ##### 21:01:58 INFO - Running pre-action listener: _resource_record_pre_action 21:01:58 INFO - Running main action method: read_buildbot_config 21:01:58 INFO - Using buildbot properties: 21:01:58 INFO - { 21:01:58 INFO - "project": "", 21:01:58 INFO - "product": "firefox", 21:01:58 INFO - "script_repo_revision": "production", 21:01:58 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 21:01:58 INFO - "repository": "", 21:01:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 21:01:58 INFO - "buildid": "20160114193043", 21:01:58 INFO - "pgo_build": "True", 21:01:58 INFO - "basedir": "/builds/slave/test-pgo", 21:01:58 INFO - "buildnumber": 645, 21:01:58 INFO - "slavename": "talos-linux64-ix-023", 21:01:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:01:58 INFO - "platform": "ubuntu64_hw", 21:01:58 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 21:01:58 INFO - "branch": "mozilla-central", 21:01:58 INFO - "repo_path": "mozilla-central", 21:01:58 INFO - "revision": "9a358be6fa798f24deecac1b502742b2c37cd6bd", 21:01:58 INFO - "stage_platform": "linux64-pgo", 21:01:58 INFO - "builduid": "18c5842b65a44ba88e1ba11134f0bd43", 21:01:58 INFO - "slavebuilddir": "test" 21:01:58 INFO - } 21:01:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 21:01:58 INFO - Running post-action listener: _resource_record_post_action 21:01:58 INFO - ##### 21:01:58 INFO - ##### Running download-and-extract step. 21:01:58 INFO - ##### 21:01:58 INFO - Running pre-action listener: _resource_record_pre_action 21:01:58 INFO - Running main action method: download_and_extract 21:01:58 INFO - mkdir: /builds/slave/test-pgo/build/tests 21:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:01:58 INFO - https://queue.taskcluster.net/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:01:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/test_packages.json 21:01:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/test_packages.json 21:01:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 21:01:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 21:02:01 INFO - Downloaded 1448 bytes. 21:02:01 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 21:02:01 INFO - Using the following test package requirements: 21:02:01 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:02:01 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:02:01 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:02:01 INFO - u'gtest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:02:01 INFO - u'firefox-46.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:02:01 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:02:01 INFO - u'jsshell-linux-x86_64.zip'], 21:02:01 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:02:01 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:02:01 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:02:01 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:02:01 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:02:01 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:02:01 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:02:01 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:02:01 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:02:01 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 21:02:01 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 21:02:01 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:02:01 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:02:01 INFO - https://queue.taskcluster.net/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:02:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:02:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:02:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/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 21:02:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/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 21:02:10 INFO - Downloaded 24030757 bytes. 21:02:10 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'] 21:02:10 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 21:02:11 INFO - Return code: 0 21:02:11 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 21:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:02:11 INFO - https://queue.taskcluster.net/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:02:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:02:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 21:02:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/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 21:02:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/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 21:02:15 INFO - Downloaded 24030757 bytes. 21:02:15 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'] 21:02:15 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 21:02:16 INFO - Return code: 0 21:02:16 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')]} 21:02:16 INFO - https://queue.taskcluster.net/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:02:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:02:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 21:02:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/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 21:02:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/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 21:02:18 INFO - Downloaded 11132144 bytes. 21:02:18 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'] 21:02:18 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 21:02:19 INFO - Return code: 0 21:02:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:02:19 INFO - https://queue.taskcluster.net/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:02:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:02:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:02:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/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 21:02:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IpknuwviQ9CPIDevMtHWnw/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 21:02:21 INFO - Downloaded 55864290 bytes. 21:02:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:02:21 INFO - mkdir: /builds/slave/test-pgo/properties 21:02:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 21:02:21 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 21:02:21 INFO - Contents: 21:02:21 INFO - build_url:https://queue.taskcluster.net/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 21:02:21 INFO - Running post-action listener: _resource_record_post_action 21:02:21 INFO - Running post-action listener: set_extra_try_arguments 21:02:21 INFO - ##### 21:02:21 INFO - ##### Running populate-webroot step. 21:02:21 INFO - ##### 21:02:21 INFO - Running pre-action listener: _resource_record_pre_action 21:02:21 INFO - Running main action method: populate_webroot 21:02:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:02:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:02:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:02:22 INFO - '--tppagecycles', 21:02:22 INFO - '7'], 21:02:22 INFO - 'tests': ['tsvgm']}}, 21:02:22 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 21:02:22 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 21:02:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:02:22 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:02:22 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 21:02:22 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:02:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:02:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:02:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:02:22 INFO - 'talos_options': ['--e10s'], 21:02:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:02:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:02:22 INFO - 'tests': ['damp', 'tps']}, 21:02:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:02:22 INFO - 'talos_options': ['--e10s'], 21:02:22 INFO - 'tests': ['damp', 'tps']}, 21:02:22 INFO - 'g3': {'tests': ['dromaeo_dom']}, 21:02:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:02:22 INFO - 'other': {'tests': ['a11yr', 21:02:22 INFO - 'ts_paint', 21:02:22 INFO - 'tpaint', 21:02:22 INFO - 'sessionrestore', 21:02:22 INFO - 'sessionrestore_no_auto_restore']}, 21:02:22 INFO - 'other-e10s': {'talos_options': ['--e10s'], 21:02:22 INFO - 'tests': ['a11yr', 21:02:22 INFO - 'ts_paint', 21:02:22 INFO - 'tpaint', 21:02:22 INFO - 'sessionrestore', 21:02:22 INFO - 'sessionrestore_no_auto_restore']}, 21:02:22 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 21:02:22 INFO - 'tests': ['a11yr', 21:02:22 INFO - 'ts_paint', 21:02:22 INFO - 'tpaint', 21:02:22 INFO - 'sessionrestore', 21:02:22 INFO - 'sessionrestore_no_auto_restore']}, 21:02:22 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 21:02:22 INFO - 'tests': ['a11yr', 21:02:22 INFO - 'ts_paint', 21:02:22 INFO - 'tpaint', 21:02:22 INFO - 'sessionrestore', 21:02:22 INFO - 'sessionrestore_no_auto_restore']}, 21:02:22 INFO - 'other_l64': {'tests': ['a11yr', 21:02:22 INFO - 'ts_paint', 21:02:22 INFO - 'tpaint', 21:02:22 INFO - 'sessionrestore', 21:02:22 INFO - 'sessionrestore_no_auto_restore']}, 21:02:22 INFO - 'other_nol64': {'tests': ['a11yr', 21:02:22 INFO - 'ts_paint', 21:02:22 INFO - 'tpaint', 21:02:22 INFO - 'sessionrestore', 21:02:22 INFO - 'sessionrestore_no_auto_restore']}, 21:02:22 INFO - 'svgr': {'tests': ['tsvgx', 21:02:22 INFO - 'tsvgr_opacity', 21:02:22 INFO - 'tart', 21:02:22 INFO - 'tscrollx', 21:02:22 INFO - 'cart']}, 21:02:22 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 21:02:22 INFO - 'tests': ['tsvgx', 21:02:22 INFO - 'tsvgr_opacity', 21:02:22 INFO - 'tart', 21:02:22 INFO - 'tscrollx', 21:02:22 INFO - 'cart']}, 21:02:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:02:22 INFO - 'tests': ['tp5o']}, 21:02:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:02:22 INFO - 'talos_options': ['--e10s'], 21:02:22 INFO - 'tests': ['tp5o']}, 21:02:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:02:22 INFO - 'talos_options': ['--xperf_path', 21:02:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:02:22 INFO - 'tests': ['tp5n']}, 21:02:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:02:22 INFO - 'talos_options': ['--e10s', 21:02:22 INFO - '--xperf_path', 21:02:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:02:22 INFO - 'tests': ['tp5n']}}, 21:02:22 INFO - 'talos.zip': {'path': '', 21:02:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:02:22 INFO - Running post-action listener: _resource_record_post_action 21:02:22 INFO - ##### 21:02:22 INFO - ##### Running create-virtualenv step. 21:02:22 INFO - ##### 21:02:22 INFO - Running pre-action listener: _resource_record_pre_action 21:02:22 INFO - Running main action method: create_virtualenv 21:02:22 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 21:02:22 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 21:02:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 21:02:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:02:22 INFO - Using real prefix '/usr' 21:02:22 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 21:02:22 INFO - Installing distribute.............................................................................................................................................................................................done. 21:02:24 INFO - Installing pip.................done. 21:02:24 INFO - Return code: 0 21:02:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 21:02: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')]} 21:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:02:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02: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')]} 21:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:02:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:24 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 0x7f3942f34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1291630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1322840>, '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 0x128f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x128fb60>, '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-1452830569.679415-1410185024', '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 21:02:24 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 21:02:24 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 21:02:24 INFO - Using env: {'DISPLAY': ':0', 21:02:24 INFO - 'HOME': '/home/cltbld', 21:02:24 INFO - 'LANG': 'en_US.UTF-8', 21:02:24 INFO - 'LANGUAGE': 'en_US:en', 21:02:24 INFO - 'LOGNAME': 'cltbld', 21:02:24 INFO - 'MAIL': '/var/mail/cltbld', 21:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:02:24 INFO - 'MOZ_NO_REMOTE': '1', 21:02:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:02:24 INFO - 'NO_EM_RESTART': '1', 21:02:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:02:24 INFO - 'PWD': '/builds/slave/test-pgo', 21:02:24 INFO - 'SHELL': '/bin/bash', 21:02:24 INFO - 'SHLVL': '1', 21:02:24 INFO - 'TERM': 'linux', 21:02:24 INFO - 'TMOUT': '86400', 21:02:24 INFO - 'USER': 'cltbld', 21:02:24 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452830569.679415-1410185024', 21:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:02:24 INFO - '_': '/tools/buildbot/bin/python'} 21:02:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:02:24 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 21:02:24 INFO - Cleaning up... 21:02:24 INFO - Return code: 0 21:02:24 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 21:02: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')]} 21:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:02:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02: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')]} 21:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:02:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:24 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 0x7f3942f34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1291630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1322840>, '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 0x128f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x128fb60>, '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-1452830569.679415-1410185024', '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 21:02:24 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 21:02:24 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 21:02:24 INFO - Using env: {'DISPLAY': ':0', 21:02:24 INFO - 'HOME': '/home/cltbld', 21:02:24 INFO - 'LANG': 'en_US.UTF-8', 21:02:24 INFO - 'LANGUAGE': 'en_US:en', 21:02:24 INFO - 'LOGNAME': 'cltbld', 21:02:24 INFO - 'MAIL': '/var/mail/cltbld', 21:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:02:24 INFO - 'MOZ_NO_REMOTE': '1', 21:02:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:02:24 INFO - 'NO_EM_RESTART': '1', 21:02:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:02:24 INFO - 'PWD': '/builds/slave/test-pgo', 21:02:24 INFO - 'SHELL': '/bin/bash', 21:02:24 INFO - 'SHLVL': '1', 21:02:24 INFO - 'TERM': 'linux', 21:02:24 INFO - 'TMOUT': '86400', 21:02:24 INFO - 'USER': 'cltbld', 21:02:24 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452830569.679415-1410185024', 21:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:02:24 INFO - '_': '/tools/buildbot/bin/python'} 21:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:02:25 INFO - Downloading/unpacking psutil>=0.7.1 21:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:02:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:02:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:02:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:02:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:02:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:02:26 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 21:02:26 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 21:02:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 21:02:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:02:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:02:26 INFO - Installing collected packages: psutil 21:02:26 INFO - Running setup.py install for psutil 21:02:26 INFO - building 'psutil._psutil_linux' extension 21:02:26 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 21:02:27 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 21:02:27 INFO - building 'psutil._psutil_posix' extension 21:02:27 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 21:02:27 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 21:02:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:02:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:02:27 INFO - Successfully installed psutil 21:02:27 INFO - Cleaning up... 21:02:27 INFO - Return code: 0 21:02:27 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 21:02: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')]} 21:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:02:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02: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')]} 21:02:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:02:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:27 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 0x7f3942f34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1291630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1322840>, '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 0x128f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x128fb60>, '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-1452830569.679415-1410185024', '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 21:02:27 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 21:02:27 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 21:02:27 INFO - Using env: {'DISPLAY': ':0', 21:02:27 INFO - 'HOME': '/home/cltbld', 21:02:27 INFO - 'LANG': 'en_US.UTF-8', 21:02:27 INFO - 'LANGUAGE': 'en_US:en', 21:02:27 INFO - 'LOGNAME': 'cltbld', 21:02:27 INFO - 'MAIL': '/var/mail/cltbld', 21:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:02:27 INFO - 'MOZ_NO_REMOTE': '1', 21:02:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:02:27 INFO - 'NO_EM_RESTART': '1', 21:02:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:02:27 INFO - 'PWD': '/builds/slave/test-pgo', 21:02:27 INFO - 'SHELL': '/bin/bash', 21:02:27 INFO - 'SHLVL': '1', 21:02:27 INFO - 'TERM': 'linux', 21:02:27 INFO - 'TMOUT': '86400', 21:02:27 INFO - 'USER': 'cltbld', 21:02:27 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452830569.679415-1410185024', 21:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:02:27 INFO - '_': '/tools/buildbot/bin/python'} 21:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:02:27 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:02:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:02:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:02:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:02:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:02:29 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 21:02:29 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:02:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:02:29 INFO - Installing collected packages: mozsystemmonitor 21:02:29 INFO - Running setup.py install for mozsystemmonitor 21:02:29 INFO - Successfully installed mozsystemmonitor 21:02:29 INFO - Cleaning up... 21:02:29 INFO - Return code: 0 21:02:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 21:02:29 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')]} 21:02:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:02:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:29 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')]} 21:02:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:02:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:29 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 0x7f3942f34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1291630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1322840>, '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 0x128f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x128fb60>, '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-1452830569.679415-1410185024', '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 21:02:29 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 21:02:29 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 21:02:29 INFO - Using env: {'DISPLAY': ':0', 21:02:29 INFO - 'HOME': '/home/cltbld', 21:02:29 INFO - 'LANG': 'en_US.UTF-8', 21:02:29 INFO - 'LANGUAGE': 'en_US:en', 21:02:29 INFO - 'LOGNAME': 'cltbld', 21:02:29 INFO - 'MAIL': '/var/mail/cltbld', 21:02:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:02:29 INFO - 'MOZ_NO_REMOTE': '1', 21:02:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:02:29 INFO - 'NO_EM_RESTART': '1', 21:02:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:02:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:02:29 INFO - 'PWD': '/builds/slave/test-pgo', 21:02:29 INFO - 'SHELL': '/bin/bash', 21:02:29 INFO - 'SHLVL': '1', 21:02:29 INFO - 'TERM': 'linux', 21:02:29 INFO - 'TMOUT': '86400', 21:02:29 INFO - 'USER': 'cltbld', 21:02:29 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452830569.679415-1410185024', 21:02:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:02:29 INFO - '_': '/tools/buildbot/bin/python'} 21:02:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:02:29 INFO - Downloading/unpacking blobuploader==1.2.4 21:02:29 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 21:02:29 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 21:02:29 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 21:02:29 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 21:02:29 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 21:02:29 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 21:02:31 INFO - Downloading blobuploader-1.2.4.tar.gz 21:02:31 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 21:02:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:02:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:02:31 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 21:02:31 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 21:02:31 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 21:02:31 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 21:02:31 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 21:02:31 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 21:02:31 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 21:02:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 21:02:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:02:31 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 21:02:31 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 21:02:31 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 21:02:31 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 21:02:31 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 21:02:31 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 21:02:32 INFO - Downloading docopt-0.6.1.tar.gz 21:02:32 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 21:02:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 21:02:32 INFO - Installing collected packages: blobuploader, requests, docopt 21:02:32 INFO - Running setup.py install for blobuploader 21:02:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:02:32 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 21:02:32 INFO - Running setup.py install for requests 21:02:32 INFO - Running setup.py install for docopt 21:02:32 INFO - Successfully installed blobuploader requests docopt 21:02:32 INFO - Cleaning up... 21:02:32 INFO - Return code: 0 21:02:32 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:02:32 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')]} 21:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:32 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')]} 21:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:32 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 0x7f3942f34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1291630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1322840>, '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 0x128f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x128fb60>, '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-1452830569.679415-1410185024', '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 21:02:32 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 21:02:32 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 21:02:32 INFO - Using env: {'DISPLAY': ':0', 21:02:32 INFO - 'HOME': '/home/cltbld', 21:02:32 INFO - 'LANG': 'en_US.UTF-8', 21:02:32 INFO - 'LANGUAGE': 'en_US:en', 21:02:32 INFO - 'LOGNAME': 'cltbld', 21:02:32 INFO - 'MAIL': '/var/mail/cltbld', 21:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:02:32 INFO - 'MOZ_NO_REMOTE': '1', 21:02:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:02:32 INFO - 'NO_EM_RESTART': '1', 21:02:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:02:32 INFO - 'PWD': '/builds/slave/test-pgo', 21:02:32 INFO - 'SHELL': '/bin/bash', 21:02:32 INFO - 'SHLVL': '1', 21:02:32 INFO - 'TERM': 'linux', 21:02:32 INFO - 'TMOUT': '86400', 21:02:32 INFO - 'USER': 'cltbld', 21:02:32 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452830569.679415-1410185024', 21:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:02:32 INFO - '_': '/tools/buildbot/bin/python'} 21:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:02:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:02:32 INFO - Running setup.py (path:/tmp/pip-ezekUP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:02:33 INFO - Running setup.py (path:/tmp/pip-NOCZsf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:02:33 INFO - Running setup.py (path:/tmp/pip-91Fs10-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:02:33 INFO - Running setup.py (path:/tmp/pip-2ldbQz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:02:33 INFO - Running setup.py (path:/tmp/pip-iK68Nc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:02:33 INFO - Running setup.py (path:/tmp/pip-2VxG6u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:02:33 INFO - Running setup.py (path:/tmp/pip-1Jul8r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:02:33 INFO - Running setup.py (path:/tmp/pip-TJO53q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:02:33 INFO - Running setup.py (path:/tmp/pip-TLah5r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:02:33 INFO - Running setup.py (path:/tmp/pip-po2ehF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:02:33 INFO - Running setup.py (path:/tmp/pip-HMvs7v-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:02:33 INFO - Running setup.py (path:/tmp/pip-uzeTNo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:02:33 INFO - Running setup.py (path:/tmp/pip-d2zyBC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:02:33 INFO - Running setup.py (path:/tmp/pip-WJDAas-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:02:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:02:33 INFO - Running setup.py (path:/tmp/pip-7r4hMM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:02:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:02:34 INFO - Running setup.py (path:/tmp/pip-rtN4s7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:02:34 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:02:34 INFO - Running setup.py (path:/tmp/pip-4JcRH_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:02:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:02:34 INFO - Running setup.py install for manifestparser 21:02:34 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 21:02:34 INFO - Running setup.py install for mozcrash 21:02:34 INFO - Running setup.py install for mozdebug 21:02:34 INFO - Running setup.py install for mozdevice 21:02:34 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 21:02:34 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 21:02:34 INFO - Running setup.py install for mozfile 21:02:34 INFO - Running setup.py install for mozhttpd 21:02:34 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 21:02:34 INFO - Running setup.py install for mozinfo 21:02:34 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 21:02:34 INFO - Running setup.py install for mozInstall 21:02:34 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 21:02:34 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 21:02:34 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 21:02:34 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 21:02:34 INFO - Running setup.py install for mozleak 21:02:35 INFO - Running setup.py install for mozlog 21:02:35 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 21:02:35 INFO - Running setup.py install for moznetwork 21:02:35 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 21:02:35 INFO - Running setup.py install for mozprocess 21:02:35 INFO - Running setup.py install for mozprofile 21:02:35 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 21:02:35 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 21:02:35 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 21:02:35 INFO - Running setup.py install for mozrunner 21:02:35 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 21:02:35 INFO - Running setup.py install for mozscreenshot 21:02:35 INFO - Running setup.py install for moztest 21:02:35 INFO - Running setup.py install for mozversion 21:02:35 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 21:02:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:02:35 INFO - Cleaning up... 21:02:35 INFO - Return code: 0 21:02:35 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:02:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:02:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:02:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:35 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 0x7f3942f34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1291630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1322840>, '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 0x128f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x128fb60>, '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-1452830569.679415-1410185024', '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 21:02:35 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 21:02:35 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 21:02:35 INFO - Using env: {'DISPLAY': ':0', 21:02:35 INFO - 'HOME': '/home/cltbld', 21:02:35 INFO - 'LANG': 'en_US.UTF-8', 21:02:35 INFO - 'LANGUAGE': 'en_US:en', 21:02:35 INFO - 'LOGNAME': 'cltbld', 21:02:35 INFO - 'MAIL': '/var/mail/cltbld', 21:02:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:02:35 INFO - 'MOZ_NO_REMOTE': '1', 21:02:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:02:35 INFO - 'NO_EM_RESTART': '1', 21:02:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:02:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:02:35 INFO - 'PWD': '/builds/slave/test-pgo', 21:02:35 INFO - 'SHELL': '/bin/bash', 21:02:35 INFO - 'SHLVL': '1', 21:02:35 INFO - 'TERM': 'linux', 21:02:35 INFO - 'TMOUT': '86400', 21:02:35 INFO - 'USER': 'cltbld', 21:02:35 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452830569.679415-1410185024', 21:02:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:02:35 INFO - '_': '/tools/buildbot/bin/python'} 21:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:02:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:02:36 INFO - Running setup.py (path:/tmp/pip-uMCyvj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:02:36 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)) 21:02:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:02:36 INFO - Running setup.py (path:/tmp/pip-guW6OL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:02:36 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)) 21:02:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:02:36 INFO - Running setup.py (path:/tmp/pip-ocAKMk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:02:36 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)) 21:02:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:02:36 INFO - Running setup.py (path:/tmp/pip-ZJ9QOw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:02:36 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)) 21:02:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:02:36 INFO - Running setup.py (path:/tmp/pip-YUBiAD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:02:36 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)) 21:02:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:02:36 INFO - Running setup.py (path:/tmp/pip-V3nXhg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:02:36 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)) 21:02:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:02:36 INFO - Running setup.py (path:/tmp/pip-pBMt7u-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:02:36 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)) 21:02:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:02:36 INFO - Running setup.py (path:/tmp/pip-uJ1yIg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:02:36 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)) 21:02:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:02:36 INFO - Running setup.py (path:/tmp/pip-FIBPUn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:02:36 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)) 21:02:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:02:36 INFO - Running setup.py (path:/tmp/pip-GfzSo4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:02:36 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)) 21:02:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:02:36 INFO - Running setup.py (path:/tmp/pip-D4qAH5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:02:36 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)) 21:02:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:02:36 INFO - Running setup.py (path:/tmp/pip-lXbjiL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:02:37 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)) 21:02:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:02:37 INFO - Running setup.py (path:/tmp/pip-K9pzpG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:02:37 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)) 21:02:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:02:37 INFO - Running setup.py (path:/tmp/pip-wgfZxq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:02:37 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)) 21:02:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:02:37 INFO - Running setup.py (path:/tmp/pip-l4xC27-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:02:37 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)) 21:02:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:02:37 INFO - Running setup.py (path:/tmp/pip-kQxqk3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:02:37 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)) 21:02:37 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:02:37 INFO - Running setup.py (path:/tmp/pip-vKMhsW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:02:37 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)) 21:02:37 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)) 21:02:37 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)) 21:02:37 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)) 21:02:37 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)) 21:02:37 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)) 21:02:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:02:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:02:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:02:39 INFO - Downloading blessings-1.5.1.tar.gz 21:02:39 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 21:02:39 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 21:02:39 INFO - Installing collected packages: blessings 21:02:39 INFO - Running setup.py install for blessings 21:02:39 INFO - Successfully installed blessings 21:02:39 INFO - Cleaning up... 21:02:39 INFO - Return code: 0 21:02:39 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 21:02:39 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:02:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:02:39 INFO - Reading from file tmpfile_stdout 21:02:39 INFO - Current package versions: 21:02:39 INFO - argparse == 1.2.1 21:02:39 INFO - blessings == 1.5.1 21:02:39 INFO - blobuploader == 1.2.4 21:02:39 INFO - docopt == 0.6.1 21:02:39 INFO - manifestparser == 1.1 21:02:39 INFO - mozInstall == 1.12 21:02:39 INFO - mozcrash == 0.16 21:02:39 INFO - mozdebug == 0.1 21:02:39 INFO - mozdevice == 0.48 21:02:39 INFO - mozfile == 1.2 21:02:39 INFO - mozhttpd == 0.7 21:02:39 INFO - mozinfo == 0.9 21:02:39 INFO - mozleak == 0.1 21:02:39 INFO - mozlog == 3.1 21:02:39 INFO - moznetwork == 0.27 21:02:39 INFO - mozprocess == 0.22 21:02:39 INFO - mozprofile == 0.28 21:02:39 INFO - mozrunner == 6.11 21:02:39 INFO - mozscreenshot == 0.1 21:02:39 INFO - mozsystemmonitor == 0.0 21:02:39 INFO - moztest == 0.7 21:02:39 INFO - mozversion == 1.4 21:02:39 INFO - psutil == 3.1.1 21:02:39 INFO - requests == 1.2.3 21:02:39 INFO - wsgiref == 0.1.2 21:02:39 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:39 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 0x7f3942f34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1291630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1322840>, '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 0x128f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x128fb60>, '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-1452830569.679415-1410185024', '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 21:02:39 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 21:02:39 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 21:02:39 INFO - Using env: {'DISPLAY': ':0', 21:02:39 INFO - 'HOME': '/home/cltbld', 21:02:39 INFO - 'LANG': 'en_US.UTF-8', 21:02:39 INFO - 'LANGUAGE': 'en_US:en', 21:02:39 INFO - 'LOGNAME': 'cltbld', 21:02:39 INFO - 'MAIL': '/var/mail/cltbld', 21:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:02:39 INFO - 'MOZ_NO_REMOTE': '1', 21:02:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:02:39 INFO - 'NO_EM_RESTART': '1', 21:02:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:02:39 INFO - 'PWD': '/builds/slave/test-pgo', 21:02:39 INFO - 'SHELL': '/bin/bash', 21:02:39 INFO - 'SHLVL': '1', 21:02:39 INFO - 'TERM': 'linux', 21:02:39 INFO - 'TMOUT': '86400', 21:02:39 INFO - 'USER': 'cltbld', 21:02:39 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452830569.679415-1410185024', 21:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:02:39 INFO - '_': '/tools/buildbot/bin/python'} 21:02:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:02:39 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)) 21:02:39 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)) 21:02:39 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)) 21:02:39 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)) 21:02:39 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)) 21:02:39 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)) 21:02:39 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)) 21:02:39 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)) 21:02:39 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)) 21:02:39 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)) 21:02:39 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)) 21:02:39 INFO - Cleaning up... 21:02:39 INFO - Return code: 0 21:02:39 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 21:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:39 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 0x7f3942f34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1291630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1322840>, '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 0x128f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x128fb60>, '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-1452830569.679415-1410185024', '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 21:02:39 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 21:02:39 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 21:02:39 INFO - Using env: {'DISPLAY': ':0', 21:02:39 INFO - 'HOME': '/home/cltbld', 21:02:39 INFO - 'LANG': 'en_US.UTF-8', 21:02:39 INFO - 'LANGUAGE': 'en_US:en', 21:02:39 INFO - 'LOGNAME': 'cltbld', 21:02:39 INFO - 'MAIL': '/var/mail/cltbld', 21:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:02:39 INFO - 'MOZ_NO_REMOTE': '1', 21:02:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:02:39 INFO - 'NO_EM_RESTART': '1', 21:02:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:02:39 INFO - 'PWD': '/builds/slave/test-pgo', 21:02:39 INFO - 'SHELL': '/bin/bash', 21:02:39 INFO - 'SHLVL': '1', 21:02:39 INFO - 'TERM': 'linux', 21:02:39 INFO - 'TMOUT': '86400', 21:02:39 INFO - 'USER': 'cltbld', 21:02:39 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452830569.679415-1410185024', 21:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:02:39 INFO - '_': '/tools/buildbot/bin/python'} 21:02:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:02:40 INFO - Downloading/unpacking jsonschema 21:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:02:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:02:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:02:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:02:41 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 21:02:41 INFO - Downloading/unpacking functools32 (from jsonschema) 21:02:41 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 21:02:41 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 21:02:41 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 21:02:41 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 21:02:41 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 21:02:41 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 21:02:41 INFO - Downloading functools32-3.2.3-2.tar.gz 21:02:41 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 21:02:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 21:02:41 INFO - warning: no files found matching '*.txt' 21:02:41 INFO - no previously-included directories found matching 'build' 21:02:41 INFO - no previously-included directories found matching 'dist' 21:02:41 INFO - no previously-included directories found matching '.git*' 21:02:41 INFO - Installing collected packages: jsonschema, functools32 21:02:42 INFO - Running setup.py install for functools32 21:02:42 INFO - warning: no files found matching '*.txt' 21:02:42 INFO - no previously-included directories found matching 'build' 21:02:42 INFO - no previously-included directories found matching 'dist' 21:02:42 INFO - no previously-included directories found matching '.git*' 21:02:42 INFO - Successfully installed jsonschema functools32 21:02:42 INFO - Cleaning up... 21:02:42 INFO - Return code: 0 21:02:42 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 21:02:42 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')]} 21:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:02:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:42 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')]} 21:02:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:02:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:02:42 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 0x7f3942f34f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11c18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1291630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1322840>, '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 0x128f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x128fb60>, '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-1452830569.679415-1410185024', '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 21:02:42 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 21:02:42 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 21:02:42 INFO - Using env: {'DISPLAY': ':0', 21:02:42 INFO - 'HOME': '/home/cltbld', 21:02:42 INFO - 'LANG': 'en_US.UTF-8', 21:02:42 INFO - 'LANGUAGE': 'en_US:en', 21:02:42 INFO - 'LOGNAME': 'cltbld', 21:02:42 INFO - 'MAIL': '/var/mail/cltbld', 21:02:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:02:42 INFO - 'MOZ_NO_REMOTE': '1', 21:02:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:02:42 INFO - 'NO_EM_RESTART': '1', 21:02:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:02:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:02:42 INFO - 'PWD': '/builds/slave/test-pgo', 21:02:42 INFO - 'SHELL': '/bin/bash', 21:02:42 INFO - 'SHLVL': '1', 21:02:42 INFO - 'TERM': 'linux', 21:02:42 INFO - 'TMOUT': '86400', 21:02:42 INFO - 'USER': 'cltbld', 21:02:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452830569.679415-1410185024', 21:02:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:02:42 INFO - '_': '/tools/buildbot/bin/python'} 21:02:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:02:42 INFO - Downloading/unpacking flake8 21:02:42 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 21:02:42 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 21:02:42 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 21:02:42 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 21:02:42 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 21:02:42 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 21:02:44 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:02:44 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 21:02:44 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:02:44 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 21:02:44 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 21:02:44 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 21:02:44 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 21:02:44 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 21:02:44 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 21:02:44 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:02:44 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 21:02:44 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:02:44 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 21:02:44 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 21:02:44 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 21:02:44 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 21:02:44 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 21:02:44 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 21:02:44 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 21:02:44 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:02:44 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 21:02:44 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 21:02:44 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 21:02:44 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 21:02:44 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 21:02:44 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 21:02:44 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 21:02:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 21:02:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:02:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:02:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:02:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:02:44 INFO - no previously-included directories found matching 'docs/_build' 21:02:44 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:02:44 INFO - Running setup.py install for pep8 21:02:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:02:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:02:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:02:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:02:44 INFO - no previously-included directories found matching 'docs/_build' 21:02:44 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 21:02:44 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:02:44 INFO - Cleaning up... 21:02:44 INFO - Return code: 0 21:02:44 INFO - Running post-action listener: _resource_record_post_action 21:02:44 INFO - Running post-action listener: _start_resource_monitoring 21:02:44 INFO - Starting resource monitoring. 21:02:44 INFO - ##### 21:02:44 INFO - ##### Running install step. 21:02:44 INFO - ##### 21:02:44 INFO - Running pre-action listener: _resource_record_pre_action 21:02:44 INFO - Running main action method: install 21:02:44 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:02:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:02:45 INFO - Reading from file tmpfile_stdout 21:02:45 INFO - Detecting whether we're running mozinstall >=1.0... 21:02:45 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 21:02:45 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 21:02:45 INFO - Reading from file tmpfile_stdout 21:02:45 INFO - Output received: 21:02:45 INFO - Usage: mozinstall [options] installer 21:02:45 INFO - Options: 21:02:45 INFO - -h, --help show this help message and exit 21:02:45 INFO - -d DEST, --destination=DEST 21:02:45 INFO - Directory to install application into. [default: 21:02:45 INFO - "/builds/slave/test-pgo"] 21:02:45 INFO - --app=APP Application being installed. [default: firefox] 21:02:45 INFO - mkdir: /builds/slave/test-pgo/build/application 21:02:45 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'] 21:02:45 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 21:03:01 INFO - Reading from file tmpfile_stdout 21:03:01 INFO - Output received: 21:03:01 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 21:03:01 INFO - Running post-action listener: _resource_record_post_action 21:03:01 INFO - ##### 21:03:01 INFO - ##### Running run-tests step. 21:03:01 INFO - ##### 21:03:01 INFO - Running pre-action listener: _resource_record_pre_action 21:03:01 INFO - Running main action method: run_tests 21:03:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 21:03:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 21:03:01 INFO - Python 2.7.3 21:03:01 INFO - Return code: 0 21:03:01 INFO - grabbing minidump binary from tooltool 21:03:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:03:01 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 0x1322840>, '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 0x128f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x128fb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 21:03:01 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 21:03:01 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 21:03:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:03:01 INFO - Return code: 0 21:03:01 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 21:03:01 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 21:03:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 21:03:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 21:03:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 21:03:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 21:03:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 21:03:02 INFO - Return code: 0 21:03:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IpknuwviQ9CPIDevMtHWnw/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 21:03:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IpknuwviQ9CPIDevMtHWnw/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 21:03:02 INFO - Using env: {'DISPLAY': ':0', 21:03:02 INFO - 'HOME': '/home/cltbld', 21:03:02 INFO - 'LANG': 'en_US.UTF-8', 21:03:02 INFO - 'LANGUAGE': 'en_US:en', 21:03:02 INFO - 'LOGNAME': 'cltbld', 21:03:02 INFO - 'MAIL': '/var/mail/cltbld', 21:03:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:03:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 21:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:03:02 INFO - 'MOZ_NO_REMOTE': '1', 21:03:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:03:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:03:02 INFO - 'NO_EM_RESTART': '1', 21:03:02 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:03:02 INFO - 'PWD': '/builds/slave/test-pgo', 21:03:02 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 21:03:02 INFO - 'SHELL': '/bin/bash', 21:03:02 INFO - 'SHLVL': '1', 21:03:02 INFO - 'TERM': 'linux', 21:03:02 INFO - 'TMOUT': '86400', 21:03:02 INFO - 'USER': 'cltbld', 21:03:02 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1452830569.679415-1410185024', 21:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:03:02 INFO - '_': '/tools/buildbot/bin/python'} 21:03:02 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IpknuwviQ9CPIDevMtHWnw/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 21:03:02 INFO - mozversion application_buildid: 20160114193043 21:03:02 INFO - mozversion application_changeset: 9a358be6fa798f24deecac1b502742b2c37cd6bd 21:03:02 INFO - mozversion application_display_name: Nightly 21:03:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:03:02 INFO - mozversion application_name: Firefox 21:03:02 INFO - mozversion application_remotingname: firefox 21:03:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 21:03:02 INFO - mozversion application_vendor: Mozilla 21:03:02 INFO - mozversion application_version: 46.0a1 21:03:02 INFO - mozversion platform_buildid: 20160114193043 21:03:02 INFO - mozversion platform_changeset: 9a358be6fa798f24deecac1b502742b2c37cd6bd 21:03:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 21:03:02 INFO - mozversion platform_version: 46.0a1 21:03:02 INFO - using testdate: 1452834182 21:03:02 INFO - actual date: 1452834182 21:03:02 INFO - starting webserver on 'localhost:39215' 21:03:02 INFO - SUITE-START | Running 3 tests 21:03:02 INFO - TEST-START | dromaeo_css 21:03:02 INFO - operating with platform_type : linux_ 21:03:02 INFO - Initialising browser for dromaeo_css test... 21:03:02 INFO - TEST-INFO | started process 9600 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplTuveQ/profile http://localhost:39215/getInfo.html) 21:03:06 INFO - PROCESS | 9600 | __metrics Screen width/height:1600/1200 21:03:06 INFO - PROCESS | 9600 | colorDepth:24 21:03:06 INFO - PROCESS | 9600 | Browser inner width/height: 1024/697 21:03:06 INFO - PROCESS | 9600 | __metrics 21:03:06 INFO - PROCESS | 9600 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 21:03:06 INFO - PROCESS | 9600 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:03:07 INFO - TEST-INFO | 9600: exit 0 21:03:07 INFO - Browser initialized. 21:03:07 INFO - Running cycle 1/1 for dromaeo_css test... 21:03:07 INFO - TEST-INFO | started process 9694 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplTuveQ/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:03:19 INFO - PROCESS | 9694 | RSS: Main: 148893696 21:03:19 INFO - PROCESS | 9694 | 21:04:10 INFO - PROCESS | 9694 | 1452834250970 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] 21:06:18 INFO - PROCESS | 9694 | Cycle 1(1): loaded http://localhost:39215/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39215/tests/dromaeo/cssquery-ext.html) 21:06:20 INFO - PROCESS | 9694 | RSS: Main: 244826112 21:06:20 INFO - PROCESS | 9694 | 21:09:22 INFO - PROCESS | 9694 | Cycle 1(1): loaded http://localhost:39215/tests/dromaeo/cssquery-ext.html (next: http://localhost:39215/tests/dromaeo/cssquery-jquery.html) 21:09:22 INFO - PROCESS | 9694 | RSS: Main: 227921920 21:09:22 INFO - PROCESS | 9694 | 21:12:24 INFO - PROCESS | 9694 | Cycle 1(1): loaded http://localhost:39215/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39215/tests/dromaeo/cssquery-mootools.html) 21:12:25 INFO - PROCESS | 9694 | RSS: Main: 230768640 21:12:25 INFO - PROCESS | 9694 | 21:15:27 INFO - PROCESS | 9694 | Cycle 1(1): loaded http://localhost:39215/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39215/tests/dromaeo/cssquery-prototype.html) 21:15:28 INFO - PROCESS | 9694 | RSS: Main: 211902464 21:15:28 INFO - PROCESS | 9694 | 21:18:30 INFO - PROCESS | 9694 | Cycle 1(1): loaded http://localhost:39215/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39215/tests/dromaeo/cssquery-yui.html) 21:18:31 INFO - PROCESS | 9694 | RSS: Main: 212193280 21:18:31 INFO - PROCESS | 9694 | 21:21:33 INFO - PROCESS | 9694 | Cycle 1(1): loaded http://localhost:39215/tests/dromaeo/cssquery-yui.html (next: http://localhost:39215/tests/dromaeo/cssquery-dojo.html) 21:21:34 INFO - PROCESS | 9694 | RSS: Main: 294289408 21:21:34 INFO - PROCESS | 9694 | 21:21:34 INFO - PROCESS | 9694 | __start_tp_report 21:21:34 INFO - PROCESS | 9694 | _x_x_mozilla_page_load 21:21:34 INFO - PROCESS | 9694 | _x_x_mozilla_page_load_details 21:21:34 INFO - PROCESS | 9694 | |i|pagename|runs| 21:21:34 INFO - PROCESS | 9694 | |0;dojo.html;3074.7693922955777;3080.6765289644572;3121.656617772046;3126.2965832687646;3144.3554071415356;4934.6299027572895;4969.428515720698;4973.104841128596;4997.075541024904;4997.45028046915;5038.823641172559;5057.165567681337;5057.721825299607;5060.190369540875;5076.593872490201;4553.362529245892;4588.380568623226;4622.745748983804;4659.720416774988;4671.532846715319;4640.489546149921;4652;4656;4677.74272415017;4698.130845793522;4466.33005049242;4547.886302842438;4576.519465456113;4620.353150558924;4644.117617652635;4530.864074077783;4539.863804085882;4547.204239258117;4555.430571178603;4587.839425620121;4507.4591049074;4516.525764794715;4526.615237704799;4554.2485489894125;4558.2251017327135;4766.52334766524;4831.879203019917;4859.1739404301;4872.585830204437;4883.12103821312;5288.418273989896;5326.094563924142;5329.173978033411;5329.173978033411;5341.332333458317;5329.626926115174;5344.198370244456;5376.220448035052;5380.112281473552;5459.12653975366;5045.117104506733;5047.49525047496;5049.520295571915;5050.116229659831;5055.721935293557;5471.753771080311;5479.589030822704;5479.616426850162;5525.4474552544425;5532.197831314517;5187.844364669066;5268.894622107613;5276.709780962007;5313.1764576490705;5355.544778693855;5319.494648008433;5382.67703937765;5384.353877534642;5390.9191362129595;5397.082495975694;5270.262163297141;5350.652207606493;5368.7047212403695;5370.03339398912;5406.91889621656;5308.708021058879;5356.946430535722;5412.161115027177;5435.619506634576;5474.671519708831;4236.4704411948505;4252.213340532012;4258.574142585779;4260.318349064135;4296.033392486665;3739.0652336915773;3809.5428548573796;3814.7520411173186;3826.1199924017633;3851.1334949636107;4860.562549370574;4921.212605983025;4926.433460152129;4927.162382395001;4954.702717836942;5304.814331498456;5373.194020896896;5376.381716102698;5377.677339359651;5426.077566813651;4943.8022479101155;4949;5010.1983682611335;5012.548870601663;5019.7239151847;4506.985928166137;4513.819447222074;4516.864494065183;4527.049319642846;4543.568361005699;5456.590755693339;5461.508464238317;5462.180672899018;5465.480779325958;5470.835874923759;5277;5335.839924802262;5336.359636843604;5356.035913535445;5416.241726158262;5556.833295001156;5569.33168019824;5572.164175373727;5588.077967135378;5592.356878958972;5310.123829568078;5331.946680533145;5334.279872217357;5341.172118321666;5387.730613469233;5288.682679039116;5313.256144139901;5361.142217245222;5387.488188622075;5394.757235924471;5306.363236411656;5306.97346513265;5308.946910530845;5311.973440132929;5317.962997215506;5288.709120998382;5306.097963346163;5312.760925758273;5317.962997215506;5321.201819726917;5032;5107.719075450737;5131.409887862944;5178.223266510054;5203.427622961395;5263.157894736823;5307.495787900143;5342.545883599938;5346.385165705994;5395.433479484597;5062.139436295765;5203.063448579292;5237.214417837203;5237.240600112886;5239.423663396947 21:21:34 INFO - PROCESS | 9694 | |1;ext.html;511.35824540202043;523.1420470428498;529.0635575032193;531.6119232959936;545.2475583694501;28266.717332826665;28552.571711424345;28699.569506457392;28702.851885924538;28717.42565148696;37185.814070929606;37659.43510847333;37732.43401348975;37824.43263351045;37834.81082594583;8507.659693612248;8677.435966662148;8681.826363472726;8682.696105636303;8689.826203475926;8336.874946875801;8454.957725211365;8462.23839854413;8468.957655211747;8469.534175620338;8498.19267169621;8584.613692383859;8586.78533036676;8595.140485951386;8597;8409.95795021024;8458.111898250656;8534.829303413897;8535.743927682179;8541.829163416696;8312.625931833081;8443.788905277417;8625.0943650917;8634.352423568194;8657.783555411102;39085.41371879424;39406.605933940584;39449.60550394488;39457.60542394568;39524.407133893015;3200.455922493182;3218.855151518164;3250.934981300361;3564.1267889367273;3582.229820588586;30408;30421.69578304211;30598.69401305981;30654.080377588707;30909.690903090905;3218.855151518187;3220.6940340667597;3249.366373557157;3322.9501557476656;3388.288459423524;266829;271440.999999998;271548;271863;272131;53591.19613205805;55135.72432137813;61105.388946110856;61292;62383.37616623867;55767;56050.71974640101;56328;56597.43402565922;56674.43325566774;15042.699146017238;15095.54713358601;15172.848271517143;15204.619884502976;15207.391704331923;11605.825912611317;11789.705257368634;12175.086868484723;12251.877481225252;12296.508139674492;19686.60626787485;20010.099545520487;20059.498512537302;20100.597988040157;20138.798612013987;7824.452288339761;8036.357091432671;8071.959640201761;8100.635471403801;8133.227343402367;857.5583574459158;859.9656013759504;902.3367824648877;949.7483166960761;1067.2529229539352;27832.582511262346;27981;28246.858765706453;28287.29281767972;28449.431011379656;1027.9074883260541;1045.3309881675734;1059.6344261229863;1082.6860210538855;1122.3714719756963;14448.927755361156;14832.555023349316;14888.851111488962;16142.838571614133;16767.91616041912;409550;416317;417260;417857;417936;54906;55354.16968745391;55376.723116384965;55411.44588554063;55422.72288638531;1324.7085641158737;1325.9668508287177;1328.023902431712;1329.7539955108336;1345.650130965956;28557.28606784847;29584.852075739484;29781.702182977893;29961.850190748908;30226.395472090873;44932.326015109145;45545.772271138434;45569.54430455786;45587.54412455833;45589.544104559856;279633;279901;281006;281481;282425;27927.441451170456;28607.427851442437;29840.850795746752;33426.49860252049;34236.14409639779;39184.21631567409;39781.40327895142;39909.60090399059;40198.19603607853;40347;34311.31377372389;34604.48093278653;34648.30703385969;38451.230975380895;39546.80226598849;32130.67869321277;33999.66000340064;36794.448083279305;38892;39448.802755986035;34393.48409773805;34644.133896652784;34717.13207169841;34969.125771855906;36168.81915590405;32128.518072228468;32902.34195316128;33855.661443386234;33863;35001.47497787586;23715.288541343787;23863.642045368986;23908.760912390655;23993.640095398932;24282.392940176636 21:21:34 INFO - PROCESS | 9694 | |2;jquery.html;350.32737144682216;351.6975401155007;354.48068579530997;355.05555223106523;356.68255252824974;13732.862671373283;14193.219372934485;15532.301046452909;16378.508644740647;18025.549361265974;14970.32633531491;15512.30194641241;15839.287232074556;16023.599410014756;16721.91639041806;5293.947060529403;5508.283923089993;5511.090670039459;5517.889642207154;5518.530924871371;4025.215083058805;4141.855035073773;5372.650777699437;5466.262054622614;5783.797567085132;5888.911666325009;5900.616459930112;5936.3173235077875;5940.168376427304;5940.316863560703;5111.1566591512355;5293.788248470057;5294.205869119624;5296.708681022562;5307.52232299095;4872.439669437989;5044.268581055726;5047.596192304607;5060.190369540893;5088.796448142107;12211;12322.568710095104;13024.674383140497;13045.739085218242;13049;1802;1968.4389948864505;2041.8672786268844;2084.687296905461;2151.4402058507612;9461.148496635336;9650.131488166098;11278.379689117095;12250.264984100982;12658;1784.732290156474;1785.874988750788;1841.484384372378;1859.0333026825974;1861.9945229576012;51228.231576526385;52947;54773;56999.14501282484;57829;9846.261530385249;10147.086762191439;10259.538320775433;10289.74275643115;10316.381017138996;13373.99390091494;13407.798883016763;13409.329533523285;13413.195208287534;13427.328633568477;7792.337651299589;7829.295363417206;7856.7250146244605;7876.881846772303;7942.166072562298;7615.619219039026;7632.274933881273;7680.423968202407;7697.576633285223;7699.153093159748;4408.801603927831;4427.1588398204885;4491.932621010622;4498.752568608693;4501.392312037833;1703.3867807589259;1754.2369069454828;1768.2750072470226;1827.8903265804095;1837.971453632089;865.3639574912435;891.2424439226605;911.5305617606938;957.4686049242598;1018.5060245780791;16127.193640317937;16506.917465412596;16774.66450671004;16917.407890723767;17029.40397086096;757.6174032113857;848.6181218451721;849.71948363201;888.8400087984096;895.6059333893066;8719.171678690514;8773.42972706772;8833.646654133763;8886.37795354319;8955.373123881396;157353;157862.2106889481;297789;538526.0736963137;547326;56302.43697562972;57781.13328300079;59980;67503.32496675069;68704;1078.0351585331084;1081.043276700126;1098.181854518389;1128.4414214963645;1132.5073592987078;12226.266424014588;14439.350229239499;14633.414663413345;14766.026345773162;15022.399104035716;22591.774082258966;28950.855245723636;29312.56031159577;29433.705662943095;30184.09447716572;40116.398254025626;40467.3929891046;40508.79745601253;40516.59483405246;44324;7061.435085193275;7164.9641751790905;7199.820004499929;7216.278372162742;7239.3484586385375;10387.532561034923;10424.478776061318;10493.47532623381;10749.408782517037;10752.139828813833;9428.717138485856;9458.196053335543;9684.903150968401;9699.951500242454;9746.122848943345;9645.324827262024;9696.5151742414;9788.951055244679;9814.55834487464;9961.40231586107;9984.750381240237;10053.145482633765;10091.444970526398;10106.343087699275;10347.482625868826;7459.925400745923;7621.161672215941;7634;7679.692812287446;7726.9227307726205;9372.34393592442;9447.244220462484;9634.470104144339;10033;10113.241506886865 21:21:34 INFO - PROCESS | 9694 | |3;mootools.html;1184.1237484261649;1472.5067102520654;1478.1796103162744;1627.4385337058718;1785.330501062101;6714.630695311762;7400.074990626172;7574.621268936545;7584.051993500812;7591.354734847545;7499.362554182887;7678;7690.807729806757;7704.8844267336035;7708.383329333654;2033.1460786469718;2086.082123865502;2271.522980174157;2324.047140672329;2350.2009316832273;2319.860808351496;2322.512272422785;2322.547103314854;2326.0695721711368;2328.208409140892;2226.298715904485;2289.828262880291;2292.5300313435705;2293.7935585797277;2296.2537175417974;2263.513344630904;2296.2537175417974;2308.376738280655;2328.9650655240102;2330.545543618978;2280.7605201453775;2281.7946384825277;2288.3249441414823;2297.4026753044163;2336.707911511061;7746.070471543395;7809.336206422461;7819.17898620648;7827.178146294671;7829;4708.270218116198;4785.425748910118;4799.472058073609;4832.444268909086;4836.22620380741;2858.1854171561213;2976.285691434062;3002.6096607441114;3016.502277124272;3020.5318175682582;913.7213149989251;923.676713150392;948.9810203795886;950.8526178442283;951.6383774165841;9768.804623907483;9876.308658393915;9882.80234395308;9898.257630677655;9898.455584942825;4078.5921407858978;4235.0682849773;4236.449261596034;4281.678874084456;4297.8495752648505;8840.823183536422;8896.466212027299;8902.777430564287;8907.465552066897;8920.152585504367;4695.7886895089805;4715.4577223619035;4727.7399743013775;4733.698232985957;4736.502667219961;4324.286492728727;4325.113351762881;4328.43730315057;4328.567143285646;4328.7402755834755;3426.1434641339665;3488.7906725596545;3687.465317528997;3716.2939041582013;3756.2111956489443;1139.6581025692426;1152.4295473740385;1153.7577108807245;1163.1973937982764;1207.7222238885106;1219.2745316536646;1243.3720970909633;1259.2633309513853;1290.4773566705312;1291.6383412644475;6831.385175334244;6896.931030689629;6926.930730692729;6927.757528486479;6964.686589103502;583.1456915618639;595.5027551994046;597.692188522911;628.1080865171467;630.1146888621494;5249.553787928069;5317;5329.866753331198;5332.733363331818;5338.332708411449;8584.184502472255;8801.295896328278;8931.062238464867;9004;9164.8625270621;8859.11408859109;8868.689595864114;8978.595963181673;9013.63945442188;9107.635694572144;6956.513044086966;7077.327653872874;7117.501774875813;7152.249013853574;7152.427805775629;7118.572885626879;7192.532485388433;7197.532160409557;7203.6758345875605;7212.567245965259;7816.88274675869;7997.520148791091;8158.469699469515;8306.584670766317;8323.916760832315;8464.703735369232;8483.321334293118;8610;8620.13798620133;8636.654533818577;6182.598131121463;6447.580907241015;6810.421114205348;6839.384455399038;6969.16370035599;6709.295523970108;7120.216776154515;7261.709531618676;7265.382442492236;7266.600336981517;6720.764773232913;6880.071190389233;6902.344277293649;6951.756688515878;7024.227334993045;6855.725770969106;6856.280090590416;6876.17485901695;6884.277150899084;6929.237783843672;6498.122753428416;6537.182852143482;6541.6402097885075;6551.770688025897;6687.264400915993;6201.627902325875;6270.49836013127;6281.780137695341;6288.402601752826;6290.119383286252;6242.313345531897;6288.496920246278;6290.182276304051;6294.402031806971;6295.842603934755 21:21:34 INFO - PROCESS | 9694 | |4;prototype.html;329.27559369387353;330.6709823725393;332.16791936199814;332.3568894189743;337.7551275325388;5688.260526131608;5878;5904.557158213135;5921.73352199151;5943.791967281146;5669.489745922866;5794.841031793637;5796.159556864252;5802.042662960617;5824.097264923933;4573.794179261941;4576.267797152456;4579.725216487005;4587.174308624463;4588.059447813207;4384.934225986613;4405.405270288502;4438.467383913935;4439.578240067189;4574.1080489304595;4403.933940990888;4424.867253982369;4480.6191473724775;4494.842680506183;4541.704789188709;4456.8885777855485;4475.820967161326;4491.932621010655;4503.932441013388;4535.705179163344;3986.3023970804993;3993.28120938231;4007.7996100194873;4017.3973903914352;4020.7386519876113;5471.699056551888;5602.719864006784;5612.214289999403;5658.943410565883;5666.603337766358;5193.454687257821;5196.92204616931;5198.402183748842;5206.088934436459;5207.973960130175;5920.970395147997;6187.535934804908;6191.94736894729;6200.3179650238435;6202.875942481125;4222.282212023963;4233.661307095425;4348.173846969097;4354.433923589913;4358.956410435919;6400.487960963112;6485.156929599122;6489.643069631168;6493.935060649427;6502.804915852532;4420.447444069491;4428;4429.202743506135;4429.357743127229;4429.867103986886;4351.238533256667;4481.081378317437;4486.865394038184;4490.146872094291;4534.387857639243;5215.973920130375;5216.426193118754;5220.634555581149;5221;5233.1365324721755;6229.314775374705;6241.438270555581;6241.531885108635;6241.656708881055;6244.469220116341;1563.0777841073593;1592.2118551316942;1592.5461243545765;1592.8646065084597;1592.9044257344597;1043.8121138195197;1061.713337398898;1067.5996501312009;1086.5653738504504;1090.929089609173;5921.792737254175;5925.200097986716;5929.466348028698;5934.347221805598;5939.613925094855;6385.36146385367;6390.392912673382;6391.872162556723;6393.904091438539;6394.552381333355;5893.6463812171405;5915.3788852170765;5923.17075609415;5925.970370148121;5930.406959304121;3376.2572234108948;3482.3035392921515;3485.6514348564942;3487.47687846825;3494.947575786365;6765.255821859591;6785.9321406786285;6791.626460544619;6792.490563207779;6796.388325050769;6636.933630663728;6638.6680665966505;6644.036614690843;6647.700853461606;6647.933520664828;6593.406593406617;6782.728690852409;6789.25318214996;6790.898136527956;6861.862762744817;6221.844453888689;6385.169927909342;6469.5794773339585;6473.579217350857;6474.158359413246;6270.7805226816845;6394.1687580614225;6413.679316034087;6417.326180751141;6430.678466076771;6754;6756.797296081126;6760.729570817112;6769.4922880785125;6776;4254.468191476066;4444.711093778894;4481.574250446202;4481.7535035573355;4484.551544845489;6356;6406.615603063645;6413.583117097373;6414.454771344302;6414.7434102637235;6217.440430361289;6249.468795152281;6259.6870156492905;6268.435840774352;6272.027835685476;6105.328413874381;6175.505959523138;6207.906881396873;6225.3774622537385;6240.188775459162;6129.693515324127;6165.599236049642;6169.259688837368;6173;6176.320604733386;6309.1167236586;6309.558330916791;6310.873782524415;6311.27420346666;6313.05304204373;6094.481969032682;6096.695165241631;6159.322474527888;6167.105769663284;6174.536909731878 21:21:34 INFO - PROCESS | 9694 | |5;yui.html;740.6370878269648;829.8132920092976;830.3647709502231;846.4709556527171;876.0276093536173;13043.73912521749;13190.934045329772;13193;13231.272280024596;13316.334183290845;12460.626181214555;12583.685407864785;12633.936830315859;12714.745705085916;12944.158629689064;802.8153524689325;824.6536454689015;826.0376661189707;826.921442462965;827.2017503109498;818.8648872935959;819.8401311744212;819.9467034642729;820.4339006085781;821.0639870547581;864.3690106222462;865.7965378136133;865.8787769712245;866.6143566113083;870.708312715238;857.9785505362385;858.2833334165979;858.8668756342777;859.0636206534875;864;631.9526035547307;631.9842003949892;631.9951277468847;634.8476365672252;635.028406537993;11267;11323.603673871457;11347.886521134766;11348.546058157664;11360.772784544262;10834.079103276232;10953.068989135932;10965.179293206542;11050.392228427434;11115.666530004113;8982.146696063863;9065.81868362629;9176.449413035172;9240.491772952484;9632.662856799978;3364.3775901458307;3372.3423932333203;3402.7788193767324;3403.1151900505797;3417.6675361255166;46177.769111154565;47006.5299347009;47013;47036.5296347039;47087.29369059467;8237.840209835524;8329.208725171098;8521.914780852236;8547.316214702809;8583.270422014073;16725.247363868653;16740.832591673927;16781.83218167827;16850.57873553171;16887.662246754997;6890.44876409886;6910.378065974087;7155.534890232118;7390.5196162249285;7550.811229719301;3816.1795214029116;3840.5967373425947;3851.1334949636107;3870.3420418528917;3908.0620651043546;3034.1656044587476;3060.7245347918347;3064.402441523926;3067.4171907337536;3071.0479751277176;527.6543863769238;531.7288183026684;536.6833568194784;552.9087700529368;555.5222508642564;450.8985478267364;563.3071322273627;587.483014946844;633.3824521091991;690.4579904774828;7584.203658615766;7877.448578599443;8170.468919520331;8261.917380826235;8369.916300837036;542.4792199488456;573.6959411511905;622.7166639179162;659.8350412396901;692.882210024287;3609.4766258892314;3641.471986561987;3689.612590677994;3766.6798322142927;3799.4490798834013;56016;59216.40783592109;60392.39607603956;60454.09318860221;61173.69413152906;45790.77104614455;46264.30603540949;51611;51985.7400712994;53308.466915331126;45435.77282113568;45965.85085372892;46188.7690561545;47981.52018479771;48165.5183448168;7932.206779322021;8359.540225287425;8608.48349099056;8677.392582519416;8698.47809131454;32346.83826580852;32555;33004.33991320208;35133;35307.64692353044;39886.202275953736;40069.398959016224;40198.39702404525;40386.79806600948;43193.784031079646;10332.690019299433;11625.418729063344;11688;11715.941420292844;12119.272843629411;12278.570250041206;12655.430505627453;12820.358982051046;12991.35043247815;13038.739225215253;11150.888491114985;11519.53921843117;11540.769184616092;11694.239874408137;11961.282323060645;10740.892591073989;11133.944330278297;11490.310581365145;11607.535698571963;11608.6517404478;10936.343819370863;11346.149038822286;11360.829587556358;11536.653900383002;11742.295462272292;9501.952490237505;9632.422054676741;9656.517174141125;9797.85303220438;9805.2646051545;8420.705275315335;8922.910770892468;9119.544022798966;9243.76890577741;9797.461139637104 21:21:34 INFO - PROCESS | 9694 | __end_tp_report 21:21:34 INFO - PROCESS | 9694 | __start_cc_report 21:21:34 INFO - PROCESS | 9694 | _x_x_mozilla_cycle_collect,829 21:21:34 INFO - PROCESS | 9694 | __end_cc_report 21:21:34 INFO - PROCESS | 9694 | __startTimestamp1452835294005__endTimestamp 21:21:34 INFO - PROCESS | 9694 | 21:21:34 INFO - PROCESS | 9694 | ------- Summary: start ------- 21:21:34 INFO - PROCESS | 9694 | Number of tests: 6 21:21:34 INFO - PROCESS | 9694 | 21:21:34 INFO - PROCESS | 9694 | [#0] dojo.html Cycles:165 Average:4993.84 Median:5203.43 stddev:526.86 (10.1%) stddev-sans-first:506.51 21:21:34 INFO - PROCESS | 9694 | Values: 3074.8 3080.7 3121.7 3126.3 3144.4 4934.6 4969.4 4973.1 4997.1 4997.5 5038.8 5057.2 5057.7 5060.2 5076.6 4553.4 4588.4 4622.7 4659.7 4671.5 4640.5 4652.0 4656.0 4677.7 4698.1 4466.3 4547.9 4576.5 4620.4 4644.1 4530.9 4539.9 4547.2 4555.4 4587.8 4507.5 4516.5 4526.6 4554.2 4558.2 4766.5 4831.9 4859.2 4872.6 4883.1 5288.4 5326.1 5329.2 5329.2 5341.3 5329.6 5344.2 5376.2 5380.1 5459.1 5045.1 5047.5 5049.5 5050.1 5055.7 5471.8 5479.6 5479.6 5525.4 5532.2 5187.8 5268.9 5276.7 5313.2 5355.5 5319.5 5382.7 5384.4 5390.9 5397.1 5270.3 5350.7 5368.7 5370.0 5406.9 5308.7 5356.9 5412.2 5435.6 5474.7 4236.5 4252.2 4258.6 4260.3 4296.0 3739.1 3809.5 3814.8 3826.1 3851.1 4860.6 4921.2 4926.4 4927.2 4954.7 5304.8 5373.2 5376.4 5377.7 5426.1 4943.8 4949.0 5010.2 5012.5 5019.7 4507.0 4513.8 4516.9 4527.0 4543.6 5456.6 5461.5 5462.2 5465.5 5470.8 5277.0 5335.8 5336.4 5356.0 5416.2 5556.8 5569.3 5572.2 5588.1 5592.4 5310.1 5331.9 5334.3 5341.2 5387.7 5288.7 5313.3 5361.1 5387.5 5394.8 5306.4 5307.0 5308.9 5312.0 5318.0 5288.7 5306.1 5312.8 5318.0 5321.2 5032.0 5107.7 5131.4 5178.2 5203.4 5263.2 5307.5 5342.5 5346.4 5395.4 5062.1 5203.1 5237.2 5237.2 5239.4 21:21:34 INFO - PROCESS | 9694 | 21:21:34 INFO - PROCESS | 9694 | [#1] ext.html Cycles:180 Average:48241.74 Median:34843.13 stddev:86690.80 (248.8%) stddev-sans-first:86859.92 21:21:34 INFO - PROCESS | 9694 | Values: 511.4 523.1 529.1 531.6 545.2 28266.7 28552.6 28699.6 28702.9 28717.4 37185.8 37659.4 37732.4 37824.4 37834.8 8507.7 8677.4 8681.8 8682.7 8689.8 8336.9 8455.0 8462.2 8469.0 8469.5 8498.2 8584.6 8586.8 8595.1 8597.0 8410.0 8458.1 8534.8 8535.7 8541.8 8312.6 8443.8 8625.1 8634.4 8657.8 39085.4 39406.6 39449.6 39457.6 39524.4 3200.5 3218.9 3250.9 3564.1 3582.2 30408.0 30421.7 30598.7 30654.1 30909.7 3218.9 3220.7 3249.4 3323.0 3388.3 266829.0 271441.0 271548.0 271863.0 272131.0 53591.2 55135.7 61105.4 61292.0 62383.4 55767.0 56050.7 56328.0 56597.4 56674.4 15042.7 15095.5 15172.8 15204.6 15207.4 11605.8 11789.7 12175.1 12251.9 12296.5 19686.6 20010.1 20059.5 20100.6 20138.8 7824.5 8036.4 8072.0 8100.6 8133.2 857.6 860.0 902.3 949.7 1067.3 27832.6 27981.0 28246.9 28287.3 28449.4 1027.9 1045.3 1059.6 1082.7 1122.4 14448.9 14832.6 14888.9 16142.8 16767.9 409550.0 416317.0 417260.0 417857.0 417936.0 54906.0 55354.2 55376.7 55411.4 55422.7 1324.7 1326.0 1328.0 1329.8 1345.7 28557.3 29584.9 29781.7 29961.9 30226.4 44932.3 45545.8 45569.5 45587.5 45589.5 279633.0 279901.0 281006.0 281481.0 282425.0 27927.4 28607.4 29840.9 33426.5 34236.1 39184.2 39781.4 39909.6 40198.2 40347.0 34311.3 34604.5 34648.3 38451.2 39546.8 32130.7 33999.7 36794.4 38892.0 39448.8 34393.5 34644.1 34717.1 34969.1 36168.8 32128.5 32902.3 33855.7 33863.0 35001.5 23715.3 23863.6 23908.8 23993.6 24282.4 21:21:34 INFO - PROCESS | 9694 | 21:21:34 INFO - PROCESS | 9694 | [#2] jquery.html Cycles:180 Average:21233.16 Median:24407.97 stddev:62815.72 (257.4%) stddev-sans-first:62972.37 21:21:34 INFO - PROCESS | 9694 | Values: 350.3 351.7 354.5 355.1 356.7 13732.9 14193.2 15532.3 16378.5 18025.5 14970.3 15512.3 15839.3 16023.6 16721.9 5293.9 5508.3 5511.1 5517.9 5518.5 4025.2 4141.9 5372.7 5466.3 5783.8 5888.9 5900.6 5936.3 5940.2 5940.3 5111.2 5293.8 5294.2 5296.7 5307.5 4872.4 5044.3 5047.6 5060.2 5088.8 12211.0 12322.6 13024.7 13045.7 13049.0 1802.0 1968.4 2041.9 2084.7 2151.4 9461.1 9650.1 11278.4 12250.3 12658.0 1784.7 1785.9 1841.5 1859.0 1862.0 51228.2 52947.0 54773.0 56999.1 57829.0 9846.3 10147.1 10259.5 10289.7 10316.4 13374.0 13407.8 13409.3 13413.2 13427.3 7792.3 7829.3 7856.7 7876.9 7942.2 7615.6 7632.3 7680.4 7697.6 7699.2 4408.8 4427.2 4491.9 4498.8 4501.4 1703.4 1754.2 1768.3 1827.9 1838.0 865.4 891.2 911.5 957.5 1018.5 16127.2 16506.9 16774.7 16917.4 17029.4 757.6 848.6 849.7 888.8 895.6 8719.2 8773.4 8833.6 8886.4 8955.4 157353.0 157862.2 297789.0 538526.1 547326.0 56302.4 57781.1 59980.0 67503.3 68704.0 1078.0 1081.0 1098.2 1128.4 1132.5 12226.3 14439.4 14633.4 14766.0 15022.4 22591.8 28950.9 29312.6 29433.7 30184.1 40116.4 40467.4 40508.8 40516.6 44324.0 7061.4 7165.0 7199.8 7216.3 7239.3 10387.5 10424.5 10493.5 10749.4 10752.1 9428.7 9458.2 9684.9 9700.0 9746.1 9645.3 9696.5 9789.0 9814.6 9961.4 9984.8 10053.1 10091.4 10106.3 10347.5 7459.9 7621.2 7634.0 7679.7 7726.9 9372.3 9447.2 9634.5 10033.0 10113.2 21:21:34 INFO - PROCESS | 9694 | 21:21:34 INFO - PROCESS | 9694 | [#3] mootools.html Cycles:180 Average:5301.56 Median:3538.85 stddev:2713.73 (76.7%) stddev-sans-first:2703.69 21:21:34 INFO - PROCESS | 9694 | Values: 1184.1 1472.5 1478.2 1627.4 1785.3 6714.6 7400.1 7574.6 7584.1 7591.4 7499.4 7678.0 7690.8 7704.9 7708.4 2033.1 2086.1 2271.5 2324.0 2350.2 2319.9 2322.5 2322.5 2326.1 2328.2 2226.3 2289.8 2292.5 2293.8 2296.3 2263.5 2296.3 2308.4 2329.0 2330.5 2280.8 2281.8 2288.3 2297.4 2336.7 7746.1 7809.3 7819.2 7827.2 7829.0 4708.3 4785.4 4799.5 4832.4 4836.2 2858.2 2976.3 3002.6 3016.5 3020.5 913.7 923.7 949.0 950.9 951.6 9768.8 9876.3 9882.8 9898.3 9898.5 4078.6 4235.1 4236.4 4281.7 4297.8 8840.8 8896.5 8902.8 8907.5 8920.2 4695.8 4715.5 4727.7 4733.7 4736.5 4324.3 4325.1 4328.4 4328.6 4328.7 3426.1 3488.8 3687.5 3716.3 3756.2 1139.7 1152.4 1153.8 1163.2 1207.7 1219.3 1243.4 1259.3 1290.5 1291.6 6831.4 6896.9 6926.9 6927.8 6964.7 583.1 595.5 597.7 628.1 630.1 5249.6 5317.0 5329.9 5332.7 5338.3 8584.2 8801.3 8931.1 9004.0 9164.9 8859.1 8868.7 8978.6 9013.6 9107.6 6956.5 7077.3 7117.5 7152.2 7152.4 7118.6 7192.5 7197.5 7203.7 7212.6 7816.9 7997.5 8158.5 8306.6 8323.9 8464.7 8483.3 8610.0 8620.1 8636.7 6182.6 6447.6 6810.4 6839.4 6969.2 6709.3 7120.2 7261.7 7265.4 7266.6 6720.8 6880.1 6902.3 6951.8 7024.2 6855.7 6856.3 6876.2 6884.3 6929.2 6498.1 6537.2 6541.6 6551.8 6687.3 6201.6 6270.5 6281.8 6288.4 6290.1 6242.3 6288.5 6290.2 6294.4 6295.8 21:21:34 INFO - PROCESS | 9694 | 21:21:34 INFO - PROCESS | 9694 | [#4] prototype.html Cycles:180 Average:5223.53 Median:5921.35 stddev:1574.35 (26.6%) stddev-sans-first:1535.31 21:21:34 INFO - PROCESS | 9694 | Values: 329.3 330.7 332.2 332.4 337.8 5688.3 5878.0 5904.6 5921.7 5943.8 5669.5 5794.8 5796.2 5802.0 5824.1 4573.8 4576.3 4579.7 4587.2 4588.1 4384.9 4405.4 4438.5 4439.6 4574.1 4403.9 4424.9 4480.6 4494.8 4541.7 4456.9 4475.8 4491.9 4503.9 4535.7 3986.3 3993.3 4007.8 4017.4 4020.7 5471.7 5602.7 5612.2 5658.9 5666.6 5193.5 5196.9 5198.4 5206.1 5208.0 5921.0 6187.5 6191.9 6200.3 6202.9 4222.3 4233.7 4348.2 4354.4 4359.0 6400.5 6485.2 6489.6 6493.9 6502.8 4420.4 4428.0 4429.2 4429.4 4429.9 4351.2 4481.1 4486.9 4490.1 4534.4 5216.0 5216.4 5220.6 5221.0 5233.1 6229.3 6241.4 6241.5 6241.7 6244.5 1563.1 1592.2 1592.5 1592.9 1592.9 1043.8 1061.7 1067.6 1086.6 1090.9 5921.8 5925.2 5929.5 5934.3 5939.6 6385.4 6390.4 6391.9 6393.9 6394.6 5893.6 5915.4 5923.2 5926.0 5930.4 3376.3 3482.3 3485.7 3487.5 3494.9 6765.3 6785.9 6791.6 6792.5 6796.4 6636.9 6638.7 6644.0 6647.7 6647.9 6593.4 6782.7 6789.3 6790.9 6861.9 6221.8 6385.2 6469.6 6473.6 6474.2 6270.8 6394.2 6413.7 6417.3 6430.7 6754.0 6756.8 6760.7 6769.5 6776.0 4254.5 4444.7 4481.6 4481.8 4484.6 6356.0 6406.6 6413.6 6414.5 6414.7 6217.4 6249.5 6259.7 6268.4 6272.0 6105.3 6175.5 6207.9 6225.4 6240.2 6129.7 6165.6 6169.3 6173.0 6176.3 6309.1 6309.6 6310.9 6311.3 6313.1 6094.5 6096.7 6159.3 6167.1 6174.5 21:21:34 INFO - PROCESS | 9694 | 21:21:34 INFO - PROCESS | 9694 | [#5] yui.html Cycles:180 Average:13374.49 Median:47534.41 stddev:15763.44 (33.2%) stddev-sans-first:15779.11 21:21:34 INFO - PROCESS | 9694 | Values: 740.6 829.8 830.4 846.5 876.0 13043.7 13190.9 13193.0 13231.3 13316.3 12460.6 12583.7 12633.9 12714.7 12944.2 802.8 824.7 826.0 826.9 827.2 818.9 819.8 819.9 820.4 821.1 864.4 865.8 865.9 866.6 870.7 858.0 858.3 858.9 859.1 864.0 632.0 632.0 632.0 634.8 635.0 11267.0 11323.6 11347.9 11348.5 11360.8 10834.1 10953.1 10965.2 11050.4 11115.7 8982.1 9065.8 9176.4 9240.5 9632.7 3364.4 3372.3 3402.8 3403.1 3417.7 46177.8 47006.5 47013.0 47036.5 47087.3 8237.8 8329.2 8521.9 8547.3 8583.3 16725.2 16740.8 16781.8 16850.6 16887.7 6890.4 6910.4 7155.5 7390.5 7550.8 3816.2 3840.6 3851.1 3870.3 3908.1 3034.2 3060.7 3064.4 3067.4 3071.0 527.7 531.7 536.7 552.9 555.5 450.9 563.3 587.5 633.4 690.5 7584.2 7877.4 8170.5 8261.9 8369.9 542.5 573.7 622.7 659.8 692.9 3609.5 3641.5 3689.6 3766.7 3799.4 56016.0 59216.4 60392.4 60454.1 61173.7 45790.8 46264.3 51611.0 51985.7 53308.5 45435.8 45965.9 46188.8 47981.5 48165.5 7932.2 8359.5 8608.5 8677.4 8698.5 32346.8 32555.0 33004.3 35133.0 35307.6 39886.2 40069.4 40198.4 40386.8 43193.8 10332.7 11625.4 11688.0 11715.9 12119.3 12278.6 12655.4 12820.4 12991.4 13038.7 11150.9 11519.5 11540.8 11694.2 11961.3 10740.9 11133.9 11490.3 11607.5 11608.7 10936.3 11346.1 11360.8 11536.7 11742.3 9502.0 9632.4 9656.5 9797.9 9805.3 8420.7 8922.9 9119.5 9243.8 9797.5 21:21:34 INFO - PROCESS | 9694 | -------- Summary: end -------- 21:21:34 INFO - PROCESS | 9694 | 21:21:34 INFO - TEST-INFO | 9694: exit 0 21:21:34 INFO - TEST-OK | dromaeo_css | took 1112125ms 21:21:34 INFO - TEST-START | kraken 21:21:34 INFO - operating with platform_type : linux_ 21:21:34 INFO - Initialising browser for kraken test... 21:21:34 INFO - TEST-INFO | started process 9889 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmLxdCS/profile http://localhost:39215/getInfo.html) 21:21:38 INFO - PROCESS | 9889 | __metrics Screen width/height:1600/1200 21:21:38 INFO - PROCESS | 9889 | colorDepth:24 21:21:38 INFO - PROCESS | 9889 | Browser inner width/height: 1024/697 21:21:38 INFO - PROCESS | 9889 | __metrics 21:21:38 INFO - PROCESS | 9889 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 21:21:38 INFO - PROCESS | 9889 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:21:39 INFO - TEST-INFO | 9889: exit 0 21:21:39 INFO - Browser initialized. 21:21:39 INFO - Running cycle 1/1 for kraken test... 21:21:39 INFO - TEST-INFO | started process 9972 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmLxdCS/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:21:51 INFO - PROCESS | 9972 | RSS: Main: 149413888 21:21:51 INFO - PROCESS | 9972 | 21:21:53 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39215/tests/kraken/driver.html?testName=audio-beat-detection) 21:21:54 INFO - PROCESS | 9972 | RSS: Main: 185786368 21:21:54 INFO - PROCESS | 9972 | 21:21:56 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39215/tests/kraken/driver.html?testName=audio-dft) 21:21:57 INFO - PROCESS | 9972 | RSS: Main: 177229824 21:21:57 INFO - PROCESS | 9972 | 21:22:00 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39215/tests/kraken/driver.html?testName=audio-fft) 21:22:01 INFO - PROCESS | 9972 | RSS: Main: 162041856 21:22:01 INFO - PROCESS | 9972 | 21:22:03 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39215/tests/kraken/driver.html?testName=audio-oscillator) 21:22:03 INFO - PROCESS | 9972 | RSS: Main: 162947072 21:22:03 INFO - PROCESS | 9972 | 21:22:05 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39215/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:22:06 INFO - PROCESS | 9972 | RSS: Main: 166588416 21:22:06 INFO - PROCESS | 9972 | 21:22:09 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39215/tests/kraken/driver.html?testName=imaging-darkroom) 21:22:09 INFO - PROCESS | 9972 | RSS: Main: 165265408 21:22:09 INFO - PROCESS | 9972 | 21:22:13 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39215/tests/kraken/driver.html?testName=imaging-desaturate) 21:22:13 INFO - PROCESS | 9972 | RSS: Main: 165593088 21:22:13 INFO - PROCESS | 9972 | 21:22:16 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39215/tests/kraken/driver.html?testName=json-parse-financial) 21:22:17 INFO - PROCESS | 9972 | RSS: Main: 166645760 21:22:17 INFO - PROCESS | 9972 | 21:22:18 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39215/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:22:19 INFO - PROCESS | 9972 | RSS: Main: 176361472 21:22:19 INFO - PROCESS | 9972 | 21:22:21 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39215/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:22:21 INFO - PROCESS | 9972 | RSS: Main: 173395968 21:22:21 INFO - PROCESS | 9972 | 21:22:23 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39215/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:22:24 INFO - PROCESS | 9972 | RSS: Main: 179929088 21:22:24 INFO - PROCESS | 9972 | 21:22:26 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39215/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:22:27 INFO - PROCESS | 9972 | RSS: Main: 188530688 21:22:27 INFO - PROCESS | 9972 | 21:22:29 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39215/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:22:30 INFO - PROCESS | 9972 | RSS: Main: 184287232 21:22:30 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | Cycle 1(1): loaded http://localhost:39215/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39215/tests/kraken/driver.html?testName=ai-astar) 21:22:32 INFO - PROCESS | 9972 | RSS: Main: 242401280 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | __start_tp_report 21:22:32 INFO - PROCESS | 9972 | _x_x_mozilla_page_load 21:22:32 INFO - PROCESS | 9972 | _x_x_mozilla_page_load_details 21:22:32 INFO - PROCESS | 9972 | |i|pagename|runs| 21:22:32 INFO - PROCESS | 9972 | |0;ai-astar;109;110;104;111;111;111;102;108;110;110 21:22:32 INFO - PROCESS | 9972 | |1;audio-beat-detection;150;124;151;166;143;147;161;128;173;123 21:22:32 INFO - PROCESS | 9972 | |2;audio-dft;161;160;162;162;159;161;159;163;163;161 21:22:32 INFO - PROCESS | 9972 | |3;audio-fft;71;72;124;102;71;71;73;120;94;70 21:22:32 INFO - PROCESS | 9972 | |4;audio-oscillator;97;96;142;96;95;95;141;95;97;138 21:22:32 INFO - PROCESS | 9972 | |5;imaging-gaussian-blur;109;109;109;109;109;109;109;109;110;109 21:22:32 INFO - PROCESS | 9972 | |6;imaging-darkroom;145;146;145;145;146;146;145;145;147;146 21:22:32 INFO - PROCESS | 9972 | |7;imaging-desaturate;96;96;96;96;96;96;96;95;95;96 21:22:32 INFO - PROCESS | 9972 | |8;json-parse-financial;56;56;56;56;56;56;55;65;54;55 21:22:32 INFO - PROCESS | 9972 | |9;json-stringify-tinderbox;66;67;67;67;66;67;67;67;67;68 21:22:32 INFO - PROCESS | 9972 | |10;stanford-crypto-aes;84;82;82;82;83;96;83;82;83;82 21:22:32 INFO - PROCESS | 9972 | |11;stanford-crypto-ccm;123;119;117;122;128;116;127;130;117;114 21:22:32 INFO - PROCESS | 9972 | |12;stanford-crypto-pbkdf2;150;139;149;149;148;142;149;152;147;157 21:22:32 INFO - PROCESS | 9972 | |13;stanford-crypto-sha256-iterative;71;70;68;67;69;70;69;70;68;66 21:22:32 INFO - PROCESS | 9972 | __end_tp_report 21:22:32 INFO - PROCESS | 9972 | __start_cc_report 21:22:32 INFO - PROCESS | 9972 | _x_x_mozilla_cycle_collect,976 21:22:32 INFO - PROCESS | 9972 | __end_cc_report 21:22:32 INFO - PROCESS | 9972 | __startTimestamp1452835352413__endTimestamp 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | ------- Summary: start ------- 21:22:32 INFO - PROCESS | 9972 | Number of tests: 14 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#0] ai-astar Cycles:10 Average:108.60 Median:110.00 stddev:3.13 (2.8%) stddev-sans-first:3.32 21:22:32 INFO - PROCESS | 9972 | Values: 109.0 110.0 104.0 111.0 111.0 111.0 102.0 108.0 110.0 110.0 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#1] audio-beat-detection Cycles:10 Average:146.60 Median:150.50 stddev:17.44 (11.6%) stddev-sans-first:18.46 21:22:32 INFO - PROCESS | 9972 | Values: 150.0 124.0 151.0 166.0 143.0 147.0 161.0 128.0 173.0 123.0 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:1.45 (0.9%) stddev-sans-first:1.54 21:22:32 INFO - PROCESS | 9972 | Values: 161.0 160.0 162.0 162.0 159.0 161.0 159.0 163.0 163.0 161.0 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#3] audio-fft Cycles:10 Average:86.80 Median:71.00 stddev:21.63 (30.5%) stddev-sans-first:22.17 21:22:32 INFO - PROCESS | 9972 | Values: 71.0 72.0 124.0 102.0 71.0 71.0 73.0 120.0 94.0 70.0 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#4] audio-oscillator Cycles:10 Average:109.20 Median:95.50 stddev:21.52 (22.5%) stddev-sans-first:22.37 21:22:32 INFO - PROCESS | 9972 | Values: 97.0 96.0 142.0 96.0 95.0 95.0 141.0 95.0 97.0 138.0 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 21:22:32 INFO - PROCESS | 9972 | Values: 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 110.0 109.0 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 21:22:32 INFO - PROCESS | 9972 | Values: 145.0 146.0 145.0 145.0 146.0 146.0 145.0 145.0 147.0 146.0 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 21:22:32 INFO - PROCESS | 9972 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 95.0 96.0 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#8] json-parse-financial Cycles:10 Average:56.50 Median:56.00 stddev:3.06 (5.5%) stddev-sans-first:3.24 21:22:32 INFO - PROCESS | 9972 | Values: 56.0 56.0 56.0 56.0 56.0 56.0 55.0 65.0 54.0 55.0 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:67.00 stddev:0.57 (0.8%) stddev-sans-first:0.50 21:22:32 INFO - PROCESS | 9972 | Values: 66.0 67.0 67.0 67.0 66.0 67.0 67.0 67.0 67.0 68.0 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:83.00 stddev:4.31 (5.2%) stddev-sans-first:4.57 21:22:32 INFO - PROCESS | 9972 | Values: 84.0 82.0 82.0 82.0 83.0 96.0 83.0 82.0 83.0 82.0 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#11] stanford-crypto-ccm Cycles:10 Average:121.30 Median:122.50 stddev:5.58 (4.6%) stddev-sans-first:5.88 21:22:32 INFO - PROCESS | 9972 | Values: 123.0 119.0 117.0 122.0 128.0 116.0 127.0 130.0 117.0 114.0 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.20 Median:149.00 stddev:4.96 (3.3%) stddev-sans-first:5.22 21:22:32 INFO - PROCESS | 9972 | Values: 150.0 139.0 149.0 149.0 148.0 142.0 149.0 152.0 147.0 157.0 21:22:32 INFO - PROCESS | 9972 | 21:22:32 INFO - PROCESS | 9972 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.50 stddev:1.55 (2.2%) stddev-sans-first:1.42 21:22:32 INFO - PROCESS | 9972 | Values: 71.0 70.0 68.0 67.0 69.0 70.0 69.0 70.0 68.0 66.0 21:22:32 INFO - PROCESS | 9972 | -------- Summary: end -------- 21:22:32 INFO - PROCESS | 9972 | 21:22:33 INFO - TEST-INFO | 9972: exit 0 21:22:33 INFO - TEST-OK | kraken | took 58651ms 21:22:33 INFO - TEST-START | v8_7 21:22:33 INFO - operating with platform_type : linux_ 21:22:33 INFO - Initialising browser for v8_7 test... 21:22:33 INFO - TEST-INFO | started process 10224 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp77jgTA/profile http://localhost:39215/getInfo.html) 21:22:37 INFO - PROCESS | 10224 | __metrics Screen width/height:1600/1200 21:22:37 INFO - PROCESS | 10224 | colorDepth:24 21:22:37 INFO - PROCESS | 10224 | Browser inner width/height: 1024/697 21:22:37 INFO - PROCESS | 10224 | __metrics 21:22:37 INFO - PROCESS | 10224 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 21:22:37 INFO - PROCESS | 10224 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:22:38 INFO - TEST-INFO | 10224: exit 0 21:22:38 INFO - Browser initialized. 21:22:38 INFO - Running cycle 1/1 for v8_7 test... 21:22:38 INFO - TEST-INFO | started process 10307 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp77jgTA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:22:50 INFO - PROCESS | 10307 | RSS: Main: 150470656 21:22:50 INFO - PROCESS | 10307 | 21:23:12 INFO - PROCESS | 10307 | Cycle 1(1): loaded http://localhost:39215/tests/v8_7/run.html (next: http://localhost:39215/tests/v8_7/run.html) 21:23:12 INFO - PROCESS | 10307 | RSS: Main: 537550848 21:23:12 INFO - PROCESS | 10307 | 21:23:12 INFO - PROCESS | 10307 | __start_tp_report 21:23:12 INFO - PROCESS | 10307 | _x_x_mozilla_page_load 21:23:12 INFO - PROCESS | 10307 | _x_x_mozilla_page_load_details 21:23:12 INFO - PROCESS | 10307 | |i|pagename|runs| 21:23:12 INFO - PROCESS | 10307 | |0;Richards;166.9727834362999 21:23:12 INFO - PROCESS | 10307 | |1;DeltaBlue;189.10741301059002 21:23:12 INFO - PROCESS | 10307 | |2;Encrypt;279.56388034665923 21:23:12 INFO - PROCESS | 10307 | |3;Decrypt;5246.073298429319 21:23:12 INFO - PROCESS | 10307 | |4;RayTrace;2008.0321285140562 21:23:12 INFO - PROCESS | 10307 | |5;Earley;798.0845969672786 21:23:12 INFO - PROCESS | 10307 | |6;Boyer;12243.90243902439 21:23:12 INFO - PROCESS | 10307 | |7;RegExp;42916.666666666664 21:23:12 INFO - PROCESS | 10307 | |8;Splay;581.7335660267597 21:23:12 INFO - PROCESS | 10307 | |9;NavierStokes;4830.917874396136 21:23:12 INFO - PROCESS | 10307 | __end_tp_report 21:23:12 INFO - PROCESS | 10307 | __startTimestamp1452835392706__endTimestamp 21:23:12 INFO - PROCESS | 10307 | 21:23:12 INFO - PROCESS | 10307 | ------- Summary: start ------- 21:23:12 INFO - PROCESS | 10307 | Number of tests: 10 21:23:12 INFO - PROCESS | 10307 | 21:23:12 INFO - PROCESS | 10307 | [#0] Richards Cycles:1 Average:166.97 Median:166.97 stddev:0.00 (0.0%) 21:23:12 INFO - PROCESS | 10307 | Values: 167.0 21:23:12 INFO - PROCESS | 10307 | 21:23:12 INFO - PROCESS | 10307 | [#1] DeltaBlue Cycles:1 Average:189.11 Median:189.11 stddev:0.00 (0.0%) 21:23:12 INFO - PROCESS | 10307 | Values: 189.1 21:23:12 INFO - PROCESS | 10307 | 21:23:12 INFO - PROCESS | 10307 | [#2] Encrypt Cycles:1 Average:279.56 Median:279.56 stddev:0.00 (0.0%) 21:23:12 INFO - PROCESS | 10307 | Values: 279.6 21:23:12 INFO - PROCESS | 10307 | 21:23:12 INFO - PROCESS | 10307 | [#3] Decrypt Cycles:1 Average:5246.07 Median:5246.07 stddev:0.00 (0.0%) 21:23:12 INFO - PROCESS | 10307 | Values: 5246.1 21:23:12 INFO - PROCESS | 10307 | 21:23:12 INFO - PROCESS | 10307 | [#4] RayTrace Cycles:1 Average:2008.03 Median:2008.03 stddev:0.00 (0.0%) 21:23:12 INFO - PROCESS | 10307 | Values: 2008.0 21:23:12 INFO - PROCESS | 10307 | 21:23:12 INFO - PROCESS | 10307 | [#5] Earley Cycles:1 Average:798.08 Median:798.08 stddev:0.00 (0.0%) 21:23:12 INFO - PROCESS | 10307 | Values: 798.1 21:23:12 INFO - PROCESS | 10307 | 21:23:12 INFO - PROCESS | 10307 | [#6] Boyer Cycles:1 Average:12243.90 Median:12243.90 stddev:0.00 (0.0%) 21:23:12 INFO - PROCESS | 10307 | Values: 12243.9 21:23:12 INFO - PROCESS | 10307 | 21:23:12 INFO - PROCESS | 10307 | [#7] RegExp Cycles:1 Average:42916.67 Median:42916.67 stddev:0.00 (0.0%) 21:23:12 INFO - PROCESS | 10307 | Values: 42916.7 21:23:12 INFO - PROCESS | 10307 | 21:23:12 INFO - PROCESS | 10307 | [#8] Splay Cycles:1 Average:581.73 Median:581.73 stddev:0.00 (0.0%) 21:23:12 INFO - PROCESS | 10307 | Values: 581.7 21:23:12 INFO - PROCESS | 10307 | 21:23:12 INFO - PROCESS | 10307 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 21:23:12 INFO - PROCESS | 10307 | Values: 4830.9 21:23:12 INFO - PROCESS | 10307 | -------- Summary: end -------- 21:23:12 INFO - PROCESS | 10307 | 21:23:13 INFO - TEST-INFO | 10307: exit 0 21:23:13 INFO - TEST-OK | v8_7 | took 40287ms 21:23:13 INFO - SUITE-END | took 1211s 21:23:13 INFO - Completed test suite (00:20:11) 21:23:13 INFO - Working with test: dromaeo_css 21:23:13 INFO - Generating results file: dromaeo_css 21:23:13 INFO - Working with test: kraken 21:23:13 INFO - Generating results file: kraken 21:23:13 INFO - javascript benchmark 21:23:13 INFO - Working with test: v8_7 21:23:13 INFO - Generating results file: v8_7 21:23:13 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 21:23:13 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,1,35]] 21:23:13 INFO - process_Request line: dromaeo_css 6666.19 graph.html#tests=[[72,1,35]] 21:23:13 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 21:23:14 INFO - process_Request line: kraken 1508.40 graph.html#tests=[[232,1,35]] 21:23:14 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 21:23:14 INFO - process_Request line: v8_7 18883.81 graph.html#tests=[[230,1,35]] 21:23:14 INFO - RETURN: dromaeo_css: 6666.19 21:23:14 INFO - RETURN: kraken: 1508.40 21:23:14 INFO - RETURN: v8_7: 18883.81 21:23:14 INFO - javascript benchmark 21:23:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3074.7693922955777, 3080.6765289644572, 3121.656617772046, 3126.2965832687646, 3144.3554071415356, 4934.6299027572895, 4969.428515720698, 4973.104841128596, 4997.075541024904, 4997.45028046915, 5038.823641172559, 5057.165567681337, 5057.721825299607, 5060.190369540875, 5076.593872490201, 4553.362529245892, 4588.380568623226, 4622.745748983804, 4659.720416774988, 4671.532846715319, 4640.489546149921, 4652.0, 4656.0, 4677.74272415017, 4698.130845793522, 4466.33005049242, 4547.886302842438, 4576.519465456113, 4620.353150558924, 4644.117617652635, 4530.864074077783, 4539.863804085882, 4547.204239258117, 4555.430571178603, 4587.839425620121, 4507.4591049074, 4516.525764794715, 4526.615237704799, 4554.2485489894125, 4558.2251017327135, 4766.52334766524, 4831.879203019917, 4859.1739404301, 4872.585830204437, 4883.12103821312, 5288.418273989896, 5326.094563924142, 5329.173978033411, 5329.173978033411, 5341.332333458317, 5329.626926115174, 5344.198370244456, 5376.220448035052, 5380.112281473552, 5459.12653975366, 5045.117104506733, 5047.49525047496, 5049.520295571915, 5050.116229659831, 5055.721935293557, 5471.753771080311, 5479.589030822704, 5479.616426850162, 5525.4474552544425, 5532.197831314517, 5187.844364669066, 5268.894622107613, 5276.709780962007, 5313.1764576490705, 5355.544778693855, 5319.494648008433, 5382.67703937765, 5384.353877534642, 5390.9191362129595, 5397.082495975694, 5270.262163297141, 5350.652207606493, 5368.7047212403695, 5370.03339398912, 5406.91889621656, 5308.708021058879, 5356.946430535722, 5412.161115027177, 5435.619506634576, 5474.671519708831, 4236.4704411948505, 4252.213340532012, 4258.574142585779, 4260.318349064135, 4296.033392486665, 3739.0652336915773, 3809.5428548573796, 3814.7520411173186, 3826.1199924017633, 3851.1334949636107, 4860.562549370574, 4921.212605983025, 4926.433460152129, 4927.162382395001, 4954.702717836942, 5304.814331498456, 5373.194020896896, 5376.381716102698, 5377.677339359651, 5426.077566813651, 4943.8022479101155, 4949.0, 5010.1983682611335, 5012.548870601663, 5019.7239151847, 4506.985928166137, 4513.819447222074, 4516.864494065183, 4527.049319642846, 4543.568361005699, 5456.590755693339, 5461.508464238317, 5462.180672899018, 5465.480779325958, 5470.835874923759, 5277.0, 5335.839924802262, 5336.359636843604, 5356.035913535445, 5416.241726158262, 5556.833295001156, 5569.33168019824, 5572.164175373727, 5588.077967135378, 5592.356878958972, 5310.123829568078, 5331.946680533145, 5334.279872217357, 5341.172118321666, 5387.730613469233, 5288.682679039116, 5313.256144139901, 5361.142217245222, 5387.488188622075, 5394.757235924471, 5306.363236411656, 5306.97346513265, 5308.946910530845, 5311.973440132929, 5317.962997215506, 5288.709120998382, 5306.097963346163, 5312.760925758273, 5317.962997215506, 5321.201819726917, 5032.0, 5107.719075450737, 5131.409887862944, 5178.223266510054, 5203.427622961395, 5263.157894736823, 5307.495787900143, 5342.545883599938, 5346.385165705994, 5395.433479484597, 5062.139436295765, 5203.063448579292, 5237.214417837203, 5237.240600112886, 5239.423663396947], "unit": "score", "name": "dojo.html", "value": 4961.822449963542}, {"lowerIsBetter": false, "replicates": [511.35824540202043, 523.1420470428498, 529.0635575032193, 531.6119232959936, 545.2475583694501, 28266.717332826665, 28552.571711424345, 28699.569506457392, 28702.851885924538, 28717.42565148696, 37185.814070929606, 37659.43510847333, 37732.43401348975, 37824.43263351045, 37834.81082594583, 8507.659693612248, 8677.435966662148, 8681.826363472726, 8682.696105636303, 8689.826203475926, 8336.874946875801, 8454.957725211365, 8462.23839854413, 8468.957655211747, 8469.534175620338, 8498.19267169621, 8584.613692383859, 8586.78533036676, 8595.140485951386, 8597.0, 8409.95795021024, 8458.111898250656, 8534.829303413897, 8535.743927682179, 8541.829163416696, 8312.625931833081, 8443.788905277417, 8625.0943650917, 8634.352423568194, 8657.783555411102, 39085.41371879424, 39406.605933940584, 39449.60550394488, 39457.60542394568, 39524.407133893015, 3200.455922493182, 3218.855151518164, 3250.934981300361, 3564.1267889367273, 3582.229820588586, 30408.0, 30421.69578304211, 30598.69401305981, 30654.080377588707, 30909.690903090905, 3218.855151518187, 3220.6940340667597, 3249.366373557157, 3322.9501557476656, 3388.288459423524, 266829.0, 271440.999999998, 271548.0, 271863.0, 272131.0, 53591.19613205805, 55135.72432137813, 61105.388946110856, 61292.0, 62383.37616623867, 55767.0, 56050.71974640101, 56328.0, 56597.43402565922, 56674.43325566774, 15042.699146017238, 15095.54713358601, 15172.848271517143, 15204.619884502976, 15207.391704331923, 11605.825912611317, 11789.705257368634, 12175.086868484723, 12251.877481225252, 12296.508139674492, 19686.60626787485, 20010.099545520487, 20059.498512537302, 20100.597988040157, 20138.798612013987, 7824.452288339761, 8036.357091432671, 8071.959640201761, 8100.635471403801, 8133.227343402367, 857.5583574459158, 859.9656013759504, 902.3367824648877, 949.7483166960761, 1067.2529229539352, 27832.582511262346, 27981.0, 28246.858765706453, 28287.29281767972, 28449.431011379656, 1027.9074883260541, 1045.3309881675734, 1059.6344261229863, 1082.6860210538855, 1122.3714719756963, 14448.927755361156, 14832.555023349316, 14888.851111488962, 16142.838571614133, 16767.91616041912, 409550.0, 416317.0, 417260.0, 417857.0, 417936.0, 54906.0, 55354.16968745391, 55376.723116384965, 55411.44588554063, 55422.72288638531, 1324.7085641158737, 1325.9668508287177, 1328.023902431712, 1329.7539955108336, 1345.650130965956, 28557.28606784847, 29584.852075739484, 29781.702182977893, 29961.850190748908, 30226.395472090873, 44932.326015109145, 45545.772271138434, 45569.54430455786, 45587.54412455833, 45589.544104559856, 279633.0, 279901.0, 281006.0, 281481.0, 282425.0, 27927.441451170456, 28607.427851442437, 29840.850795746752, 33426.49860252049, 34236.14409639779, 39184.21631567409, 39781.40327895142, 39909.60090399059, 40198.19603607853, 40347.0, 34311.31377372389, 34604.48093278653, 34648.30703385969, 38451.230975380895, 39546.80226598849, 32130.67869321277, 33999.66000340064, 36794.448083279305, 38892.0, 39448.802755986035, 34393.48409773805, 34644.133896652784, 34717.13207169841, 34969.125771855906, 36168.81915590405, 32128.518072228468, 32902.34195316128, 33855.661443386234, 33863.0, 35001.47497787586, 23715.288541343787, 23863.642045368986, 23908.760912390655, 23993.640095398932, 24282.392940176636], "unit": "score", "name": "ext.html", "value": 18137.906203486586}, {"lowerIsBetter": false, "replicates": [350.32737144682216, 351.6975401155007, 354.48068579530997, 355.05555223106523, 356.68255252824974, 13732.862671373283, 14193.219372934485, 15532.301046452909, 16378.508644740647, 18025.549361265974, 14970.32633531491, 15512.30194641241, 15839.287232074556, 16023.599410014756, 16721.91639041806, 5293.947060529403, 5508.283923089993, 5511.090670039459, 5517.889642207154, 5518.530924871371, 4025.215083058805, 4141.855035073773, 5372.650777699437, 5466.262054622614, 5783.797567085132, 5888.911666325009, 5900.616459930112, 5936.3173235077875, 5940.168376427304, 5940.316863560703, 5111.1566591512355, 5293.788248470057, 5294.205869119624, 5296.708681022562, 5307.52232299095, 4872.439669437989, 5044.268581055726, 5047.596192304607, 5060.190369540893, 5088.796448142107, 12211.0, 12322.568710095104, 13024.674383140497, 13045.739085218242, 13049.0, 1802.0, 1968.4389948864505, 2041.8672786268844, 2084.687296905461, 2151.4402058507612, 9461.148496635336, 9650.131488166098, 11278.379689117095, 12250.264984100982, 12658.0, 1784.732290156474, 1785.874988750788, 1841.484384372378, 1859.0333026825974, 1861.9945229576012, 51228.231576526385, 52947.0, 54773.0, 56999.14501282484, 57829.0, 9846.261530385249, 10147.086762191439, 10259.538320775433, 10289.74275643115, 10316.381017138996, 13373.99390091494, 13407.798883016763, 13409.329533523285, 13413.195208287534, 13427.328633568477, 7792.337651299589, 7829.295363417206, 7856.7250146244605, 7876.881846772303, 7942.166072562298, 7615.619219039026, 7632.274933881273, 7680.423968202407, 7697.576633285223, 7699.153093159748, 4408.801603927831, 4427.1588398204885, 4491.932621010622, 4498.752568608693, 4501.392312037833, 1703.3867807589259, 1754.2369069454828, 1768.2750072470226, 1827.8903265804095, 1837.971453632089, 865.3639574912435, 891.2424439226605, 911.5305617606938, 957.4686049242598, 1018.5060245780791, 16127.193640317937, 16506.917465412596, 16774.66450671004, 16917.407890723767, 17029.40397086096, 757.6174032113857, 848.6181218451721, 849.71948363201, 888.8400087984096, 895.6059333893066, 8719.171678690514, 8773.42972706772, 8833.646654133763, 8886.37795354319, 8955.373123881396, 157353.0, 157862.2106889481, 297789.0, 538526.0736963137, 547326.0, 56302.43697562972, 57781.13328300079, 59980.0, 67503.32496675069, 68704.0, 1078.0351585331084, 1081.043276700126, 1098.181854518389, 1128.4414214963645, 1132.5073592987078, 12226.266424014588, 14439.350229239499, 14633.414663413345, 14766.026345773162, 15022.399104035716, 22591.774082258966, 28950.855245723636, 29312.56031159577, 29433.705662943095, 30184.09447716572, 40116.398254025626, 40467.3929891046, 40508.79745601253, 40516.59483405246, 44324.0, 7061.435085193275, 7164.9641751790905, 7199.820004499929, 7216.278372162742, 7239.3484586385375, 10387.532561034923, 10424.478776061318, 10493.47532623381, 10749.408782517037, 10752.139828813833, 9428.717138485856, 9458.196053335543, 9684.903150968401, 9699.951500242454, 9746.122848943345, 9645.324827262024, 9696.5151742414, 9788.951055244679, 9814.55834487464, 9961.40231586107, 9984.750381240237, 10053.145482633765, 10091.444970526398, 10106.343087699275, 10347.482625868826, 7459.925400745923, 7621.161672215941, 7634.0, 7679.692812287446, 7726.9227307726205, 9372.34393592442, 9447.244220462484, 9634.470104144339, 10033.0, 10113.241506886865], "unit": "score", "name": "jquery.html", "value": 7848.024927559558}, {"lowerIsBetter": false, "replicates": [1184.1237484261649, 1472.5067102520654, 1478.1796103162744, 1627.4385337058718, 1785.330501062101, 6714.630695311762, 7400.074990626172, 7574.621268936545, 7584.051993500812, 7591.354734847545, 7499.362554182887, 7678.0, 7690.807729806757, 7704.8844267336035, 7708.383329333654, 2033.1460786469718, 2086.082123865502, 2271.522980174157, 2324.047140672329, 2350.2009316832273, 2319.860808351496, 2322.512272422785, 2322.547103314854, 2326.0695721711368, 2328.208409140892, 2226.298715904485, 2289.828262880291, 2292.5300313435705, 2293.7935585797277, 2296.2537175417974, 2263.513344630904, 2296.2537175417974, 2308.376738280655, 2328.9650655240102, 2330.545543618978, 2280.7605201453775, 2281.7946384825277, 2288.3249441414823, 2297.4026753044163, 2336.707911511061, 7746.070471543395, 7809.336206422461, 7819.17898620648, 7827.178146294671, 7829.0, 4708.270218116198, 4785.425748910118, 4799.472058073609, 4832.444268909086, 4836.22620380741, 2858.1854171561213, 2976.285691434062, 3002.6096607441114, 3016.502277124272, 3020.5318175682582, 913.7213149989251, 923.676713150392, 948.9810203795886, 950.8526178442283, 951.6383774165841, 9768.804623907483, 9876.308658393915, 9882.80234395308, 9898.257630677655, 9898.455584942825, 4078.5921407858978, 4235.0682849773, 4236.449261596034, 4281.678874084456, 4297.8495752648505, 8840.823183536422, 8896.466212027299, 8902.777430564287, 8907.465552066897, 8920.152585504367, 4695.7886895089805, 4715.4577223619035, 4727.7399743013775, 4733.698232985957, 4736.502667219961, 4324.286492728727, 4325.113351762881, 4328.43730315057, 4328.567143285646, 4328.7402755834755, 3426.1434641339665, 3488.7906725596545, 3687.465317528997, 3716.2939041582013, 3756.2111956489443, 1139.6581025692426, 1152.4295473740385, 1153.7577108807245, 1163.1973937982764, 1207.7222238885106, 1219.2745316536646, 1243.3720970909633, 1259.2633309513853, 1290.4773566705312, 1291.6383412644475, 6831.385175334244, 6896.931030689629, 6926.930730692729, 6927.757528486479, 6964.686589103502, 583.1456915618639, 595.5027551994046, 597.692188522911, 628.1080865171467, 630.1146888621494, 5249.553787928069, 5317.0, 5329.866753331198, 5332.733363331818, 5338.332708411449, 8584.184502472255, 8801.295896328278, 8931.062238464867, 9004.0, 9164.8625270621, 8859.11408859109, 8868.689595864114, 8978.595963181673, 9013.63945442188, 9107.635694572144, 6956.513044086966, 7077.327653872874, 7117.501774875813, 7152.249013853574, 7152.427805775629, 7118.572885626879, 7192.532485388433, 7197.532160409557, 7203.6758345875605, 7212.567245965259, 7816.88274675869, 7997.520148791091, 8158.469699469515, 8306.584670766317, 8323.916760832315, 8464.703735369232, 8483.321334293118, 8610.0, 8620.13798620133, 8636.654533818577, 6182.598131121463, 6447.580907241015, 6810.421114205348, 6839.384455399038, 6969.16370035599, 6709.295523970108, 7120.216776154515, 7261.709531618676, 7265.382442492236, 7266.600336981517, 6720.764773232913, 6880.071190389233, 6902.344277293649, 6951.756688515878, 7024.227334993045, 6855.725770969106, 6856.280090590416, 6876.17485901695, 6884.277150899084, 6929.237783843672, 6498.122753428416, 6537.182852143482, 6541.6402097885075, 6551.770688025897, 6687.264400915993, 6201.627902325875, 6270.49836013127, 6281.780137695341, 6288.402601752826, 6290.119383286252, 6242.313345531897, 6288.496920246278, 6290.182276304051, 6294.402031806971, 6295.842603934755], "unit": "score", "name": "mootools.html", "value": 4329.133046743779}, {"lowerIsBetter": false, "replicates": [329.27559369387353, 330.6709823725393, 332.16791936199814, 332.3568894189743, 337.7551275325388, 5688.260526131608, 5878.0, 5904.557158213135, 5921.73352199151, 5943.791967281146, 5669.489745922866, 5794.841031793637, 5796.159556864252, 5802.042662960617, 5824.097264923933, 4573.794179261941, 4576.267797152456, 4579.725216487005, 4587.174308624463, 4588.059447813207, 4384.934225986613, 4405.405270288502, 4438.467383913935, 4439.578240067189, 4574.1080489304595, 4403.933940990888, 4424.867253982369, 4480.6191473724775, 4494.842680506183, 4541.704789188709, 4456.8885777855485, 4475.820967161326, 4491.932621010655, 4503.932441013388, 4535.705179163344, 3986.3023970804993, 3993.28120938231, 4007.7996100194873, 4017.3973903914352, 4020.7386519876113, 5471.699056551888, 5602.719864006784, 5612.214289999403, 5658.943410565883, 5666.603337766358, 5193.454687257821, 5196.92204616931, 5198.402183748842, 5206.088934436459, 5207.973960130175, 5920.970395147997, 6187.535934804908, 6191.94736894729, 6200.3179650238435, 6202.875942481125, 4222.282212023963, 4233.661307095425, 4348.173846969097, 4354.433923589913, 4358.956410435919, 6400.487960963112, 6485.156929599122, 6489.643069631168, 6493.935060649427, 6502.804915852532, 4420.447444069491, 4428.0, 4429.202743506135, 4429.357743127229, 4429.867103986886, 4351.238533256667, 4481.081378317437, 4486.865394038184, 4490.146872094291, 4534.387857639243, 5215.973920130375, 5216.426193118754, 5220.634555581149, 5221.0, 5233.1365324721755, 6229.314775374705, 6241.438270555581, 6241.531885108635, 6241.656708881055, 6244.469220116341, 1563.0777841073593, 1592.2118551316942, 1592.5461243545765, 1592.8646065084597, 1592.9044257344597, 1043.8121138195197, 1061.713337398898, 1067.5996501312009, 1086.5653738504504, 1090.929089609173, 5921.792737254175, 5925.200097986716, 5929.466348028698, 5934.347221805598, 5939.613925094855, 6385.36146385367, 6390.392912673382, 6391.872162556723, 6393.904091438539, 6394.552381333355, 5893.6463812171405, 5915.3788852170765, 5923.17075609415, 5925.970370148121, 5930.406959304121, 3376.2572234108948, 3482.3035392921515, 3485.6514348564942, 3487.47687846825, 3494.947575786365, 6765.255821859591, 6785.9321406786285, 6791.626460544619, 6792.490563207779, 6796.388325050769, 6636.933630663728, 6638.6680665966505, 6644.036614690843, 6647.700853461606, 6647.933520664828, 6593.406593406617, 6782.728690852409, 6789.25318214996, 6790.898136527956, 6861.862762744817, 6221.844453888689, 6385.169927909342, 6469.5794773339585, 6473.579217350857, 6474.158359413246, 6270.7805226816845, 6394.1687580614225, 6413.679316034087, 6417.326180751141, 6430.678466076771, 6754.0, 6756.797296081126, 6760.729570817112, 6769.4922880785125, 6776.0, 4254.468191476066, 4444.711093778894, 4481.574250446202, 4481.7535035573355, 4484.551544845489, 6356.0, 6406.615603063645, 6413.583117097373, 6414.454771344302, 6414.7434102637235, 6217.440430361289, 6249.468795152281, 6259.6870156492905, 6268.435840774352, 6272.027835685476, 6105.328413874381, 6175.505959523138, 6207.906881396873, 6225.3774622537385, 6240.188775459162, 6129.693515324127, 6165.599236049642, 6169.259688837368, 6173.0, 6176.320604733386, 6309.1167236586, 6309.558330916791, 6310.873782524415, 6311.27420346666, 6313.05304204373, 6094.481969032682, 6096.695165241631, 6159.322474527888, 6167.105769663284, 6174.536909731878], "unit": "score", "name": "prototype.html", "value": 4713.264788224954}, {"lowerIsBetter": false, "replicates": [740.6370878269648, 829.8132920092976, 830.3647709502231, 846.4709556527171, 876.0276093536173, 13043.73912521749, 13190.934045329772, 13193.0, 13231.272280024596, 13316.334183290845, 12460.626181214555, 12583.685407864785, 12633.936830315859, 12714.745705085916, 12944.158629689064, 802.8153524689325, 824.6536454689015, 826.0376661189707, 826.921442462965, 827.2017503109498, 818.8648872935959, 819.8401311744212, 819.9467034642729, 820.4339006085781, 821.0639870547581, 864.3690106222462, 865.7965378136133, 865.8787769712245, 866.6143566113083, 870.708312715238, 857.9785505362385, 858.2833334165979, 858.8668756342777, 859.0636206534875, 864.0, 631.9526035547307, 631.9842003949892, 631.9951277468847, 634.8476365672252, 635.028406537993, 11267.0, 11323.603673871457, 11347.886521134766, 11348.546058157664, 11360.772784544262, 10834.079103276232, 10953.068989135932, 10965.179293206542, 11050.392228427434, 11115.666530004113, 8982.146696063863, 9065.81868362629, 9176.449413035172, 9240.491772952484, 9632.662856799978, 3364.3775901458307, 3372.3423932333203, 3402.7788193767324, 3403.1151900505797, 3417.6675361255166, 46177.769111154565, 47006.5299347009, 47013.0, 47036.5296347039, 47087.29369059467, 8237.840209835524, 8329.208725171098, 8521.914780852236, 8547.316214702809, 8583.270422014073, 16725.247363868653, 16740.832591673927, 16781.83218167827, 16850.57873553171, 16887.662246754997, 6890.44876409886, 6910.378065974087, 7155.534890232118, 7390.5196162249285, 7550.811229719301, 3816.1795214029116, 3840.5967373425947, 3851.1334949636107, 3870.3420418528917, 3908.0620651043546, 3034.1656044587476, 3060.7245347918347, 3064.402441523926, 3067.4171907337536, 3071.0479751277176, 527.6543863769238, 531.7288183026684, 536.6833568194784, 552.9087700529368, 555.5222508642564, 450.8985478267364, 563.3071322273627, 587.483014946844, 633.3824521091991, 690.4579904774828, 7584.203658615766, 7877.448578599443, 8170.468919520331, 8261.917380826235, 8369.916300837036, 542.4792199488456, 573.6959411511905, 622.7166639179162, 659.8350412396901, 692.882210024287, 3609.4766258892314, 3641.471986561987, 3689.612590677994, 3766.6798322142927, 3799.4490798834013, 56016.0, 59216.40783592109, 60392.39607603956, 60454.09318860221, 61173.69413152906, 45790.77104614455, 46264.30603540949, 51611.0, 51985.7400712994, 53308.466915331126, 45435.77282113568, 45965.85085372892, 46188.7690561545, 47981.52018479771, 48165.5183448168, 7932.206779322021, 8359.540225287425, 8608.48349099056, 8677.392582519416, 8698.47809131454, 32346.83826580852, 32555.0, 33004.33991320208, 35133.0, 35307.64692353044, 39886.202275953736, 40069.398959016224, 40198.39702404525, 40386.79806600948, 43193.784031079646, 10332.690019299433, 11625.418729063344, 11688.0, 11715.941420292844, 12119.272843629411, 12278.570250041206, 12655.430505627453, 12820.358982051046, 12991.35043247815, 13038.739225215253, 11150.888491114985, 11519.53921843117, 11540.769184616092, 11694.239874408137, 11961.282323060645, 10740.892591073989, 11133.944330278297, 11490.310581365145, 11607.535698571963, 11608.6517404478, 10936.343819370863, 11346.149038822286, 11360.829587556358, 11536.653900383002, 11742.295462272292, 9501.952490237505, 9632.422054676741, 9656.517174141125, 9797.85303220438, 9805.2646051545, 8420.705275315335, 8922.910770892468, 9119.544022798966, 9243.76890577741, 9797.461139637104], "unit": "score", "name": "yui.html", "value": 6088.591403248881}], "name": "dromaeo_css", "value": 6666.194905967874}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 104.0, 111.0, 111.0, 111.0, 102.0, 108.0, 110.0, 110.0], "unit": "score", "name": "ai-astar", "value": 108.6}, {"lowerIsBetter": true, "replicates": [150.0, 124.0, 151.0, 166.0, 143.0, 147.0, 161.0, 128.0, 173.0, 123.0], "unit": "score", "name": "audio-beat-detection", "value": 146.6}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 162.0, 162.0, 159.0, 161.0, 159.0, 163.0, 163.0, 161.0], "unit": "score", "name": "audio-dft", "value": 161.1}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 124.0, 102.0, 71.0, 71.0, 73.0, 120.0, 94.0, 70.0], "unit": "score", "name": "audio-fft", "value": 86.8}, {"lowerIsBetter": true, "replicates": [97.0, 96.0, 142.0, 96.0, 95.0, 95.0, 141.0, 95.0, 97.0, 138.0], "unit": "score", "name": "audio-oscillator", "value": 109.2}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 110.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 147.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.8}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 56.0, 56.0, 56.0, 56.0, 55.0, 65.0, 54.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 56.5}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 67.0, 67.0, 66.0, 67.0, 67.0, 67.0, 67.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.9}, {"lowerIsBetter": true, "replicates": [84.0, 82.0, 82.0, 82.0, 83.0, 96.0, 83.0, 82.0, 83.0, 82.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.9}, {"lowerIsBetter": true, "replicates": [123.0, 119.0, 117.0, 122.0, 128.0, 116.0, 127.0, 130.0, 117.0, 114.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.3}, {"lowerIsBetter": true, "replicates": [150.0, 139.0, 149.0, 149.0, 148.0, 142.0, 149.0, 152.0, 147.0, 157.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 148.2}, {"lowerIsBetter": true, "replicates": [71.0, 70.0, 68.0, 67.0, 69.0, 70.0, 69.0, 70.0, 68.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.8}], "name": "kraken", "value": 1508.4}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.9727834362999], "unit": "score", "name": "Richards", "value": 211.42367800000002}, {"lowerIsBetter": false, "replicates": [189.10741301059002], "unit": "score", "name": "DeltaBlue", "value": 349.631984}, {"lowerIsBetter": false, "replicates": [279.56388034665923], "unit": "score", "name": "Encrypt", "value": 952.1294369999999}, {"lowerIsBetter": false, "replicates": [5246.073298429319], "unit": "score", "name": "Decrypt", "value": 50.739092814371276}, {"lowerIsBetter": false, "replicates": [2008.0321285140562], "unit": "score", "name": "RayTrace", "value": 368.51452200000006}, {"lowerIsBetter": false, "replicates": [798.0845969672786], "unit": "score", "name": "Earley", "value": 835.078139}, {"lowerIsBetter": false, "replicates": [12243.90243902439], "unit": "score", "name": "Boyer", "value": 54.4322370517928}, {"lowerIsBetter": false, "replicates": [42916.666666666664], "unit": "score", "name": "RegExp", "value": 21.22683495145631}, {"lowerIsBetter": false, "replicates": [581.7335660267597], "unit": "score", "name": "Splay", "value": 140.08302900000007}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 18883.81117413999}]} 21:23:14 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,1,35]]", "result": "18883.81"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,1,35]]", "result": "1508.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,1,35]]", "result": "6666.19"}}} 21:23:14 INFO - Return code: 0 21:23:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 21:23:14 INFO - # TBPL SUCCESS # 21:23:14 INFO - Running post-action listener: _resource_record_post_action 21:23:14 INFO - Running post-run listener: _resource_record_post_run 21:23:15 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 0; Write bytes: 542859264; Read time: 0; Write time: 517616 21:23:15 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 239108096; Read time: 0; Write time: 265476 21:23:15 INFO - run-tests - Wall time: 1213s; CPU: 13.0%; Read bytes: 0; Write bytes: 303517696; Read time: 0; Write time: 252116 21:23:15 INFO - Running post-run listener: _upload_blobber_files 21:23:15 INFO - Blob upload gear active. 21:23:15 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 21:23:15 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 21:23:15 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', 'Firefox', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 21:23:15 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 Firefox -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 21:23:15 INFO - (blobuploader) - INFO - Open directory for files ... 21:23:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 21:23:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:23:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:23:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:23:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:23:16 INFO - (blobuploader) - INFO - Done attempting. 21:23:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:23:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:23:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:23:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:23:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:23:17 INFO - (blobuploader) - INFO - Done attempting. 21:23:17 INFO - (blobuploader) - INFO - Iteration through files over. 21:23:17 INFO - Return code: 0 21:23:17 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 21:23:17 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 21:23:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81e2b8d3ab5fc711ce6f30ecd5f399303fff514083b19b7eb11ef3b2f6946d96b0a3e760883dbe06299aa107ceb3ee362971e6401908363fbc68650b74c040ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/237d8ecf24467b8032ac7d57e78bb45bcd824545697077e77b97470dab6a27dbcfb9e135c1cf87e4327eb5ce795d860ffa9c11219e6e89701ed96a6b6759f674"} 21:23:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 21:23:17 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 21:23:17 INFO - Contents: 21:23:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81e2b8d3ab5fc711ce6f30ecd5f399303fff514083b19b7eb11ef3b2f6946d96b0a3e760883dbe06299aa107ceb3ee362971e6401908363fbc68650b74c040ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/237d8ecf24467b8032ac7d57e78bb45bcd824545697077e77b97470dab6a27dbcfb9e135c1cf87e4327eb5ce795d860ffa9c11219e6e89701ed96a6b6759f674"} 21:23:17 INFO - Copying logs to upload dir... 21:23:17 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1280.529157 ========= master_lag: -0.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-01-14 21:23:17.136644) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-14 21:23:17.140543) ========= 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-1452830569.679415-1410185024 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81e2b8d3ab5fc711ce6f30ecd5f399303fff514083b19b7eb11ef3b2f6946d96b0a3e760883dbe06299aa107ceb3ee362971e6401908363fbc68650b74c040ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/237d8ecf24467b8032ac7d57e78bb45bcd824545697077e77b97470dab6a27dbcfb9e135c1cf87e4327eb5ce795d860ffa9c11219e6e89701ed96a6b6759f674"} build_url:https://queue.taskcluster.net/v1/task/IpknuwviQ9CPIDevMtHWnw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011440 build_url: 'https://queue.taskcluster.net/v1/task/IpknuwviQ9CPIDevMtHWnw/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/Firefox/sha512/81e2b8d3ab5fc711ce6f30ecd5f399303fff514083b19b7eb11ef3b2f6946d96b0a3e760883dbe06299aa107ceb3ee362971e6401908363fbc68650b74c040ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/237d8ecf24467b8032ac7d57e78bb45bcd824545697077e77b97470dab6a27dbcfb9e135c1cf87e4327eb5ce795d860ffa9c11219e6e89701ed96a6b6759f674"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-14 21:23:17.165008) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 21:23:17.165349) ========= 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-1452830569.679415-1410185024 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004628 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-14 21:23:17.248970) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-14 21:23:17.249297) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-14 21:23:17.249607) ========= ========= Total master_lag: 0.04 =========