builder: fx-team_ubuntu64_hw_test-g2-pgo slave: talos-linux64-ix-032 starttime: 1453910443.46 results: success (0) buildid: 20160127053017 builduid: f6c3a2ad3b394252ac4a824b687bb89d revision: 60c5168a485cb166abd27b8eb0b83b919ac4b166 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.460453) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.460892) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.461189) ========= 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=745a4373a2a16df560f216e000000264-1453910169.700118-1119135004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006440 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.484057) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.484341) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.501517) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.501767) ========= 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=745a4373a2a16df560f216e000000264-1453910169.700118-1119135004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004570 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.551034) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.551321) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.551676) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.551969) ========= 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=745a4373a2a16df560f216e000000264-1453910169.700118-1119135004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-27 08:00:43-- 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% 85.1M=0s 2016-01-27 08:00:43 (85.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.163027 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.727398) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.727698) ========= 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=745a4373a2a16df560f216e000000264-1453910169.700118-1119135004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021219 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:43.779144) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-27 08:00:43.779469) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 60c5168a485cb166abd27b8eb0b83b919ac4b166 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 60c5168a485cb166abd27b8eb0b83b919ac4b166 --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=745a4373a2a16df560f216e000000264-1453910169.700118-1119135004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-27 08:00:43,986 truncating revision to first 12 chars 2016-01-27 08:00:43,987 Setting DEBUG logging. 2016-01-27 08:00:43,987 attempt 1/10 2016-01-27 08:00:43,987 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/60c5168a485c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-27 08:00:45,011 unpacking tar archive at: fx-team-60c5168a485c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.311163 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-01-27 08:00:45.103514) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:45.103862) ========= echo 60c5168a485cb166abd27b8eb0b83b919ac4b166 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'60c5168a485cb166abd27b8eb0b83b919ac4b166'] 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=745a4373a2a16df560f216e000000264-1453910169.700118-1119135004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 60c5168a485cb166abd27b8eb0b83b919ac4b166 program finished with exit code 0 elapsedTime=0.004645 script_repo_revision: '60c5168a485cb166abd27b8eb0b83b919ac4b166' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:45.153883) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:45.154159) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-27 08:00:45.163963) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 26 mins, 15 secs) (at 2016-01-27 08:00:45.164240) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g2 --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'g2', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=745a4373a2a16df560f216e000000264-1453910169.700118-1119135004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:00:45 INFO - MultiFileLogger online at 20160127 08:00:45 in /builds/slave/test-pgo 08:00:45 INFO - Run as scripts/scripts/talos_script.py --suite g2 --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:00:45 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 08:00:45 INFO - {'append_to_log': False, 08:00:45 INFO - 'base_work_dir': '/builds/slave/test-pgo', 08:00:45 INFO - 'blob_upload_branch': 'Fx-Team', 08:00:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 08:00:45 INFO - 'branch': 'Fx-Team', 08:00:45 INFO - 'buildbot_json_path': 'buildprops.json', 08:00:45 INFO - 'config_files': ('talos/linux_config.py',), 08:00:45 INFO - 'default_actions': ('clobber', 08:00:45 INFO - 'read-buildbot-config', 08:00:45 INFO - 'download-and-extract', 08:00:45 INFO - 'populate-webroot', 08:00:45 INFO - 'create-virtualenv', 08:00:45 INFO - 'install', 08:00:45 INFO - 'run-tests'), 08:00:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:00:45 INFO - 'download_minidump_stackwalk': True, 08:00:45 INFO - 'download_symbols': 'ondemand', 08:00:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:00:45 INFO - 'tooltool.py': '/tools/tooltool.py', 08:00:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:00:45 INFO - '/tools/misc-python/virtualenv.py')}, 08:00:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:00:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:00:45 INFO - 'installer_path': 'installer.exe', 08:00:45 INFO - 'log_level': 'info', 08:00:45 INFO - 'log_name': 'talos', 08:00:45 INFO - 'log_to_console': True, 08:00:45 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:00:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:00:45 INFO - 'opt_config_files': (), 08:00:45 INFO - 'pip_index': False, 08:00:45 INFO - 'sps_profile': False, 08:00:45 INFO - 'sps_profile_interval': 0, 08:00:45 INFO - 'suite': 'g2', 08:00:45 INFO - 'system_bits': '32', 08:00:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:00:45 INFO - 'title': 'talos-linux64-ix-032', 08:00:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:00:45 INFO - 'use_talos_json': True, 08:00:45 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 08:00:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:00:45 INFO - 'work_dir': 'build'} 08:00:45 INFO - ##### 08:00:45 INFO - ##### Running clobber step. 08:00:45 INFO - ##### 08:00:45 INFO - Running pre-action listener: _resource_record_pre_action 08:00:45 INFO - Running main action method: clobber 08:00:45 INFO - rmtree: /builds/slave/test-pgo/build 08:00:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 08:00:45 INFO - Running post-action listener: _resource_record_post_action 08:00:45 INFO - ##### 08:00:45 INFO - ##### Running read-buildbot-config step. 08:00:45 INFO - ##### 08:00:45 INFO - Running pre-action listener: _resource_record_pre_action 08:00:45 INFO - Running main action method: read_buildbot_config 08:00:45 INFO - Using buildbot properties: 08:00:45 INFO - { 08:00:45 INFO - "project": "", 08:00:45 INFO - "product": "firefox", 08:00:45 INFO - "script_repo_revision": "production", 08:00:45 INFO - "builddir": "fx-team_ubuntu64_hw_test-g2", 08:00:45 INFO - "repository": "", 08:00:45 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos g2", 08:00:45 INFO - "buildid": "20160127053017", 08:00:45 INFO - "pgo_build": "True", 08:00:45 INFO - "basedir": "/builds/slave/test-pgo", 08:00:45 INFO - "buildnumber": 651, 08:00:45 INFO - "slavename": "talos-linux64-ix-032", 08:00:45 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:00:45 INFO - "platform": "ubuntu64_hw", 08:00:45 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 08:00:45 INFO - "branch": "fx-team", 08:00:45 INFO - "repo_path": "integration/fx-team", 08:00:45 INFO - "revision": "60c5168a485cb166abd27b8eb0b83b919ac4b166", 08:00:45 INFO - "stage_platform": "linux64-pgo", 08:00:45 INFO - "builduid": "f6c3a2ad3b394252ac4a824b687bb89d", 08:00:45 INFO - "slavebuilddir": "test" 08:00:45 INFO - } 08:00:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2. 08:00:45 INFO - Running post-action listener: _resource_record_post_action 08:00:45 INFO - ##### 08:00:45 INFO - ##### Running download-and-extract step. 08:00:45 INFO - ##### 08:00:45 INFO - Running pre-action listener: _resource_record_pre_action 08:00:45 INFO - Running main action method: download_and_extract 08:00:45 INFO - mkdir: /builds/slave/test-pgo/build/tests 08: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')]} 08:00:45 INFO - https://queue.taskcluster.net/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 08:00:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/test_packages.json 08:00:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/test_packages.json 08:00:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 08:00:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 08:00:46 INFO - Downloaded 1448 bytes. 08:00:46 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 08:00:46 INFO - Using the following test package requirements: 08:00:46 INFO - {u'common': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 08:00:46 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 08:00:46 INFO - u'firefox-47.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:00:46 INFO - u'gtest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 08:00:46 INFO - u'firefox-47.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:00:46 INFO - u'jittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 08:00:46 INFO - u'jsshell-linux-x86_64.zip'], 08:00:46 INFO - u'mochitest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 08:00:46 INFO - u'firefox-47.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:00:46 INFO - u'mozbase': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 08:00:46 INFO - u'reftest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 08:00:46 INFO - u'firefox-47.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:00:46 INFO - u'talos': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 08:00:46 INFO - u'firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:00:46 INFO - u'web-platform': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 08:00:46 INFO - u'firefox-47.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:00:46 INFO - u'webapprt': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 08:00:46 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 08:00:46 INFO - u'firefox-47.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:00:46 INFO - Downloading packages: [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:00:46 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')]} 08:00:46 INFO - https://queue.taskcluster.net/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:00:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 08:00:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 08:00:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 08:00:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:00:47 INFO - Downloaded 22503517 bytes. 08:00:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 08:00:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 08:00:49 INFO - Return code: 0 08:00:49 INFO - Downloading packages: [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:00:49 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')]} 08:00:49 INFO - https://queue.taskcluster.net/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:00:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 08:00:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 08:00:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 08:00:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:00:50 INFO - Downloaded 22503517 bytes. 08:00:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 08:00:50 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test-pgo/build/tests 08:00:51 INFO - Return code: 0 08:00:51 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')]} 08:00:51 INFO - https://queue.taskcluster.net/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:00:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 08:00:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 08:00:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 08:00:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:00:52 INFO - Downloaded 10706572 bytes. 08:00:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test-pgo/build/tests'] 08:00:52 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test-pgo/build/tests 08:00:52 INFO - Return code: 0 08:00:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:52 INFO - https://queue.taskcluster.net/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:00:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 08:00:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 08:00:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 08:00:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:00:54 INFO - Downloaded 55980975 bytes. 08:00:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 08:00:54 INFO - mkdir: /builds/slave/test-pgo/properties 08:00:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 08:00:54 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 08:00:54 INFO - Contents: 08:00:54 INFO - build_url:https://queue.taskcluster.net/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 08:00:54 INFO - Running post-action listener: _resource_record_post_action 08:00:54 INFO - Running post-action listener: set_extra_try_arguments 08:00:54 INFO - ##### 08:00:54 INFO - ##### Running populate-webroot step. 08:00:54 INFO - ##### 08:00:54 INFO - Running pre-action listener: _resource_record_pre_action 08:00:54 INFO - Running main action method: populate_webroot 08:00:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:00:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:00:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:00:54 INFO - '--tppagecycles', 08:00:54 INFO - '7'], 08:00:54 INFO - 'tests': ['tsvgm']}}, 08:00:54 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:00:54 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:00:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:00:54 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:00:54 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:00:54 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:00:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:00:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:54 INFO - 'talos_options': ['--e10s'], 08:00:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:00:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:54 INFO - 'tests': ['damp', 'tps']}, 08:00:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:54 INFO - 'talos_options': ['--e10s'], 08:00:54 INFO - 'tests': ['damp', 'tps']}, 08:00:54 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:00:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:00:54 INFO - 'other': {'tests': ['a11yr', 08:00:54 INFO - 'ts_paint', 08:00:54 INFO - 'tpaint', 08:00:54 INFO - 'sessionrestore', 08:00:54 INFO - 'sessionrestore_no_auto_restore']}, 08:00:54 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:00:54 INFO - 'tests': ['a11yr', 08:00:54 INFO - 'ts_paint', 08:00:54 INFO - 'tpaint', 08:00:54 INFO - 'sessionrestore', 08:00:54 INFO - 'sessionrestore_no_auto_restore']}, 08:00:54 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:00:54 INFO - 'tests': ['a11yr', 08:00:54 INFO - 'ts_paint', 08:00:54 INFO - 'tpaint', 08:00:54 INFO - 'sessionrestore', 08:00:54 INFO - 'sessionrestore_no_auto_restore']}, 08:00:54 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:00:54 INFO - 'tests': ['a11yr', 08:00:54 INFO - 'ts_paint', 08:00:54 INFO - 'tpaint', 08:00:54 INFO - 'sessionrestore', 08:00:54 INFO - 'sessionrestore_no_auto_restore']}, 08:00:54 INFO - 'other_l64': {'tests': ['a11yr', 08:00:54 INFO - 'ts_paint', 08:00:54 INFO - 'tpaint', 08:00:54 INFO - 'sessionrestore', 08:00:54 INFO - 'sessionrestore_no_auto_restore']}, 08:00:54 INFO - 'other_nol64': {'tests': ['a11yr', 08:00:54 INFO - 'ts_paint', 08:00:54 INFO - 'tpaint', 08:00:54 INFO - 'sessionrestore', 08:00:54 INFO - 'sessionrestore_no_auto_restore']}, 08:00:54 INFO - 'svgr': {'tests': ['tsvgx', 08:00:54 INFO - 'tsvgr_opacity', 08:00:54 INFO - 'tart', 08:00:54 INFO - 'tscrollx', 08:00:54 INFO - 'cart']}, 08:00:54 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:00:54 INFO - 'tests': ['tsvgx', 08:00:54 INFO - 'tsvgr_opacity', 08:00:54 INFO - 'tart', 08:00:54 INFO - 'tscrollx', 08:00:54 INFO - 'cart']}, 08:00:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:54 INFO - 'tests': ['tp5o']}, 08:00:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:54 INFO - 'talos_options': ['--e10s'], 08:00:54 INFO - 'tests': ['tp5o']}, 08:00:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:54 INFO - 'talos_options': ['--xperf_path', 08:00:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:00:54 INFO - 'tests': ['tp5n']}, 08:00:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:00:54 INFO - 'talos_options': ['--e10s', 08:00:54 INFO - '--xperf_path', 08:00:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:00:54 INFO - 'tests': ['tp5n']}}, 08:00:54 INFO - 'talos.zip': {'path': '', 08:00:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:00:54 INFO - Downloading pageset... 08:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:00:54 INFO - trying http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip 08:00:54 INFO - Downloading http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip to /builds/slave/test-pgo/build/tp5n.zip 08:00:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 'file_name': '/builds/slave/test-pgo/build/tp5n.zip'}, attempt #1 08:00:54 INFO - Downloaded 81753769 bytes. 08:00:54 INFO - Running command: ['unzip', '-q', '-o', '/builds/slave/test-pgo/build/tp5n.zip', '-d', '/builds/slave/test-pgo/build/tests/talos/talos/tests'] 08:00:54 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/tp5n.zip -d /builds/slave/test-pgo/build/tests/talos/talos/tests 08:00:59 INFO - Return code: 0 08:00:59 INFO - Running post-action listener: _resource_record_post_action 08:00:59 INFO - ##### 08:00:59 INFO - ##### Running create-virtualenv step. 08:00:59 INFO - ##### 08:00:59 INFO - Running pre-action listener: _resource_record_pre_action 08:00:59 INFO - Running main action method: create_virtualenv 08:00:59 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 08:00:59 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 08:00:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 08:00:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:00:59 INFO - Using real prefix '/usr' 08:00:59 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 08:01:00 INFO - Installing distribute.............................................................................................................................................................................................done. 08:01:03 INFO - Installing pip.................done. 08:01:03 INFO - Return code: 0 08:01:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 08:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faaf5a06f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d24840>, '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 0x2c916b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c91b60>, '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': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', '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 08:01:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 08:01:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:01:03 INFO - Using env: {'DISPLAY': ':0', 08:01:03 INFO - 'HOME': '/home/cltbld', 08:01:03 INFO - 'LANG': 'en_US.UTF-8', 08:01:03 INFO - 'LANGUAGE': 'en_US:en', 08:01:03 INFO - 'LOGNAME': 'cltbld', 08:01:03 INFO - 'MAIL': '/var/mail/cltbld', 08:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:03 INFO - 'MOZ_NO_REMOTE': '1', 08:01:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:01:03 INFO - 'NO_EM_RESTART': '1', 08:01:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:01:03 INFO - 'PWD': '/builds/slave/test-pgo', 08:01:03 INFO - 'SHELL': '/bin/bash', 08:01:03 INFO - 'SHLVL': '1', 08:01:03 INFO - 'TERM': 'linux', 08:01:03 INFO - 'TMOUT': '86400', 08:01:03 INFO - 'USER': 'cltbld', 08:01:03 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', 08:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:03 INFO - '_': '/tools/buildbot/bin/python'} 08:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:03 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 08:01:03 INFO - Cleaning up... 08:01:03 INFO - Return code: 0 08:01:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 08:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:03 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7faaf5a06f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d24840>, '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 0x2c916b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c91b60>, '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': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', '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 08:01:03 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 08:01:03 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 08:01:03 INFO - Using env: {'DISPLAY': ':0', 08:01:03 INFO - 'HOME': '/home/cltbld', 08:01:03 INFO - 'LANG': 'en_US.UTF-8', 08:01:03 INFO - 'LANGUAGE': 'en_US:en', 08:01:03 INFO - 'LOGNAME': 'cltbld', 08:01:03 INFO - 'MAIL': '/var/mail/cltbld', 08:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:03 INFO - 'MOZ_NO_REMOTE': '1', 08:01:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:01:03 INFO - 'NO_EM_RESTART': '1', 08:01:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:01:03 INFO - 'PWD': '/builds/slave/test-pgo', 08:01:03 INFO - 'SHELL': '/bin/bash', 08:01:03 INFO - 'SHLVL': '1', 08:01:03 INFO - 'TERM': 'linux', 08:01:03 INFO - 'TMOUT': '86400', 08:01:03 INFO - 'USER': 'cltbld', 08:01:03 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', 08:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:03 INFO - '_': '/tools/buildbot/bin/python'} 08:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:04 INFO - Downloading/unpacking psutil>=0.7.1 08:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:05 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 08:01:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:01:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:01:05 INFO - Installing collected packages: psutil 08:01:05 INFO - Running setup.py install for psutil 08:01:06 INFO - building 'psutil._psutil_linux' extension 08:01:06 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 08:01:06 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 08:01:06 INFO - building 'psutil._psutil_posix' extension 08:01:06 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 08:01:06 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 08:01:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:01:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:01:07 INFO - Successfully installed psutil 08:01:07 INFO - Cleaning up... 08:01:09 INFO - Return code: 0 08:01:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 08: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')]} 08:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08: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')]} 08:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08: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', '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 0x7faaf5a06f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d24840>, '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 0x2c916b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c91b60>, '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': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', '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 08: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 08: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 mozsystemmonitor==0.0.0 08:01:09 INFO - Using env: {'DISPLAY': ':0', 08:01:09 INFO - 'HOME': '/home/cltbld', 08:01:09 INFO - 'LANG': 'en_US.UTF-8', 08:01:09 INFO - 'LANGUAGE': 'en_US:en', 08:01:09 INFO - 'LOGNAME': 'cltbld', 08:01:09 INFO - 'MAIL': '/var/mail/cltbld', 08:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:09 INFO - 'MOZ_NO_REMOTE': '1', 08:01:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:01:09 INFO - 'NO_EM_RESTART': '1', 08:01:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:01:09 INFO - 'PWD': '/builds/slave/test-pgo', 08:01:09 INFO - 'SHELL': '/bin/bash', 08:01:09 INFO - 'SHLVL': '1', 08:01:09 INFO - 'TERM': 'linux', 08:01:09 INFO - 'TMOUT': '86400', 08:01:09 INFO - 'USER': 'cltbld', 08:01:09 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', 08:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:09 INFO - '_': '/tools/buildbot/bin/python'} 08:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08: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 08: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 08: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 08: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 08: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 08: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 08:01:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:01:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:01:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:01:11 INFO - Installing collected packages: mozsystemmonitor 08:01:13 INFO - Running setup.py install for mozsystemmonitor 08:01:13 INFO - Successfully installed mozsystemmonitor 08:01:13 INFO - Cleaning up... 08:01:15 INFO - Return code: 0 08:01:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 08:01:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:15 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 0x7faaf5a06f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d24840>, '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 0x2c916b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c91b60>, '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': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', '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 08:01:15 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 08:01:15 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 08:01:15 INFO - Using env: {'DISPLAY': ':0', 08:01:15 INFO - 'HOME': '/home/cltbld', 08:01:15 INFO - 'LANG': 'en_US.UTF-8', 08:01:15 INFO - 'LANGUAGE': 'en_US:en', 08:01:15 INFO - 'LOGNAME': 'cltbld', 08:01:15 INFO - 'MAIL': '/var/mail/cltbld', 08:01:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:15 INFO - 'MOZ_NO_REMOTE': '1', 08:01:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:01:15 INFO - 'NO_EM_RESTART': '1', 08:01:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:01:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:01:15 INFO - 'PWD': '/builds/slave/test-pgo', 08:01:15 INFO - 'SHELL': '/bin/bash', 08:01:15 INFO - 'SHLVL': '1', 08:01:15 INFO - 'TERM': 'linux', 08:01:15 INFO - 'TMOUT': '86400', 08:01:15 INFO - 'USER': 'cltbld', 08:01:15 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', 08:01:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:15 INFO - '_': '/tools/buildbot/bin/python'} 08:01:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:15 INFO - Downloading/unpacking blobuploader==1.2.4 08:01:15 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 08:01:15 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 08:01:15 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 08:01:15 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 08:01:15 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 08:01:15 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 08:01:17 INFO - Downloading blobuploader-1.2.4.tar.gz 08:01:17 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:01:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:01:17 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 08:01:17 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 08:01:17 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 08:01:17 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 08:01:17 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 08:01:17 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 08:01:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 08:01:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:01:18 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 08:01:18 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 08:01:18 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 08:01:18 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 08:01:18 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 08:01:18 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 08:01:18 INFO - Downloading docopt-0.6.1.tar.gz 08:01:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 08:01:18 INFO - Installing collected packages: blobuploader, requests, docopt 08:01:18 INFO - Running setup.py install for blobuploader 08:01:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:01:18 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 08:01:18 INFO - Running setup.py install for requests 08:01:18 INFO - Running setup.py install for docopt 08:01:18 INFO - Successfully installed blobuploader requests docopt 08:01:18 INFO - Cleaning up... 08:01:19 INFO - Return code: 0 08:01:19 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:01:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:19 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 0x7faaf5a06f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d24840>, '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 0x2c916b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c91b60>, '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': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', '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 08:01:19 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 08:01:19 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 08:01:19 INFO - Using env: {'DISPLAY': ':0', 08:01:19 INFO - 'HOME': '/home/cltbld', 08:01:19 INFO - 'LANG': 'en_US.UTF-8', 08:01:19 INFO - 'LANGUAGE': 'en_US:en', 08:01:19 INFO - 'LOGNAME': 'cltbld', 08:01:19 INFO - 'MAIL': '/var/mail/cltbld', 08:01:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:19 INFO - 'MOZ_NO_REMOTE': '1', 08:01:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:01:19 INFO - 'NO_EM_RESTART': '1', 08:01:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:01:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:01:19 INFO - 'PWD': '/builds/slave/test-pgo', 08:01:19 INFO - 'SHELL': '/bin/bash', 08:01:19 INFO - 'SHLVL': '1', 08:01:19 INFO - 'TERM': 'linux', 08:01:19 INFO - 'TMOUT': '86400', 08:01:19 INFO - 'USER': 'cltbld', 08:01:19 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', 08:01:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:19 INFO - '_': '/tools/buildbot/bin/python'} 08:01:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:01:19 INFO - Running setup.py (path:/tmp/pip-UFqVki-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:01:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:01:19 INFO - Running setup.py (path:/tmp/pip-gmfxMA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:01:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:01:19 INFO - Running setup.py (path:/tmp/pip-0K8gZ2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:01:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:01:19 INFO - Running setup.py (path:/tmp/pip-AyrtyF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:01:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:01:19 INFO - Running setup.py (path:/tmp/pip-23tMMc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:01:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:01:19 INFO - Running setup.py (path:/tmp/pip-re3gSD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:01:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:01:19 INFO - Running setup.py (path:/tmp/pip-XebUMn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:01:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:01:19 INFO - Running setup.py (path:/tmp/pip-yhJo9h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:01:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:01:19 INFO - Running setup.py (path:/tmp/pip-No6R_a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:01:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:01:19 INFO - Running setup.py (path:/tmp/pip-va1pXr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:01:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:01:19 INFO - Running setup.py (path:/tmp/pip-0rYgFw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:01:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:01:20 INFO - Running setup.py (path:/tmp/pip-0W3qms-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:01:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:01:20 INFO - Running setup.py (path:/tmp/pip-27Od3z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:01:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:01:20 INFO - Running setup.py (path:/tmp/pip-rOXkWH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:01:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:01:20 INFO - Running setup.py (path:/tmp/pip-NV8YIe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:01:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:01:20 INFO - Running setup.py (path:/tmp/pip-9sA5Hv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:01:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:01:20 INFO - Running setup.py (path:/tmp/pip-ZSClx_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:01:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:01:20 INFO - Running setup.py install for manifestparser 08:01:20 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 08:01:20 INFO - Running setup.py install for mozcrash 08:01:20 INFO - Running setup.py install for mozdebug 08:01:20 INFO - Running setup.py install for mozdevice 08:01:20 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 08:01:20 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 08:01:20 INFO - Running setup.py install for mozfile 08:01:20 INFO - Running setup.py install for mozhttpd 08:01:21 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 08:01:21 INFO - Running setup.py install for mozinfo 08:01:21 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 08:01:21 INFO - Running setup.py install for mozInstall 08:01:21 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 08:01:21 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 08:01:21 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 08:01:21 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 08:01:21 INFO - Running setup.py install for mozleak 08:01:21 INFO - Running setup.py install for mozlog 08:01:21 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 08:01:21 INFO - Running setup.py install for moznetwork 08:01:21 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 08:01:21 INFO - Running setup.py install for mozprocess 08:01:21 INFO - Running setup.py install for mozprofile 08:01:21 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 08:01:21 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 08:01:21 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 08:01:21 INFO - Running setup.py install for mozrunner 08:01:21 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 08:01:21 INFO - Running setup.py install for mozscreenshot 08:01:22 INFO - Running setup.py install for moztest 08:01:22 INFO - Running setup.py install for mozversion 08:01:22 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 08:01:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:01:22 INFO - Cleaning up... 08:01:22 INFO - Return code: 0 08:01:22 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:01:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:22 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 0x7faaf5a06f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d24840>, '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 0x2c916b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c91b60>, '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': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', '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 08:01:22 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 08:01:22 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 08:01:22 INFO - Using env: {'DISPLAY': ':0', 08:01:22 INFO - 'HOME': '/home/cltbld', 08:01:22 INFO - 'LANG': 'en_US.UTF-8', 08:01:22 INFO - 'LANGUAGE': 'en_US:en', 08:01:22 INFO - 'LOGNAME': 'cltbld', 08:01:22 INFO - 'MAIL': '/var/mail/cltbld', 08:01:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:22 INFO - 'MOZ_NO_REMOTE': '1', 08:01:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:01:22 INFO - 'NO_EM_RESTART': '1', 08:01:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:01:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:01:22 INFO - 'PWD': '/builds/slave/test-pgo', 08:01:22 INFO - 'SHELL': '/bin/bash', 08:01:22 INFO - 'SHLVL': '1', 08:01:22 INFO - 'TERM': 'linux', 08:01:22 INFO - 'TMOUT': '86400', 08:01:22 INFO - 'USER': 'cltbld', 08:01:22 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', 08:01:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:22 INFO - '_': '/tools/buildbot/bin/python'} 08:01:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:01:22 INFO - Running setup.py (path:/tmp/pip-2DfMUg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:01:22 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)) 08:01:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:01:22 INFO - Running setup.py (path:/tmp/pip-W83kGw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:01:22 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)) 08:01:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:01:22 INFO - Running setup.py (path:/tmp/pip-9zJcAL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:01:22 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)) 08:01:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:01:22 INFO - Running setup.py (path:/tmp/pip-eLgl4V-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:01:22 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)) 08:01:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:01:22 INFO - Running setup.py (path:/tmp/pip-I6jduw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:01:22 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)) 08:01:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:01:22 INFO - Running setup.py (path:/tmp/pip-dJpqce-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:01:22 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)) 08:01:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:01:22 INFO - Running setup.py (path:/tmp/pip-82IfHk-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:01:22 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)) 08:01:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:01:22 INFO - Running setup.py (path:/tmp/pip-RvzmIG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:01:23 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)) 08:01:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:01:23 INFO - Running setup.py (path:/tmp/pip-TJIcMK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:01:23 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)) 08:01:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:01:23 INFO - Running setup.py (path:/tmp/pip-4HNKKE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:01:23 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)) 08:01:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:01:23 INFO - Running setup.py (path:/tmp/pip-FuAfE9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:01:23 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)) 08:01:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:01:23 INFO - Running setup.py (path:/tmp/pip-sMf46g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:01:23 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)) 08:01:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:01:23 INFO - Running setup.py (path:/tmp/pip-hylxyW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:01:23 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)) 08:01:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:01:23 INFO - Running setup.py (path:/tmp/pip-AtqRHW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:01:23 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)) 08:01:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:01:23 INFO - Running setup.py (path:/tmp/pip-AWWKdU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:01:23 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)) 08:01:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:01:23 INFO - Running setup.py (path:/tmp/pip-etV_mn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:01:23 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)) 08:01:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:01:23 INFO - Running setup.py (path:/tmp/pip-cP92XU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:01:23 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)) 08:01:23 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)) 08:01:23 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)) 08:01:23 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)) 08:01:23 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)) 08:01:23 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)) 08:01:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:25 INFO - Downloading blessings-1.6.tar.gz 08:01:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 08:01:25 INFO - Installing collected packages: blessings 08:01:25 INFO - Running setup.py install for blessings 08:01:25 INFO - Successfully installed blessings 08:01:25 INFO - Cleaning up... 08:01:25 INFO - Return code: 0 08:01:25 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 08:01:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:01:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:01:25 INFO - Reading from file tmpfile_stdout 08:01:25 INFO - Current package versions: 08:01:25 INFO - argparse == 1.2.1 08:01:25 INFO - blessings == 1.6 08:01:25 INFO - blobuploader == 1.2.4 08:01:25 INFO - docopt == 0.6.1 08:01:25 INFO - manifestparser == 1.1 08:01:25 INFO - mozInstall == 1.12 08:01:25 INFO - mozcrash == 0.16 08:01:25 INFO - mozdebug == 0.1 08:01:25 INFO - mozdevice == 0.48 08:01:25 INFO - mozfile == 1.2 08:01:25 INFO - mozhttpd == 0.7 08:01:25 INFO - mozinfo == 0.9 08:01:25 INFO - mozleak == 0.1 08:01:25 INFO - mozlog == 3.1 08:01:25 INFO - moznetwork == 0.27 08:01:25 INFO - mozprocess == 0.22 08:01:25 INFO - mozprofile == 0.28 08:01:25 INFO - mozrunner == 6.11 08:01:25 INFO - mozscreenshot == 0.1 08:01:25 INFO - mozsystemmonitor == 0.0 08:01:25 INFO - moztest == 0.7 08:01:25 INFO - mozversion == 1.4 08:01:25 INFO - psutil == 3.1.1 08:01:25 INFO - requests == 1.2.3 08:01:25 INFO - wsgiref == 0.1.2 08:01:25 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:25 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 0x7faaf5a06f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d24840>, '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 0x2c916b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c91b60>, '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': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', '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 08:01:25 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 08:01:25 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 08:01:25 INFO - Using env: {'DISPLAY': ':0', 08:01:25 INFO - 'HOME': '/home/cltbld', 08:01:25 INFO - 'LANG': 'en_US.UTF-8', 08:01:25 INFO - 'LANGUAGE': 'en_US:en', 08:01:25 INFO - 'LOGNAME': 'cltbld', 08:01:25 INFO - 'MAIL': '/var/mail/cltbld', 08:01:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:25 INFO - 'MOZ_NO_REMOTE': '1', 08:01:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:01:25 INFO - 'NO_EM_RESTART': '1', 08:01:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:01:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:01:25 INFO - 'PWD': '/builds/slave/test-pgo', 08:01:25 INFO - 'SHELL': '/bin/bash', 08:01:25 INFO - 'SHLVL': '1', 08:01:25 INFO - 'TERM': 'linux', 08:01:25 INFO - 'TMOUT': '86400', 08:01:25 INFO - 'USER': 'cltbld', 08:01:25 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', 08:01:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:25 INFO - '_': '/tools/buildbot/bin/python'} 08:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:26 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)) 08:01:26 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)) 08:01:26 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)) 08:01:26 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)) 08:01:26 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)) 08:01:26 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)) 08:01:26 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)) 08:01:26 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)) 08:01:26 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)) 08:01:26 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)) 08:01:26 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)) 08:01:26 INFO - Cleaning up... 08:01:26 INFO - Return code: 0 08:01:26 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 08:01:26 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')]} 08:01:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:26 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')]} 08:01:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:26 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 0x7faaf5a06f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d24840>, '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 0x2c916b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c91b60>, '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': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', '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 08:01:26 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 08:01:26 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 08:01:26 INFO - Using env: {'DISPLAY': ':0', 08:01:26 INFO - 'HOME': '/home/cltbld', 08:01:26 INFO - 'LANG': 'en_US.UTF-8', 08:01:26 INFO - 'LANGUAGE': 'en_US:en', 08:01:26 INFO - 'LOGNAME': 'cltbld', 08:01:26 INFO - 'MAIL': '/var/mail/cltbld', 08:01:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:26 INFO - 'MOZ_NO_REMOTE': '1', 08:01:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:01:26 INFO - 'NO_EM_RESTART': '1', 08:01:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:01:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:01:26 INFO - 'PWD': '/builds/slave/test-pgo', 08:01:26 INFO - 'SHELL': '/bin/bash', 08:01:26 INFO - 'SHLVL': '1', 08:01:26 INFO - 'TERM': 'linux', 08:01:26 INFO - 'TMOUT': '86400', 08:01:26 INFO - 'USER': 'cltbld', 08:01:26 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', 08:01:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:26 INFO - '_': '/tools/buildbot/bin/python'} 08:01:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:26 INFO - Downloading/unpacking jsonschema 08:01:26 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 08:01:26 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 08:01:26 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 08:01:26 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 08:01:26 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 08:01:26 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 08:01:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:01:28 INFO - Downloading/unpacking functools32 (from jsonschema) 08:01:28 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 08:01:28 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 08:01:28 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 08:01:28 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 08:01:28 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 08:01:28 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 08:01:28 INFO - Downloading functools32-3.2.3-2.tar.gz 08:01:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 08:01:28 INFO - warning: no files found matching '*.txt' 08:01:28 INFO - no previously-included directories found matching 'build' 08:01:28 INFO - no previously-included directories found matching 'dist' 08:01:28 INFO - no previously-included directories found matching '.git*' 08:01:28 INFO - Installing collected packages: jsonschema, functools32 08:01:29 INFO - Running setup.py install for functools32 08:01:29 INFO - warning: no files found matching '*.txt' 08:01:29 INFO - no previously-included directories found matching 'build' 08:01:29 INFO - no previously-included directories found matching 'dist' 08:01:29 INFO - no previously-included directories found matching '.git*' 08:01:29 INFO - Successfully installed jsonschema functools32 08:01:29 INFO - Cleaning up... 08:01:29 INFO - Return code: 0 08:01:29 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 08:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:01:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:01:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:01:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:01:29 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 0x7faaf5a06f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c93630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d24840>, '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 0x2c916b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c91b60>, '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': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', '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 08:01:29 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 08:01:29 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 08:01:29 INFO - Using env: {'DISPLAY': ':0', 08:01:29 INFO - 'HOME': '/home/cltbld', 08:01:29 INFO - 'LANG': 'en_US.UTF-8', 08:01:29 INFO - 'LANGUAGE': 'en_US:en', 08:01:29 INFO - 'LOGNAME': 'cltbld', 08:01:29 INFO - 'MAIL': '/var/mail/cltbld', 08:01:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:29 INFO - 'MOZ_NO_REMOTE': '1', 08:01:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:01:29 INFO - 'NO_EM_RESTART': '1', 08:01:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:01:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:01:29 INFO - 'PWD': '/builds/slave/test-pgo', 08:01:29 INFO - 'SHELL': '/bin/bash', 08:01:29 INFO - 'SHLVL': '1', 08:01:29 INFO - 'TERM': 'linux', 08:01:29 INFO - 'TMOUT': '86400', 08:01:29 INFO - 'USER': 'cltbld', 08:01:29 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', 08:01:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:29 INFO - '_': '/tools/buildbot/bin/python'} 08:01:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:01:30 INFO - Downloading/unpacking flake8 08:01:30 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 08:01:30 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 08:01:30 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 08:01:30 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 08:01:30 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 08:01:30 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 08:01:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:01:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:01:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:01:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:01:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:01:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:01:32 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 08:01:32 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 08:01:32 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 08:01:32 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 08:01:32 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 08:01:32 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 08:01:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:01:32 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 08:01:32 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 08:01:32 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 08:01:32 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 08:01:32 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 08:01:32 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 08:01:32 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 08:01:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:01:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:01:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:01:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:01:32 INFO - no previously-included directories found matching 'docs/_build' 08:01:32 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:01:32 INFO - Running setup.py install for pep8 08:01:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:01:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:01:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:01:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:01:32 INFO - no previously-included directories found matching 'docs/_build' 08:01:32 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 08:01:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:01:32 INFO - Cleaning up... 08:01:32 INFO - Return code: 0 08:01:32 INFO - Running post-action listener: _resource_record_post_action 08:01:32 INFO - Running post-action listener: _start_resource_monitoring 08:01:32 INFO - Starting resource monitoring. 08:01:32 INFO - ##### 08:01:32 INFO - ##### Running install step. 08:01:32 INFO - ##### 08:01:32 INFO - Running pre-action listener: _resource_record_pre_action 08:01:32 INFO - Running main action method: install 08:01:32 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:01:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:01:32 INFO - Reading from file tmpfile_stdout 08:01:32 INFO - Detecting whether we're running mozinstall >=1.0... 08:01:32 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 08:01:32 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 08:01:33 INFO - Reading from file tmpfile_stdout 08:01:33 INFO - Output received: 08:01:33 INFO - Usage: mozinstall [options] installer 08:01:33 INFO - Options: 08:01:33 INFO - -h, --help show this help message and exit 08:01:33 INFO - -d DEST, --destination=DEST 08:01:33 INFO - Directory to install application into. [default: 08:01:33 INFO - "/builds/slave/test-pgo"] 08:01:33 INFO - --app=APP Application being installed. [default: firefox] 08:01:33 INFO - mkdir: /builds/slave/test-pgo/build/application 08:01:33 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 08:01:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 08:01:49 INFO - Reading from file tmpfile_stdout 08:01:49 INFO - Output received: 08:01:49 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 08:01:49 INFO - Running post-action listener: _resource_record_post_action 08:01:49 INFO - ##### 08:01:49 INFO - ##### Running run-tests step. 08:01:49 INFO - ##### 08:01:49 INFO - Running pre-action listener: _resource_record_pre_action 08:01:49 INFO - Running main action method: run_tests 08:01:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 08:01:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 08:01:49 INFO - Python 2.7.3 08:01:49 INFO - Return code: 0 08:01:49 INFO - grabbing minidump binary from tooltool 08:01:49 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')]} 08:01:49 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 0x2d24840>, '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 0x2c916b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c91b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 08:01:49 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 08:01:49 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 08:01:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:01:49 INFO - Return code: 0 08:01:49 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 08:01:49 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 08:01:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 08:01:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 08:01:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 08:01:49 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 08:01:49 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 08:01:50 INFO - Return code: 0 08:01:50 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'g2', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/g2_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/g2_raw.log'] in /builds/slave/test-pgo/build 08:01:50 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite g2 --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/g2_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/g2_raw.log 08:01:50 INFO - Using env: {'DISPLAY': ':0', 08:01:50 INFO - 'HOME': '/home/cltbld', 08:01:50 INFO - 'LANG': 'en_US.UTF-8', 08:01:50 INFO - 'LANGUAGE': 'en_US:en', 08:01:50 INFO - 'LOGNAME': 'cltbld', 08:01:50 INFO - 'MAIL': '/var/mail/cltbld', 08:01:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:01:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 08:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:01:50 INFO - 'MOZ_NO_REMOTE': '1', 08:01:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:01:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:01:50 INFO - 'NO_EM_RESTART': '1', 08:01:50 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:01:50 INFO - 'PWD': '/builds/slave/test-pgo', 08:01:50 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 08:01:50 INFO - 'SHELL': '/bin/bash', 08:01:50 INFO - 'SHLVL': '1', 08:01:50 INFO - 'TERM': 'linux', 08:01:50 INFO - 'TMOUT': '86400', 08:01:50 INFO - 'USER': 'cltbld', 08:01:50 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1453910169.700118-1119135004', 08:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:01:50 INFO - '_': '/tools/buildbot/bin/python'} 08:01:50 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'g2', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/g2_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/g2_raw.log'] with output_timeout 3600 08:01:51 INFO - mozversion application_buildid: 20160127053017 08:01:51 INFO - mozversion application_changeset: 60c5168a485cb166abd27b8eb0b83b919ac4b166 08:01:51 INFO - mozversion application_display_name: Nightly 08:01:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:01:51 INFO - mozversion application_name: Firefox 08:01:51 INFO - mozversion application_remotingname: firefox 08:01:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:01:51 INFO - mozversion application_vendor: Mozilla 08:01:51 INFO - mozversion application_version: 47.0a1 08:01:51 INFO - mozversion platform_buildid: 20160127053017 08:01:51 INFO - mozversion platform_changeset: 60c5168a485cb166abd27b8eb0b83b919ac4b166 08:01:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:01:51 INFO - mozversion platform_version: 47.0a1 08:01:51 INFO - using testdate: 1453910511 08:01:51 INFO - actual date: 1453910511 08:01:51 INFO - starting webserver on 'localhost:39130' 08:01:51 INFO - SUITE-START | Running 2 tests 08:01:51 INFO - TEST-START | damp 08:01:51 INFO - operating with platform_type : linux_ 08:01:51 INFO - Initialising browser for damp test... 08:01:51 INFO - TEST-INFO | started process 12673 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8mfN9_/profile http://localhost:39130/getInfo.html) 08:01:56 INFO - PROCESS | 12673 | __metrics Screen width/height:1600/1200 08:01:56 INFO - PROCESS | 12673 | colorDepth:24 08:01:56 INFO - PROCESS | 12673 | Browser inner width/height: 1024/697 08:01:56 INFO - PROCESS | 12673 | __metrics 08:01:57 INFO - PROCESS | 12673 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 08:01:57 INFO - PROCESS | 12673 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:01:58 INFO - TEST-INFO | 12673: exit 0 08:01:58 INFO - Browser initialized. 08:01:58 INFO - Running cycle 1/1 for damp test... 08:01:58 INFO - TEST-INFO | started process 12770 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp8mfN9_/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/devtools/damp.manifest.develop -tpchrome -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 25) 08:02:10 INFO - PROCESS | 12770 | RSS: Main: 149528576 08:02:10 INFO - PROCESS | 12770 | 08:02:10 INFO - PROCESS | 12770 | console.error: 08:02:10 INFO - PROCESS | 12770 | Failed to load module devtools/shared/gcli/commands/mdn: Module `devtools/shared/gcli/commands/mdn` is not found at resource://devtools/shared/gcli/commands/mdn.js 08:02:10 INFO - PROCESS | 12770 | console.error: 08:02:10 INFO - PROCESS | 12770 | exports.createSystem/system.addItemsByModule/damp: 230.57 08:26:56 INFO - RETURN: tps: 48.82 08:26:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [1272.1450000000004, 292.7350000000006, 280.24000000000524, 285.61999999999534, 277.86999999999534, 283.4800000000105, 282.13000000000466, 306.2949999999837, 296.88999999995576, 305.46000000002095, 295.3099999999977, 282.42499999998836, 292.2600000000093, 311.21999999997206, 295.1450000000186, 279.4449999999488, 290.75, 295.98499999998603, 291.8649999999907, 286.6150000001071, 285.25, 285.6899999999441, 309.4700000000885, 284.53999999992084, 276.60999999998603], "unit": "ms", "name": "simple.webconsole.open.DAMP", "value": 288.68250000005355}, {"lowerIsBetter": true, "replicates": [37.340000000000146, 32.11499999999796, 32.38500000000931, 36.14499999998952, 33.470000000001164, 33.60500000001048, 32.574999999953434, 29.839999999967404, 34.625, 29.78499999997439, 34.460000000020955, 32.0449999999837, 36.10999999998603, 32.51000000000931, 33.23000000009779, 32.660000000032596, 30.440000000060536, 33.10999999998603, 30.0, 35.95999999996275, 37.97999999998137, 37.44499999994878, 33.45999999996275, 37.860000000102445, 28.90500000002794], "unit": "ms", "name": "simple.webconsole.reload.DAMP", "value": 33.17000000004191}, {"lowerIsBetter": true, "replicates": [43.350000000000364, 36.26000000000204, 36.74000000000524, 44.07500000001164, 73.91500000000815, 37.54999999998836, 34.71500000002561, 36.535000000032596, 39.0800000000163, 33.97499999997672, 35.65000000002328, 33.07500000001164, 41.09000000002561, 38.56499999994412, 36.214999999967404, 39.46999999997206, 42.93000000005122, 39.49499999999534, 49.64000000001397, 45.61999999999534, 45.09499999997206, 34.785000000032596, 43.44499999994878, 34.50500000000466, 36.66500000003725], "unit": "ms", "name": "simple.webconsole.close.DAMP", "value": 38.05749999996624}, {"lowerIsBetter": true, "replicates": [561.75, 384.7150000000038, 407.54499999999825, 361.91499999997905, 375.37999999997555, 385.0800000000163, 371.20999999996275, 383.34500000003027, 385.679999999993, 386.5449999999837, 391.15499999996973, 390.10999999998603, 371.2399999999907, 372.625, 376.7600000000093, 388.7399999999907, 364.4650000000838, 384.2150000000838, 382.9299999999348, 364.2949999999255, 381.5100000000093, 380.6500000000233, 390.39000000001397, 369.94999999995343, 380.4300000000512], "unit": "ms", "name": "simple.inspector.open.DAMP", "value": 382.21999999997206}, {"lowerIsBetter": true, "replicates": [120.27999999999884, 111.71499999999651, 99.85499999999593, 104.08499999999185, 123.38500000000931, 100.41000000000349, 101.21500000002561, 106.625, 102.65999999997439, 104.08499999996275, 110.54500000004191, 107.10000000003492, 105.83500000002095, 106.85000000009313, 104.60000000009313, 105.59999999997672, 106.47999999998137, 115.15500000002794, 109.93500000005588, 106.18999999994412, 110.10999999998603, 114.53000000002794, 108.10999999998603, 115.84499999997206, 104.68999999994412], "unit": "ms", "name": "simple.inspector.reload.DAMP", "value": 106.55249999999069}, {"lowerIsBetter": true, "replicates": [66.7549999999992, 56.56999999999971, 53.079999999987194, 51.76000000000931, 55.545000000012806, 55.375, 61.65500000002794, 56.3300000000163, 52.804999999993015, 55.58499999996275, 54.09500000003027, 56.82999999995809, 54.80999999999767, 54.82999999995809, 53.11999999999534, 55.15500000002794, 56.07999999995809, 52.61999999999534, 53.85499999998137, 55.375, 55.669999999925494, 55.83499999996275, 56.60999999998603, 53.28999999992084, 53.06999999994878], "unit": "ms", "name": "simple.inspector.close.DAMP", "value": 55.26500000001397}, {"lowerIsBetter": true, "replicates": [520.2199999999993, 399.5400000000009, 414.25500000000466, 414.2399999999907, 405.9000000000233, 401.75500000000466, 412.2299999999814, 402.8850000000093, 420.02999999996973, 434.5599999999977, 424.6599999999744, 402.1900000000023, 414.554999999993, 396.0800000000745, 412.6450000000186, 413.7700000000186, 396.33500000007916, 399.8149999999441, 398.7600000000093, 406.6450000000186, 406.7150000000838, 422.84499999997206, 429.9399999999441, 415.5700000000652, 413.83499999996275], "unit": "ms", "name": "simple.jsdebugger.open.DAMP", "value": 412.4375}, {"lowerIsBetter": true, "replicates": [39.61499999999978, 48.07499999999709, 34.77500000000873, 41.43499999999767, 46.67499999998836, 41.420000000012806, 34.570000000006985, 38.95999999996275, 40.24499999999534, 39.35999999998603, 35.64000000001397, 40.27999999996973, 40.85999999998603, 35.035000000032596, 87.60499999998137, 41.43999999994412, 40.33499999996275, 40.19500000006519, 35.44499999994878, 47.29999999993015, 35.07999999995809, 35.38000000000466, 42.5, 40.86499999999069, 52.964999999967404], "unit": "ms", "name": "simple.jsdebugger.reload.DAMP", "value": 40.30749999996624}, {"lowerIsBetter": true, "replicates": [31.26000000000022, 37.57499999999709, 35.01499999999942, 36.88500000000931, 36.51000000000931, 43.375, 36.54000000003725, 58.425000000046566, 60.26500000001397, 38.35999999998603, 36.19000000000233, 40.820000000006985, 63.04999999998836, 59.28999999992084, 63.04000000003725, 35.61499999999069, 34.96000000007916, 36.160000000032596, 40.38000000000466, 37.13500000000931, 57.59999999997672, 39.95000000006985, 37.17999999993481, 38.97999999998137, 35.714999999967404], "unit": "ms", "name": "simple.jsdebugger.close.DAMP", "value": 37.96749999999156}, {"lowerIsBetter": true, "replicates": [551.0900000000001, 582.2649999999994, 515.9400000000023, 553.5950000000012, 598.0, 747.4899999999907, 522.3099999999977, 584.3499999999767, 565.2900000000373, 523.375, 627.7549999999464, 518.2200000000303, 573.054999999993, 593.6600000000326, 667.3299999999581, 509.8549999999814, 536.1600000000326, 601.2600000000093, 515.6300000000047, 503.5899999999674, 593.0400000000373, 546.7750000000233, 514.9200000000419, 535.640000000014, 525.125], "unit": "ms", "name": "simple.styleeditor.open.DAMP", "value": 550.1850000000122}, {"lowerIsBetter": true, "replicates": [43.965000000000146, 42.87999999999738, 43.39499999998952, 54.92499999998836, 41.55999999999767, 127.96499999999651, 44.09000000002561, 53.464999999967404, 49.964999999967404, 46.09500000003027, 43.89000000001397, 44.289999999979045, 31.099999999976717, 39.02000000001863, 32.13000000000466, 32.17499999993015, 43.54500000004191, 77.41500000003725, 33.550000000046566, 43.59000000008382, 36.875, 31.48499999998603, 43.699999999953434, 44.59999999997672, 31.42499999993015], "unit": "ms", "name": "simple.styleeditor.reload.DAMP", "value": 43.567500000062864}, {"lowerIsBetter": true, "replicates": [39.44000000000051, 47.4800000000032, 46.30000000000291, 52.125, 134.19999999998254, 61.595000000001164, 45.3300000000163, 55.875, 48.570000000006985, 42.5449999999837, 53.25, 45.335000000020955, 40.85999999998603, 44.85499999998137, 49.67999999993481, 46.29500000004191, 49.63500000000931, 50.800000000046566, 43.92499999993015, 47.72999999998137, 51.46000000007916, 44.03000000002794, 45.29000000003725, 41.97499999997672, 89.31000000005588], "unit": "ms", "name": "simple.styleeditor.close.DAMP", "value": 47.60499999999229}, {"lowerIsBetter": true, "replicates": [406.77499999999964, 286.4300000000003, 295.5249999999942, 318.94999999998254, 342.77999999999884, 350.625, 274.5899999999674, 283.55000000004657, 322.29999999998836, 440.04999999998836, 383.23499999998603, 280.8649999999907, 287.3150000000023, 294.6149999999907, 315.2699999999022, 291.49499999999534, 291.36999999999534, 452.50500000000466, 286.65500000002794, 284.6350000000093, 336.3649999999907, 289.0450000000419, 295.6800000000512, 355.29000000003725, 531.6300000000047], "unit": "ms", "name": "simple.performance.open.DAMP", "value": 295.6025000000227}, {"lowerIsBetter": true, "replicates": [26.13000000000102, 27.764999999999418, 24.979999999995925, 33.81500000000233, 29.68499999999767, 27.920000000012806, 25.260000000009313, 26.479999999981374, 27.260000000009313, 42.73499999998603, 26.445000000006985, 25.479999999981374, 31.619999999995343, 28.239999999990687, 30.85999999998603, 25.67000000004191, 29.849999999976717, 26.300000000046566, 26.315000000060536, 33.39500000001863, 29.785000000032596, 26.5, 25.300000000046566, 77.95999999996275, 26.28000000002794], "unit": "ms", "name": "simple.performance.reload.DAMP", "value": 27.512500000004366}, {"lowerIsBetter": true, "replicates": [26.56999999999971, 34.45999999999913, 37.26499999999942, 32.789999999979045, 41.389999999984866, 36.60499999998137, 34.29500000004191, 32.78000000002794, 35.35499999998137, 44.79500000004191, 38.97999999998137, 32.98999999999069, 34.39000000001397, 33.07500000006985, 36.41499999992084, 31.849999999976717, 35.660000000032596, 38.065000000060536, 32.72999999998137, 36.56499999994412, 34.875, 35.46000000007916, 35.410000000032596, 57.535000000032596, 36.375], "unit": "ms", "name": "simple.performance.close.DAMP", "value": 35.43500000005588}, {"lowerIsBetter": true, "replicates": [344.6350000000002, 269.92499999999563, 262.6900000000023, 292.98499999998603, 277.98499999998603, 284.9899999999907, 272.6350000000093, 277.7050000000163, 276.6800000000512, 333.14000000001397, 293.15499999996973, 272.36999999999534, 294.25, 273.3549999999814, 282.2799999999115, 276.42499999993015, 540.5250000000233, 305.17500000004657, 281.29999999993015, 263.7299999999814, 281.1700000000419, 283.25500000000466, 285.8499999999767, 345.64000000001397, 298.48499999998603], "unit": "ms", "name": "simple.netmonitor.open.DAMP", "value": 281.78999999992084}, {"lowerIsBetter": true, "replicates": [71.69000000000051, 109.94499999999971, 39.195000000006985, 28.93000000002212, 33.125, 34.23499999998603, 29.78499999997439, 24.78000000002794, 87.10999999998603, 29.035000000032596, 38.14000000001397, 35.72999999998137, 41.33499999996275, 36.705000000074506, 29.645000000018626, 33.70999999996275, 36.14999999990687, 34.410000000032596, 36.67499999993015, 37.52000000001863, 81.83499999996275, 31.839999999967404, 34.26500000001397, 32.20499999995809, 36.39500000001863], "unit": "ms", "name": "simple.netmonitor.reload.DAMP", "value": 35.070000000006985}, {"lowerIsBetter": true, "replicates": [70.86999999999898, 72.65499999999884, 48.38000000000466, 54.11499999999069, 56.9550000000163, 53.61999999999534, 53.53000000002794, 107.01500000001397, 71.84000000002561, 48.0, 49.20000000001164, 53.09499999997206, 51.850000000034925, 109.17999999993481, 59.14000000001397, 125.34499999997206, 57.17999999993481, 50.97999999998137, 51.904999999911524, 50.08499999996275, 83.66500000003725, 54.07999999995809, 139.72499999997672, 54.67499999993015, 48.904999999911524], "unit": "ms", "name": "simple.netmonitor.close.DAMP", "value": 54.09749999997439}, {"lowerIsBetter": true, "replicates": [386.85999999999876, 606.7199999999939, 479.25999999999476, 494.2699999999895, 354.72000000000116, 332.6600000000035, 474.98500000004424, 453.1149999999907, 502.304999999993, 339.5800000000163, 363.695000000007, 478.445000000007, 646.2850000000326, 635.390000000014, 326.9799999999814, 630.5250000000233, 337.14000000001397, 332.4749999999767, 423.41500000003725, 677.0699999999488, 488.0600000000559, 420.5699999999488, 629.4699999999721, 346.6049999999814, 352.7299999999814], "unit": "ms", "name": "simple.memory.open.DAMP", "value": 464.05000000001746}, {"lowerIsBetter": true, "replicates": [54.22999999999956, 31.110000000000582, 82.17999999999302, 48.41500000000815, 32.59999999997672, 47.48000000001048, 69.23499999998603, 32.98499999998603, 37.94499999994878, 37.699999999953434, 58.460000000020955, 82.30999999999767, 43.6699999999837, 33.15500000002794, 31.625, 84.55499999993481, 33.78000000002794, 34.80500000005122, 80.1600000000326, 90.55499999993481, 32.6150000001071, 84.32999999995809, 37.03999999992084, 35.64500000001863, 58.125], "unit": "ms", "name": "simple.memory.reload.DAMP", "value": 40.80749999996624}, {"lowerIsBetter": true, "replicates": [70.59000000000015, 64.84500000000116, 342.2649999999994, 76.98500000001513, 69.59500000000116, 66.9200000000128, 353.19999999995343, 64.63500000000931, 68.86999999999534, 66.72999999998137, 69.4550000000163, 380.32500000001164, 73.43499999999767, 65.90000000002328, 72.14000000001397, 70.40000000002328, 71.59999999997672, 69.0, 336.5100000000093, 89.44000000006054, 71.99499999999534, 377.72500000009313, 70.55000000004657, 67.27000000001863, 69.16500000003725], "unit": "ms", "name": "simple.saveHeapSnapshot", "value": 70.47500000003492}, {"lowerIsBetter": true, "replicates": [12.224999999998545, 10.174999999995634, 9.189999999987776, 16.51500000001397, 11.614999999990687, 19.31500000000233, 7.255000000004657, 8.65500000002794, 14.539999999979045, 14.23499999998603, 10.434999999997672, 7.540000000037253, 15.46999999997206, 9.459999999962747, 7.9200000000419095, 15.410000000032596, 10.04500000004191, 14.059999999939464, 7.5, 7.410000000032596, 10.104999999981374, 7.160000000032596, 7.375, 11.565000000060536, 15.23499999998603], "unit": "ms", "name": "simple.readHeapSnapshot", "value": 10.139999999988504}, {"lowerIsBetter": true, "replicates": [3.9500000000007276, 4.125, 3.9949999999953434, 4.169999999983702, 4.10999999998603, 4.135000000009313, 4.150000000023283, 4.2950000000419095, 4.309999999997672, 4.149999999965075, 4.03000000002794, 3.98499999998603, 4.080000000016298, 4.25, 4.48499999998603, 4.1399999998975545, 6.550000000046566, 4.14000000001397, 4.175000000046566, 3.9949999999953434, 4.319999999948777, 4.539999999920838, 4.135000000009313, 4.1450000000186265, 4.025000000023283], "unit": "ms", "name": "simple.takeCensus", "value": 4.142500000016298}, {"lowerIsBetter": true, "replicates": [28.299999999999272, 35.81000000000495, 56.529999999998836, 42.33499999999185, 41.68499999999767, 39.30500000002212, 48.69000000000233, 37.38000000000466, 40.0, 34.17499999998836, 36.5800000000163, 43.14499999996042, 36.875, 33.89500000001863, 59.669999999925494, 25.584999999962747, 41.875, 23.64000000001397, 30.339999999967404, 41.15000000002328, 34.65500000002794, 47.36499999999069, 34.72499999997672, 22.739999999990687, 36.785000000032596], "unit": "ms", "name": "simple.memory.close.DAMP", "value": 37.12750000000233}, {"lowerIsBetter": true, "replicates": [472.04499999999825, 638.6450000000041, 550.8300000000017, 495.054999999993, 531.75, 550.445000000007, 543.5400000000373, 497.1050000000396, 509.1499999999651, 509.9400000000023, 552.5949999999721, 563.1749999999884, 512.414999999979, 497.0350000000326, 525.8299999999581, 545.9799999999814, 516.8950000000186, 520.4300000000512, 536.4250000000466, 531.8499999999767, 505.1450000000186, 532.4549999999581, 516.6149999999907, 511.17500000004657, 555.765000000014], "unit": "ms", "name": "complicated.webconsole.open.DAMP", "value": 528.789999999979}, {"lowerIsBetter": true, "replicates": [2062.7249999999985, 2171.010000000002, 2033.00499999999, 2051.329999999987, 2459.4800000000105, 2120.804999999993, 2041.3099999999977, 2045.4249999999884, 2047.0499999999884, 2099.7050000000163, 2103.625, 2017.0200000000186, 2043.984999999986, 2043.0050000000047, 2060.405000000028, 2081.5250000000233, 2166.6300000000047, 2056.069999999949, 2034.0100000000093, 2052.8649999999907, 2071.1849999999395, 2046.545000000042, 2098.704999999958, 2120.060000000056, 2089.04999999993], "unit": "ms", "name": "complicated.webconsole.reload.DAMP", "value": 2058.2374999999884}, {"lowerIsBetter": true, "replicates": [1115.329999999998, 730.560000000005, 698.7450000000099, 742.1849999999977, 690.945000000007, 639.3999999999942, 702.8499999999767, 723.4199999999837, 754.390000000014, 673.640000000014, 624.8500000000349, 695.4899999999907, 736.9900000000489, 766.3449999999721, 626.9349999999395, 708.6549999999115, 691.6350000000093, 628.6199999999953, 683.4300000000512, 697.375, 762.375, 706.2900000000373, 730.8299999999581, 658.0300000000279, 651.2149999999674], "unit": "ms", "name": "complicated.webconsole.close.DAMP", "value": 698.060000000005}, {"lowerIsBetter": true, "replicates": [636.9600000000028, 628.5049999999974, 702.8950000000041, 642.375, 893.1600000000035, 956.5400000000081, 707.9199999999837, 602.1399999999558, 612.1149999999907, 603.4199999999837, 922.0300000000279, 698.9349999999977, 650.6849999999395, 612.6149999999907, 676.0999999999767, 655.3649999999907, 616.3699999999953, 927.234999999986, 676.2149999999674, 634.5050000000047, 622.9500000000698, 708.8800000000047, 597.6049999999814, 605.9949999999953, 829.7600000000093], "unit": "ms", "name": "complicated.inspector.open.DAMP", "value": 653.0249999999651}, {"lowerIsBetter": true, "replicates": [1979.1349999999984, 2024.8700000000026, 2027.6199999999953, 2037.0050000000047, 1993.9949999999953, 2096.3150000000023, 2018.850000000035, 2023.7299999999814, 2019.7450000000536, 2443.2949999999837, 2313.5, 2027.4249999999884, 2043.5849999999627, 2029.3349999999627, 2017.844999999972, 2038.734999999986, 2320.3649999999907, 2063.390000000014, 2025.6500000000233, 2042.5999999999767, 2022.109999999986, 2033.140000000014, 2051.4649999999674, 2298.855000000098, 2383.859999999986], "unit": "ms", "name": "complicated.inspector.reload.DAMP", "value": 2035.0725000000093}, {"lowerIsBetter": true, "replicates": [805.0950000000012, 1080.4549999999872, 1071.7799999999988, 1059.945000000007, 671.679999999993, 693.5149999999849, 1101.640000000014, 1065.9500000000116, 1045.640000000014, 683.7200000000303, 712.8699999999953, 1088.4499999999534, 1100.6949999999488, 1058.3349999999627, 693.1900000000605, 1159.3099999999395, 697.375, 692.9600000000792, 1102.0500000000466, 1064.2449999999953, 1049.7799999999115, 1148.5899999999674, 1093.640000000014, 681.4399999999441, 700.2800000000279], "unit": "ms", "name": "complicated.inspector.close.DAMP", "value": 1059.1399999999849}, {"lowerIsBetter": true, "replicates": [626.755000000001, 677.5249999999942, 679.2649999999994, 672.7999999999884, 703.8450000000012, 718.4649999999965, 672.390000000014, 681.8250000000116, 664.539999999979, 660.4799999999814, 720.1750000000466, 693.1749999999884, 667.2149999999674, 685.7849999999162, 612.2199999999721, 677.5100000000093, 659.6199999999953, 722.8850000000093, 664.8899999998976, 688.1149999999907, 663.7850000000326, 691.6650000000373, 681.890000000014, 643.1949999999488, 712.8399999999674], "unit": "ms", "name": "complicated.jsdebugger.open.DAMP", "value": 678.3949999999968}, {"lowerIsBetter": true, "replicates": [2041.5, 2040.320000000007, 2021.7399999999907, 2025.4899999999907, 2116.0100000000093, 2179.4899999999907, 2015.5650000000023, 2012.960000000021, 2026.9749999999767, 2055.4899999999907, 2233.8950000000186, 2057.609999999986, 2051.439999999944, 2032.3800000000047, 2047.2150000000838, 2061.4899999999907, 2071.780000000028, 2191.7050000000745, 2048.2850000000326, 2059.1300000000047, 2044.359999999986, 2056.304999999935, 2030.890000000014, 2041.9100000000326, 2277.8349999999627], "unit": "ms", "name": "complicated.jsdebugger.reload.DAMP", "value": 2049.8624999999884}, {"lowerIsBetter": true, "replicates": [528.9850000000006, 573.8399999999965, 545.4250000000029, 554.695000000007, 547.9550000000163, 591.0400000000081, 545.8500000000349, 552.2249999999767, 568.7550000000047, 549.1599999999744, 585.0650000000023, 560.6300000000047, 548.1900000000605, 551.4650000000838, 555.6850000000559, 551.2299999999814, 570.8400000000838, 593.25, 559.0300000000279, 553.1550000000279, 569.625, 556.9249999999302, 566.2049999999581, 568.1600000000326, 610.375], "unit": "ms", "name": "complicated.jsdebugger.close.DAMP", "value": 557.977499999979}, {"lowerIsBetter": true, "replicates": [1757.7000000000044, 1782.5400000000081, 1674.1849999999977, 1774.2999999999884, 2277.0750000000116, 2492.4250000000175, 1710.445000000007, 1691.469999999972, 1703.1599999999744, 1859.3800000000047, 2436.5800000000163, 1678.0350000000326, 1677.6599999999162, 1725.6149999999907, 1879.1949999999488, 1778.625, 1877.6549999999115, 2512.344999999972, 1690.844999999972, 1744.0200000000186, 1752.0999999999767, 1725.0550000000512, 1719.359999999986, 1892.3300000000745, 2377.530000000028], "unit": "ms", "name": "complicated.styleeditor.open.DAMP", "value": 1763.1999999999825}, {"lowerIsBetter": true, "replicates": [2040.6299999999974, 2277.279999999999, 2079.3300000000017, 2202.709999999992, 2078.6900000000023, 1968.1700000000128, 2089.945000000007, 2201.5250000000233, 2229.0499999999884, 2372.134999999951, 1986.0999999999767, 2127.4899999999907, 2066.3649999999907, 2209.7700000000186, 2266.6199999999953, 2333.79999999993, 2175.79999999993, 1947.2299999999814, 2125.7050000000745, 2245.3249999999534, 2227.5500000000466, 2086.4199999999255, 2256.6900000000605, 2169.3499999999767, 2048.4749999999767], "unit": "ms", "name": "complicated.styleeditor.reload.DAMP", "value": 2172.5749999999534}, {"lowerIsBetter": true, "replicates": [961.6699999999983, 596.6999999999971, 629.0749999999971, 651.1300000000047, 586.2249999999767, 577.2900000000081, 611.375, 626.4549999999581, 629.6750000000466, 554.1149999999907, 584.4550000000163, 625.859999999986, 616.9749999999767, 642.1900000000605, 893.0700000000652, 649.1750000000466, 616.0050000000047, 575.375, 619.1650000000373, 709.4199999999255, 621.2900000000373, 614.8649999999907, 627.9449999999488, 587.3250000000698, 575.8450000000885], "unit": "ms", "name": "complicated.styleeditor.close.DAMP", "value": 618.070000000007}, {"lowerIsBetter": true, "replicates": [579.8449999999939, 716.5950000000012, 615.3600000000006, 503.5, 529.9100000000035, 618.5899999999965, 576.4900000000489, 499.35999999998603, 494.46000000002095, 596.515000000014, 609.7400000000489, 557.2550000000047, 593.5250000000233, 516.7099999999627, 579.0999999999767, 485.06500000006054, 941.5300000000279, 625.9599999999627, 566.0999999999767, 491.1050000000978, 917.9150000000373, 568.5100000000093, 492.2950000000419, 711.2199999999721, 708.3449999999721], "unit": "ms", "name": "complicated.performance.open.DAMP", "value": 577.7950000000128}, {"lowerIsBetter": true, "replicates": [1892.4100000000035, 2221.3899999999994, 1917.3050000000076, 2340.2149999999965, 1930.4200000000128, 2037.8500000000058, 2086.1450000000186, 2348.1500000000233, 2363.6450000000186, 1876.149999999965, 1951.7449999999953, 2127.1300000000047, 1994.4899999999907, 2236.295000000042, 1853.75, 2237.515000000014, 2045.234999999986, 1977.5100000000093, 1973.7249999999767, 2379.8099999999395, 1981.0649999999441, 1972.155000000028, 2255.3099999999395, 2249.734999999986, 1988.2399999999907], "unit": "ms", "name": "complicated.performance.reload.DAMP", "value": 2041.542499999996}, {"lowerIsBetter": true, "replicates": [544.8199999999997, 567.9850000000006, 880.3800000000047, 575.3099999999977, 551.4400000000023, 633.4650000000256, 900.7749999999651, 587.7249999999767, 554.2699999999604, 561.6000000000349, 619.8299999999581, 843.6300000000047, 998.4700000000885, 550.1600000000326, 565.4349999999395, 576.109999999986, 572.9050000000279, 595.3099999999395, 983.7900000000373, 586.5500000000466, 562.5749999999534, 998.3200000000652, 562.1550000000279, 563.625, 524.9300000000512], "unit": "ms", "name": "complicated.performance.close.DAMP", "value": 575.7099999999919}, {"lowerIsBetter": true, "replicates": [482.91999999999825, 532.5849999999919, 540.820000000007, 544.734999999986, 537.445000000007, 659.5800000000163, 551.3150000000023, 542.7699999999604, 525.375, 516.6499999999651, 628.6450000000186, 533.6549999999697, 578.6350000000093, 517.2399999999907, 491.03000000002794, 530.1350000000093, 538.1500000000233, 514.0549999999348, 554.4300000000512, 547.5499999999302, 535.2150000000838, 555.9550000000745, 533.7550000000047, 553.8850000000093, 558.6650000000373], "unit": "ms", "name": "complicated.netmonitor.open.DAMP", "value": 539.4850000000151}, {"lowerIsBetter": true, "replicates": [2281.550000000003, 2445.5550000000076, 2242.949999999997, 2194.095000000001, 2652.4400000000023, 2628.670000000013, 2220.8850000000093, 2253.5349999999744, 2216.3800000000047, 2214.7050000000163, 2662.1749999999884, 2269.9850000000442, 2272.8699999999953, 2236.6849999999395, 2193.444999999949, 2253.1500000000233, 2489.694999999949, 2784.170000000042, 2253.3600000001024, 2231.855000000098, 2430.6600000000326, 2263.859999999986, 2220.2449999999953, 2469.5500000000466, 2291.569999999949], "unit": "ms", "name": "complicated.netmonitor.reload.DAMP", "value": 2258.69749999998}, {"lowerIsBetter": true, "replicates": [818.9449999999997, 866.179999999993, 767.9750000000058, 726.960000000021, 1191.5100000000093, 767.2750000000233, 759.5299999999697, 744.429999999993, 721.3400000000256, 761.1749999999884, 752.7700000000186, 770.7649999999558, 780.984999999986, 769.1149999999907, 761.3100000000559, 768.4449999999488, 816.2399999999907, 739.7850000000326, 784.8249999999534, 763.9050000000279, 812.484999999986, 765.609999999986, 747.3149999999441, 827.7399999999907, 820.75], "unit": "ms", "name": "complicated.netmonitor.close.DAMP", "value": 767.6250000000146}, {"lowerIsBetter": true, "replicates": [551.3499999999985, 502.179999999993, 517.3249999999971, 511.2300000000105, 595.375, 636.2099999999919, 522.210000000021, 540.1149999999907, 558.0050000000047, 494.9899999999907, 618.2550000000047, 530.8800000000047, 535.5500000000466, 538.0250000000233, 559.2999999999302, 535.2399999999907, 637.4450000000652, 605.4949999999953, 530.6750000000466, 523.5200000000186, 726.6199999999953, 543.9250000000466, 516.8800000000047, 618.390000000014, 583.9699999999721], "unit": "ms", "name": "complicated.memory.open.DAMP", "value": 539.070000000007}, {"lowerIsBetter": true, "replicates": [2407.274999999994, 2616.3300000000017, 1950.8600000000151, 1903.570000000007, 1876.0, 2012.1199999999953, 1903.9500000000116, 1915.2399999999907, 2051.8950000000186, 2037.484999999986, 2029.75, 1921.7000000000116, 1924.905000000028, 2106.875, 2062.109999999986, 2062.75, 1869.8149999999441, 2053.6750000000466, 1950.3149999999441, 1950.5449999999255, 2310.5400000000373, 1919.875, 2058.015000000014, 1854.780000000028, 1928.7100000000792], "unit": "ms", "name": "complicated.memory.reload.DAMP", "value": 1950.7024999999703}, {"lowerIsBetter": true, "replicates": [1500.8150000000023, 1343.7850000000035, 1308.4100000000035, 1246.554999999993, 1384.5299999999988, 1476.8549999999814, 1278.3549999999814, 1408.875, 1413.719999999972, 1453.0900000000256, 1459.0599999999977, 1214.625, 1281.6199999999953, 1430.75, 1451.8250000000698, 1427.5849999999627, 1347.2850000000326, 1389.7449999999953, 1288.6300000000047, 1442.4749999999767, 1361.3399999999674, 1261.484999999986, 1446.9300000000512, 1343.6749999999302, 1763.6149999999907], "unit": "ms", "name": "complicated.saveHeapSnapshot", "value": 1387.137499999997}, {"lowerIsBetter": true, "replicates": [227.65999999999622, 260.47499999999127, 207.04000000000815, 206.66500000000815, 306.40499999999884, 321.1049999999814, 213.38000000000466, 216.0800000000163, 276.26499999995576, 255.96000000002095, 241.88000000000466, 201.625, 210.33500000007916, 288.2750000000233, 290.24499999999534, 267.2049999999581, 310.11999999999534, 267.76500000001397, 207.60999999998603, 203.86999999999534, 281.17499999993015, 206.18499999993946, 215.76500000001397, 273.4799999999814, 271.5099999997765], "unit": "ms", "name": "complicated.readHeapSnapshot", "value": 258.2175000000061}, {"lowerIsBetter": true, "replicates": [97.61500000000524, 165.6999999999971, 126.07000000000698, 126.15499999999884, 137.7850000000035, 138.3650000000198, 125.82500000001164, 124.98999999999069, 139.01500000001397, 129.82500000001164, 129.28999999997905, 125.78999999997905, 126.93000000005122, 127.81499999994412, 129.46000000007916, 135.24499999999534, 125.90000000002328, 142.92499999993015, 126.36999999999534, 127.40500000002794, 120.93500000005588, 127.76000000000931, 130.1700000000419, 124.81499999994412, 131.1350000000093], "unit": "ms", "name": "complicated.takeCensus", "value": 127.78749999997672}, {"lowerIsBetter": true, "replicates": [251.83499999999913, 247.22500000000582, 312.0350000000035, 303.8650000000198, 229.7149999999965, 640.3699999999953, 293.1149999999907, 393.57500000001164, 281.7250000000349, 307.0550000000512, 637.7299999999814, 248.45999999996275, 256.82499999995343, 273.92499999993015, 816.9000000000233, 307.0549999999348, 244.4649999999674, 262.35999999998603, 251.2400000001071, 383.2949999999255, 244.89500000001863, 247.18500000005588, 299.21000000007916, 243.85499999998137, 271.04000000003725], "unit": "ms", "name": "complicated.memory.close.DAMP", "value": 277.82499999998254}, {"lowerIsBetter": true, "replicates": [118.71499999999651, 96.90499999999884, 102.3299999999872, 99.55999999999767, 90.89499999998952, 98.32500000001164, 102.0899999999674, 95.15999999997439, 99.11499999999069, 91.19999999995343, 102.88000000000466, 90.73000000003958, 117.93999999994412, 101.47999999998137, 94.67999999993481, 97.82499999995343, 94.54500000004191, 92.94999999995343, 101.14000000001397, 88.31999999994878, 94.35999999998603, 108.29000000003725, 95.82000000006519, 89.48999999999069, 96.42500000004657], "unit": "ms", "name": "console.bulklog", "value": 96.6650000000227}, {"lowerIsBetter": true, "replicates": [23.0, 26.0, 31.0, 30.0, 25.0, 24.0, 26.0, 29.0, 29.0, 29.0, 24.0, 26.0, 26.0, 25.0, 24.0, 30.0, 25.0, 26.0, 25.0, 29.0, 26.0, 25.0, 30.0, 24.0, 23.0], "unit": "ms", "name": "console.streamlog", "value": 26.0}], "name": "damp", "value": 230.56722663754434}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [62.474999999998545, 61.76000000000204, 63.455000000001746, 62.00999999999476, 58.96500000000378], "unit": "ms", "name": "amazon.com", "value": 61.8849999999984}, {"lowerIsBetter": true, "replicates": [57.42500000000291, 63.205000000001746, 59.66500000000087, 56.76499999999942, 64.97499999999854], "unit": "ms", "name": "cgi.ebay.com", "value": 61.43500000000131}, {"lowerIsBetter": true, "replicates": [66.15000000000146, 62.75, 57.74499999999534, 63.44499999999971, 60.54000000000087], "unit": "ms", "name": "163.com", "value": 61.64500000000044}, {"lowerIsBetter": true, "replicates": [45.61000000000058, 59.23500000000058, 55.79000000000087, 50.400000000001455, 55.69999999999709], "unit": "ms", "name": "mail.ru", "value": 55.74499999999898}, {"lowerIsBetter": true, "replicates": [37.095000000001164, 37.31499999999505, 37.474999999998545, 46.98999999999796, 54.80500000000029], "unit": "ms", "name": "bbc.co.uk", "value": 42.232499999998254}, {"lowerIsBetter": true, "replicates": [72.47499999999854, 69.02500000000146, 73.17500000000291, 75.25999999999476, 80.74500000000262], "unit": "ms", "name": "store.apple.com", "value": 74.21749999999884}, {"lowerIsBetter": true, "replicates": [48.06500000000233, 47.349999999998545, 47.07499999999709, 49.51000000000204, 46.30000000000291], "unit": "ms", "name": "imdb.com", "value": 47.21249999999782}, {"lowerIsBetter": true, "replicates": [45.63999999999942, 44.68000000000029, 56.794999999998254, 43.77499999999418, 49.41500000000087], "unit": "ms", "name": "cnn.com", "value": 47.04750000000058}, {"lowerIsBetter": true, "replicates": [52.78500000000349, 58.01000000000204, 54.19499999999971, 51.69999999999709, 54.82999999999447], "unit": "ms", "name": "sohu.com", "value": 54.51249999999709}, {"lowerIsBetter": true, "replicates": [52.375, 47.37000000000262, 44.2699999999968, 47.970000000001164, 46.13500000000204], "unit": "ms", "name": "youku.com", "value": 46.75250000000233}, {"lowerIsBetter": true, "replicates": [77.31499999999505, 77.7300000000032, 73.61500000000524, 74.97999999999593, 76.50999999999476], "unit": "ms", "name": "ifeng.com", "value": 75.74499999999534}, {"lowerIsBetter": true, "replicates": [56.61499999999796, 48.69499999999971, 42.98500000000058, 46.345000000001164, 45.57499999999709], "unit": "ms", "name": "tudou.com", "value": 45.95999999999913}, {"lowerIsBetter": true, "replicates": [37.51499999999942, 40.94000000000233, 37.02499999999418, 42.23500000000058, 45.1050000000032], "unit": "ms", "name": "chemistry.about.com", "value": 41.587500000001455}, {"lowerIsBetter": true, "replicates": [35.705000000001746, 38.625, 39.73999999999069, 38.080000000001746, 42.18500000000495], "unit": "ms", "name": "beatonna.livejournal.com", "value": 39.18249999999534}, {"lowerIsBetter": true, "replicates": [60.86999999999534, 59.674999999995634, 59.620000000009895, 58.44999999999709, 61.900000000001455], "unit": "ms", "name": "rakuten.co.jp", "value": 59.647500000002765}, {"lowerIsBetter": true, "replicates": [43.570000000006985, 42.349999999998545, 49.63500000000931, 43.054999999993015, 45.87000000000262], "unit": "ms", "name": "uol.com.br", "value": 44.46249999999782}, {"lowerIsBetter": true, "replicates": [39.88999999999942, 39.69000000000233, 40.66500000000815, 42.13000000000466, 41.929999999993015], "unit": "ms", "name": "thepiratebay.org", "value": 41.29750000000058}, {"lowerIsBetter": true, "replicates": [47.544999999998254, 40.66500000000087, 48.529999999998836, 42.80999999999767, 41.61000000000058], "unit": "ms", "name": "page.renren.com", "value": 42.20999999999913}, {"lowerIsBetter": true, "replicates": [53.64999999999418, 51.42500000000291, 58.479999999995925, 56.794999999998254, 57.96499999999651], "unit": "ms", "name": "chinaz.com", "value": 57.37999999999738}, {"lowerIsBetter": true, "replicates": [53.13000000000466, 49.72500000000582, 47.72499999999127, 47.0, 49.66500000000815], "unit": "ms", "name": "globo.com", "value": 48.69499999999971}, {"lowerIsBetter": true, "replicates": [33.520000000004075, 37.320000000006985, 40.13499999999476, 39.830000000001746, 37.47500000000582], "unit": "ms", "name": "spiegel.de", "value": 38.65250000000378}, {"lowerIsBetter": true, "replicates": [40.670000000012806, 37.36999999999534, 40.49000000000524, 37.90999999998894, 39.5], "unit": "ms", "name": "dailymotion.com", "value": 38.70499999999447}, {"lowerIsBetter": true, "replicates": [50.65000000000873, 59.55500000000757, 52.78500000000349, 55.19000000000233, 53.99499999999534], "unit": "ms", "name": "goo.ne.jp", "value": 54.592499999998836}, {"lowerIsBetter": true, "replicates": [44.279999999998836, 45.78499999998894, 43.120000000009895, 44.88999999999942, 51.49499999999534], "unit": "ms", "name": "stackoverflow.com", "value": 45.33749999999418}, {"lowerIsBetter": true, "replicates": [36.679999999993015, 40.73499999998603, 45.28500000000349, 41.13499999999476, 47.22500000000582], "unit": "ms", "name": "ezinearticles.com", "value": 43.20999999999913}, {"lowerIsBetter": true, "replicates": [44.63499999999476, 47.120000000009895, 45.0, 43.11000000000058, 40.5399999999936], "unit": "ms", "name": "huffingtonpost.com", "value": 44.05500000000029}, {"lowerIsBetter": true, "replicates": [43.26499999999942, 40.19499999999243, 42.20999999999185, 41.88999999999942, 44.11999999999534], "unit": "ms", "name": "media.photobucket.com", "value": 42.049999999995634}, {"lowerIsBetter": true, "replicates": [35.945000000006985, 39.84000000001106, 42.77499999999418, 43.01499999999942, 37.375], "unit": "ms", "name": "imgur.com", "value": 41.30750000000262}, {"lowerIsBetter": true, "replicates": [42.379999999990105, 52.529999999998836, 40.76999999998952, 44.720000000001164, 39.195000000006985], "unit": "ms", "name": "reddit.com", "value": 42.74499999999534}, {"lowerIsBetter": true, "replicates": [50.98500000000058, 49.90000000000873, 46.01499999999942, 51.904999999998836, 52.654999999998836], "unit": "ms", "name": "noimpactman.typepad.com", "value": 50.90250000000378}, {"lowerIsBetter": true, "replicates": [49.83499999999185, 47.73999999999069, 52.63999999999942, 52.84999999999127, 48.179999999993015], "unit": "ms", "name": "myspace.com", "value": 50.40999999999622}, {"lowerIsBetter": true, "replicates": [40.75, 40.104999999995925, 44.5399999999936, 39.029999999998836, 45.89999999999418], "unit": "ms", "name": "mashable.com", "value": 42.32249999999476}, {"lowerIsBetter": true, "replicates": [54.32499999999709, 46.330000000001746, 45.070000000006985, 44.69000000000233, 45.47500000000582], "unit": "ms", "name": "dailymail.co.uk", "value": 45.2725000000064}, {"lowerIsBetter": true, "replicates": [40.22500000000582, 37.379999999990105, 33.904999999998836, 32.154999999998836, 32.145000000004075], "unit": "ms", "name": "whois.domaintools.com", "value": 33.029999999998836}, {"lowerIsBetter": true, "replicates": [50.205000000001746, 46.904999999998836, 45.98500000000058, 40.54000000000815, 47.28500000000349], "unit": "ms", "name": "indiatimes.com", "value": 46.44499999999971}, {"lowerIsBetter": true, "replicates": [38.48500000000058, 39.595000000001164, 37.06500000000233, 42.30000000000291, 36.93499999999767], "unit": "ms", "name": "reuters.com", "value": 38.330000000001746}, {"lowerIsBetter": true, "replicates": [67.82000000000698, 70.2549999999901, 69.94499999999243, 69.0399999999936, 69.79499999999825], "unit": "ms", "name": "xinhuanet.com", "value": 69.86999999999534}, {"lowerIsBetter": true, "replicates": [53.0, 53.28500000000349, 54.61000000000058, 52.94999999999709, 60.845000000001164], "unit": "ms", "name": "56.com", "value": 53.94750000000204}, {"lowerIsBetter": true, "replicates": [34.69999999999709, 53.654999999998836, 44.24499999999534, 37.929999999993015, 64.16000000000349], "unit": "ms", "name": "bild.de", "value": 48.94999999999709}, {"lowerIsBetter": true, "replicates": [41.7899999999936, 51.830000000001746, 46.375, 59.48500000000058, 48.48999999999069], "unit": "ms", "name": "guardian.co.uk", "value": 50.15999999999622}, {"lowerIsBetter": true, "replicates": [64.09500000000116, 66.27500000000873, 66.42999999999302, 64.56499999998778, 65.35500000001048], "unit": "ms", "name": "naver.com", "value": 65.8150000000096}, {"lowerIsBetter": true, "replicates": [51.55500000000757, 53.31500000000233, 54.13999999999942, 52.245000000009895, 50.05000000000291], "unit": "ms", "name": "yelp.com", "value": 52.78000000000611}, {"lowerIsBetter": true, "replicates": [50.68499999999767, 45.69000000000233, 46.36000000000058, 52.25, 47.810000000012224], "unit": "ms", "name": "wsj.com", "value": 47.0850000000064}, {"lowerIsBetter": true, "replicates": [38.604999999995925, 39.13000000000466, 46.75, 41.43499999999767, 39.104999999995925], "unit": "ms", "name": "google.com", "value": 40.282500000001164}, {"lowerIsBetter": true, "replicates": [70.05000000000291, 51.85500000001048, 57.41500000000815, 51.94999999999709, 58.5], "unit": "ms", "name": "xunlei.com", "value": 54.68250000000262}, {"lowerIsBetter": true, "replicates": [46.01000000000931, 38.78500000000349, 48.93000000000757, 38.47500000000582, 59.89999999999418], "unit": "ms", "name": "aljazeera.net", "value": 43.85750000000553}, {"lowerIsBetter": true, "replicates": [47.36000000000058, 45.625, 45.845000000001164, 43.11999999999534, 46.419999999998254], "unit": "ms", "name": "w3.org", "value": 45.73500000000058}, {"lowerIsBetter": true, "replicates": [58.995000000009895, 61.229999999995925, 56.86999999999534, 58.375, 59.21500000001106], "unit": "ms", "name": "homeway.com.cn", "value": 58.79500000000553}, {"lowerIsBetter": true, "replicates": [43.13999999999942, 39.77500000000873, 41.08999999999651, 39.970000000001164, 39.370000000009895], "unit": "ms", "name": "youtube.com", "value": 39.87250000000495}, {"lowerIsBetter": true, "replicates": [62.945000000006985, 63.125, 65.5, 65.66000000000349, 81.57499999999709], "unit": "ms", "name": "people.com.cn", "value": 65.58000000000175}], "name": "tps", "value": 48.8187778063848}]} 08:26:56 INFO - TinderboxPrint: TalosResult: {"graphserver": {"damp": {"url": "http://graphs.mozilla.org/graph.html#tests=[[327,64,35]]", "result": "230.57"}, "tps": {"url": "http://graphs.mozilla.org/graph.html#tests=[[329,64,35]]", "result": "48.82"}}} 08:26:56 INFO - Return code: 0 08:26:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 08:26:56 INFO - # TBPL SUCCESS # 08:26:56 INFO - Running post-action listener: _resource_record_post_action 08:26:56 INFO - Running post-run listener: _resource_record_post_run 08:26:57 INFO - Total resource usage - Wall time: 1523s; CPU: 15.0%; Read bytes: 20799488; Write bytes: 851525632; Read time: 4656; Write time: 1225308 08:26:57 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 24801280; Read time: 0; Write time: 52060 08:26:57 INFO - run-tests - Wall time: 1507s; CPU: 15.0%; Read bytes: 20799488; Write bytes: 805765120; Read time: 4656; Write time: 1163896 08:26:57 INFO - Running post-run listener: _upload_blobber_files 08:26:57 INFO - Blob upload gear active. 08:26:57 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 08:26:57 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 08:26:57 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', 'Fx-Team', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 08:26:57 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 Fx-Team -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 08:26:57 INFO - (blobuploader) - INFO - Open directory for files ... 08:26:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/g2_raw.log ... 08:26:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:26:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:26:59 INFO - (blobuploader) - INFO - TinderboxPrint: g2_raw.log: uploaded 08:26:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:26:59 INFO - (blobuploader) - INFO - Done attempting. 08:26:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/g2_errorsummary.log ... 08:26:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:26:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:27:00 INFO - (blobuploader) - INFO - TinderboxPrint: g2_errorsummary.log: uploaded 08:27:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:27:00 INFO - (blobuploader) - INFO - Done attempting. 08:27:00 INFO - (blobuploader) - INFO - Iteration through files over. 08:27:00 INFO - Return code: 0 08:27:00 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 08:27:00 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 08:27:00 INFO - Setting buildbot property blobber_files to {"g2_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/efbe0761f46bd20474acdefad0efee0432a0474974ec070544d0a256414d7dc07a5a2e09de1e98d20988519feba23a20f4ab820e216a11cd7c3a6777e199d58a", "g2_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c58f7fae1ca547f0a5fd2d88dd8aa5fc1cd05dec07b072fb843b9889ab542a299b1a53ba29da63beae951b5c5d313d454b4b60375c5d32d8e6c903e92c6ce6c"} 08:27:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 08:27:00 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 08:27:00 INFO - Contents: 08:27:00 INFO - blobber_files:{"g2_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/efbe0761f46bd20474acdefad0efee0432a0474974ec070544d0a256414d7dc07a5a2e09de1e98d20988519feba23a20f4ab820e216a11cd7c3a6777e199d58a", "g2_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c58f7fae1ca547f0a5fd2d88dd8aa5fc1cd05dec07b072fb843b9889ab542a299b1a53ba29da63beae951b5c5d313d454b4b60375c5d32d8e6c903e92c6ce6c"} 08:27:00 INFO - Running post-run listener: copy_logs_to_upload_dir 08:27:00 INFO - Copying logs to upload dir... 08:27:00 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 08:27:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1575.399463 ========= master_lag: 0.44 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 26 mins, 15 secs) (at 2016-01-27 08:27:00.998894) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-27 08:27:01.002851) ========= 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=745a4373a2a16df560f216e000000264-1453910169.700118-1119135004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"g2_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/efbe0761f46bd20474acdefad0efee0432a0474974ec070544d0a256414d7dc07a5a2e09de1e98d20988519feba23a20f4ab820e216a11cd7c3a6777e199d58a", "g2_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c58f7fae1ca547f0a5fd2d88dd8aa5fc1cd05dec07b072fb843b9889ab542a299b1a53ba29da63beae951b5c5d313d454b4b60375c5d32d8e6c903e92c6ce6c"} build_url:https://queue.taskcluster.net/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/LsJWkVY6RpmlsoyQCPyYrw/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"g2_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/efbe0761f46bd20474acdefad0efee0432a0474974ec070544d0a256414d7dc07a5a2e09de1e98d20988519feba23a20f4ab820e216a11cd7c3a6777e199d58a", "g2_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c58f7fae1ca547f0a5fd2d88dd8aa5fc1cd05dec07b072fb843b9889ab542a299b1a53ba29da63beae951b5c5d313d454b4b60375c5d32d8e6c903e92c6ce6c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-27 08:27:01.030370) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 08:27:01.030753) ========= 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=745a4373a2a16df560f216e000000264-1453910169.700118-1119135004 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004594 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 08:27:01.112241) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-27 08:27:01.112563) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-27 08:27:01.112886) ========= ========= Total master_lag: 0.69 =========