builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-002 starttime: 1456570767.44 results: success (0) buildid: 20160222065616 builduid: 9e95d762599340abad2cb4ba43c2f2b7 revision: 0c7dfc6d57d70816a6b07393f67f8b3747e6486d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:27.444487) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:27.444942) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:27.445260) ========= 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=a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.009080 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.13 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:27.582677) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:27.583017) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:27.789947) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:27.790237) ========= 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=a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004648 ========= master_lag: 0.13 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:27.923967) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:27.924270) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:27.927660) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:27.927991) ========= 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=a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-27 02:59:28-- 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% 35.4M=0s 2016-02-27 02:59:28 (35.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117254 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:28.093201) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:28.093509) ========= 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=a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014876 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:28.153302) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:28.153666) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0c7dfc6d57d70816a6b07393f67f8b3747e6486d --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0c7dfc6d57d70816a6b07393f67f8b3747e6486d --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-27 02:59:28,420 truncating revision to first 12 chars 2016-02-27 02:59:28,420 Setting DEBUG logging. 2016-02-27 02:59:28,420 attempt 1/10 2016-02-27 02:59:28,420 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0c7dfc6d57d7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-27 02:59:28,732 unpacking tar archive at: mozilla-aurora-0c7dfc6d57d7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.599213 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:28.772482) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:28.772861) ========= echo 0c7dfc6d57d70816a6b07393f67f8b3747e6486d in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'0c7dfc6d57d70816a6b07393f67f8b3747e6486d'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 0c7dfc6d57d70816a6b07393f67f8b3747e6486d program finished with exit code 0 elapsedTime=0.004384 script_repo_revision: '0c7dfc6d57d70816a6b07393f67f8b3747e6486d' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:28.825743) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:28.826072) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-27 02:59:28.841785) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2016-02-27 02:59:28.842089) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:59:29 INFO - MultiFileLogger online at 20160227 02:59:29 in /builds/slave/test-pgo 02:59:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:59:29 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 02:59:29 INFO - {'append_to_log': False, 02:59:29 INFO - 'base_work_dir': '/builds/slave/test-pgo', 02:59:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:59:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 02:59:29 INFO - 'branch': 'Mozilla-Aurora', 02:59:29 INFO - 'buildbot_json_path': 'buildprops.json', 02:59:29 INFO - 'config_files': ('talos/linux_config.py',), 02:59:29 INFO - 'default_actions': ('clobber', 02:59:29 INFO - 'read-buildbot-config', 02:59:29 INFO - 'download-and-extract', 02:59:29 INFO - 'populate-webroot', 02:59:29 INFO - 'create-virtualenv', 02:59:29 INFO - 'install', 02:59:29 INFO - 'run-tests'), 02:59:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:59:29 INFO - 'download_minidump_stackwalk': True, 02:59:29 INFO - 'download_symbols': 'ondemand', 02:59:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:59:29 INFO - 'tooltool.py': '/tools/tooltool.py', 02:59:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:59:29 INFO - '/tools/misc-python/virtualenv.py')}, 02:59:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:59:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:59:29 INFO - 'installer_path': 'installer.exe', 02:59:29 INFO - 'log_level': 'info', 02:59:29 INFO - 'log_name': 'talos', 02:59:29 INFO - 'log_to_console': True, 02:59:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:59:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:59:29 INFO - 'opt_config_files': (), 02:59:29 INFO - 'pip_index': False, 02:59:29 INFO - 'sps_profile': False, 02:59:29 INFO - 'sps_profile_interval': 0, 02:59:29 INFO - 'suite': 'dromaeojs', 02:59:29 INFO - 'system_bits': '32', 02:59:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:59:29 INFO - 'title': 'talos-linux64-ix-002', 02:59:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:59:29 INFO - 'use_talos_json': True, 02:59:29 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 02:59:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:59:29 INFO - 'work_dir': 'build'} 02:59:29 INFO - ##### 02:59:29 INFO - ##### Running clobber step. 02:59:29 INFO - ##### 02:59:29 INFO - Running pre-action listener: _resource_record_pre_action 02:59:29 INFO - Running main action method: clobber 02:59:29 INFO - rmtree: /builds/slave/test-pgo/build 02:59:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 02:59:30 INFO - Running post-action listener: _resource_record_post_action 02:59:30 INFO - ##### 02:59:30 INFO - ##### Running read-buildbot-config step. 02:59:30 INFO - ##### 02:59:30 INFO - Running pre-action listener: _resource_record_pre_action 02:59:30 INFO - Running main action method: read_buildbot_config 02:59:30 INFO - Using buildbot properties: 02:59:30 INFO - { 02:59:30 INFO - "project": "", 02:59:30 INFO - "product": "firefox", 02:59:30 INFO - "script_repo_revision": "production", 02:59:30 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 02:59:30 INFO - "repository": "", 02:59:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 02:59:30 INFO - "buildid": "20160222065616", 02:59:30 INFO - "pgo_build": "True", 02:59:30 INFO - "basedir": "/builds/slave/test-pgo", 02:59:30 INFO - "buildnumber": 296, 02:59:30 INFO - "slavename": "talos-linux64-ix-002", 02:59:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:59:30 INFO - "platform": "ubuntu64_hw", 02:59:30 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 02:59:30 INFO - "branch": "mozilla-aurora", 02:59:30 INFO - "repo_path": "releases/mozilla-aurora", 02:59:30 INFO - "revision": "0c7dfc6d57d70816a6b07393f67f8b3747e6486d", 02:59:30 INFO - "stage_platform": "linux64-pgo", 02:59:30 INFO - "builduid": "9e95d762599340abad2cb4ba43c2f2b7", 02:59:30 INFO - "slavebuilddir": "test" 02:59:30 INFO - } 02:59:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2. 02:59:30 INFO - Running post-action listener: _resource_record_post_action 02:59:30 INFO - ##### 02:59:30 INFO - ##### Running download-and-extract step. 02:59:30 INFO - ##### 02:59:30 INFO - Running pre-action listener: _resource_record_pre_action 02:59:30 INFO - Running main action method: download_and_extract 02:59:30 INFO - mkdir: /builds/slave/test-pgo/build/tests 02:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:59:30 INFO - https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 02:59:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/test_packages.json 02:59:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/test_packages.json 02:59:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 02:59:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 02:59:39 INFO - Downloaded 1448 bytes. 02:59:39 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 02:59:39 INFO - Using the following test package requirements: 02:59:39 INFO - {u'common': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 02:59:39 INFO - u'cppunittest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:39 INFO - u'firefox-46.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 02:59:39 INFO - u'gtest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:39 INFO - u'firefox-46.0a2.en-US.linux-x86_64.gtest.tests.zip'], 02:59:39 INFO - u'jittest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:39 INFO - u'jsshell-linux-x86_64.zip'], 02:59:39 INFO - u'mochitest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:39 INFO - u'firefox-46.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 02:59:39 INFO - u'mozbase': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 02:59:39 INFO - u'reftest': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:39 INFO - u'firefox-46.0a2.en-US.linux-x86_64.reftest.tests.zip'], 02:59:39 INFO - u'talos': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:39 INFO - u'firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip'], 02:59:39 INFO - u'web-platform': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:39 INFO - u'firefox-46.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 02:59:39 INFO - u'webapprt': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'], 02:59:39 INFO - u'xpcshell': [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 02:59:39 INFO - u'firefox-46.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 02:59:39 INFO - Downloading packages: [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:59: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')]} 02:59:39 INFO - https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:59:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 02:59:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 02:59:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 02:59:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:00:09 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip: timed out 03:00:09 INFO - retry: attempt #1 caught exception: timed out 03:00:09 INFO - retry: Failed, sleeping 30 seconds before retrying 03:00:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:00:41 INFO - Downloaded 23079161 bytes. 03:00:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 03:00:41 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 03:00:42 INFO - Return code: 0 03:00:42 INFO - Downloading packages: [u'firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:00: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')]} 03:00:42 INFO - https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:00:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 03:00:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 03:00:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip 03:00:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:00:44 INFO - Downloaded 23079161 bytes. 03:00:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 03:00:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 03:00:45 INFO - Return code: 0 03:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:00:45 INFO - https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:00:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip 03:00:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip 03:00:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip 03:00:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:00:46 INFO - Downloaded 10706572 bytes. 03:00:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 03:00:46 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 03:00:47 INFO - Return code: 0 03:00:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:00:47 INFO - https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:00:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 03:00:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 03:00:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 03:00:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:00:49 INFO - Downloaded 59960416 bytes. 03:00:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 03:00:49 INFO - mkdir: /builds/slave/test-pgo/properties 03:00:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 03:00:49 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 03:00:49 INFO - Contents: 03:00:49 INFO - build_url:https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 03:00:50 INFO - Running post-action listener: _resource_record_post_action 03:00:50 INFO - Running post-action listener: set_extra_try_arguments 03:00:50 INFO - ##### 03:00:50 INFO - ##### Running populate-webroot step. 03:00:50 INFO - ##### 03:00:50 INFO - Running pre-action listener: _resource_record_pre_action 03:00:50 INFO - Running main action method: populate_webroot 03:00:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:00:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:00:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:00:50 INFO - '--tppagecycles', 03:00:50 INFO - '7'], 03:00:50 INFO - 'tests': ['tsvgm']}}, 03:00:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:00:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:00:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:00:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:00:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:00:50 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 03:00:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:50 INFO - 'talos_options': ['--e10s'], 03:00:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:00:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:50 INFO - 'tests': ['damp', 'tps']}, 03:00:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:50 INFO - 'talos_options': ['--e10s'], 03:00:50 INFO - 'tests': ['damp', 'tps']}, 03:00:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:00:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:00:50 INFO - 'other': {'tests': ['a11yr', 03:00:50 INFO - 'ts_paint', 03:00:50 INFO - 'tpaint', 03:00:50 INFO - 'sessionrestore', 03:00:50 INFO - 'sessionrestore_no_auto_restore']}, 03:00:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:00:50 INFO - 'tests': ['a11yr', 03:00:50 INFO - 'ts_paint', 03:00:50 INFO - 'tpaint', 03:00:50 INFO - 'sessionrestore', 03:00:50 INFO - 'sessionrestore_no_auto_restore']}, 03:00:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:00:50 INFO - 'tests': ['a11yr', 03:00:50 INFO - 'ts_paint', 03:00:50 INFO - 'tpaint', 03:00:50 INFO - 'sessionrestore', 03:00:50 INFO - 'sessionrestore_no_auto_restore']}, 03:00:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:00:50 INFO - 'tests': ['a11yr', 03:00:50 INFO - 'ts_paint', 03:00:50 INFO - 'tpaint', 03:00:50 INFO - 'sessionrestore', 03:00:50 INFO - 'sessionrestore_no_auto_restore']}, 03:00:50 INFO - 'other_l64': {'tests': ['a11yr', 03:00:50 INFO - 'ts_paint', 03:00:50 INFO - 'tpaint', 03:00:50 INFO - 'sessionrestore', 03:00:50 INFO - 'sessionrestore_no_auto_restore']}, 03:00:50 INFO - 'other_nol64': {'tests': ['a11yr', 03:00:50 INFO - 'ts_paint', 03:00:50 INFO - 'tpaint', 03:00:50 INFO - 'sessionrestore', 03:00:50 INFO - 'sessionrestore_no_auto_restore']}, 03:00:50 INFO - 'svgr': {'tests': ['tsvgx', 03:00:50 INFO - 'tsvgr_opacity', 03:00:50 INFO - 'tart', 03:00:50 INFO - 'tscrollx', 03:00:50 INFO - 'cart']}, 03:00:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:00:50 INFO - 'tests': ['tsvgx', 03:00:50 INFO - 'tsvgr_opacity', 03:00:50 INFO - 'tart', 03:00:50 INFO - 'tscrollx', 03:00:50 INFO - 'cart']}, 03:00:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:50 INFO - 'tests': ['tp5o']}, 03:00:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:50 INFO - 'talos_options': ['--e10s'], 03:00:50 INFO - 'tests': ['tp5o']}, 03:00:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:50 INFO - 'talos_options': ['--xperf_path', 03:00:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:50 INFO - 'tests': ['tp5n']}, 03:00:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:00:50 INFO - 'talos_options': ['--e10s', 03:00:50 INFO - '--xperf_path', 03:00:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:00:50 INFO - 'tests': ['tp5n']}}, 03:00:50 INFO - 'talos.zip': {'path': '', 03:00:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:00:50 INFO - Running post-action listener: _resource_record_post_action 03:00:50 INFO - ##### 03:00:50 INFO - ##### Running create-virtualenv step. 03:00:50 INFO - ##### 03:00:50 INFO - Running pre-action listener: _resource_record_pre_action 03:00:50 INFO - Running main action method: create_virtualenv 03:00:50 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 03:00:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 03:00:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 03:00:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:00:50 INFO - Using real prefix '/usr' 03:00:50 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 03:00:51 INFO - Installing distribute.............................................................................................................................................................................................done. 03:00:53 INFO - Installing pip.................done. 03:00:53 INFO - Return code: 0 03:00:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 03:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88513fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2980230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a127f0>, '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 0x297e2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297e760>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:00:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 03:00:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:00:53 INFO - Using env: {'DISPLAY': ':0', 03:00:53 INFO - 'HOME': '/home/cltbld', 03:00:53 INFO - 'LANG': 'en_US.UTF-8', 03:00:53 INFO - 'LANGUAGE': 'en_US:en', 03:00:53 INFO - 'LOGNAME': 'cltbld', 03:00:53 INFO - 'MAIL': '/var/mail/cltbld', 03:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:53 INFO - 'MOZ_NO_REMOTE': '1', 03:00:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:53 INFO - 'NO_EM_RESTART': '1', 03:00:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:00:53 INFO - 'PWD': '/builds/slave/test-pgo', 03:00:53 INFO - 'SHELL': '/bin/bash', 03:00:53 INFO - 'SHLVL': '1', 03:00:53 INFO - 'TERM': 'linux', 03:00:53 INFO - 'TMOUT': '86400', 03:00:53 INFO - 'USER': 'cltbld', 03:00:53 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 03:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:53 INFO - '_': '/tools/buildbot/bin/python'} 03:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:00:53 INFO - Cleaning up... 03:00:53 INFO - Return code: 0 03:00:53 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 03:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:00:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88513fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2980230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a127f0>, '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 0x297e2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297e760>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:00:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 03:00:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 03:00:53 INFO - Using env: {'DISPLAY': ':0', 03:00:53 INFO - 'HOME': '/home/cltbld', 03:00:53 INFO - 'LANG': 'en_US.UTF-8', 03:00:53 INFO - 'LANGUAGE': 'en_US:en', 03:00:53 INFO - 'LOGNAME': 'cltbld', 03:00:53 INFO - 'MAIL': '/var/mail/cltbld', 03:00:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:53 INFO - 'MOZ_NO_REMOTE': '1', 03:00:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:53 INFO - 'NO_EM_RESTART': '1', 03:00:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:00:53 INFO - 'PWD': '/builds/slave/test-pgo', 03:00:53 INFO - 'SHELL': '/bin/bash', 03:00:53 INFO - 'SHLVL': '1', 03:00:53 INFO - 'TERM': 'linux', 03:00:53 INFO - 'TMOUT': '86400', 03:00:53 INFO - 'USER': 'cltbld', 03:00:53 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 03:00:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:53 INFO - '_': '/tools/buildbot/bin/python'} 03:00:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:53 INFO - Downloading/unpacking psutil>=0.7.1 03:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 03:00:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:55 INFO - Installing collected packages: psutil 03:00:55 INFO - Running setup.py install for psutil 03:00:55 INFO - building 'psutil._psutil_linux' extension 03:00:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:00:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:00:55 INFO - building 'psutil._psutil_posix' extension 03:00:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:00:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:00:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:00:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:00:55 INFO - Successfully installed psutil 03:00:55 INFO - Cleaning up... 03:00:56 INFO - Return code: 0 03:00:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 03:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:00:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88513fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2980230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a127f0>, '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 0x297e2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297e760>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:00:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 03:00:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:00:56 INFO - Using env: {'DISPLAY': ':0', 03:00:56 INFO - 'HOME': '/home/cltbld', 03:00:56 INFO - 'LANG': 'en_US.UTF-8', 03:00:56 INFO - 'LANGUAGE': 'en_US:en', 03:00:56 INFO - 'LOGNAME': 'cltbld', 03:00:56 INFO - 'MAIL': '/var/mail/cltbld', 03:00:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:56 INFO - 'MOZ_NO_REMOTE': '1', 03:00:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:56 INFO - 'NO_EM_RESTART': '1', 03:00:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:00:56 INFO - 'PWD': '/builds/slave/test-pgo', 03:00:56 INFO - 'SHELL': '/bin/bash', 03:00:56 INFO - 'SHLVL': '1', 03:00:56 INFO - 'TERM': 'linux', 03:00:56 INFO - 'TMOUT': '86400', 03:00:56 INFO - 'USER': 'cltbld', 03:00:56 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 03:00:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:56 INFO - '_': '/tools/buildbot/bin/python'} 03:00:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:00:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:00:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:00:58 INFO - Installing collected packages: mozsystemmonitor 03:00:58 INFO - Running setup.py install for mozsystemmonitor 03:00:58 INFO - Successfully installed mozsystemmonitor 03:00:58 INFO - Cleaning up... 03:00:58 INFO - Return code: 0 03:00:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 03:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:00:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f88513fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2980230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a127f0>, '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 0x297e2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297e760>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:00:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 03:00:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:00:58 INFO - Using env: {'DISPLAY': ':0', 03:00:58 INFO - 'HOME': '/home/cltbld', 03:00:58 INFO - 'LANG': 'en_US.UTF-8', 03:00:58 INFO - 'LANGUAGE': 'en_US:en', 03:00:58 INFO - 'LOGNAME': 'cltbld', 03:00:58 INFO - 'MAIL': '/var/mail/cltbld', 03:00:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:00:58 INFO - 'MOZ_NO_REMOTE': '1', 03:00:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:00:58 INFO - 'NO_EM_RESTART': '1', 03:00:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:00:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:00:58 INFO - 'PWD': '/builds/slave/test-pgo', 03:00:58 INFO - 'SHELL': '/bin/bash', 03:00:58 INFO - 'SHLVL': '1', 03:00:58 INFO - 'TERM': 'linux', 03:00:58 INFO - 'TMOUT': '86400', 03:00:58 INFO - 'USER': 'cltbld', 03:00:58 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 03:00:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:00:58 INFO - '_': '/tools/buildbot/bin/python'} 03:00:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:00:58 INFO - Downloading/unpacking blobuploader==1.2.4 03:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:00:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:00:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:00 INFO - Downloading blobuploader-1.2.4.tar.gz 03:01:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:01:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 03:01:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:01 INFO - Downloading docopt-0.6.1.tar.gz 03:01:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 03:01:01 INFO - Installing collected packages: blobuploader, requests, docopt 03:01:01 INFO - Running setup.py install for blobuploader 03:01:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:01:01 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 03:01:01 INFO - Running setup.py install for requests 03:01:01 INFO - Running setup.py install for docopt 03:01:01 INFO - Successfully installed blobuploader requests docopt 03:01:01 INFO - Cleaning up... 03:01:01 INFO - Return code: 0 03:01:01 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:01 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88513fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2980230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a127f0>, '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 0x297e2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297e760>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:01 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 03:01:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:01:01 INFO - Using env: {'DISPLAY': ':0', 03:01:01 INFO - 'HOME': '/home/cltbld', 03:01:01 INFO - 'LANG': 'en_US.UTF-8', 03:01:01 INFO - 'LANGUAGE': 'en_US:en', 03:01:01 INFO - 'LOGNAME': 'cltbld', 03:01:01 INFO - 'MAIL': '/var/mail/cltbld', 03:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:01 INFO - 'MOZ_NO_REMOTE': '1', 03:01:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:01 INFO - 'NO_EM_RESTART': '1', 03:01:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:01 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:01 INFO - 'SHELL': '/bin/bash', 03:01:01 INFO - 'SHLVL': '1', 03:01:01 INFO - 'TERM': 'linux', 03:01:01 INFO - 'TMOUT': '86400', 03:01:01 INFO - 'USER': 'cltbld', 03:01:01 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 03:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:01 INFO - '_': '/tools/buildbot/bin/python'} 03:01:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:01:02 INFO - Running setup.py (path:/tmp/pip-i6asMq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:01:02 INFO - Running setup.py (path:/tmp/pip-nyzEDM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:01:02 INFO - Running setup.py (path:/tmp/pip-Bjyvo3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:01:02 INFO - Running setup.py (path:/tmp/pip-kVcFGY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:01:02 INFO - Running setup.py (path:/tmp/pip-__46ui-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:01:02 INFO - Running setup.py (path:/tmp/pip-RVZmAj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:01:02 INFO - Running setup.py (path:/tmp/pip-eOtnVs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:01:02 INFO - Running setup.py (path:/tmp/pip-WxHHJR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:01:02 INFO - Running setup.py (path:/tmp/pip-EYYnuq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:01:02 INFO - Running setup.py (path:/tmp/pip-sLqVEq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:01:02 INFO - Running setup.py (path:/tmp/pip-A0ePc_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:01:02 INFO - Running setup.py (path:/tmp/pip-gAfg38-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:01:02 INFO - Running setup.py (path:/tmp/pip-vc3Bs8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:01:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:01:02 INFO - Running setup.py (path:/tmp/pip-tVhQkv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:01:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:01:03 INFO - Running setup.py (path:/tmp/pip-SlnYXU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:01:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:01:03 INFO - Running setup.py (path:/tmp/pip-duxrvT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:01:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:01:03 INFO - Running setup.py (path:/tmp/pip-yQqVpa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:01:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:01:03 INFO - Running setup.py install for manifestparser 03:01:03 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 03:01:03 INFO - Running setup.py install for mozcrash 03:01:03 INFO - Running setup.py install for mozdebug 03:01:03 INFO - Running setup.py install for mozdevice 03:01:03 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 03:01:03 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 03:01:03 INFO - Running setup.py install for mozfile 03:01:03 INFO - Running setup.py install for mozhttpd 03:01:03 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 03:01:03 INFO - Running setup.py install for mozinfo 03:01:03 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 03:01:03 INFO - Running setup.py install for mozInstall 03:01:04 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 03:01:04 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 03:01:04 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 03:01:04 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 03:01:04 INFO - Running setup.py install for mozleak 03:01:04 INFO - Running setup.py install for mozlog 03:01:04 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 03:01:04 INFO - Running setup.py install for moznetwork 03:01:04 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 03:01:04 INFO - Running setup.py install for mozprocess 03:01:04 INFO - Running setup.py install for mozprofile 03:01:04 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 03:01:04 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 03:01:04 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 03:01:04 INFO - Running setup.py install for mozrunner 03:01:04 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 03:01:04 INFO - Running setup.py install for mozscreenshot 03:01:04 INFO - Running setup.py install for moztest 03:01:04 INFO - Running setup.py install for mozversion 03:01:05 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 03:01:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:01:05 INFO - Cleaning up... 03:01:05 INFO - Return code: 0 03:01:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88513fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2980230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a127f0>, '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 0x297e2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297e760>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 03:01:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:01:05 INFO - Using env: {'DISPLAY': ':0', 03:01:05 INFO - 'HOME': '/home/cltbld', 03:01:05 INFO - 'LANG': 'en_US.UTF-8', 03:01:05 INFO - 'LANGUAGE': 'en_US:en', 03:01:05 INFO - 'LOGNAME': 'cltbld', 03:01:05 INFO - 'MAIL': '/var/mail/cltbld', 03:01:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:05 INFO - 'MOZ_NO_REMOTE': '1', 03:01:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:05 INFO - 'NO_EM_RESTART': '1', 03:01:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:05 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:05 INFO - 'SHELL': '/bin/bash', 03:01:05 INFO - 'SHLVL': '1', 03:01:05 INFO - 'TERM': 'linux', 03:01:05 INFO - 'TMOUT': '86400', 03:01:05 INFO - 'USER': 'cltbld', 03:01:05 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 03:01:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:05 INFO - '_': '/tools/buildbot/bin/python'} 03:01:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:01:05 INFO - Running setup.py (path:/tmp/pip-xzI0GE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 03:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 03:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:01:05 INFO - Running setup.py (path:/tmp/pip-1HSXWy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 03:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 03:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:01:05 INFO - Running setup.py (path:/tmp/pip-QkBnkK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 03:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 03:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:01:05 INFO - Running setup.py (path:/tmp/pip-UaOXiO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 03:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 03:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 03:01:05 INFO - Running setup.py (path:/tmp/pip-tmnGMt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 03:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 03:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:01:05 INFO - Running setup.py (path:/tmp/pip-RO2RHd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 03:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 03:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:01:05 INFO - Running setup.py (path:/tmp/pip-Vg81P_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 03:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 03:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:01:05 INFO - Running setup.py (path:/tmp/pip-6HWfnn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 03:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 03:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 03:01:05 INFO - Running setup.py (path:/tmp/pip-_K4Bji-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 03:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 03:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 03:01:05 INFO - Running setup.py (path:/tmp/pip-TnHPvk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 03:01:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 03:01:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:01:05 INFO - Running setup.py (path:/tmp/pip-dsuORv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 03:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 03:01:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:01:06 INFO - Running setup.py (path:/tmp/pip-2jDkRC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 03:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 03:01:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:01:06 INFO - Running setup.py (path:/tmp/pip-0TdQDh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 03:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 03:01:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:01:06 INFO - Running setup.py (path:/tmp/pip-Y5q4V1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 03:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 03:01:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:01:06 INFO - Running setup.py (path:/tmp/pip-C2CYpT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 03:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 03:01:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 03:01:06 INFO - Running setup.py (path:/tmp/pip-Xn0qIs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 03:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 03:01:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 03:01:06 INFO - Running setup.py (path:/tmp/pip-cVBS8A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 03:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 03:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 03:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 03:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 03:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 03:01:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 03:01:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 03:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:08 INFO - Downloading blessings-1.6.tar.gz 03:01:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 03:01:08 INFO - Installing collected packages: blessings 03:01:08 INFO - Running setup.py install for blessings 03:01:08 INFO - Successfully installed blessings 03:01:08 INFO - Cleaning up... 03:01:08 INFO - Return code: 0 03:01:08 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 03:01:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:01:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:01:08 INFO - Reading from file tmpfile_stdout 03:01:08 INFO - Current package versions: 03:01:08 INFO - argparse == 1.2.1 03:01:08 INFO - blessings == 1.6 03:01:08 INFO - blobuploader == 1.2.4 03:01:08 INFO - docopt == 0.6.1 03:01:08 INFO - manifestparser == 1.1 03:01:08 INFO - mozInstall == 1.12 03:01:08 INFO - mozcrash == 0.16 03:01:08 INFO - mozdebug == 0.1 03:01:08 INFO - mozdevice == 0.48 03:01:08 INFO - mozfile == 1.2 03:01:08 INFO - mozhttpd == 0.7 03:01:08 INFO - mozinfo == 0.9 03:01:08 INFO - mozleak == 0.1 03:01:08 INFO - mozlog == 3.1 03:01:08 INFO - moznetwork == 0.27 03:01:08 INFO - mozprocess == 0.22 03:01:08 INFO - mozprofile == 0.28 03:01:08 INFO - mozrunner == 6.11 03:01:08 INFO - mozscreenshot == 0.1 03:01:08 INFO - mozsystemmonitor == 0.0 03:01:08 INFO - moztest == 0.7 03:01:08 INFO - mozversion == 1.4 03:01:08 INFO - psutil == 3.1.1 03:01:08 INFO - requests == 1.2.3 03:01:08 INFO - wsgiref == 0.1.2 03:01:08 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 03:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:08 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88513fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2980230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a127f0>, '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 0x297e2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297e760>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:08 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 03:01:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:01:08 INFO - Using env: {'DISPLAY': ':0', 03:01:08 INFO - 'HOME': '/home/cltbld', 03:01:08 INFO - 'LANG': 'en_US.UTF-8', 03:01:08 INFO - 'LANGUAGE': 'en_US:en', 03:01:08 INFO - 'LOGNAME': 'cltbld', 03:01:08 INFO - 'MAIL': '/var/mail/cltbld', 03:01:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:08 INFO - 'MOZ_NO_REMOTE': '1', 03:01:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:08 INFO - 'NO_EM_RESTART': '1', 03:01:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:08 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:08 INFO - 'SHELL': '/bin/bash', 03:01:08 INFO - 'SHLVL': '1', 03:01:08 INFO - 'TERM': 'linux', 03:01:08 INFO - 'TMOUT': '86400', 03:01:08 INFO - 'USER': 'cltbld', 03:01:08 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 03:01:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:08 INFO - '_': '/tools/buildbot/bin/python'} 03:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 03:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 03:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 03:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 03:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 03:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 03:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 03:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 03:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 9)) 03:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 03:01:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 03:01:09 INFO - Cleaning up... 03:01:09 INFO - Return code: 0 03:01:09 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 03:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88513fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2980230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a127f0>, '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 0x297e2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297e760>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 03:01:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:01:09 INFO - Using env: {'DISPLAY': ':0', 03:01:09 INFO - 'HOME': '/home/cltbld', 03:01:09 INFO - 'LANG': 'en_US.UTF-8', 03:01:09 INFO - 'LANGUAGE': 'en_US:en', 03:01:09 INFO - 'LOGNAME': 'cltbld', 03:01:09 INFO - 'MAIL': '/var/mail/cltbld', 03:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:09 INFO - 'MOZ_NO_REMOTE': '1', 03:01:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:09 INFO - 'NO_EM_RESTART': '1', 03:01:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:09 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:09 INFO - 'SHELL': '/bin/bash', 03:01:09 INFO - 'SHLVL': '1', 03:01:09 INFO - 'TERM': 'linux', 03:01:09 INFO - 'TMOUT': '86400', 03:01:09 INFO - 'USER': 'cltbld', 03:01:09 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 03:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:09 INFO - '_': '/tools/buildbot/bin/python'} 03:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:09 INFO - Downloading/unpacking jsonschema 03:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:01:11 INFO - Downloading/unpacking functools32 (from jsonschema) 03:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:11 INFO - Downloading functools32-3.2.3-2.tar.gz 03:01:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 03:01:11 INFO - warning: no files found matching '*.txt' 03:01:11 INFO - no previously-included directories found matching 'build' 03:01:11 INFO - no previously-included directories found matching 'dist' 03:01:11 INFO - no previously-included directories found matching '.git*' 03:01:11 INFO - Installing collected packages: jsonschema, functools32 03:01:11 INFO - Running setup.py install for functools32 03:01:11 INFO - warning: no files found matching '*.txt' 03:01:11 INFO - no previously-included directories found matching 'build' 03:01:11 INFO - no previously-included directories found matching 'dist' 03:01:11 INFO - no previously-included directories found matching '.git*' 03:01:11 INFO - Successfully installed jsonschema functools32 03:01:11 INFO - Cleaning up... 03:01:11 INFO - Return code: 0 03:01:11 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 03:01: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')]} 03:01:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:01:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01: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')]} 03:01:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:01:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:01:11 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88513fcf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2980230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a127f0>, '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 0x297e2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297e760>, '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': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:01:11 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 03:01:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:01:11 INFO - Using env: {'DISPLAY': ':0', 03:01:11 INFO - 'HOME': '/home/cltbld', 03:01:11 INFO - 'LANG': 'en_US.UTF-8', 03:01:11 INFO - 'LANGUAGE': 'en_US:en', 03:01:11 INFO - 'LOGNAME': 'cltbld', 03:01:11 INFO - 'MAIL': '/var/mail/cltbld', 03:01:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:11 INFO - 'MOZ_NO_REMOTE': '1', 03:01:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:11 INFO - 'NO_EM_RESTART': '1', 03:01:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:11 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:11 INFO - 'SHELL': '/bin/bash', 03:01:11 INFO - 'SHLVL': '1', 03:01:11 INFO - 'TERM': 'linux', 03:01:11 INFO - 'TMOUT': '86400', 03:01:11 INFO - 'USER': 'cltbld', 03:01:11 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 03:01:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:11 INFO - '_': '/tools/buildbot/bin/python'} 03:01:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:01:12 INFO - Downloading/unpacking flake8 03:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:01:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:01:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:01:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:01:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:01:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 03:01:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:01:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:01:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:01:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:01:14 INFO - no previously-included directories found matching 'docs/_build' 03:01:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:01:14 INFO - Running setup.py install for pep8 03:01:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:01:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:01:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:01:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:01:14 INFO - no previously-included directories found matching 'docs/_build' 03:01:14 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 03:01:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:01:14 INFO - Cleaning up... 03:01:14 INFO - Return code: 0 03:01:14 INFO - Running post-action listener: _resource_record_post_action 03:01:14 INFO - Running post-action listener: _start_resource_monitoring 03:01:14 INFO - Starting resource monitoring. 03:01:14 INFO - ##### 03:01:14 INFO - ##### Running install step. 03:01:14 INFO - ##### 03:01:14 INFO - Running pre-action listener: _resource_record_pre_action 03:01:14 INFO - Running main action method: install 03:01:14 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 03:01:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 03:01:14 INFO - Reading from file tmpfile_stdout 03:01:15 INFO - Detecting whether we're running mozinstall >=1.0... 03:01:15 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 03:01:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 03:01:15 INFO - Reading from file tmpfile_stdout 03:01:15 INFO - Output received: 03:01:15 INFO - Usage: mozinstall [options] installer 03:01:15 INFO - Options: 03:01:15 INFO - -h, --help show this help message and exit 03:01:15 INFO - -d DEST, --destination=DEST 03:01:15 INFO - Directory to install application into. [default: 03:01:15 INFO - "/builds/slave/test-pgo"] 03:01:15 INFO - --app=APP Application being installed. [default: firefox] 03:01:15 INFO - mkdir: /builds/slave/test-pgo/build/application 03:01:15 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 03:01:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 03:01:30 INFO - Reading from file tmpfile_stdout 03:01:30 INFO - Output received: 03:01:30 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 03:01:30 INFO - Running post-action listener: _resource_record_post_action 03:01:30 INFO - ##### 03:01:30 INFO - ##### Running run-tests step. 03:01:30 INFO - ##### 03:01:30 INFO - Running pre-action listener: _resource_record_pre_action 03:01:30 INFO - Running main action method: run_tests 03:01:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 03:01:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 03:01:30 INFO - Python 2.7.3 03:01:30 INFO - Return code: 0 03:01:30 INFO - grabbing minidump binary from tooltool 03:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:01:30 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 0x2a127f0>, '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 0x297e2d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x297e760>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 03:01:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 03:01:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:01:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:01:30 INFO - Return code: 0 03:01:30 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 03:01:30 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 03:01:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 03:01:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 03:01:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 03:01:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 03:01:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 03:01:31 INFO - Return code: 0 03:01:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 03:01:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-002 --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 03:01:31 INFO - Using env: {'DISPLAY': ':0', 03:01:31 INFO - 'HOME': '/home/cltbld', 03:01:31 INFO - 'LANG': 'en_US.UTF-8', 03:01:31 INFO - 'LANGUAGE': 'en_US:en', 03:01:31 INFO - 'LOGNAME': 'cltbld', 03:01:31 INFO - 'MAIL': '/var/mail/cltbld', 03:01:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:01:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 03:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:01:31 INFO - 'MOZ_NO_REMOTE': '1', 03:01:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 03:01:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:01:31 INFO - 'NO_EM_RESTART': '1', 03:01:31 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 03:01:31 INFO - 'PWD': '/builds/slave/test-pgo', 03:01:31 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 03:01:31 INFO - 'SHELL': '/bin/bash', 03:01:31 INFO - 'SHLVL': '1', 03:01:31 INFO - 'TERM': 'linux', 03:01:31 INFO - 'TMOUT': '86400', 03:01:31 INFO - 'USER': 'cltbld', 03:01:31 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612', 03:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:01:31 INFO - '_': '/tools/buildbot/bin/python'} 03:01:31 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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 03:01:31 INFO - mozversion application_buildid: 20160222065616 03:01:31 INFO - mozversion application_changeset: 0c7dfc6d57d70816a6b07393f67f8b3747e6486d 03:01:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:01:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:01:31 INFO - mozversion application_name: Firefox 03:01:31 INFO - mozversion application_remotingname: firefox-dev 03:01:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:01:31 INFO - mozversion application_vendor: Mozilla 03:01:31 INFO - mozversion application_version: 46.0a2 03:01:31 INFO - mozversion platform_buildid: 20160222065616 03:01:31 INFO - mozversion platform_changeset: 0c7dfc6d57d70816a6b07393f67f8b3747e6486d 03:01:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:01:31 INFO - mozversion platform_version: 46.0a2 03:01:31 INFO - using testdate: 1456570891 03:01:31 INFO - actual date: 1456570891 03:01:31 INFO - starting webserver on 'localhost:57228' 03:01:31 INFO - SUITE-START | Running 3 tests 03:01:31 INFO - TEST-START | dromaeo_css 03:01:31 INFO - operating with platform_type : linux_ 03:01:31 INFO - Initialising browser for dromaeo_css test... 03:01:31 INFO - TEST-INFO | started process 14797 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpd5hOKA/profile http://localhost:57228/getInfo.html) 03:01:35 INFO - PROCESS | 14797 | __metrics Screen width/height:1600/1200 03:01:35 INFO - PROCESS | 14797 | colorDepth:24 03:01:35 INFO - PROCESS | 14797 | Browser inner width/height: 1024/697 03:01:35 INFO - PROCESS | 14797 | __metrics 03:01:36 INFO - PROCESS | 14797 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:01:37 INFO - TEST-INFO | 14797: exit 0 03:01:37 INFO - Browser initialized. 03:01:37 INFO - Running cycle 1/1 for dromaeo_css test... 03:01:37 INFO - TEST-INFO | started process 14885 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpd5hOKA/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:01:48 INFO - PROCESS | 14885 | RSS: Main: 137277440 03:01:48 INFO - PROCESS | 14885 | 03:02:39 INFO - PROCESS | 14885 | 1456570959597 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:04:47 INFO - PROCESS | 14885 | Cycle 1(1): loaded http://localhost:57228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57228/tests/dromaeo/cssquery-ext.html) 03:04:48 INFO - PROCESS | 14885 | RSS: Main: 228470784 03:04:48 INFO - PROCESS | 14885 | 03:07:50 INFO - PROCESS | 14885 | Cycle 1(1): loaded http://localhost:57228/tests/dromaeo/cssquery-ext.html (next: http://localhost:57228/tests/dromaeo/cssquery-jquery.html) 03:07:51 INFO - PROCESS | 14885 | RSS: Main: 213946368 03:07:51 INFO - PROCESS | 14885 | 03:10:53 INFO - PROCESS | 14885 | Cycle 1(1): loaded http://localhost:57228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57228/tests/dromaeo/cssquery-mootools.html) 03:10:53 INFO - PROCESS | 14885 | RSS: Main: 202788864 03:10:53 INFO - PROCESS | 14885 | 03:13:55 INFO - PROCESS | 14885 | Cycle 1(1): loaded http://localhost:57228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57228/tests/dromaeo/cssquery-prototype.html) 03:13:56 INFO - PROCESS | 14885 | RSS: Main: 199172096 03:13:56 INFO - PROCESS | 14885 | 03:16:58 INFO - PROCESS | 14885 | Cycle 1(1): loaded http://localhost:57228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57228/tests/dromaeo/cssquery-yui.html) 03:16:59 INFO - PROCESS | 14885 | RSS: Main: 201895936 03:16:59 INFO - PROCESS | 14885 | 03:20:01 INFO - PROCESS | 14885 | Cycle 1(1): loaded http://localhost:57228/tests/dromaeo/cssquery-yui.html (next: http://localhost:57228/tests/dromaeo/cssquery-dojo.html) 03:20:01 INFO - PROCESS | 14885 | RSS: Main: 244731904 03:20:01 INFO - PROCESS | 14885 | 03:20:01 INFO - PROCESS | 14885 | __start_tp_report 03:20:01 INFO - PROCESS | 14885 | _x_x_mozilla_page_load 03:20:01 INFO - PROCESS | 14885 | _x_x_mozilla_page_load_details 03:20:01 INFO - PROCESS | 14885 | |i|pagename|runs| 03:20:01 INFO - PROCESS | 14885 | |0;dojo.html;3193.169775858277;3232.6120865496146;3261.8205998670064;3266.0528446750427;3291.9012429627114;5150.356205474316;5158.587313014959;5171.612129090315;5184.974075129629;5217.92173117403;5171.612129090324;5173.896522069556;5202.843914682556;5215.191645294976;5220.26916231727;4665.043666048452;4710.434747830265;4744.501827308135;4750.334953106568;4784.210605250129;4716.386869706933;4724.078804633098;4741.336212930192;4767.42790865095;4769.52304769522;4683.367745354385;4746.667733258673;4753.762311884409;4762.6428017898625;4773;4779.091972525209;4804.471508134103;4806.086843499724;4806.255030470265;4830.2271636538335;4610.446746390421;4617.445906491209;4687.5781179693995;4723.055388922195;4729.243321068647;4947.529984651453;4955.083309587738;4955.578775804061;4957.603391728653;4973.254011898244;5456.61803673743;5457.918131228034;5463.153211252223;5475.342958844924;5488.259085023511;5361.169018802053;5467.234587157801;5489.066858634042;5544.7227638618315;5626.971865140689;5176.223566465061;5187.170052791573;5194.428612852583;5211.166213405875;5212.58299336056;5599;5630.634008789416;5631.577631677641;5634.859128521738;5638.295213098363;5347.67913925158;5390.811321603725;5400.702961337164;5418.83743487696;5461.017016936989;5489.64317319373;5510.834674959758;5512.035393806068;5515.586331025109;5516.586256030733;5433.375161856437;5475.479829416199;5485.451454854483;5490.313710786152;5529.889402212014;5443.237946687467;5447.863803404946;5522.972385138049;5526.613137080366;5528.502434780889;4239.512456067591;4240.406343111967;4243.893902652459;4265.978670106691;4267;3812.618738126165;3872.0513474199;3874.089588946595;3879.515060617423;3896.2597106550234;5185.844424667266;5208.088584497774;5235.267062611238;5236.607254455855;5260.053190425683;5488.341399032141;5549.361823390362;5553.08374118266;5556.055470570012;5597.412271711411;5120.129577971754;5155.845324640267;5222.086134926372;5247.055530004636;5266;4760.619150467955;4809.062232864558;4822.397200349957;4826.927596086061;4853.4418541867435;5619.072852979294;5643.887122257532;5662.235598194274;5666.886662266732;5698.259226300637;5423.132298832168;5510.283663123768;5528.889422211614;5534.4742249486235;5536.557075433875;5651.265335506522;5660.9150862737915;5665.915011274752;5677.120046392815;5699.145128230799;5459.7270136492225;5466.535344495604;5470.370907345547;5474.233607294902;5478.561715062706;5449.7002664852225;5495.862603434786;5520.558355331644;5525.778968841201;5564.304461942257;5419.295491586068;5419.674819510842;5426.430224826337;5427.05026620332;5428.918566221589;5359.276497672764;5416.079266524779;5420.83737487876;5421.83734487966;5449.1826226066405;5203.869903252449;5319.654222475527;5324.6006549508165;5344.919826202532;5413.160960051198;5381.650192737616;5407.918881216706;5411.431799660979;5420.430854760193;5424.783008679608;5308.362996440452;5309.097453432849;5313.017091838023;5313.043652142652;5333.4133245343755 03:20:01 INFO - PROCESS | 14885 | |1;ext.html;538.0261726275439;560.9242752228448;621.2669050520384;633.0820310549702;654.6464908949167;31314;38807.80596097019;39057.804710976474;39109.60890391095;39151.21697566047;41257.38113928294;41711.58288417115;41796.58203417965;41882.79058604703;41903.37144942828;7774.494657847222;7881.645325960317;8638.870416943735;8860.379773415863;9853.704388868346;9984.550695218733;10107.79784404315;10142.543585538631;10142.645007424742;10145.594176232942;10131.088202061814;10221.693349199535;10224.437655928921;10227.539760710786;10227.846582301272;9976.451295178762;10050.698479045604;10114;10132.442715650635;10134.341267817568;10062.144717699004;10221;10229.283950123494;10238.7440313992;10581.629642962536;42471.36292955608;42908.57091429077;42934.141317173795;43006.56993430057;43022.139557208684;3479.982600087009;3513.0339156731848;3700.8889733307774;3820.8853734388013;4150.64719498843;31535.684643153505;31809.681903180903;32069.679303206904;32201.838990804896;32261.838690806395;4001.4397984282223;4080.6123418275215;4105.876823695264;4107.178564287125;4328.394024836525;339369;342030;343415;343488;343711;58059.41940580625;58411;59076;62831;64081;57730.42269577335;57838.42161578331;57892.42107578955;57955.71022144862;58199.70900145472;17253.482395528154;17450.47648570545;17458.301667933396;17508.299668013136;17509.212085456184;13460.528881489101;13513.256770877499;13531.255780932142;13534;13684.589462316146;20043.298484553263;21251.149954001667;21285.82927938978;21330.573388532142;21406.25078122258;8304.626291816883;8675.6529738811;8708.608112634947;8768.824623507495;8818;1116.2576886370634;1117.2179474367974;1163.7032556697989;1198.8261702053153;1199.2145144930084;30493.695063049086;30791.2302192447;30805;32299.51550726741;34987;1186.4423720851187;1196.2642974570751;1223.6451429085644;1291.7739395605731;1336.6725152337544;13075.346232688327;15568;15656.37374505007;15985.920070399574;17048.744268835977;341589;345407;346166;346313;347876;56787.71606142025;57360.71319643375;57394.13908791372;57410.71294643584;57509.42490575124;1346.1855577375743;1373.0731756064815;1477.1358755128156;1480.7556753135611;1524.3445318513145;27812.30469238285;27889.860550697118;28247.15258542247;31140.53289200709;31580.526292106097;47517.76241118772;54024;54740.452595473536;54743.45256547384;54805;323791;434611;442876;444618;445393;43917.341239882066;44631.33053004143;44951.10097798091;45019.549804501534;45939;49289.260661090826;49612.75193624153;49639.75180124076;50359;50671.23993140032;39045.46881775107;40436;40605.37578496827;40755.59244407518;40820.79589602152;37198.25603487861;37263.62736372602;37966.81016594899;38162.42756358601;38509.2298154041;39874.800625996686;40029;40241.19517609573;40417;40484.3927341096;33957;34287.82856085704;35510.64489355177;36120.27759444744;39530.60469395269;25886.787171078427;26072.8696356517;26193.869030654725;26245.081422150895;26444.338891527867 03:20:01 INFO - PROCESS | 14885 | |2;jquery.html;397.4554859842017;398.7189031732629;399.39292275740877;399.5046142782948;404.3267958848517;14920.179390133539;15354;17466.73799893001;17963.640727185448;17994.370197043107;11278.492467838947;11672.533098676044;16712.2479488423;17965.7305140423;17991;5020.774065167067;5344.4922732340365;5346.304980352568;5350.384705758829;5367.543758780508;3974.3442332015184;4094.0379010932406;5280.392754833205;5317.7607007684555;5548.55611551075;5407.080796264644;5477.287952566181;5485.040117979358;5514.779408823662;5515.420880807517;5528.530074943635;5560.110382338807;5563.972180139093;5570.944290557083;5573.832785016436;5254.106801843695;5377.247185394048;5695.77216911323;5695.832354367345;5696.117101849179;20758.273460428813;20860.269890553758;21298.78701212999;21488;21536;2285.3258288804677;2298.712660917385;2392.7966122879575;2393.56915755164;2401.387646150238;9848.359856609297;9904.306698531105;10162;12371.690707732289;12824.422900969479;1848.9815101848944;1870.8222718841823;1881.482592287132;1916.252661462031;1951.7950615185484;58284.41715582832;59071.11393329103;60185.699071504365;60368.99999999956;64488.67755661192;15215.163166025975;15606.375744970073;16104.677906441806;16265.186740662919;16267.349306027627;24988.375290617518;25078.623820642704;25240.368990775376;25286.87356563242;25429;9624.75938101539;9943.651972180938;9964.153046990941;10006.69979900604;10271.589136434608;8497.575121243914;8526.445781024338;8556.272716819016;8629.8705519416;8646.351523635753;6830.749515969497;7959.721409750631;8110.188981101961;8113.716019939392;8226.095129535746;1923.2691577200853;1951.8926459044606;1956.3935180094215;1958;1977.861549691508;974.64425484699;1041.1514615588271;1044.7597052677927;1137.3460260350332;1180.6753142885775;17331.65336693259;17349.2192851322;17452.912735436243;17588.296468141387;18150.183241754152;991.861139440479;1008.5259927833906;1013.2653825976256;1018.7249442650443;1102.388174563109;9212.309076819265;9513.238940884712;10148.949255253825;10531.631392901365;10591.364518128938;413325;414663;417037;529758;631610;49120.754396228505;50327;50379.49620503822;80996.19003810005;82142.17857821465;521.0828941063679;537.532346858228;539.1508374310476;540.4676393752229;1208.6917835951776;10529;11003.504842281956;11022.724431889266;11090.223684342018;11202.551897923991;31314.373712525165;31776.046718598478;31933;32479.350412991134;34758.47862282017;36679.08302292464;37760;39494;42884.78557607192;44694.77652611716;7757.340626046624;7992.8001799953145;8072.5963701815845;8132.2680958714;8194.754157375288;10638.308509946828;11794.46924888365;11831.40842957866;11866.110041746731;12010.159288849696;3023.380207057592;4807.533702220716;4975.751212439436;10089.848652270075;10245.948770256102;9242.537873106452;9960.55177517028;10070.44612546317;10210.387376757419;10473.266871318934;10052.246081544059;10199.694009179737;10355.792884142425;10556;10571.735706607089;7702.884456733265;7844.843103137791;7875.330596899099;7877.921220787719;7887.211278872296;7491.6096153560065;9463.810723785347;9805.460699661588;10108.191344692259;10180.236482264008 03:20:01 INFO - PROCESS | 14885 | |3;mootools.html;1421.8507056759047;1573.4178354009018;1576.2906691988605;1777.697791375466;1878.8684792064562;7098.183708873481;7478;7629.732959346424;7661.348785353238;7675.04061992251;7685.92314076859;7849.764507064783;7853.175416581263;7881.92118078819;7883.251091146332;2044.2845004248543;2096.2348742708878;2105.2421128393767;2355.975150809399;2382.4877651304964;2421.927342179729;2489.8755062246814;2491.8878650460774;2494.326531836403;2496.6754321938215;2296.44885227545;2307.9423014424688;2323.779240972105;2325.093213646671;2333.1017558240114;2345.2495201535603;2346.3195673254736;2356.4697942962866;2375.97624023761;2378.5242951409787;2369.9526009479714;2386.331827088418;2407.1454633604994;2414.2757172848073;2415.7342692303832;7935.603219838985;7976.800579985489;7995.200479951958;7999.0801057877925;8002.159773223844;4982.975085124587;5073.898522029539;5091.338126043628;5091.61812864033;5098.4136824265315;3186.123815950609;3232.5151227315855;3251.9837400812844;3273.4598791199423;3277.96722032779;1159.2928313728619;1163.4241050679882;1174.6358628825005;1180.4923882730423;1196.521391443421;10820;10854.620088296871;10859.945700271448;10876.238663293572;10922.126229901589;4519.983003824179;4537.909241815145;4605.332226827091;4606.032733126014;4609.170349337084;9347.672831450867;9351.064893510595;9375.10936461035;9390.718278451657;9397.530123493798;5006.324146240284;5010.123228435009;5015.14742493777;5020.874478138076;5072.898542029213;4547.8635640930825;4614.100250451197;4616.053708989649;4616.446026476844;4618.122556714214;3646.3801153803915;3699.8705045323286;3799.0502374406396;3838;3888.3972984187494;1268.6574624851423;1280.014388920526;1281.404147071616;1283.6341642631805;1294.501616877509;1346.9730605387838;1355.1462578575631;1356.389624668903;1357.8370595528402;1380.7721725915112;7241.8551628967125;7326.743563975236;7350.485466017434;7362.153352364439;7378.335949764547;1134.0530656901415;1139.658102569226;1147.3058799426394;1147.8048731715628;1148.2306854407566;5943.078822782476;6061.121137435047;6063;6064.545159113084;6067.362926892701;9212.078792120868;9800.55897484615;9818.361806482555;9913.603455861685;9979.900200998043;9721.416714997124;9857.35927164732;9881.604735810632;9906.05892440217;9922.652707155215;7482.775516734507;7784.766457006299;7927.801804954807;7929.32600728933;7937.563434011185;7247.528910620793;7295.8540829184185;7320.450966177452;7338;7340.155882073416;8327.50034997902;8707.129287071242;8755.824883502168;8765.07966663491;8976.775580610538;8335.416520843726;8490.490570565786;8548.871766923377;8590.65637374498;8646.394752367274;7342.9632851835395;7359.484836061424;7438.033055702724;7445.143808462096;7446.813829654302;6900.2409734930125;6919.550229235084;7040.155181378267;7086.078809754905;7095.361417472453;6537.084808126961;6671.5997040177745;6688.3646053624825;6822.3859852613505;6980.755673551402;6678.131842860397;6705.865882682417;6718.328167183243;6799.184097908283;6832.863342733217;6690.13028306317;6761.898571521333;6765.985102234507;6766.357196066366;6766.93233067663;6380.202474690664;6389.233292004989;6404.967975160094;6423.261324947691;6475.3200913903365;6439.259485159267;6481.513886458629;6490.480761538971;6498.902516462163;6501.414872661483 03:20:01 INFO - PROCESS | 14885 | |4;prototype.html;374.3766628563442;379.4119115371174;382.12112142073244;385.9845606175753;389.02549114468263;5917.408259174081;6079.78720744774;6087.7260523276445;6108.175396321501;6145.846353841156;5870.88258234835;6073.301570319414;6092.939070609293;6094.51243900488;6100.664463454508;4796.232602783547;4805.351277577517;4806.110869489138;4811.037792441508;4811.975940120295;4578.290364993415;4651.465081515636;4712.976435117819;4733.171694953387;4798.616110711135;4655.231886738701;4672.672912896099;4692.249240121581;4699.318598803172;4731;4732.716037037789;4744.83393081244;4747.83382581608;4755.144074066667;4761.666683332168;4231.534531201596;4235.57644235577;4240.533541310453;4242.8727138185905;4243.6605071594195;5675.659460432387;5800.941990580082;5813.912791308134;5821.53427725781;5824.30108386992;5574.331080270394;5590.636608620467;5595.860103497404;5600.27196464457;5604.215409842625;6493.772717954849;6513.641749703765;6513.837154071139;6518.478521718251;6522.825891339557;4504.977475112636;4693.178693728618;4713.505081966378;4725.527447255281;4728.196206644844;6452.709628066701;6572.211334639826;6661;6757.898631520531;6768.018637297564;4763.8332658357485;4852.56326930578;4876.731779752147;4879.878003049881;4881.780319885614;4932.778024988884;4941.332920055818;4943.110240156734;4947.529984651453;4947.6289278304275;5492.80775172867;5547.084731019418;5581.916271255935;5588.993981083363;5595.944040559623;6530.869382612416;6545.345465453416;6551.705173267214;6553.017047442826;6553.508486863504;1714.9399771007954;1717.2444124585143;1717.2444124585143;1717.4246627379969;1718.2954988454674;1205.3973013493253;1213.2962881528692;1224.3327386574338;1228.686684895346;1232.6117273059137;6266.467350275187;6281.120643109968;6293.056041593706;6300.30696623371;6301;6637.86724265512;6691.297413771581;6700.765473208415;6706.396424321834;6722.193336799615;6214.5960512566535;6301.558890877594;6302.653354065479;6306.1171435998995;6307.96846015767;3639.490471334015;3644.9635503645154;3677.889663310105;3682.9447558286647;3683.2265224303196;6977.790666279918;7016;7016.36852683261;7020.92979070213;7027.262137475594;6811.591304521646;6920.792376228721;6977.89533157003;6983.755568555076;6991.405730512862;6993.160820701447;6996.475264355194;7005.124359455068;7009.894851577231;7021.122359705037;6782.525223234326;6785.287544807724;6788.796336109925;6794.116764820542;6794.728210871707;6478.093066970722;6682.465402767864;6692.59844409337;6710.6309152997055;6713.12729345182;6981;6988.6505674715045;6989.790306290819;6993.545419547713;6993.615351155532;4781.665283430127;4825.782839772289;4826.372571565674;4826.710397376169;4829.323894654822;6660.733570657119;6786.016027676058;6789.219239787488;6793.864122717617;6795.7961261162245;6619.569727967667;6629.171353580802;6632.436242919406;6633.170853643294;6644.169478815148;6602.60384376939;6605.5376123670885;6607.108040414482;6613.966930165318;6623.966880165568;6347.016212487072;6486.772962946275;6493.383128602775;6495.545311828316;6500.252470965899;6640.601563906181;6641.933580664325;6643.50173736962;6644.335566443317;6653.667316634246;6476.384743449365;6485.675716214265;6491.870162596816;6492.740290388331;6502.057201705821 03:20:01 INFO - PROCESS | 14885 | |5;yui.html;727.9672414741336;733.6808488307587;770.3836930455635;807.931325837304;831.3889291370841;14205.71588568228;14475.13149211048;14476.420943162275;14480.348384322704;14556.49052283169;13536.323183840794;13618.863811361884;13620.114692544978;13658.93170534146;13681.521146759866;734.4968696442927;739.6301849075462;883.4964070479829;899.0829354058873;902.0933961368816;908.4413085952158;912.0013585124311;915.6612053540199;915.7847905742145;915.8671992561075;959.203860795538;961.27423795035;961.4952150121208;961.9182369498601;962.5860879821674;791.1416113516813;798.5927177139672;928.5682157796657;947.1948843482984;948.5826236456006;702.1223470661673;702.8348338140532;703.2159142556013;703.3353480960494;703.6165289917008;13553.186808791505;13613.455461781616;13616.251106189156;13624.659383515393;13630.863691363158;13309.467621295233;13762.793558096637;13788.103773254807;13895.235762033084;13966.58100256994;10704.946475267652;10813.18901082422;10858.674239772741;10954.287971281841;11018.283811552223;3797.487339209227;3821.445890345912;3837.4052021936645;3838.7888666123354;3840.347140986039;48228.51771482275;48484.27273590899;48529;48844.267335989985;49040;10837.18721095921;11048.558057677763;11116.166287528305;11270.54917803295;11281.492332845042;18921.81078189228;19005.04974751257;19050.61898762017;19095.522611934535;19119.80880191208;7916.327112195413;8023.839523209504;8142.267195952274;8170.101288858221;8331.6667333306;4056.6957478189256;4122.361034039729;4147.440095587118;4189.2459357315365;4214.283571792803;3118.4075025745037;3146.6695996920453;3152.0386282183695;3159.4313023655504;3164.477861152884;521.6791673121079;532.7309708597211;536.5814664561648;539.7058603061341;541.5748637319764;562.7580140539526;575.466634861948;580.3247031186228;694.982625434358;695.5556766557745;9361.157495825408;9471.573779179816;9477.668281610111;9628.364885374192;9752.512374381253;550.3423409026175;565.191775760658;592.5082181789105;628.9088082228051;703.929607039292;4329.264025115738;4693.624510039189;4719.197736384823;4847.563719265283;4898.730569818694;58702;60106.69946650239;61171.38828611746;63072.68463657652;63211.68394158;54634.45365546294;55484.16773748397;55783.442165578635;56129;56509.71745141248;48727.756361217966;48968.51031489711;49038.509614904106;49820.750896245285;50099;9436.480993545421;9630.47032413224;9662.75843103928;9697.709068727949;9776.804463910825;35553.288934220654;35635.10912227215;36502;37232;37589.624103758615;43975.560244398424;46107;47595.524044759106;47995.76002119967;48843.26735098905;11360.659180224606;11920.821187682019;11940.283582985048;11992.220505667105;12045.33750643723;13380.397882095134;13549.93225033908;13571.932140339235;13631.113977591425;13780.448782048607;10671.092957098423;10672.57309707603;11293.096552275634;11381.658550243506;11393.316401015965;12455.750884982432;12701.93649031749;12888.806667900177;13198.472061117833;13389.19664820114;11194.328340299608;12065.336406497376;12321.815172772236;13279;13800.861991379958;10013.449260290754;10105.39367637944;10245.538950747383;10314.27800053989;10317.036000699647;9665.758356041155;9815.509224538602;10724.946375268073;10757.462126893468;10829.24195306321 03:20:01 INFO - PROCESS | 14885 | __end_tp_report 03:20:01 INFO - PROCESS | 14885 | __start_cc_report 03:20:01 INFO - PROCESS | 14885 | _x_x_mozilla_cycle_collect,763 03:20:01 INFO - PROCESS | 14885 | __end_cc_report 03:20:01 INFO - PROCESS | 14885 | __startTimestamp1456572001874__endTimestamp 03:20:01 INFO - PROCESS | 14885 | 03:20:01 INFO - PROCESS | 14885 | ------- Summary: start ------- 03:20:01 INFO - PROCESS | 14885 | Number of tests: 6 03:20:01 INFO - PROCESS | 14885 | 03:20:01 INFO - PROCESS | 14885 | [#0] dojo.html Cycles:165 Average:5138.36 Median:5333.41 stddev:532.58 (10.0%) stddev-sans-first:511.89 03:20:01 INFO - PROCESS | 14885 | Values: 3193.2 3232.6 3261.8 3266.1 3291.9 5150.4 5158.6 5171.6 5185.0 5217.9 5171.6 5173.9 5202.8 5215.2 5220.3 4665.0 4710.4 4744.5 4750.3 4784.2 4716.4 4724.1 4741.3 4767.4 4769.5 4683.4 4746.7 4753.8 4762.6 4773.0 4779.1 4804.5 4806.1 4806.3 4830.2 4610.4 4617.4 4687.6 4723.1 4729.2 4947.5 4955.1 4955.6 4957.6 4973.3 5456.6 5457.9 5463.2 5475.3 5488.3 5361.2 5467.2 5489.1 5544.7 5627.0 5176.2 5187.2 5194.4 5211.2 5212.6 5599.0 5630.6 5631.6 5634.9 5638.3 5347.7 5390.8 5400.7 5418.8 5461.0 5489.6 5510.8 5512.0 5515.6 5516.6 5433.4 5475.5 5485.5 5490.3 5529.9 5443.2 5447.9 5523.0 5526.6 5528.5 4239.5 4240.4 4243.9 4266.0 4267.0 3812.6 3872.1 3874.1 3879.5 3896.3 5185.8 5208.1 5235.3 5236.6 5260.1 5488.3 5549.4 5553.1 5556.1 5597.4 5120.1 5155.8 5222.1 5247.1 5266.0 4760.6 4809.1 4822.4 4826.9 4853.4 5619.1 5643.9 5662.2 5666.9 5698.3 5423.1 5510.3 5528.9 5534.5 5536.6 5651.3 5660.9 5665.9 5677.1 5699.1 5459.7 5466.5 5470.4 5474.2 5478.6 5449.7 5495.9 5520.6 5525.8 5564.3 5419.3 5419.7 5426.4 5427.1 5428.9 5359.3 5416.1 5420.8 5421.8 5449.2 5203.9 5319.7 5324.6 5344.9 5413.2 5381.7 5407.9 5411.4 5420.4 5424.8 5308.4 5309.1 5313.0 5313.0 5333.4 03:20:01 INFO - PROCESS | 14885 | 03:20:01 INFO - PROCESS | 14885 | [#1] ext.html Cycles:180 Average:54533.53 Median:19910.58 stddev:97551.82 (489.9%) stddev-sans-first:97741.23 03:20:01 INFO - PROCESS | 14885 | Values: 538.0 560.9 621.3 633.1 654.6 31314.0 38807.8 39057.8 39109.6 39151.2 41257.4 41711.6 41796.6 41882.8 41903.4 7774.5 7881.6 8638.9 8860.4 9853.7 9984.6 10107.8 10142.5 10142.6 10145.6 10131.1 10221.7 10224.4 10227.5 10227.8 9976.5 10050.7 10114.0 10132.4 10134.3 10062.1 10221.0 10229.3 10238.7 10581.6 42471.4 42908.6 42934.1 43006.6 43022.1 3480.0 3513.0 3700.9 3820.9 4150.6 31535.7 31809.7 32069.7 32201.8 32261.8 4001.4 4080.6 4105.9 4107.2 4328.4 339369.0 342030.0 343415.0 343488.0 343711.0 58059.4 58411.0 59076.0 62831.0 64081.0 57730.4 57838.4 57892.4 57955.7 58199.7 17253.5 17450.5 17458.3 17508.3 17509.2 13460.5 13513.3 13531.3 13534.0 13684.6 20043.3 21251.1 21285.8 21330.6 21406.3 8304.6 8675.7 8708.6 8768.8 8818.0 1116.3 1117.2 1163.7 1198.8 1199.2 30493.7 30791.2 30805.0 32299.5 34987.0 1186.4 1196.3 1223.6 1291.8 1336.7 13075.3 15568.0 15656.4 15985.9 17048.7 341589.0 345407.0 346166.0 346313.0 347876.0 56787.7 57360.7 57394.1 57410.7 57509.4 1346.2 1373.1 1477.1 1480.8 1524.3 27812.3 27889.9 28247.2 31140.5 31580.5 47517.8 54024.0 54740.5 54743.5 54805.0 323791.0 434611.0 442876.0 444618.0 445393.0 43917.3 44631.3 44951.1 45019.5 45939.0 49289.3 49612.8 49639.8 50359.0 50671.2 39045.5 40436.0 40605.4 40755.6 40820.8 37198.3 37263.6 37966.8 38162.4 38509.2 39874.8 40029.0 40241.2 40417.0 40484.4 33957.0 34287.8 35510.6 36120.3 39530.6 25886.8 26072.9 26193.9 26245.1 26444.3 03:20:01 INFO - PROCESS | 14885 | 03:20:01 INFO - PROCESS | 14885 | [#2] jquery.html Cycles:180 Average:26310.01 Median:37219.54 stddev:79867.05 (214.6%) stddev-sans-first:80067.39 03:20:01 INFO - PROCESS | 14885 | Values: 397.5 398.7 399.4 399.5 404.3 14920.2 15354.0 17466.7 17963.6 17994.4 11278.5 11672.5 16712.2 17965.7 17991.0 5020.8 5344.5 5346.3 5350.4 5367.5 3974.3 4094.0 5280.4 5317.8 5548.6 5407.1 5477.3 5485.0 5514.8 5515.4 5528.5 5560.1 5564.0 5570.9 5573.8 5254.1 5377.2 5695.8 5695.8 5696.1 20758.3 20860.3 21298.8 21488.0 21536.0 2285.3 2298.7 2392.8 2393.6 2401.4 9848.4 9904.3 10162.0 12371.7 12824.4 1849.0 1870.8 1881.5 1916.3 1951.8 58284.4 59071.1 60185.7 60369.0 64488.7 15215.2 15606.4 16104.7 16265.2 16267.3 24988.4 25078.6 25240.4 25286.9 25429.0 9624.8 9943.7 9964.2 10006.7 10271.6 8497.6 8526.4 8556.3 8629.9 8646.4 6830.7 7959.7 8110.2 8113.7 8226.1 1923.3 1951.9 1956.4 1958.0 1977.9 974.6 1041.2 1044.8 1137.3 1180.7 17331.7 17349.2 17452.9 17588.3 18150.2 991.9 1008.5 1013.3 1018.7 1102.4 9212.3 9513.2 10148.9 10531.6 10591.4 413325.0 414663.0 417037.0 529758.0 631610.0 49120.8 50327.0 50379.5 80996.2 82142.2 521.1 537.5 539.2 540.5 1208.7 10529.0 11003.5 11022.7 11090.2 11202.6 31314.4 31776.0 31933.0 32479.4 34758.5 36679.1 37760.0 39494.0 42884.8 44694.8 7757.3 7992.8 8072.6 8132.3 8194.8 10638.3 11794.5 11831.4 11866.1 12010.2 3023.4 4807.5 4975.8 10089.8 10245.9 9242.5 9960.6 10070.4 10210.4 10473.3 10052.2 10199.7 10355.8 10556.0 10571.7 7702.9 7844.8 7875.3 7877.9 7887.2 7491.6 9463.8 9805.5 10108.2 10180.2 03:20:01 INFO - PROCESS | 14885 | 03:20:01 INFO - PROCESS | 14885 | [#3] mootools.html Cycles:180 Average:5585.55 Median:6384.72 stddev:2835.35 (44.4%) stddev-sans-first:2826.03 03:20:01 INFO - PROCESS | 14885 | Values: 1421.9 1573.4 1576.3 1777.7 1878.9 7098.2 7478.0 7629.7 7661.3 7675.0 7685.9 7849.8 7853.2 7881.9 7883.3 2044.3 2096.2 2105.2 2356.0 2382.5 2421.9 2489.9 2491.9 2494.3 2496.7 2296.4 2307.9 2323.8 2325.1 2333.1 2345.2 2346.3 2356.5 2376.0 2378.5 2370.0 2386.3 2407.1 2414.3 2415.7 7935.6 7976.8 7995.2 7999.1 8002.2 4983.0 5073.9 5091.3 5091.6 5098.4 3186.1 3232.5 3252.0 3273.5 3278.0 1159.3 1163.4 1174.6 1180.5 1196.5 10820.0 10854.6 10859.9 10876.2 10922.1 4520.0 4537.9 4605.3 4606.0 4609.2 9347.7 9351.1 9375.1 9390.7 9397.5 5006.3 5010.1 5015.1 5020.9 5072.9 4547.9 4614.1 4616.1 4616.4 4618.1 3646.4 3699.9 3799.1 3838.0 3888.4 1268.7 1280.0 1281.4 1283.6 1294.5 1347.0 1355.1 1356.4 1357.8 1380.8 7241.9 7326.7 7350.5 7362.2 7378.3 1134.1 1139.7 1147.3 1147.8 1148.2 5943.1 6061.1 6063.0 6064.5 6067.4 9212.1 9800.6 9818.4 9913.6 9979.9 9721.4 9857.4 9881.6 9906.1 9922.7 7482.8 7784.8 7927.8 7929.3 7937.6 7247.5 7295.9 7320.5 7338.0 7340.2 8327.5 8707.1 8755.8 8765.1 8976.8 8335.4 8490.5 8548.9 8590.7 8646.4 7343.0 7359.5 7438.0 7445.1 7446.8 6900.2 6919.6 7040.2 7086.1 7095.4 6537.1 6671.6 6688.4 6822.4 6980.8 6678.1 6705.9 6718.3 6799.2 6832.9 6690.1 6761.9 6766.0 6766.4 6766.9 6380.2 6389.2 6405.0 6423.3 6475.3 6439.3 6481.5 6490.5 6498.9 6501.4 03:20:01 INFO - PROCESS | 14885 | 03:20:01 INFO - PROCESS | 14885 | [#4] prototype.html Cycles:180 Average:5512.98 Median:6240.53 stddev:1620.70 (26.0%) stddev-sans-first:1578.68 03:20:01 INFO - PROCESS | 14885 | Values: 374.4 379.4 382.1 386.0 389.0 5917.4 6079.8 6087.7 6108.2 6145.8 5870.9 6073.3 6092.9 6094.5 6100.7 4796.2 4805.4 4806.1 4811.0 4812.0 4578.3 4651.5 4713.0 4733.2 4798.6 4655.2 4672.7 4692.2 4699.3 4731.0 4732.7 4744.8 4747.8 4755.1 4761.7 4231.5 4235.6 4240.5 4242.9 4243.7 5675.7 5800.9 5813.9 5821.5 5824.3 5574.3 5590.6 5595.9 5600.3 5604.2 6493.8 6513.6 6513.8 6518.5 6522.8 4505.0 4693.2 4713.5 4725.5 4728.2 6452.7 6572.2 6661.0 6757.9 6768.0 4763.8 4852.6 4876.7 4879.9 4881.8 4932.8 4941.3 4943.1 4947.5 4947.6 5492.8 5547.1 5581.9 5589.0 5595.9 6530.9 6545.3 6551.7 6553.0 6553.5 1714.9 1717.2 1717.2 1717.4 1718.3 1205.4 1213.3 1224.3 1228.7 1232.6 6266.5 6281.1 6293.1 6300.3 6301.0 6637.9 6691.3 6700.8 6706.4 6722.2 6214.6 6301.6 6302.7 6306.1 6308.0 3639.5 3645.0 3677.9 3682.9 3683.2 6977.8 7016.0 7016.4 7020.9 7027.3 6811.6 6920.8 6977.9 6983.8 6991.4 6993.2 6996.5 7005.1 7009.9 7021.1 6782.5 6785.3 6788.8 6794.1 6794.7 6478.1 6682.5 6692.6 6710.6 6713.1 6981.0 6988.7 6989.8 6993.5 6993.6 4781.7 4825.8 4826.4 4826.7 4829.3 6660.7 6786.0 6789.2 6793.9 6795.8 6619.6 6629.2 6632.4 6633.2 6644.2 6602.6 6605.5 6607.1 6614.0 6624.0 6347.0 6486.8 6493.4 6495.5 6500.3 6640.6 6641.9 6643.5 6644.3 6653.7 6476.4 6485.7 6491.9 6492.7 6502.1 03:20:01 INFO - PROCESS | 14885 | 03:20:01 INFO - PROCESS | 14885 | [#5] yui.html Cycles:180 Average:14645.45 Median:25400.31 stddev:16835.96 (66.3%) stddev-sans-first:16850.75 03:20:01 INFO - PROCESS | 14885 | Values: 728.0 733.7 770.4 807.9 831.4 14205.7 14475.1 14476.4 14480.3 14556.5 13536.3 13618.9 13620.1 13658.9 13681.5 734.5 739.6 883.5 899.1 902.1 908.4 912.0 915.7 915.8 915.9 959.2 961.3 961.5 961.9 962.6 791.1 798.6 928.6 947.2 948.6 702.1 702.8 703.2 703.3 703.6 13553.2 13613.5 13616.3 13624.7 13630.9 13309.5 13762.8 13788.1 13895.2 13966.6 10704.9 10813.2 10858.7 10954.3 11018.3 3797.5 3821.4 3837.4 3838.8 3840.3 48228.5 48484.3 48529.0 48844.3 49040.0 10837.2 11048.6 11116.2 11270.5 11281.5 18921.8 19005.0 19050.6 19095.5 19119.8 7916.3 8023.8 8142.3 8170.1 8331.7 4056.7 4122.4 4147.4 4189.2 4214.3 3118.4 3146.7 3152.0 3159.4 3164.5 521.7 532.7 536.6 539.7 541.6 562.8 575.5 580.3 695.0 695.6 9361.2 9471.6 9477.7 9628.4 9752.5 550.3 565.2 592.5 628.9 703.9 4329.3 4693.6 4719.2 4847.6 4898.7 58702.0 60106.7 61171.4 63072.7 63211.7 54634.5 55484.2 55783.4 56129.0 56509.7 48727.8 48968.5 49038.5 49820.8 50099.0 9436.5 9630.5 9662.8 9697.7 9776.8 35553.3 35635.1 36502.0 37232.0 37589.6 43975.6 46107.0 47595.5 47995.8 48843.3 11360.7 11920.8 11940.3 11992.2 12045.3 13380.4 13549.9 13571.9 13631.1 13780.4 10671.1 10672.6 11293.1 11381.7 11393.3 12455.8 12701.9 12888.8 13198.5 13389.2 11194.3 12065.3 12321.8 13279.0 13800.9 10013.4 10105.4 10245.5 10314.3 10317.0 9665.8 9815.5 10724.9 10757.5 10829.2 03:20:01 INFO - PROCESS | 14885 | -------- Summary: end -------- 03:20:01 INFO - PROCESS | 14885 | 03:20:02 INFO - TEST-INFO | 14885: exit 0 03:20:02 INFO - TEST-OK | dromaeo_css | took 1110994ms 03:20:02 INFO - TEST-START | kraken 03:20:02 INFO - operating with platform_type : linux_ 03:20:02 INFO - Initialising browser for kraken test... 03:20:02 INFO - TEST-INFO | started process 15075 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZw52VC/profile http://localhost:57228/getInfo.html) 03:20:06 INFO - PROCESS | 15075 | __metrics Screen width/height:1600/1200 03:20:06 INFO - PROCESS | 15075 | colorDepth:24 03:20:06 INFO - PROCESS | 15075 | Browser inner width/height: 1024/697 03:20:06 INFO - PROCESS | 15075 | __metrics 03:20:06 INFO - PROCESS | 15075 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:20:07 INFO - TEST-INFO | 15075: exit 0 03:20:07 INFO - Browser initialized. 03:20:07 INFO - Running cycle 1/1 for kraken test... 03:20:07 INFO - TEST-INFO | started process 15162 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpZw52VC/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:20:19 INFO - PROCESS | 15162 | RSS: Main: 136617984 03:20:19 INFO - PROCESS | 15162 | 03:20:22 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57228/tests/kraken/driver.html?testName=audio-beat-detection) 03:20:22 INFO - PROCESS | 15162 | RSS: Main: 169795584 03:20:22 INFO - PROCESS | 15162 | 03:20:25 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57228/tests/kraken/driver.html?testName=audio-dft) 03:20:25 INFO - PROCESS | 15162 | RSS: Main: 168026112 03:20:25 INFO - PROCESS | 15162 | 03:20:28 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57228/tests/kraken/driver.html?testName=audio-fft) 03:20:29 INFO - PROCESS | 15162 | RSS: Main: 166883328 03:20:29 INFO - PROCESS | 15162 | 03:20:31 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57228/tests/kraken/driver.html?testName=audio-oscillator) 03:20:31 INFO - PROCESS | 15162 | RSS: Main: 168005632 03:20:31 INFO - PROCESS | 15162 | 03:20:33 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:20:34 INFO - PROCESS | 15162 | RSS: Main: 164270080 03:20:34 INFO - PROCESS | 15162 | 03:20:37 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57228/tests/kraken/driver.html?testName=imaging-darkroom) 03:20:37 INFO - PROCESS | 15162 | RSS: Main: 164851712 03:20:37 INFO - PROCESS | 15162 | 03:20:41 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57228/tests/kraken/driver.html?testName=imaging-desaturate) 03:20:41 INFO - PROCESS | 15162 | RSS: Main: 164765696 03:20:41 INFO - PROCESS | 15162 | 03:20:44 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57228/tests/kraken/driver.html?testName=json-parse-financial) 03:20:45 INFO - PROCESS | 15162 | RSS: Main: 166215680 03:20:45 INFO - PROCESS | 15162 | 03:20:46 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:20:47 INFO - PROCESS | 15162 | RSS: Main: 172810240 03:20:47 INFO - PROCESS | 15162 | 03:20:49 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57228/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:20:49 INFO - PROCESS | 15162 | RSS: Main: 170414080 03:20:49 INFO - PROCESS | 15162 | 03:20:51 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:20:52 INFO - PROCESS | 15162 | RSS: Main: 181248000 03:20:52 INFO - PROCESS | 15162 | 03:20:54 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:20:55 INFO - PROCESS | 15162 | RSS: Main: 188190720 03:20:55 INFO - PROCESS | 15162 | 03:20:57 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:20:58 INFO - PROCESS | 15162 | RSS: Main: 185061376 03:20:58 INFO - PROCESS | 15162 | 03:20:59 INFO - PROCESS | 15162 | Cycle 1(1): loaded http://localhost:57228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57228/tests/kraken/driver.html?testName=ai-astar) 03:21:00 INFO - PROCESS | 15162 | RSS: Main: 244666368 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | __start_tp_report 03:21:00 INFO - PROCESS | 15162 | _x_x_mozilla_page_load 03:21:00 INFO - PROCESS | 15162 | _x_x_mozilla_page_load_details 03:21:00 INFO - PROCESS | 15162 | |i|pagename|runs| 03:21:00 INFO - PROCESS | 15162 | |0;ai-astar;109;111;107;112;111;110;99;109;111;109 03:21:00 INFO - PROCESS | 15162 | |1;audio-beat-detection;145;126;150;164;125;147;154;129;169;128 03:21:00 INFO - PROCESS | 15162 | |2;audio-dft;161;161;160;161;160;158;160;161;158;161 03:21:00 INFO - PROCESS | 15162 | |3;audio-fft;76;76;76;146;74;73;76;76;143;74 03:21:00 INFO - PROCESS | 15162 | |4;audio-oscillator;96;96;137;95;96;95;140;94;96;137 03:21:00 INFO - PROCESS | 15162 | |5;imaging-gaussian-blur;109;108;109;109;109;109;109;111;109;108 03:21:00 INFO - PROCESS | 15162 | |6;imaging-darkroom;146;145;146;146;146;146;145;145;145;225 03:21:00 INFO - PROCESS | 15162 | |7;imaging-desaturate;95;96;96;96;95;97;97;95;96;96 03:21:00 INFO - PROCESS | 15162 | |8;json-parse-financial;55;55;54;55;56;55;54;55;57;53 03:21:00 INFO - PROCESS | 15162 | |9;json-stringify-tinderbox;64;64;64;64;65;64;64;64;64;64 03:21:00 INFO - PROCESS | 15162 | |10;stanford-crypto-aes;79;86;80;80;80;80;88;81;81;80 03:21:00 INFO - PROCESS | 15162 | |11;stanford-crypto-ccm;113;110;118;108;116;109;110;120;110;112 03:21:00 INFO - PROCESS | 15162 | |12;stanford-crypto-pbkdf2;140;136;134;138;136;134;136;134;139;136 03:21:00 INFO - PROCESS | 15162 | |13;stanford-crypto-sha256-iterative;65;69;66;64;64;63;64;64;64;63 03:21:00 INFO - PROCESS | 15162 | __end_tp_report 03:21:00 INFO - PROCESS | 15162 | __start_cc_report 03:21:00 INFO - PROCESS | 15162 | _x_x_mozilla_cycle_collect,938 03:21:00 INFO - PROCESS | 15162 | __end_cc_report 03:21:00 INFO - PROCESS | 15162 | __startTimestamp1456572060127__endTimestamp 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | ------- Summary: start ------- 03:21:00 INFO - PROCESS | 15162 | Number of tests: 14 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#0] ai-astar Cycles:10 Average:108.80 Median:111.00 stddev:3.74 (3.4%) stddev-sans-first:3.96 03:21:00 INFO - PROCESS | 15162 | Values: 109.0 111.0 107.0 112.0 111.0 110.0 99.0 109.0 111.0 109.0 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#1] audio-beat-detection Cycles:10 Average:143.70 Median:148.50 stddev:16.11 (10.8%) stddev-sans-first:17.08 03:21:00 INFO - PROCESS | 15162 | Values: 145.0 126.0 150.0 164.0 125.0 147.0 154.0 129.0 169.0 128.0 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#2] audio-dft Cycles:10 Average:160.10 Median:161.00 stddev:1.20 (0.7%) stddev-sans-first:1.22 03:21:00 INFO - PROCESS | 15162 | Values: 161.0 161.0 160.0 161.0 160.0 158.0 160.0 161.0 158.0 161.0 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#3] audio-fft Cycles:10 Average:89.00 Median:76.00 stddev:29.28 (38.5%) stddev-sans-first:30.68 03:21:00 INFO - PROCESS | 15162 | Values: 76.0 76.0 76.0 146.0 74.0 73.0 76.0 76.0 143.0 74.0 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#4] audio-oscillator Cycles:10 Average:108.20 Median:95.50 stddev:20.59 (21.6%) stddev-sans-first:21.36 03:21:00 INFO - PROCESS | 15162 | Values: 96.0 96.0 137.0 95.0 96.0 95.0 140.0 94.0 96.0 137.0 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.82 (0.7%) stddev-sans-first:0.87 03:21:00 INFO - PROCESS | 15162 | Values: 109.0 108.0 109.0 109.0 109.0 109.0 109.0 111.0 109.0 108.0 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#6] imaging-darkroom Cycles:10 Average:153.50 Median:146.00 stddev:25.13 (17.2%) stddev-sans-first:26.50 03:21:00 INFO - PROCESS | 15162 | Values: 146.0 145.0 146.0 146.0 146.0 146.0 145.0 145.0 145.0 225.0 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 03:21:00 INFO - PROCESS | 15162 | Values: 95.0 96.0 96.0 96.0 95.0 97.0 97.0 95.0 96.0 96.0 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:1.10 (2.0%) stddev-sans-first:1.17 03:21:00 INFO - PROCESS | 15162 | Values: 55.0 55.0 54.0 55.0 56.0 55.0 54.0 55.0 57.0 53.0 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:64.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 03:21:00 INFO - PROCESS | 15162 | Values: 64.0 64.0 64.0 64.0 65.0 64.0 64.0 64.0 64.0 64.0 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:80.50 stddev:2.99 (3.7%) stddev-sans-first:3.03 03:21:00 INFO - PROCESS | 15162 | Values: 79.0 86.0 80.0 80.0 80.0 80.0 88.0 81.0 81.0 80.0 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#11] stanford-crypto-ccm Cycles:10 Average:112.60 Median:112.50 stddev:4.09 (3.6%) stddev-sans-first:4.33 03:21:00 INFO - PROCESS | 15162 | Values: 113.0 110.0 118.0 108.0 116.0 109.0 110.0 120.0 110.0 112.0 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:136.30 Median:136.00 stddev:2.11 (1.6%) stddev-sans-first:1.76 03:21:00 INFO - PROCESS | 15162 | Values: 140.0 136.0 134.0 138.0 136.0 134.0 136.0 134.0 139.0 136.0 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - PROCESS | 15162 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:64.00 stddev:1.78 (2.8%) stddev-sans-first:1.88 03:21:00 INFO - PROCESS | 15162 | Values: 65.0 69.0 66.0 64.0 64.0 63.0 64.0 64.0 64.0 63.0 03:21:00 INFO - PROCESS | 15162 | -------- Summary: end -------- 03:21:00 INFO - PROCESS | 15162 | 03:21:00 INFO - TEST-INFO | 15162: exit 0 03:21:01 INFO - TEST-OK | kraken | took 58572ms 03:21:01 INFO - TEST-START | v8_7 03:21:01 INFO - operating with platform_type : linux_ 03:21:01 INFO - Initialising browser for v8_7 test... 03:21:01 INFO - TEST-INFO | started process 15416 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHA6asK/profile http://localhost:57228/getInfo.html) 03:21:05 INFO - PROCESS | 15416 | __metrics Screen width/height:1600/1200 03:21:05 INFO - PROCESS | 15416 | colorDepth:24 03:21:05 INFO - PROCESS | 15416 | Browser inner width/height: 1024/697 03:21:05 INFO - PROCESS | 15416 | __metrics 03:21:05 INFO - PROCESS | 15416 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:21:06 INFO - TEST-INFO | 15416: exit 0 03:21:06 INFO - Browser initialized. 03:21:06 INFO - Running cycle 1/1 for v8_7 test... 03:21:06 INFO - TEST-INFO | started process 15505 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpHA6asK/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:21:18 INFO - PROCESS | 15505 | RSS: Main: 138395648 03:21:18 INFO - PROCESS | 15505 | 03:21:40 INFO - PROCESS | 15505 | Cycle 1(1): loaded http://localhost:57228/tests/v8_7/run.html (next: http://localhost:57228/tests/v8_7/run.html) 03:21:40 INFO - PROCESS | 15505 | RSS: Main: 424177664 03:21:40 INFO - PROCESS | 15505 | 03:21:40 INFO - PROCESS | 15505 | __start_tp_report 03:21:40 INFO - PROCESS | 15505 | _x_x_mozilla_page_load 03:21:40 INFO - PROCESS | 15505 | _x_x_mozilla_page_load_details 03:21:40 INFO - PROCESS | 15505 | |i|pagename|runs| 03:21:40 INFO - PROCESS | 15505 | |0;Richards;168.74789065136684 03:21:40 INFO - PROCESS | 15505 | |1;DeltaBlue;171.58544955387782 03:21:40 INFO - PROCESS | 15505 | |2;Encrypt;279.4857462269424 03:21:40 INFO - PROCESS | 15505 | |3;Decrypt;5164.948453608247 03:21:40 INFO - PROCESS | 15505 | |4;RayTrace;956.9377990430622 03:21:40 INFO - PROCESS | 15505 | |5;Earley;570.1254275940707 03:21:40 INFO - PROCESS | 15505 | |6;Boyer;11235.955056179775 03:21:40 INFO - PROCESS | 15505 | |7;RegExp;39980.39215686275 03:21:40 INFO - PROCESS | 15505 | |8;Splay;495.5401387512388 03:21:40 INFO - PROCESS | 15505 | |9;NavierStokes;4822.115384615385 03:21:40 INFO - PROCESS | 15505 | __end_tp_report 03:21:40 INFO - PROCESS | 15505 | __startTimestamp1456572100728__endTimestamp 03:21:40 INFO - PROCESS | 15505 | 03:21:40 INFO - PROCESS | 15505 | ------- Summary: start ------- 03:21:40 INFO - PROCESS | 15505 | Number of tests: 10 03:21:40 INFO - PROCESS | 15505 | 03:21:40 INFO - PROCESS | 15505 | [#0] Richards Cycles:1 Average:168.75 Median:168.75 stddev:0.00 (0.0%) 03:21:40 INFO - PROCESS | 15505 | Values: 168.7 03:21:40 INFO - PROCESS | 15505 | 03:21:40 INFO - PROCESS | 15505 | [#1] DeltaBlue Cycles:1 Average:171.59 Median:171.59 stddev:0.00 (0.0%) 03:21:40 INFO - PROCESS | 15505 | Values: 171.6 03:21:40 INFO - PROCESS | 15505 | 03:21:40 INFO - PROCESS | 15505 | [#2] Encrypt Cycles:1 Average:279.49 Median:279.49 stddev:0.00 (0.0%) 03:21:40 INFO - PROCESS | 15505 | Values: 279.5 03:21:40 INFO - PROCESS | 15505 | 03:21:40 INFO - PROCESS | 15505 | [#3] Decrypt Cycles:1 Average:5164.95 Median:5164.95 stddev:0.00 (0.0%) 03:21:40 INFO - PROCESS | 15505 | Values: 5164.9 03:21:40 INFO - PROCESS | 15505 | 03:21:40 INFO - PROCESS | 15505 | [#4] RayTrace Cycles:1 Average:956.94 Median:956.94 stddev:0.00 (0.0%) 03:21:40 INFO - PROCESS | 15505 | Values: 956.9 03:21:40 INFO - PROCESS | 15505 | 03:21:40 INFO - PROCESS | 15505 | [#5] Earley Cycles:1 Average:570.13 Median:570.13 stddev:0.00 (0.0%) 03:21:40 INFO - PROCESS | 15505 | Values: 570.1 03:21:40 INFO - PROCESS | 15505 | 03:21:40 INFO - PROCESS | 15505 | [#6] Boyer Cycles:1 Average:11235.96 Median:11235.96 stddev:0.00 (0.0%) 03:21:40 INFO - PROCESS | 15505 | Values: 11236.0 03:21:40 INFO - PROCESS | 15505 | 03:21:40 INFO - PROCESS | 15505 | [#7] RegExp Cycles:1 Average:39980.39 Median:39980.39 stddev:0.00 (0.0%) 03:21:40 INFO - PROCESS | 15505 | Values: 39980.4 03:21:40 INFO - PROCESS | 15505 | 03:21:40 INFO - PROCESS | 15505 | [#8] Splay Cycles:1 Average:495.54 Median:495.54 stddev:0.00 (0.0%) 03:21:40 INFO - PROCESS | 15505 | Values: 495.5 03:21:40 INFO - PROCESS | 15505 | 03:21:40 INFO - PROCESS | 15505 | [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 03:21:40 INFO - PROCESS | 15505 | Values: 4822.1 03:21:40 INFO - PROCESS | 15505 | -------- Summary: end -------- 03:21:40 INFO - PROCESS | 15505 | 03:21:41 INFO - TEST-INFO | 15505: exit 0 03:21:41 INFO - TEST-OK | v8_7 | took 40590ms 03:21:41 INFO - SUITE-END | took 1210s 03:21:41 INFO - Completed test suite (00:20:10) 03:21:41 INFO - Working with test: dromaeo_css 03:21:41 INFO - Generating results file: dromaeo_css 03:21:41 INFO - Working with test: kraken 03:21:41 INFO - Generating results file: kraken 03:21:41 INFO - javascript benchmark 03:21:41 INFO - Working with test: v8_7 03:21:41 INFO - Generating results file: v8_7 03:21:41 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:21:41 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,52,35]] 03:21:41 INFO - process_Request line: dromaeo_css 7196.12 graph.html#tests=[[72,52,35]] 03:21:41 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:21:41 INFO - process_Request line: kraken 1482.20 graph.html#tests=[[232,52,35]] 03:21:41 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 03:21:41 INFO - process_Request line: v8_7 21936.47 graph.html#tests=[[230,52,35]] 03:21:41 INFO - RETURN: dromaeo_css: 7196.12 03:21:41 INFO - RETURN: kraken: 1482.20 03:21:41 INFO - RETURN: v8_7: 21936.47 03:21:41 INFO - javascript benchmark 03:21:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3193.169775858277, 3232.6120865496146, 3261.8205998670064, 3266.0528446750427, 3291.9012429627114, 5150.356205474316, 5158.587313014959, 5171.612129090315, 5184.974075129629, 5217.92173117403, 5171.612129090324, 5173.896522069556, 5202.843914682556, 5215.191645294976, 5220.26916231727, 4665.043666048452, 4710.434747830265, 4744.501827308135, 4750.334953106568, 4784.210605250129, 4716.386869706933, 4724.078804633098, 4741.336212930192, 4767.42790865095, 4769.52304769522, 4683.367745354385, 4746.667733258673, 4753.762311884409, 4762.6428017898625, 4773.0, 4779.091972525209, 4804.471508134103, 4806.086843499724, 4806.255030470265, 4830.2271636538335, 4610.446746390421, 4617.445906491209, 4687.5781179693995, 4723.055388922195, 4729.243321068647, 4947.529984651453, 4955.083309587738, 4955.578775804061, 4957.603391728653, 4973.254011898244, 5456.61803673743, 5457.918131228034, 5463.153211252223, 5475.342958844924, 5488.259085023511, 5361.169018802053, 5467.234587157801, 5489.066858634042, 5544.7227638618315, 5626.971865140689, 5176.223566465061, 5187.170052791573, 5194.428612852583, 5211.166213405875, 5212.58299336056, 5599.0, 5630.634008789416, 5631.577631677641, 5634.859128521738, 5638.295213098363, 5347.67913925158, 5390.811321603725, 5400.702961337164, 5418.83743487696, 5461.017016936989, 5489.64317319373, 5510.834674959758, 5512.035393806068, 5515.586331025109, 5516.586256030733, 5433.375161856437, 5475.479829416199, 5485.451454854483, 5490.313710786152, 5529.889402212014, 5443.237946687467, 5447.863803404946, 5522.972385138049, 5526.613137080366, 5528.502434780889, 4239.512456067591, 4240.406343111967, 4243.893902652459, 4265.978670106691, 4267.0, 3812.618738126165, 3872.0513474199, 3874.089588946595, 3879.515060617423, 3896.2597106550234, 5185.844424667266, 5208.088584497774, 5235.267062611238, 5236.607254455855, 5260.053190425683, 5488.341399032141, 5549.361823390362, 5553.08374118266, 5556.055470570012, 5597.412271711411, 5120.129577971754, 5155.845324640267, 5222.086134926372, 5247.055530004636, 5266.0, 4760.619150467955, 4809.062232864558, 4822.397200349957, 4826.927596086061, 4853.4418541867435, 5619.072852979294, 5643.887122257532, 5662.235598194274, 5666.886662266732, 5698.259226300637, 5423.132298832168, 5510.283663123768, 5528.889422211614, 5534.4742249486235, 5536.557075433875, 5651.265335506522, 5660.9150862737915, 5665.915011274752, 5677.120046392815, 5699.145128230799, 5459.7270136492225, 5466.535344495604, 5470.370907345547, 5474.233607294902, 5478.561715062706, 5449.7002664852225, 5495.862603434786, 5520.558355331644, 5525.778968841201, 5564.304461942257, 5419.295491586068, 5419.674819510842, 5426.430224826337, 5427.05026620332, 5428.918566221589, 5359.276497672764, 5416.079266524779, 5420.83737487876, 5421.83734487966, 5449.1826226066405, 5203.869903252449, 5319.654222475527, 5324.6006549508165, 5344.919826202532, 5413.160960051198, 5381.650192737616, 5407.918881216706, 5411.431799660979, 5420.430854760193, 5424.783008679608, 5308.362996440452, 5309.097453432849, 5313.017091838023, 5313.043652142652, 5333.4133245343755], "unit": "score", "name": "dojo.html", "value": 5106.456343835056}, {"lowerIsBetter": false, "replicates": [538.0261726275439, 560.9242752228448, 621.2669050520384, 633.0820310549702, 654.6464908949167, 31314.0, 38807.80596097019, 39057.804710976474, 39109.60890391095, 39151.21697566047, 41257.38113928294, 41711.58288417115, 41796.58203417965, 41882.79058604703, 41903.37144942828, 7774.494657847222, 7881.645325960317, 8638.870416943735, 8860.379773415863, 9853.704388868346, 9984.550695218733, 10107.79784404315, 10142.543585538631, 10142.645007424742, 10145.594176232942, 10131.088202061814, 10221.693349199535, 10224.437655928921, 10227.539760710786, 10227.846582301272, 9976.451295178762, 10050.698479045604, 10114.0, 10132.442715650635, 10134.341267817568, 10062.144717699004, 10221.0, 10229.283950123494, 10238.7440313992, 10581.629642962536, 42471.36292955608, 42908.57091429077, 42934.141317173795, 43006.56993430057, 43022.139557208684, 3479.982600087009, 3513.0339156731848, 3700.8889733307774, 3820.8853734388013, 4150.64719498843, 31535.684643153505, 31809.681903180903, 32069.679303206904, 32201.838990804896, 32261.838690806395, 4001.4397984282223, 4080.6123418275215, 4105.876823695264, 4107.178564287125, 4328.394024836525, 339369.0, 342030.0, 343415.0, 343488.0, 343711.0, 58059.41940580625, 58411.0, 59076.0, 62831.0, 64081.0, 57730.42269577335, 57838.42161578331, 57892.42107578955, 57955.71022144862, 58199.70900145472, 17253.482395528154, 17450.47648570545, 17458.301667933396, 17508.299668013136, 17509.212085456184, 13460.528881489101, 13513.256770877499, 13531.255780932142, 13534.0, 13684.589462316146, 20043.298484553263, 21251.149954001667, 21285.82927938978, 21330.573388532142, 21406.25078122258, 8304.626291816883, 8675.6529738811, 8708.608112634947, 8768.824623507495, 8818.0, 1116.2576886370634, 1117.2179474367974, 1163.7032556697989, 1198.8261702053153, 1199.2145144930084, 30493.695063049086, 30791.2302192447, 30805.0, 32299.51550726741, 34987.0, 1186.4423720851187, 1196.2642974570751, 1223.6451429085644, 1291.7739395605731, 1336.6725152337544, 13075.346232688327, 15568.0, 15656.37374505007, 15985.920070399574, 17048.744268835977, 341589.0, 345407.0, 346166.0, 346313.0, 347876.0, 56787.71606142025, 57360.71319643375, 57394.13908791372, 57410.71294643584, 57509.42490575124, 1346.1855577375743, 1373.0731756064815, 1477.1358755128156, 1480.7556753135611, 1524.3445318513145, 27812.30469238285, 27889.860550697118, 28247.15258542247, 31140.53289200709, 31580.526292106097, 47517.76241118772, 54024.0, 54740.452595473536, 54743.45256547384, 54805.0, 323791.0, 434611.0, 442876.0, 444618.0, 445393.0, 43917.341239882066, 44631.33053004143, 44951.10097798091, 45019.549804501534, 45939.0, 49289.260661090826, 49612.75193624153, 49639.75180124076, 50359.0, 50671.23993140032, 39045.46881775107, 40436.0, 40605.37578496827, 40755.59244407518, 40820.79589602152, 37198.25603487861, 37263.62736372602, 37966.81016594899, 38162.42756358601, 38509.2298154041, 39874.800625996686, 40029.0, 40241.19517609573, 40417.0, 40484.3927341096, 33957.0, 34287.82856085704, 35510.64489355177, 36120.27759444744, 39530.60469395269, 25886.787171078427, 26072.8696356517, 26193.869030654725, 26245.081422150895, 26444.338891527867], "unit": "score", "name": "ext.html", "value": 20480.99878751746}, {"lowerIsBetter": false, "replicates": [397.4554859842017, 398.7189031732629, 399.39292275740877, 399.5046142782948, 404.3267958848517, 14920.179390133539, 15354.0, 17466.73799893001, 17963.640727185448, 17994.370197043107, 11278.492467838947, 11672.533098676044, 16712.2479488423, 17965.7305140423, 17991.0, 5020.774065167067, 5344.4922732340365, 5346.304980352568, 5350.384705758829, 5367.543758780508, 3974.3442332015184, 4094.0379010932406, 5280.392754833205, 5317.7607007684555, 5548.55611551075, 5407.080796264644, 5477.287952566181, 5485.040117979358, 5514.779408823662, 5515.420880807517, 5528.530074943635, 5560.110382338807, 5563.972180139093, 5570.944290557083, 5573.832785016436, 5254.106801843695, 5377.247185394048, 5695.77216911323, 5695.832354367345, 5696.117101849179, 20758.273460428813, 20860.269890553758, 21298.78701212999, 21488.0, 21536.0, 2285.3258288804677, 2298.712660917385, 2392.7966122879575, 2393.56915755164, 2401.387646150238, 9848.359856609297, 9904.306698531105, 10162.0, 12371.690707732289, 12824.422900969479, 1848.9815101848944, 1870.8222718841823, 1881.482592287132, 1916.252661462031, 1951.7950615185484, 58284.41715582832, 59071.11393329103, 60185.699071504365, 60368.99999999956, 64488.67755661192, 15215.163166025975, 15606.375744970073, 16104.677906441806, 16265.186740662919, 16267.349306027627, 24988.375290617518, 25078.623820642704, 25240.368990775376, 25286.87356563242, 25429.0, 9624.75938101539, 9943.651972180938, 9964.153046990941, 10006.69979900604, 10271.589136434608, 8497.575121243914, 8526.445781024338, 8556.272716819016, 8629.8705519416, 8646.351523635753, 6830.749515969497, 7959.721409750631, 8110.188981101961, 8113.716019939392, 8226.095129535746, 1923.2691577200853, 1951.8926459044606, 1956.3935180094215, 1958.0, 1977.861549691508, 974.64425484699, 1041.1514615588271, 1044.7597052677927, 1137.3460260350332, 1180.6753142885775, 17331.65336693259, 17349.2192851322, 17452.912735436243, 17588.296468141387, 18150.183241754152, 991.861139440479, 1008.5259927833906, 1013.2653825976256, 1018.7249442650443, 1102.388174563109, 9212.309076819265, 9513.238940884712, 10148.949255253825, 10531.631392901365, 10591.364518128938, 413325.0, 414663.0, 417037.0, 529758.0, 631610.0, 49120.754396228505, 50327.0, 50379.49620503822, 80996.19003810005, 82142.17857821465, 521.0828941063679, 537.532346858228, 539.1508374310476, 540.4676393752229, 1208.6917835951776, 10529.0, 11003.504842281956, 11022.724431889266, 11090.223684342018, 11202.551897923991, 31314.373712525165, 31776.046718598478, 31933.0, 32479.350412991134, 34758.47862282017, 36679.08302292464, 37760.0, 39494.0, 42884.78557607192, 44694.77652611716, 7757.340626046624, 7992.8001799953145, 8072.5963701815845, 8132.2680958714, 8194.754157375288, 10638.308509946828, 11794.46924888365, 11831.40842957866, 11866.110041746731, 12010.159288849696, 3023.380207057592, 4807.533702220716, 4975.751212439436, 10089.848652270075, 10245.948770256102, 9242.537873106452, 9960.55177517028, 10070.44612546317, 10210.387376757419, 10473.266871318934, 10052.246081544059, 10199.694009179737, 10355.792884142425, 10556.0, 10571.735706607089, 7702.884456733265, 7844.843103137791, 7875.330596899099, 7877.921220787719, 7887.211278872296, 7491.6096153560065, 9463.810723785347, 9805.460699661588, 10108.191344692259, 10180.236482264008], "unit": "score", "name": "jquery.html", "value": 8569.240260416158}, {"lowerIsBetter": false, "replicates": [1421.8507056759047, 1573.4178354009018, 1576.2906691988605, 1777.697791375466, 1878.8684792064562, 7098.183708873481, 7478.0, 7629.732959346424, 7661.348785353238, 7675.04061992251, 7685.92314076859, 7849.764507064783, 7853.175416581263, 7881.92118078819, 7883.251091146332, 2044.2845004248543, 2096.2348742708878, 2105.2421128393767, 2355.975150809399, 2382.4877651304964, 2421.927342179729, 2489.8755062246814, 2491.8878650460774, 2494.326531836403, 2496.6754321938215, 2296.44885227545, 2307.9423014424688, 2323.779240972105, 2325.093213646671, 2333.1017558240114, 2345.2495201535603, 2346.3195673254736, 2356.4697942962866, 2375.97624023761, 2378.5242951409787, 2369.9526009479714, 2386.331827088418, 2407.1454633604994, 2414.2757172848073, 2415.7342692303832, 7935.603219838985, 7976.800579985489, 7995.200479951958, 7999.0801057877925, 8002.159773223844, 4982.975085124587, 5073.898522029539, 5091.338126043628, 5091.61812864033, 5098.4136824265315, 3186.123815950609, 3232.5151227315855, 3251.9837400812844, 3273.4598791199423, 3277.96722032779, 1159.2928313728619, 1163.4241050679882, 1174.6358628825005, 1180.4923882730423, 1196.521391443421, 10820.0, 10854.620088296871, 10859.945700271448, 10876.238663293572, 10922.126229901589, 4519.983003824179, 4537.909241815145, 4605.332226827091, 4606.032733126014, 4609.170349337084, 9347.672831450867, 9351.064893510595, 9375.10936461035, 9390.718278451657, 9397.530123493798, 5006.324146240284, 5010.123228435009, 5015.14742493777, 5020.874478138076, 5072.898542029213, 4547.8635640930825, 4614.100250451197, 4616.053708989649, 4616.446026476844, 4618.122556714214, 3646.3801153803915, 3699.8705045323286, 3799.0502374406396, 3838.0, 3888.3972984187494, 1268.6574624851423, 1280.014388920526, 1281.404147071616, 1283.6341642631805, 1294.501616877509, 1346.9730605387838, 1355.1462578575631, 1356.389624668903, 1357.8370595528402, 1380.7721725915112, 7241.8551628967125, 7326.743563975236, 7350.485466017434, 7362.153352364439, 7378.335949764547, 1134.0530656901415, 1139.658102569226, 1147.3058799426394, 1147.8048731715628, 1148.2306854407566, 5943.078822782476, 6061.121137435047, 6063.0, 6064.545159113084, 6067.362926892701, 9212.078792120868, 9800.55897484615, 9818.361806482555, 9913.603455861685, 9979.900200998043, 9721.416714997124, 9857.35927164732, 9881.604735810632, 9906.05892440217, 9922.652707155215, 7482.775516734507, 7784.766457006299, 7927.801804954807, 7929.32600728933, 7937.563434011185, 7247.528910620793, 7295.8540829184185, 7320.450966177452, 7338.0, 7340.155882073416, 8327.50034997902, 8707.129287071242, 8755.824883502168, 8765.07966663491, 8976.775580610538, 8335.416520843726, 8490.490570565786, 8548.871766923377, 8590.65637374498, 8646.394752367274, 7342.9632851835395, 7359.484836061424, 7438.033055702724, 7445.143808462096, 7446.813829654302, 6900.2409734930125, 6919.550229235084, 7040.155181378267, 7086.078809754905, 7095.361417472453, 6537.084808126961, 6671.5997040177745, 6688.3646053624825, 6822.3859852613505, 6980.755673551402, 6678.131842860397, 6705.865882682417, 6718.328167183243, 6799.184097908283, 6832.863342733217, 6690.13028306317, 6761.898571521333, 6765.985102234507, 6766.357196066366, 6766.93233067663, 6380.202474690664, 6389.233292004989, 6404.967975160094, 6423.261324947691, 6475.3200913903365, 6439.259485159267, 6481.513886458629, 6490.480761538971, 6498.902516462163, 6501.414872661483], "unit": "score", "name": "mootools.html", "value": 4645.879255835634}, {"lowerIsBetter": false, "replicates": [374.3766628563442, 379.4119115371174, 382.12112142073244, 385.9845606175753, 389.02549114468263, 5917.408259174081, 6079.78720744774, 6087.7260523276445, 6108.175396321501, 6145.846353841156, 5870.88258234835, 6073.301570319414, 6092.939070609293, 6094.51243900488, 6100.664463454508, 4796.232602783547, 4805.351277577517, 4806.110869489138, 4811.037792441508, 4811.975940120295, 4578.290364993415, 4651.465081515636, 4712.976435117819, 4733.171694953387, 4798.616110711135, 4655.231886738701, 4672.672912896099, 4692.249240121581, 4699.318598803172, 4731.0, 4732.716037037789, 4744.83393081244, 4747.83382581608, 4755.144074066667, 4761.666683332168, 4231.534531201596, 4235.57644235577, 4240.533541310453, 4242.8727138185905, 4243.6605071594195, 5675.659460432387, 5800.941990580082, 5813.912791308134, 5821.53427725781, 5824.30108386992, 5574.331080270394, 5590.636608620467, 5595.860103497404, 5600.27196464457, 5604.215409842625, 6493.772717954849, 6513.641749703765, 6513.837154071139, 6518.478521718251, 6522.825891339557, 4504.977475112636, 4693.178693728618, 4713.505081966378, 4725.527447255281, 4728.196206644844, 6452.709628066701, 6572.211334639826, 6661.0, 6757.898631520531, 6768.018637297564, 4763.8332658357485, 4852.56326930578, 4876.731779752147, 4879.878003049881, 4881.780319885614, 4932.778024988884, 4941.332920055818, 4943.110240156734, 4947.529984651453, 4947.6289278304275, 5492.80775172867, 5547.084731019418, 5581.916271255935, 5588.993981083363, 5595.944040559623, 6530.869382612416, 6545.345465453416, 6551.705173267214, 6553.017047442826, 6553.508486863504, 1714.9399771007954, 1717.2444124585143, 1717.2444124585143, 1717.4246627379969, 1718.2954988454674, 1205.3973013493253, 1213.2962881528692, 1224.3327386574338, 1228.686684895346, 1232.6117273059137, 6266.467350275187, 6281.120643109968, 6293.056041593706, 6300.30696623371, 6301.0, 6637.86724265512, 6691.297413771581, 6700.765473208415, 6706.396424321834, 6722.193336799615, 6214.5960512566535, 6301.558890877594, 6302.653354065479, 6306.1171435998995, 6307.96846015767, 3639.490471334015, 3644.9635503645154, 3677.889663310105, 3682.9447558286647, 3683.2265224303196, 6977.790666279918, 7016.0, 7016.36852683261, 7020.92979070213, 7027.262137475594, 6811.591304521646, 6920.792376228721, 6977.89533157003, 6983.755568555076, 6991.405730512862, 6993.160820701447, 6996.475264355194, 7005.124359455068, 7009.894851577231, 7021.122359705037, 6782.525223234326, 6785.287544807724, 6788.796336109925, 6794.116764820542, 6794.728210871707, 6478.093066970722, 6682.465402767864, 6692.59844409337, 6710.6309152997055, 6713.12729345182, 6981.0, 6988.6505674715045, 6989.790306290819, 6993.545419547713, 6993.615351155532, 4781.665283430127, 4825.782839772289, 4826.372571565674, 4826.710397376169, 4829.323894654822, 6660.733570657119, 6786.016027676058, 6789.219239787488, 6793.864122717617, 6795.7961261162245, 6619.569727967667, 6629.171353580802, 6632.436242919406, 6633.170853643294, 6644.169478815148, 6602.60384376939, 6605.5376123670885, 6607.108040414482, 6613.966930165318, 6623.966880165568, 6347.016212487072, 6486.772962946275, 6493.383128602775, 6495.545311828316, 6500.252470965899, 6640.601563906181, 6641.933580664325, 6643.50173736962, 6644.335566443317, 6653.667316634246, 6476.384743449365, 6485.675716214265, 6491.870162596816, 6492.740290388331, 6502.057201705821], "unit": "score", "name": "prototype.html", "value": 5003.319266474287}, {"lowerIsBetter": false, "replicates": [727.9672414741336, 733.6808488307587, 770.3836930455635, 807.931325837304, 831.3889291370841, 14205.71588568228, 14475.13149211048, 14476.420943162275, 14480.348384322704, 14556.49052283169, 13536.323183840794, 13618.863811361884, 13620.114692544978, 13658.93170534146, 13681.521146759866, 734.4968696442927, 739.6301849075462, 883.4964070479829, 899.0829354058873, 902.0933961368816, 908.4413085952158, 912.0013585124311, 915.6612053540199, 915.7847905742145, 915.8671992561075, 959.203860795538, 961.27423795035, 961.4952150121208, 961.9182369498601, 962.5860879821674, 791.1416113516813, 798.5927177139672, 928.5682157796657, 947.1948843482984, 948.5826236456006, 702.1223470661673, 702.8348338140532, 703.2159142556013, 703.3353480960494, 703.6165289917008, 13553.186808791505, 13613.455461781616, 13616.251106189156, 13624.659383515393, 13630.863691363158, 13309.467621295233, 13762.793558096637, 13788.103773254807, 13895.235762033084, 13966.58100256994, 10704.946475267652, 10813.18901082422, 10858.674239772741, 10954.287971281841, 11018.283811552223, 3797.487339209227, 3821.445890345912, 3837.4052021936645, 3838.7888666123354, 3840.347140986039, 48228.51771482275, 48484.27273590899, 48529.0, 48844.267335989985, 49040.0, 10837.18721095921, 11048.558057677763, 11116.166287528305, 11270.54917803295, 11281.492332845042, 18921.81078189228, 19005.04974751257, 19050.61898762017, 19095.522611934535, 19119.80880191208, 7916.327112195413, 8023.839523209504, 8142.267195952274, 8170.101288858221, 8331.6667333306, 4056.6957478189256, 4122.361034039729, 4147.440095587118, 4189.2459357315365, 4214.283571792803, 3118.4075025745037, 3146.6695996920453, 3152.0386282183695, 3159.4313023655504, 3164.477861152884, 521.6791673121079, 532.7309708597211, 536.5814664561648, 539.7058603061341, 541.5748637319764, 562.7580140539526, 575.466634861948, 580.3247031186228, 694.982625434358, 695.5556766557745, 9361.157495825408, 9471.573779179816, 9477.668281610111, 9628.364885374192, 9752.512374381253, 550.3423409026175, 565.191775760658, 592.5082181789105, 628.9088082228051, 703.929607039292, 4329.264025115738, 4693.624510039189, 4719.197736384823, 4847.563719265283, 4898.730569818694, 58702.0, 60106.69946650239, 61171.38828611746, 63072.68463657652, 63211.68394158, 54634.45365546294, 55484.16773748397, 55783.442165578635, 56129.0, 56509.71745141248, 48727.756361217966, 48968.51031489711, 49038.509614904106, 49820.750896245285, 50099.0, 9436.480993545421, 9630.47032413224, 9662.75843103928, 9697.709068727949, 9776.804463910825, 35553.288934220654, 35635.10912227215, 36502.0, 37232.0, 37589.624103758615, 43975.560244398424, 46107.0, 47595.524044759106, 47995.76002119967, 48843.26735098905, 11360.659180224606, 11920.821187682019, 11940.283582985048, 11992.220505667105, 12045.33750643723, 13380.397882095134, 13549.93225033908, 13571.932140339235, 13631.113977591425, 13780.448782048607, 10671.092957098423, 10672.57309707603, 11293.096552275634, 11381.658550243506, 11393.316401015965, 12455.750884982432, 12701.93649031749, 12888.806667900177, 13198.472061117833, 13389.19664820114, 11194.328340299608, 12065.336406497376, 12321.815172772236, 13279.0, 13800.861991379958, 10013.449260290754, 10105.39367637944, 10245.538950747383, 10314.27800053989, 10317.036000699647, 9665.758356041155, 9815.509224538602, 10724.946375268073, 10757.462126893468, 10829.24195306321], "unit": "score", "name": "yui.html", "value": 6665.142203804998}], "name": "dromaeo_css", "value": 7196.1158684138745}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 107.0, 112.0, 111.0, 110.0, 99.0, 109.0, 111.0, 109.0], "unit": "score", "name": "ai-astar", "value": 108.8}, {"lowerIsBetter": true, "replicates": [145.0, 126.0, 150.0, 164.0, 125.0, 147.0, 154.0, 129.0, 169.0, 128.0], "unit": "score", "name": "audio-beat-detection", "value": 143.7}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 160.0, 161.0, 160.0, 158.0, 160.0, 161.0, 158.0, 161.0], "unit": "score", "name": "audio-dft", "value": 160.1}, {"lowerIsBetter": true, "replicates": [76.0, 76.0, 76.0, 146.0, 74.0, 73.0, 76.0, 76.0, 143.0, 74.0], "unit": "score", "name": "audio-fft", "value": 89.0}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 137.0, 95.0, 96.0, 95.0, 140.0, 94.0, 96.0, 137.0], "unit": "score", "name": "audio-oscillator", "value": 108.2}, {"lowerIsBetter": true, "replicates": [109.0, 108.0, 109.0, 109.0, 109.0, 109.0, 109.0, 111.0, 109.0, 108.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 225.0], "unit": "score", "name": "imaging-darkroom", "value": 153.5}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 97.0, 97.0, 95.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [55.0, 55.0, 54.0, 55.0, 56.0, 55.0, 54.0, 55.0, 57.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 54.9}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 64.0, 64.0, 64.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 64.1}, {"lowerIsBetter": true, "replicates": [79.0, 86.0, 80.0, 80.0, 80.0, 80.0, 88.0, 81.0, 81.0, 80.0], "unit": "score", "name": "stanford-crypto-aes", "value": 81.5}, {"lowerIsBetter": true, "replicates": [113.0, 110.0, 118.0, 108.0, 116.0, 109.0, 110.0, 120.0, 110.0, 112.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 112.6}, {"lowerIsBetter": true, "replicates": [140.0, 136.0, 134.0, 138.0, 136.0, 134.0, 136.0, 134.0, 139.0, 136.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 136.3}, {"lowerIsBetter": true, "replicates": [65.0, 69.0, 66.0, 64.0, 64.0, 63.0, 64.0, 64.0, 64.0, 63.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 64.6}], "name": "kraken", "value": 1482.1999999999998}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.74789065136684], "unit": "score", "name": "Richards", "value": 209.19965200000001}, {"lowerIsBetter": false, "replicates": [171.58544955387782], "unit": "score", "name": "DeltaBlue", "value": 385.33570399999996}, {"lowerIsBetter": false, "replicates": [279.4857462269424], "unit": "score", "name": "Encrypt", "value": 952.3956179999999}, {"lowerIsBetter": false, "replicates": [5164.948453608247], "unit": "score", "name": "Decrypt", "value": 51.53604191616765}, {"lowerIsBetter": false, "replicates": [956.9377990430622], "unit": "score", "name": "RayTrace", "value": 773.2885049999996}, {"lowerIsBetter": false, "replicates": [570.1254275940707], "unit": "score", "name": "Earley", "value": 1168.9761020000003}, {"lowerIsBetter": false, "replicates": [11235.955056179775], "unit": "score", "name": "Boyer", "value": 59.315206999999965}, {"lowerIsBetter": false, "replicates": [39980.39215686275], "unit": "score", "name": "RegExp", "value": 22.785794507111312}, {"lowerIsBetter": false, "replicates": [495.5401387512388], "unit": "score", "name": "Splay", "value": 164.44883800000005}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 21936.47150767376}]} 03:21:41 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,52,35]]", "result": "21936.47"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,52,35]]", "result": "1482.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,52,35]]", "result": "7196.12"}}} 03:21:42 INFO - Return code: 0 03:21:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 03:21:42 INFO - # TBPL SUCCESS # 03:21:42 INFO - Running post-action listener: _resource_record_post_action 03:21:42 INFO - Running post-run listener: _resource_record_post_run 03:21:43 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 266240; Write bytes: 507469824; Read time: 64; Write time: 510716 03:21:43 INFO - install - Wall time: 15s; CPU: 15.0%; Read bytes: 0; Write bytes: 248692736; Read time: 0; Write time: 372328 03:21:43 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 266240; Write bytes: 258179072; Read time: 64; Write time: 138388 03:21:43 INFO - Running post-run listener: _upload_blobber_files 03:21:43 INFO - Blob upload gear active. 03:21:43 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 03:21:43 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:21:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 03:21:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Mozilla-Aurora -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 03:21:43 INFO - (blobuploader) - INFO - Open directory for files ... 03:21:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:21:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:21:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:44 INFO - (blobuploader) - INFO - Done attempting. 03:21:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 03:21:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:21:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:21:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:21:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:21:45 INFO - (blobuploader) - INFO - Done attempting. 03:21:45 INFO - (blobuploader) - INFO - Iteration through files over. 03:21:45 INFO - Return code: 0 03:21:45 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 03:21:45 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 03:21:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c15edfa32f13b398bd80c78c19614cd567fdbec63ca57757f1899e6e083bfd8763095b831f67fe2db7589aacaf47aa33ba30a70b9e662bf9aaacabc2571a4fa8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c329f963eb8f2c6d2ba4c12010cb56fca42aad41f3b06fba57173a30f73fea71f7800848c6decee0e7c5ce820dac9e18349dfa36ab8eec69ac48821dd0dc4d3f"} 03:21:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 03:21:45 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 03:21:45 INFO - Contents: 03:21:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c15edfa32f13b398bd80c78c19614cd567fdbec63ca57757f1899e6e083bfd8763095b831f67fe2db7589aacaf47aa33ba30a70b9e662bf9aaacabc2571a4fa8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c329f963eb8f2c6d2ba4c12010cb56fca42aad41f3b06fba57173a30f73fea71f7800848c6decee0e7c5ce820dac9e18349dfa36ab8eec69ac48821dd0dc4d3f"} 03:21:45 INFO - Running post-run listener: copy_logs_to_upload_dir 03:21:45 INFO - Copying logs to upload dir... 03:21:45 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 03:21:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.210655 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2016-02-27 03:21:45.073155) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-27 03:21:45.077004) ========= 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=a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c15edfa32f13b398bd80c78c19614cd567fdbec63ca57757f1899e6e083bfd8763095b831f67fe2db7589aacaf47aa33ba30a70b9e662bf9aaacabc2571a4fa8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c329f963eb8f2c6d2ba4c12010cb56fca42aad41f3b06fba57173a30f73fea71f7800848c6decee0e7c5ce820dac9e18349dfa36ab8eec69ac48821dd0dc4d3f"} build_url:https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011215 build_url: 'https://queue.taskcluster.net/v1/task/ZcMiz-E3TQq-fqKMSmZANA/artifacts/public/build/firefox-46.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c15edfa32f13b398bd80c78c19614cd567fdbec63ca57757f1899e6e083bfd8763095b831f67fe2db7589aacaf47aa33ba30a70b9e662bf9aaacabc2571a4fa8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c329f963eb8f2c6d2ba4c12010cb56fca42aad41f3b06fba57173a30f73fea71f7800848c6decee0e7c5ce820dac9e18349dfa36ab8eec69ac48821dd0dc4d3f"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-27 03:21:45.101433) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 03:21:45.101735) ========= 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=a0d2d2de893abaa8702a9954000001a7-1456569554.342834-1229548612 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 03:21:45.185856) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-27 03:21:45.186154) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-27 03:21:45.186426) ========= ========= Total master_lag: 0.53 =========