builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-031 starttime: 1452615025.18 results: success (0) buildid: 20160112053004 builduid: 4e88bd89faa146699ce4d7e005753824 revision: eae9f44f7444407a28ab3b876032eceebe19310c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.176564) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.177155) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.177541) ========= 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=db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006388 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.207097) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.207417) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.223445) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.223715) ========= 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=db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004608 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.280888) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.281194) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.281580) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.281861) ========= 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=db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-12 08:10:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 225M=0s 2016-01-12 08:10:25 (225 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.164524 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.470843) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.471212) ========= 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=db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017059 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.521007) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:25.521544) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev eae9f44f7444407a28ab3b876032eceebe19310c --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 eae9f44f7444407a28ab3b876032eceebe19310c --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=db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-12 08:10:25,954 truncating revision to first 12 chars 2016-01-12 08:10:25,954 Setting DEBUG logging. 2016-01-12 08:10:25,955 attempt 1/10 2016-01-12 08:10:25,955 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/eae9f44f7444?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-12 08:10:26,252 unpacking tar archive at: fx-team-eae9f44f7444/testing/mozharness/ program finished with exit code 0 elapsedTime=0.649031 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:26.183614) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:26.183926) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:26.202367) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:26.202634) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-12 08:10:26.203020) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2016-01-12 08:10:26.203331) ========= /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=db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:10:26 INFO - MultiFileLogger online at 20160112 08:10:26 in /builds/slave/test-pgo 08:10:26 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 08:10:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 08:10:26 INFO - {'append_to_log': False, 08:10:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 08:10:26 INFO - 'blob_upload_branch': 'Fx-Team', 08:10:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 08:10:26 INFO - 'branch': 'Fx-Team', 08:10:26 INFO - 'buildbot_json_path': 'buildprops.json', 08:10:26 INFO - 'config_files': ('talos/linux_config.py',), 08:10:26 INFO - 'default_actions': ('clobber', 08:10:26 INFO - 'read-buildbot-config', 08:10:26 INFO - 'download-and-extract', 08:10:26 INFO - 'populate-webroot', 08:10:26 INFO - 'create-virtualenv', 08:10:26 INFO - 'install', 08:10:26 INFO - 'run-tests'), 08:10:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:10:26 INFO - 'download_minidump_stackwalk': True, 08:10:26 INFO - 'download_symbols': 'ondemand', 08:10:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:10:26 INFO - 'tooltool.py': '/tools/tooltool.py', 08:10:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:10:26 INFO - '/tools/misc-python/virtualenv.py')}, 08:10:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:10:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:10:26 INFO - 'installer_path': 'installer.exe', 08:10:26 INFO - 'log_level': 'info', 08:10:26 INFO - 'log_name': 'talos', 08:10:26 INFO - 'log_to_console': True, 08:10:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:10:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:10:26 INFO - 'opt_config_files': (), 08:10:26 INFO - 'pip_index': False, 08:10:26 INFO - 'sps_profile': False, 08:10:26 INFO - 'sps_profile_interval': 0, 08:10:26 INFO - 'suite': 'dromaeojs', 08:10:26 INFO - 'system_bits': '32', 08:10:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:10:26 INFO - 'title': 'talos-linux64-ix-031', 08:10:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:10:26 INFO - 'use_talos_json': True, 08:10:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 08:10:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:10:26 INFO - 'work_dir': 'build'} 08:10:26 INFO - ##### 08:10:26 INFO - ##### Running clobber step. 08:10:26 INFO - ##### 08:10:26 INFO - Running pre-action listener: _resource_record_pre_action 08:10:26 INFO - Running main action method: clobber 08:10:26 INFO - rmtree: /builds/slave/test-pgo/build 08:10:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 08:10:27 INFO - Running post-action listener: _resource_record_post_action 08:10:27 INFO - ##### 08:10:27 INFO - ##### Running read-buildbot-config step. 08:10:27 INFO - ##### 08:10:27 INFO - Running pre-action listener: _resource_record_pre_action 08:10:27 INFO - Running main action method: read_buildbot_config 08:10:27 INFO - Using buildbot properties: 08:10:27 INFO - { 08:10:27 INFO - "project": "", 08:10:27 INFO - "product": "firefox", 08:10:27 INFO - "script_repo_revision": "production", 08:10:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:10:27 INFO - "repository": "", 08:10:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 08:10:27 INFO - "buildid": "20160112053004", 08:10:27 INFO - "pgo_build": "True", 08:10:27 INFO - "basedir": "/builds/slave/test-pgo", 08:10:27 INFO - "buildnumber": 890, 08:10:27 INFO - "slavename": "talos-linux64-ix-031", 08:10:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:10:27 INFO - "platform": "ubuntu64_hw", 08:10:27 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 08:10:27 INFO - "branch": "fx-team", 08:10:27 INFO - "repo_path": "integration/fx-team", 08:10:27 INFO - "revision": "eae9f44f7444407a28ab3b876032eceebe19310c", 08:10:27 INFO - "stage_platform": "linux64-pgo", 08:10:27 INFO - "builduid": "4e88bd89faa146699ce4d7e005753824", 08:10:27 INFO - "slavebuilddir": "test" 08:10:27 INFO - } 08:10:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 08:10:27 INFO - Running post-action listener: _resource_record_post_action 08:10:27 INFO - ##### 08:10:27 INFO - ##### Running download-and-extract step. 08:10:27 INFO - ##### 08:10:27 INFO - Running pre-action listener: _resource_record_pre_action 08:10:27 INFO - Running main action method: download_and_extract 08:10:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 08:10:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:27 INFO - https://queue.taskcluster.net/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 08:10:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/test_packages.json 08:10:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/test_packages.json 08:10:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 08:10:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 08:10:35 INFO - Downloaded 1302 bytes. 08:10:35 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 08:10:35 INFO - Using the following test package requirements: 08:10:35 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 08:10:35 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:10:35 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:35 INFO - u'jsshell-linux-x86_64.zip'], 08:10:35 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:10:35 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 08:10:35 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:10:35 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:10:35 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:10:35 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 08:10:35 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 08:10:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:10:35 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:35 INFO - https://queue.taskcluster.net/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:10:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:10:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:10:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:10:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:10:48 INFO - Downloaded 24027118 bytes. 08:10:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 08:10:48 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:10:48 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 08:10:50 INFO - Return code: 0 08:10:50 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:10:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:50 INFO - https://queue.taskcluster.net/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:10:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:10:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:10:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:10:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:10:53 INFO - Downloaded 24027118 bytes. 08:10:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 08:10:53 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 08:10:53 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 08:10:54 INFO - Return code: 0 08:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:54 INFO - https://queue.taskcluster.net/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:10:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 08:10:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 08:10:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 08:10:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:10:57 INFO - Downloaded 11132285 bytes. 08:10:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 08:10:57 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 08:10:57 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 08:10:57 INFO - Return code: 0 08:10:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:57 INFO - https://queue.taskcluster.net/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:10:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:10:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:10:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:10:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:11:04 INFO - Downloaded 55735769 bytes. 08:11:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:11:04 INFO - mkdir: /builds/slave/test-pgo/properties 08:11:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 08:11:04 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 08:11:04 INFO - Contents: 08:11:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 08:11:04 INFO - Running post-action listener: _resource_record_post_action 08:11:04 INFO - Running post-action listener: set_extra_try_arguments 08:11:04 INFO - ##### 08:11:04 INFO - ##### Running populate-webroot step. 08:11:04 INFO - ##### 08:11:04 INFO - Running pre-action listener: _resource_record_pre_action 08:11:04 INFO - Running main action method: populate_webroot 08:11:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:11:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:11:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:11:04 INFO - '--tppagecycles', 08:11:04 INFO - '7'], 08:11:04 INFO - 'tests': ['tsvgm']}}, 08:11:04 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 08:11:04 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 08:11:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:11:04 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:11:04 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 08:11:04 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 08:11:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:04 INFO - 'talos_options': ['--e10s'], 08:11:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:11:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:04 INFO - 'tests': ['damp', 'tps']}, 08:11:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:04 INFO - 'talos_options': ['--e10s'], 08:11:04 INFO - 'tests': ['damp', 'tps']}, 08:11:04 INFO - 'g3': {'tests': ['dromaeo_dom']}, 08:11:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:11:04 INFO - 'other': {'tests': ['a11yr', 08:11:04 INFO - 'ts_paint', 08:11:04 INFO - 'tpaint', 08:11:04 INFO - 'sessionrestore', 08:11:04 INFO - 'sessionrestore_no_auto_restore']}, 08:11:04 INFO - 'other-e10s': {'talos_options': ['--e10s'], 08:11:04 INFO - 'tests': ['a11yr', 08:11:04 INFO - 'ts_paint', 08:11:04 INFO - 'tpaint', 08:11:04 INFO - 'sessionrestore', 08:11:04 INFO - 'sessionrestore_no_auto_restore']}, 08:11:04 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 08:11:04 INFO - 'tests': ['a11yr', 08:11:04 INFO - 'ts_paint', 08:11:04 INFO - 'tpaint', 08:11:04 INFO - 'sessionrestore', 08:11:04 INFO - 'sessionrestore_no_auto_restore']}, 08:11:04 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 08:11:04 INFO - 'tests': ['a11yr', 08:11:04 INFO - 'ts_paint', 08:11:04 INFO - 'tpaint', 08:11:04 INFO - 'sessionrestore', 08:11:04 INFO - 'sessionrestore_no_auto_restore']}, 08:11:04 INFO - 'other_l64': {'tests': ['a11yr', 08:11:04 INFO - 'ts_paint', 08:11:04 INFO - 'tpaint', 08:11:04 INFO - 'sessionrestore', 08:11:04 INFO - 'sessionrestore_no_auto_restore']}, 08:11:04 INFO - 'other_nol64': {'tests': ['a11yr', 08:11:04 INFO - 'ts_paint', 08:11:04 INFO - 'tpaint', 08:11:04 INFO - 'sessionrestore', 08:11:04 INFO - 'sessionrestore_no_auto_restore']}, 08:11:04 INFO - 'svgr': {'tests': ['tsvgx', 08:11:04 INFO - 'tsvgr_opacity', 08:11:04 INFO - 'tart', 08:11:04 INFO - 'tscrollx', 08:11:04 INFO - 'cart']}, 08:11:04 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 08:11:04 INFO - 'tests': ['tsvgx', 08:11:04 INFO - 'tsvgr_opacity', 08:11:04 INFO - 'tart', 08:11:04 INFO - 'tscrollx', 08:11:04 INFO - 'cart']}, 08:11:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:04 INFO - 'tests': ['tp5o']}, 08:11:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:04 INFO - 'talos_options': ['--e10s'], 08:11:04 INFO - 'tests': ['tp5o']}, 08:11:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:04 INFO - 'talos_options': ['--xperf_path', 08:11:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:04 INFO - 'tests': ['tp5n']}, 08:11:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:11:04 INFO - 'talos_options': ['--e10s', 08:11:04 INFO - '--xperf_path', 08:11:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:11:04 INFO - 'tests': ['tp5n']}}, 08:11:04 INFO - 'talos.zip': {'path': '', 08:11:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:11:04 INFO - Running post-action listener: _resource_record_post_action 08:11:04 INFO - ##### 08:11:04 INFO - ##### Running create-virtualenv step. 08:11:04 INFO - ##### 08:11:04 INFO - Running pre-action listener: _resource_record_pre_action 08:11:04 INFO - Running main action method: create_virtualenv 08:11:04 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 08:11:04 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 08:11:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 08:11:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:11:04 INFO - Using real prefix '/usr' 08:11:04 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 08:11:05 INFO - Installing distribute.............................................................................................................................................................................................done. 08:11:07 INFO - Installing pip.................done. 08:11:07 INFO - Return code: 0 08:11:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 08:11:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe848933f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x182e480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bd660>, '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 0x18be560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18bea10>, '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': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 08:11:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:11:07 INFO - Using env: {'DISPLAY': ':0', 08:11:07 INFO - 'HOME': '/home/cltbld', 08:11:07 INFO - 'LANG': 'en_US.UTF-8', 08:11:07 INFO - 'LANGUAGE': 'en_US:en', 08:11:07 INFO - 'LOGNAME': 'cltbld', 08:11:07 INFO - 'MAIL': '/var/mail/cltbld', 08:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:07 INFO - 'MOZ_NO_REMOTE': '1', 08:11:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:07 INFO - 'NO_EM_RESTART': '1', 08:11:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:11:07 INFO - 'PWD': '/builds/slave/test-pgo', 08:11:07 INFO - 'SHELL': '/bin/bash', 08:11:07 INFO - 'SHLVL': '1', 08:11:07 INFO - 'TERM': 'linux', 08:11:07 INFO - 'TMOUT': '86400', 08:11:07 INFO - 'USER': 'cltbld', 08:11:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 08:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:07 INFO - '_': '/tools/buildbot/bin/python'} 08:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:11:07 INFO - Cleaning up... 08:11:07 INFO - Return code: 0 08:11:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 08:11:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe848933f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x182e480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bd660>, '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 0x18be560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18bea10>, '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': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 08:11:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 08:11:07 INFO - Using env: {'DISPLAY': ':0', 08:11:07 INFO - 'HOME': '/home/cltbld', 08:11:07 INFO - 'LANG': 'en_US.UTF-8', 08:11:07 INFO - 'LANGUAGE': 'en_US:en', 08:11:07 INFO - 'LOGNAME': 'cltbld', 08:11:07 INFO - 'MAIL': '/var/mail/cltbld', 08:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:07 INFO - 'MOZ_NO_REMOTE': '1', 08:11:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:07 INFO - 'NO_EM_RESTART': '1', 08:11:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:11:07 INFO - 'PWD': '/builds/slave/test-pgo', 08:11:07 INFO - 'SHELL': '/bin/bash', 08:11:07 INFO - 'SHLVL': '1', 08:11:07 INFO - 'TERM': 'linux', 08:11:07 INFO - 'TMOUT': '86400', 08:11:07 INFO - 'USER': 'cltbld', 08:11:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 08:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:07 INFO - '_': '/tools/buildbot/bin/python'} 08:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:07 INFO - Downloading/unpacking psutil>=0.7.1 08:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:09 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 08:11:09 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 08:11:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 08:11:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:09 INFO - Installing collected packages: psutil 08:11:09 INFO - Running setup.py install for psutil 08:11:09 INFO - building 'psutil._psutil_linux' extension 08:11:09 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:11:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:11:09 INFO - building 'psutil._psutil_posix' extension 08:11:09 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:11:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:11:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:11:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:11:10 INFO - Successfully installed psutil 08:11:10 INFO - Cleaning up... 08:11:10 INFO - Return code: 0 08:11:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 08:11:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe848933f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x182e480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bd660>, '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 0x18be560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18bea10>, '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': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 08:11:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:11:10 INFO - Using env: {'DISPLAY': ':0', 08:11:10 INFO - 'HOME': '/home/cltbld', 08:11:10 INFO - 'LANG': 'en_US.UTF-8', 08:11:10 INFO - 'LANGUAGE': 'en_US:en', 08:11:10 INFO - 'LOGNAME': 'cltbld', 08:11:10 INFO - 'MAIL': '/var/mail/cltbld', 08:11:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:10 INFO - 'MOZ_NO_REMOTE': '1', 08:11:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:10 INFO - 'NO_EM_RESTART': '1', 08:11:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:11:10 INFO - 'PWD': '/builds/slave/test-pgo', 08:11:10 INFO - 'SHELL': '/bin/bash', 08:11:10 INFO - 'SHLVL': '1', 08:11:10 INFO - 'TERM': 'linux', 08:11:10 INFO - 'TMOUT': '86400', 08:11:10 INFO - 'USER': 'cltbld', 08:11:10 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 08:11:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:10 INFO - '_': '/tools/buildbot/bin/python'} 08:11:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:11:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 08:11:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:11:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:11:12 INFO - Installing collected packages: mozsystemmonitor 08:11:12 INFO - Running setup.py install for mozsystemmonitor 08:11:12 INFO - Successfully installed mozsystemmonitor 08:11:12 INFO - Cleaning up... 08:11:12 INFO - Return code: 0 08:11:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 08:11: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')]} 08:11:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11: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')]} 08:11:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:12 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe848933f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x182e480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bd660>, '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 0x18be560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18bea10>, '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': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 08:11:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:11:12 INFO - Using env: {'DISPLAY': ':0', 08:11:12 INFO - 'HOME': '/home/cltbld', 08:11:12 INFO - 'LANG': 'en_US.UTF-8', 08:11:12 INFO - 'LANGUAGE': 'en_US:en', 08:11:12 INFO - 'LOGNAME': 'cltbld', 08:11:12 INFO - 'MAIL': '/var/mail/cltbld', 08:11:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:12 INFO - 'MOZ_NO_REMOTE': '1', 08:11:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:12 INFO - 'NO_EM_RESTART': '1', 08:11:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:11:12 INFO - 'PWD': '/builds/slave/test-pgo', 08:11:12 INFO - 'SHELL': '/bin/bash', 08:11:12 INFO - 'SHLVL': '1', 08:11:12 INFO - 'TERM': 'linux', 08:11:12 INFO - 'TMOUT': '86400', 08:11:12 INFO - 'USER': 'cltbld', 08:11:12 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 08:11:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:12 INFO - '_': '/tools/buildbot/bin/python'} 08:11:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:12 INFO - Downloading/unpacking blobuploader==1.2.4 08:11: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 08:11: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 08:11: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 08:11: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 08:11: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 08:11: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 08:11:14 INFO - Downloading blobuploader-1.2.4.tar.gz 08:11:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 08:11:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:11:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:11: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 08:11: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 08:11: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 08:11: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 08:11: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 08:11: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 08:11:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 08:11:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 08:11:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:11: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 08:11: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 08:11: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 08:11: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 08:11: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 08:11: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 08:11:14 INFO - Downloading docopt-0.6.1.tar.gz 08:11:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 08:11:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 08:11:14 INFO - Installing collected packages: blobuploader, requests, docopt 08:11:14 INFO - Running setup.py install for blobuploader 08:11:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:11:14 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 08:11:14 INFO - Running setup.py install for requests 08:11:15 INFO - Running setup.py install for docopt 08:11:15 INFO - Successfully installed blobuploader requests docopt 08:11:15 INFO - Cleaning up... 08:11:15 INFO - Return code: 0 08:11:15 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:15 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe848933f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x182e480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bd660>, '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 0x18be560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18bea10>, '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': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:15 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 08:11:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:11:15 INFO - Using env: {'DISPLAY': ':0', 08:11:15 INFO - 'HOME': '/home/cltbld', 08:11:15 INFO - 'LANG': 'en_US.UTF-8', 08:11:15 INFO - 'LANGUAGE': 'en_US:en', 08:11:15 INFO - 'LOGNAME': 'cltbld', 08:11:15 INFO - 'MAIL': '/var/mail/cltbld', 08:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:15 INFO - 'MOZ_NO_REMOTE': '1', 08:11:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:15 INFO - 'NO_EM_RESTART': '1', 08:11:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:11:15 INFO - 'PWD': '/builds/slave/test-pgo', 08:11:15 INFO - 'SHELL': '/bin/bash', 08:11:15 INFO - 'SHLVL': '1', 08:11:15 INFO - 'TERM': 'linux', 08:11:15 INFO - 'TMOUT': '86400', 08:11:15 INFO - 'USER': 'cltbld', 08:11:15 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 08:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:15 INFO - '_': '/tools/buildbot/bin/python'} 08:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:11:15 INFO - Running setup.py (path:/tmp/pip-SYRbPv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:11:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:11:15 INFO - Running setup.py (path:/tmp/pip-AIwY9A-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:11:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:11:15 INFO - Running setup.py (path:/tmp/pip-3HfCG9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:11:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:11:15 INFO - Running setup.py (path:/tmp/pip-658On_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:11:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:11:15 INFO - Running setup.py (path:/tmp/pip-VUS5YU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:11:15 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:11:16 INFO - Running setup.py (path:/tmp/pip-KcPuNl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:11:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:11:16 INFO - Running setup.py (path:/tmp/pip-bIaHLS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:11:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:11:16 INFO - Running setup.py (path:/tmp/pip-pbh43X-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:11:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:11:16 INFO - Running setup.py (path:/tmp/pip-auvWWK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:11:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:11:16 INFO - Running setup.py (path:/tmp/pip-ssXX1p-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:11:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:11:16 INFO - Running setup.py (path:/tmp/pip-Njpm9F-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:11:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:11:16 INFO - Running setup.py (path:/tmp/pip-wyYn8W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:11:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:11:16 INFO - Running setup.py (path:/tmp/pip-TXI6xl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:11:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:11:16 INFO - Running setup.py (path:/tmp/pip-Z6Xs5O-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:11:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:11:16 INFO - Running setup.py (path:/tmp/pip-G8pvcX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:11:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:11:16 INFO - Running setup.py (path:/tmp/pip-zKAl4S-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:11:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:11:16 INFO - Running setup.py (path:/tmp/pip-L9ixmq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:11:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:11:16 INFO - Running setup.py install for manifestparser 08:11:16 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 08:11:16 INFO - Running setup.py install for mozcrash 08:11:17 INFO - Running setup.py install for mozdebug 08:11:17 INFO - Running setup.py install for mozdevice 08:11:17 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 08:11:17 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 08:11:17 INFO - Running setup.py install for mozfile 08:11:17 INFO - Running setup.py install for mozhttpd 08:11:17 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 08:11:17 INFO - Running setup.py install for mozinfo 08:11:17 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 08:11:17 INFO - Running setup.py install for mozInstall 08:11:17 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 08:11:17 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 08:11:17 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 08:11:17 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 08:11:17 INFO - Running setup.py install for mozleak 08:11:17 INFO - Running setup.py install for mozlog 08:11:17 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 08:11:17 INFO - Running setup.py install for moznetwork 08:11:17 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 08:11:17 INFO - Running setup.py install for mozprocess 08:11:18 INFO - Running setup.py install for mozprofile 08:11:18 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 08:11:18 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 08:11:18 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 08:11:18 INFO - Running setup.py install for mozrunner 08:11:18 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 08:11:18 INFO - Running setup.py install for mozscreenshot 08:11:18 INFO - Running setup.py install for moztest 08:11:18 INFO - Running setup.py install for mozversion 08:11:18 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 08:11:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:11:18 INFO - Cleaning up... 08:11:18 INFO - Return code: 0 08:11:18 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:18 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe848933f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x182e480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bd660>, '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 0x18be560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18bea10>, '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': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 08:11:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:11:18 INFO - Using env: {'DISPLAY': ':0', 08:11:18 INFO - 'HOME': '/home/cltbld', 08:11:18 INFO - 'LANG': 'en_US.UTF-8', 08:11:18 INFO - 'LANGUAGE': 'en_US:en', 08:11:18 INFO - 'LOGNAME': 'cltbld', 08:11:18 INFO - 'MAIL': '/var/mail/cltbld', 08:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:18 INFO - 'MOZ_NO_REMOTE': '1', 08:11:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:18 INFO - 'NO_EM_RESTART': '1', 08:11:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:11:18 INFO - 'PWD': '/builds/slave/test-pgo', 08:11:18 INFO - 'SHELL': '/bin/bash', 08:11:18 INFO - 'SHLVL': '1', 08:11:18 INFO - 'TERM': 'linux', 08:11:18 INFO - 'TMOUT': '86400', 08:11:18 INFO - 'USER': 'cltbld', 08:11:18 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 08:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:18 INFO - '_': '/tools/buildbot/bin/python'} 08:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:11:18 INFO - Running setup.py (path:/tmp/pip-WB4I6y-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 08:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 08:11:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:11:18 INFO - Running setup.py (path:/tmp/pip-yvVTri-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 08:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:11:18 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:11:18 INFO - Running setup.py (path:/tmp/pip-CMrgPo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:11:19 INFO - Running setup.py (path:/tmp/pip-642SPy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 08:11:19 INFO - Running setup.py (path:/tmp/pip-CzCgly-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:11:19 INFO - Running setup.py (path:/tmp/pip-xNSCca-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:11:19 INFO - Running setup.py (path:/tmp/pip-Ajcrup-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:11:19 INFO - Running setup.py (path:/tmp/pip-OMPKc0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 08:11:19 INFO - Running setup.py (path:/tmp/pip-5Mt3M9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 08:11:19 INFO - Running setup.py (path:/tmp/pip-n2tsBO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:11:19 INFO - Running setup.py (path:/tmp/pip-aD5rMJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:11:19 INFO - Running setup.py (path:/tmp/pip-AaZ0Ac-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:11:19 INFO - Running setup.py (path:/tmp/pip-iuBbxU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:11:19 INFO - Running setup.py (path:/tmp/pip-j9LIQb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:11:19 INFO - Running setup.py (path:/tmp/pip-nzpSfG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 08:11:19 INFO - Running setup.py (path:/tmp/pip-DIKzBC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 08:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 08:11:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 08:11:20 INFO - Running setup.py (path:/tmp/pip-eQn_cI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 08:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 08:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 08:11:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 08:11:20 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.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:11:20 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.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 08:11:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 08:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:21 INFO - Downloading blessings-1.5.1.tar.gz 08:11:21 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 08:11:21 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 08:11:21 INFO - Installing collected packages: blessings 08:11:21 INFO - Running setup.py install for blessings 08:11:21 INFO - Successfully installed blessings 08:11:21 INFO - Cleaning up... 08:11:22 INFO - Return code: 0 08:11:22 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 08:11:22 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:11:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:11:22 INFO - Reading from file tmpfile_stdout 08:11:22 INFO - Current package versions: 08:11:22 INFO - argparse == 1.2.1 08:11:22 INFO - blessings == 1.5.1 08:11:22 INFO - blobuploader == 1.2.4 08:11:22 INFO - docopt == 0.6.1 08:11:22 INFO - manifestparser == 1.1 08:11:22 INFO - mozInstall == 1.12 08:11:22 INFO - mozcrash == 0.16 08:11:22 INFO - mozdebug == 0.1 08:11:22 INFO - mozdevice == 0.47 08:11:22 INFO - mozfile == 1.2 08:11:22 INFO - mozhttpd == 0.7 08:11:22 INFO - mozinfo == 0.9 08:11:22 INFO - mozleak == 0.1 08:11:22 INFO - mozlog == 3.1 08:11:22 INFO - moznetwork == 0.27 08:11:22 INFO - mozprocess == 0.22 08:11:22 INFO - mozprofile == 0.28 08:11:22 INFO - mozrunner == 6.11 08:11:22 INFO - mozscreenshot == 0.1 08:11:22 INFO - mozsystemmonitor == 0.0 08:11:22 INFO - moztest == 0.7 08:11:22 INFO - mozversion == 1.4 08:11:22 INFO - psutil == 3.1.1 08:11:22 INFO - requests == 1.2.3 08:11:22 INFO - wsgiref == 0.1.2 08:11:22 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 08:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:22 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe848933f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x182e480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bd660>, '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 0x18be560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18bea10>, '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': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 08:11:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:11:22 INFO - Using env: {'DISPLAY': ':0', 08:11:22 INFO - 'HOME': '/home/cltbld', 08:11:22 INFO - 'LANG': 'en_US.UTF-8', 08:11:22 INFO - 'LANGUAGE': 'en_US:en', 08:11:22 INFO - 'LOGNAME': 'cltbld', 08:11:22 INFO - 'MAIL': '/var/mail/cltbld', 08:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:22 INFO - 'MOZ_NO_REMOTE': '1', 08:11:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:22 INFO - 'NO_EM_RESTART': '1', 08:11:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:11:22 INFO - 'PWD': '/builds/slave/test-pgo', 08:11:22 INFO - 'SHELL': '/bin/bash', 08:11:22 INFO - 'SHLVL': '1', 08:11:22 INFO - 'TERM': 'linux', 08:11:22 INFO - 'TMOUT': '86400', 08:11:22 INFO - 'USER': 'cltbld', 08:11:22 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 08:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:22 INFO - '_': '/tools/buildbot/bin/python'} 08:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 08:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 08:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 08:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 08:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 08:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 08:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 08:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 08:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 9)) 08:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 08:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 08:11:22 INFO - Cleaning up... 08:11:22 INFO - Return code: 0 08:11:22 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 08:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:22 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe848933f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x182e480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bd660>, '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 0x18be560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18bea10>, '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': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:22 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 08:11:22 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:11:22 INFO - Using env: {'DISPLAY': ':0', 08:11:22 INFO - 'HOME': '/home/cltbld', 08:11:22 INFO - 'LANG': 'en_US.UTF-8', 08:11:22 INFO - 'LANGUAGE': 'en_US:en', 08:11:22 INFO - 'LOGNAME': 'cltbld', 08:11:22 INFO - 'MAIL': '/var/mail/cltbld', 08:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:22 INFO - 'MOZ_NO_REMOTE': '1', 08:11:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:22 INFO - 'NO_EM_RESTART': '1', 08:11:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:11:22 INFO - 'PWD': '/builds/slave/test-pgo', 08:11:22 INFO - 'SHELL': '/bin/bash', 08:11:22 INFO - 'SHLVL': '1', 08:11:22 INFO - 'TERM': 'linux', 08:11:22 INFO - 'TMOUT': '86400', 08:11:22 INFO - 'USER': 'cltbld', 08:11:22 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 08:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:22 INFO - '_': '/tools/buildbot/bin/python'} 08:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:22 INFO - Downloading/unpacking jsonschema 08:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:11:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 08:11:24 INFO - Downloading/unpacking functools32 (from jsonschema) 08:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:24 INFO - Downloading functools32-3.2.3-2.tar.gz 08:11:24 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 08:11:24 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 08:11:24 INFO - warning: no files found matching '*.txt' 08:11:24 INFO - no previously-included directories found matching 'build' 08:11:24 INFO - no previously-included directories found matching 'dist' 08:11:24 INFO - no previously-included directories found matching '.git*' 08:11:24 INFO - Installing collected packages: jsonschema, functools32 08:11:24 INFO - Running setup.py install for functools32 08:11:24 INFO - warning: no files found matching '*.txt' 08:11:24 INFO - no previously-included directories found matching 'build' 08:11:24 INFO - no previously-included directories found matching 'dist' 08:11:24 INFO - no previously-included directories found matching '.git*' 08:11:24 INFO - Successfully installed jsonschema functools32 08:11:24 INFO - Cleaning up... 08:11:24 INFO - Return code: 0 08:11:24 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 08:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:11:24 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe848933f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17618a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x182e480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18bd660>, '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 0x18be560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18bea10>, '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': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:11:24 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 08:11:24 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 08:11:24 INFO - Using env: {'DISPLAY': ':0', 08:11:24 INFO - 'HOME': '/home/cltbld', 08:11:24 INFO - 'LANG': 'en_US.UTF-8', 08:11:24 INFO - 'LANGUAGE': 'en_US:en', 08:11:24 INFO - 'LOGNAME': 'cltbld', 08:11:24 INFO - 'MAIL': '/var/mail/cltbld', 08:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:24 INFO - 'MOZ_NO_REMOTE': '1', 08:11:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:24 INFO - 'NO_EM_RESTART': '1', 08:11:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:11:24 INFO - 'PWD': '/builds/slave/test-pgo', 08:11:24 INFO - 'SHELL': '/bin/bash', 08:11:24 INFO - 'SHLVL': '1', 08:11:24 INFO - 'TERM': 'linux', 08:11:24 INFO - 'TMOUT': '86400', 08:11:24 INFO - 'USER': 'cltbld', 08:11:24 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 08:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:24 INFO - '_': '/tools/buildbot/bin/python'} 08:11:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:11:25 INFO - Downloading/unpacking flake8 08:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:26 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:11:26 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 08:11:26 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:11:26 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 08:11:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:27 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 08:11:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:11:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:27 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 08:11:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 08:11:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:11:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:11:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:11:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:11:27 INFO - no previously-included directories found matching 'docs/_build' 08:11:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:11:27 INFO - Running setup.py install for pep8 08:11:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:11:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:11:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:11:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:11:27 INFO - no previously-included directories found matching 'docs/_build' 08:11:27 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 08:11:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:11:27 INFO - Cleaning up... 08:11:27 INFO - Return code: 0 08:11:27 INFO - Running post-action listener: _resource_record_post_action 08:11:27 INFO - Running post-action listener: _start_resource_monitoring 08:11:27 INFO - Starting resource monitoring. 08:11:27 INFO - ##### 08:11:27 INFO - ##### Running install step. 08:11:27 INFO - ##### 08:11:27 INFO - Running pre-action listener: _resource_record_pre_action 08:11:27 INFO - Running main action method: install 08:11:27 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 08:11:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 08:11:27 INFO - Reading from file tmpfile_stdout 08:11:27 INFO - Detecting whether we're running mozinstall >=1.0... 08:11:27 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 08:11:27 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 08:11:28 INFO - Reading from file tmpfile_stdout 08:11:28 INFO - Output received: 08:11:28 INFO - Usage: mozinstall [options] installer 08:11:28 INFO - Options: 08:11:28 INFO - -h, --help show this help message and exit 08:11:28 INFO - -d DEST, --destination=DEST 08:11:28 INFO - Directory to install application into. [default: 08:11:28 INFO - "/builds/slave/test-pgo"] 08:11:28 INFO - --app=APP Application being installed. [default: firefox] 08:11:28 INFO - mkdir: /builds/slave/test-pgo/build/application 08:11:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 08:11:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 08:11:44 INFO - Reading from file tmpfile_stdout 08:11:44 INFO - Output received: 08:11:44 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 08:11:44 INFO - Running post-action listener: _resource_record_post_action 08:11:44 INFO - ##### 08:11:44 INFO - ##### Running run-tests step. 08:11:44 INFO - ##### 08:11:44 INFO - Running pre-action listener: _resource_record_pre_action 08:11:44 INFO - Running main action method: run_tests 08:11:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 08:11:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 08:11:44 INFO - Python 2.7.3 08:11:44 INFO - Return code: 0 08:11:44 INFO - grabbing minidump binary from tooltool 08:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:44 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 0x18bd660>, '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 0x18be560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18bea10>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 08:11:44 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 08:11:44 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:11:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:11:44 INFO - Return code: 0 08:11:44 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 08:11:44 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 08:11:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 08:11:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 08:11:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 08:11:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 08:11:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 08:11:45 INFO - Return code: 0 08:11:45 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/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 08:11:45 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/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 08:11:45 INFO - Using env: {'DISPLAY': ':0', 08:11:45 INFO - 'HOME': '/home/cltbld', 08:11:45 INFO - 'LANG': 'en_US.UTF-8', 08:11:45 INFO - 'LANGUAGE': 'en_US:en', 08:11:45 INFO - 'LOGNAME': 'cltbld', 08:11:45 INFO - 'MAIL': '/var/mail/cltbld', 08:11:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:11:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 08:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:45 INFO - 'MOZ_NO_REMOTE': '1', 08:11:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 08:11:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:11:45 INFO - 'NO_EM_RESTART': '1', 08:11:45 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 08:11:45 INFO - 'PWD': '/builds/slave/test-pgo', 08:11:45 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 08:11:45 INFO - 'SHELL': '/bin/bash', 08:11:45 INFO - 'SHLVL': '1', 08:11:45 INFO - 'TERM': 'linux', 08:11:45 INFO - 'TMOUT': '86400', 08:11:45 INFO - 'USER': 'cltbld', 08:11:45 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127', 08:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:45 INFO - '_': '/tools/buildbot/bin/python'} 08:11:45 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/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 08:11:45 INFO - mozversion application_buildid: 20160112053004 08:11:45 INFO - mozversion application_changeset: eae9f44f7444407a28ab3b876032eceebe19310c 08:11:45 INFO - mozversion application_display_name: Nightly 08:11:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:11:45 INFO - mozversion application_name: Firefox 08:11:45 INFO - mozversion application_remotingname: firefox 08:11:45 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:11:45 INFO - mozversion application_vendor: Mozilla 08:11:45 INFO - mozversion application_version: 46.0a1 08:11:45 INFO - mozversion platform_buildid: 20160112053004 08:11:45 INFO - mozversion platform_changeset: eae9f44f7444407a28ab3b876032eceebe19310c 08:11:45 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:11:45 INFO - mozversion platform_version: 46.0a1 08:11:45 INFO - using testdate: 1452615105 08:11:45 INFO - actual date: 1452615105 08:11:45 INFO - starting webserver on 'localhost:51987' 08:11:45 INFO - SUITE-START | Running 3 tests 08:11:45 INFO - TEST-START | dromaeo_css 08:11:45 INFO - operating with platform_type : linux_ 08:11:45 INFO - Initialising browser for dromaeo_css test... 08:11:45 INFO - TEST-INFO | started process 12367 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpIJMa67/profile http://localhost:51987/getInfo.html) 08:11:49 INFO - PROCESS | 12367 | __metrics Screen width/height:1600/1200 08:11:49 INFO - PROCESS | 12367 | colorDepth:24 08:11:49 INFO - PROCESS | 12367 | Browser inner width/height: 1024/697 08:11:49 INFO - PROCESS | 12367 | __metrics 08:11:49 INFO - PROCESS | 12367 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 08:11:49 INFO - PROCESS | 12367 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:11:50 INFO - TEST-INFO | 12367: exit 0 08:11:50 INFO - Browser initialized. 08:11:50 INFO - Running cycle 1/1 for dromaeo_css test... 08:11:50 INFO - TEST-INFO | started process 12459 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpIJMa67/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:12:02 INFO - PROCESS | 12459 | RSS: Main: 147644416 08:12:02 INFO - PROCESS | 12459 | 08:12:53 INFO - PROCESS | 12459 | 1452615173005 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] 08:15:00 INFO - PROCESS | 12459 | Cycle 1(1): loaded http://localhost:51987/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51987/tests/dromaeo/cssquery-ext.html) 08:15:02 INFO - PROCESS | 12459 | RSS: Main: 299749376 08:15:02 INFO - PROCESS | 12459 | 08:18:04 INFO - PROCESS | 12459 | Cycle 1(1): loaded http://localhost:51987/tests/dromaeo/cssquery-ext.html (next: http://localhost:51987/tests/dromaeo/cssquery-jquery.html) 08:18:05 INFO - PROCESS | 12459 | RSS: Main: 233828352 08:18:05 INFO - PROCESS | 12459 | 08:21:07 INFO - PROCESS | 12459 | Cycle 1(1): loaded http://localhost:51987/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51987/tests/dromaeo/cssquery-mootools.html) 08:21:07 INFO - PROCESS | 12459 | RSS: Main: 244977664 08:21:07 INFO - PROCESS | 12459 | 08:24:09 INFO - PROCESS | 12459 | Cycle 1(1): loaded http://localhost:51987/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51987/tests/dromaeo/cssquery-prototype.html) 08:24:10 INFO - PROCESS | 12459 | RSS: Main: 214134784 08:24:10 INFO - PROCESS | 12459 | 08:27:12 INFO - PROCESS | 12459 | Cycle 1(1): loaded http://localhost:51987/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51987/tests/dromaeo/cssquery-yui.html) 08:27:13 INFO - PROCESS | 12459 | RSS: Main: 218296320 08:27:13 INFO - PROCESS | 12459 | 08:30:16 INFO - PROCESS | 12459 | Cycle 1(1): loaded http://localhost:51987/tests/dromaeo/cssquery-yui.html (next: http://localhost:51987/tests/dromaeo/cssquery-dojo.html) 08:30:16 INFO - PROCESS | 12459 | RSS: Main: 262139904 08:30:16 INFO - PROCESS | 12459 | 08:30:16 INFO - PROCESS | 12459 | __start_tp_report 08:30:16 INFO - PROCESS | 12459 | _x_x_mozilla_page_load 08:30:16 INFO - PROCESS | 12459 | _x_x_mozilla_page_load_details 08:30:16 INFO - PROCESS | 12459 | |i|pagename|runs| 08:30:16 INFO - PROCESS | 12459 | |0;dojo.html;3120.079576524925;3172.397244523539;3213.1645772099264;3215.662355452679;3237.320162765819;4945.703257804535;5048.974755126228;5109.591232701384;5135.127028405175;5189.040027594898;5045.697258164512;5061.822836200734;5076.593872490201;5081.339425874631;5148.433672296044;4655.604273636736;4667.836625718101;4680.134175177603;4746.240601503743;4762.166620841339;531.6509379310646;4545.318202269669;4653.7207767534055;4690.788914498856;4749.596284315838;4585.885352866188;4681.227597446417;4740;4776.140294746961;4785.904281914342;4681.204195286809;4711.293306004092;4712.986707857793;4713.764311784397;4747.264174053027;4571.657125715551;4637.536246375369;4677.532246775329;4728.621710263171;4771.665983381165;4966.751662416865;4985.850424487271;5002.274670172841;5026.246063090565;5047.64666473347;5513.09034009392;5532.612717109804;5545.112781954868;5545.417731138253;5546;5421.403645598981;5492.697901615409;5562.3047119110115;5574.526165275915;5620.269364982526;5083.694978301313;5165.870853228662;5229.032628963578;5255.63210575256;5273.261743355895;5633.943660563424;5639.943600563942;5648.774049037993;5649.406812284733;5705.71471426427;5384.542313903362;5440.183972404171;5443.564514838803;5448.210009548593;5451.809186678447;5375.4624537546715;5447.782088716486;5526.253955716007;5564.4713752193475;5594.41258667834;5350.010248104113;5398.055340315429;5412.621116521885;5414.458554144554;5414.512693857571;5396.2984811974975;5410.675359478444;5416.83749487516;5421.83734487966;5449.945500545023;4220.725652832618;4222.387753775685;4242.130363275521;4244.681648876347;4260.659147268211;3805.448210009533;3809.7333186676665;3814.9237015260096;3848.826802793881;3855.036240939765;4961.131801934647;5029.371328583927;5032.647714659939;5034.421041580192;5081.974590127025;5407.540359069444;5515.944840551543;5561.221428999943;5566;5612.607117501817;5056.317397151411;5106.514881086291;5108.259302401131;5113.3097031900215;5157.4584668610005;4619.930701039487;4689.788959496831;4692.741899195577;4698.553637404441;4699.624030077585;5734.6845923473775;5735.426457354315;5764.538836893039;5782.624129431573;5811.0411782056335;5474.644148130359;5492.313460817398;5495.862603434946;5498.395176530498;5499.752511137089;5581.162825576033;5641.887162256814;5643.097104463266;5662.122371032497;5705.857353566194;5477.72611369422;5499.230107784833;5499.587530935116;5506.752196151103;5511.22842802016;5563.860903477445;5571;5581.7209139543675;5583.190437386637;5584.664920104807;5352.250684904038;5409.242706021081;5419.485148910847;5475.72621368922;5482.39693633708;5351.973240133774;5381.75782089799;5382.703951282717;5388.973055134699;5422.864428389322;5201.453847346125;5228.268042474133;5241.921371179359;5296.285001524745;5298.682079075268;5334.3865455473115;5381;5392.029434701792;5422.728863556886;5433.293671822637;5178.275041494118;5298.099323115002;5344.545713614386;5349.384820745664;5384.030874442638 08:30:16 INFO - PROCESS | 12459 | |1;ext.html;540.2490538151969;548.1531034551617;551.5064017704152;568.8691600931784;581.2240658720608;28100.437991240164;28844;36939.81530092349;37809;37861;39931;40308;40329;40385.192296154135;40486.19027619446;8478.745637630866;8647.30821534279;8684.8697269541;8705.173008564176;8710.128987101303;8358.498490090584;8507.872381914276;8514.70198543051;8516.276116530102;8517.36119791018;8676.783080422976;8751.956240218822;8759.124087591223;8761.7809554761;8767.123287671246;8618.310535157203;8683.261922736576;8684.044755076937;8694.652213911437;8695.608697608592;8544.530050847201;8690.348223883235;8695.565221738887;8696.434731742416;8698.47809131454;39690.60309396898;40297;40357.798211008754;40362;40427.797861010506;3197.456432406473;3231.2083539532728;3301.8679252830098;3510.543629328171;4011.7191796574252;26679.733202667918;27020.054298099472;27163.864180679167;27193.776280067443;27367.589486157725;3660.359437098497;3666.853325866962;3709.9443508347126;3727.1800203955086;3747.5502939647417;267088;268196;268623;268914.999999998;269240;51905.48094519082;52465.47534524682;57752;58033.12950305749;58147;68137.3186268131;69166;69326;69332.653336733;69402;12856.807147892789;12959;12967.675808104872;13025.218486890817;13142.4085916134;9677.080677335642;9722;9724.562394692255;9910.950445247727;10310.536025878853;18234.811822581374;18288.359907403443;18335;18402.35591754282;18417.355392561196;7401.740939067106;7632.511660226313;7652.06441845018;7662.1571627120975;7738.574378409242;958.4728399380313;987.9357841740264;989.8564708117283;1008.4000019988096;1063.1654151491198;27267.181984540497;28496.572551411744;28961.42077158487;29157.41685166285;29379.118626441243;1021.1626466297713;1056.4400867540216;1136.9260998035102;1155.0124643429915;1179.1745777955466;14710.926445367919;15018.774718379233;15058.54824355271;15238.161901095546;15268.389264429521;161055.19472402564;161621;161763;162029.18985405233;163145;52405.47594524006;52711.736441317546;52724.20913686298;52735.472645273054;52782.20826687603;1336.111485861891;1338.906276560651;1339.3370281710615;1343.1068339554088;1352.114365090867;27398;28378.736445308226;28526.85736571304;28732.13803585896;28763.85618071896;46449.303260451794;46791.766041169576;46857.765711171225;46890.76554617205;46915.296270555285;311233;312462;312537;315000;315277;27516.449671006867;30835.38329233358;31209;31326.373472529966;31581.68418315879;39091.218175636895;41457.58542414537;41847.37228941629;42467.3629895558;42638.78680606577;35084.64915350814;35377.469337960465;35409;35775.46336805002;36405;29504.704952951055;34649.82675086609;35129.64870351264;35736.28527429489;36201.275974480886;32347.19132021718;32558.83720581382;32584.51123233106;32643.673563264063;32759.18102047468;28458.85770571134;30111.698883011766;31837.6816231844;31883.68116318807;31923.36153276968;24529.50940981206;24596.139135130186;24626.87686561556;24972;25017.74982250154 08:30:16 INFO - PROCESS | 12459 | |2;jquery.html;361.34235691042295;365.21478820535845;366.0829621797397;367.51488035792755;371.563412989737;13059.347032648377;13380.264085475295;15813.683726325467;16058;16469.09419981902;14113.788293175585;14869.182194979272;15307;15311.387544498206;15750.76373854393;5073.5941124710025;5275.762590683409;5277.815276465324;5278.076336641092;5280.181571856349;3913.3542965410816;4059.8782036538805;5179.223116532513;5269.394019687747;5629.690367029812;5759.884802303952;5784.074548072309;5805.36141024487;5809.651420914737;5815.156802263669;4952.900941981158;5143.588512918957;5149.0216858797085;5162.819301324435;5163.1480805667015;4854;4954.603631709455;5021.723805190713;5022.974885125587;5028.296038554606;12377.76222377764;12563.497460101586;12953.935230323883;13072.86927130726;13113.082084254105;2068.917243310266;2114.397396741921;2220.4004918671867;2279.1453205048106;2337.0768546424238;5488.231647569343;8783.38516303859;8870.778230544223;8881.955590222073;8945.284377249869;1720.3720641965717;1778.315348590789;1805.5034865411985;1821.4990877508658;1835.715464103066;56659;58617.70691146517;58673.413265867224;58863;58935.70532147355;9157.679481218125;9258.166764991183;9367.84539555906;9394.295427842939;9491.47796871165;11841.111916606289;11861.940690296493;11880.40597970098;11881.88118118825;11886.524539018515;6949.478789090838;7158.2483839197175;7178.353948144692;7221.277872212737;7242.529235599669;6597.934020659828;6645;6645.734170633121;6766.221884483348;6819.24988251292;3968.2460332536725;4088.5707000765087;4117.711760176759;4342.1749867525705;4369.890752731207;1692.5007122898774;1744.6161844394212;1773.556610847288;1831.32241070802;1853.6848735714962;903.5256490342623;938.6714649872627;946.7065209784851;962.4610218277647;1015.817152912468;13791.586252412444;13886.513972010931;14654.120752754869;16075.678486430439;16241.2691428886;886.259972922604;888.1651247827022;902.069511832847;933.169479163545;995.5072239241622;9158.358914875895;9360.672376466791;9399.530023498797;9495.857562136573;9541.713748587554;377919;378423;379131;383523;460848;56252.43747562472;62561;70559.64720176469;72148;76512;1111.9444027798577;1143.6283207957447;1149.1725957310723;1156.0520373293987;1163.4241050679968;14679.779803303172;14810.555683329518;15022.906812609977;15111.773323399937;15268.541943741706;29588.556171657874;29631;29813.10560683183;30753.231169220053;31285.217869553442;39396;39452.605473944895;39922.800385997885;40869.59130408658;42129.57870421379;7061.329173728487;7566.470347075872;7615.771526854203;7663.118741344817;7673.4244931631465;9327.253819694544;11080.168987326144;11152.776944461228;11537;11655.067594592581;9542.570584323861;9631.903680963382;9887.604495820087;9954.303198776306;10041.096301332915;9324.440533568008;9841.901580984098;10221;10243.94878025605;10263.127634150882;9484.430934143973;9706.563204655666;9999.20006399501;10327.225458090823;10374.58501659925;7318.121825380988;7458;7485.70057197706;7498.58757768328;7541.886871696819;9184.954075229582;9920;10064.295499314978;10168.186545076524;10245 08:30:16 INFO - PROCESS | 12459 | |3;mootools.html;1336.7994800779888;1470.330999395275;1470.8896832737544;1627.1294857251366;1791.5431564950936;6578.243501997272;7625.389968802497;7804.492707973977;7811;7814.3357814585825;7711.920331153643;7926.92073079269;7933.404994625412;7942.602869856499;7948.324392426636;1953.1308567687386;2000.5698774763423;2276.9658455123144;2293.8050265727434;2298.7241531016302;2349.7650234976536;2356.140008896749;2361.8464799788044;2363.267387109993;2370.9525809483885;2275.283285764987;2339.789418952294;2341.6019276722914;2342.367560758594;2344.120954642009;1968.6456437841182;2011.7988201179837;2276.726792784868;2315.5253173099472;2323.2217207235594;2301.344116926685;2341.2390972933695;2347.8473899196497;2389.7132344118645;2390.9163179288817;7793.883091753628;7806.921930780733;7863.292303692638;7867.96066019672;7874.803129921741;4746.38297021385;4866.099771542276;4899.093667671492;4902.681325713817;4904.215325547895;3115.454795410794;3132.8746850126195;3176.952345714816;3196.568463257477;3206.8556914938886;990.9504524773805;996.7707427291691;1014.7666036811501;1040.8907064758166;1051.4217180550665;11163.720906977309;11176.608818691308;11189.888101118966;11196.66410007701;11204.551817927273;4394.274944634163;4497.460304763449;4563.817447302137;4567.725936443824;4577.22187228172;9543.045695430541;9596.66411675602;9608.423494590346;9611.519424028771;9616.038396160327;4907.214845624683;4908.533689299511;4936.8765780855765;4945.55490005894;4945.67853089548;4430.3575981483145;4450.06548624786;4472.619827314649;4485.887852803706;4498.3252512123445;3631.1829838286176;3721.4603882436895;3735.831887565066;3739.495168152319;3753.6621704046765;1258.3330834657645;1266.5946896992873;1266.8796464335874;1267.2903174222465;1289.5293217975372;920.7606022434215;960.4381436859376;1276.9170003949898;1277.4953893212314;1297.9805302920463;7397.445191610543;7486.625668716542;7603.46775725705;7604.163542010375;7612.086549614081;624.709510077816;625.2212455563913;627.6579264300967;645.1033064040987;647.9935200647933;5641.12562552805;5688.089905615082;5690.630109042899;5712.485876271073;5717.1995920570325;9571.664991725396;9765.267604929517;9862.802743945225;9897.554610042565;9902.950485247527;9516.286278529138;9629.085236902483;9762.46306453152;9772.706818795448;9819.263555233387;7619.276168763959;7625.809354766065;7679.347255483346;7686.923130768732;7707.807304817425;7334.963325183553;7351.632418379167;7382.114146302263;7430.034095567542;7471.103467583925;8593.44142630727;8644.654213831627;8674.739757807276;8701;8879;8847.24798392144;9073.45559266446;9110.498922559324;9136.771580710536;9199.356045077047;7120.145582529923;7167.964160179375;7196.172440169447;7197.71209151628;7213.458990575623;7675.2324767522805;7701.499402538817;7744.49660772048;7770.378369730521;7776.105747838845;7300.744473943387;7334.266573342623;7425.294597013275;7536.0956685198125;7538.698452062075;6814.557053791487;6878.518503704893;6994.090768200102;7210.639468026473;7442.06974128234;6146.262448506208;6263.6241825490615;6825;7002.859942801014;7014.719411223494;6506.512011599054;6543.378379053982;6571.638559879061;6581.14445122131;6582.539222254588;6550.8362290943105;6587.9670601646685;6600.273969863407;6612.140421745335;6627.569208001464 08:30:16 INFO - PROCESS | 12459 | |4;prototype.html;341.62250712962174;341.94528875379945;349.69052388636067;352.218075871565;354.9025260709209;5875.353711091781;5880.411958804118;5927.555433342495;5964.13520039594;5978.372270911546;5863;5869.7358618862145;5871;5871.266091738533;5890.911636325448;4446.666500012496;4623.237165867637;4630.560096790799;4633;4633.768311584424;4591.6097131743845;4592.655550833684;4594.21898277292;4602.378678878359;4604.953950460486;4544.659150563704;4546.0680560485025;4549.61328287096;4549.886252843672;4560.33875088112;4291.785410729451;4362.978185109086;4366.366876802877;4396.868093957154;4541.364209010741;3813.9809300953316;3867.8066096695334;3961.960380396188;3966.7421617594764;3970.5235371755284;5547.639403438763;5560.888782224333;5562.666240025612;5564.666120032811;5577.274954255961;4953.0589188054155;5099.235114732783;5209.6874187548865;5226.764795584169;5234.398044224887;6005.309389420229;6164.96917515414;6169.537284703621;6197.628142311476;6203.8138855834395;4299.07569872479;4300.526942036374;4313.482382114166;4318.244307246219;4337.609615134653;6397.8400539986405;6480.31956644555;6481.060246264313;6481.578697384702;6487.772927947499;4349.891252718644;4468.352088947085;4472.888177795581;4486.416765820422;4492.797824097924;4532.9546704533195;4535.569120933506;4537.0925814837165;4542.250528662799;4542.613877820422;5199.584033277404;5237.73811309433;5239;5246.186841039568;5257.106291930381;6106.145139680449;6241.157443745127;6263.217097862767;6268.623882567061;6270;1590.4433448293005;1608.0592853180785;1608.7828143200752;1608.9919550402174;1610.3558576569467;1073.4418102586767;1082.610260306289;1085.6580177244143;1102.415719668561;1106.3527836215903;5893.292804863444;5901.675407852524;5906.202662640489;5908.261467316585;5908.704564771744;6392.648404337806;6395.808125756234;6397.424231819158;6403.807885763435;6408.807735767934;5902.8819423611285;5903.35063143054;5907.438793314628;5907.852303692442;5907.852303692442;3363.327334533103;3463.79217246966;3471.0628130405153;3483.8083905385447;3487.877924272638;6777.559458635173;6784.21981472137;6784.59292442455;6784.864302713918;6790.354916282945;6539.444147247537;6560.507961902877;6560.934390656128;6567.6059436433015;6570.8357291068105;6255.937440625536;6318.810435686844;6706.96646516774;6751.987201919653;6771.559848609923;6425.742970281136;6439.259485159267;6442.452391546771;6451.129097571956;6472.935270647234;6289.127790250225;6380.904286435614;6391.808245752635;6397;6401.487880969605;6719.160104986877;6772.390484856386;6776.966115169393;6777.288384719731;6779.830504237236;4469.620082292911;4477.082198149306;4506.842260520866;4512.796924138356;4513.7066090704;6491.350864913471;6536.542442029012;6558.311377305505;6561.442277406474;6564.081028656087;6188.783392581418;6277.654728989949;6305.864944309885;6309.526785491014;6312.621242725451;6293.213348331458;6301.495880329654;6301.62190268567;6307.180066591313;6309.211348581427;6159.260888693386;6185.876282474415;6193.876122477615;6194.473469754941;6199.969000154971;6179.876402472015;6330.3669633036325;6330.430261276507;6345.809625711236;6347.492200623847;6176.969115154396;6189.783357582463;6194.318624951162;6194.4115309045565;6201.875962480635 08:30:16 INFO - PROCESS | 12459 | |5;yui.html;699.6676578625154;722.4401089155907;748.5359077372028;763.4083585221452;778.686079121666;11528.020118289935;11535.077193824494;11576.189666723332;11608.651740447789;11753.177277590561;11565.190436669436;11720.589779357724;11765.352905590189;11849.703757406069;11875.7624847503;674.6222115615246;677.7627830259395;789.4237206838997;804.5775967616989;805.4643661964786;786;797.0634504457269;804.4811096842533;804.666063583615;805.3677862877643;847.1824689174945;851.1318455175717;851.3274513134647;851.7700220940343;852.1180578101666;699.7131176217752;706.6537396675618;828.6188353357463;842.839860426517;844.3709436469869;614.4285814192799;618.3785295777759;618.7370367593754;619.2599843187393;619.7490016543308;12124.090693198046;12161.209521381083;12185.45165467556;12187;12193.695157621041;12206.633800985985;12313.56902508408;12518.624441266686;12693.619191424272;12834.240638262818;10233.334833235816;10234.744131396774;10256.589736410535;10323.741906452324;10450.529726162342;3556.8577256909693;3596.3166998270244;3626.4016437287814;3627.3652110880753;3731.477593136963;45938.08123837538;47049;47794.28308575374;47839.282410763866;49312.75343623259;9281.21109705669;9501.619935202652;9572.856407153899;9577.138057574852;9689.32174747775;16714.331426742796;17037.48887533376;17038.659226815642;17040.14799260032;17047.48857534276;7497.437692173109;7508.737194198176;7614.2766437188375;7646.923530764732;7686.615669216517;4252.638525725348;4343.956560434355;4398.472183337956;4420.513743488278;4494.797734101973;3043.5434684797015;3046.7257946784894;3057.5260834570677;3063.6170478690165;3070.831104289241;576.3314555116045;576.9249997500298;581.0644861772543;583.5302581421996;593.7684303121786;471.9150552900442;575.6229669566441;593.9851503712442;649.6394501051867;664.8332177029379;7899.68401263943;8194.50832950025;8284.212999765012;8345.41582089248;8355.749327520183;545.9153831156177;617.1113596421139;625.2465778736612;633.5470138850744;657.2769953051605;3814.6376094271;3878.398848178537;3911.2373087247715;3913.5890731473355;3937.9606203938165;51162.744186279575;52330.47669523332;52863;52890.73554632202;53065;46895.531044689116;48502.27246591304;48607.5139248603;48727.269090963666;49215.507844921805;42049;42400.78799605982;43241.78379108147;43363;43509.56490435119;7722.474509039852;7725.613719313899;7870.134285228734;7884.881726774218;7930.246626570467;32522.34955300928;33534.832325838215;33591.496127558596;33780.98657040293;33957;38388.80805595954;41274.79362603168;41874;42055.7897210512;42092.36861447142;9339.719808405758;9456.669016584387;9481.810363792823;9523.428594284365;9556.331056825955;10169.542370593193;10251.58993640246;10403.687889363331;10631.840522392016;10757.139428845518;9647.517624118625;9660.420374777535;9705.5147242639;9715.319927605282;9942.453165075991;9995.600175992879;10103;10466.842997355197;10497.685069447929;10511;9758.316917815684;9935.751606209902;10169.440680762333;10223.335483193569;10322.535485903003;7733.149353571215;7894.802629934297;8181.922636919141;8202.917970820215;8260.958695206486;8141;8520;8529.488230706178;8598.78503037429;8620.956895215484 08:30:16 INFO - PROCESS | 12459 | __end_tp_report 08:30:16 INFO - PROCESS | 12459 | __start_cc_report 08:30:16 INFO - PROCESS | 12459 | _x_x_mozilla_cycle_collect,1035 08:30:16 INFO - PROCESS | 12459 | __end_cc_report 08:30:16 INFO - PROCESS | 12459 | __startTimestamp1452616216502__endTimestamp 08:30:16 INFO - PROCESS | 12459 | 08:30:16 INFO - PROCESS | 12459 | ------- Summary: start ------- 08:30:16 INFO - PROCESS | 12459 | Number of tests: 6 08:30:16 INFO - PROCESS | 12459 | 08:30:16 INFO - PROCESS | 12459 | [#0] dojo.html Cycles:165 Average:5086.43 Median:5334.39 stddev:657.89 (12.3%) stddev-sans-first:641.57 08:30:16 INFO - PROCESS | 12459 | Values: 3120.1 3172.4 3213.2 3215.7 3237.3 4945.7 5049.0 5109.6 5135.1 5189.0 5045.7 5061.8 5076.6 5081.3 5148.4 4655.6 4667.8 4680.1 4746.2 4762.2 531.7 4545.3 4653.7 4690.8 4749.6 4585.9 4681.2 4740.0 4776.1 4785.9 4681.2 4711.3 4713.0 4713.8 4747.3 4571.7 4637.5 4677.5 4728.6 4771.7 4966.8 4985.9 5002.3 5026.2 5047.6 5513.1 5532.6 5545.1 5545.4 5546.0 5421.4 5492.7 5562.3 5574.5 5620.3 5083.7 5165.9 5229.0 5255.6 5273.3 5633.9 5639.9 5648.8 5649.4 5705.7 5384.5 5440.2 5443.6 5448.2 5451.8 5375.5 5447.8 5526.3 5564.5 5594.4 5350.0 5398.1 5412.6 5414.5 5414.5 5396.3 5410.7 5416.8 5421.8 5449.9 4220.7 4222.4 4242.1 4244.7 4260.7 3805.4 3809.7 3814.9 3848.8 3855.0 4961.1 5029.4 5032.6 5034.4 5082.0 5407.5 5515.9 5561.2 5566.0 5612.6 5056.3 5106.5 5108.3 5113.3 5157.5 4619.9 4689.8 4692.7 4698.6 4699.6 5734.7 5735.4 5764.5 5782.6 5811.0 5474.6 5492.3 5495.9 5498.4 5499.8 5581.2 5641.9 5643.1 5662.1 5705.9 5477.7 5499.2 5499.6 5506.8 5511.2 5563.9 5571.0 5581.7 5583.2 5584.7 5352.3 5409.2 5419.5 5475.7 5482.4 5352.0 5381.8 5382.7 5389.0 5422.9 5201.5 5228.3 5241.9 5296.3 5298.7 5334.4 5381.0 5392.0 5422.7 5433.3 5178.3 5298.1 5344.5 5349.4 5384.0 08:30:16 INFO - PROCESS | 12459 | 08:30:16 INFO - PROCESS | 12459 | [#1] ext.html Cycles:180 Average:42072.57 Median:35755.87 stddev:67130.78 (187.7%) stddev-sans-first:67246.67 08:30:16 INFO - PROCESS | 12459 | Values: 540.2 548.2 551.5 568.9 581.2 28100.4 28844.0 36939.8 37809.0 37861.0 39931.0 40308.0 40329.0 40385.2 40486.2 8478.7 8647.3 8684.9 8705.2 8710.1 8358.5 8507.9 8514.7 8516.3 8517.4 8676.8 8752.0 8759.1 8761.8 8767.1 8618.3 8683.3 8684.0 8694.7 8695.6 8544.5 8690.3 8695.6 8696.4 8698.5 39690.6 40297.0 40357.8 40362.0 40427.8 3197.5 3231.2 3301.9 3510.5 4011.7 26679.7 27020.1 27163.9 27193.8 27367.6 3660.4 3666.9 3709.9 3727.2 3747.6 267088.0 268196.0 268623.0 268915.0 269240.0 51905.5 52465.5 57752.0 58033.1 58147.0 68137.3 69166.0 69326.0 69332.7 69402.0 12856.8 12959.0 12967.7 13025.2 13142.4 9677.1 9722.0 9724.6 9911.0 10310.5 18234.8 18288.4 18335.0 18402.4 18417.4 7401.7 7632.5 7652.1 7662.2 7738.6 958.5 987.9 989.9 1008.4 1063.2 27267.2 28496.6 28961.4 29157.4 29379.1 1021.2 1056.4 1136.9 1155.0 1179.2 14710.9 15018.8 15058.5 15238.2 15268.4 161055.2 161621.0 161763.0 162029.2 163145.0 52405.5 52711.7 52724.2 52735.5 52782.2 1336.1 1338.9 1339.3 1343.1 1352.1 27398.0 28378.7 28526.9 28732.1 28763.9 46449.3 46791.8 46857.8 46890.8 46915.3 311233.0 312462.0 312537.0 315000.0 315277.0 27516.4 30835.4 31209.0 31326.4 31581.7 39091.2 41457.6 41847.4 42467.4 42638.8 35084.6 35377.5 35409.0 35775.5 36405.0 29504.7 34649.8 35129.6 35736.3 36201.3 32347.2 32558.8 32584.5 32643.7 32759.2 28458.9 30111.7 31837.7 31883.7 31923.4 24529.5 24596.1 24626.9 24972.0 25017.7 08:30:16 INFO - PROCESS | 12459 | 08:30:16 INFO - PROCESS | 12459 | [#2] jquery.html Cycles:180 Average:22854.51 Median:232851.00 stddev:65150.64 (28.0%) stddev-sans-first:65311.52 08:30:16 INFO - PROCESS | 12459 | Values: 361.3 365.2 366.1 367.5 371.6 13059.3 13380.3 15813.7 16058.0 16469.1 14113.8 14869.2 15307.0 15311.4 15750.8 5073.6 5275.8 5277.8 5278.1 5280.2 3913.4 4059.9 5179.2 5269.4 5629.7 5759.9 5784.1 5805.4 5809.7 5815.2 4952.9 5143.6 5149.0 5162.8 5163.1 4854.0 4954.6 5021.7 5023.0 5028.3 12377.8 12563.5 12953.9 13072.9 13113.1 2068.9 2114.4 2220.4 2279.1 2337.1 5488.2 8783.4 8870.8 8882.0 8945.3 1720.4 1778.3 1805.5 1821.5 1835.7 56659.0 58617.7 58673.4 58863.0 58935.7 9157.7 9258.2 9367.8 9394.3 9491.5 11841.1 11861.9 11880.4 11881.9 11886.5 6949.5 7158.2 7178.4 7221.3 7242.5 6597.9 6645.0 6645.7 6766.2 6819.2 3968.2 4088.6 4117.7 4342.2 4369.9 1692.5 1744.6 1773.6 1831.3 1853.7 903.5 938.7 946.7 962.5 1015.8 13791.6 13886.5 14654.1 16075.7 16241.3 886.3 888.2 902.1 933.2 995.5 9158.4 9360.7 9399.5 9495.9 9541.7 377919.0 378423.0 379131.0 383523.0 460848.0 56252.4 62561.0 70559.6 72148.0 76512.0 1111.9 1143.6 1149.2 1156.1 1163.4 14679.8 14810.6 15022.9 15111.8 15268.5 29588.6 29631.0 29813.1 30753.2 31285.2 39396.0 39452.6 39922.8 40869.6 42129.6 7061.3 7566.5 7615.8 7663.1 7673.4 9327.3 11080.2 11152.8 11537.0 11655.1 9542.6 9631.9 9887.6 9954.3 10041.1 9324.4 9841.9 10221.0 10243.9 10263.1 9484.4 9706.6 9999.2 10327.2 10374.6 7318.1 7458.0 7485.7 7498.6 7541.9 9185.0 9920.0 10064.3 10168.2 10245.0 08:30:16 INFO - PROCESS | 12459 | 08:30:16 INFO - PROCESS | 12459 | [#3] mootools.html Cycles:180 Average:5596.43 Median:6561.24 stddev:2959.56 (45.1%) stddev-sans-first:2950.54 08:30:16 INFO - PROCESS | 12459 | Values: 1336.8 1470.3 1470.9 1627.1 1791.5 6578.2 7625.4 7804.5 7811.0 7814.3 7711.9 7926.9 7933.4 7942.6 7948.3 1953.1 2000.6 2277.0 2293.8 2298.7 2349.8 2356.1 2361.8 2363.3 2371.0 2275.3 2339.8 2341.6 2342.4 2344.1 1968.6 2011.8 2276.7 2315.5 2323.2 2301.3 2341.2 2347.8 2389.7 2390.9 7793.9 7806.9 7863.3 7868.0 7874.8 4746.4 4866.1 4899.1 4902.7 4904.2 3115.5 3132.9 3177.0 3196.6 3206.9 991.0 996.8 1014.8 1040.9 1051.4 11163.7 11176.6 11189.9 11196.7 11204.6 4394.3 4497.5 4563.8 4567.7 4577.2 9543.0 9596.7 9608.4 9611.5 9616.0 4907.2 4908.5 4936.9 4945.6 4945.7 4430.4 4450.1 4472.6 4485.9 4498.3 3631.2 3721.5 3735.8 3739.5 3753.7 1258.3 1266.6 1266.9 1267.3 1289.5 920.8 960.4 1276.9 1277.5 1298.0 7397.4 7486.6 7603.5 7604.2 7612.1 624.7 625.2 627.7 645.1 648.0 5641.1 5688.1 5690.6 5712.5 5717.2 9571.7 9765.3 9862.8 9897.6 9903.0 9516.3 9629.1 9762.5 9772.7 9819.3 7619.3 7625.8 7679.3 7686.9 7707.8 7335.0 7351.6 7382.1 7430.0 7471.1 8593.4 8644.7 8674.7 8701.0 8879.0 8847.2 9073.5 9110.5 9136.8 9199.4 7120.1 7168.0 7196.2 7197.7 7213.5 7675.2 7701.5 7744.5 7770.4 7776.1 7300.7 7334.3 7425.3 7536.1 7538.7 6814.6 6878.5 6994.1 7210.6 7442.1 6146.3 6263.6 6825.0 7002.9 7014.7 6506.5 6543.4 6571.6 6581.1 6582.5 6550.8 6588.0 6600.3 6612.1 6627.6 08:30:16 INFO - PROCESS | 12459 | 08:30:16 INFO - PROCESS | 12459 | [#4] prototype.html Cycles:180 Average:5234.45 Median:5904.78 stddev:1570.13 (26.6%) stddev-sans-first:1530.99 08:30:16 INFO - PROCESS | 12459 | Values: 341.6 341.9 349.7 352.2 354.9 5875.4 5880.4 5927.6 5964.1 5978.4 5863.0 5869.7 5871.0 5871.3 5890.9 4446.7 4623.2 4630.6 4633.0 4633.8 4591.6 4592.7 4594.2 4602.4 4605.0 4544.7 4546.1 4549.6 4549.9 4560.3 4291.8 4363.0 4366.4 4396.9 4541.4 3814.0 3867.8 3962.0 3966.7 3970.5 5547.6 5560.9 5562.7 5564.7 5577.3 4953.1 5099.2 5209.7 5226.8 5234.4 6005.3 6165.0 6169.5 6197.6 6203.8 4299.1 4300.5 4313.5 4318.2 4337.6 6397.8 6480.3 6481.1 6481.6 6487.8 4349.9 4468.4 4472.9 4486.4 4492.8 4533.0 4535.6 4537.1 4542.3 4542.6 5199.6 5237.7 5239.0 5246.2 5257.1 6106.1 6241.2 6263.2 6268.6 6270.0 1590.4 1608.1 1608.8 1609.0 1610.4 1073.4 1082.6 1085.7 1102.4 1106.4 5893.3 5901.7 5906.2 5908.3 5908.7 6392.6 6395.8 6397.4 6403.8 6408.8 5902.9 5903.4 5907.4 5907.9 5907.9 3363.3 3463.8 3471.1 3483.8 3487.9 6777.6 6784.2 6784.6 6784.9 6790.4 6539.4 6560.5 6560.9 6567.6 6570.8 6255.9 6318.8 6707.0 6752.0 6771.6 6425.7 6439.3 6442.5 6451.1 6472.9 6289.1 6380.9 6391.8 6397.0 6401.5 6719.2 6772.4 6777.0 6777.3 6779.8 4469.6 4477.1 4506.8 4512.8 4513.7 6491.4 6536.5 6558.3 6561.4 6564.1 6188.8 6277.7 6305.9 6309.5 6312.6 6293.2 6301.5 6301.6 6307.2 6309.2 6159.3 6185.9 6193.9 6194.5 6200.0 6179.9 6330.4 6330.4 6345.8 6347.5 6177.0 6189.8 6194.3 6194.4 6201.9 08:30:16 INFO - PROCESS | 12459 | 08:30:16 INFO - PROCESS | 12459 | [#5] yui.html Cycles:180 Average:12842.02 Median:52876.87 stddev:14976.41 (28.3%) stddev-sans-first:14990.67 08:30:16 INFO - PROCESS | 12459 | Values: 699.7 722.4 748.5 763.4 778.7 11528.0 11535.1 11576.2 11608.7 11753.2 11565.2 11720.6 11765.4 11849.7 11875.8 674.6 677.8 789.4 804.6 805.5 786.0 797.1 804.5 804.7 805.4 847.2 851.1 851.3 851.8 852.1 699.7 706.7 828.6 842.8 844.4 614.4 618.4 618.7 619.3 619.7 12124.1 12161.2 12185.5 12187.0 12193.7 12206.6 12313.6 12518.6 12693.6 12834.2 10233.3 10234.7 10256.6 10323.7 10450.5 3556.9 3596.3 3626.4 3627.4 3731.5 45938.1 47049.0 47794.3 47839.3 49312.8 9281.2 9501.6 9572.9 9577.1 9689.3 16714.3 17037.5 17038.7 17040.1 17047.5 7497.4 7508.7 7614.3 7646.9 7686.6 4252.6 4344.0 4398.5 4420.5 4494.8 3043.5 3046.7 3057.5 3063.6 3070.8 576.3 576.9 581.1 583.5 593.8 471.9 575.6 594.0 649.6 664.8 7899.7 8194.5 8284.2 8345.4 8355.7 545.9 617.1 625.2 633.5 657.3 3814.6 3878.4 3911.2 3913.6 3938.0 51162.7 52330.5 52863.0 52890.7 53065.0 46895.5 48502.3 48607.5 48727.3 49215.5 42049.0 42400.8 43241.8 43363.0 43509.6 7722.5 7725.6 7870.1 7884.9 7930.2 32522.3 33534.8 33591.5 33781.0 33957.0 38388.8 41274.8 41874.0 42055.8 42092.4 9339.7 9456.7 9481.8 9523.4 9556.3 10169.5 10251.6 10403.7 10631.8 10757.1 9647.5 9660.4 9705.5 9715.3 9942.5 9995.6 10103.0 10466.8 10497.7 10511.0 9758.3 9935.8 10169.4 10223.3 10322.5 7733.1 7894.8 8181.9 8202.9 8261.0 8141.0 8520.0 8529.5 8598.8 8621.0 08:30:16 INFO - PROCESS | 12459 | -------- Summary: end -------- 08:30:16 INFO - PROCESS | 12459 | 08:30:17 INFO - TEST-INFO | 12459: exit 0 08:30:17 INFO - TEST-OK | dromaeo_css | took 1111876ms 08:30:17 INFO - TEST-START | kraken 08:30:17 INFO - operating with platform_type : linux_ 08:30:17 INFO - Initialising browser for kraken test... 08:30:17 INFO - TEST-INFO | started process 12653 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpT4nsm6/profile http://localhost:51987/getInfo.html) 08:30:20 INFO - PROCESS | 12653 | __metrics Screen width/height:1600/1200 08:30:20 INFO - PROCESS | 12653 | colorDepth:24 08:30:20 INFO - PROCESS | 12653 | Browser inner width/height: 1024/697 08:30:20 INFO - PROCESS | 12653 | __metrics 08:30:20 INFO - PROCESS | 12653 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 08:30:21 INFO - PROCESS | 12653 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:30:22 INFO - TEST-INFO | 12653: exit 0 08:30:22 INFO - Browser initialized. 08:30:22 INFO - Running cycle 1/1 for kraken test... 08:30:22 INFO - TEST-INFO | started process 12733 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpT4nsm6/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:30:33 INFO - PROCESS | 12733 | RSS: Main: 149196800 08:30:33 INFO - PROCESS | 12733 | 08:30:36 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51987/tests/kraken/driver.html?testName=audio-beat-detection) 08:30:36 INFO - PROCESS | 12733 | RSS: Main: 187019264 08:30:36 INFO - PROCESS | 12733 | 08:30:39 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51987/tests/kraken/driver.html?testName=audio-dft) 08:30:40 INFO - PROCESS | 12733 | RSS: Main: 176197632 08:30:40 INFO - PROCESS | 12733 | 08:30:43 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51987/tests/kraken/driver.html?testName=audio-fft) 08:30:43 INFO - PROCESS | 12733 | RSS: Main: 160456704 08:30:43 INFO - PROCESS | 12733 | 08:30:45 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51987/tests/kraken/driver.html?testName=audio-oscillator) 08:30:46 INFO - PROCESS | 12733 | RSS: Main: 162222080 08:30:46 INFO - PROCESS | 12733 | 08:30:48 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51987/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:30:48 INFO - PROCESS | 12733 | RSS: Main: 162869248 08:30:48 INFO - PROCESS | 12733 | 08:30:51 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51987/tests/kraken/driver.html?testName=imaging-darkroom) 08:30:52 INFO - PROCESS | 12733 | RSS: Main: 163876864 08:30:52 INFO - PROCESS | 12733 | 08:30:55 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51987/tests/kraken/driver.html?testName=imaging-desaturate) 08:30:56 INFO - PROCESS | 12733 | RSS: Main: 164503552 08:30:56 INFO - PROCESS | 12733 | 08:30:59 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51987/tests/kraken/driver.html?testName=json-parse-financial) 08:30:59 INFO - PROCESS | 12733 | RSS: Main: 166412288 08:30:59 INFO - PROCESS | 12733 | 08:31:01 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51987/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:31:01 INFO - PROCESS | 12733 | RSS: Main: 175550464 08:31:01 INFO - PROCESS | 12733 | 08:31:03 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51987/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:31:04 INFO - PROCESS | 12733 | RSS: Main: 172826624 08:31:04 INFO - PROCESS | 12733 | 08:31:06 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51987/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:31:06 INFO - PROCESS | 12733 | RSS: Main: 181608448 08:31:06 INFO - PROCESS | 12733 | 08:31:09 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51987/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:31:09 INFO - PROCESS | 12733 | RSS: Main: 186916864 08:31:09 INFO - PROCESS | 12733 | 08:31:12 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51987/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:31:12 INFO - PROCESS | 12733 | RSS: Main: 183582720 08:31:12 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | Cycle 1(1): loaded http://localhost:51987/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51987/tests/kraken/driver.html?testName=ai-astar) 08:31:14 INFO - PROCESS | 12733 | RSS: Main: 243105792 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | __start_tp_report 08:31:14 INFO - PROCESS | 12733 | _x_x_mozilla_page_load 08:31:14 INFO - PROCESS | 12733 | _x_x_mozilla_page_load_details 08:31:14 INFO - PROCESS | 12733 | |i|pagename|runs| 08:31:14 INFO - PROCESS | 12733 | |0;ai-astar;109;110;105;109;110;111;101;110;110;110 08:31:14 INFO - PROCESS | 12733 | |1;audio-beat-detection;152;126;151;160;125;155;150;127;160;125 08:31:14 INFO - PROCESS | 12733 | |2;audio-dft;160;161;161;161;161;162;161;162;162;161 08:31:14 INFO - PROCESS | 12733 | |3;audio-fft;72;72;90;94;72;71;73;99;88;71 08:31:14 INFO - PROCESS | 12733 | |4;audio-oscillator;103;96;141;95;96;96;138;96;97;142 08:31:14 INFO - PROCESS | 12733 | |5;imaging-gaussian-blur;109;110;109;109;109;109;109;109;109;109 08:31:14 INFO - PROCESS | 12733 | |6;imaging-darkroom;146;145;145;145;145;146;145;146;147;145 08:31:14 INFO - PROCESS | 12733 | |7;imaging-desaturate;95;96;95;95;95;95;95;96;96;95 08:31:14 INFO - PROCESS | 12733 | |8;json-parse-financial;56;55;55;56;55;57;56;65;53;56 08:31:14 INFO - PROCESS | 12733 | |9;json-stringify-tinderbox;68;68;69;68;68;68;67;67;67;68 08:31:14 INFO - PROCESS | 12733 | |10;stanford-crypto-aes;83;84;84;84;89;83;85;84;87;84 08:31:14 INFO - PROCESS | 12733 | |11;stanford-crypto-ccm;117;122;118;115;113;131;117;118;117;131 08:31:14 INFO - PROCESS | 12733 | |12;stanford-crypto-pbkdf2;141;140;151;151;152;141;151;148;151;138 08:31:14 INFO - PROCESS | 12733 | |13;stanford-crypto-sha256-iterative;69;69;66;66;67;65;66;70;67;68 08:31:14 INFO - PROCESS | 12733 | __end_tp_report 08:31:14 INFO - PROCESS | 12733 | __start_cc_report 08:31:14 INFO - PROCESS | 12733 | _x_x_mozilla_cycle_collect,959 08:31:14 INFO - PROCESS | 12733 | __end_cc_report 08:31:14 INFO - PROCESS | 12733 | __startTimestamp1452616274750__endTimestamp 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | ------- Summary: start ------- 08:31:14 INFO - PROCESS | 12733 | Number of tests: 14 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#0] ai-astar Cycles:10 Average:108.50 Median:110.00 stddev:3.10 (2.8%) stddev-sans-first:3.28 08:31:14 INFO - PROCESS | 12733 | Values: 109.0 110.0 105.0 109.0 110.0 111.0 101.0 110.0 110.0 110.0 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#1] audio-beat-detection Cycles:10 Average:143.10 Median:151.50 stddev:15.31 (10.1%) stddev-sans-first:15.89 08:31:14 INFO - PROCESS | 12733 | Values: 152.0 126.0 151.0 160.0 125.0 155.0 150.0 127.0 160.0 125.0 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.50 08:31:14 INFO - PROCESS | 12733 | Values: 160.0 161.0 161.0 161.0 161.0 162.0 161.0 162.0 162.0 161.0 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#3] audio-fft Cycles:10 Average:80.20 Median:80.50 stddev:11.17 (13.9%) stddev-sans-first:11.45 08:31:14 INFO - PROCESS | 12733 | Values: 72.0 72.0 90.0 94.0 72.0 71.0 73.0 99.0 88.0 71.0 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#4] audio-oscillator Cycles:10 Average:110.00 Median:96.00 stddev:21.07 (21.9%) stddev-sans-first:22.20 08:31:14 INFO - PROCESS | 12733 | Values: 103.0 96.0 141.0 95.0 96.0 96.0 138.0 96.0 97.0 142.0 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 08:31:14 INFO - PROCESS | 12733 | Values: 109.0 110.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:145.50 stddev:0.71 (0.5%) stddev-sans-first:0.73 08:31:14 INFO - PROCESS | 12733 | Values: 146.0 145.0 145.0 145.0 145.0 146.0 145.0 146.0 147.0 145.0 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 08:31:14 INFO - PROCESS | 12733 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 95.0 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#8] json-parse-financial Cycles:10 Average:56.40 Median:56.00 stddev:3.20 (5.7%) stddev-sans-first:3.40 08:31:14 INFO - PROCESS | 12733 | Values: 56.0 55.0 55.0 56.0 55.0 57.0 56.0 65.0 53.0 56.0 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:0.63 (0.9%) stddev-sans-first:0.67 08:31:14 INFO - PROCESS | 12733 | Values: 68.0 68.0 69.0 68.0 68.0 68.0 67.0 67.0 67.0 68.0 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:84.00 stddev:1.89 (2.2%) stddev-sans-first:1.90 08:31:14 INFO - PROCESS | 12733 | Values: 83.0 84.0 84.0 84.0 89.0 83.0 85.0 84.0 87.0 84.0 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#11] stanford-crypto-ccm Cycles:10 Average:119.90 Median:118.00 stddev:6.28 (5.3%) stddev-sans-first:6.57 08:31:14 INFO - PROCESS | 12733 | Values: 117.0 122.0 118.0 115.0 113.0 131.0 117.0 118.0 117.0 131.0 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.40 Median:151.00 stddev:5.66 (3.7%) stddev-sans-first:5.66 08:31:14 INFO - PROCESS | 12733 | Values: 141.0 140.0 151.0 151.0 152.0 141.0 151.0 148.0 151.0 138.0 08:31:14 INFO - PROCESS | 12733 | 08:31:14 INFO - PROCESS | 12733 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.64 (2.4%) stddev-sans-first:1.62 08:31:14 INFO - PROCESS | 12733 | Values: 69.0 69.0 66.0 66.0 67.0 65.0 66.0 70.0 67.0 68.0 08:31:14 INFO - PROCESS | 12733 | -------- Summary: end -------- 08:31:14 INFO - PROCESS | 12733 | 08:31:15 INFO - TEST-INFO | 12733: exit 0 08:31:15 INFO - TEST-OK | kraken | took 58488ms 08:31:15 INFO - TEST-START | v8_7 08:31:15 INFO - operating with platform_type : linux_ 08:31:15 INFO - Initialising browser for v8_7 test... 08:31:15 INFO - TEST-INFO | started process 12985 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpJ6N8dP/profile http://localhost:51987/getInfo.html) 08:31:19 INFO - PROCESS | 12985 | __metrics Screen width/height:1600/1200 08:31:19 INFO - PROCESS | 12985 | colorDepth:24 08:31:19 INFO - PROCESS | 12985 | Browser inner width/height: 1024/697 08:31:19 INFO - PROCESS | 12985 | __metrics 08:31:19 INFO - PROCESS | 12985 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 08:31:19 INFO - PROCESS | 12985 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:31:20 INFO - TEST-INFO | 12985: exit 0 08:31:20 INFO - Browser initialized. 08:31:20 INFO - Running cycle 1/1 for v8_7 test... 08:31:20 INFO - TEST-INFO | started process 13071 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpJ6N8dP/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:31:32 INFO - PROCESS | 13071 | RSS: Main: 148942848 08:31:32 INFO - PROCESS | 13071 | 08:31:54 INFO - PROCESS | 13071 | Cycle 1(1): loaded http://localhost:51987/tests/v8_7/run.html (next: http://localhost:51987/tests/v8_7/run.html) 08:31:54 INFO - PROCESS | 13071 | RSS: Main: 547373056 08:31:54 INFO - PROCESS | 13071 | 08:31:54 INFO - PROCESS | 13071 | __start_tp_report 08:31:54 INFO - PROCESS | 13071 | _x_x_mozilla_page_load 08:31:54 INFO - PROCESS | 13071 | _x_x_mozilla_page_load_details 08:31:54 INFO - PROCESS | 13071 | |i|pagename|runs| 08:31:54 INFO - PROCESS | 13071 | |0;Richards;167.33601070950468 08:31:54 INFO - PROCESS | 13071 | |1;DeltaBlue;190.5487804878049 08:31:54 INFO - PROCESS | 13071 | |2;Encrypt;279.01785714285717 08:31:54 INFO - PROCESS | 13071 | |3;Decrypt;5175.257731958763 08:31:54 INFO - PROCESS | 13071 | |4;RayTrace;1921.3051823416506 08:31:54 INFO - PROCESS | 13071 | |5;Earley;788.6435331230284 08:31:54 INFO - PROCESS | 13071 | |6;Boyer;11904.761904761905 08:31:54 INFO - PROCESS | 13071 | |7;RegExp;42375 08:31:54 INFO - PROCESS | 13071 | |8;Splay;568.1818181818181 08:31:54 INFO - PROCESS | 13071 | |9;NavierStokes;4830.917874396136 08:31:54 INFO - PROCESS | 13071 | __end_tp_report 08:31:54 INFO - PROCESS | 13071 | __startTimestamp1452616314907__endTimestamp 08:31:54 INFO - PROCESS | 13071 | 08:31:54 INFO - PROCESS | 13071 | ------- Summary: start ------- 08:31:54 INFO - PROCESS | 13071 | Number of tests: 10 08:31:54 INFO - PROCESS | 13071 | 08:31:54 INFO - PROCESS | 13071 | [#0] Richards Cycles:1 Average:167.34 Median:167.34 stddev:0.00 (0.0%) 08:31:54 INFO - PROCESS | 13071 | Values: 167.3 08:31:54 INFO - PROCESS | 13071 | 08:31:54 INFO - PROCESS | 13071 | [#1] DeltaBlue Cycles:1 Average:190.55 Median:190.55 stddev:0.00 (0.0%) 08:31:54 INFO - PROCESS | 13071 | Values: 190.5 08:31:54 INFO - PROCESS | 13071 | 08:31:54 INFO - PROCESS | 13071 | [#2] Encrypt Cycles:1 Average:279.02 Median:279.02 stddev:0.00 (0.0%) 08:31:54 INFO - PROCESS | 13071 | Values: 279.0 08:31:54 INFO - PROCESS | 13071 | 08:31:54 INFO - PROCESS | 13071 | [#3] Decrypt Cycles:1 Average:5175.26 Median:5175.26 stddev:0.00 (0.0%) 08:31:54 INFO - PROCESS | 13071 | Values: 5175.3 08:31:54 INFO - PROCESS | 13071 | 08:31:54 INFO - PROCESS | 13071 | [#4] RayTrace Cycles:1 Average:1921.31 Median:1921.31 stddev:0.00 (0.0%) 08:31:54 INFO - PROCESS | 13071 | Values: 1921.3 08:31:54 INFO - PROCESS | 13071 | 08:31:54 INFO - PROCESS | 13071 | [#5] Earley Cycles:1 Average:788.64 Median:788.64 stddev:0.00 (0.0%) 08:31:54 INFO - PROCESS | 13071 | Values: 788.6 08:31:54 INFO - PROCESS | 13071 | 08:31:54 INFO - PROCESS | 13071 | [#6] Boyer Cycles:1 Average:11904.76 Median:11904.76 stddev:0.00 (0.0%) 08:31:54 INFO - PROCESS | 13071 | Values: 11904.8 08:31:54 INFO - PROCESS | 13071 | 08:31:54 INFO - PROCESS | 13071 | [#7] RegExp Cycles:1 Average:42375.00 Median:42375.00 stddev:0.00 (0.0%) 08:31:54 INFO - PROCESS | 13071 | Values: 42375.0 08:31:54 INFO - PROCESS | 13071 | 08:31:54 INFO - PROCESS | 13071 | [#8] Splay Cycles:1 Average:568.18 Median:568.18 stddev:0.00 (0.0%) 08:31:54 INFO - PROCESS | 13071 | Values: 568.2 08:31:54 INFO - PROCESS | 13071 | 08:31:54 INFO - PROCESS | 13071 | [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 08:31:54 INFO - PROCESS | 13071 | Values: 4830.9 08:31:54 INFO - PROCESS | 13071 | -------- Summary: end -------- 08:31:54 INFO - PROCESS | 13071 | 08:31:55 INFO - TEST-INFO | 13071: exit 0 08:31:55 INFO - TEST-OK | v8_7 | took 40160ms 08:31:55 INFO - SUITE-END | took 1210s 08:31:55 INFO - Completed test suite (00:20:10) 08:31:55 INFO - Working with test: dromaeo_css 08:31:55 INFO - Generating results file: dromaeo_css 08:31:55 INFO - Working with test: kraken 08:31:55 INFO - Generating results file: kraken 08:31:55 INFO - javascript benchmark 08:31:55 INFO - Working with test: v8_7 08:31:55 INFO - Generating results file: v8_7 08:31:55 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:31:56 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 08:31:56 INFO - process_Request line: dromaeo_css 6668.31 graph.html#tests=[[72,64,35]] 08:31:56 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:31:56 INFO - process_Request line: kraken 1495.40 graph.html#tests=[[232,64,35]] 08:31:56 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 08:31:56 INFO - process_Request line: v8_7 19121.82 graph.html#tests=[[230,64,35]] 08:31:56 INFO - RETURN: dromaeo_css: 6668.31 08:31:56 INFO - RETURN: kraken: 1495.40 08:31:56 INFO - RETURN: v8_7: 19121.82 08:31:56 INFO - javascript benchmark 08:31:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3120.079576524925, 3172.397244523539, 3213.1645772099264, 3215.662355452679, 3237.320162765819, 4945.703257804535, 5048.974755126228, 5109.591232701384, 5135.127028405175, 5189.040027594898, 5045.697258164512, 5061.822836200734, 5076.593872490201, 5081.339425874631, 5148.433672296044, 4655.604273636736, 4667.836625718101, 4680.134175177603, 4746.240601503743, 4762.166620841339, 531.6509379310646, 4545.318202269669, 4653.7207767534055, 4690.788914498856, 4749.596284315838, 4585.885352866188, 4681.227597446417, 4740.0, 4776.140294746961, 4785.904281914342, 4681.204195286809, 4711.293306004092, 4712.986707857793, 4713.764311784397, 4747.264174053027, 4571.657125715551, 4637.536246375369, 4677.532246775329, 4728.621710263171, 4771.665983381165, 4966.751662416865, 4985.850424487271, 5002.274670172841, 5026.246063090565, 5047.64666473347, 5513.09034009392, 5532.612717109804, 5545.112781954868, 5545.417731138253, 5546.0, 5421.403645598981, 5492.697901615409, 5562.3047119110115, 5574.526165275915, 5620.269364982526, 5083.694978301313, 5165.870853228662, 5229.032628963578, 5255.63210575256, 5273.261743355895, 5633.943660563424, 5639.943600563942, 5648.774049037993, 5649.406812284733, 5705.71471426427, 5384.542313903362, 5440.183972404171, 5443.564514838803, 5448.210009548593, 5451.809186678447, 5375.4624537546715, 5447.782088716486, 5526.253955716007, 5564.4713752193475, 5594.41258667834, 5350.010248104113, 5398.055340315429, 5412.621116521885, 5414.458554144554, 5414.512693857571, 5396.2984811974975, 5410.675359478444, 5416.83749487516, 5421.83734487966, 5449.945500545023, 4220.725652832618, 4222.387753775685, 4242.130363275521, 4244.681648876347, 4260.659147268211, 3805.448210009533, 3809.7333186676665, 3814.9237015260096, 3848.826802793881, 3855.036240939765, 4961.131801934647, 5029.371328583927, 5032.647714659939, 5034.421041580192, 5081.974590127025, 5407.540359069444, 5515.944840551543, 5561.221428999943, 5566.0, 5612.607117501817, 5056.317397151411, 5106.514881086291, 5108.259302401131, 5113.3097031900215, 5157.4584668610005, 4619.930701039487, 4689.788959496831, 4692.741899195577, 4698.553637404441, 4699.624030077585, 5734.6845923473775, 5735.426457354315, 5764.538836893039, 5782.624129431573, 5811.0411782056335, 5474.644148130359, 5492.313460817398, 5495.862603434946, 5498.395176530498, 5499.752511137089, 5581.162825576033, 5641.887162256814, 5643.097104463266, 5662.122371032497, 5705.857353566194, 5477.72611369422, 5499.230107784833, 5499.587530935116, 5506.752196151103, 5511.22842802016, 5563.860903477445, 5571.0, 5581.7209139543675, 5583.190437386637, 5584.664920104807, 5352.250684904038, 5409.242706021081, 5419.485148910847, 5475.72621368922, 5482.39693633708, 5351.973240133774, 5381.75782089799, 5382.703951282717, 5388.973055134699, 5422.864428389322, 5201.453847346125, 5228.268042474133, 5241.921371179359, 5296.285001524745, 5298.682079075268, 5334.3865455473115, 5381.0, 5392.029434701792, 5422.728863556886, 5433.293671822637, 5178.275041494118, 5298.099323115002, 5344.545713614386, 5349.384820745664, 5384.030874442638], "unit": "score", "name": "dojo.html", "value": 5047.146196171087}, {"lowerIsBetter": false, "replicates": [540.2490538151969, 548.1531034551617, 551.5064017704152, 568.8691600931784, 581.2240658720608, 28100.437991240164, 28844.0, 36939.81530092349, 37809.0, 37861.0, 39931.0, 40308.0, 40329.0, 40385.192296154135, 40486.19027619446, 8478.745637630866, 8647.30821534279, 8684.8697269541, 8705.173008564176, 8710.128987101303, 8358.498490090584, 8507.872381914276, 8514.70198543051, 8516.276116530102, 8517.36119791018, 8676.783080422976, 8751.956240218822, 8759.124087591223, 8761.7809554761, 8767.123287671246, 8618.310535157203, 8683.261922736576, 8684.044755076937, 8694.652213911437, 8695.608697608592, 8544.530050847201, 8690.348223883235, 8695.565221738887, 8696.434731742416, 8698.47809131454, 39690.60309396898, 40297.0, 40357.798211008754, 40362.0, 40427.797861010506, 3197.456432406473, 3231.2083539532728, 3301.8679252830098, 3510.543629328171, 4011.7191796574252, 26679.733202667918, 27020.054298099472, 27163.864180679167, 27193.776280067443, 27367.589486157725, 3660.359437098497, 3666.853325866962, 3709.9443508347126, 3727.1800203955086, 3747.5502939647417, 267088.0, 268196.0, 268623.0, 268914.999999998, 269240.0, 51905.48094519082, 52465.47534524682, 57752.0, 58033.12950305749, 58147.0, 68137.3186268131, 69166.0, 69326.0, 69332.653336733, 69402.0, 12856.807147892789, 12959.0, 12967.675808104872, 13025.218486890817, 13142.4085916134, 9677.080677335642, 9722.0, 9724.562394692255, 9910.950445247727, 10310.536025878853, 18234.811822581374, 18288.359907403443, 18335.0, 18402.35591754282, 18417.355392561196, 7401.740939067106, 7632.511660226313, 7652.06441845018, 7662.1571627120975, 7738.574378409242, 958.4728399380313, 987.9357841740264, 989.8564708117283, 1008.4000019988096, 1063.1654151491198, 27267.181984540497, 28496.572551411744, 28961.42077158487, 29157.41685166285, 29379.118626441243, 1021.1626466297713, 1056.4400867540216, 1136.9260998035102, 1155.0124643429915, 1179.1745777955466, 14710.926445367919, 15018.774718379233, 15058.54824355271, 15238.161901095546, 15268.389264429521, 161055.19472402564, 161621.0, 161763.0, 162029.18985405233, 163145.0, 52405.47594524006, 52711.736441317546, 52724.20913686298, 52735.472645273054, 52782.20826687603, 1336.111485861891, 1338.906276560651, 1339.3370281710615, 1343.1068339554088, 1352.114365090867, 27398.0, 28378.736445308226, 28526.85736571304, 28732.13803585896, 28763.85618071896, 46449.303260451794, 46791.766041169576, 46857.765711171225, 46890.76554617205, 46915.296270555285, 311233.0, 312462.0, 312537.0, 315000.0, 315277.0, 27516.449671006867, 30835.38329233358, 31209.0, 31326.373472529966, 31581.68418315879, 39091.218175636895, 41457.58542414537, 41847.37228941629, 42467.3629895558, 42638.78680606577, 35084.64915350814, 35377.469337960465, 35409.0, 35775.46336805002, 36405.0, 29504.704952951055, 34649.82675086609, 35129.64870351264, 35736.28527429489, 36201.275974480886, 32347.19132021718, 32558.83720581382, 32584.51123233106, 32643.673563264063, 32759.18102047468, 28458.85770571134, 30111.698883011766, 31837.6816231844, 31883.68116318807, 31923.36153276968, 24529.50940981206, 24596.139135130186, 24626.87686561556, 24972.0, 25017.74982250154], "unit": "score", "name": "ext.html", "value": 17691.668738222943}, {"lowerIsBetter": false, "replicates": [361.34235691042295, 365.21478820535845, 366.0829621797397, 367.51488035792755, 371.563412989737, 13059.347032648377, 13380.264085475295, 15813.683726325467, 16058.0, 16469.09419981902, 14113.788293175585, 14869.182194979272, 15307.0, 15311.387544498206, 15750.76373854393, 5073.5941124710025, 5275.762590683409, 5277.815276465324, 5278.076336641092, 5280.181571856349, 3913.3542965410816, 4059.8782036538805, 5179.223116532513, 5269.394019687747, 5629.690367029812, 5759.884802303952, 5784.074548072309, 5805.36141024487, 5809.651420914737, 5815.156802263669, 4952.900941981158, 5143.588512918957, 5149.0216858797085, 5162.819301324435, 5163.1480805667015, 4854.0, 4954.603631709455, 5021.723805190713, 5022.974885125587, 5028.296038554606, 12377.76222377764, 12563.497460101586, 12953.935230323883, 13072.86927130726, 13113.082084254105, 2068.917243310266, 2114.397396741921, 2220.4004918671867, 2279.1453205048106, 2337.0768546424238, 5488.231647569343, 8783.38516303859, 8870.778230544223, 8881.955590222073, 8945.284377249869, 1720.3720641965717, 1778.315348590789, 1805.5034865411985, 1821.4990877508658, 1835.715464103066, 56659.0, 58617.70691146517, 58673.413265867224, 58863.0, 58935.70532147355, 9157.679481218125, 9258.166764991183, 9367.84539555906, 9394.295427842939, 9491.47796871165, 11841.111916606289, 11861.940690296493, 11880.40597970098, 11881.88118118825, 11886.524539018515, 6949.478789090838, 7158.2483839197175, 7178.353948144692, 7221.277872212737, 7242.529235599669, 6597.934020659828, 6645.0, 6645.734170633121, 6766.221884483348, 6819.24988251292, 3968.2460332536725, 4088.5707000765087, 4117.711760176759, 4342.1749867525705, 4369.890752731207, 1692.5007122898774, 1744.6161844394212, 1773.556610847288, 1831.32241070802, 1853.6848735714962, 903.5256490342623, 938.6714649872627, 946.7065209784851, 962.4610218277647, 1015.817152912468, 13791.586252412444, 13886.513972010931, 14654.120752754869, 16075.678486430439, 16241.2691428886, 886.259972922604, 888.1651247827022, 902.069511832847, 933.169479163545, 995.5072239241622, 9158.358914875895, 9360.672376466791, 9399.530023498797, 9495.857562136573, 9541.713748587554, 377919.0, 378423.0, 379131.0, 383523.0, 460848.0, 56252.43747562472, 62561.0, 70559.64720176469, 72148.0, 76512.0, 1111.9444027798577, 1143.6283207957447, 1149.1725957310723, 1156.0520373293987, 1163.4241050679968, 14679.779803303172, 14810.555683329518, 15022.906812609977, 15111.773323399937, 15268.541943741706, 29588.556171657874, 29631.0, 29813.10560683183, 30753.231169220053, 31285.217869553442, 39396.0, 39452.605473944895, 39922.800385997885, 40869.59130408658, 42129.57870421379, 7061.329173728487, 7566.470347075872, 7615.771526854203, 7663.118741344817, 7673.4244931631465, 9327.253819694544, 11080.168987326144, 11152.776944461228, 11537.0, 11655.067594592581, 9542.570584323861, 9631.903680963382, 9887.604495820087, 9954.303198776306, 10041.096301332915, 9324.440533568008, 9841.901580984098, 10221.0, 10243.94878025605, 10263.127634150882, 9484.430934143973, 9706.563204655666, 9999.20006399501, 10327.225458090823, 10374.58501659925, 7318.121825380988, 7458.0, 7485.70057197706, 7498.58757768328, 7541.886871696819, 9184.954075229582, 9920.0, 10064.295499314978, 10168.186545076524, 10245.0], "unit": "score", "name": "jquery.html", "value": 7783.186499463707}, {"lowerIsBetter": false, "replicates": [1336.7994800779888, 1470.330999395275, 1470.8896832737544, 1627.1294857251366, 1791.5431564950936, 6578.243501997272, 7625.389968802497, 7804.492707973977, 7811.0, 7814.3357814585825, 7711.920331153643, 7926.92073079269, 7933.404994625412, 7942.602869856499, 7948.324392426636, 1953.1308567687386, 2000.5698774763423, 2276.9658455123144, 2293.8050265727434, 2298.7241531016302, 2349.7650234976536, 2356.140008896749, 2361.8464799788044, 2363.267387109993, 2370.9525809483885, 2275.283285764987, 2339.789418952294, 2341.6019276722914, 2342.367560758594, 2344.120954642009, 1968.6456437841182, 2011.7988201179837, 2276.726792784868, 2315.5253173099472, 2323.2217207235594, 2301.344116926685, 2341.2390972933695, 2347.8473899196497, 2389.7132344118645, 2390.9163179288817, 7793.883091753628, 7806.921930780733, 7863.292303692638, 7867.96066019672, 7874.803129921741, 4746.38297021385, 4866.099771542276, 4899.093667671492, 4902.681325713817, 4904.215325547895, 3115.454795410794, 3132.8746850126195, 3176.952345714816, 3196.568463257477, 3206.8556914938886, 990.9504524773805, 996.7707427291691, 1014.7666036811501, 1040.8907064758166, 1051.4217180550665, 11163.720906977309, 11176.608818691308, 11189.888101118966, 11196.66410007701, 11204.551817927273, 4394.274944634163, 4497.460304763449, 4563.817447302137, 4567.725936443824, 4577.22187228172, 9543.045695430541, 9596.66411675602, 9608.423494590346, 9611.519424028771, 9616.038396160327, 4907.214845624683, 4908.533689299511, 4936.8765780855765, 4945.55490005894, 4945.67853089548, 4430.3575981483145, 4450.06548624786, 4472.619827314649, 4485.887852803706, 4498.3252512123445, 3631.1829838286176, 3721.4603882436895, 3735.831887565066, 3739.495168152319, 3753.6621704046765, 1258.3330834657645, 1266.5946896992873, 1266.8796464335874, 1267.2903174222465, 1289.5293217975372, 920.7606022434215, 960.4381436859376, 1276.9170003949898, 1277.4953893212314, 1297.9805302920463, 7397.445191610543, 7486.625668716542, 7603.46775725705, 7604.163542010375, 7612.086549614081, 624.709510077816, 625.2212455563913, 627.6579264300967, 645.1033064040987, 647.9935200647933, 5641.12562552805, 5688.089905615082, 5690.630109042899, 5712.485876271073, 5717.1995920570325, 9571.664991725396, 9765.267604929517, 9862.802743945225, 9897.554610042565, 9902.950485247527, 9516.286278529138, 9629.085236902483, 9762.46306453152, 9772.706818795448, 9819.263555233387, 7619.276168763959, 7625.809354766065, 7679.347255483346, 7686.923130768732, 7707.807304817425, 7334.963325183553, 7351.632418379167, 7382.114146302263, 7430.034095567542, 7471.103467583925, 8593.44142630727, 8644.654213831627, 8674.739757807276, 8701.0, 8879.0, 8847.24798392144, 9073.45559266446, 9110.498922559324, 9136.771580710536, 9199.356045077047, 7120.145582529923, 7167.964160179375, 7196.172440169447, 7197.71209151628, 7213.458990575623, 7675.2324767522805, 7701.499402538817, 7744.49660772048, 7770.378369730521, 7776.105747838845, 7300.744473943387, 7334.266573342623, 7425.294597013275, 7536.0956685198125, 7538.698452062075, 6814.557053791487, 6878.518503704893, 6994.090768200102, 7210.639468026473, 7442.06974128234, 6146.262448506208, 6263.6241825490615, 6825.0, 7002.859942801014, 7014.719411223494, 6506.512011599054, 6543.378379053982, 6571.638559879061, 6581.14445122131, 6582.539222254588, 6550.8362290943105, 6587.9670601646685, 6600.273969863407, 6612.140421745335, 6627.569208001464], "unit": "score", "name": "mootools.html", "value": 4520.49976379042}, {"lowerIsBetter": false, "replicates": [341.62250712962174, 341.94528875379945, 349.69052388636067, 352.218075871565, 354.9025260709209, 5875.353711091781, 5880.411958804118, 5927.555433342495, 5964.13520039594, 5978.372270911546, 5863.0, 5869.7358618862145, 5871.0, 5871.266091738533, 5890.911636325448, 4446.666500012496, 4623.237165867637, 4630.560096790799, 4633.0, 4633.768311584424, 4591.6097131743845, 4592.655550833684, 4594.21898277292, 4602.378678878359, 4604.953950460486, 4544.659150563704, 4546.0680560485025, 4549.61328287096, 4549.886252843672, 4560.33875088112, 4291.785410729451, 4362.978185109086, 4366.366876802877, 4396.868093957154, 4541.364209010741, 3813.9809300953316, 3867.8066096695334, 3961.960380396188, 3966.7421617594764, 3970.5235371755284, 5547.639403438763, 5560.888782224333, 5562.666240025612, 5564.666120032811, 5577.274954255961, 4953.0589188054155, 5099.235114732783, 5209.6874187548865, 5226.764795584169, 5234.398044224887, 6005.309389420229, 6164.96917515414, 6169.537284703621, 6197.628142311476, 6203.8138855834395, 4299.07569872479, 4300.526942036374, 4313.482382114166, 4318.244307246219, 4337.609615134653, 6397.8400539986405, 6480.31956644555, 6481.060246264313, 6481.578697384702, 6487.772927947499, 4349.891252718644, 4468.352088947085, 4472.888177795581, 4486.416765820422, 4492.797824097924, 4532.9546704533195, 4535.569120933506, 4537.0925814837165, 4542.250528662799, 4542.613877820422, 5199.584033277404, 5237.73811309433, 5239.0, 5246.186841039568, 5257.106291930381, 6106.145139680449, 6241.157443745127, 6263.217097862767, 6268.623882567061, 6270.0, 1590.4433448293005, 1608.0592853180785, 1608.7828143200752, 1608.9919550402174, 1610.3558576569467, 1073.4418102586767, 1082.610260306289, 1085.6580177244143, 1102.415719668561, 1106.3527836215903, 5893.292804863444, 5901.675407852524, 5906.202662640489, 5908.261467316585, 5908.704564771744, 6392.648404337806, 6395.808125756234, 6397.424231819158, 6403.807885763435, 6408.807735767934, 5902.8819423611285, 5903.35063143054, 5907.438793314628, 5907.852303692442, 5907.852303692442, 3363.327334533103, 3463.79217246966, 3471.0628130405153, 3483.8083905385447, 3487.877924272638, 6777.559458635173, 6784.21981472137, 6784.59292442455, 6784.864302713918, 6790.354916282945, 6539.444147247537, 6560.507961902877, 6560.934390656128, 6567.6059436433015, 6570.8357291068105, 6255.937440625536, 6318.810435686844, 6706.96646516774, 6751.987201919653, 6771.559848609923, 6425.742970281136, 6439.259485159267, 6442.452391546771, 6451.129097571956, 6472.935270647234, 6289.127790250225, 6380.904286435614, 6391.808245752635, 6397.0, 6401.487880969605, 6719.160104986877, 6772.390484856386, 6776.966115169393, 6777.288384719731, 6779.830504237236, 4469.620082292911, 4477.082198149306, 4506.842260520866, 4512.796924138356, 4513.7066090704, 6491.350864913471, 6536.542442029012, 6558.311377305505, 6561.442277406474, 6564.081028656087, 6188.783392581418, 6277.654728989949, 6305.864944309885, 6309.526785491014, 6312.621242725451, 6293.213348331458, 6301.495880329654, 6301.62190268567, 6307.180066591313, 6309.211348581427, 6159.260888693386, 6185.876282474415, 6193.876122477615, 6194.473469754941, 6199.969000154971, 6179.876402472015, 6330.3669633036325, 6330.430261276507, 6345.809625711236, 6347.492200623847, 6176.969115154396, 6189.783357582463, 6194.318624951162, 6194.4115309045565, 6201.875962480635], "unit": "score", "name": "prototype.html", "value": 4731.752397767608}, {"lowerIsBetter": false, "replicates": [699.6676578625154, 722.4401089155907, 748.5359077372028, 763.4083585221452, 778.686079121666, 11528.020118289935, 11535.077193824494, 11576.189666723332, 11608.651740447789, 11753.177277590561, 11565.190436669436, 11720.589779357724, 11765.352905590189, 11849.703757406069, 11875.7624847503, 674.6222115615246, 677.7627830259395, 789.4237206838997, 804.5775967616989, 805.4643661964786, 786.0, 797.0634504457269, 804.4811096842533, 804.666063583615, 805.3677862877643, 847.1824689174945, 851.1318455175717, 851.3274513134647, 851.7700220940343, 852.1180578101666, 699.7131176217752, 706.6537396675618, 828.6188353357463, 842.839860426517, 844.3709436469869, 614.4285814192799, 618.3785295777759, 618.7370367593754, 619.2599843187393, 619.7490016543308, 12124.090693198046, 12161.209521381083, 12185.45165467556, 12187.0, 12193.695157621041, 12206.633800985985, 12313.56902508408, 12518.624441266686, 12693.619191424272, 12834.240638262818, 10233.334833235816, 10234.744131396774, 10256.589736410535, 10323.741906452324, 10450.529726162342, 3556.8577256909693, 3596.3166998270244, 3626.4016437287814, 3627.3652110880753, 3731.477593136963, 45938.08123837538, 47049.0, 47794.28308575374, 47839.282410763866, 49312.75343623259, 9281.21109705669, 9501.619935202652, 9572.856407153899, 9577.138057574852, 9689.32174747775, 16714.331426742796, 17037.48887533376, 17038.659226815642, 17040.14799260032, 17047.48857534276, 7497.437692173109, 7508.737194198176, 7614.2766437188375, 7646.923530764732, 7686.615669216517, 4252.638525725348, 4343.956560434355, 4398.472183337956, 4420.513743488278, 4494.797734101973, 3043.5434684797015, 3046.7257946784894, 3057.5260834570677, 3063.6170478690165, 3070.831104289241, 576.3314555116045, 576.9249997500298, 581.0644861772543, 583.5302581421996, 593.7684303121786, 471.9150552900442, 575.6229669566441, 593.9851503712442, 649.6394501051867, 664.8332177029379, 7899.68401263943, 8194.50832950025, 8284.212999765012, 8345.41582089248, 8355.749327520183, 545.9153831156177, 617.1113596421139, 625.2465778736612, 633.5470138850744, 657.2769953051605, 3814.6376094271, 3878.398848178537, 3911.2373087247715, 3913.5890731473355, 3937.9606203938165, 51162.744186279575, 52330.47669523332, 52863.0, 52890.73554632202, 53065.0, 46895.531044689116, 48502.27246591304, 48607.5139248603, 48727.269090963666, 49215.507844921805, 42049.0, 42400.78799605982, 43241.78379108147, 43363.0, 43509.56490435119, 7722.474509039852, 7725.613719313899, 7870.134285228734, 7884.881726774218, 7930.246626570467, 32522.34955300928, 33534.832325838215, 33591.496127558596, 33780.98657040293, 33957.0, 38388.80805595954, 41274.79362603168, 41874.0, 42055.7897210512, 42092.36861447142, 9339.719808405758, 9456.669016584387, 9481.810363792823, 9523.428594284365, 9556.331056825955, 10169.542370593193, 10251.58993640246, 10403.687889363331, 10631.840522392016, 10757.139428845518, 9647.517624118625, 9660.420374777535, 9705.5147242639, 9715.319927605282, 9942.453165075991, 9995.600175992879, 10103.0, 10466.842997355197, 10497.685069447929, 10511.0, 9758.316917815684, 9935.751606209902, 10169.440680762333, 10223.335483193569, 10322.535485903003, 7733.149353571215, 7894.802629934297, 8181.922636919141, 8202.917970820215, 8260.958695206486, 8141.0, 8520.0, 8529.488230706178, 8598.78503037429, 8620.956895215484], "unit": "score", "name": "yui.html", "value": 5913.96025520778}], "name": "dromaeo_css", "value": 6668.313077814245}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 110.0, 105.0, 109.0, 110.0, 111.0, 101.0, 110.0, 110.0, 110.0], "unit": "score", "name": "ai-astar", "value": 108.5}, {"lowerIsBetter": true, "replicates": [152.0, 126.0, 151.0, 160.0, 125.0, 155.0, 150.0, 127.0, 160.0, 125.0], "unit": "score", "name": "audio-beat-detection", "value": 143.1}, {"lowerIsBetter": true, "replicates": [160.0, 161.0, 161.0, 161.0, 161.0, 162.0, 161.0, 162.0, 162.0, 161.0], "unit": "score", "name": "audio-dft", "value": 161.2}, {"lowerIsBetter": true, "replicates": [72.0, 72.0, 90.0, 94.0, 72.0, 71.0, 73.0, 99.0, 88.0, 71.0], "unit": "score", "name": "audio-fft", "value": 80.2}, {"lowerIsBetter": true, "replicates": [103.0, 96.0, 141.0, 95.0, 96.0, 96.0, 138.0, 96.0, 97.0, 142.0], "unit": "score", "name": "audio-oscillator", "value": 110.0}, {"lowerIsBetter": true, "replicates": [109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 147.0, 145.0], "unit": "score", "name": "imaging-darkroom", "value": 145.5}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [56.0, 55.0, 55.0, 56.0, 55.0, 57.0, 56.0, 65.0, 53.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 56.4}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 69.0, 68.0, 68.0, 68.0, 67.0, 67.0, 67.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.8}, {"lowerIsBetter": true, "replicates": [83.0, 84.0, 84.0, 84.0, 89.0, 83.0, 85.0, 84.0, 87.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.7}, {"lowerIsBetter": true, "replicates": [117.0, 122.0, 118.0, 115.0, 113.0, 131.0, 117.0, 118.0, 117.0, 131.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 119.9}, {"lowerIsBetter": true, "replicates": [141.0, 140.0, 151.0, 151.0, 152.0, 141.0, 151.0, 148.0, 151.0, 138.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 146.4}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 66.0, 66.0, 67.0, 65.0, 66.0, 70.0, 67.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.3}], "name": "kraken", "value": 1495.4}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.33601070950468], "unit": "score", "name": "Richards", "value": 210.96475200000003}, {"lowerIsBetter": false, "replicates": [190.5487804878049], "unit": "score", "name": "DeltaBlue", "value": 346.9872640000001}, {"lowerIsBetter": false, "replicates": [279.01785714285717], "unit": "score", "name": "Encrypt", "value": 953.9927040000001}, {"lowerIsBetter": false, "replicates": [5175.257731958763], "unit": "score", "name": "Decrypt", "value": 51.433380478087685}, {"lowerIsBetter": false, "replicates": [1921.3051823416506], "unit": "score", "name": "RayTrace", "value": 385.1491198801198}, {"lowerIsBetter": false, "replicates": [788.6435331230284], "unit": "score", "name": "Earley", "value": 845.075084}, {"lowerIsBetter": false, "replicates": [11904.761904761905], "unit": "score", "name": "Boyer", "value": 55.98289200000003}, {"lowerIsBetter": false, "replicates": [42375.0], "unit": "score", "name": "RegExp", "value": 21.49817109144544}, {"lowerIsBetter": false, "replicates": [568.1818181818181], "unit": "score", "name": "Splay", "value": 143.42415999999997}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 19121.824780637617}]} 08:31:56 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "19121.82"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1495.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6668.31"}}} 08:31:56 INFO - Return code: 0 08:31:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 08:31:56 INFO - # TBPL SUCCESS # 08:31:56 INFO - Running post-action listener: _resource_record_post_action 08:31:56 INFO - Running post-run listener: _resource_record_post_run 08:31:57 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 8192; Write bytes: 417247232; Read time: 288; Write time: 267384 08:31:57 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 4096; Write bytes: 118677504; Read time: 104; Write time: 78252 08:31:57 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 4096; Write bytes: 298569728; Read time: 184; Write time: 189132 08:31:57 INFO - Running post-run listener: _upload_blobber_files 08:31:57 INFO - Blob upload gear active. 08:31:57 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 08:31:57 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 08:31:57 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 08:31:57 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Fx-Team -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 08:31:57 INFO - (blobuploader) - INFO - Open directory for files ... 08:31:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:31:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:31:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:58 INFO - (blobuploader) - INFO - Done attempting. 08:31:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 08:31:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:31:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:31:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:31:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:31:59 INFO - (blobuploader) - INFO - Done attempting. 08:31:59 INFO - (blobuploader) - INFO - Iteration through files over. 08:31:59 INFO - Return code: 0 08:31:59 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 08:31:59 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 08:31:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8787f7c49cf59bcceb0025200b37c151bf77173faacaec0ab126985256bc54970b0e2686295e63731f2b7f33020b9d8927ebb82e4af09657c43c015a8b253b7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c29f5c4b6e9b5b6127479a29bd65d7dbbb0cfe8b48eec2077fb06f057914d308f03a0963af72cd00fdaa1ec023c9d1da75b65879a7f59145ffdaa4b89806692"} 08:31:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 08:31:59 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 08:31:59 INFO - Contents: 08:31:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8787f7c49cf59bcceb0025200b37c151bf77173faacaec0ab126985256bc54970b0e2686295e63731f2b7f33020b9d8927ebb82e4af09657c43c015a8b253b7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c29f5c4b6e9b5b6127479a29bd65d7dbbb0cfe8b48eec2077fb06f057914d308f03a0963af72cd00fdaa1ec023c9d1da75b65879a7f59145ffdaa4b89806692"} 08:31:59 INFO - Copying logs to upload dir... 08:31:59 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1292.936225 ========= master_lag: 1.74 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 34 secs) (at 2016-01-12 08:32:00.876504) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-12 08:32:00.880467) ========= 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=db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127 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/8787f7c49cf59bcceb0025200b37c151bf77173faacaec0ab126985256bc54970b0e2686295e63731f2b7f33020b9d8927ebb82e4af09657c43c015a8b253b7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c29f5c4b6e9b5b6127479a29bd65d7dbbb0cfe8b48eec2077fb06f057914d308f03a0963af72cd00fdaa1ec023c9d1da75b65879a7f59145ffdaa4b89806692"} build_url:https://queue.taskcluster.net/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011423 build_url: 'https://queue.taskcluster.net/v1/task/Qh1D1Z9fRcGlBGogduUCGA/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8787f7c49cf59bcceb0025200b37c151bf77173faacaec0ab126985256bc54970b0e2686295e63731f2b7f33020b9d8927ebb82e4af09657c43c015a8b253b7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3c29f5c4b6e9b5b6127479a29bd65d7dbbb0cfe8b48eec2077fb06f057914d308f03a0963af72cd00fdaa1ec023c9d1da75b65879a7f59145ffdaa4b89806692"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-12 08:32:00.944276) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 29 secs) (at 2016-01-12 08:32:00.944557) ========= 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=db0483c52d987ecb441a263b000001ad-1452608020.689043-736224127 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004660 ========= master_lag: 29.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 29 secs) (at 2016-01-12 08:32:30.032438) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-12 08:32:30.032791) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-12 08:32:30.102474) ========= ========= Total master_lag: 31.02 =========