builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1457073799.1 results: failure (2) buildid: 20160303203202 builduid: 7bdf2bea87c44acd9cbec1a57ca1af07 revision: 579da5ef8ccf4417fb472947e4cd5665f7f90a46 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.105357) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.105944) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.106331) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006354 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.135562) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.135888) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.152459) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.152753) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004651 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.203648) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.203955) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.204356) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.204858) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-03-03 22:43:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 220M=0s 2016-03-03 22:43:19 (220 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.148733 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.373712) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.374485) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018669 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.432195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:19.432559) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 579da5ef8ccf4417fb472947e4cd5665f7f90a46 --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 579da5ef8ccf4417fb472947e4cd5665f7f90a46 --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=edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-03-03 22:43:19,606 truncating revision to first 12 chars 2016-03-03 22:43:19,606 Setting DEBUG logging. 2016-03-03 22:43:19,606 attempt 1/10 2016-03-03 22:43:19,606 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/579da5ef8ccf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-03 22:43:19,874 unpacking tar archive at: fx-team-579da5ef8ccf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.568575 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:20.014453) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:20.014829) ========= echo 579da5ef8ccf4417fb472947e4cd5665f7f90a46 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'579da5ef8ccf4417fb472947e4cd5665f7f90a46'] 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=edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 579da5ef8ccf4417fb472947e4cd5665f7f90a46 program finished with exit code 0 elapsedTime=0.004709 script_repo_revision: '579da5ef8ccf4417fb472947e4cd5665f7f90a46' ========= master_lag: 0.08 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:20.104067) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:20.104380) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-03 22:43:20.114832) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 23 mins, 33 secs) (at 2016-03-03 22:43:20.115395) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', 'dromaeojs', '--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=edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:43:20 INFO - MultiFileLogger online at 20160303 22:43:20 in /builds/slave/test-pgo 22:43:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:43:20 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 22:43:20 INFO - {'append_to_log': False, 22:43:20 INFO - 'base_work_dir': '/builds/slave/test-pgo', 22:43:20 INFO - 'blob_upload_branch': 'Fx-Team', 22:43:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 22:43:20 INFO - 'branch': 'Fx-Team', 22:43:20 INFO - 'buildbot_json_path': 'buildprops.json', 22:43:20 INFO - 'config_files': ('talos/linux_config.py',), 22:43:20 INFO - 'default_actions': ('clobber', 22:43:20 INFO - 'read-buildbot-config', 22:43:20 INFO - 'download-and-extract', 22:43:20 INFO - 'populate-webroot', 22:43:20 INFO - 'create-virtualenv', 22:43:20 INFO - 'install', 22:43:20 INFO - 'run-tests'), 22:43:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:43:20 INFO - 'download_minidump_stackwalk': True, 22:43:20 INFO - 'download_symbols': 'ondemand', 22:43:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:43:20 INFO - 'tooltool.py': '/tools/tooltool.py', 22:43:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:43:20 INFO - '/tools/misc-python/virtualenv.py')}, 22:43:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:43:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:43:20 INFO - 'installer_path': 'installer.exe', 22:43:20 INFO - 'log_level': 'info', 22:43:20 INFO - 'log_name': 'talos', 22:43:20 INFO - 'log_to_console': True, 22:43:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:43:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:43:20 INFO - 'opt_config_files': (), 22:43:20 INFO - 'pip_index': False, 22:43:20 INFO - 'sps_profile': False, 22:43:20 INFO - 'sps_profile_interval': 0, 22:43:20 INFO - 'suite': 'dromaeojs', 22:43:20 INFO - 'system_bits': '32', 22:43:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:43:20 INFO - 'title': 'talos-linux64-ix-033', 22:43:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:43:20 INFO - 'use_talos_json': True, 22:43:20 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 22:43:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:43:20 INFO - 'work_dir': 'build'} 22:43:20 INFO - ##### 22:43:20 INFO - ##### Running clobber step. 22:43:20 INFO - ##### 22:43:20 INFO - Running pre-action listener: _resource_record_pre_action 22:43:20 INFO - Running main action method: clobber 22:43:20 INFO - rmtree: /builds/slave/test-pgo/build 22:43:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 22:43:21 INFO - Running post-action listener: _resource_record_post_action 22:43:21 INFO - ##### 22:43:21 INFO - ##### Running read-buildbot-config step. 22:43:21 INFO - ##### 22:43:21 INFO - Running pre-action listener: _resource_record_pre_action 22:43:21 INFO - Running main action method: read_buildbot_config 22:43:21 INFO - Using buildbot properties: 22:43:21 INFO - { 22:43:21 INFO - "project": "", 22:43:21 INFO - "product": "firefox", 22:43:21 INFO - "script_repo_revision": "production", 22:43:21 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:43:21 INFO - "repository": "", 22:43:21 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 22:43:21 INFO - "buildid": "20160303203202", 22:43:21 INFO - "pgo_build": "True", 22:43:21 INFO - "basedir": "/builds/slave/test-pgo", 22:43:21 INFO - "buildnumber": 973, 22:43:21 INFO - "slavename": "talos-linux64-ix-033", 22:43:21 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:43:21 INFO - "platform": "ubuntu64_hw", 22:43:21 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 22:43:21 INFO - "branch": "fx-team", 22:43:21 INFO - "repo_path": "integration/fx-team", 22:43:21 INFO - "revision": "579da5ef8ccf4417fb472947e4cd5665f7f90a46", 22:43:21 INFO - "stage_platform": "linux64-pgo", 22:43:21 INFO - "builduid": "7bdf2bea87c44acd9cbec1a57ca1af07", 22:43:21 INFO - "slavebuilddir": "test" 22:43:21 INFO - } 22:43:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2. 22:43:21 INFO - Running post-action listener: _resource_record_post_action 22:43:21 INFO - ##### 22:43:21 INFO - ##### Running download-and-extract step. 22:43:21 INFO - ##### 22:43:21 INFO - Running pre-action listener: _resource_record_pre_action 22:43:21 INFO - Running main action method: download_and_extract 22:43:21 INFO - mkdir: /builds/slave/test-pgo/build/tests 22:43:21 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')]} 22:43:21 INFO - https://queue.taskcluster.net/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:43:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json 22:43:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json 22:43:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json 22:43:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:43:30 INFO - Downloaded 1448 bytes. 22:43:30 INFO - Reading from file /builds/slave/test-pgo/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json 22:43:30 INFO - Using the following test package requirements: 22:43:30 INFO - {u'common': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 22:43:30 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:30 INFO - u'firefox-47.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:43:30 INFO - u'gtest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:30 INFO - u'firefox-47.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:43:30 INFO - u'jittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:30 INFO - u'jsshell-linux-x86_64.zip'], 22:43:30 INFO - u'mochitest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:30 INFO - u'firefox-47.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:43:30 INFO - u'mozbase': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 22:43:30 INFO - u'reftest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:30 INFO - u'firefox-47.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:43:30 INFO - u'talos': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:30 INFO - u'firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:43:30 INFO - u'web-platform': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:30 INFO - u'firefox-47.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:43:30 INFO - u'webapprt': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 22:43:30 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 22:43:30 INFO - u'firefox-47.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:43:30 INFO - Downloading packages: [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:43: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')]} 22:43:30 INFO - https://queue.taskcluster.net/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:43:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 22:43:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 22:43:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/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 22:43:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/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 22:43:40 INFO - Downloaded 23394384 bytes. 22:43:40 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'] 22:43:40 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 22:43:41 INFO - Return code: 0 22:43:41 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 22:43:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:41 INFO - https://queue.taskcluster.net/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:43:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 22:43:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 22:43:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/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 22:43:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/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 22:43:42 INFO - Downloaded 23394384 bytes. 22:43:42 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'] 22:43:42 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 22:43:47 INFO - Return code: 0 22:43:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:47 INFO - https://queue.taskcluster.net/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:43:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 22:43:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 22:43:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/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 22:43:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/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 22:43:48 INFO - Downloaded 10808282 bytes. 22:43:48 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'] 22:43:48 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 22:43:48 INFO - Return code: 0 22:43:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:48 INFO - https://queue.taskcluster.net/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:43:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 22:43:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 22:43:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/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 22:43:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/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 22:43:50 INFO - Downloaded 56122320 bytes. 22:43:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 22:43:50 INFO - mkdir: /builds/slave/test-pgo/properties 22:43:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 22:43:50 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 22:43:50 INFO - Contents: 22:43:50 INFO - build_url:https://queue.taskcluster.net/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 22:43:51 INFO - Running post-action listener: _resource_record_post_action 22:43:51 INFO - Running post-action listener: set_extra_try_arguments 22:43:51 INFO - ##### 22:43:51 INFO - ##### Running populate-webroot step. 22:43:51 INFO - ##### 22:43:51 INFO - Running pre-action listener: _resource_record_pre_action 22:43:51 INFO - Running main action method: populate_webroot 22:43:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:43:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:43:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:43:51 INFO - '--tppagecycles', 22:43:51 INFO - '7'], 22:43:51 INFO - 'tests': ['tsvgm']}}, 22:43:51 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:43:51 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:43:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:43:51 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 22:43:51 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:43:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:43:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:43:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:51 INFO - 'talos_options': ['--e10s'], 22:43:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:43:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:51 INFO - 'tests': ['damp', 'tps']}, 22:43:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:51 INFO - 'talos_options': ['--e10s'], 22:43:51 INFO - 'tests': ['damp', 'tps']}, 22:43:51 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:43:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:43:51 INFO - 'other': {'tests': ['a11yr', 22:43:51 INFO - 'ts_paint', 22:43:51 INFO - 'tpaint', 22:43:51 INFO - 'sessionrestore', 22:43:51 INFO - 'sessionrestore_no_auto_restore']}, 22:43:51 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:43:51 INFO - 'tests': ['a11yr', 22:43:51 INFO - 'ts_paint', 22:43:51 INFO - 'tpaint', 22:43:51 INFO - 'sessionrestore', 22:43:51 INFO - 'sessionrestore_no_auto_restore']}, 22:43:51 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:43:51 INFO - 'tests': ['a11yr', 22:43:51 INFO - 'ts_paint', 22:43:51 INFO - 'tpaint', 22:43:51 INFO - 'sessionrestore', 22:43:51 INFO - 'sessionrestore_no_auto_restore']}, 22:43:51 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:43:51 INFO - 'tests': ['a11yr', 22:43:51 INFO - 'ts_paint', 22:43:51 INFO - 'tpaint', 22:43:51 INFO - 'sessionrestore', 22:43:51 INFO - 'sessionrestore_no_auto_restore']}, 22:43:51 INFO - 'other_l64': {'tests': ['a11yr', 22:43:51 INFO - 'ts_paint', 22:43:51 INFO - 'tpaint', 22:43:51 INFO - 'sessionrestore', 22:43:51 INFO - 'sessionrestore_no_auto_restore']}, 22:43:51 INFO - 'other_nol64': {'tests': ['a11yr', 22:43:51 INFO - 'ts_paint', 22:43:51 INFO - 'tpaint', 22:43:51 INFO - 'sessionrestore', 22:43:51 INFO - 'sessionrestore_no_auto_restore']}, 22:43:51 INFO - 'svgr': {'tests': ['tsvgx', 22:43:51 INFO - 'tsvgr_opacity', 22:43:51 INFO - 'tart', 22:43:51 INFO - 'tscrollx', 22:43:51 INFO - 'cart']}, 22:43:51 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:43:51 INFO - 'tests': ['tsvgx', 22:43:51 INFO - 'tsvgr_opacity', 22:43:51 INFO - 'tart', 22:43:51 INFO - 'tscrollx', 22:43:51 INFO - 'cart']}, 22:43:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:51 INFO - 'tests': ['tp5o']}, 22:43:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:51 INFO - 'talos_options': ['--e10s'], 22:43:51 INFO - 'tests': ['tp5o']}, 22:43:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:51 INFO - 'talos_options': ['--xperf_path', 22:43:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:43:51 INFO - 'tests': ['tp5n']}, 22:43:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:43:51 INFO - 'talos_options': ['--e10s', 22:43:51 INFO - '--xperf_path', 22:43:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:43:51 INFO - 'tests': ['tp5n']}}, 22:43:51 INFO - 'talos.zip': {'path': '', 22:43:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:43:51 INFO - Running post-action listener: _resource_record_post_action 22:43:51 INFO - ##### 22:43:51 INFO - ##### Running create-virtualenv step. 22:43:51 INFO - ##### 22:43:51 INFO - Running pre-action listener: _resource_record_pre_action 22:43:51 INFO - Running main action method: create_virtualenv 22:43:51 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 22:43:51 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 22:43:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 22:43:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:43:51 INFO - Using real prefix '/usr' 22:43:51 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 22:43:51 INFO - Installing distribute.............................................................................................................................................................................................done. 22:43:53 INFO - Installing pip.................done. 22:43:53 INFO - Return code: 0 22:43:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 22:43:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7efb1ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf046d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf93920>, '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 0xf94820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf94cb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', '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 22:43:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 22:43:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:43:53 INFO - Using env: {'DISPLAY': ':0', 22:43:53 INFO - 'HOME': '/home/cltbld', 22:43:53 INFO - 'LANG': 'en_US.UTF-8', 22:43:53 INFO - 'LANGUAGE': 'en_US:en', 22:43:53 INFO - 'LOGNAME': 'cltbld', 22:43:53 INFO - 'MAIL': '/var/mail/cltbld', 22:43:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:53 INFO - 'MOZ_NO_REMOTE': '1', 22:43:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:43:53 INFO - 'NO_EM_RESTART': '1', 22:43:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:43:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:43:53 INFO - 'PWD': '/builds/slave/test-pgo', 22:43:53 INFO - 'SHELL': '/bin/bash', 22:43:53 INFO - 'SHLVL': '1', 22:43:53 INFO - 'TERM': 'linux', 22:43:53 INFO - 'TMOUT': '86400', 22:43:53 INFO - 'USER': 'cltbld', 22:43:53 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', 22:43:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:53 INFO - '_': '/tools/buildbot/bin/python'} 22:43:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:43:53 INFO - Cleaning up... 22:43:54 INFO - Return code: 0 22:43:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 22:43:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:54 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 0x7fd7efb1ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf046d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf93920>, '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 0xf94820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf94cb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', '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 22:43:54 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 22:43:54 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 22:43:54 INFO - Using env: {'DISPLAY': ':0', 22:43:54 INFO - 'HOME': '/home/cltbld', 22:43:54 INFO - 'LANG': 'en_US.UTF-8', 22:43:54 INFO - 'LANGUAGE': 'en_US:en', 22:43:54 INFO - 'LOGNAME': 'cltbld', 22:43:54 INFO - 'MAIL': '/var/mail/cltbld', 22:43:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:54 INFO - 'MOZ_NO_REMOTE': '1', 22:43:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:43:54 INFO - 'NO_EM_RESTART': '1', 22:43:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:43:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:43:54 INFO - 'PWD': '/builds/slave/test-pgo', 22:43:54 INFO - 'SHELL': '/bin/bash', 22:43:54 INFO - 'SHLVL': '1', 22:43:54 INFO - 'TERM': 'linux', 22:43:54 INFO - 'TMOUT': '86400', 22:43:54 INFO - 'USER': 'cltbld', 22:43:54 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', 22:43:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:54 INFO - '_': '/tools/buildbot/bin/python'} 22:43:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:54 INFO - Downloading/unpacking psutil>=0.7.1 22:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:43:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:43:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:43:56 INFO - Installing collected packages: psutil 22:43:56 INFO - Running setup.py install for psutil 22:43:56 INFO - building 'psutil._psutil_linux' extension 22:43:56 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 22:43:56 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 22:43:56 INFO - building 'psutil._psutil_posix' extension 22:43:56 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 22:43:56 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 22:43:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:43:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:43:56 INFO - Successfully installed psutil 22:43:56 INFO - Cleaning up... 22:43:56 INFO - Return code: 0 22:43:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:43: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')]} 22:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43: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')]} 22:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7efb1ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf046d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf93920>, '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 0xf94820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf94cb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', '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 22:43:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 22:43:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:43:56 INFO - Using env: {'DISPLAY': ':0', 22:43:56 INFO - 'HOME': '/home/cltbld', 22:43:56 INFO - 'LANG': 'en_US.UTF-8', 22:43:56 INFO - 'LANGUAGE': 'en_US:en', 22:43:56 INFO - 'LOGNAME': 'cltbld', 22:43:56 INFO - 'MAIL': '/var/mail/cltbld', 22:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:56 INFO - 'MOZ_NO_REMOTE': '1', 22:43:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:43:56 INFO - 'NO_EM_RESTART': '1', 22:43:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:43:56 INFO - 'PWD': '/builds/slave/test-pgo', 22:43:56 INFO - 'SHELL': '/bin/bash', 22:43:56 INFO - 'SHLVL': '1', 22:43:56 INFO - 'TERM': 'linux', 22:43:56 INFO - 'TMOUT': '86400', 22:43:56 INFO - 'USER': 'cltbld', 22:43:56 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', 22:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:56 INFO - '_': '/tools/buildbot/bin/python'} 22:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:43:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:43:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:43:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:43:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:43:58 INFO - Installing collected packages: mozsystemmonitor 22:43:58 INFO - Running setup.py install for mozsystemmonitor 22:43:58 INFO - Successfully installed mozsystemmonitor 22:43:58 INFO - Cleaning up... 22:43:58 INFO - Return code: 0 22:43:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:43:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:43:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:43:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:43:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7efb1ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf046d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf93920>, '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 0xf94820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf94cb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', '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 22:43:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 22:43:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:43:58 INFO - Using env: {'DISPLAY': ':0', 22:43:58 INFO - 'HOME': '/home/cltbld', 22:43:58 INFO - 'LANG': 'en_US.UTF-8', 22:43:58 INFO - 'LANGUAGE': 'en_US:en', 22:43:58 INFO - 'LOGNAME': 'cltbld', 22:43:58 INFO - 'MAIL': '/var/mail/cltbld', 22:43:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:43:58 INFO - 'MOZ_NO_REMOTE': '1', 22:43:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:43:58 INFO - 'NO_EM_RESTART': '1', 22:43:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:43:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:43:58 INFO - 'PWD': '/builds/slave/test-pgo', 22:43:58 INFO - 'SHELL': '/bin/bash', 22:43:58 INFO - 'SHLVL': '1', 22:43:58 INFO - 'TERM': 'linux', 22:43:58 INFO - 'TMOUT': '86400', 22:43:58 INFO - 'USER': 'cltbld', 22:43:58 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', 22:43:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:43:58 INFO - '_': '/tools/buildbot/bin/python'} 22:43:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:43:59 INFO - Downloading/unpacking blobuploader==1.2.4 22:43:59 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 22:43:59 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 22:43:59 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 22:43:59 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 22:43:59 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 22:43:59 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 22:44:00 INFO - Downloading blobuploader-1.2.4.tar.gz 22:44:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:44:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:44:01 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 22:44:01 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 22:44:01 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 22:44:01 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 22:44:01 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 22:44:01 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 22:44:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 22:44:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:44:01 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 22:44:01 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 22:44:01 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 22:44:01 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 22:44:01 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 22:44:01 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 22:44:01 INFO - Downloading docopt-0.6.1.tar.gz 22:44:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 22:44:01 INFO - Installing collected packages: blobuploader, requests, docopt 22:44:01 INFO - Running setup.py install for blobuploader 22:44:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:44:01 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 22:44:01 INFO - Running setup.py install for requests 22:44:02 INFO - Running setup.py install for docopt 22:44:02 INFO - Successfully installed blobuploader requests docopt 22:44:02 INFO - Cleaning up... 22:44:02 INFO - Return code: 0 22:44:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:44: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')]} 22:44:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44: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')]} 22:44:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:02 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 0x7fd7efb1ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf046d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf93920>, '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 0xf94820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf94cb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', '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 22:44:02 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 22:44:02 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 22:44:02 INFO - Using env: {'DISPLAY': ':0', 22:44:02 INFO - 'HOME': '/home/cltbld', 22:44:02 INFO - 'LANG': 'en_US.UTF-8', 22:44:02 INFO - 'LANGUAGE': 'en_US:en', 22:44:02 INFO - 'LOGNAME': 'cltbld', 22:44:02 INFO - 'MAIL': '/var/mail/cltbld', 22:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:02 INFO - 'MOZ_NO_REMOTE': '1', 22:44:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:02 INFO - 'NO_EM_RESTART': '1', 22:44:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:44:02 INFO - 'PWD': '/builds/slave/test-pgo', 22:44:02 INFO - 'SHELL': '/bin/bash', 22:44:02 INFO - 'SHLVL': '1', 22:44:02 INFO - 'TERM': 'linux', 22:44:02 INFO - 'TMOUT': '86400', 22:44:02 INFO - 'USER': 'cltbld', 22:44:02 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', 22:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:02 INFO - '_': '/tools/buildbot/bin/python'} 22:44:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:44:02 INFO - Running setup.py (path:/tmp/pip-gWnN_a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:44:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:44:02 INFO - Running setup.py (path:/tmp/pip-WUm_ps-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:44:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:44:02 INFO - Running setup.py (path:/tmp/pip-gWV5Xo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:44:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:44:02 INFO - Running setup.py (path:/tmp/pip-ZYTGJ9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:44:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:44:02 INFO - Running setup.py (path:/tmp/pip-2TRJX8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:44:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:44:02 INFO - Running setup.py (path:/tmp/pip-pj18ni-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:44:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:44:02 INFO - Running setup.py (path:/tmp/pip-jeCSW2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:44:03 INFO - Running setup.py (path:/tmp/pip-GCgrSb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:44:03 INFO - Running setup.py (path:/tmp/pip-ngOntd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:44:03 INFO - Running setup.py (path:/tmp/pip-i5H6l7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:44:03 INFO - Running setup.py (path:/tmp/pip-euSUXq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:44:03 INFO - Running setup.py (path:/tmp/pip-F3Vcs4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:44:03 INFO - Running setup.py (path:/tmp/pip-NjIPXU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:44:03 INFO - Running setup.py (path:/tmp/pip-FHw2fJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:44:03 INFO - Running setup.py (path:/tmp/pip-a6TOAB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:44:03 INFO - Running setup.py (path:/tmp/pip-fefuec-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:44:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:44:03 INFO - Running setup.py (path:/tmp/pip-VFNhCM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:44:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:44:03 INFO - Running setup.py install for manifestparser 22:44:03 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 22:44:03 INFO - Running setup.py install for mozcrash 22:44:03 INFO - Running setup.py install for mozdebug 22:44:04 INFO - Running setup.py install for mozdevice 22:44:04 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 22:44:04 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 22:44:04 INFO - Running setup.py install for mozfile 22:44:04 INFO - Running setup.py install for mozhttpd 22:44:04 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 22:44:04 INFO - Running setup.py install for mozinfo 22:44:04 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 22:44:04 INFO - Running setup.py install for mozInstall 22:44:04 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 22:44:04 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 22:44:04 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 22:44:04 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 22:44:04 INFO - Running setup.py install for mozleak 22:44:04 INFO - Running setup.py install for mozlog 22:44:04 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 22:44:04 INFO - Running setup.py install for moznetwork 22:44:04 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 22:44:04 INFO - Running setup.py install for mozprocess 22:44:04 INFO - Running setup.py install for mozprofile 22:44:05 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 22:44:05 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 22:44:05 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 22:44:05 INFO - Running setup.py install for mozrunner 22:44:05 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 22:44:05 INFO - Running setup.py install for mozscreenshot 22:44:05 INFO - Running setup.py install for moztest 22:44:05 INFO - Running setup.py install for mozversion 22:44:05 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 22:44:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:44:05 INFO - Cleaning up... 22:44:05 INFO - Return code: 0 22:44:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7efb1ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf046d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf93920>, '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 0xf94820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf94cb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', '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 22:44:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 22:44:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:44:05 INFO - Using env: {'DISPLAY': ':0', 22:44:05 INFO - 'HOME': '/home/cltbld', 22:44:05 INFO - 'LANG': 'en_US.UTF-8', 22:44:05 INFO - 'LANGUAGE': 'en_US:en', 22:44:05 INFO - 'LOGNAME': 'cltbld', 22:44:05 INFO - 'MAIL': '/var/mail/cltbld', 22:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:05 INFO - 'MOZ_NO_REMOTE': '1', 22:44:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:05 INFO - 'NO_EM_RESTART': '1', 22:44:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:44:05 INFO - 'PWD': '/builds/slave/test-pgo', 22:44:05 INFO - 'SHELL': '/bin/bash', 22:44:05 INFO - 'SHLVL': '1', 22:44:05 INFO - 'TERM': 'linux', 22:44:05 INFO - 'TMOUT': '86400', 22:44:05 INFO - 'USER': 'cltbld', 22:44:05 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', 22:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:05 INFO - '_': '/tools/buildbot/bin/python'} 22:44:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:44:05 INFO - Running setup.py (path:/tmp/pip-N_flFA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 22:44:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:44:05 INFO - Running setup.py (path:/tmp/pip-89bsQD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 22:44:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:44:05 INFO - Running setup.py (path:/tmp/pip-8fBMwf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:44:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 22:44:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:44:05 INFO - Running setup.py (path:/tmp/pip-r915su-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:44:06 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)) 22:44:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:44:06 INFO - Running setup.py (path:/tmp/pip-Lg_Lcz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:44:06 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)) 22:44:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:44:06 INFO - Running setup.py (path:/tmp/pip-MFHt7l-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:44:06 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)) 22:44:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:44:06 INFO - Running setup.py (path:/tmp/pip-ZIYO4z-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:44:06 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)) 22:44:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:44:06 INFO - Running setup.py (path:/tmp/pip-Qbu28b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:44:06 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)) 22:44:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:44:06 INFO - Running setup.py (path:/tmp/pip-cov9n2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:44:06 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)) 22:44:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:44:06 INFO - Running setup.py (path:/tmp/pip-o2lIpX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:44:06 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)) 22:44:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:44:06 INFO - Running setup.py (path:/tmp/pip-kiXlCD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:44:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 22:44:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:44:06 INFO - Running setup.py (path:/tmp/pip-k58LdR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:44:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 22:44:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:44:06 INFO - Running setup.py (path:/tmp/pip-Q2j2LX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:44:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 22:44:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:44:06 INFO - Running setup.py (path:/tmp/pip-0w76x_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:44:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 22:44:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:44:06 INFO - Running setup.py (path:/tmp/pip-a3eEdC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:44:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 22:44:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:44:06 INFO - Running setup.py (path:/tmp/pip-DT1cjh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:44:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 22:44:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:44:06 INFO - Running setup.py (path:/tmp/pip-8ktE_R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:44:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 22:44:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 22:44:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 22:44:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 22:44:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 22:44:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 22:44:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:08 INFO - Downloading blessings-1.6.tar.gz 22:44:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 22:44:08 INFO - Installing collected packages: blessings 22:44:08 INFO - Running setup.py install for blessings 22:44:08 INFO - Successfully installed blessings 22:44:08 INFO - Cleaning up... 22:44:09 INFO - Return code: 0 22:44:09 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 22:44:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:44:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:44:09 INFO - Reading from file tmpfile_stdout 22:44:09 INFO - Current package versions: 22:44:09 INFO - argparse == 1.2.1 22:44:09 INFO - blessings == 1.6 22:44:09 INFO - blobuploader == 1.2.4 22:44:09 INFO - docopt == 0.6.1 22:44:09 INFO - manifestparser == 1.1 22:44:09 INFO - mozInstall == 1.12 22:44:09 INFO - mozcrash == 0.16 22:44:09 INFO - mozdebug == 0.1 22:44:09 INFO - mozdevice == 0.48 22:44:09 INFO - mozfile == 1.2 22:44:09 INFO - mozhttpd == 0.7 22:44:09 INFO - mozinfo == 0.9 22:44:09 INFO - mozleak == 0.1 22:44:09 INFO - mozlog == 3.1 22:44:09 INFO - moznetwork == 0.27 22:44:09 INFO - mozprocess == 0.22 22:44:09 INFO - mozprofile == 0.28 22:44:09 INFO - mozrunner == 6.11 22:44:09 INFO - mozscreenshot == 0.1 22:44:09 INFO - mozsystemmonitor == 0.0 22:44:09 INFO - moztest == 0.7 22:44:09 INFO - mozversion == 1.4 22:44:09 INFO - psutil == 3.1.1 22:44:09 INFO - requests == 1.2.3 22:44:09 INFO - wsgiref == 0.1.2 22:44:09 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:44: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')]} 22:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44: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')]} 22:44:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:09 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 0x7fd7efb1ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf046d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf93920>, '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 0xf94820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf94cb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', '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 22:44:09 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 22:44:09 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 22:44:09 INFO - Using env: {'DISPLAY': ':0', 22:44:09 INFO - 'HOME': '/home/cltbld', 22:44:09 INFO - 'LANG': 'en_US.UTF-8', 22:44:09 INFO - 'LANGUAGE': 'en_US:en', 22:44:09 INFO - 'LOGNAME': 'cltbld', 22:44:09 INFO - 'MAIL': '/var/mail/cltbld', 22:44:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:09 INFO - 'MOZ_NO_REMOTE': '1', 22:44:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:09 INFO - 'NO_EM_RESTART': '1', 22:44:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:44:09 INFO - 'PWD': '/builds/slave/test-pgo', 22:44:09 INFO - 'SHELL': '/bin/bash', 22:44:09 INFO - 'SHLVL': '1', 22:44:09 INFO - 'TERM': 'linux', 22:44:09 INFO - 'TMOUT': '86400', 22:44:09 INFO - 'USER': 'cltbld', 22:44:09 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', 22:44:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:09 INFO - '_': '/tools/buildbot/bin/python'} 22:44:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 22:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 22:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 22:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 22:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 22:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 22:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 22:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 22:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 9)) 22:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 22:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 22:44:09 INFO - Cleaning up... 22:44:09 INFO - Return code: 0 22:44:09 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 22:44: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')]} 22:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44: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')]} 22:44:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd7efb1ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf046d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf93920>, '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 0xf94820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf94cb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', '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 22:44:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 22:44:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:44:09 INFO - Using env: {'DISPLAY': ':0', 22:44:09 INFO - 'HOME': '/home/cltbld', 22:44:09 INFO - 'LANG': 'en_US.UTF-8', 22:44:09 INFO - 'LANGUAGE': 'en_US:en', 22:44:09 INFO - 'LOGNAME': 'cltbld', 22:44:09 INFO - 'MAIL': '/var/mail/cltbld', 22:44:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:09 INFO - 'MOZ_NO_REMOTE': '1', 22:44:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:09 INFO - 'NO_EM_RESTART': '1', 22:44:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:44:09 INFO - 'PWD': '/builds/slave/test-pgo', 22:44:09 INFO - 'SHELL': '/bin/bash', 22:44:09 INFO - 'SHLVL': '1', 22:44:09 INFO - 'TERM': 'linux', 22:44:09 INFO - 'TMOUT': '86400', 22:44:09 INFO - 'USER': 'cltbld', 22:44:09 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', 22:44:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:09 INFO - '_': '/tools/buildbot/bin/python'} 22:44:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:09 INFO - Downloading/unpacking jsonschema 22:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:44:11 INFO - Downloading/unpacking functools32 (from jsonschema) 22:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:11 INFO - Downloading functools32-3.2.3-2.tar.gz 22:44:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 22:44:11 INFO - warning: no files found matching '*.txt' 22:44:11 INFO - no previously-included directories found matching 'build' 22:44:11 INFO - no previously-included directories found matching 'dist' 22:44:11 INFO - no previously-included directories found matching '.git*' 22:44:11 INFO - Installing collected packages: jsonschema, functools32 22:44:11 INFO - Running setup.py install for functools32 22:44:11 INFO - warning: no files found matching '*.txt' 22:44:11 INFO - no previously-included directories found matching 'build' 22:44:11 INFO - no previously-included directories found matching 'dist' 22:44:11 INFO - no previously-included directories found matching '.git*' 22:44:12 INFO - Successfully installed jsonschema functools32 22:44:12 INFO - Cleaning up... 22:44:12 INFO - Return code: 0 22:44:12 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 22:44:12 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')]} 22:44:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:44:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:12 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')]} 22:44:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:44:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:44:12 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 0x7fd7efb1ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf046d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf93920>, '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 0xf94820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf94cb0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', '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 22:44:12 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 22:44:12 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 22:44:12 INFO - Using env: {'DISPLAY': ':0', 22:44:12 INFO - 'HOME': '/home/cltbld', 22:44:12 INFO - 'LANG': 'en_US.UTF-8', 22:44:12 INFO - 'LANGUAGE': 'en_US:en', 22:44:12 INFO - 'LOGNAME': 'cltbld', 22:44:12 INFO - 'MAIL': '/var/mail/cltbld', 22:44:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:12 INFO - 'MOZ_NO_REMOTE': '1', 22:44:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:12 INFO - 'NO_EM_RESTART': '1', 22:44:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:44:12 INFO - 'PWD': '/builds/slave/test-pgo', 22:44:12 INFO - 'SHELL': '/bin/bash', 22:44:12 INFO - 'SHLVL': '1', 22:44:12 INFO - 'TERM': 'linux', 22:44:12 INFO - 'TMOUT': '86400', 22:44:12 INFO - 'USER': 'cltbld', 22:44:12 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', 22:44:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:12 INFO - '_': '/tools/buildbot/bin/python'} 22:44:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:44:12 INFO - Downloading/unpacking flake8 22:44:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:44:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:44:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:44:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:44:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 22:44:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:44:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:44:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:44:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:44:14 INFO - no previously-included directories found matching 'docs/_build' 22:44:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:44:14 INFO - Running setup.py install for pep8 22:44:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:44:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:44:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:44:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:44:14 INFO - no previously-included directories found matching 'docs/_build' 22:44:14 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 22:44:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:44:14 INFO - Cleaning up... 22:44:17 INFO - Return code: 0 22:44:17 INFO - Running post-action listener: _resource_record_post_action 22:44:17 INFO - Running post-action listener: _start_resource_monitoring 22:44:17 INFO - Starting resource monitoring. 22:44:17 INFO - ##### 22:44:17 INFO - ##### Running install step. 22:44:17 INFO - ##### 22:44:17 INFO - Running pre-action listener: _resource_record_pre_action 22:44:17 INFO - Running main action method: install 22:44:17 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:44:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:44:17 INFO - Reading from file tmpfile_stdout 22:44:17 INFO - Detecting whether we're running mozinstall >=1.0... 22:44:17 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 22:44:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 22:44:17 INFO - Reading from file tmpfile_stdout 22:44:17 INFO - Output received: 22:44:17 INFO - Usage: mozinstall [options] installer 22:44:17 INFO - Options: 22:44:17 INFO - -h, --help show this help message and exit 22:44:17 INFO - -d DEST, --destination=DEST 22:44:17 INFO - Directory to install application into. [default: 22:44:17 INFO - "/builds/slave/test-pgo"] 22:44:17 INFO - --app=APP Application being installed. [default: firefox] 22:44:17 INFO - mkdir: /builds/slave/test-pgo/build/application 22:44:17 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'] 22:44:17 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 22:44:33 INFO - Reading from file tmpfile_stdout 22:44:33 INFO - Output received: 22:44:33 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:44:33 INFO - Running post-action listener: _resource_record_post_action 22:44:33 INFO - ##### 22:44:33 INFO - ##### Running run-tests step. 22:44:33 INFO - ##### 22:44:33 INFO - Running pre-action listener: _resource_record_pre_action 22:44:33 INFO - Running main action method: run_tests 22:44:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:44:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:44:33 INFO - Python 2.7.3 22:44:33 INFO - Return code: 0 22:44:33 INFO - grabbing minidump binary from tooltool 22:44:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:44:33 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 0xf93920>, '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 0xf94820>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf94cb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:44:33 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 22:44:33 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 22:44:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:44:33 INFO - Return code: 0 22:44:33 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:44:33 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:44:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:44:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:44:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:44:33 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 22:44:33 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 22:44:34 INFO - Return code: 0 22:44:34 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', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 22:44:34 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 dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 22:44:34 INFO - Using env: {'DISPLAY': ':0', 22:44:34 INFO - 'HOME': '/home/cltbld', 22:44:34 INFO - 'LANG': 'en_US.UTF-8', 22:44:34 INFO - 'LANGUAGE': 'en_US:en', 22:44:34 INFO - 'LOGNAME': 'cltbld', 22:44:34 INFO - 'MAIL': '/var/mail/cltbld', 22:44:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:44:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:44:34 INFO - 'MOZ_NO_REMOTE': '1', 22:44:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:44:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:44:34 INFO - 'NO_EM_RESTART': '1', 22:44:34 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:44:34 INFO - 'PWD': '/builds/slave/test-pgo', 22:44:34 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:44:34 INFO - 'SHELL': '/bin/bash', 22:44:34 INFO - 'SHLVL': '1', 22:44:34 INFO - 'TERM': 'linux', 22:44:34 INFO - 'TMOUT': '86400', 22:44:34 INFO - 'USER': 'cltbld', 22:44:34 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226', 22:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:44:34 INFO - '_': '/tools/buildbot/bin/python'} 22:44:34 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', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:44:35 INFO - mozversion application_buildid: 20160303203202 22:44:35 INFO - mozversion application_changeset: 579da5ef8ccf4417fb472947e4cd5665f7f90a46 22:44:35 INFO - mozversion application_display_name: Nightly 22:44:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:44:35 INFO - mozversion application_name: Firefox 22:44:35 INFO - mozversion application_remotingname: firefox 22:44:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:44:35 INFO - mozversion application_vendor: Mozilla 22:44:35 INFO - mozversion application_version: 47.0a1 22:44:35 INFO - mozversion platform_buildid: 20160303203202 22:44:35 INFO - mozversion platform_changeset: 579da5ef8ccf4417fb472947e4cd5665f7f90a46 22:44:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:44:35 INFO - mozversion platform_version: 47.0a1 22:44:35 INFO - using testdate: 1457073875 22:44:35 INFO - actual date: 1457073875 22:44:35 INFO - starting webserver on 'localhost:51800' 22:44:35 INFO - SUITE-START | Running 2 tests 22:44:35 INFO - TEST-START | dromaeo_css 22:44:35 INFO - operating with platform_type : linux_ 22:44:35 INFO - Initialising browser for dromaeo_css test... 22:44:35 INFO - TEST-INFO | started process 1312 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpB0hwD4/profile http://localhost:51800/getInfo.html) 22:45:45 INFO - PROCESS | 1312 | __metrics Screen width/height:1600/1200 22:45:45 INFO - PROCESS | 1312 | colorDepth:24 22:45:45 INFO - PROCESS | 1312 | Browser inner width/height: 1024/697 22:45:45 INFO - PROCESS | 1312 | __metrics 22:45:49 INFO - PROCESS | 1312 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:45:50 INFO - PROCESS | 1312 | JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 22:45:52 INFO - TEST-INFO | 1312: exit 0 22:45:52 INFO - Browser initialized. 22:45:52 INFO - Running cycle 1/1 for dromaeo_css test... 22:45:52 INFO - TEST-INFO | started process 1409 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpB0hwD4/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:46:04 INFO - PROCESS | 1409 | RSS: Main: 153264128 22:46:04 INFO - PROCESS | 1409 | 22:46:55 INFO - PROCESS | 1409 | 1457074015861 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:49:05 INFO - PROCESS | 1409 | Cycle 1(1): loaded http://localhost:51800/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51800/tests/dromaeo/cssquery-ext.html) 22:49:06 INFO - PROCESS | 1409 | RSS: Main: 259911680 22:49:06 INFO - PROCESS | 1409 | 22:52:08 INFO - PROCESS | 1409 | Cycle 1(1): loaded http://localhost:51800/tests/dromaeo/cssquery-ext.html (next: http://localhost:51800/tests/dromaeo/cssquery-jquery.html) 22:52:08 INFO - PROCESS | 1409 | RSS: Main: 245084160 22:52:08 INFO - PROCESS | 1409 | 22:55:10 INFO - PROCESS | 1409 | Cycle 1(1): loaded http://localhost:51800/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51800/tests/dromaeo/cssquery-mootools.html) 22:55:11 INFO - PROCESS | 1409 | RSS: Main: 239972352 22:55:11 INFO - PROCESS | 1409 | 22:58:13 INFO - PROCESS | 1409 | Cycle 1(1): loaded http://localhost:51800/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51800/tests/dromaeo/cssquery-prototype.html) 22:58:13 INFO - PROCESS | 1409 | RSS: Main: 221773824 22:58:13 INFO - PROCESS | 1409 | 23:01:16 INFO - PROCESS | 1409 | Cycle 1(1): loaded http://localhost:51800/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51800/tests/dromaeo/cssquery-yui.html) 23:01:16 INFO - PROCESS | 1409 | RSS: Main: 218710016 23:01:16 INFO - PROCESS | 1409 | 23:04:19 INFO - PROCESS | 1409 | Cycle 1(1): loaded http://localhost:51800/tests/dromaeo/cssquery-yui.html (next: http://localhost:51800/tests/dromaeo/cssquery-dojo.html) 23:04:19 INFO - PROCESS | 1409 | RSS: Main: 308310016 23:04:19 INFO - PROCESS | 1409 | 23:04:19 INFO - PROCESS | 1409 | __start_tp_report 23:04:19 INFO - PROCESS | 1409 | _x_x_mozilla_page_load 23:04:19 INFO - PROCESS | 1409 | _x_x_mozilla_page_load_details 23:04:19 INFO - PROCESS | 1409 | |i|pagename|runs| 23:04:19 INFO - PROCESS | 1409 | |0;dojo.html;3106.8135911845284;3116.2520994961214;3151.7005884440982;3163.6519982801888;3186.0123361757855;5011.3986321641405;5022.598192144629;5035.992801439717;5043.117454445471;5057.696538207705;5112.8977420451565;5118.718470484122;5129.487051294868;5144.151215049522;5195.844124676266;4623.699459535136;4640.396748422709;4745.691530050535;4752.334673145762;4785.521447855204;4702.27114797207;4709.9058018839605;4734.242521196609;4776.379070720802;4830.420349558058;4495.348174514694;4547.24970379887;4559.247724125516;4584.518625544337;4584.633229341662;4676.55572720591;4689.20283551797;4689.835855745033;4700.952990470086;4718.740469274188;4538.364628951949;4645.488996210448;4648.465426475965;4658.18481765693;4680.836170734008;5001.799928002875;5024.799008039674;5030.421501527335;5050.772715227824;5058.038972595232;5469.07025805614;5499.285092937933;5500.339959204921;5504.394516603171;5551.639143455703;5303.363596368422;5484.780608775644;5508.862278443031;5537.58468114889;5556.16657501374;5082.822101226477;5097.847064588068;5100.56645185156;5100.846974590768;5140.126178549618;5569.637973531708;5571.9442805572235;5586.329640443173;5591.832245032656;5609.467100625516;5228.9738551307;5361.731913404315;5364.946350536523;5426.131818908955;5451.48210919962;5446.346438427414;5467.097928841776;5470.72646367688;5475.315585551806;5480.287562616834;5450.155225939986;5454.854480559127;5457.945420545744;5459.69971651555;5502.614816962854;5268.130758424893;5444.047291723932;5478;5507.201455788888;5552.333719953551;4136;4137.77242251679;4141.79291035447;4143.295639741251;4177.603127702863;3714.517112775322;3735.402335626287;3747.45661879026;3791.014336272589;3796.2027974125745;5152.227165925141;5170.586353091744;5174.197999310113;5205.427402985669;5224.686518808883;5462.590305727086;5507.201455788888;5507.669539827623;5511.173324001352;5513.807016754395;5083.542481176712;5085.262636917701;5158.638895277295;5183.740812959337;5206.0368831766245;4514.932276015862;4521.61566266871;4571.474280457724;4574.222382195035;4583.427071616048;5555.194496797942;5588.21764952907;5592.412796656293;5598.412166722517;5628.802991895264;5335.06636338647;5339.492748188916;5341.465853414783;5368.812091576776;5437.4562543745305;5781.017227071324;5794.333651630117;5802.941970580409;5805.071188609971;5816.098504731774;5417.377001644703;5430.918536222039;5444.101723215784;5447.3735520414075;5447.591430642797;5367.1680889462195;5403.64876283056;5409.107497262908;5462.808801691922;5469.753861076181;5326.041312563776;5342.973285133549;5367.141257398797;5371.462853714754;5375.489328513785;5334.306540149756;5373.2746079278795;5376.247325374373;5382.381026181883;5426.240326354235;5202.557782588522;5238.790448382022;5276.630635855607;5294.179402192666;5334.253204551444;5302.469753024667;5323.920141197807;5333.306670132858;5334.06653835585;5338.706371149624;5143.048536020848;5220.112580861187;5229.686218826882;5231.319928409434;5272.815451459181 23:04:19 INFO - PROCESS | 1409 | |1;ext.html;488.49440828742235;520.4535238000099;527.4382782336813;531.5641174237126;551.884104338089;27913.441731165363;28361.574576381372;35833;36055.819720901425;36057.459138112885;38474.230515389674;38972.41541376882;38982.41526377099;39030.21939561207;39081;8447.15528447155;8645.783855403633;8655.95672021639;8659.350548708871;8662.480251184918;8437.915620843805;8570.657173713043;8578.528180950045;8579.227869491744;8583.484990900566;8531.317494600447;8628.525431101287;8744.606492707811;8831.646734130627;8838.513881736502;8634.309255259595;8801.867971980426;8815.471071735716;8818.07410221927;8819.691310804123;8700.173483519118;8835.734927952171;8845.248153906925;8845.557722113868;8848.070952550019;36948.07629809276;37150.44274335887;37201.627983720085;37271;37309.44035839465;3174.571432856581;3176.539401786751;3199.9520007199912;3449.3963556377535;3458.239187378773;30028.54957175644;30121.397572048656;30381.848090759406;30462.238444038856;31307;3914.9608503914883;3933.8229779659982;3956.841726330972;4001.779902105383;4042.959570404317;264359;265758;266026.698665066;266771;268076;40451.797741011105;57544.136837947466;58773;58867.41132588619;59140.7042964791;39048;39261.60738392637;39309;39362.212755744724;39393.681852949485;14335.92832035854;14569.927150364181;14572.344244509022;14585.562433127023;14597.343119559646;11600.955913967784;11709.941450292694;11734.94132529332;11740.295582265091;11751.177417580859;13684.794728079087;13783.104098233583;14232.85767142336;14299.499517516837;14371.496997605034;5610.5231055360755;5717.885012422537;5743.0811070228565;5763.452472015152;5813.157092221604;809.1544336168718;850.4684572142411;901.2384535067857;978.6427953796813;994.0357852882693;25805.483890322088;26645.86677066641;26742;26855.328616784736;27049.86475067612;917;926.4117285523606;951.5527701980059;985.0494273026563;994.3785134291068;13504.392302346418;14001.64995875091;14124.646883827781;14234.359453824603;14305.85694143066;290153;291938;293228;293444;297809;49907.750461248186;50569.24146137811;50625.74687126541;50645.74677126665;50691;1147.2657499200518;1201.3332600406684;1322.3586560518333;1346.7979803029427;1364.40648317982;25797.742022579532;26156.215313540622;26505.204843854714;27191;28624.42751145007;40576;41674.166516670106;41915.58084419239;41950.37074443947;42029.36955945602;206409;207311;207718;208016;208315;31633.841830790698;31941.6805831948;41028;41637.79181104075;42052;41967.370489443296;42186.36720449134;42580.787096065564;42782.78608606937;43399.56600434081;36191.27617447689;36374.454383184806;36468.27063458769;36490.08774780545;36814;35327;35732.7258166408;36459;36913.44629830609;36975.81512092422;30261.697383025887;31306.90425835085;36024.81987590045;36986;37003.81498092492;28387.425556576738;30847.384888693825;31999.36001280008;32884.54712255179;33059;25528.744712552638;25631;25682;25684.614730779427;25937.48125037451 23:04:19 INFO - PROCESS | 1409 | |2;jquery.html;351.96128425873167;354.5320177365877;361.83355656398055;366.22543320876355;368.64978270642894;16594.58513537162;16958.83041169588;17316.82683173166;17602;17616;16208.83791162088;16290.755638665429;17186.312547498117;17188.398406055792;17205.397811076615;4703.92944105837;5325.973370133144;5506.394296627388;5511.614187006911;5515.779368825262;5440.891182176354;5457.508824205821;5465.234867118606;5470.398256191816;5482;5288.603354748409;5324.653897496651;5407.053765591026;5421.484958928915;5465.152901300285;5374.650647707906;5378;5386.353637563518;5403.081476149064;5407.080796264644;4729.101470720552;4757;4781.713097214179;4791.4250289965075;4802.111609352281;13054.934725326408;13673.316334183251;20548.178072877217;20935.895320523454;21261.362159135097;2030.4923769057737;2134.487722946482;2282.2582660635194;2301.9079236830507;2711.8881258683846;9187;9377.019837915763;9432.386894851812;9535.90464095364;9598.90401095987;1736.8089510153873;1818.8453981411596;1852.8610354223488;1866.6453373859058;1888.376835644234;58465.41534584642;60972;62009.37990620081;62429;66472.33527664709;9608.711738647851;10247.282690211763;10303;10500.317479363817;10594.894051059546;13763.38064787087;13798.65503362424;13878.236696981561;13879.7792353223;13890.791638125436;7291.30732580404;7482.064741907262;7515.586642734702;7569.167391586921;7575.545467271982;7178.282171782885;7209.2069872314005;7264;7265.12818461788;7283.963580182065;4547.477040140427;4611.815527378867;4622.19111655459;4656.603019094258;4659.6505262105475;1689.00348794212;1739.773829402195;1749.6325771587854;1777.3512667876132;1782.0911335218873;819.7745619954441;829.8298848735994;833.0669649991927;841.2849078283532;851.8083431227924;15012;15366.923165384325;15393.30730117125;15616.92191539035;15887.364505419884;788.9212769979221;807.4065561812064;850.0097386544729;854.3891117850767;887.9112088791069;8565.614547345385;8609.827803443895;8760.342974276955;8885.289176865836;8921.598528066253;408083;412242;415992;419334;425763;47120.7643961778;49258;71686.64156679183;72522;72759.27240727631;1004.8342023566175;1022.9641962531275;1053.5943661690305;1053.6996955867694;1054.3831858362944;14525.636859078184;14717.411303548166;14838.480653177086;15094.320755565806;15305;34890.302193956486;35327;35454.29091418105;35487.29025419425;36181.638183617826;49870.25194622011;50189.49810501848;50205.24692129694;50364.49635503598;50855.74572127116;7692.076950765944;7771.184025677222;7777.3778097753175;7790.610469476617;7827.217278272355;8801.735947921572;11393.373364465035;11404.429778510876;11616.593419230625;11786.58746943853;9915.71095757547;10468.895311046792;10528.420936848546;10604.151667866397;10908;10738.516766745672;10776.67669969902;11080.168987326144;11086.778264434828;11219.1024718024;10696.946515267375;10705.357678539312;10707.250492465764;10928.50821713009;11238.66284011481;7843.372530197456;7949.403794715304;7979.122296547491;8099.797505062421;8276;10097.646582369582;10541.104006159563;10694.518746656264;10765.246432749633;10853.45732713377 23:04:19 INFO - PROCESS | 1409 | |3;mootools.html;1346.2259200959452;1478.585995921142;1496.8952173347866;1658.7926509186352;1808.8914665120094;7256.310650488202;7433.814154646137;7492.325690687837;7496.400287976962;7524.887126693097;7445.627718614075;7620.085589729227;7620.733274335399;7620.96189519053;7634.9618251908805;1966.783653798081;2000.839932805372;2206.536627308259;2267.9886600567015;2291.8281128915314;2264.8074913632363;2272.761360057195;2278.236790675126;2279.555486680098;2280.247518318951;2132.776058513857;2155.3641675705703;2162.589108069462;2195.2316689158824;2196.7144271244715;2194.692743015979;2231.6987206727126;2232.2410380470637;2238;2240.350298413458;2238.1271304191378;2256.277991042867;2258.559580881729;2259.26573863495;2263.366257447917;7818.296353328228;7874.960625196894;7885.684572617089;7893.171217022187;7900.683972641087;4829.613630909554;4944.629152813553;4945.604351651859;4954.5540901319055;4986.925196122061;2914.883404663811;2940.4707152712504;3101.4572449821417;3135.278885856243;3135.372925414926;1013.3919648211088;1017.6082208349765;1912.1204246046834;1915.1956178405103;1964.035964035964;10484.423356715453;10502.9474852626;10527.368357898551;10538.104261137736;10541.84187237192;3955.20895820837;4137.958620413818;4171.749695018309;4179.2059508693255;4185.518665353462;8505.702300419456;8527.872081918777;8556.529390883561;8566.143385661384;8577.270931970728;4602.07958408313;4653.767311634405;4692.155412025868;4692.202325604587;4693.882652933636;3929.135590170158;4198.706090573631;4326.372675962031;4335.501417336984;4355.2813785725;3517.2437925846066;3563.2339047104997;3567.5897271813556;3588;3613.060604242863;1037.0666400239693;1042.744527590745;1046.6493724602344;1057.2387880725864;1075.6127793994156;884.4074470105045;903.810199858024;1107.379867274329;1132.1904838040841;1134.7446824464594;7527.059117610299;7571.3942884569105;7581.3176814087;7616.428767842323;7616.5810880401;474.3525088254508;476.7640018191018;493.58785414178845;495.1211599411074;496.85839535732146;5640.746166422521;5672.858178545487;5728.112142617901;5737.655740655574;5744.310682718101;9384.62461501546;9547.728736315534;9551.14039736427;9654.082862128087;9668.854967175497;9559.378640388353;9593.42439453621;9620.182284505896;9747.3664211826;9766.41401515897;7196.748113816096;7361.852762944711;7433.442491813135;7556.622168891534;7572.4699271051595;6948.270431604608;6967.65161741921;6984.40632546219;6989.580625162506;7027.894581581382;8295.170482951899;8384.958075209584;8390.328773698211;8490.61792219364;8541.95729021351;8625.353098517762;8798.34012449056;8812.603432845666;8830.072842351718;8953.104689531256;6275.937240627535;6582.934170658424;6667.49993750461;6807.965960170168;6909.170899491892;7664.501807382502;7670.501417407851;7676.117246516722;7698.2686644768655;7722.768316950501;6752.189737231563;6909.792706218822;6970.721171153097;7028.753993610199;7108.573485590881;6862.828429289308;6898.344657257552;6942.930570694229;7011.649417529001;7091.787246382617;7104.964475177591;7120.60836653989;7139.393151582174;7177.533460325062;7309.451791115581;6682.031105489774;6695.330466953265;6721.462283017445;6729.1924969002075;6750.966245168939;6571.967140164268;6592.604443733391;6762.985552167214;6798.320167983162;6805.149356330458 23:04:19 INFO - PROCESS | 1409 | |4;prototype.html;316.67698947073984;320.4824208902622;330.07414203160124;340.38560398480735;341.61909470939895;5904.0553511438175;5914.2902851657755;5914.733836977335;5917.171595976567;5934.169216309715;5808.1287806829;5865.76536938523;5873.383294754053;5879.500242479395;5884.911726324109;4386.912261754763;4436.6672499562665;4537.727736335831;4567.748773817439;4572.359869618256;4515.412996310475;4553.840615578455;4554.294084416921;4563.680542362036;4567.725936443808;4339.696221264513;4510.458744950611;4513.79687914043;4519.028408892087;4529.524399938008;4414.139242847646;4449.7997590108525;4458.710183838057;4496.527864574238;4536.274196128604;3985.9202815943518;3992.441058251847;3995.680345572347;4002.2995975704134;4010.9197816043516;5585.553155747531;5587.05020146576;5590.944090559083;5592.664440133605;5599.244102046212;5270.420253772082;5277.577793776489;5281.524662780368;5283.973580132075;5284.313039304904;6229.968850155766;6232;6238.438540531374;6239.719212635397;6243.906341404882;4147.066909945268;4263.637590804786;4264.658827293809;4273.636740877029;4424.889377765549;6465.83835404114;6487;6521.673916304213;6523.956167013255;6525.445337146348;4349.695521313541;4354.673399494987;4356.411884395629;4357.433533640607;4361.323994780814;4415.27148020573;4417.911641767147;4418.094290670405;4420.182266280748;4421.226285400042;5142.640015198975;5142.948570514322;5151.484851514893;5154.53609175176;5159.638825282269;6110.7555697771595;6261.592996455216;6288.937110628926;6289.842753931189;6295.464885484785;1542.537238828347;1547.868431183362;1561.0243597751405;1562.1095975293974;1564.3742502998891;953.4946478366477;975.7014709738776;989.4459102902398;998.8901220865699;1003.3879333606494;5878.353381128072;5883.911741323884;5904.822855314347;5913.674747888907;5916.970415147896;6358.682065896686;6361.5864968777805;6375.71309291083;6379.553431259767;6381.8085457436355;5888.028475301613;5902.734376953047;5904.527637788967;5907.054871220584;5915.319738230072;3418.282160746271;3438.8796392126155;3440.82795860211;3441.5698037745283;3448.2930999145115;6572.277049524549;6668.433183179387;6741.662916854138;6747.527673062838;6748.122744043243;6522.119513865662;6528.738850445929;6531.0529973153625;6541.182352205974;6555.8361040973405;6712.784781022251;6713.56361836479;6715.395614394728;6716.529842911047;6725.125733654691;6355.5551111421755;6357.4596159326975;6358.90461643085;6359;6368.458681011981;6134.141220229082;6301.149344838388;6306.558540902276;6309.779157729457;6310.905336419865;6697.229818570927;6727.899081513683;6740.752960702286;6744.8651026978205;6749.527533072638;4270.743755374688;4272.700910936204;4272.850450234351;4273.273543497551;4275.743455392686;6376.266729326187;6378.872422551616;6384.3934826191435;6384.648844313608;6386.042093685985;6168.228971378578;6177.289611694712;6177.938220617736;6183.721732521957;6187.474064704371;6126.938730612636;6170.413810687977;6298.433141017331;6301.8109456716375;6313.621182729051;6142.447179753665;6163.691815409122;6168.0439531872635;6176.073588961692;6178.443940045418;6311.368863113652;6315.210598675166;6316.968415157895;6321.810345689637;6342.8731425370315;5916;6082.178905847831;6149.508039356751;6161.753529858755;6177.135201071764 23:04:19 INFO - PROCESS | 1409 | |5;yui.html;754.30227039988;788.972385966491;791.5765065689857;797.2466019611469;839.5802098950525;13789.241591712453;13985.021048526594;14028;14028.298585070732;14039.298035098256;12130.636080917564;14825.406983720639;15190.088594684326;15191.316390762415;15214.923925380384;838.7944953486405;845.8266055458616;851.6252848746532;851.8296340731847;852.9658813647446;694.9722011119549;840.2102024097329;856.7044369692446;859.0593300336122;860.1097863711029;887.201518633232;891.4205766251923;898.1691934960169;898.1736802142012;898.8651702244683;719.4388377065844;891.1445012787699;891.2958762577556;893.3121496447699;894.2577660541767;644.310587671192;647.8348021254598;650.8991106378519;652.4943169044029;653.9149910511603;13948.442062317496;13954.860451395458;14005;14005.159690418608;14021.369038393297;13005;13261.73476530464;13397.86602133976;13434.798478022838;13660.65848353789;10469.528871200815;10493.265471417004;10506.369617822955;10516.316439431412;10722.571097156182;3681.852725890961;3697.131174174067;3718.1634132320282;3799.4490798834013;3815.790131542764;51620;52480;52624;52641.47358526404;53094;10372.896271037343;10470.424126672953;10586.470676466146;10703.357798532114;10823.783524329468;19685.11416986239;19727.901360493393;19739.111739971737;19757.604847902963;19765.802341976683;7587.810304742426;7717.922820771721;8054.476459030145;8112.797180070427;8113.350931925432;4581.977090114595;4697.694649847749;4779.856604301876;4782.736949467743;4838.903221935541;3230.935381292361;3237.3201627658455;3246.7077962983444;3249.5613092232247;3253.528238405418;570.8858228354346;575.1171951055194;578.6296770067161;579.8782255726345;581.8021872563264;655.6266315936341;662.5594572945749;678.6369292428527;679.4700133895569;680.1237309644674;8395.24442800151;8663.653453861774;8926.509042002623;9306.627734890664;9615.9038409615;588.6644612570793;654.9476041916636;674.6761554453791;683.5556888022726;704.5350068954466;3917.9608203917596;3942.9211415772097;4099.467069280974;4195.286801243796;4239.915201695949;57122.14316785252;57209;58366.7081664589;58522.12216816751;58605.41394586085;50266.49733502618;50565.49434505682;51002.74498627483;51162.232566511535;51456.48543514592;46049.53950460586;46298.30552541715;46996.5300346999;47842.5215747845;48483.515164848606;8132.430729848854;8146.103928567972;8408.159184081544;8479.872801908099;8507.914920850711;32582.34835303328;33644.158896027795;33895.64417423275;34050;34987;41546.169076618906;41549;42382.36426453667;44780;45161.322580161985;9946.254030947563;10126.746831329276;10208.795824083625;10326.328788628714;10655.840162397415;11074.833877491683;11196.496157672762;11401.429928503376;11952.64142075739;11962;7886.621088876828;10993.945030274797;11073.335599864035;11859.40702964831;12085.456154473246;11311.151663625425;11520.654380368602;11544.093194553934;11551.942240288745;12004.459799308877;11401.429928503707;11640.825387619023;11642.359670218219;12033.63899083029;12050.939745301217;9722.854157187507;9877.209823214269;9914.335231492943;10096.798064038825;10401.895981040092;9630.518474076409;9971.60113595477;10167.593296268362;10304;10322.845157322496 23:04:19 INFO - PROCESS | 1409 | __end_tp_report 23:04:19 INFO - PROCESS | 1409 | __start_cc_report 23:04:19 INFO - PROCESS | 1409 | _x_x_mozilla_cycle_collect,809 23:04:19 INFO - PROCESS | 1409 | __end_cc_report 23:04:19 INFO - PROCESS | 1409 | __startTimestamp1457075059828__endTimestamp 23:04:19 INFO - PROCESS | 1409 | 23:04:19 INFO - PROCESS | 1409 | ------- Summary: start ------- 23:04:19 INFO - PROCESS | 1409 | Number of tests: 6 23:04:19 INFO - PROCESS | 1409 | 23:04:19 INFO - PROCESS | 1409 | [#0] dojo.html Cycles:165 Average:5077.97 Median:5268.13 stddev:559.46 (10.6%) stddev-sans-first:539.38 23:04:19 INFO - PROCESS | 1409 | Values: 3106.8 3116.3 3151.7 3163.7 3186.0 5011.4 5022.6 5036.0 5043.1 5057.7 5112.9 5118.7 5129.5 5144.2 5195.8 4623.7 4640.4 4745.7 4752.3 4785.5 4702.3 4709.9 4734.2 4776.4 4830.4 4495.3 4547.2 4559.2 4584.5 4584.6 4676.6 4689.2 4689.8 4701.0 4718.7 4538.4 4645.5 4648.5 4658.2 4680.8 5001.8 5024.8 5030.4 5050.8 5058.0 5469.1 5499.3 5500.3 5504.4 5551.6 5303.4 5484.8 5508.9 5537.6 5556.2 5082.8 5097.8 5100.6 5100.8 5140.1 5569.6 5571.9 5586.3 5591.8 5609.5 5229.0 5361.7 5364.9 5426.1 5451.5 5446.3 5467.1 5470.7 5475.3 5480.3 5450.2 5454.9 5457.9 5459.7 5502.6 5268.1 5444.0 5478.0 5507.2 5552.3 4136.0 4137.8 4141.8 4143.3 4177.6 3714.5 3735.4 3747.5 3791.0 3796.2 5152.2 5170.6 5174.2 5205.4 5224.7 5462.6 5507.2 5507.7 5511.2 5513.8 5083.5 5085.3 5158.6 5183.7 5206.0 4514.9 4521.6 4571.5 4574.2 4583.4 5555.2 5588.2 5592.4 5598.4 5628.8 5335.1 5339.5 5341.5 5368.8 5437.5 5781.0 5794.3 5802.9 5805.1 5816.1 5417.4 5430.9 5444.1 5447.4 5447.6 5367.2 5403.6 5409.1 5462.8 5469.8 5326.0 5343.0 5367.1 5371.5 5375.5 5334.3 5373.3 5376.2 5382.4 5426.2 5202.6 5238.8 5276.6 5294.2 5334.3 5302.5 5323.9 5333.3 5334.1 5338.7 5143.0 5220.1 5229.7 5231.3 5272.8 23:04:19 INFO - PROCESS | 1409 | 23:04:19 INFO - PROCESS | 1409 | [#1] ext.html Cycles:180 Average:41810.74 Median:36980.91 stddev:67314.57 (182.0%) stddev-sans-first:67431.90 23:04:19 INFO - PROCESS | 1409 | Values: 488.5 520.5 527.4 531.6 551.9 27913.4 28361.6 35833.0 36055.8 36057.5 38474.2 38972.4 38982.4 39030.2 39081.0 8447.2 8645.8 8656.0 8659.4 8662.5 8437.9 8570.7 8578.5 8579.2 8583.5 8531.3 8628.5 8744.6 8831.6 8838.5 8634.3 8801.9 8815.5 8818.1 8819.7 8700.2 8835.7 8845.2 8845.6 8848.1 36948.1 37150.4 37201.6 37271.0 37309.4 3174.6 3176.5 3200.0 3449.4 3458.2 30028.5 30121.4 30381.8 30462.2 31307.0 3915.0 3933.8 3956.8 4001.8 4043.0 264359.0 265758.0 266026.7 266771.0 268076.0 40451.8 57544.1 58773.0 58867.4 59140.7 39048.0 39261.6 39309.0 39362.2 39393.7 14335.9 14569.9 14572.3 14585.6 14597.3 11601.0 11709.9 11734.9 11740.3 11751.2 13684.8 13783.1 14232.9 14299.5 14371.5 5610.5 5717.9 5743.1 5763.5 5813.2 809.2 850.5 901.2 978.6 994.0 25805.5 26645.9 26742.0 26855.3 27049.9 917.0 926.4 951.6 985.0 994.4 13504.4 14001.6 14124.6 14234.4 14305.9 290153.0 291938.0 293228.0 293444.0 297809.0 49907.8 50569.2 50625.7 50645.7 50691.0 1147.3 1201.3 1322.4 1346.8 1364.4 25797.7 26156.2 26505.2 27191.0 28624.4 40576.0 41674.2 41915.6 41950.4 42029.4 206409.0 207311.0 207718.0 208016.0 208315.0 31633.8 31941.7 41028.0 41637.8 42052.0 41967.4 42186.4 42580.8 42782.8 43399.6 36191.3 36374.5 36468.3 36490.1 36814.0 35327.0 35732.7 36459.0 36913.4 36975.8 30261.7 31306.9 36024.8 36986.0 37003.8 28387.4 30847.4 31999.4 32884.5 33059.0 25528.7 25631.0 25682.0 25684.6 25937.5 23:04:19 INFO - PROCESS | 1409 | 23:04:19 INFO - PROCESS | 1409 | [#2] jquery.html Cycles:180 Average:24323.81 Median:422548.50 stddev:68211.72 (16.1%) stddev-sans-first:68379.32 23:04:19 INFO - PROCESS | 1409 | Values: 352.0 354.5 361.8 366.2 368.6 16594.6 16958.8 17316.8 17602.0 17616.0 16208.8 16290.8 17186.3 17188.4 17205.4 4703.9 5326.0 5506.4 5511.6 5515.8 5440.9 5457.5 5465.2 5470.4 5482.0 5288.6 5324.7 5407.1 5421.5 5465.2 5374.7 5378.0 5386.4 5403.1 5407.1 4729.1 4757.0 4781.7 4791.4 4802.1 13054.9 13673.3 20548.2 20935.9 21261.4 2030.5 2134.5 2282.3 2301.9 2711.9 9187.0 9377.0 9432.4 9535.9 9598.9 1736.8 1818.8 1852.9 1866.6 1888.4 58465.4 60972.0 62009.4 62429.0 66472.3 9608.7 10247.3 10303.0 10500.3 10594.9 13763.4 13798.7 13878.2 13879.8 13890.8 7291.3 7482.1 7515.6 7569.2 7575.5 7178.3 7209.2 7264.0 7265.1 7284.0 4547.5 4611.8 4622.2 4656.6 4659.7 1689.0 1739.8 1749.6 1777.4 1782.1 819.8 829.8 833.1 841.3 851.8 15012.0 15366.9 15393.3 15616.9 15887.4 788.9 807.4 850.0 854.4 887.9 8565.6 8609.8 8760.3 8885.3 8921.6 408083.0 412242.0 415992.0 419334.0 425763.0 47120.8 49258.0 71686.6 72522.0 72759.3 1004.8 1023.0 1053.6 1053.7 1054.4 14525.6 14717.4 14838.5 15094.3 15305.0 34890.3 35327.0 35454.3 35487.3 36181.6 49870.3 50189.5 50205.2 50364.5 50855.7 7692.1 7771.2 7777.4 7790.6 7827.2 8801.7 11393.4 11404.4 11616.6 11786.6 9915.7 10468.9 10528.4 10604.2 10908.0 10738.5 10776.7 11080.2 11086.8 11219.1 10696.9 10705.4 10707.3 10928.5 11238.7 7843.4 7949.4 7979.1 8099.8 8276.0 10097.6 10541.1 10694.5 10765.2 10853.5 23:04:19 INFO - PROCESS | 1409 | 23:04:19 INFO - PROCESS | 1409 | [#3] mootools.html Cycles:180 Average:5452.42 Median:6587.77 stddev:2857.69 (43.4%) stddev-sans-first:2849.04 23:04:19 INFO - PROCESS | 1409 | Values: 1346.2 1478.6 1496.9 1658.8 1808.9 7256.3 7433.8 7492.3 7496.4 7524.9 7445.6 7620.1 7620.7 7621.0 7635.0 1966.8 2000.8 2206.5 2268.0 2291.8 2264.8 2272.8 2278.2 2279.6 2280.2 2132.8 2155.4 2162.6 2195.2 2196.7 2194.7 2231.7 2232.2 2238.0 2240.4 2238.1 2256.3 2258.6 2259.3 2263.4 7818.3 7875.0 7885.7 7893.2 7900.7 4829.6 4944.6 4945.6 4954.6 4986.9 2914.9 2940.5 3101.5 3135.3 3135.4 1013.4 1017.6 1912.1 1915.2 1964.0 10484.4 10502.9 10527.4 10538.1 10541.8 3955.2 4138.0 4171.7 4179.2 4185.5 8505.7 8527.9 8556.5 8566.1 8577.3 4602.1 4653.8 4692.2 4692.2 4693.9 3929.1 4198.7 4326.4 4335.5 4355.3 3517.2 3563.2 3567.6 3588.0 3613.1 1037.1 1042.7 1046.6 1057.2 1075.6 884.4 903.8 1107.4 1132.2 1134.7 7527.1 7571.4 7581.3 7616.4 7616.6 474.4 476.8 493.6 495.1 496.9 5640.7 5672.9 5728.1 5737.7 5744.3 9384.6 9547.7 9551.1 9654.1 9668.9 9559.4 9593.4 9620.2 9747.4 9766.4 7196.7 7361.9 7433.4 7556.6 7572.5 6948.3 6967.7 6984.4 6989.6 7027.9 8295.2 8385.0 8390.3 8490.6 8542.0 8625.4 8798.3 8812.6 8830.1 8953.1 6275.9 6582.9 6667.5 6808.0 6909.2 7664.5 7670.5 7676.1 7698.3 7722.8 6752.2 6909.8 6970.7 7028.8 7108.6 6862.8 6898.3 6942.9 7011.6 7091.8 7105.0 7120.6 7139.4 7177.5 7309.5 6682.0 6695.3 6721.5 6729.2 6751.0 6572.0 6592.6 6763.0 6798.3 6805.1 23:04:19 INFO - PROCESS | 1409 | 23:04:19 INFO - PROCESS | 1409 | [#4] prototype.html Cycles:180 Average:5202.72 Median:5914.51 stddev:1579.60 (26.7%) stddev-sans-first:1540.87 23:04:19 INFO - PROCESS | 1409 | Values: 316.7 320.5 330.1 340.4 341.6 5904.1 5914.3 5914.7 5917.2 5934.2 5808.1 5865.8 5873.4 5879.5 5884.9 4386.9 4436.7 4537.7 4567.7 4572.4 4515.4 4553.8 4554.3 4563.7 4567.7 4339.7 4510.5 4513.8 4519.0 4529.5 4414.1 4449.8 4458.7 4496.5 4536.3 3985.9 3992.4 3995.7 4002.3 4010.9 5585.6 5587.1 5590.9 5592.7 5599.2 5270.4 5277.6 5281.5 5284.0 5284.3 6230.0 6232.0 6238.4 6239.7 6243.9 4147.1 4263.6 4264.7 4273.6 4424.9 6465.8 6487.0 6521.7 6524.0 6525.4 4349.7 4354.7 4356.4 4357.4 4361.3 4415.3 4417.9 4418.1 4420.2 4421.2 5142.6 5142.9 5151.5 5154.5 5159.6 6110.8 6261.6 6288.9 6289.8 6295.5 1542.5 1547.9 1561.0 1562.1 1564.4 953.5 975.7 989.4 998.9 1003.4 5878.4 5883.9 5904.8 5913.7 5917.0 6358.7 6361.6 6375.7 6379.6 6381.8 5888.0 5902.7 5904.5 5907.1 5915.3 3418.3 3438.9 3440.8 3441.6 3448.3 6572.3 6668.4 6741.7 6747.5 6748.1 6522.1 6528.7 6531.1 6541.2 6555.8 6712.8 6713.6 6715.4 6716.5 6725.1 6355.6 6357.5 6358.9 6359.0 6368.5 6134.1 6301.1 6306.6 6309.8 6310.9 6697.2 6727.9 6740.8 6744.9 6749.5 4270.7 4272.7 4272.9 4273.3 4275.7 6376.3 6378.9 6384.4 6384.6 6386.0 6168.2 6177.3 6177.9 6183.7 6187.5 6126.9 6170.4 6298.4 6301.8 6313.6 6142.4 6163.7 6168.0 6176.1 6178.4 6311.4 6315.2 6317.0 6321.8 6342.9 5916.0 6082.2 6149.5 6161.8 6177.1 23:04:19 INFO - PROCESS | 1409 | 23:04:19 INFO - PROCESS | 1409 | [#5] yui.html Cycles:180 Average:14052.04 Median:25498.00 stddev:16144.04 (63.3%) stddev-sans-first:16158.44 23:04:19 INFO - PROCESS | 1409 | Values: 754.3 789.0 791.6 797.2 839.6 13789.2 13985.0 14028.0 14028.3 14039.3 12130.6 14825.4 15190.1 15191.3 15214.9 838.8 845.8 851.6 851.8 853.0 695.0 840.2 856.7 859.1 860.1 887.2 891.4 898.2 898.2 898.9 719.4 891.1 891.3 893.3 894.3 644.3 647.8 650.9 652.5 653.9 13948.4 13954.9 14005.0 14005.2 14021.4 13005.0 13261.7 13397.9 13434.8 13660.7 10469.5 10493.3 10506.4 10516.3 10722.6 3681.9 3697.1 3718.2 3799.4 3815.8 51620.0 52480.0 52624.0 52641.5 53094.0 10372.9 10470.4 10586.5 10703.4 10823.8 19685.1 19727.9 19739.1 19757.6 19765.8 7587.8 7717.9 8054.5 8112.8 8113.4 4582.0 4697.7 4779.9 4782.7 4838.9 3230.9 3237.3 3246.7 3249.6 3253.5 570.9 575.1 578.6 579.9 581.8 655.6 662.6 678.6 679.5 680.1 8395.2 8663.7 8926.5 9306.6 9615.9 588.7 654.9 674.7 683.6 704.5 3918.0 3942.9 4099.5 4195.3 4239.9 57122.1 57209.0 58366.7 58522.1 58605.4 50266.5 50565.5 51002.7 51162.2 51456.5 46049.5 46298.3 46996.5 47842.5 48483.5 8132.4 8146.1 8408.2 8479.9 8507.9 32582.3 33644.2 33895.6 34050.0 34987.0 41546.2 41549.0 42382.4 44780.0 45161.3 9946.3 10126.7 10208.8 10326.3 10655.8 11074.8 11196.5 11401.4 11952.6 11962.0 7886.6 10993.9 11073.3 11859.4 12085.5 11311.2 11520.7 11544.1 11551.9 12004.5 11401.4 11640.8 11642.4 12033.6 12050.9 9722.9 9877.2 9914.3 10096.8 10401.9 9630.5 9971.6 10167.6 10304.0 10322.8 23:04:19 INFO - PROCESS | 1409 | -------- Summary: end -------- 23:04:19 INFO - PROCESS | 1409 | 23:04:21 INFO - TEST-INFO | 1409: exit 0 23:04:21 INFO - TEST-OK | dromaeo_css | took 1185869ms 23:04:21 INFO - TEST-START | kraken 23:04:21 INFO - operating with platform_type : linux_ 23:04:21 INFO - Initialising browser for kraken test... 23:04:21 INFO - TEST-INFO | started process 1592 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphZi8qK/profile http://localhost:51800/getInfo.html) 23:04:33 INFO - PROCESS | 1592 | __metrics Screen width/height:1600/1200 23:04:33 INFO - PROCESS | 1592 | colorDepth:24 23:04:33 INFO - PROCESS | 1592 | Browser inner width/height: 1024/697 23:04:33 INFO - PROCESS | 1592 | __metrics 23:04:39 INFO - PROCESS | 1592 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:04:39 INFO - PROCESS | 1592 | JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 23:04:41 INFO - TEST-INFO | 1592: exit 0 23:04:41 INFO - Browser initialized. 23:04:41 INFO - Running cycle 1/1 for kraken test... 23:04:41 INFO - TEST-INFO | started process 1680 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphZi8qK/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:04:53 INFO - PROCESS | 1680 | RSS: Main: 153030656 23:04:53 INFO - PROCESS | 1680 | 23:04:55 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51800/tests/kraken/driver.html?testName=audio-beat-detection) 23:04:56 INFO - PROCESS | 1680 | RSS: Main: 198086656 23:04:56 INFO - PROCESS | 1680 | 23:04:59 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51800/tests/kraken/driver.html?testName=audio-dft) 23:04:59 INFO - PROCESS | 1680 | RSS: Main: 185434112 23:04:59 INFO - PROCESS | 1680 | 23:05:02 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51800/tests/kraken/driver.html?testName=audio-fft) 23:05:03 INFO - PROCESS | 1680 | RSS: Main: 170684416 23:05:03 INFO - PROCESS | 1680 | 23:05:05 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51800/tests/kraken/driver.html?testName=audio-oscillator) 23:05:05 INFO - PROCESS | 1680 | RSS: Main: 174374912 23:05:05 INFO - PROCESS | 1680 | 23:05:08 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51800/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:05:08 INFO - PROCESS | 1680 | RSS: Main: 174575616 23:05:08 INFO - PROCESS | 1680 | 23:05:11 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51800/tests/kraken/driver.html?testName=imaging-darkroom) 23:05:12 INFO - PROCESS | 1680 | RSS: Main: 174972928 23:05:12 INFO - PROCESS | 1680 | 23:05:16 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51800/tests/kraken/driver.html?testName=imaging-desaturate) 23:05:16 INFO - PROCESS | 1680 | RSS: Main: 174096384 23:05:16 INFO - PROCESS | 1680 | 23:05:19 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51800/tests/kraken/driver.html?testName=json-parse-financial) 23:05:20 INFO - PROCESS | 1680 | RSS: Main: 174546944 23:05:20 INFO - PROCESS | 1680 | 23:05:21 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51800/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:05:22 INFO - PROCESS | 1680 | RSS: Main: 189865984 23:05:22 INFO - PROCESS | 1680 | 23:05:23 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:05:24 INFO - PROCESS | 1680 | RSS: Main: 184958976 23:05:24 INFO - PROCESS | 1680 | 23:05:26 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:05:27 INFO - PROCESS | 1680 | RSS: Main: 204587008 23:05:29 INFO - PROCESS | 1680 | 23:05:32 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:05:32 INFO - PROCESS | 1680 | RSS: Main: 192745472 23:05:32 INFO - PROCESS | 1680 | 23:05:38 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:05:39 INFO - PROCESS | 1680 | RSS: Main: 174829568 23:05:39 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:51800/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51800/tests/kraken/driver.html?testName=ai-astar) 23:05:40 INFO - PROCESS | 1680 | RSS: Main: 245891072 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | __start_tp_report 23:05:40 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 23:05:40 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 23:05:40 INFO - PROCESS | 1680 | |i|pagename|runs| 23:05:40 INFO - PROCESS | 1680 | |0;ai-astar;110;111;105;110;110;111;101;112;111;112 23:05:40 INFO - PROCESS | 1680 | |1;audio-beat-detection;155;130;152;166;131;151;158;132;170;127 23:05:40 INFO - PROCESS | 1680 | |2;audio-dft;160;161;161;160;161;161;162;164;162;162 23:05:40 INFO - PROCESS | 1680 | |3;audio-fft;73;75;99;105;76;76;78;121;96;76 23:05:40 INFO - PROCESS | 1680 | |4;audio-oscillator;93;93;135;93;93;93;137;92;94;147 23:05:40 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;117;117;117;118;117;116;117;117;117;117 23:05:40 INFO - PROCESS | 1680 | |6;imaging-darkroom;147;146;149;147;147;146;146;148;148;148 23:05:40 INFO - PROCESS | 1680 | |7;imaging-desaturate;95;96;96;96;96;96;95;96;95;95 23:05:40 INFO - PROCESS | 1680 | |8;json-parse-financial;56;57;57;58;57;58;57;56;57;58 23:05:40 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;68;68;68;68;68;68;69;69;68;68 23:05:40 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;80;80;82;82;84;82;81;83;92;86 23:05:40 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;116;122;117;131;126;115;117;122;121;114 23:05:40 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;145;160;156;166;142;153;151;151;154;143 23:05:40 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;69;71;71;70;70;71;71;71;70;70 23:05:40 INFO - PROCESS | 1680 | __end_tp_report 23:05:40 INFO - PROCESS | 1680 | __start_cc_report 23:05:40 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1046 23:05:40 INFO - PROCESS | 1680 | __end_cc_report 23:05:40 INFO - PROCESS | 1680 | __startTimestamp1457075140985__endTimestamp 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | ------- Summary: start ------- 23:05:40 INFO - PROCESS | 1680 | Number of tests: 14 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:109.30 Median:111.00 stddev:3.53 (3.2%) stddev-sans-first:3.73 23:05:40 INFO - PROCESS | 1680 | Values: 110.0 111.0 105.0 110.0 110.0 111.0 101.0 112.0 111.0 112.0 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:147.20 Median:153.50 stddev:15.94 (10.4%) stddev-sans-first:16.65 23:05:40 INFO - PROCESS | 1680 | Values: 155.0 130.0 152.0 166.0 131.0 151.0 158.0 132.0 170.0 127.0 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:1.17 (0.7%) stddev-sans-first:1.13 23:05:40 INFO - PROCESS | 1680 | Values: 160.0 161.0 161.0 160.0 161.0 161.0 162.0 164.0 162.0 162.0 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:87.50 Median:76.00 stddev:16.62 (21.9%) stddev-sans-first:16.78 23:05:40 INFO - PROCESS | 1680 | Values: 73.0 75.0 99.0 105.0 76.0 76.0 78.0 121.0 96.0 76.0 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:107.00 Median:93.00 stddev:22.75 (24.5%) stddev-sans-first:23.56 23:05:40 INFO - PROCESS | 1680 | Values: 93.0 93.0 135.0 93.0 93.0 93.0 137.0 92.0 94.0 147.0 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 23:05:40 INFO - PROCESS | 1680 | Values: 117.0 117.0 117.0 118.0 117.0 116.0 117.0 117.0 117.0 117.0 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.50 stddev:1.03 (0.7%) stddev-sans-first:1.09 23:05:40 INFO - PROCESS | 1680 | Values: 147.0 146.0 149.0 147.0 147.0 146.0 146.0 148.0 148.0 148.0 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 23:05:40 INFO - PROCESS | 1680 | Values: 95.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 95.0 95.0 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.00 stddev:0.74 (1.3%) stddev-sans-first:0.67 23:05:40 INFO - PROCESS | 1680 | Values: 56.0 57.0 57.0 58.0 57.0 58.0 57.0 56.0 57.0 58.0 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 23:05:40 INFO - PROCESS | 1680 | Values: 68.0 68.0 68.0 68.0 68.0 68.0 69.0 69.0 68.0 68.0 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:82.50 stddev:3.58 (4.3%) stddev-sans-first:3.61 23:05:40 INFO - PROCESS | 1680 | Values: 80.0 80.0 82.0 82.0 84.0 82.0 81.0 83.0 92.0 86.0 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:120.10 Median:121.50 stddev:5.38 (4.4%) stddev-sans-first:5.50 23:05:40 INFO - PROCESS | 1680 | Values: 116.0 122.0 117.0 131.0 126.0 115.0 117.0 122.0 121.0 114.0 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.10 Median:153.50 stddev:7.55 (4.9%) stddev-sans-first:7.56 23:05:40 INFO - PROCESS | 1680 | Values: 145.0 160.0 156.0 166.0 142.0 153.0 151.0 151.0 154.0 143.0 23:05:40 INFO - PROCESS | 1680 | 23:05:40 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.40 Median:71.00 stddev:0.70 (1.0%) stddev-sans-first:0.53 23:05:40 INFO - PROCESS | 1680 | Values: 69.0 71.0 71.0 70.0 70.0 71.0 71.0 71.0 70.0 70.0 23:05:40 INFO - PROCESS | 1680 | -------- Summary: end -------- 23:05:40 INFO - PROCESS | 1680 | 23:05:50 INFO - Browser shutdown timed out after 5 seconds, terminating process. 23:05:50 INFO - Terminating psutil.Process(pid=1680, name='firefox') 23:06:38 INFO - TEST-UNEXPECTED-ERROR | kraken | psutil.TimeoutExpired timeout after 3 seconds (pid=1680, name='firefox') 23:06:38 ERROR - Traceback (most recent call last): 23:06:38 INFO - File "/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py", line 196, in run_tests 23:06:38 INFO - talos_results.add(mytest.runTest(browser_config, test)) 23:06:38 INFO - File "/builds/slave/test-pgo/build/tests/talos/talos/ttest.py", line 64, in runTest 23:06:38 INFO - return self._runTest(browser_config, test_config, setup) 23:06:38 INFO - File "/builds/slave/test-pgo/build/tests/talos/talos/ttest.py", line 167, in _runTest 23:06:38 INFO - if counter_management else None), 23:06:38 INFO - File "/builds/slave/test-pgo/build/tests/talos/talos/talos_process.py", line 132, in run_browser 23:06:38 INFO - return_code = context.kill_process() 23:06:38 INFO - File "/builds/slave/test-pgo/build/tests/talos/talos/talos_process.py", line 43, in kill_process 23:06:38 INFO - return self.process.wait(3) 23:06:38 INFO - File "/builds/slave/test-pgo/build/venv/local/lib/python2.7/site-packages/psutil/__init__.py", line 1102, in wait 23:06:38 INFO - return self._proc.wait(timeout) 23:06:38 INFO - File "/builds/slave/test-pgo/build/venv/local/lib/python2.7/site-packages/psutil/_pslinux.py", line 709, in wrapper 23:06:38 INFO - return fun(self, *args, **kwargs) 23:06:38 INFO - File "/builds/slave/test-pgo/build/venv/local/lib/python2.7/site-packages/psutil/_pslinux.py", line 851, in wait 23:06:38 INFO - raise TimeoutExpired(timeout, self.pid, self._name) 23:06:38 INFO - TimeoutExpired: psutil.TimeoutExpired timeout after 3 seconds (pid=1680, name='firefox') 23:06:38 INFO - TEST-INFO took 136991ms 23:06:38 INFO - SUITE-END | took 1322s 23:06:38 ERROR - Return code: 2 23:06:38 WARNING - setting return code to 2 23:06:38 ERROR - # TBPL FAILURE # 23:06:38 INFO - Running post-action listener: _resource_record_post_action 23:06:38 INFO - Running post-run listener: _resource_record_post_run 23:06:39 INFO - Total resource usage - Wall time: 1340s; CPU: 14.0%; Read bytes: 4096; Write bytes: 659435520; Read time: 1372; Write time: 12122044 23:06:39 INFO - install - Wall time: 16s; CPU: 28.0%; Read bytes: 0; Write bytes: 139165696; Read time: 0; Write time: 1428748 23:06:39 INFO - run-tests - Wall time: 1324s; CPU: 13.0%; Read bytes: 4096; Write bytes: 520269824; Read time: 1372; Write time: 10693296 23:06:39 INFO - Running post-run listener: _upload_blobber_files 23:06:39 INFO - Blob upload gear active. 23:06:39 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 23:06:39 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:06:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 23:06:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Fx-Team -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 23:06:39 INFO - (blobuploader) - INFO - Open directory for files ... 23:06:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 23:06:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:06:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:40 INFO - (blobuploader) - INFO - Done attempting. 23:06:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:06:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:06:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:06:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:06:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:06:41 INFO - (blobuploader) - INFO - Done attempting. 23:06:41 INFO - (blobuploader) - INFO - Iteration through files over. 23:06:41 INFO - Return code: 0 23:06:41 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 23:06:41 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 23:06:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ce06daeb6cebd7c4b6def916d739cc364f10ba01469a3a417ffea0e2616bd1bb08f832df3fe3c61c9bb39d23bcebfeb8d0298cb27a22f01c14a70dc9b4799eb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ec80f0186dcaf397d1a343e33057244375e6b8e796d11c4dacf37c2ff631c712abeeeb4b79ca54c98f1c8b9f4674faafa9bdf668c5aba403f9c720b93a619aa1"} 23:06:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 23:06:41 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 23:06:41 INFO - Contents: 23:06:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ce06daeb6cebd7c4b6def916d739cc364f10ba01469a3a417ffea0e2616bd1bb08f832df3fe3c61c9bb39d23bcebfeb8d0298cb27a22f01c14a70dc9b4799eb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ec80f0186dcaf397d1a343e33057244375e6b8e796d11c4dacf37c2ff631c712abeeeb4b79ca54c98f1c8b9f4674faafa9bdf668c5aba403f9c720b93a619aa1"} 23:06:41 INFO - Running post-run listener: copy_logs_to_upload_dir 23:06:41 INFO - Copying logs to upload dir... 23:06:41 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs 23:06:41 INFO - Copying logs to upload dir... 23:06:41 WARNING - returning nonzero exit status 2 program finished with exit code 2 elapsedTime=1401.068677 ========= master_lag: 12.61 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 23 mins, 33 secs) (at 2016-03-03 23:06:53.793740) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-03 23:06:53.797118) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ce06daeb6cebd7c4b6def916d739cc364f10ba01469a3a417ffea0e2616bd1bb08f832df3fe3c61c9bb39d23bcebfeb8d0298cb27a22f01c14a70dc9b4799eb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ec80f0186dcaf397d1a343e33057244375e6b8e796d11c4dacf37c2ff631c712abeeeb4b79ca54c98f1c8b9f4674faafa9bdf668c5aba403f9c720b93a619aa1"} build_url:https://queue.taskcluster.net/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011438 build_url: 'https://queue.taskcluster.net/v1/task/Rfm4VLMpSEKTteC8pSIKCQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ce06daeb6cebd7c4b6def916d739cc364f10ba01469a3a417ffea0e2616bd1bb08f832df3fe3c61c9bb39d23bcebfeb8d0298cb27a22f01c14a70dc9b4799eb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ec80f0186dcaf397d1a343e33057244375e6b8e796d11c4dacf37c2ff631c712abeeeb4b79ca54c98f1c8b9f4674faafa9bdf668c5aba403f9c720b93a619aa1"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-03 23:06:53.861059) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-03-03 23:06:53.861426) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1457072494.678403-867175226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004540 ========= master_lag: 1.61 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-03-03 23:06:55.471245) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-03 23:06:55.471607) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-03 23:06:55.613581) ========= ========= Total master_lag: 14.49 =========