builder: fx-team_ubuntu64_hw_test-g2-pgo slave: talos-linux64-ix-016 starttime: 1453953959.2 results: success (0) buildid: 20160127173007 builduid: d42d99f9351848a58dec4aa27c213914 revision: 261e997621c182a03bc330c8bd18c98eddb9c7eb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.198138) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.198756) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.199078) ========= 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=00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006314 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.224883) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.225259) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.299792) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.300044) ========= 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=00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004573 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.348833) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.349156) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.349556) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.349834) ========= 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=00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-27 20:05:59-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-01-27 20:05:59 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.149916 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.513994) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.514358) ========= 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=00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018391 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.566446) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 20:05:59.566989) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 261e997621c182a03bc330c8bd18c98eddb9c7eb --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 261e997621c182a03bc330c8bd18c98eddb9c7eb --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=00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-27 20:05:59,723 truncating revision to first 12 chars 2016-01-27 20:05:59,723 Setting DEBUG logging. 2016-01-27 20:05:59,723 attempt 1/10 2016-01-27 20:05:59,723 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/261e997621c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-27 20:06:00,018 unpacking tar archive at: fx-team-261e997621c1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.579542 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 20:06:00.160544) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-27 20:06:00.161025) ========= echo 261e997621c182a03bc330c8bd18c98eddb9c7eb in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'261e997621c182a03bc330c8bd18c98eddb9c7eb'] 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=00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 261e997621c182a03bc330c8bd18c98eddb9c7eb program finished with exit code 0 elapsedTime=0.004823 script_repo_revision: '261e997621c182a03bc330c8bd18c98eddb9c7eb' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-01-27 20:06:00.212841) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-27 20:06:00.213141) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-27 20:06:00.229471) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 47 secs) (at 2016-01-27 20:06:00.229857) ========= /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=00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:06:00 INFO - MultiFileLogger online at 20160127 20:06:00 in /builds/slave/test-pgo 20:06:00 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 20:06:00 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 20:06:00 INFO - {'append_to_log': False, 20:06:00 INFO - 'base_work_dir': '/builds/slave/test-pgo', 20:06:00 INFO - 'blob_upload_branch': 'Fx-Team', 20:06:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 20:06:00 INFO - 'branch': 'Fx-Team', 20:06:00 INFO - 'buildbot_json_path': 'buildprops.json', 20:06:00 INFO - 'config_files': ('talos/linux_config.py',), 20:06:00 INFO - 'default_actions': ('clobber', 20:06:00 INFO - 'read-buildbot-config', 20:06:00 INFO - 'download-and-extract', 20:06:00 INFO - 'populate-webroot', 20:06:00 INFO - 'create-virtualenv', 20:06:00 INFO - 'install', 20:06:00 INFO - 'run-tests'), 20:06:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:06:00 INFO - 'download_minidump_stackwalk': True, 20:06:00 INFO - 'download_symbols': 'ondemand', 20:06:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:06:00 INFO - 'tooltool.py': '/tools/tooltool.py', 20:06:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:06:00 INFO - '/tools/misc-python/virtualenv.py')}, 20:06:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:06:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:06:00 INFO - 'installer_path': 'installer.exe', 20:06:00 INFO - 'log_level': 'info', 20:06:00 INFO - 'log_name': 'talos', 20:06:00 INFO - 'log_to_console': True, 20:06:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:06:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:06:00 INFO - 'opt_config_files': (), 20:06:00 INFO - 'pip_index': False, 20:06:00 INFO - 'sps_profile': False, 20:06:00 INFO - 'sps_profile_interval': 0, 20:06:00 INFO - 'suite': 'g2', 20:06:00 INFO - 'system_bits': '32', 20:06:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:06:00 INFO - 'title': 'talos-linux64-ix-016', 20:06:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:06:00 INFO - 'use_talos_json': True, 20:06:00 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 20:06:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:06:00 INFO - 'work_dir': 'build'} 20:06:00 INFO - ##### 20:06:00 INFO - ##### Running clobber step. 20:06:00 INFO - ##### 20:06:00 INFO - Running pre-action listener: _resource_record_pre_action 20:06:00 INFO - Running main action method: clobber 20:06:00 INFO - rmtree: /builds/slave/test-pgo/build 20:06:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 20:06:01 INFO - Running post-action listener: _resource_record_post_action 20:06:01 INFO - ##### 20:06:01 INFO - ##### Running read-buildbot-config step. 20:06:01 INFO - ##### 20:06:01 INFO - Running pre-action listener: _resource_record_pre_action 20:06:01 INFO - Running main action method: read_buildbot_config 20:06:01 INFO - Using buildbot properties: 20:06:01 INFO - { 20:06:01 INFO - "project": "", 20:06:01 INFO - "product": "firefox", 20:06:01 INFO - "script_repo_revision": "production", 20:06:01 INFO - "builddir": "fx-team_ubuntu64_hw_test-g2", 20:06:01 INFO - "repository": "", 20:06:01 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos g2", 20:06:01 INFO - "buildid": "20160127173007", 20:06:01 INFO - "pgo_build": "True", 20:06:01 INFO - "basedir": "/builds/slave/test-pgo", 20:06:01 INFO - "buildnumber": 372, 20:06:01 INFO - "slavename": "talos-linux64-ix-016", 20:06:01 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:06:01 INFO - "platform": "ubuntu64_hw", 20:06:01 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 20:06:01 INFO - "branch": "fx-team", 20:06:01 INFO - "repo_path": "integration/fx-team", 20:06:01 INFO - "revision": "261e997621c182a03bc330c8bd18c98eddb9c7eb", 20:06:01 INFO - "stage_platform": "linux64-pgo", 20:06:01 INFO - "builduid": "d42d99f9351848a58dec4aa27c213914", 20:06:01 INFO - "slavebuilddir": "test" 20:06:01 INFO - } 20:06:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2. 20:06:01 INFO - Running post-action listener: _resource_record_post_action 20:06:01 INFO - ##### 20:06:01 INFO - ##### Running download-and-extract step. 20:06:01 INFO - ##### 20:06:01 INFO - Running pre-action listener: _resource_record_pre_action 20:06:01 INFO - Running main action method: download_and_extract 20:06:01 INFO - mkdir: /builds/slave/test-pgo/build/tests 20:06:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:06:01 INFO - https://queue.taskcluster.net/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 20:06:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/test_packages.json 20:06:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/test_packages.json 20:06:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 20:06:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 20:06:02 INFO - Downloaded 1448 bytes. 20:06:02 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 20:06:02 INFO - Using the following test package requirements: 20:06:02 INFO - {u'common': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 20:06:02 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 20:06:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:06:02 INFO - u'gtest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 20:06:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:06:02 INFO - u'jittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 20:06:02 INFO - u'jsshell-linux-x86_64.zip'], 20:06:02 INFO - u'mochitest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 20:06:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:06:02 INFO - u'mozbase': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 20:06:02 INFO - u'reftest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 20:06:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:06:02 INFO - u'talos': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 20:06:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:06:02 INFO - u'web-platform': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 20:06:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:06:02 INFO - u'webapprt': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 20:06:02 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 20:06:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:06:02 INFO - Downloading packages: [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:06:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:06:02 INFO - https://queue.taskcluster.net/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:06:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 20:06:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 20:06:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/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 20:06:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/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 20:06:03 INFO - Downloaded 22503362 bytes. 20:06:03 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'] 20:06:03 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 20:06:04 INFO - Return code: 0 20:06:04 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 20:06:04 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')]} 20:06:04 INFO - https://queue.taskcluster.net/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:06:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 20:06:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 20:06:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/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 20:06:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/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 20:06:06 INFO - Downloaded 22503362 bytes. 20:06:06 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'] 20:06:06 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 20:06:08 INFO - Return code: 0 20:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:06:08 INFO - https://queue.taskcluster.net/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:06:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 20:06:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 20:06:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/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 20:06:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/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 20:06:09 INFO - Downloaded 10706572 bytes. 20:06:09 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'] 20:06:09 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 20:06:09 INFO - Return code: 0 20:06: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')]} 20:06:09 INFO - https://queue.taskcluster.net/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:06:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 20:06:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 20:06:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/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 20:06:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WvydVMQlSHypr21YMD-mKg/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 20:06:11 INFO - Downloaded 55985142 bytes. 20:06:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 20:06:11 INFO - mkdir: /builds/slave/test-pgo/properties 20:06:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 20:06:11 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 20:06:11 INFO - Contents: 20:06:11 INFO - build_url:https://queue.taskcluster.net/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 20:06:11 INFO - Running post-action listener: _resource_record_post_action 20:06:11 INFO - Running post-action listener: set_extra_try_arguments 20:06:11 INFO - ##### 20:06:11 INFO - ##### Running populate-webroot step. 20:06:11 INFO - ##### 20:06:11 INFO - Running pre-action listener: _resource_record_pre_action 20:06:11 INFO - Running main action method: populate_webroot 20:06:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:06:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:06:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:06:11 INFO - '--tppagecycles', 20:06:11 INFO - '7'], 20:06:11 INFO - 'tests': ['tsvgm']}}, 20:06:11 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 20:06:11 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 20:06:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:06:11 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 20:06:11 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 20:06:11 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 20:06:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:06:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:11 INFO - 'talos_options': ['--e10s'], 20:06:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:06:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:11 INFO - 'tests': ['damp', 'tps']}, 20:06:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:11 INFO - 'talos_options': ['--e10s'], 20:06:11 INFO - 'tests': ['damp', 'tps']}, 20:06:11 INFO - 'g3': {'tests': ['dromaeo_dom']}, 20:06:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:06:11 INFO - 'other': {'tests': ['a11yr', 20:06:11 INFO - 'ts_paint', 20:06:11 INFO - 'tpaint', 20:06:11 INFO - 'sessionrestore', 20:06:11 INFO - 'sessionrestore_no_auto_restore']}, 20:06:11 INFO - 'other-e10s': {'talos_options': ['--e10s'], 20:06:11 INFO - 'tests': ['a11yr', 20:06:11 INFO - 'ts_paint', 20:06:11 INFO - 'tpaint', 20:06:11 INFO - 'sessionrestore', 20:06:11 INFO - 'sessionrestore_no_auto_restore']}, 20:06:11 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 20:06:11 INFO - 'tests': ['a11yr', 20:06:11 INFO - 'ts_paint', 20:06:11 INFO - 'tpaint', 20:06:11 INFO - 'sessionrestore', 20:06:11 INFO - 'sessionrestore_no_auto_restore']}, 20:06:11 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 20:06:11 INFO - 'tests': ['a11yr', 20:06:11 INFO - 'ts_paint', 20:06:11 INFO - 'tpaint', 20:06:11 INFO - 'sessionrestore', 20:06:11 INFO - 'sessionrestore_no_auto_restore']}, 20:06:11 INFO - 'other_l64': {'tests': ['a11yr', 20:06:11 INFO - 'ts_paint', 20:06:11 INFO - 'tpaint', 20:06:11 INFO - 'sessionrestore', 20:06:11 INFO - 'sessionrestore_no_auto_restore']}, 20:06:11 INFO - 'other_nol64': {'tests': ['a11yr', 20:06:11 INFO - 'ts_paint', 20:06:11 INFO - 'tpaint', 20:06:11 INFO - 'sessionrestore', 20:06:11 INFO - 'sessionrestore_no_auto_restore']}, 20:06:11 INFO - 'svgr': {'tests': ['tsvgx', 20:06:11 INFO - 'tsvgr_opacity', 20:06:11 INFO - 'tart', 20:06:11 INFO - 'tscrollx', 20:06:11 INFO - 'cart']}, 20:06:11 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 20:06:11 INFO - 'tests': ['tsvgx', 20:06:11 INFO - 'tsvgr_opacity', 20:06:11 INFO - 'tart', 20:06:11 INFO - 'tscrollx', 20:06:11 INFO - 'cart']}, 20:06:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:11 INFO - 'tests': ['tp5o']}, 20:06:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:11 INFO - 'talos_options': ['--e10s'], 20:06:11 INFO - 'tests': ['tp5o']}, 20:06:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:11 INFO - 'talos_options': ['--xperf_path', 20:06:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:06:11 INFO - 'tests': ['tp5n']}, 20:06:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:06:11 INFO - 'talos_options': ['--e10s', 20:06:11 INFO - '--xperf_path', 20:06:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:06:11 INFO - 'tests': ['tp5n']}}, 20:06:11 INFO - 'talos.zip': {'path': '', 20:06:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:06:11 INFO - Downloading pageset... 20:06:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:06:11 INFO - trying http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip 20:06:11 INFO - Downloading http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip to /builds/slave/test-pgo/build/tp5n.zip 20:06:11 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 20:06:12 INFO - Downloaded 81753769 bytes. 20:06:12 INFO - Running command: ['unzip', '-q', '-o', '/builds/slave/test-pgo/build/tp5n.zip', '-d', '/builds/slave/test-pgo/build/tests/talos/talos/tests'] 20:06:12 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/tp5n.zip -d /builds/slave/test-pgo/build/tests/talos/talos/tests 20:06:14 INFO - Return code: 0 20:06:14 INFO - Running post-action listener: _resource_record_post_action 20:06:14 INFO - ##### 20:06:14 INFO - ##### Running create-virtualenv step. 20:06:14 INFO - ##### 20:06:14 INFO - Running pre-action listener: _resource_record_pre_action 20:06:14 INFO - Running main action method: create_virtualenv 20:06:14 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 20:06:14 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 20:06:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 20:06:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:06:14 INFO - Using real prefix '/usr' 20:06:14 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 20:06:16 INFO - Installing distribute.............................................................................................................................................................................................done. 20:06:19 INFO - Installing pip.................done. 20:06:19 INFO - Return code: 0 20:06:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 20:06: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')]} 20:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06: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')]} 20:06:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:19 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 0x7fedbe176f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201e840>, '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 0x1f8b6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bb60>, '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': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', '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 20:06:19 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 20:06:19 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 20:06:19 INFO - Using env: {'DISPLAY': ':0', 20:06:19 INFO - 'HOME': '/home/cltbld', 20:06:19 INFO - 'LANG': 'en_US.UTF-8', 20:06:19 INFO - 'LANGUAGE': 'en_US:en', 20:06:19 INFO - 'LOGNAME': 'cltbld', 20:06:19 INFO - 'MAIL': '/var/mail/cltbld', 20:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:19 INFO - 'MOZ_NO_REMOTE': '1', 20:06:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:06:19 INFO - 'NO_EM_RESTART': '1', 20:06:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:06:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:06:19 INFO - 'PWD': '/builds/slave/test-pgo', 20:06:19 INFO - 'SHELL': '/bin/bash', 20:06:19 INFO - 'SHLVL': '1', 20:06:19 INFO - 'TERM': 'linux', 20:06:19 INFO - 'TMOUT': '86400', 20:06:19 INFO - 'USER': 'cltbld', 20:06:19 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', 20:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:19 INFO - '_': '/tools/buildbot/bin/python'} 20:06:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:19 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 20:06:19 INFO - Cleaning up... 20:06:19 INFO - Return code: 0 20:06:19 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 20:06: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')]} 20:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06: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')]} 20:06:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:19 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 0x7fedbe176f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201e840>, '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 0x1f8b6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bb60>, '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': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', '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 20:06:19 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 20:06:19 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 20:06:19 INFO - Using env: {'DISPLAY': ':0', 20:06:19 INFO - 'HOME': '/home/cltbld', 20:06:19 INFO - 'LANG': 'en_US.UTF-8', 20:06:19 INFO - 'LANGUAGE': 'en_US:en', 20:06:19 INFO - 'LOGNAME': 'cltbld', 20:06:19 INFO - 'MAIL': '/var/mail/cltbld', 20:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:19 INFO - 'MOZ_NO_REMOTE': '1', 20:06:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:06:19 INFO - 'NO_EM_RESTART': '1', 20:06:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:06:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:06:19 INFO - 'PWD': '/builds/slave/test-pgo', 20:06:19 INFO - 'SHELL': '/bin/bash', 20:06:19 INFO - 'SHLVL': '1', 20:06:19 INFO - 'TERM': 'linux', 20:06:19 INFO - 'TMOUT': '86400', 20:06:19 INFO - 'USER': 'cltbld', 20:06:19 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', 20:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:19 INFO - '_': '/tools/buildbot/bin/python'} 20:06:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:19 INFO - Downloading/unpacking psutil>=0.7.1 20:06:19 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 20:06:19 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 20:06:19 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 20:06:19 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 20:06:19 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 20:06:19 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 20:06:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 20:06:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:06:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:06:21 INFO - Installing collected packages: psutil 20:06:21 INFO - Running setup.py install for psutil 20:06:21 INFO - building 'psutil._psutil_linux' extension 20:06:21 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 20:06:21 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 20:06:21 INFO - building 'psutil._psutil_posix' extension 20:06:21 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 20:06:21 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 20:06:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:06:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:06:22 INFO - Successfully installed psutil 20:06:22 INFO - Cleaning up... 20:06:22 INFO - Return code: 0 20:06:22 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 20:06: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')]} 20:06:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06: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')]} 20:06:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:22 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 0x7fedbe176f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201e840>, '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 0x1f8b6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bb60>, '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': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', '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 20:06:22 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 20:06:22 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 20:06:22 INFO - Using env: {'DISPLAY': ':0', 20:06:22 INFO - 'HOME': '/home/cltbld', 20:06:22 INFO - 'LANG': 'en_US.UTF-8', 20:06:22 INFO - 'LANGUAGE': 'en_US:en', 20:06:22 INFO - 'LOGNAME': 'cltbld', 20:06:22 INFO - 'MAIL': '/var/mail/cltbld', 20:06:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:22 INFO - 'MOZ_NO_REMOTE': '1', 20:06:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:06:22 INFO - 'NO_EM_RESTART': '1', 20:06:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:06:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:06:22 INFO - 'PWD': '/builds/slave/test-pgo', 20:06:22 INFO - 'SHELL': '/bin/bash', 20:06:22 INFO - 'SHLVL': '1', 20:06:22 INFO - 'TERM': 'linux', 20:06:22 INFO - 'TMOUT': '86400', 20:06:22 INFO - 'USER': 'cltbld', 20:06:22 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', 20:06:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:22 INFO - '_': '/tools/buildbot/bin/python'} 20:06:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:22 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:06:22 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 20:06:22 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 20:06:22 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 20:06:22 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 20:06:22 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 20:06:22 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 20:06:24 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:06:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:06:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 20:06:24 INFO - Installing collected packages: mozsystemmonitor 20:06:24 INFO - Running setup.py install for mozsystemmonitor 20:06:24 INFO - Successfully installed mozsystemmonitor 20:06:24 INFO - Cleaning up... 20:06:24 INFO - Return code: 0 20:06:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 20:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:06:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:24 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 0x7fedbe176f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201e840>, '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 0x1f8b6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bb60>, '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': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', '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 20:06:24 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 20:06:24 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 20:06:24 INFO - Using env: {'DISPLAY': ':0', 20:06:24 INFO - 'HOME': '/home/cltbld', 20:06:24 INFO - 'LANG': 'en_US.UTF-8', 20:06:24 INFO - 'LANGUAGE': 'en_US:en', 20:06:24 INFO - 'LOGNAME': 'cltbld', 20:06:24 INFO - 'MAIL': '/var/mail/cltbld', 20:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:24 INFO - 'MOZ_NO_REMOTE': '1', 20:06:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:06:24 INFO - 'NO_EM_RESTART': '1', 20:06:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:06:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:06:24 INFO - 'PWD': '/builds/slave/test-pgo', 20:06:24 INFO - 'SHELL': '/bin/bash', 20:06:24 INFO - 'SHLVL': '1', 20:06:24 INFO - 'TERM': 'linux', 20:06:24 INFO - 'TMOUT': '86400', 20:06:24 INFO - 'USER': 'cltbld', 20:06:24 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', 20:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:24 INFO - '_': '/tools/buildbot/bin/python'} 20:06:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:24 INFO - Downloading/unpacking blobuploader==1.2.4 20:06:24 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 20:06:24 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 20:06:24 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 20:06:24 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 20:06:24 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 20:06:24 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 20:06:26 INFO - Downloading blobuploader-1.2.4.tar.gz 20:06:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:06:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:06: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 20:06: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 20:06: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 20:06: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 20:06: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 20:06: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 20:06:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 20:06:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:06: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 20:06: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 20:06: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 20:06: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 20:06: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 20:06: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 20:06:26 INFO - Downloading docopt-0.6.1.tar.gz 20:06:26 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 20:06:27 INFO - Installing collected packages: blobuploader, requests, docopt 20:06:27 INFO - Running setup.py install for blobuploader 20:06:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:06:27 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 20:06:27 INFO - Running setup.py install for requests 20:06:27 INFO - Running setup.py install for docopt 20:06:27 INFO - Successfully installed blobuploader requests docopt 20:06:27 INFO - Cleaning up... 20:06:27 INFO - Return code: 0 20:06:27 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 20:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:06:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:27 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 0x7fedbe176f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201e840>, '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 0x1f8b6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bb60>, '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': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', '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 20:06:27 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 20:06:27 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 20:06:27 INFO - Using env: {'DISPLAY': ':0', 20:06:27 INFO - 'HOME': '/home/cltbld', 20:06:27 INFO - 'LANG': 'en_US.UTF-8', 20:06:27 INFO - 'LANGUAGE': 'en_US:en', 20:06:27 INFO - 'LOGNAME': 'cltbld', 20:06:27 INFO - 'MAIL': '/var/mail/cltbld', 20:06:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:27 INFO - 'MOZ_NO_REMOTE': '1', 20:06:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:06:27 INFO - 'NO_EM_RESTART': '1', 20:06:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:06:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:06:27 INFO - 'PWD': '/builds/slave/test-pgo', 20:06:27 INFO - 'SHELL': '/bin/bash', 20:06:27 INFO - 'SHLVL': '1', 20:06:27 INFO - 'TERM': 'linux', 20:06:27 INFO - 'TMOUT': '86400', 20:06:27 INFO - 'USER': 'cltbld', 20:06:27 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', 20:06:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:27 INFO - '_': '/tools/buildbot/bin/python'} 20:06:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 20:06:27 INFO - Running setup.py (path:/tmp/pip-Xoa0o5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 20:06:27 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 20:06:27 INFO - Running setup.py (path:/tmp/pip-6FYksM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 20:06:28 INFO - Running setup.py (path:/tmp/pip-afhPtY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 20:06:28 INFO - Running setup.py (path:/tmp/pip-h1aqbU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 20:06:28 INFO - Running setup.py (path:/tmp/pip-VrYBoQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 20:06:28 INFO - Running setup.py (path:/tmp/pip-MRk9mz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 20:06:28 INFO - Running setup.py (path:/tmp/pip-gJe4Jg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 20:06:28 INFO - Running setup.py (path:/tmp/pip-va9523-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 20:06:28 INFO - Running setup.py (path:/tmp/pip-uL8B0G-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 20:06:28 INFO - Running setup.py (path:/tmp/pip-IeVTZV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 20:06:28 INFO - Running setup.py (path:/tmp/pip-gHxdpc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 20:06:28 INFO - Running setup.py (path:/tmp/pip-mAH3Mg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 20:06:28 INFO - Running setup.py (path:/tmp/pip-1ga_49-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 20:06:28 INFO - Running setup.py (path:/tmp/pip-bB1gw5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:06:28 INFO - Running setup.py (path:/tmp/pip-Ecdwt0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:06:28 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 20:06:28 INFO - Running setup.py (path:/tmp/pip-hdgZ3U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 20:06:29 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 20:06:29 INFO - Running setup.py (path:/tmp/pip-24QKmQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 20:06:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:06:29 INFO - Running setup.py install for manifestparser 20:06:29 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 20:06:29 INFO - Running setup.py install for mozcrash 20:06:29 INFO - Running setup.py install for mozdebug 20:06:29 INFO - Running setup.py install for mozdevice 20:06:29 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 20:06:29 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 20:06:29 INFO - Running setup.py install for mozfile 20:06:29 INFO - Running setup.py install for mozhttpd 20:06:29 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 20:06:29 INFO - Running setup.py install for mozinfo 20:06:29 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 20:06:29 INFO - Running setup.py install for mozInstall 20:06:29 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 20:06:29 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 20:06:29 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 20:06:29 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 20:06:29 INFO - Running setup.py install for mozleak 20:06:29 INFO - Running setup.py install for mozlog 20:06:30 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 20:06:30 INFO - Running setup.py install for moznetwork 20:06:30 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 20:06:30 INFO - Running setup.py install for mozprocess 20:06:30 INFO - Running setup.py install for mozprofile 20:06:30 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 20:06:30 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 20:06:30 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 20:06:30 INFO - Running setup.py install for mozrunner 20:06:30 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 20:06:30 INFO - Running setup.py install for mozscreenshot 20:06:30 INFO - Running setup.py install for moztest 20:06:30 INFO - Running setup.py install for mozversion 20:06:30 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 20:06:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:06:30 INFO - Cleaning up... 20:06:30 INFO - Return code: 0 20:06:30 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 20:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:06:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:30 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 0x7fedbe176f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201e840>, '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 0x1f8b6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bb60>, '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': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', '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 20:06:30 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 20:06:30 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 20:06:30 INFO - Using env: {'DISPLAY': ':0', 20:06:30 INFO - 'HOME': '/home/cltbld', 20:06:30 INFO - 'LANG': 'en_US.UTF-8', 20:06:30 INFO - 'LANGUAGE': 'en_US:en', 20:06:30 INFO - 'LOGNAME': 'cltbld', 20:06:30 INFO - 'MAIL': '/var/mail/cltbld', 20:06:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:30 INFO - 'MOZ_NO_REMOTE': '1', 20:06:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:06:30 INFO - 'NO_EM_RESTART': '1', 20:06:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:06:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:06:30 INFO - 'PWD': '/builds/slave/test-pgo', 20:06:30 INFO - 'SHELL': '/bin/bash', 20:06:30 INFO - 'SHLVL': '1', 20:06:30 INFO - 'TERM': 'linux', 20:06:30 INFO - 'TMOUT': '86400', 20:06:30 INFO - 'USER': 'cltbld', 20:06:30 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', 20:06:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:30 INFO - '_': '/tools/buildbot/bin/python'} 20:06:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 20:06:31 INFO - Running setup.py (path:/tmp/pip-OGK_WX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 20:06:31 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)) 20:06:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 20:06:31 INFO - Running setup.py (path:/tmp/pip-DcVSeB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 20:06:31 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)) 20:06:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 20:06:31 INFO - Running setup.py (path:/tmp/pip-RqSseh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 20:06:31 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)) 20:06:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 20:06:31 INFO - Running setup.py (path:/tmp/pip-MZGSlm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 20:06:31 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)) 20:06:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 20:06:31 INFO - Running setup.py (path:/tmp/pip-uM_d29-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 20:06:31 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)) 20:06:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 20:06:31 INFO - Running setup.py (path:/tmp/pip-LwRcBM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 20:06:31 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)) 20:06:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 20:06:31 INFO - Running setup.py (path:/tmp/pip-SBQgUW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 20:06:31 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)) 20:06:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 20:06:31 INFO - Running setup.py (path:/tmp/pip-c5rmO2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 20:06:31 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)) 20:06:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 20:06:31 INFO - Running setup.py (path:/tmp/pip-fgp6lX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 20:06:31 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)) 20:06:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 20:06:31 INFO - Running setup.py (path:/tmp/pip-5DMRF2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 20:06:31 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)) 20:06:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 20:06:31 INFO - Running setup.py (path:/tmp/pip-zbr7u9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 20:06:31 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)) 20:06:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 20:06:31 INFO - Running setup.py (path:/tmp/pip-4bql0O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 20:06:31 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)) 20:06:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 20:06:31 INFO - Running setup.py (path:/tmp/pip-X24r_S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 20:06:32 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)) 20:06:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 20:06:32 INFO - Running setup.py (path:/tmp/pip-hU8cvO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 20:06:32 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)) 20:06:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:06:32 INFO - Running setup.py (path:/tmp/pip-RYAtQo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 20:06:32 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)) 20:06:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 20:06:32 INFO - Running setup.py (path:/tmp/pip-HRMjZr-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 20:06:32 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)) 20:06:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 20:06:32 INFO - Running setup.py (path:/tmp/pip-T7syXz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 20:06:32 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)) 20:06:32 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)) 20:06:32 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)) 20:06:32 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)) 20:06:32 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)) 20:06:32 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)) 20:06:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 20:06: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 20:06: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 20:06: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 20:06: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 20:06: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 20:06: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 20:06:34 INFO - Downloading blessings-1.6.tar.gz 20:06:34 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 20:06:34 INFO - Installing collected packages: blessings 20:06:34 INFO - Running setup.py install for blessings 20:06:34 INFO - Successfully installed blessings 20:06:34 INFO - Cleaning up... 20:06:34 INFO - Return code: 0 20:06:34 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 20:06:34 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 20:06:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 20:06:34 INFO - Reading from file tmpfile_stdout 20:06:34 INFO - Current package versions: 20:06:34 INFO - argparse == 1.2.1 20:06:34 INFO - blessings == 1.6 20:06:34 INFO - blobuploader == 1.2.4 20:06:34 INFO - docopt == 0.6.1 20:06:34 INFO - manifestparser == 1.1 20:06:34 INFO - mozInstall == 1.12 20:06:34 INFO - mozcrash == 0.16 20:06:34 INFO - mozdebug == 0.1 20:06:34 INFO - mozdevice == 0.48 20:06:34 INFO - mozfile == 1.2 20:06:34 INFO - mozhttpd == 0.7 20:06:34 INFO - mozinfo == 0.9 20:06:34 INFO - mozleak == 0.1 20:06:34 INFO - mozlog == 3.1 20:06:34 INFO - moznetwork == 0.27 20:06:34 INFO - mozprocess == 0.22 20:06:34 INFO - mozprofile == 0.28 20:06:34 INFO - mozrunner == 6.11 20:06:34 INFO - mozscreenshot == 0.1 20:06:34 INFO - mozsystemmonitor == 0.0 20:06:34 INFO - moztest == 0.7 20:06:34 INFO - mozversion == 1.4 20:06:34 INFO - psutil == 3.1.1 20:06:34 INFO - requests == 1.2.3 20:06:34 INFO - wsgiref == 0.1.2 20:06:34 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 20:06:34 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')]} 20:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:34 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')]} 20:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:34 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 0x7fedbe176f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201e840>, '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 0x1f8b6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bb60>, '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': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', '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 20:06:34 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 20:06:34 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 20:06:34 INFO - Using env: {'DISPLAY': ':0', 20:06:34 INFO - 'HOME': '/home/cltbld', 20:06:34 INFO - 'LANG': 'en_US.UTF-8', 20:06:34 INFO - 'LANGUAGE': 'en_US:en', 20:06:34 INFO - 'LOGNAME': 'cltbld', 20:06:34 INFO - 'MAIL': '/var/mail/cltbld', 20:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:34 INFO - 'MOZ_NO_REMOTE': '1', 20:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:06:34 INFO - 'NO_EM_RESTART': '1', 20:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:06:34 INFO - 'PWD': '/builds/slave/test-pgo', 20:06:34 INFO - 'SHELL': '/bin/bash', 20:06:34 INFO - 'SHLVL': '1', 20:06:34 INFO - 'TERM': 'linux', 20:06:34 INFO - 'TMOUT': '86400', 20:06:34 INFO - 'USER': 'cltbld', 20:06:34 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', 20:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:34 INFO - '_': '/tools/buildbot/bin/python'} 20:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:34 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)) 20:06:34 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)) 20:06:34 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)) 20:06:34 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)) 20:06:34 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)) 20:06:34 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)) 20:06:34 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)) 20:06:34 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)) 20:06:34 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)) 20:06:34 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)) 20:06:34 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)) 20:06:34 INFO - Cleaning up... 20:06:34 INFO - Return code: 0 20:06:34 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 20:06:34 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')]} 20:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:34 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')]} 20:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:34 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 0x7fedbe176f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201e840>, '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 0x1f8b6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bb60>, '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': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', '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 20:06:34 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 20:06:34 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 20:06:34 INFO - Using env: {'DISPLAY': ':0', 20:06:34 INFO - 'HOME': '/home/cltbld', 20:06:34 INFO - 'LANG': 'en_US.UTF-8', 20:06:34 INFO - 'LANGUAGE': 'en_US:en', 20:06:34 INFO - 'LOGNAME': 'cltbld', 20:06:34 INFO - 'MAIL': '/var/mail/cltbld', 20:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:34 INFO - 'MOZ_NO_REMOTE': '1', 20:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:06:34 INFO - 'NO_EM_RESTART': '1', 20:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:06:34 INFO - 'PWD': '/builds/slave/test-pgo', 20:06:34 INFO - 'SHELL': '/bin/bash', 20:06:34 INFO - 'SHLVL': '1', 20:06:34 INFO - 'TERM': 'linux', 20:06:34 INFO - 'TMOUT': '86400', 20:06:34 INFO - 'USER': 'cltbld', 20:06:34 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', 20:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:34 INFO - '_': '/tools/buildbot/bin/python'} 20:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:34 INFO - Downloading/unpacking jsonschema 20:06:34 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 20:06:34 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 20:06:34 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 20:06:34 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 20:06:34 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 20:06:34 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 20:06:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:06:36 INFO - Downloading/unpacking functools32 (from jsonschema) 20:06:36 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 20:06:36 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 20:06:36 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 20:06:36 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 20:06:36 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 20:06:36 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 20:06:36 INFO - Downloading functools32-3.2.3-2.tar.gz 20:06:36 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 20:06:36 INFO - warning: no files found matching '*.txt' 20:06:36 INFO - no previously-included directories found matching 'build' 20:06:36 INFO - no previously-included directories found matching 'dist' 20:06:36 INFO - no previously-included directories found matching '.git*' 20:06:37 INFO - Installing collected packages: jsonschema, functools32 20:06:37 INFO - Running setup.py install for functools32 20:06:37 INFO - warning: no files found matching '*.txt' 20:06:37 INFO - no previously-included directories found matching 'build' 20:06:37 INFO - no previously-included directories found matching 'dist' 20:06:37 INFO - no previously-included directories found matching '.git*' 20:06:37 INFO - Successfully installed jsonschema functools32 20:06:37 INFO - Cleaning up... 20:06:37 INFO - Return code: 0 20:06:37 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 20:06:37 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')]} 20:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:06:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:37 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')]} 20:06:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:06:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:06:37 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 0x7fedbe176f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ebd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f8d630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x201e840>, '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 0x1f8b6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bb60>, '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': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', '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 20:06:37 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 20:06:37 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 20:06:37 INFO - Using env: {'DISPLAY': ':0', 20:06:37 INFO - 'HOME': '/home/cltbld', 20:06:37 INFO - 'LANG': 'en_US.UTF-8', 20:06:37 INFO - 'LANGUAGE': 'en_US:en', 20:06:37 INFO - 'LOGNAME': 'cltbld', 20:06:37 INFO - 'MAIL': '/var/mail/cltbld', 20:06:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:37 INFO - 'MOZ_NO_REMOTE': '1', 20:06:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:06:37 INFO - 'NO_EM_RESTART': '1', 20:06:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:06:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:06:37 INFO - 'PWD': '/builds/slave/test-pgo', 20:06:37 INFO - 'SHELL': '/bin/bash', 20:06:37 INFO - 'SHLVL': '1', 20:06:37 INFO - 'TERM': 'linux', 20:06:37 INFO - 'TMOUT': '86400', 20:06:37 INFO - 'USER': 'cltbld', 20:06:37 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', 20:06:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:37 INFO - '_': '/tools/buildbot/bin/python'} 20:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:06:37 INFO - Downloading/unpacking flake8 20:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:06:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:06:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:06:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:06:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:06:39 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 20:06:39 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 20:06:39 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 20:06:39 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 20:06:39 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 20:06:39 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 20:06:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:06:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:06:39 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 20:06:39 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 20:06:39 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 20:06:39 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 20:06:39 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 20:06:39 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 20:06:39 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:06:39 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 20:06:39 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 20:06:39 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 20:06:39 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 20:06:39 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 20:06:39 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 20:06:39 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 20:06:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:06:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:06:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:06:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:06:39 INFO - no previously-included directories found matching 'docs/_build' 20:06:39 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:06:39 INFO - Running setup.py install for pep8 20:06:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:06:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:06:39 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:06:39 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:06:39 INFO - no previously-included directories found matching 'docs/_build' 20:06:40 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 20:06:40 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:06:40 INFO - Cleaning up... 20:06:40 INFO - Return code: 0 20:06:40 INFO - Running post-action listener: _resource_record_post_action 20:06:40 INFO - Running post-action listener: _start_resource_monitoring 20:06:40 INFO - Starting resource monitoring. 20:06:40 INFO - ##### 20:06:40 INFO - ##### Running install step. 20:06:40 INFO - ##### 20:06:40 INFO - Running pre-action listener: _resource_record_pre_action 20:06:40 INFO - Running main action method: install 20:06:40 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 20:06:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 20:06:40 INFO - Reading from file tmpfile_stdout 20:06:40 INFO - Detecting whether we're running mozinstall >=1.0... 20:06:40 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 20:06:40 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 20:06:40 INFO - Reading from file tmpfile_stdout 20:06:40 INFO - Output received: 20:06:40 INFO - Usage: mozinstall [options] installer 20:06:40 INFO - Options: 20:06:40 INFO - -h, --help show this help message and exit 20:06:40 INFO - -d DEST, --destination=DEST 20:06:40 INFO - Directory to install application into. [default: 20:06:40 INFO - "/builds/slave/test-pgo"] 20:06:40 INFO - --app=APP Application being installed. [default: firefox] 20:06:40 INFO - mkdir: /builds/slave/test-pgo/build/application 20:06:40 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'] 20:06:40 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 20:06:56 INFO - Reading from file tmpfile_stdout 20:06:56 INFO - Output received: 20:06:56 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 20:06:56 INFO - Running post-action listener: _resource_record_post_action 20:06:56 INFO - ##### 20:06:56 INFO - ##### Running run-tests step. 20:06:56 INFO - ##### 20:06:56 INFO - Running pre-action listener: _resource_record_pre_action 20:06:56 INFO - Running main action method: run_tests 20:06:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 20:06:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 20:06:56 INFO - Python 2.7.3 20:06:56 INFO - Return code: 0 20:06:56 INFO - grabbing minidump binary from tooltool 20:06:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:06:56 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 0x201e840>, '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 0x1f8b6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f8bb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 20:06:56 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 20:06:56 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 20:06:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:06:56 INFO - Return code: 0 20:06:56 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 20:06:56 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 20:06:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 20:06:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 20:06:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 20:06:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 20:06:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 20:06:58 INFO - Return code: 0 20:06:58 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/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 20:06:58 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/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --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 20:06:58 INFO - Using env: {'DISPLAY': ':0', 20:06:58 INFO - 'HOME': '/home/cltbld', 20:06:58 INFO - 'LANG': 'en_US.UTF-8', 20:06:58 INFO - 'LANGUAGE': 'en_US:en', 20:06:58 INFO - 'LOGNAME': 'cltbld', 20:06:58 INFO - 'MAIL': '/var/mail/cltbld', 20:06:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 20:06:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 20:06:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:06:58 INFO - 'MOZ_NO_REMOTE': '1', 20:06:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 20:06:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:06:58 INFO - 'NO_EM_RESTART': '1', 20:06:58 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:06:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 20:06:58 INFO - 'PWD': '/builds/slave/test-pgo', 20:06:58 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 20:06:58 INFO - 'SHELL': '/bin/bash', 20:06:58 INFO - 'SHLVL': '1', 20:06:58 INFO - 'TERM': 'linux', 20:06:58 INFO - 'TMOUT': '86400', 20:06:58 INFO - 'USER': 'cltbld', 20:06:58 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049', 20:06:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:06:58 INFO - '_': '/tools/buildbot/bin/python'} 20:06:58 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/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 20:06:58 INFO - mozversion application_buildid: 20160127173007 20:06:58 INFO - mozversion application_changeset: 261e997621c182a03bc330c8bd18c98eddb9c7eb 20:06:58 INFO - mozversion application_display_name: Nightly 20:06:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:06:58 INFO - mozversion application_name: Firefox 20:06:58 INFO - mozversion application_remotingname: firefox 20:06:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:06:58 INFO - mozversion application_vendor: Mozilla 20:06:58 INFO - mozversion application_version: 47.0a1 20:06:58 INFO - mozversion platform_buildid: 20160127173007 20:06:58 INFO - mozversion platform_changeset: 261e997621c182a03bc330c8bd18c98eddb9c7eb 20:06:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:06:58 INFO - mozversion platform_version: 47.0a1 20:06:58 INFO - using testdate: 1453954018 20:06:58 INFO - actual date: 1453954018 20:06:58 INFO - starting webserver on 'localhost:54056' 20:06:58 INFO - SUITE-START | Running 2 tests 20:06:58 INFO - TEST-START | damp 20:06:58 INFO - operating with platform_type : linux_ 20:06:58 INFO - Initialising browser for damp test... 20:06:58 INFO - TEST-INFO | started process 11464 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpJRgGi1/profile http://localhost:54056/getInfo.html) 20:07:02 INFO - PROCESS | 11464 | __metrics Screen width/height:1600/1200 20:07:02 INFO - PROCESS | 11464 | colorDepth:24 20:07:02 INFO - PROCESS | 11464 | Browser inner width/height: 1024/697 20:07:02 INFO - PROCESS | 11464 | __metrics 20:07:03 INFO - PROCESS | 11464 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 20:07:03 INFO - PROCESS | 11464 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:07:03 INFO - TEST-INFO | 11464: exit 0 20:07:03 INFO - Browser initialized. 20:07:03 INFO - Running cycle 1/1 for damp test... 20:07:03 INFO - TEST-INFO | started process 11563 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpJRgGi1/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/devtools/damp.manifest.develop -tpchrome -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 25) 20:07:15 INFO - PROCESS | 11563 | RSS: Main: 149893120 20:07:15 INFO - PROCESS | 11563 | 20:07:16 INFO - PROCESS | 11563 | console.error: 20:07:16 INFO - PROCESS | 11563 | 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 20:07:16 INFO - PROCESS | 11563 | console.error: 20:07:16 INFO - PROCESS | 11563 | exports.createSystem/system.addItemsByModule/damp: 231.21 20:31:42 INFO - RETURN: tps: 48.22 20:31:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [1138.33, 299.75, 302.0650000000023, 285.5199999999895, 298.1699999999837, 286.61999999999534, 292.95999999999185, 292.8549999999814, 293.2149999999674, 304.17499999998836, 296.36000000004424, 304.17500000004657, 288.17499999998836, 271.93499999993946, 288.9299999999348, 292.53000000002794, 297.1049999999814, 298.51500000001397, 279.7700000000186, 282.0349999999162, 280.1049999999814, 299.3850000000093, 285.8850000000093, 279.8399999999674, 294.8450000000885], "unit": "ms", "name": "simple.webconsole.open.DAMP", "value": 292.9074999999866}, {"lowerIsBetter": true, "replicates": [33.00500000000102, 33.12000000000262, 40.14499999998952, 34.86499999999069, 33.345000000001164, 33.904999999998836, 33.81500000000233, 34.695000000006985, 32.28000000002794, 27.69000000000233, 30.205000000016298, 34.77500000002328, 37.02500000002328, 28.14499999990221, 32.815000000060536, 30.294999999925494, 30.214999999967404, 32.75, 31.229999999981374, 32.79500000004191, 33.10999999998603, 36.464999999967404, 33.05499999993481, 32.65500000002794, 33.43500000005588], "unit": "ms", "name": "simple.webconsole.reload.DAMP", "value": 33.08249999996042}, {"lowerIsBetter": true, "replicates": [36.094999999999345, 37.05000000000291, 41.0399999999936, 50.29000000000815, 34.195000000006985, 34.60500000001048, 44.55999999999767, 39.9550000000163, 36.31999999994878, 35.04999999998836, 34.57500000001164, 45.67499999998836, 33.089999999967404, 35.64500000001863, 36.544999999925494, 42.71000000007916, 45.48000000009779, 38.199999999953434, 40.78999999992084, 44.48999999999069, 38.34999999997672, 41.16500000003725, 33.625, 42.76500000001397, 34.97999999998137], "unit": "ms", "name": "simple.webconsole.close.DAMP", "value": 38.274999999965075}, {"lowerIsBetter": true, "replicates": [546.2900000000009, 363.4050000000061, 424.24499999999534, 364.9649999999965, 393.20999999999185, 385.3399999999965, 371.4550000000163, 382.875, 374.48500000004424, 398.36999999999534, 389.5, 391.5099999999511, 404.5, 379.57499999995343, 375.4200000000419, 388.55000000004657, 377.0649999999441, 405.3850000000093, 376.31500000006054, 375.57500000006985, 377.26499999989755, 373.9550000000745, 379.8299999999581, 379.3399999999674, 381.8300000000745], "unit": "ms", "name": "simple.inspector.open.DAMP", "value": 379.70249999995576}, {"lowerIsBetter": true, "replicates": [135.5650000000005, 100.55999999999767, 105.41500000000815, 106.05999999999767, 102.55999999999767, 103.6700000000128, 100.2850000000326, 100.9199999999837, 101.43499999999767, 105.80499999999302, 102.79999999998836, 122.24499999999534, 104.85499999998137, 101.36499999999069, 103.4649999999674, 106.65499999991152, 112.44000000006054, 103.34999999997672, 114.9649999999674, 113.04500000004191, 105.11499999999069, 110.64500000001863, 108.6600000000326, 107.11499999999069, 107.0], "unit": "ms", "name": "simple.inspector.reload.DAMP", "value": 105.26499999999942}, {"lowerIsBetter": true, "replicates": [73.39499999999862, 55.174999999995634, 53.35000000000582, 60.28500000000349, 61.295000000012806, 53.27000000001863, 52.61499999999069, 55.054999999993015, 56.38500000000931, 53.10999999998603, 52.51000000000931, 60.98999999999069, 54.5800000000163, 52.48999999999069, 51.92999999993481, 53.809999999939464, 57.53000000002794, 56.550000000046566, 56.45999999996275, 53.09999999997672, 56.03999999992084, 57.65500000002794, 55.76000000000931, 54.48999999999069, 52.01000000000931], "unit": "ms", "name": "simple.inspector.close.DAMP", "value": 54.81750000000466}, {"lowerIsBetter": true, "replicates": [507.744999999999, 404.1050000000032, 403.8099999999977, 402.5999999999767, 414.85999999998603, 415.04000000000815, 392.88000000000466, 385.0599999999977, 424.7700000000186, 395.85999999998603, 434.2949999999837, 396.0249999999651, 403.32500000001164, 386.8850000000093, 398.63000000000466, 415.61999999999534, 396.6799999999348, 412.625, 405.6899999999441, 413.6850000000559, 412.3399999999674, 389.6600000000326, 409.89000000001397, 406.57500000006985, 401.44000000006054], "unit": "ms", "name": "simple.jsdebugger.open.DAMP", "value": 403.95750000000044}, {"lowerIsBetter": true, "replicates": [38.13500000000022, 34.799999999995634, 38.63500000000931, 42.30500000002212, 35.73499999998603, 35.8300000000163, 39.78000000002794, 35.570000000006985, 41.84499999997206, 41.70000000001164, 41.70000000001164, 45.7050000000163, 59.84500000003027, 37.75500000000466, 40.26500000001397, 42.32999999995809, 41.044999999925494, 35.59999999997672, 34.65999999991618, 40.45499999995809, 42.51500000001397, 35.75, 41.110000000102445, 39.47499999997672, 39.86499999999069], "unit": "ms", "name": "simple.jsdebugger.reload.DAMP", "value": 40.06500000000233}, {"lowerIsBetter": true, "replicates": [31.895000000000437, 37.84000000000378, 37.91000000000349, 38.86999999999534, 36.93499999999767, 41.38000000000466, 34.68499999999767, 54.26499999995576, 35.17000000004191, 41.52500000002328, 44.55999999999767, 41.414999999979045, 35.77999999996973, 36.42999999993481, 37.52500000002328, 68.89000000001397, 37.470000000088476, 38.09499999997206, 60.55500000005122, 41.13000000000466, 37.49499999999534, 39.99499999999534, 40.90500000002794, 41.57999999995809, 40.964999999967404], "unit": "ms", "name": "simple.jsdebugger.close.DAMP", "value": 39.43249999999534}, {"lowerIsBetter": true, "replicates": [512.6499999999996, 509.58000000000175, 544.1199999999953, 570.0450000000128, 515.0799999999872, 526.1299999999756, 521.8349999999627, 569.3250000000116, 550.0200000000186, 531.484999999986, 588.6849999999977, 539.1900000000023, 613.5050000000047, 523.8399999999674, 524.609999999986, 600.8699999999953, 518.8050000000512, 545.125, 555.0550000000512, 537.3099999999395, 543.890000000014, 537.8950000000186, 514.5999999999767, 539.1600000000326, 516.2399999999907], "unit": "ms", "name": "simple.styleeditor.open.DAMP", "value": 538.5275000000256}, {"lowerIsBetter": true, "replicates": [32.57999999999993, 30.580000000001746, 31.289999999993597, 38.25999999998021, 43.204999999987194, 89.41500000000815, 31.449999999953434, 45.14499999996042, 44.47999999998137, 43.88000000000466, 38.839999999967404, 55.134999999951106, 38.26500000001397, 30.330000000074506, 30.824999999953434, 49.39500000001863, 48.29000000003725, 44.089999999967404, 37.79500000004191, 45.41500000003725, 44.23999999999069, 44.84499999997206, 69.56000000005588, 31.130000000004657, 44.63500000000931], "unit": "ms", "name": "simple.styleeditor.reload.DAMP", "value": 43.98499999998603}, {"lowerIsBetter": true, "replicates": [40.38000000000102, 53.62999999999738, 43.104999999995925, 59.16000000000349, 47.78500000000349, 63.720000000001164, 42.95499999995809, 44.30999999999767, 86.32000000000698, 47.78499999997439, 50.21999999997206, 54.13999999995576, 51.90000000002328, 43.59499999997206, 47.35499999998137, 45.61999999999534, 44.839999999967404, 48.71999999997206, 49.845000000088476, 40.675000000046566, 50.39500000001863, 49.04000000003725, 58.81999999994878, 44.88000000000466, 48.72499999997672], "unit": "ms", "name": "simple.styleeditor.close.DAMP", "value": 48.72249999997439}, {"lowerIsBetter": true, "replicates": [408.8799999999992, 391.27999999999884, 296.59999999999127, 327.3850000000093, 303.25500000000466, 442.1499999999942, 312.679999999993, 276.50500000000466, 482.179999999993, 274.3550000000396, 319.4800000000396, 352.21000000002095, 303.29999999998836, 299.4200000000419, 269.36999999999534, 318.53000000002794, 273.0450000000419, 287.16499999992084, 341.29999999993015, 310.8200000000652, 292.44999999995343, 270.98499999998603, 498.25500000000466, 281.60000000009313, 271.30000000004657], "unit": "ms", "name": "simple.performance.open.DAMP", "value": 303.2774999999965}, {"lowerIsBetter": true, "replicates": [28.395000000000437, 79.18499999999767, 28.35000000000582, 32.36999999999534, 26.455000000016298, 26.095000000001164, 25.67499999998836, 30.380000000004657, 25.929999999993015, 26.96999999997206, 28.494999999995343, 26.974999999976717, 25.945000000006985, 45.55500000005122, 25.505000000004657, 26.45000000006985, 24.85999999998603, 25.714999999967404, 26.675000000046566, 80.07499999995343, 26.354999999981374, 106.7149999999674, 25.940000000060536, 25.630000000004657, 29.96500000008382], "unit": "ms", "name": "simple.performance.reload.DAMP", "value": 26.565000000031432}, {"lowerIsBetter": true, "replicates": [26.659999999999854, 39.08999999999651, 33.770000000004075, 32.29999999998836, 31.45999999999185, 47.429999999993015, 36.84499999997206, 31.819999999948777, 39.02000000001863, 33.90999999997439, 36.76499999995576, 33.68499999999767, 36.63500000000931, 34.88000000000466, 37.15500000002794, 32.85999999998603, 34.84499999997206, 34.080000000074506, 35.15000000002328, 50.434999999939464, 33.824999999953434, 50.26500000001397, 36.425000000046566, 33.26500000001397, 32.15000000002328], "unit": "ms", "name": "simple.performance.close.DAMP", "value": 34.86249999998836}, {"lowerIsBetter": true, "replicates": [373.3600000000006, 350.4150000000009, 278.445000000007, 257.4199999999837, 1155.0999999999767, 278.12999999997555, 284.40499999996973, 284.90499999996973, 311.2949999999837, 280.8950000000186, 332.8400000000256, 285.8649999999907, 339.8300000000163, 281.5, 274.74499999999534, 290.8299999999581, 276.7750000000233, 275.375, 278.61999999999534, 434.1799999999348, 280.24499999999534, 377.74499999999534, 296.8200000000652, 313.32499999995343, 390.73499999998603], "unit": "ms", "name": "simple.netmonitor.open.DAMP", "value": 285.3849999999802}, {"lowerIsBetter": true, "replicates": [71.33500000000095, 32.25, 49.20000000001164, 34.64999999999418, 71.60000000000582, 35.60500000001048, 28.550000000046566, 31.915000000037253, 36.335000000020955, 63.785000000032596, 70.26500000001397, 78.7050000000163, 47.535000000032596, 38.88500000000931, 26.875, 69.28000000002794, 31.585000000079162, 32.0, 85.78499999991618, 25.430000000051223, 39.59499999997206, 30.899999999906868, 51.70999999996275, 31.48499999998603, 56.589999999967404], "unit": "ms", "name": "simple.netmonitor.reload.DAMP", "value": 37.610000000015134}, {"lowerIsBetter": true, "replicates": [81.02000000000044, 54.7300000000032, 52.94499999999243, 102.47000000000116, 67.52499999999418, 49.25500000000466, 60.76000000000931, 50.68499999999767, 50.80999999999767, 51.45000000001164, 84.40000000002328, 80.68499999999767, 68.24500000005355, 50.30500000005122, 53.75, 69.125, 52.02000000001863, 55.49499999999534, 83.32499999995343, 55.86999999999534, 52.0, 53.30500000005122, 48.639999999897555, 51.86999999999534, 73.61499999999069], "unit": "ms", "name": "simple.netmonitor.close.DAMP", "value": 54.2400000000016}, {"lowerIsBetter": true, "replicates": [381.2900000000009, 349.625, 616.195000000007, 377.4549999999872, 583.6199999999953, 334.10999999998603, 363.73500000004424, 722.4349999999977, 335.69999999995343, 613.0899999999674, 481.4650000000256, 374.9400000000023, 574.8500000000349, 426.76500000001397, 460.60999999998603, 499.6450000000186, 519.125, 741.4050000000279, 508.3550000000978, 331.4449999999488, 466.66500000003725, 335.56500000006054, 329.07499999995343, 514.3800000000047, 413.8950000000186], "unit": "ms", "name": "simple.memory.open.DAMP", "value": 463.63750000001164}, {"lowerIsBetter": true, "replicates": [40.07999999999993, 34.375, 43.69499999999243, 31.5, 38.5, 50.86500000001979, 55.535000000032596, 35.63000000000466, 40.460000000020955, 43.75500000000466, 39.460000000020955, 37.0, 34.52000000001863, 84.8399999999674, 82.47499999997672, 59.11999999999534, 78.75500000000466, 87.79500000004191, 34.01500000001397, 33.51999999990221, 85.10999999998603, 39.09999999997672, 55.044999999925494, 88.56499999994412, 39.714999999967404], "unit": "ms", "name": "simple.memory.reload.DAMP", "value": 42.077500000006694}, {"lowerIsBetter": true, "replicates": [67.29999999999927, 75.11499999999796, 65.2549999999901, 71.19999999998254, 76.45000000001164, 69.09500000000116, 369.5249999999651, 75.13999999995576, 73.42499999998836, 69.42499999998836, 64.88000000000466, 71.8300000000163, 68.75500000000466, 361.0200000000186, 362.41500000003725, 69.69499999994878, 351.2150000000838, 70.26999999990221, 61.56999999994878, 71.31999999994878, 221.8550000000978, 68.36999999999534, 69.54000000003725, 190.51500000001397, 65.64500000001863], "unit": "ms", "name": "simple.saveHeapSnapshot", "value": 71.25999999996566}, {"lowerIsBetter": true, "replicates": [13.159999999999854, 9.390000000006694, 10.644999999989523, 15.48499999998603, 15.760000000009313, 19.605000000010477, 7.875, 13.985000000044238, 13.119999999995343, 17.239999999990687, 10.990000000048894, 19.520000000018626, 11.345000000030268, 9.45499999995809, 7.684999999939464, 12.715000000083819, 7.864999999990687, 17.104999999981374, 10.489999999990687, 7.540000000037253, 9.775000000023283, 9.90500000002794, 9.784999999916181, 7.305000000051223, 14.0], "unit": "ms", "name": "simple.readHeapSnapshot", "value": 10.817500000019209}, {"lowerIsBetter": true, "replicates": [4.069999999999709, 4.394999999996799, 4.580000000001746, 4.154999999998836, 4.105000000010477, 4.1450000000186265, 4.4200000000419095, 4.010000000009313, 4.210000000020955, 3.98499999998603, 5.089999999967404, 4.1049999999813735, 4.519999999960419, 4.099999999976717, 4.010000000009313, 4.055000000051223, 3.9350000000558794, 4.325000000069849, 3.98499999998603, 4.564999999944121, 4.035000000032596, 4.065000000060536, 4.150000000023283, 4.15500000002794, 4.8950000000186265], "unit": "ms", "name": "simple.takeCensus", "value": 4.147500000020955}, {"lowerIsBetter": true, "replicates": [28.654999999998836, 42.05500000000029, 24.80000000000291, 34.35999999998603, 39.78500000000349, 23.43499999999767, 44.31500000000233, 44.16500000003725, 37.945000000006985, 34.79500000004191, 26.53499999997439, 25.425000000046566, 25.179999999993015, 50.95499999995809, 46.69499999994878, 34.23499999998603, 47.45000000006985, 50.54500000004191, 36.39000000001397, 39.85499999998137, 43.285000000032596, 38.70000000006985, 24.81000000005588, 46.72499999997672, 36.199999999953434], "unit": "ms", "name": "simple.memory.close.DAMP", "value": 38.32250000003842}, {"lowerIsBetter": true, "replicates": [495.1749999999993, 502.32500000000437, 504.41999999999825, 507.1600000000035, 511.3949999999895, 559.7000000000116, 561.5650000000023, 516.2000000000116, 543.7949999999837, 509.320000000007, 522.429999999993, 518.1650000000373, 499.6499999999651, 545.6799999999348, 546.0, 522.1949999999488, 543.4199999999255, 549.8100000000559, 509.1050000000978, 527.7900000000373, 549.9550000000745, 504.1149999999907, 543.6550000000279, 544.3499999999767, 508.0199999999022], "unit": "ms", "name": "complicated.webconsole.open.DAMP", "value": 522.3124999999709}, {"lowerIsBetter": true, "replicates": [2062.5099999999984, 2103.9400000000023, 2013.9349999999977, 2061.600000000006, 2042.3050000000221, 2075.8000000000175, 2043.0400000000373, 2173.570000000007, 2097.844999999972, 2040.7000000000116, 2018.3950000000186, 2051.2649999999558, 2052.0750000000116, 2001.4250000000466, 2041.1199999999953, 2046.6850000000559, 2010.7850000000326, 2056.25, 2045.7849999999162, 2098.734999999986, 2088.2649999998976, 2140.92499999993, 2072.67499999993, 2036.875, 2125.085000000079], "unit": "ms", "name": "complicated.webconsole.reload.DAMP", "value": 2051.6699999999837}, {"lowerIsBetter": true, "replicates": [1160.1650000000009, 657.4900000000052, 763.4300000000076, 729.5400000000081, 744.5950000000012, 624.1749999999884, 646.7099999999627, 698.6549999999697, 623.9100000000326, 713.4449999999488, 744.6199999999953, 750.765000000014, 742.3800000000047, 687.7700000000186, 738.6199999999953, 751.765000000014, 655.3449999999721, 724.9949999999953, 742.265000000014, 659.765000000014, 752.7449999999953, 658.9600000000792, 623.109999999986, 702.1699999999255, 669.0600000000559], "unit": "ms", "name": "complicated.webconsole.close.DAMP", "value": 707.8074999999371}, {"lowerIsBetter": true, "replicates": [634.1649999999972, 624.8999999999942, 632.7049999999872, 624.0749999999825, 613.945000000007, 685.2200000000012, 751.7800000000279, 639.234999999986, 942.0749999999534, 639.4700000000303, 627.0200000000186, 647.0249999999651, 590.7949999999837, 726.8800000000047, 651.3849999998929, 641.3399999999674, 701.234999999986, 634.2949999999255, 602.8499999999767, 607.4650000000838, 627.7200000000885, 619.5100000000093, 805.6350000000093, 663.1450000000186, 640.8100000000559], "unit": "ms", "name": "complicated.inspector.open.DAMP", "value": 639.3525000000081}, {"lowerIsBetter": true, "replicates": [2248.834999999999, 2362.6100000000006, 1977.7850000000035, 2036.2749999999942, 2027.3850000000093, 2440.5750000000116, 2005.2200000000303, 2406.3150000000023, 2055.1549999999697, 2025.6699999999837, 2039.2000000000116, 2045.2449999999953, 1999.414999999979, 2039.1800000000512, 1984.5100000000093, 2020.1300000000047, 2011.030000000028, 2041.8050000000512, 2050.019999999902, 2412.5599999999395, 2042.1999999999534, 2397.0100000000093, 2210.930000000051, 2026.0700000000652, 2381.5050000000047], "unit": "ms", "name": "complicated.inspector.reload.DAMP", "value": 2040.5025000000314}, {"lowerIsBetter": true, "replicates": [661.25, 668.5850000000064, 1027.75, 1026.8950000000186, 1059.1650000000081, 701.0050000000047, 1079.9099999999744, 704.7150000000256, 693.0799999999581, 1048.9700000000303, 1044.5350000000326, 1037.679999999993, 1051.8800000000047, 1096.5750000000698, 1032.2700000000186, 1040.0650000000605, 1085.655000000028, 1147.7750000000233, 1038.1000000000931, 695.4199999999255, 1076.0700000000652, 686.4250000000466, 693.734999999986, 1112.6599999999162, 714.4399999999441], "unit": "ms", "name": "complicated.inspector.close.DAMP", "value": 1037.890000000043}, {"lowerIsBetter": true, "replicates": [614.2299999999996, 700.2449999999953, 651.6000000000058, 659.7350000000151, 675.0449999999837, 699.3450000000012, 663.3250000000116, 667.6500000000233, 716.4750000000349, 666.710000000021, 676.3399999999674, 696.460000000021, 659.8649999999907, 667.3500000000931, 668.0550000000512, 669.9699999999721, 684.9949999999953, 678.2900000000373, 670.5250000000233, 657.8999999999069, 686.9450000000652, 655.4300000000512, 672.0299999999115, 683.3249999999534, 655.9800000000978], "unit": "ms", "name": "complicated.jsdebugger.open.DAMP", "value": 670.2474999999977}, {"lowerIsBetter": true, "replicates": [1974.1900000000023, 2141.7649999999994, 2007.449999999997, 2013.6499999999942, 2015.3550000000105, 2236.070000000007, 2025.4100000000326, 2043.5750000000116, 2191.320000000007, 2061.3699999999953, 2005.054999999993, 1998.280000000028, 2044.6450000000186, 2067.350000000093, 2018.6899999999441, 2015.1750000000466, 2063.094999999972, 2091.9499999999534, 2030.890000000014, 2039.6699999999255, 2054.445000000065, 2019.1999999999534, 2209.3899999998976, 2051.9499999999534, 2046.155000000028], "unit": "ms", "name": "complicated.jsdebugger.reload.DAMP", "value": 2044.1100000000151}, {"lowerIsBetter": true, "replicates": [523.244999999999, 608.4700000000012, 575.5449999999983, 552.054999999993, 545.7050000000163, 615.0699999999779, 554.7449999999953, 544.5199999999604, 595.3950000000186, 557.1599999999744, 536.2849999999744, 537.5100000000093, 552.9100000000326, 554.234999999986, 554.2700000000186, 560.0899999999674, 562.1600000000326, 557.234999999986, 552.6550000000279, 537.0650000000605, 532.9349999999395, 537.4400000000605, 583.6550000000279, 544.9050000000279, 564.5649999999441], "unit": "ms", "name": "complicated.jsdebugger.close.DAMP", "value": 554.2525000000023}, {"lowerIsBetter": true, "replicates": [1917.244999999999, 2257.6399999999994, 1701.324999999997, 1705.6699999999837, 1723.0450000000128, 2343.0350000000035, 1671.1849999999977, 1760.4749999999767, 2469.2200000000303, 1731.1100000000442, 1730.469999999972, 1721.8699999999953, 1709.9749999999767, 1711.8800000000047, 1701.7100000000792, 1754.0900000000838, 1661.4649999999674, 1694.0849999999627, 1748.8049999999348, 1859.484999999986, 1723.875, 1836.9349999999395, 2519.180000000051, 1737.079999999958, 1831.765000000014], "unit": "ms", "name": "complicated.styleeditor.open.DAMP", "value": 1730.7900000000081}, {"lowerIsBetter": true, "replicates": [2129.834999999999, 1917.8699999999953, 2171.925000000003, 2221.5599999999977, 2260.0200000000186, 2041.2400000000198, 2065.0650000000023, 2233.195000000007, 1897.5599999999977, 2191.0449999999837, 2194.0350000000326, 2070.625, 2207.594999999972, 2067.045000000042, 2197.734999999986, 2221.9899999999907, 2106.7949999999255, 2253.225000000093, 2232.4649999999674, 2167.039999999921, 2301.70000000007, 2225.17499999993, 1938.3699999999953, 2187.5250000000233, 2222.405000000028], "unit": "ms", "name": "complicated.styleeditor.reload.DAMP", "value": 2192.540000000008}, {"lowerIsBetter": true, "replicates": [596.4050000000061, 568.1199999999953, 620.8450000000012, 649.8349999999919, 611.7550000000047, 580.0199999999895, 618.6600000000326, 574.3400000000256, 559.0300000000279, 621.2000000000116, 635.390000000014, 633.4799999999814, 602.8100000000559, 576.3950000000186, 607.6549999999115, 584.6699999999255, 640.0699999999488, 675.9649999999674, 681.6149999999907, 568.0300000000279, 645.765000000014, 581.5799999999581, 554.484999999986, 608.9799999999814, 622.8349999999627], "unit": "ms", "name": "complicated.styleeditor.close.DAMP", "value": 610.367499999993}, {"lowerIsBetter": true, "replicates": [931.864999999998, 638.5650000000023, 489.7700000000041, 490.76500000001397, 485.65499999999884, 609.6449999999895, 563.8549999999814, 1002.4099999999744, 636.1149999999907, 488.45000000001164, 490.875, 584.5400000000373, 508.4299999999348, 564.5250000000233, 504.0700000000652, 893.9250000000466, 570.9050000000279, 510.9200000000419, 498.2249999999767, 738.859999999986, 496.83499999996275, 1021.0949999999721, 642.1049999999814, 484.8149999999441, 490.0600000000559], "unit": "ms", "name": "complicated.performance.open.DAMP", "value": 537.3875000000116}, {"lowerIsBetter": true, "replicates": [1924.949999999997, 1907.9199999999983, 2448.8849999999948, 2316.5050000000047, 2349.0199999999895, 2004.6900000000023, 2100.725000000035, 1895.7999999999884, 1972.695000000007, 2279.1849999999977, 2311.240000000049, 2004.1049999999814, 2208.680000000051, 1966.2350000001024, 2323.405000000028, 2000.9000000000233, 1977.6849999999395, 2351.405000000028, 2347.29999999993, 2177.5449999999255, 2226.6900000000605, 1909.2299999999814, 1967.6999999999534, 2300.4150000000373, 2254.844999999972], "unit": "ms", "name": "complicated.performance.reload.DAMP", "value": 2193.1124999999884}, {"lowerIsBetter": true, "replicates": [566.7449999999953, 598.7399999999907, 581.4649999999965, 565.3950000000186, 563.3849999999802, 589.7300000000105, 871.6149999999907, 543.4349999999977, 614.5900000000256, 565.3650000000489, 585.2700000000186, 996.6199999999953, 561.5149999998976, 1209.5050000000047, 600.0800000000745, 555.2099999999627, 985.6149999999907, 575.0849999999627, 554.9200000000419, 580.5599999999395, 548.1700000000419, 561.2649999998976, 599.0299999999115, 566.4249999999302, 535.234999999986], "unit": "ms", "name": "complicated.performance.close.DAMP", "value": 577.8224999999511}, {"lowerIsBetter": true, "replicates": [531.3450000000012, 572.929999999993, 525.9249999999884, 529.1149999999907, 534.054999999993, 611.1399999999849, 545.3899999999558, 538.9550000000163, 597.8349999999627, 536.9400000000023, 547.0949999999721, 549.1000000000349, 526.3850000000093, 551.9250000000466, 552.4650000000838, 557.1450000000186, 542.2949999999255, 538.7750000000233, 529.3649999999907, 541.2449999999953, 538.484999999986, 523.3449999999721, 566.0699999999488, 528.0850000000792, 522.1049999999814], "unit": "ms", "name": "complicated.netmonitor.open.DAMP", "value": 540.1000000000058}, {"lowerIsBetter": true, "replicates": [2399.470000000001, 2675.404999999999, 2224.6449999999895, 2186.904999999999, 2205.789999999979, 2584.1849999999977, 2248.5499999999884, 2474.7050000000163, 2705.070000000007, 2216.320000000007, 2199.3300000000163, 2290.125, 2226.6350000000093, 2235.484999999986, 2224.2399999999907, 2464.7299999999814, 2297.569999999949, 2205.5100000000093, 2195.3649999999907, 2400.70000000007, 2219.82500000007, 2495.9749999999767, 2749.4349999999395, 2200.5250000000233, 2214.9000000000233], "unit": "ms", "name": "complicated.netmonitor.reload.DAMP", "value": 2231.0599999999977}, {"lowerIsBetter": true, "replicates": [841.0400000000009, 770.7400000000052, 738.4599999999919, 764.9500000000116, 779.5599999999977, 736.304999999993, 774.3250000000116, 815.7199999999721, 768.179999999993, 774.6149999999907, 763.054999999993, 786.6599999999744, 760.9249999999302, 858.0900000000838, 736.8699999999953, 828.4799999999814, 748.0650000000605, 773.3850000000093, 735.4949999999953, 857.0100000000093, 749.3299999999581, 836.3049999999348, 768.6650000000373, 754.3800000000047, 745.7100000000792], "unit": "ms", "name": "complicated.netmonitor.close.DAMP", "value": 768.4225000000151}, {"lowerIsBetter": true, "replicates": [503.7150000000038, 612.875, 521.2399999999907, 534.7600000000093, 522.554999999993, 580.3500000000058, 544.5800000000163, 978.2700000000186, 638.4049999999697, 518.164999999979, 526.7449999999953, 528.1599999999744, 550.4699999999721, 705.5549999999348, 518.2249999999767, 734.5800000000745, 525.3699999999953, 541.4650000000838, 505.11999999999534, 515.7299999999814, 518.2800000000279, 976.9349999999395, 641.4399999999441, 540.9350000000559, 506.6450000000186], "unit": "ms", "name": "complicated.memory.open.DAMP", "value": 537.8475000000326}, {"lowerIsBetter": true, "replicates": [2229.6500000000015, 1996.3650000000052, 1907.425000000003, 1909.335000000021, 2040.2299999999814, 1953.2399999999907, 1890.4099999999744, 1872.7050000000163, 2005.0050000000047, 1913.9500000000116, 1922.280000000028, 1927.7449999999953, 2093.9949999999953, 1935.375, 1941.545000000042, 2237.0, 1926.2850000000326, 1970.6900000000605, 2047.030000000028, 2502.2550000000047, 2029.545000000042, 1864.5699999999488, 2044.0050000000047, 1950.9949999999953, 2038.7600000000093], "unit": "ms", "name": "complicated.memory.reload.DAMP", "value": 1952.117499999993}, {"lowerIsBetter": true, "replicates": [1262.5149999999994, 1369.5299999999988, 1229.9349999999977, 1438.5449999999837, 1405.5750000000116, 1369.0950000000012, 1232.7999999999884, 1337.1050000000396, 1445.2999999999884, 1442.8800000000047, 1245.3300000000163, 1244.6350000000093, 1552.170000000042, 1579.1199999999953, 1449.1199999999953, 1392.2900000000373, 1259.1850000000559, 1386.3800000000047, 1396.2600000000093, 1328.9650000000838, 1431.4499999999534, 1287.140000000014, 1443.9949999999953, 1438.615000000107, 1435.7750000000233], "unit": "ms", "name": "complicated.saveHeapSnapshot", "value": 1394.2750000000233}, {"lowerIsBetter": true, "replicates": [265.33000000000175, 284.63000000000466, 206.125, 219.29000000000815, 277.75500000000466, 304.41500000000815, 203.1149999999907, 275.00500000000466, 282.2399999999907, 214.63000000000466, 209.57000000000698, 205.9099999999744, 297.64000000001397, 216.65500000002794, 209.65000000002328, 272.5100000000093, 208.9900000001071, 212.39500000001863, 252.3850000000093, 280.4300000000512, 279.7049999999581, 307.35000000009313, 285.7600000000093, 212.4550000000745, 287.5], "unit": "ms", "name": "complicated.readHeapSnapshot", "value": 262.4475000000093}, {"lowerIsBetter": true, "replicates": [120.55500000000029, 130.89499999998952, 127.21499999999651, 124.75, 138.06500000000233, 126.77999999999884, 125.5099999999511, 124.15499999996973, 128.4099999999744, 126.7149999999674, 130.25500000000466, 127.48999999999069, 135.56499999994412, 100.55000000004657, 129.23499999998603, 121.9649999999674, 126.15000000002328, 132.94999999995343, 127.76000000000931, 123.26000000000931, 127.9100000000326, 120.23000000009779, 148.08499999996275, 125.67500000004657, 128.09500000008848], "unit": "ms", "name": "complicated.takeCensus", "value": 127.3524999999936}, {"lowerIsBetter": true, "replicates": [239.52000000000407, 643.2850000000035, 302.67499999998836, 397.875, 282.59500000000116, 251.14999999999418, 231.40500000002794, 236.85999999998603, 628.75, 393.2300000000396, 325.1599999999744, 255.39499999996042, 133.47499999997672, 240.59499999997206, 410.0350000000326, 240.14000000001397, 230.84499999997206, 406.2049999999581, 272.6350000000093, 246.85999999998603, 279.0900000000838, 230.47999999998137, 637.7949999999255, 383.80999999993946, 280.4700000000885], "unit": "ms", "name": "complicated.memory.close.DAMP", "value": 279.78000000008615}, {"lowerIsBetter": true, "replicates": [101.30500000000029, 92.41500000000815, 88.93499999999767, 95.89000000001397, 116.14499999998952, 100.79999999998836, 94.22499999997672, 89.28999999997905, 88.38500000000931, 87.48499999998603, 90.25, 100.81500000000233, 152.09499999997206, 94.11999999999534, 91.5350000000326, 103.25, 95.38500000000931, 93.57999999995809, 98.98999999999069, 91.14500000001863, 93.59500000008848, 91.43000000005122, 92.72999999998137, 88.79500000004191, 105.69999999995343], "unit": "ms", "name": "console.bulklog", "value": 93.58750000002328}, {"lowerIsBetter": true, "replicates": [24.0, 24.0, 28.0, 27.0, 27.0, 25.0, 27.0, 24.0, 24.0, 29.0, 29.0, 26.0, 25.0, 23.0, 30.0, 25.0, 27.0, 29.0, 29.0, 27.0, 29.0, 24.0, 24.0, 30.0, 30.0], "unit": "ms", "name": "console.streamlog", "value": 27.0}], "name": "damp", "value": 231.2093681921865}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [60.36500000000524, 67.42499999999563, 64.19499999999971, 63.10000000000582, 63.44499999999971], "unit": "ms", "name": "amazon.com", "value": 63.81999999999971}, {"lowerIsBetter": true, "replicates": [57.080000000001746, 57.68000000000029, 57.419999999998254, 56.30999999999767, 57.28499999999622], "unit": "ms", "name": "cgi.ebay.com", "value": 57.352499999997235}, {"lowerIsBetter": true, "replicates": [61.48500000000058, 56.03500000000349, 55.9800000000032, 57.63500000000204, 57.30000000000291], "unit": "ms", "name": "163.com", "value": 56.6675000000032}, {"lowerIsBetter": true, "replicates": [49.94999999999709, 53.71499999999651, 70.33000000000175, 52.73500000000058, 56.56000000000495], "unit": "ms", "name": "mail.ru", "value": 55.13750000000073}, {"lowerIsBetter": true, "replicates": [41.7699999999968, 38.11499999999796, 40.650000000001455, 38.645000000004075, 37.05000000000291], "unit": "ms", "name": "bbc.co.uk", "value": 38.38000000000102}, {"lowerIsBetter": true, "replicates": [68.6649999999936, 66.96499999999651, 71.31999999999971, 70.80500000000029, 70.18500000000495], "unit": "ms", "name": "store.apple.com", "value": 70.49500000000262}, {"lowerIsBetter": true, "replicates": [45.849999999998545, 42.48500000000058, 43.28500000000349, 47.06500000000233, 47.61499999999796], "unit": "ms", "name": "imdb.com", "value": 45.17500000000291}, {"lowerIsBetter": true, "replicates": [44.770000000004075, 47.03499999999622, 42.74499999999534, 46.0199999999968, 44.345000000001164], "unit": "ms", "name": "cnn.com", "value": 45.18249999999898}, {"lowerIsBetter": true, "replicates": [54.99500000000262, 56.23500000000058, 53.395000000004075, 54.68999999999505, 56.05999999999767], "unit": "ms", "name": "sohu.com", "value": 55.37499999999636}, {"lowerIsBetter": true, "replicates": [45.6449999999968, 48.294999999998254, 46.45999999999913, 46.73500000000058, 46.52499999999418], "unit": "ms", "name": "youku.com", "value": 46.62999999999738}, {"lowerIsBetter": true, "replicates": [79.18000000000029, 74.43999999999505, 75.09500000000116, 72.97000000000116, 73.50499999999738], "unit": "ms", "name": "ifeng.com", "value": 73.97249999999622}, {"lowerIsBetter": true, "replicates": [56.06499999999505, 43.66500000000087, 46.21499999999651, 44.73500000000058, 54.00999999999476], "unit": "ms", "name": "tudou.com", "value": 45.474999999998545}, {"lowerIsBetter": true, "replicates": [41.424999999995634, 41.354999999995925, 37.424999999995634, 36.44999999999709, 39.98999999999796], "unit": "ms", "name": "chemistry.about.com", "value": 38.7074999999968}, {"lowerIsBetter": true, "replicates": [38.779999999998836, 38.93499999999767, 40.24499999999534, 33.30500000000029, 37.72500000000582], "unit": "ms", "name": "beatonna.livejournal.com", "value": 38.330000000001746}, {"lowerIsBetter": true, "replicates": [71.47000000000116, 62.7300000000032, 58.61499999999796, 58.775000000001455, 57.70999999999913], "unit": "ms", "name": "rakuten.co.jp", "value": 58.69499999999971}, {"lowerIsBetter": true, "replicates": [42.794999999998254, 52.345000000001164, 42.279999999998836, 40.36000000000058, 41.81500000000233], "unit": "ms", "name": "uol.com.br", "value": 42.04750000000058}, {"lowerIsBetter": true, "replicates": [37.93000000000029, 47.74499999999534, 44.900000000001455, 38.724999999998545, 42.16500000000087], "unit": "ms", "name": "thepiratebay.org", "value": 43.532500000001164}, {"lowerIsBetter": true, "replicates": [44.58999999999651, 40.705000000001746, 55.44000000000233, 48.80999999999767, 45.529999999998836], "unit": "ms", "name": "page.renren.com", "value": 47.169999999998254}, {"lowerIsBetter": true, "replicates": [49.19000000000233, 53.30999999999767, 52.629999999990105, 58.320000000006985, 56.4600000000064], "unit": "ms", "name": "chinaz.com", "value": 54.88500000000204}, {"lowerIsBetter": true, "replicates": [49.44499999999971, 48.345000000001164, 52.51999999998952, 49.0, 48.669999999998254], "unit": "ms", "name": "globo.com", "value": 48.83499999999913}, {"lowerIsBetter": true, "replicates": [36.81999999999971, 34.19000000000233, 38.81500000000233, 37.51499999999942, 39.895000000004075], "unit": "ms", "name": "spiegel.de", "value": 38.16500000000087}, {"lowerIsBetter": true, "replicates": [44.06500000000233, 42.88999999999942, 35.22500000000582, 36.43499999999767, 39.955000000001746], "unit": "ms", "name": "dailymotion.com", "value": 38.19499999999971}, {"lowerIsBetter": true, "replicates": [55.38000000000466, 50.98500000000058, 54.44499999999243, 54.654999999998836, 52.919999999998254], "unit": "ms", "name": "goo.ne.jp", "value": 53.68249999999534}, {"lowerIsBetter": true, "replicates": [42.65500000000611, 42.26000000000931, 45.879999999990105, 45.83999999999651, 45.245000000009895], "unit": "ms", "name": "stackoverflow.com", "value": 45.5425000000032}, {"lowerIsBetter": true, "replicates": [36.27499999999418, 39.07499999999709, 39.054999999993015, 37.34999999999127, 38.654999999998836], "unit": "ms", "name": "ezinearticles.com", "value": 38.854999999995925}, {"lowerIsBetter": true, "replicates": [46.320000000006985, 43.0, 42.35000000000582, 36.745000000009895, 42.38000000000466], "unit": "ms", "name": "huffingtonpost.com", "value": 42.36500000000524}, {"lowerIsBetter": true, "replicates": [47.854999999995925, 44.48500000000058, 39.38999999999942, 43.63499999999476, 45.895000000004075], "unit": "ms", "name": "media.photobucket.com", "value": 44.05999999999767}, {"lowerIsBetter": true, "replicates": [39.435000000012224, 37.520000000004075, 37.445000000006985, 39.79999999998836, 38.03500000000349], "unit": "ms", "name": "imgur.com", "value": 37.77750000000378}, {"lowerIsBetter": true, "replicates": [38.60000000000582, 42.11499999999069, 43.69499999999243, 39.580000000001746, 44.395000000004075], "unit": "ms", "name": "reddit.com", "value": 42.90499999999156}, {"lowerIsBetter": true, "replicates": [51.18499999999767, 54.330000000001746, 50.76000000000931, 44.80500000000757, 53.36000000000058], "unit": "ms", "name": "noimpactman.typepad.com", "value": 52.06000000000495}, {"lowerIsBetter": true, "replicates": [47.17500000000291, 52.83999999999651, 53.36000000000058, 51.05500000000757, 52.40000000000873], "unit": "ms", "name": "myspace.com", "value": 52.62000000000262}, {"lowerIsBetter": true, "replicates": [39.77499999999418, 39.23500000000058, 44.76999999998952, 41.779999999998836, 41.91500000000815], "unit": "ms", "name": "mashable.com", "value": 41.84750000000349}, {"lowerIsBetter": true, "replicates": [48.80500000000757, 44.75999999999476, 45.83999999999651, 45.875, 49.63999999999942], "unit": "ms", "name": "dailymail.co.uk", "value": 45.857499999998254}, {"lowerIsBetter": true, "replicates": [34.22499999999127, 35.83999999999651, 31.860000000000582, 42.044999999998254, 33.63999999999942], "unit": "ms", "name": "whois.domaintools.com", "value": 34.73999999999796}, {"lowerIsBetter": true, "replicates": [44.03500000000349, 48.69000000000233, 51.625, 49.18499999999767, 44.520000000004075], "unit": "ms", "name": "indiatimes.com", "value": 48.9375}, {"lowerIsBetter": true, "replicates": [45.88999999999942, 36.375, 37.370000000009895, 38.66000000000349, 38.00999999999476], "unit": "ms", "name": "reuters.com", "value": 37.69000000000233}, {"lowerIsBetter": true, "replicates": [69.36500000000524, 68.13500000000931, 74.60000000000582, 70.9149999999936, 69.77500000000873], "unit": "ms", "name": "xinhuanet.com", "value": 70.34500000000116}, {"lowerIsBetter": true, "replicates": [52.30000000000291, 55.5399999999936, 52.695000000006985, 54.21499999999651, 58.22500000000582], "unit": "ms", "name": "56.com", "value": 54.87749999999505}, {"lowerIsBetter": true, "replicates": [35.38499999999476, 34.5850000000064, 37.30000000000291, 44.36000000000058, 33.23500000000058], "unit": "ms", "name": "bild.de", "value": 35.94250000000466}, {"lowerIsBetter": true, "replicates": [51.34999999999127, 62.580000000001746, 58.570000000006985, 51.270000000004075, 63.96499999999651], "unit": "ms", "name": "guardian.co.uk", "value": 60.575000000004366}, {"lowerIsBetter": true, "replicates": [72.82000000000698, 67.38000000000466, 62.645000000004075, 66.41999999999825, 62.67500000000291], "unit": "ms", "name": "naver.com", "value": 64.54750000000058}, {"lowerIsBetter": true, "replicates": [51.10000000000582, 49.8350000000064, 49.40999999998894, 50.38999999999942, 49.97500000000582], "unit": "ms", "name": "yelp.com", "value": 49.90500000000611}, {"lowerIsBetter": true, "replicates": [52.520000000004075, 48.705000000001746, 48.56999999999243, 46.88999999999942, 47.51499999999942], "unit": "ms", "name": "wsj.com", "value": 48.042499999995925}, {"lowerIsBetter": true, "replicates": [35.39499999998952, 37.48000000001048, 41.345000000001164, 39.745000000009895, 44.13999999999942], "unit": "ms", "name": "google.com", "value": 40.54500000000553}, {"lowerIsBetter": true, "replicates": [71.63499999999476, 54.84999999999127, 50.27499999999418, 57.01499999999942, 70.54000000000815], "unit": "ms", "name": "xunlei.com", "value": 55.93249999999534}, {"lowerIsBetter": true, "replicates": [53.13000000000466, 44.11000000000058, 36.98999999999069, 48.88499999999476, 45.104999999995925], "unit": "ms", "name": "aljazeera.net", "value": 44.607499999998254}, {"lowerIsBetter": true, "replicates": [46.254999999990105, 43.44000000000233, 44.870000000009895, 46.504999999990105, 44.51999999998952], "unit": "ms", "name": "w3.org", "value": 44.69499999999971}, {"lowerIsBetter": true, "replicates": [58.44000000000233, 57.3350000000064, 65.02500000000873, 63.939999999987776, 59.06500000000233], "unit": "ms", "name": "homeway.com.cn", "value": 61.50249999999505}, {"lowerIsBetter": true, "replicates": [46.28500000000349, 38.42500000000291, 38.54000000000815, 46.40000000000873, 39.080000000001746], "unit": "ms", "name": "youtube.com", "value": 38.81000000000495}, {"lowerIsBetter": true, "replicates": [63.2899999999936, 65.77999999999884, 64.47999999999593, 61.53500000000349, 68.48999999999069], "unit": "ms", "name": "people.com.cn", "value": 65.12999999999738}], "name": "tps", "value": 48.22219299241428}]} 20:31:42 INFO - TinderboxPrint: TalosResult: {"graphserver": {"damp": {"url": "http://graphs.mozilla.org/graph.html#tests=[[327,64,35]]", "result": "231.21"}, "tps": {"url": "http://graphs.mozilla.org/graph.html#tests=[[329,64,35]]", "result": "48.22"}}} 20:31:42 INFO - Return code: 0 20:31:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 20:31:42 INFO - # TBPL SUCCESS # 20:31:42 INFO - Running post-action listener: _resource_record_post_action 20:31:42 INFO - Running post-run listener: _resource_record_post_run 20:31:43 INFO - Total resource usage - Wall time: 1502s; CPU: 15.0%; Read bytes: 4096; Write bytes: 975396864; Read time: 12; Write time: 927740 20:31:43 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 102150144; Read time: 0; Write time: 72692 20:31:43 INFO - run-tests - Wall time: 1486s; CPU: 15.0%; Read bytes: 4096; Write bytes: 873246720; Read time: 12; Write time: 855048 20:31:43 INFO - Running post-run listener: _upload_blobber_files 20:31:43 INFO - Blob upload gear active. 20:31:43 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 20:31:43 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:31:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 20:31:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Fx-Team -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 20:31:44 INFO - (blobuploader) - INFO - Open directory for files ... 20:31:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/g2_errorsummary.log ... 20:31:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:31:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:31:44 INFO - (blobuploader) - INFO - TinderboxPrint: g2_errorsummary.log: uploaded 20:31:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:31:44 INFO - (blobuploader) - INFO - Done attempting. 20:31:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/g2_raw.log ... 20:31:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:31:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:31:47 INFO - (blobuploader) - INFO - TinderboxPrint: g2_raw.log: uploaded 20:31:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:31:47 INFO - (blobuploader) - INFO - Done attempting. 20:31:47 INFO - (blobuploader) - INFO - Iteration through files over. 20:31:47 INFO - Return code: 0 20:31:47 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 20:31:47 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 20:31:47 INFO - Setting buildbot property blobber_files to {"g2_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b662ea193baff38851f64ca0fd249ed5c9811ae9d5290b9551c4b64b6ff427ae26285168ea3121b9a404163f2faf19a4a753e9a261462299e8b2703aefc3f22", "g2_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fffb6d7092735fb83f2395ea2e0bb56cfbaba38b4c8f6da73ea38a1696637795fa28aa649799cb88a21b0ec5e847c70b45a62f375ca6ffd25828a8e75fd079cb"} 20:31:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 20:31:47 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 20:31:47 INFO - Contents: 20:31:47 INFO - blobber_files:{"g2_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6b662ea193baff38851f64ca0fd249ed5c9811ae9d5290b9551c4b64b6ff427ae26285168ea3121b9a404163f2faf19a4a753e9a261462299e8b2703aefc3f22", "g2_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fffb6d7092735fb83f2395ea2e0bb56cfbaba38b4c8f6da73ea38a1696637795fa28aa649799cb88a21b0ec5e847c70b45a62f375ca6ffd25828a8e75fd079cb"} 20:31:47 INFO - Running post-run listener: copy_logs_to_upload_dir 20:31:47 INFO - Copying logs to upload dir... 20:31:47 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 20:31:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1546.726475 ========= master_lag: 0.58 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 25 mins, 47 secs) (at 2016-01-27 20:31:47.534074) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-27 20:31:47.541625) ========= 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=00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049 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/6b662ea193baff38851f64ca0fd249ed5c9811ae9d5290b9551c4b64b6ff427ae26285168ea3121b9a404163f2faf19a4a753e9a261462299e8b2703aefc3f22", "g2_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fffb6d7092735fb83f2395ea2e0bb56cfbaba38b4c8f6da73ea38a1696637795fa28aa649799cb88a21b0ec5e847c70b45a62f375ca6ffd25828a8e75fd079cb"} build_url:https://queue.taskcluster.net/v1/task/WvydVMQlSHypr21YMD-mKg/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011367 build_url: 'https://queue.taskcluster.net/v1/task/WvydVMQlSHypr21YMD-mKg/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/6b662ea193baff38851f64ca0fd249ed5c9811ae9d5290b9551c4b64b6ff427ae26285168ea3121b9a404163f2faf19a4a753e9a261462299e8b2703aefc3f22", "g2_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fffb6d7092735fb83f2395ea2e0bb56cfbaba38b4c8f6da73ea38a1696637795fa28aa649799cb88a21b0ec5e847c70b45a62f375ca6ffd25828a8e75fd079cb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-27 20:31:47.575732) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 20:31:47.576265) ========= 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=00204705a9daa57ed339a427000001b9-1453953618.669482-1138568049 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004553 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-27 20:31:47.654303) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-27 20:31:47.654812) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-27 20:31:47.655253) ========= ========= Total master_lag: 0.85 =========