builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1450476264.26 results: success (0) buildid: 20151218113010 builduid: 5f314976ac3b46ab8423c106bc92f780 revision: 79e8243e94f78f6f599ad2a10ed6ede11b351cd3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.259001) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.259527) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.259828) ========= 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=fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006359 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.04 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.305657) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.306007) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.407011) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.407298) ========= 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=fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004559 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.463498) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.463788) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.464164) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.464494) ========= 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=fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-18 14:04: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 36.9M=0s 2015-12-18 14:04:25 (36.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.170154 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.655245) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.655598) ========= 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=fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016999 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:24.707459) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-18 14:04:24.707782) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 79e8243e94f78f6f599ad2a10ed6ede11b351cd3 --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 79e8243e94f78f6f599ad2a10ed6ede11b351cd3 --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=fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-18 14:04:25,394 truncating revision to first 12 chars 2015-12-18 14:04:25,394 Setting DEBUG logging. 2015-12-18 14:04:25,395 attempt 1/10 2015-12-18 14:04:25,395 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/79e8243e94f7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-18 14:04:26,015 unpacking tar archive at: fx-team-79e8243e94f7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.425679 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-18 14:04:26.148069) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:26.148360) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:26.160327) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:26.160790) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-18 14:04:26.161430) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2015-12-18 14:04:26.161853) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --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', '--system-bits', '64', '--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=fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:04:26 INFO - MultiFileLogger online at 20151218 14:04:26 in /builds/slave/test-pgo 14:04:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 14:04:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 14:04:26 INFO - {'append_to_log': False, 14:04:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 14:04:26 INFO - 'blob_upload_branch': 'Fx-Team', 14:04:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 14:04:26 INFO - 'branch': 'Fx-Team', 14:04:26 INFO - 'buildbot_json_path': 'buildprops.json', 14:04:26 INFO - 'config_files': ('talos/linux_config.py',), 14:04:26 INFO - 'default_actions': ('clobber', 14:04:26 INFO - 'read-buildbot-config', 14:04:26 INFO - 'download-and-extract', 14:04:26 INFO - 'populate-webroot', 14:04:26 INFO - 'create-virtualenv', 14:04:26 INFO - 'install', 14:04:26 INFO - 'run-tests'), 14:04:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:04:26 INFO - 'download_minidump_stackwalk': True, 14:04:26 INFO - 'download_symbols': 'ondemand', 14:04:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:04:26 INFO - 'tooltool.py': '/tools/tooltool.py', 14:04:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:04:26 INFO - '/tools/misc-python/virtualenv.py')}, 14:04:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:04:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:04:26 INFO - 'installer_path': 'installer.exe', 14:04:26 INFO - 'log_level': 'info', 14:04:26 INFO - 'log_name': 'talos', 14:04:26 INFO - 'log_to_console': True, 14:04:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:04:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:04:26 INFO - 'opt_config_files': (), 14:04:26 INFO - 'pip_index': False, 14:04:26 INFO - 'sps_profile': False, 14:04:26 INFO - 'sps_profile_interval': 0, 14:04:26 INFO - 'suite': 'dromaeojs', 14:04:26 INFO - 'system_bits': '64', 14:04:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:04:26 INFO - 'title': 'talos-linux64-ix-046', 14:04:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:04:26 INFO - 'use_talos_json': True, 14:04:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 14:04:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:04:26 INFO - 'work_dir': 'build'} 14:04:26 INFO - ##### 14:04:26 INFO - ##### Running clobber step. 14:04:26 INFO - ##### 14:04:26 INFO - Running pre-action listener: _resource_record_pre_action 14:04:26 INFO - Running main action method: clobber 14:04:26 INFO - rmtree: /builds/slave/test-pgo/build 14:04:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 14:04:27 INFO - Running post-action listener: _resource_record_post_action 14:04:27 INFO - ##### 14:04:27 INFO - ##### Running read-buildbot-config step. 14:04:27 INFO - ##### 14:04:27 INFO - Running pre-action listener: _resource_record_pre_action 14:04:27 INFO - Running main action method: read_buildbot_config 14:04:27 INFO - Using buildbot properties: 14:04:27 INFO - { 14:04:27 INFO - "project": "", 14:04:27 INFO - "product": "firefox", 14:04:27 INFO - "script_repo_revision": "production", 14:04:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 14:04:27 INFO - "repository": "", 14:04:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 14:04:27 INFO - "buildid": "20151218113010", 14:04:27 INFO - "pgo_build": "True", 14:04:27 INFO - "basedir": "/builds/slave/test-pgo", 14:04:27 INFO - "buildnumber": 553, 14:04:27 INFO - "slavename": "talos-linux64-ix-046", 14:04:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:04:27 INFO - "platform": "ubuntu64_hw", 14:04:27 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 14:04:27 INFO - "branch": "fx-team", 14:04:27 INFO - "repo_path": "integration/fx-team", 14:04:27 INFO - "revision": "79e8243e94f78f6f599ad2a10ed6ede11b351cd3", 14:04:27 INFO - "stage_platform": "linux64-pgo", 14:04:27 INFO - "builduid": "5f314976ac3b46ab8423c106bc92f780", 14:04:27 INFO - "slavebuilddir": "test" 14:04:27 INFO - } 14:04:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 14:04:27 INFO - Running post-action listener: _resource_record_post_action 14:04:27 INFO - ##### 14:04:27 INFO - ##### Running download-and-extract step. 14:04:27 INFO - ##### 14:04:27 INFO - Running pre-action listener: _resource_record_pre_action 14:04:27 INFO - Running main action method: download_and_extract 14:04:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 14:04: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')]} 14:04:27 INFO - https://queue.taskcluster.net/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 14:04:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/test_packages.json 14:04:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/test_packages.json 14:04:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 14:04:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 14:04:41 INFO - Downloaded 1302 bytes. 14:04:41 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 14:04:41 INFO - Using the following test package requirements: 14:04:41 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 14:04:41 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:04:41 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:41 INFO - u'jsshell-linux-x86_64.zip'], 14:04:41 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:04:41 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 14:04:41 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:04:41 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:04:41 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:04:41 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 14:04:41 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 14:04:41 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:04:41 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:04:41 INFO - https://queue.taskcluster.net/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:04:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 14:04:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 14:04:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/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 14:04:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/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 14:04:52 INFO - Downloaded 23121263 bytes. 14:04:52 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 14:04:52 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 14:04:52 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 14:04:53 INFO - Return code: 0 14:04:53 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 14:04:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:04:53 INFO - https://queue.taskcluster.net/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:04:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 14:04:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 14:04:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/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 14:04:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/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 14:04:57 INFO - Downloaded 23121263 bytes. 14:04:57 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 14:04:57 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 14:04:57 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 14:04:58 INFO - Return code: 0 14:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:04:58 INFO - https://queue.taskcluster.net/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:04:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 14:04:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 14:04:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/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 14:04:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/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 14:05:02 INFO - Downloaded 11132039 bytes. 14:05:02 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 14:05:02 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 14:05:02 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 14:05:03 INFO - Return code: 0 14:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:03 INFO - https://queue.taskcluster.net/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:05:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 14:05:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 14:05:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/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 14:05:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/IXXb3hXPSYivPVMXRQRQrg/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 14:05:08 INFO - Downloaded 54642602 bytes. 14:05:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 14:05:08 INFO - mkdir: /builds/slave/test-pgo/properties 14:05:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 14:05:08 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 14:05:08 INFO - Contents: 14:05:08 INFO - build_url:https://queue.taskcluster.net/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 14:05:08 INFO - Running post-action listener: _resource_record_post_action 14:05:08 INFO - Running post-action listener: set_extra_try_arguments 14:05:08 INFO - ##### 14:05:08 INFO - ##### Running populate-webroot step. 14:05:08 INFO - ##### 14:05:08 INFO - Running pre-action listener: _resource_record_pre_action 14:05:08 INFO - Running main action method: populate_webroot 14:05:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:05:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:05:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:05:08 INFO - '--tppagecycles', 14:05:08 INFO - '7'], 14:05:08 INFO - 'tests': ['tsvgm']}}, 14:05:08 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:05:08 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:05:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:05:08 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:05:08 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:05:08 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 14:05:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:05:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:08 INFO - 'talos_options': ['--e10s'], 14:05:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:05:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:08 INFO - 'tests': ['damp', 'tps']}, 14:05:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:08 INFO - 'talos_options': ['--e10s'], 14:05:08 INFO - 'tests': ['damp', 'tps']}, 14:05:08 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:05:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:05:08 INFO - 'other': {'tests': ['a11yr', 14:05:08 INFO - 'ts_paint', 14:05:08 INFO - 'tpaint', 14:05:08 INFO - 'sessionrestore', 14:05:08 INFO - 'sessionrestore_no_auto_restore']}, 14:05:08 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:05:08 INFO - 'tests': ['a11yr', 14:05:08 INFO - 'ts_paint', 14:05:08 INFO - 'tpaint', 14:05:08 INFO - 'sessionrestore', 14:05:08 INFO - 'sessionrestore_no_auto_restore']}, 14:05:08 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:05:08 INFO - 'tests': ['a11yr', 14:05:08 INFO - 'ts_paint', 14:05:08 INFO - 'tpaint', 14:05:08 INFO - 'sessionrestore', 14:05:08 INFO - 'sessionrestore_no_auto_restore']}, 14:05:08 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:05:08 INFO - 'tests': ['a11yr', 14:05:08 INFO - 'ts_paint', 14:05:08 INFO - 'tpaint', 14:05:08 INFO - 'sessionrestore', 14:05:08 INFO - 'sessionrestore_no_auto_restore']}, 14:05:08 INFO - 'other_l64': {'tests': ['a11yr', 14:05:08 INFO - 'ts_paint', 14:05:08 INFO - 'tpaint', 14:05:08 INFO - 'sessionrestore', 14:05:08 INFO - 'sessionrestore_no_auto_restore']}, 14:05:08 INFO - 'other_nol64': {'tests': ['a11yr', 14:05:08 INFO - 'ts_paint', 14:05:08 INFO - 'tpaint', 14:05:08 INFO - 'sessionrestore', 14:05:08 INFO - 'sessionrestore_no_auto_restore']}, 14:05:08 INFO - 'svgr': {'tests': ['tsvgx', 14:05:08 INFO - 'tsvgr_opacity', 14:05:08 INFO - 'tart', 14:05:08 INFO - 'tscrollx', 14:05:08 INFO - 'cart']}, 14:05:08 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:05:08 INFO - 'tests': ['tsvgx', 14:05:08 INFO - 'tsvgr_opacity', 14:05:08 INFO - 'tart', 14:05:08 INFO - 'tscrollx', 14:05:08 INFO - 'cart']}, 14:05:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:08 INFO - 'tests': ['tp5o']}, 14:05:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:08 INFO - 'talos_options': ['--e10s'], 14:05:08 INFO - 'tests': ['tp5o']}, 14:05:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:08 INFO - 'talos_options': ['--xperf_path', 14:05:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:05:08 INFO - 'tests': ['tp5n']}, 14:05:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:05:08 INFO - 'talos_options': ['--e10s', 14:05:08 INFO - '--xperf_path', 14:05:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:05:08 INFO - 'tests': ['tp5n']}}, 14:05:08 INFO - 'talos.zip': {'path': '', 14:05:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:05:08 INFO - Running post-action listener: _resource_record_post_action 14:05:08 INFO - ##### 14:05:08 INFO - ##### Running create-virtualenv step. 14:05:08 INFO - ##### 14:05:08 INFO - Running pre-action listener: _resource_record_pre_action 14:05:08 INFO - Running main action method: create_virtualenv 14:05:08 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 14:05:08 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 14:05:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 14:05:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:05:08 INFO - Using real prefix '/usr' 14:05:08 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 14:05:09 INFO - Installing distribute.............................................................................................................................................................................................done. 14:05:11 INFO - Installing pip.................done. 14:05:11 INFO - Return code: 0 14:05:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 14:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:11 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 0x7fc23269bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2578350>, '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 0x2579250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25796e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', '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 14:05:11 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 14:05:11 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 14:05:11 INFO - Using env: {'DISPLAY': ':0', 14:05:11 INFO - 'HOME': '/home/cltbld', 14:05:11 INFO - 'LANG': 'en_US.UTF-8', 14:05:11 INFO - 'LANGUAGE': 'en_US:en', 14:05:11 INFO - 'LOGNAME': 'cltbld', 14:05:11 INFO - 'MAIL': '/var/mail/cltbld', 14:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:11 INFO - 'MOZ_NO_REMOTE': '1', 14:05:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:11 INFO - 'NO_EM_RESTART': '1', 14:05:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:05:11 INFO - 'PWD': '/builds/slave/test-pgo', 14:05:11 INFO - 'SHELL': '/bin/bash', 14:05:11 INFO - 'SHLVL': '1', 14:05:11 INFO - 'TERM': 'linux', 14:05:11 INFO - 'TMOUT': '86400', 14:05:11 INFO - 'USER': 'cltbld', 14:05:11 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', 14:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:11 INFO - '_': '/tools/buildbot/bin/python'} 14:05:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:11 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 14:05:11 INFO - Cleaning up... 14:05:11 INFO - Return code: 0 14:05:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 14:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:11 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 0x7fc23269bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2578350>, '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 0x2579250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25796e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', '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 14:05:11 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 14:05:11 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 14:05:11 INFO - Using env: {'DISPLAY': ':0', 14:05:11 INFO - 'HOME': '/home/cltbld', 14:05:11 INFO - 'LANG': 'en_US.UTF-8', 14:05:11 INFO - 'LANGUAGE': 'en_US:en', 14:05:11 INFO - 'LOGNAME': 'cltbld', 14:05:11 INFO - 'MAIL': '/var/mail/cltbld', 14:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:11 INFO - 'MOZ_NO_REMOTE': '1', 14:05:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:11 INFO - 'NO_EM_RESTART': '1', 14:05:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:05:11 INFO - 'PWD': '/builds/slave/test-pgo', 14:05:11 INFO - 'SHELL': '/bin/bash', 14:05:11 INFO - 'SHLVL': '1', 14:05:11 INFO - 'TERM': 'linux', 14:05:11 INFO - 'TMOUT': '86400', 14:05:11 INFO - 'USER': 'cltbld', 14:05:11 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', 14:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:11 INFO - '_': '/tools/buildbot/bin/python'} 14:05:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:11 INFO - Downloading/unpacking psutil>=0.7.1 14:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:13 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 14:05:13 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 14:05:13 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 14:05:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:05:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:05:13 INFO - Installing collected packages: psutil 14:05:13 INFO - Running setup.py install for psutil 14:05:13 INFO - building 'psutil._psutil_linux' extension 14:05:13 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 14:05:13 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 14:05:13 INFO - building 'psutil._psutil_posix' extension 14:05:13 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 14:05:13 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 14:05:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:05:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:05:14 INFO - Successfully installed psutil 14:05:14 INFO - Cleaning up... 14:05:14 INFO - Return code: 0 14:05:14 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 14:05:14 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')]} 14:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:14 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')]} 14:05:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:14 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 0x7fc23269bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2578350>, '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 0x2579250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25796e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', '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 14:05:14 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 14:05:14 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 14:05:14 INFO - Using env: {'DISPLAY': ':0', 14:05:14 INFO - 'HOME': '/home/cltbld', 14:05:14 INFO - 'LANG': 'en_US.UTF-8', 14:05:14 INFO - 'LANGUAGE': 'en_US:en', 14:05:14 INFO - 'LOGNAME': 'cltbld', 14:05:14 INFO - 'MAIL': '/var/mail/cltbld', 14:05:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:14 INFO - 'MOZ_NO_REMOTE': '1', 14:05:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:14 INFO - 'NO_EM_RESTART': '1', 14:05:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:05:14 INFO - 'PWD': '/builds/slave/test-pgo', 14:05:14 INFO - 'SHELL': '/bin/bash', 14:05:14 INFO - 'SHLVL': '1', 14:05:14 INFO - 'TERM': 'linux', 14:05:14 INFO - 'TMOUT': '86400', 14:05:14 INFO - 'USER': 'cltbld', 14:05:14 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', 14:05:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:14 INFO - '_': '/tools/buildbot/bin/python'} 14:05:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:14 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05:15 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:05:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 14:05:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:05:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:05:16 INFO - Installing collected packages: mozsystemmonitor 14:05:16 INFO - Running setup.py install for mozsystemmonitor 14:05:16 INFO - Successfully installed mozsystemmonitor 14:05:16 INFO - Cleaning up... 14:05:16 INFO - Return code: 0 14:05:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 14:05:16 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')]} 14:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:16 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')]} 14:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:16 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 0x7fc23269bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2578350>, '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 0x2579250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25796e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', '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 14:05:16 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 14:05:16 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 14:05:16 INFO - Using env: {'DISPLAY': ':0', 14:05:16 INFO - 'HOME': '/home/cltbld', 14:05:16 INFO - 'LANG': 'en_US.UTF-8', 14:05:16 INFO - 'LANGUAGE': 'en_US:en', 14:05:16 INFO - 'LOGNAME': 'cltbld', 14:05:16 INFO - 'MAIL': '/var/mail/cltbld', 14:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:16 INFO - 'MOZ_NO_REMOTE': '1', 14:05:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:16 INFO - 'NO_EM_RESTART': '1', 14:05:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:05:16 INFO - 'PWD': '/builds/slave/test-pgo', 14:05:16 INFO - 'SHELL': '/bin/bash', 14:05:16 INFO - 'SHLVL': '1', 14:05:16 INFO - 'TERM': 'linux', 14:05:16 INFO - 'TMOUT': '86400', 14:05:16 INFO - 'USER': 'cltbld', 14:05:16 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', 14:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:16 INFO - '_': '/tools/buildbot/bin/python'} 14:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:16 INFO - Downloading/unpacking blobuploader==1.2.4 14:05:16 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 14:05:16 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 14:05:16 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 14:05:16 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 14:05:16 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 14:05:16 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 14:05:18 INFO - Downloading blobuploader-1.2.4.tar.gz 14:05:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 14:05:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:05:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 14:05:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 14:05:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:18 INFO - Downloading docopt-0.6.1.tar.gz 14:05:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 14:05:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 14:05:18 INFO - Installing collected packages: blobuploader, requests, docopt 14:05:18 INFO - Running setup.py install for blobuploader 14:05:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:05:18 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 14:05:18 INFO - Running setup.py install for requests 14:05:19 INFO - Running setup.py install for docopt 14:05:19 INFO - Successfully installed blobuploader requests docopt 14:05:19 INFO - Cleaning up... 14:05:19 INFO - Return code: 0 14:05:19 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:19 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 0x7fc23269bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2578350>, '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 0x2579250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25796e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', '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 14:05:19 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 14:05:19 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 14:05:19 INFO - Using env: {'DISPLAY': ':0', 14:05:19 INFO - 'HOME': '/home/cltbld', 14:05:19 INFO - 'LANG': 'en_US.UTF-8', 14:05:19 INFO - 'LANGUAGE': 'en_US:en', 14:05:19 INFO - 'LOGNAME': 'cltbld', 14:05:19 INFO - 'MAIL': '/var/mail/cltbld', 14:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:19 INFO - 'MOZ_NO_REMOTE': '1', 14:05:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:19 INFO - 'NO_EM_RESTART': '1', 14:05:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:05:19 INFO - 'PWD': '/builds/slave/test-pgo', 14:05:19 INFO - 'SHELL': '/bin/bash', 14:05:19 INFO - 'SHLVL': '1', 14:05:19 INFO - 'TERM': 'linux', 14:05:19 INFO - 'TMOUT': '86400', 14:05:19 INFO - 'USER': 'cltbld', 14:05:19 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', 14:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:19 INFO - '_': '/tools/buildbot/bin/python'} 14:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:05:19 INFO - Running setup.py (path:/tmp/pip-JHykGj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:05:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:05:19 INFO - Running setup.py (path:/tmp/pip-173Sgs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:05:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:05:19 INFO - Running setup.py (path:/tmp/pip-pKsdLp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:05:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:05:19 INFO - Running setup.py (path:/tmp/pip-txkxJX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:05:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:05:19 INFO - Running setup.py (path:/tmp/pip-7S8cDp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:05:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:05:19 INFO - Running setup.py (path:/tmp/pip-SRmGxV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:05:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:05:19 INFO - Running setup.py (path:/tmp/pip-EX8d7f-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:05:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:05:20 INFO - Running setup.py (path:/tmp/pip-Q0FUPT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:05:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:05:20 INFO - Running setup.py (path:/tmp/pip-evvAI6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:05:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:05:20 INFO - Running setup.py (path:/tmp/pip-tdjbRc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:05:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:05:20 INFO - Running setup.py (path:/tmp/pip-dJ7azE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:05:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:05:20 INFO - Running setup.py (path:/tmp/pip-khAHhW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:05:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:05:20 INFO - Running setup.py (path:/tmp/pip-7Geivu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:05:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:05:20 INFO - Running setup.py (path:/tmp/pip-akLRti-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:05:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:05:20 INFO - Running setup.py (path:/tmp/pip-2OCqH5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:05:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:05:20 INFO - Running setup.py (path:/tmp/pip-SDCbS2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:05:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:05:20 INFO - Running setup.py (path:/tmp/pip-54B7oL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:05:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:05:20 INFO - Running setup.py install for manifestparser 14:05:20 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 14:05:20 INFO - Running setup.py install for mozcrash 14:05:20 INFO - Running setup.py install for mozdebug 14:05:21 INFO - Running setup.py install for mozdevice 14:05:21 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 14:05:21 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 14:05:21 INFO - Running setup.py install for mozfile 14:05:21 INFO - Running setup.py install for mozhttpd 14:05:21 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 14:05:21 INFO - Running setup.py install for mozinfo 14:05:21 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 14:05:21 INFO - Running setup.py install for mozInstall 14:05:21 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 14:05:21 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 14:05:21 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 14:05:21 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 14:05:21 INFO - Running setup.py install for mozleak 14:05:21 INFO - Running setup.py install for mozlog 14:05:21 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 14:05:21 INFO - Running setup.py install for moznetwork 14:05:21 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 14:05:21 INFO - Running setup.py install for mozprocess 14:05:22 INFO - Running setup.py install for mozprofile 14:05:22 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 14:05:22 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 14:05:22 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 14:05:22 INFO - Running setup.py install for mozrunner 14:05:22 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 14:05:22 INFO - Running setup.py install for mozscreenshot 14:05:22 INFO - Running setup.py install for moztest 14:05:22 INFO - Running setup.py install for mozversion 14:05:22 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 14:05:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:05:22 INFO - Cleaning up... 14:05:22 INFO - Return code: 0 14:05:22 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:05: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')]} 14:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05: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')]} 14:05:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05: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/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 0x7fc23269bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2578350>, '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 0x2579250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25796e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', '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 14:05: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/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 14:05: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/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 14:05:22 INFO - Using env: {'DISPLAY': ':0', 14:05:22 INFO - 'HOME': '/home/cltbld', 14:05:22 INFO - 'LANG': 'en_US.UTF-8', 14:05:22 INFO - 'LANGUAGE': 'en_US:en', 14:05:22 INFO - 'LOGNAME': 'cltbld', 14:05:22 INFO - 'MAIL': '/var/mail/cltbld', 14:05:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:22 INFO - 'MOZ_NO_REMOTE': '1', 14:05:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:22 INFO - 'NO_EM_RESTART': '1', 14:05:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:05:22 INFO - 'PWD': '/builds/slave/test-pgo', 14:05:22 INFO - 'SHELL': '/bin/bash', 14:05:22 INFO - 'SHLVL': '1', 14:05:22 INFO - 'TERM': 'linux', 14:05:22 INFO - 'TMOUT': '86400', 14:05:22 INFO - 'USER': 'cltbld', 14:05:22 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', 14:05:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:22 INFO - '_': '/tools/buildbot/bin/python'} 14:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:05:22 INFO - Running setup.py (path:/tmp/pip-ao7oOt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 14:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 14:05:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:05:22 INFO - Running setup.py (path:/tmp/pip-vpRzvv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 14:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 14:05:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:05:22 INFO - Running setup.py (path:/tmp/pip-2xVhXx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 14:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 14:05:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:05:23 INFO - Running setup.py (path:/tmp/pip-WLNf1s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 14:05:23 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)) 14:05:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 14:05:23 INFO - Running setup.py (path:/tmp/pip-PYBP6r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 14:05:23 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)) 14:05:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:05:23 INFO - Running setup.py (path:/tmp/pip-iRqVCO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 14:05:23 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)) 14:05:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:05:23 INFO - Running setup.py (path:/tmp/pip-jANTG3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 14:05:23 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)) 14:05:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:05:23 INFO - Running setup.py (path:/tmp/pip-DOI710-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 14:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 14:05:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 14:05:23 INFO - Running setup.py (path:/tmp/pip-uhZexN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 14:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 14:05:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 14:05:23 INFO - Running setup.py (path:/tmp/pip-MpN0ZW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 14:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:05:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:05:23 INFO - Running setup.py (path:/tmp/pip-Qsj08C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 14:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 14:05:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:05:23 INFO - Running setup.py (path:/tmp/pip-ZVPm4C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 14:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 14:05:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:05:23 INFO - Running setup.py (path:/tmp/pip-sayX1i-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 14:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 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)) 14:05:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:05:23 INFO - Running setup.py (path:/tmp/pip-j9Ixjg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 14:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 14:05:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:05:23 INFO - Running setup.py (path:/tmp/pip-wemQFB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 14:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 14:05:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 14:05:23 INFO - Running setup.py (path:/tmp/pip-OogVZW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 14:05:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 14:05:23 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 14:05:23 INFO - Running setup.py (path:/tmp/pip-tBejWF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 14:05:24 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)) 14:05:24 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)) 14:05:24 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)) 14:05:24 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)) 14:05:24 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)) 14:05:24 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)) 14:05:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05:25 INFO - Downloading blessings-1.5.1.tar.gz 14:05:25 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 14:05:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 14:05:25 INFO - Installing collected packages: blessings 14:05:25 INFO - Running setup.py install for blessings 14:05:25 INFO - Successfully installed blessings 14:05:25 INFO - Cleaning up... 14:05:25 INFO - Return code: 0 14:05:25 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 14:05:25 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:05:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:05:26 INFO - Reading from file tmpfile_stdout 14:05:26 INFO - Current package versions: 14:05:26 INFO - argparse == 1.2.1 14:05:26 INFO - blessings == 1.5.1 14:05:26 INFO - blobuploader == 1.2.4 14:05:26 INFO - docopt == 0.6.1 14:05:26 INFO - manifestparser == 1.1 14:05:26 INFO - mozInstall == 1.12 14:05:26 INFO - mozcrash == 0.16 14:05:26 INFO - mozdebug == 0.1 14:05:26 INFO - mozdevice == 0.47 14:05:26 INFO - mozfile == 1.2 14:05:26 INFO - mozhttpd == 0.7 14:05:26 INFO - mozinfo == 0.9 14:05:26 INFO - mozleak == 0.1 14:05:26 INFO - mozlog == 3.1 14:05:26 INFO - moznetwork == 0.27 14:05:26 INFO - mozprocess == 0.22 14:05:26 INFO - mozprofile == 0.27 14:05:26 INFO - mozrunner == 6.11 14:05:26 INFO - mozscreenshot == 0.1 14:05:26 INFO - mozsystemmonitor == 0.0 14:05:26 INFO - moztest == 0.7 14:05:26 INFO - mozversion == 1.4 14:05:26 INFO - psutil == 3.1.1 14:05:26 INFO - requests == 1.2.3 14:05:26 INFO - wsgiref == 0.1.2 14:05:26 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 14:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:26 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 0x7fc23269bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2578350>, '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 0x2579250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25796e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', '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 14:05:26 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 14:05:26 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 14:05:26 INFO - Using env: {'DISPLAY': ':0', 14:05:26 INFO - 'HOME': '/home/cltbld', 14:05:26 INFO - 'LANG': 'en_US.UTF-8', 14:05:26 INFO - 'LANGUAGE': 'en_US:en', 14:05:26 INFO - 'LOGNAME': 'cltbld', 14:05:26 INFO - 'MAIL': '/var/mail/cltbld', 14:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:26 INFO - 'MOZ_NO_REMOTE': '1', 14:05:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:26 INFO - 'NO_EM_RESTART': '1', 14:05:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:05:26 INFO - 'PWD': '/builds/slave/test-pgo', 14:05:26 INFO - 'SHELL': '/bin/bash', 14:05:26 INFO - 'SHLVL': '1', 14:05:26 INFO - 'TERM': 'linux', 14:05:26 INFO - 'TMOUT': '86400', 14:05:26 INFO - 'USER': 'cltbld', 14:05:26 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', 14:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:26 INFO - '_': '/tools/buildbot/bin/python'} 14:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:05:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 14:05:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 14:05:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 14:05:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 14:05:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 14:05:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 14:05:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 14:05:26 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.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:05:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 14:05:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 14:05:26 INFO - Cleaning up... 14:05:26 INFO - Return code: 0 14:05:26 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 14:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:26 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 0x7fc23269bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2578350>, '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 0x2579250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25796e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', '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 14:05:26 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 14:05:26 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 14:05:26 INFO - Using env: {'DISPLAY': ':0', 14:05:26 INFO - 'HOME': '/home/cltbld', 14:05:26 INFO - 'LANG': 'en_US.UTF-8', 14:05:26 INFO - 'LANGUAGE': 'en_US:en', 14:05:26 INFO - 'LOGNAME': 'cltbld', 14:05:26 INFO - 'MAIL': '/var/mail/cltbld', 14:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:26 INFO - 'MOZ_NO_REMOTE': '1', 14:05:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:26 INFO - 'NO_EM_RESTART': '1', 14:05:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:05:26 INFO - 'PWD': '/builds/slave/test-pgo', 14:05:26 INFO - 'SHELL': '/bin/bash', 14:05:26 INFO - 'SHLVL': '1', 14:05:26 INFO - 'TERM': 'linux', 14:05:26 INFO - 'TMOUT': '86400', 14:05:26 INFO - 'USER': 'cltbld', 14:05:26 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', 14:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:26 INFO - '_': '/tools/buildbot/bin/python'} 14:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:26 INFO - Downloading/unpacking jsonschema 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05: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 14:05:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:05:28 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 14:05:28 INFO - Downloading/unpacking functools32 (from jsonschema) 14:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:28 INFO - Downloading functools32-3.2.3-2.tar.gz 14:05:28 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 14:05:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 14:05:28 INFO - warning: no files found matching '*.txt' 14:05:28 INFO - no previously-included directories found matching 'build' 14:05:28 INFO - no previously-included directories found matching 'dist' 14:05:28 INFO - no previously-included directories found matching '.git*' 14:05:28 INFO - Installing collected packages: jsonschema, functools32 14:05:28 INFO - Running setup.py install for functools32 14:05:28 INFO - warning: no files found matching '*.txt' 14:05:28 INFO - no previously-included directories found matching 'build' 14:05:28 INFO - no previously-included directories found matching 'dist' 14:05:28 INFO - no previously-included directories found matching '.git*' 14:05:28 INFO - Successfully installed jsonschema functools32 14:05:28 INFO - Cleaning up... 14:05:28 INFO - Return code: 0 14:05:28 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 14:05:28 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')]} 14:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:05:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:28 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')]} 14:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:05:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:05:28 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 0x7fc23269bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x241c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e9600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2578350>, '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 0x2579250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25796e0>, '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': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', '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 14:05:28 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 14:05:28 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 14:05:28 INFO - Using env: {'DISPLAY': ':0', 14:05:28 INFO - 'HOME': '/home/cltbld', 14:05:28 INFO - 'LANG': 'en_US.UTF-8', 14:05:28 INFO - 'LANGUAGE': 'en_US:en', 14:05:28 INFO - 'LOGNAME': 'cltbld', 14:05:28 INFO - 'MAIL': '/var/mail/cltbld', 14:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:28 INFO - 'MOZ_NO_REMOTE': '1', 14:05:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:28 INFO - 'NO_EM_RESTART': '1', 14:05:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:05:28 INFO - 'PWD': '/builds/slave/test-pgo', 14:05:28 INFO - 'SHELL': '/bin/bash', 14:05:28 INFO - 'SHLVL': '1', 14:05:28 INFO - 'TERM': 'linux', 14:05:28 INFO - 'TMOUT': '86400', 14:05:28 INFO - 'USER': 'cltbld', 14:05:28 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', 14:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:28 INFO - '_': '/tools/buildbot/bin/python'} 14:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:05:28 INFO - Downloading/unpacking flake8 14:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:05:30 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 14:05:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:05:30 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 14:05:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:31 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 14:05:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:05:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:05:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:05:31 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 14:05:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 14:05:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:05:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:05:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:05:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:05:31 INFO - no previously-included directories found matching 'docs/_build' 14:05:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:05:31 INFO - Running setup.py install for pep8 14:05:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:05:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:05:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:05:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:05:31 INFO - no previously-included directories found matching 'docs/_build' 14:05:31 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 14:05:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:05:31 INFO - Cleaning up... 14:05:31 INFO - Return code: 0 14:05:31 INFO - Running post-action listener: _resource_record_post_action 14:05:31 INFO - Running post-action listener: _start_resource_monitoring 14:05:31 INFO - Starting resource monitoring. 14:05:31 INFO - ##### 14:05:31 INFO - ##### Running install step. 14:05:31 INFO - ##### 14:05:31 INFO - Running pre-action listener: _resource_record_pre_action 14:05:31 INFO - Running main action method: install 14:05:31 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 14:05:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 14:05:31 INFO - Reading from file tmpfile_stdout 14:05:31 INFO - Detecting whether we're running mozinstall >=1.0... 14:05:31 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 14:05:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 14:05:32 INFO - Reading from file tmpfile_stdout 14:05:32 INFO - Output received: 14:05:32 INFO - Usage: mozinstall [options] installer 14:05:32 INFO - Options: 14:05:32 INFO - -h, --help show this help message and exit 14:05:32 INFO - -d DEST, --destination=DEST 14:05:32 INFO - Directory to install application into. [default: 14:05:32 INFO - "/builds/slave/test-pgo"] 14:05:32 INFO - --app=APP Application being installed. [default: firefox] 14:05:32 INFO - mkdir: /builds/slave/test-pgo/build/application 14:05:32 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'] 14:05:32 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 14:05:47 INFO - Reading from file tmpfile_stdout 14:05:47 INFO - Output received: 14:05:47 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 14:05:47 INFO - Running post-action listener: _resource_record_post_action 14:05:47 INFO - ##### 14:05:47 INFO - ##### Running run-tests step. 14:05:47 INFO - ##### 14:05:47 INFO - Running pre-action listener: _resource_record_pre_action 14:05:47 INFO - Running main action method: run_tests 14:05:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 14:05:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 14:05:47 INFO - Python 2.7.3 14:05:47 INFO - Return code: 0 14:05:47 INFO - grabbing minidump binary from tooltool 14:05:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:05:47 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 0x2578350>, '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 0x2579250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25796e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 14:05:47 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 14:05:47 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 14:05:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:05:47 INFO - Return code: 0 14:05:47 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 14:05:47 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 14:05:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 14:05:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 14:05:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 14:05:47 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 14:05:47 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 14:05:48 INFO - Return code: 0 14:05:48 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 14:05:48 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost 14:05:48 INFO - Using env: {'DISPLAY': ':0', 14:05:48 INFO - 'HOME': '/home/cltbld', 14:05:48 INFO - 'LANG': 'en_US.UTF-8', 14:05:48 INFO - 'LANGUAGE': 'en_US:en', 14:05:48 INFO - 'LOGNAME': 'cltbld', 14:05:48 INFO - 'MAIL': '/var/mail/cltbld', 14:05:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:05:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 14:05:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:05:48 INFO - 'MOZ_NO_REMOTE': '1', 14:05:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 14:05:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:05:48 INFO - 'NO_EM_RESTART': '1', 14:05:48 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:05:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 14:05:48 INFO - 'PWD': '/builds/slave/test-pgo', 14:05:48 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 14:05:48 INFO - 'SHELL': '/bin/bash', 14:05:48 INFO - 'SHLVL': '1', 14:05:48 INFO - 'TERM': 'linux', 14:05:48 INFO - 'TMOUT': '86400', 14:05:48 INFO - 'USER': 'cltbld', 14:05:48 INFO - 'XDG_SESSION_COOKIE': 'fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985', 14:05:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:05:48 INFO - '_': '/tools/buildbot/bin/python'} 14:05:48 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] with output_timeout 3600 14:05:48 INFO - mozversion INFO | application_buildid: 20151218113010 14:05:48 INFO - mozversion INFO | application_changeset: 79e8243e94f78f6f599ad2a10ed6ede11b351cd3 14:05:48 INFO - mozversion INFO | application_display_name: Nightly 14:05:48 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:05:48 INFO - mozversion INFO | application_name: Firefox 14:05:48 INFO - mozversion INFO | application_remotingname: firefox 14:05:48 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 14:05:48 INFO - mozversion INFO | application_vendor: Mozilla 14:05:48 INFO - mozversion INFO | application_version: 46.0a1 14:05:48 INFO - mozversion INFO | platform_buildid: 20151218113010 14:05:48 INFO - mozversion INFO | platform_changeset: 79e8243e94f78f6f599ad2a10ed6ede11b351cd3 14:05:48 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 14:05:48 INFO - mozversion INFO | platform_version: 46.0a1 14:05:48 INFO - 2015-12-18 14:05:48,974 DEBUG : using testdate: 1450476348 14:05:48 INFO - 2015-12-18 14:05:48,975 DEBUG : actual date: 1450476348 14:05:48 INFO - 2015-12-18 14:05:48,984 INFO : starting webserver on 'localhost:46069' 14:05:48 INFO - 2015-12-18 14:05:48,985 INFO : Starting test suite talos-linux64-ix-046 14:05:48 INFO - 2015-12-18 14:05:48,985 INFO : Starting test dromaeo_css 14:05:48 INFO - 2015-12-18 14:05:48,985 DEBUG : operating with platform_type : linux_ 14:05:48 INFO - 2015-12-18 14:05:48,986 INFO : Initialising browser for dromaeo_css test... 14:05:49 INFO - 2015-12-18 14:05:49,002 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplINUW6/profile http://localhost:46069/getInfo.html 14:05:52 INFO - 2015-12-18 14:05:52,734 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:05:52 INFO - 2015-12-18 14:05:52,734 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:05:52 INFO - 2015-12-18 14:05:52,734 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:05:52 INFO - 2015-12-18 14:05:52,734 DEBUG : BROWSER_OUTPUT: __metrics 14:05:52 INFO - 2015-12-18 14:05:52,795 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 14:05:52 INFO - 2015-12-18 14:05:52,851 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:05:53 INFO - 2015-12-18 14:05:53,922 INFO : Browser initialized. 14:05:53 INFO - 2015-12-18 14:05:53,923 INFO : Running cycle 1/1 for dromaeo_css test... 14:05:53 INFO - 2015-12-18 14:05:53,923 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmplINUW6/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:06:05 INFO - 2015-12-18 14:06:05,701 DEBUG : BROWSER_OUTPUT: RSS: Main: 149807104 14:06:05 INFO - 2015-12-18 14:06:05,702 DEBUG : BROWSER_OUTPUT: 14:06:57 INFO - 2015-12-18 14:06:57,031 DEBUG : BROWSER_OUTPUT: 1450476417028 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] 14:09:05 INFO - 2015-12-18 14:09:05,680 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46069/tests/dromaeo/cssquery-ext.html) 14:09:06 INFO - 2015-12-18 14:09:06,708 DEBUG : BROWSER_OUTPUT: RSS: Main: 243003392 14:09:06 INFO - 2015-12-18 14:09:06,708 DEBUG : BROWSER_OUTPUT: 14:12:09 INFO - 2015-12-18 14:12:09,095 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/dromaeo/cssquery-ext.html (next: http://localhost:46069/tests/dromaeo/cssquery-jquery.html) 14:12:09 INFO - 2015-12-18 14:12:09,680 DEBUG : BROWSER_OUTPUT: RSS: Main: 236826624 14:12:09 INFO - 2015-12-18 14:12:09,681 DEBUG : BROWSER_OUTPUT: 14:15:11 INFO - 2015-12-18 14:15:11,592 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46069/tests/dromaeo/cssquery-mootools.html) 14:15:12 INFO - 2015-12-18 14:15:12,158 DEBUG : BROWSER_OUTPUT: RSS: Main: 227545088 14:15:12 INFO - 2015-12-18 14:15:12,158 DEBUG : BROWSER_OUTPUT: 14:18:14 INFO - 2015-12-18 14:18:14,004 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46069/tests/dromaeo/cssquery-prototype.html) 14:18:14 INFO - 2015-12-18 14:18:14,585 DEBUG : BROWSER_OUTPUT: RSS: Main: 217833472 14:18:14 INFO - 2015-12-18 14:18:14,586 DEBUG : BROWSER_OUTPUT: 14:21:17 INFO - 2015-12-18 14:21:17,014 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46069/tests/dromaeo/cssquery-yui.html) 14:21:17 INFO - 2015-12-18 14:21:17,614 DEBUG : BROWSER_OUTPUT: RSS: Main: 220684288 14:21:17 INFO - 2015-12-18 14:21:17,615 DEBUG : BROWSER_OUTPUT: 14:24:20 INFO - 2015-12-18 14:24:20,232 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/dromaeo/cssquery-yui.html (next: http://localhost:46069/tests/dromaeo/cssquery-dojo.html) 14:24:20 INFO - 2015-12-18 14:24:20,478 DEBUG : BROWSER_OUTPUT: RSS: Main: 280727552 14:24:20 INFO - 2015-12-18 14:24:20,478 DEBUG : BROWSER_OUTPUT: 14:24:20 INFO - 2015-12-18 14:24:20,479 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:24:20 INFO - 2015-12-18 14:24:20,480 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:24:20 INFO - 2015-12-18 14:24:20,480 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:24:20 INFO - 2015-12-18 14:24:20,480 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:24:20 INFO - 2015-12-18 14:24:20,481 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3038.3923215356926;3098.7056229658174;3142.2301536123637;3144.135362775238;3165.9208519787003;5083.135866902626;5146.022255771405;5182.559482443997;5206.947930520699;5233.816816411427;5160.767765450554;5219.060569097561;5229.294045303883;5230.084735171339;5248;4584.679072464929;4711.222648263032;4723.881902952437;4838.516148385151;4865.464798872121;4617.5382461753725;4751.572358487735;4779.80880764769;4814.542618451242;4855.377058018912;4745.21703918853;4810.158222311082;4810.446798618152;4812.109759694467;4852.2479015752615;4769.189237829577;4778.593819525345;4783.641226907961;4785.760711964388;4786.21027530457;4641.535846415365;4706.10583989041;4753.667243292971;4770.356001939728;4790.520947905216;4959.132151873444;4982.701037937735;4982.90034199314;5039.899202015939;5103.821366252163;5593.972030139864;5599.860003499904;5608.158776183564;5620.185073164409;5656.830295091154;5501.559875209973;5571.804986825482;5617.8033768817895;5629.211910332557;5629.80295689653;5309.893802123975;5311.282976798083;5311.362636483646;5317.9468205317835;5319.840404787863;5704.771809127588;5723.742431590588;5728.799492017843;5730.083186690193;5771.653700777967;5353.9732301338245;5450.209719590716;5455.509004189642;5472.699001554953;5560.916586251209;5683.54531637468;5748.080307150836;5772.797952071657;5775.653460792366;5796.681182534917;5625.296837895263;5656.321241451053;5666.716664166775;5667.4332566743;5669.914951275734;5435;5686.2607860977805;5701.71491425427;5712.80005199816;5723.828285151452;4297.935530967038;4321.416608757841;4332.133573285292;4334.869953901388;4335.132973405269;3902.785346805953;3917.5690674025836;3918.5885482024546;3921.843126274974;3923.0388554803685;5140.228965655182;5168.224766285012;5204.115300398941;5225.399079105951;5238.580913526908;5604.299462567179;5642.915356269659;5644.57665675076;5644.943550564524;5647.463490968352;5098.898022039612;5202.947970520247;5217.6347655664495;5226.608004399685;5269.077911365495;4901.852944411673;4906.460289368167;4918.803247870045;4959;4992.80028798844;5724.284464441945;5733.369329373765;5733.827985160452;5740.913886291709;5748.19525266463;5551.278333816578;5685.5167310778215;5705.22979397776;5706.372299047019;5714.571407144564;5580.693061881636;5581.972090139686;5700.173474846207;5760.8271751847515;5828.679422631796;5583.776648933997;5611.074172761449;5620.213170156263;5628.521575666115;5635.633683810539;5428.158635411518;5691.459311365414;5697.316322041382;5703.885922281448;5703.971480142573;5427.6200665953;5449.782008719607;5477.561795056306;5503.064479038493;5534.750936207961;5487.588430867621;5489.5608351331;5490.615656903979;5490.752916118704;5506.174073888788;5175.456577059505;5309.893802124013;5419.972900135474;5431.6740995540395;5474.178873169057;5325.866853328698;5431.8098866539485;5503.862403439946;5558.249636299048;5568.638038527482;5353.598480114085;5383.892322153613;5458.972705136449;5483.862903427446;5489.533389661764 14:24:20 INFO - 2015-12-18 14:24:20,482 DEBUG : BROWSER_OUTPUT: |1;ext.html;579.8666306749448;590.3683059126735;601.2244204975582;608.2913405882148;620.9379062093791;27698.584521232173;37193.25613487729;37414.56805307653;37469;37647.623523764785;40699.38950915739;41267.79366103173;41297.7935110324;41319.79340103303;41382.58617413825;7488.887666685004;7605;8685.913140868573;8927.107289271053;8928.107189281085;10391.636292729756;10657.733556661107;10663.573457061708;10674.679759607185;10682.679519614385;10287.691369258935;10293.536790844393;10335.224858135633;10390.480475976208;10424.478776061205;7701.768946931601;7883.25109114639;7888.763337099839;10266.640667576636;10385.584576616926;10184.643537476228;10204.795904081951;10287.79424411515;10295.073443390056;10299.588016479333;37889.621103788886;38270;38367.616323836686;38461.80769096137;38483.61516384856;3257.4136655402017;3289.3750187464307;3311.867525298985;3390.3558323918624;3670.926581468382;29251.707482925325;29570.852145739133;29764;29844.8507757462;30512.542311865338;3676.3382591133586;3678.797666128362;3718.0704823794053;3731.869384571527;3812.294725475796;252727.99999999817;253425;253738;254015;254307;56366;56531;56945;57009.714951425805;57170.714146429;58743.41256587465;59346.70326648339;59586;60036.39963600308;66207;14656.413743450143;14701.852981470049;14803.925980370244;14811.703765924622;14833.332500037524;11222;11259.605913792979;11271.661850144508;11273.323600583992;11279.605213817476;19024;19276.710849337273;19281;19300.32448864311;19318.13068411925;7011.474139439562;7175;7251.74618888347;7265.745698900513;7305.671244793997;926.7868390270276;938.7700013496737;1014.4167103915131;1041.4688508860384;1054.9261551691309;23922.40193995129;23948;24002.39994000129;25540;26061.60907586388;1027.198784947742;1043.7964596903682;1057.3444464431948;1097.5280629329304;1127.8251870960014;14461.008315229563;15060.32228549718;15234.847651523565;15402.922985385001;15475.690486190213;327488;332485;332498;333077;333671;51699;52047.73976130171;52066.47933520616;52085;52086;1292.702678383977;1310.711643438442;1317.4927652853721;1343.737971095627;1346.158650843223;27176.456470870868;28114;28241.3986027838;28363.574546382235;28403.857980709963;42110.78944605257;42380;44066.779666102746;47204.52795472001;47797.76101119472;213309;213945;214440;214880;215496;26846.597301040892;26941.326466838487;29480.557791632713;30920;43981;40362.39456408215;40677.59322406738;40964.79517602393;41561.37657935073;41713.58286417097;34262.48606270958;34488.82755586206;35021.64978350286;35341.116472088404;35684.28631427409;35060.474092888115;35335.64664353323;35491.82254088713;35513.467297990035;35560.288794224485;34362.14094647654;35013.649863501036;35093.64906350904;35234.47148292829;35292;30987.69012309848;31621.20946976344;31739.20651983719;32181;35530.28939421249;24392.26823195307;24529.877350613133;24621.753782461947;24907;24911.377215569755 14:24:20 INFO - 2015-12-18 14:24:20,486 DEBUG : BROWSER_OUTPUT: |2;jquery.html;354.13414202275413;354.4222916645867;355.2823296940181;356.1861147278469;357.45845044757215;13410.530631427877;13850.168989860615;16032.118233497153;16406;17059.999999999985;15628.14045227512;15813.446529371475;16809.32762689491;16818;16852.32590696374;4670.462896766864;4841.8547443576745;4848.587870031051;4850.151223535885;4851.63612729045;3725.590185079639;3827.2345530893795;4830.516948305177;5914.88170236595;6062.666553339564;5928.614640048405;5956.940430595682;5962.642241465526;5962.701864906738;6016.398360163992;5807.39022402648;5925.940740592603;5943.465088142045;5947.702614869261;5956.255468066492;5262.552683021948;5362.9006053758885;5381.381141168777;5388.892222155574;5418.674879507243;12600.369981500888;12711.237325760483;12841;12843.486260549567;12852.164609300366;2115.6509175985943;2125.574885022986;2126.213301078611;2171.6416791229426;2346.8239882008747;9656.806863862683;9936.503174841302;9990.300678952477;10299;12062.552493700725;1846.1138653446287;1863.3664554051688;1865.897375644339;1869.83171514563;1924.3553409607798;57216.71391643057;61453.38546614566;61762.073568896056;63097.68451157715;63259.36740632581;9839.458829764431;9929.602815887283;10055.39667619945;10105.646302379382;10145.797084058277;13468.461261549624;13541.05212635106;13542.932285338511;13546.390412431465;13552.525661601796;7701.499402538929;7752.496087754278;7760.456768026184;7797.415193860483;7887.48731332473;7316.6341682915645;7344.045274114331;7350.117985841734;7411.147718012389;7421.591812450259;4377.693561450668;4433.866983990485;4438.045820148684;4452.131834292282;4464;1691.7208660571114;1750.159923236828;1764.4001039646582;1796.9820301797076;1798.3346161920174;432.5285438871645;445.65684422994764;923.4090182283344;964.508100868548;987.3483500889379;10119.898801012043;10606.469676516142;11122.38826864514;11213;11457.598984035685;442.97342159470537;892.8616064510011;928.4661319741176;980.887197972228;1003.1623594298842;8815.294776417872;8961.283097352196;9000.684976025808;9201.631934722536;9208.26333893287;578419;585803;589946;595635;601917;80225.59887200643;84008.57995709982;85406.57296713477;86391.56804216064;86446.56776716076;1112.4437781109445;1151.3552410650063;1152.0840931459509;1154.2324354304294;1156.19066653343;15346.23268836576;15470.14914179686;15499.922500387425;15705.921470392575;15989.440369587008;36377;36404.453933190496;36863.63136368602;37342.06644833792;37384.81307593445;45956.7702161487;46277.30584041309;46897.76551117359;47239.52760472351;47365.526344736114;9438.716838494856;9606.327557070937;9905.108540231126;9980.700578982642;9986.60053597848;17162;17326.39357622477;17328.913355433142;17734.379296724554;17920.91039544794;14236.003479756318;14296.714065718836;14555.854441455449;14679.119252844437;14875.851241487879;14249.857501424853;14365.425382984564;14463.783043254149;14521.34653940554;15033.173175475455;14738.852611473749;15124;15228.923855380652;15340.846591533942;15367.077975321516;9088.318376121684;9566.61733530651;9569.712908612753;9823.85264221023;9828.508574571386;12312.322822244863;14329.85670143327;14485.710285794014;14508.85491145075;15292.617684557976 14:24:20 INFO - 2015-12-18 14:24:20,486 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1533.3253368517858;1666.949991500255;1713.768641233434;1721.8192089830563;1732.1512458895138;6015.398460153982;7078.079849619548;7319.2680731926785;7327.963360183192;7328.670209840557;7179.317964793349;7335.743248986287;7343.375813055896;7348;7353.30143636355;1907.6947688369864;1948.4641723526001;1949.1618604000346;2183.650615901456;2207.61366760817;2174.282486779359;2214.977850221493;2215.900284487202;2216.556688662274;2217.911283548664;2067.4727944374163;2074.087401543316;2195.034184958621;2199.3841724317217;2199.59307528107;2204.1073365287084;2211.5908556916943;2236.7651396603287;2239.1155493580027;2253.6056190166655;2238.9552208955893;2241.181968581472;2247.224707475918;2260.615695331781;2264.6603009548535;7223.169335526377;7279.45404094695;7294.343509084208;7308.45186611001;7328.157261914895;4448.799804008828;4477.126960242754;4540.682152249311;4541.2733962565835;4546.045330480603;2847.288177955511;2927.473054850126;2957.526795712697;2960.925976850596;2962.7777916656337;1046.685994201744;1062.8565143705578;1068.1027936533271;1085.1969542538543;1109.8668159820793;9891.703248902473;9897.80204395908;9905.306628535933;9917.603295868157;9924.354916930377;3983.980080099639;4002.539707933625;4071.511418629724;4096.262672718939;4097.201045796041;8542.487450752975;8548.358873084544;8561.957190214009;8566.657333706582;8583.570821458901;4531.796069176895;4553.908921821545;4563.292689633112;4565.863024109282;4566.657500687462;4140.937885931713;4149.813258403379;4151.7924103794685;4165.375193720905;4167.06241095751;3092.737117345051;3183.6657150999276;3252.284497410566;3503.4394496880377;3569.5002699622073;1140.5836869542723;1144.542183126756;1152.9884701153048;1155.607093588184;1160.5415860734963;1074.838774183863;1080.2492267873909;1107.4739498738036;1209.673388185185;1306.5361796562274;6751.797446076625;6794.3545363190415;6811.4550835933005;6811.965940170367;6833.829154271084;1089.1667874076338;1096.0792933935531;1097.8956999085074;1110.8278216876236;1115.7489564848006;5452.91820622683;5496.532794712414;5507.366652834895;5530.889382212334;5549.139883318092;8545.2309292164;8623.439476434012;8728.869066964;8757;8820.470771753715;8515.361347898808;8591.871121933176;8629.482231066157;8636.920384951882;8650.610722517502;6826.829329266808;6897.862042759217;6947;7002.649867506706;7018.192907815666;6519.250286216956;6532.41208291256;6537.05212744159;6543.443807276435;6552.3775241352;7713.575753333621;7719.498232614862;7758.7672369829;7916.683332666629;7923.881141782993;7897.486663366863;8049.758507244792;8102.473339233166;8116.634751436318;8117.594120294079;6424.518161138027;6522.5760325578685;6563.245226798979;6563.245226798979;6578.5395022347975;6078.665673387829;6144.324124346408;6522.445592124533;6792.96603516999;7080.681369338289;6433.806985790434;6531.771388001397;6543.672816359068;6596.241432235354;6624.8343791405605;6335.461485773577;6339.714712838026;6387.584806987531;6442.033694945796;6506.73973041092;6343.5876668016435;6395.712192951235;6398.680065996775;6398.968005159944;6405.487560995202;5987.850303742442;6009.098635204754;6013.458788709037;6018.2778066630535;6018.3981601841215;5963.52291816645;6018.187544681412;6022.45797878193;6039.48664363534;6111.54163437735 14:24:20 INFO - 2015-12-18 14:24:20,489 DEBUG : BROWSER_OUTPUT: |4;prototype.html;340.5436714802165;345.1905282113443;347.19624070277314;351.14145913242135;355.6941030713586;5919;5938.257717785277;5989.071693887444;6021.427964343391;6031.819045428644;5680.772769089232;5732.197492351074;5779.595428320009;5896.115582662592;5908.645481271126;4560.8859778505475;4564.246899261618;4579.9771001144945;4604.125216208918;4608.354830323758;4572.862814115582;4583.427071616048;4586.082783443308;4590.219662657356;4594;4336.566343365654;4421;4425.66807489438;4496.415465989417;4505.391772110772;4486.528914463999;4505.864824055284;4515.435570553681;4524.638028957708;4527.547245275463;3986.7807270600106;4000.7599544027453;4001.339778936472;4001.559828418872;4007.438958545806;5620.213170156181;5627.380988091307;5630.831075067755;5634.859128521779;5634.943650563483;5195.142801437758;5308;5346.679199248018;5359.732013399314;5361.785528578852;6276.466500347476;6280.246370435577;6286;6287.308396076428;6298.118263443122;4518.977405112986;4519.322101684741;4521.1409832131785;4522.909541809178;4523.479799822997;6386.329435409261;6460.870782584322;6503.382178693017;6509.567895063078;6522.184726909137;4574.336721175478;4613.815447382134;4628.143793398232;4630.814767409266;4632.62938964882;4662.836800711959;4669.929951050669;4673.415823022122;4677.859664210011;4678.111158879802;5317.867053323698;5319.069162896478;5319.760610772447;5320.893582128336;5327.254184414185;6162.938370616326;6294.370562943761;6304.716287767062;6310.390850333136;6337.556371053982;1637.0668718830152;1656.1470842516114;1657.1051632118522;1658.07147997122;1659.1621231278368;1113.3709454158375;1134.13805507813;1140.4126874659557;1153.2907262033789;1164.4061528620464;6036.3058248302;6041.607295525777;6049.274087109575;6054.424829641177;6057.606255593372;6501.512386570931;6530.379613936669;6534.411902928759;6536.24833144185;6554.573952693059;5893.970530147321;5937.910931336033;5981.043033114681;6103.38966103395;6106.969465152646;3534.8232588370993;3551.3785087609567;3557.4130268505924;3559.14580500678;3561.554805649294;6652.467802575782;6663.06717059612;6664.5334826561675;6665.133532640726;6666.899996500057;6589.209294884645;6703.128593282842;6712.463002959751;6718.428933540704;6735.124433823669;6631;6632.502562307846;6633.900491492632;6643.86712265752;6646.468282537386;6537.823191825517;6564.934350656624;6576.342365763386;6578.5395022347975;6603.372679595431;6368.52236082305;6477.251142191488;6487.091807147098;6488.091667166506;6504.9674751625935;6638.468922486093;6647;6649.933500664932;6653.900191497035;6658.067870498231;4638.489766125656;4644.58198762113;4649.8140074398;4653.650976176868;4654.743989080633;6468.094466774562;6537.5750576212395;6587.538872278895;6599.571027883172;6606.405423511907;6383.39357761012;6409.551331406757;6416.13382193398;6416.871662566629;6421.8715625688155;6367.140436041261;6368.395002474757;6372.330905254881;6377.649229292433;6388.776392826228;6140.385961403824;6248.812535623939;6252.812415627538;6263.749450021948;6272.905906411499;6400.967995159995;6403.551751377358;6404.839879003062;6409.198850143732;6418.229812422539;6259.530535209787;6262.5929314594405;6265.686715664107;6273.717682704198;6274.780382686584 14:24:20 INFO - 2015-12-18 14:24:20,490 DEBUG : BROWSER_OUTPUT: |5;yui.html;734.6894018312411;770.9553554933062;778.5514556719119;844.1727107434714;859.8839156713842;12970.805437918427;13150.145240559357;13208.801867971988;13211.207327560342;13237.536686215984;12517.311547864863;12632.17890837095;12651.557195498159;12668.429920653569;14755;828.0891019878146;840.3907167303717;840.7813968368238;841.2933136165619;842.49029337251;687.9174499060119;700.0374485082986;816.9264766171043;833.6457005772553;841.6549214821924;868.5006121480175;869.9478031318109;873.2708188662474;875.0156074416282;875.5753459572103;860.8665656823202;868.0885070675797;868.1188593577492;868.4702331577732;869.4609342207872;524.8871492629103;531.8351311093573;532.4941305759505;532.6511135206446;622.3807311724818;13127.671808204776;13232.536861209812;13244.86755132446;13266.270355130464;13278.136921100195;11747.882521174764;12140.514379424812;12178.330191839445;12261.632151035394;12634.620961371174;9889.270904545478;10070.69787906364;10084.495775211284;10172.23708221879;10547.947260263649;3555.2000799820094;3569.464580312948;3579.94630080549;3646.6717995380277;3649.927001459956;43201.56798432007;43295;43454.13091738147;43663;43868.12263754707;9732.318737688436;9828.459434731158;9875.506224688737;9878.506074696237;9964.352317099365;17369.7394539082;17433;17460;17466.214020369112;17475.737863932052;7325.120985481776;7390.371818395497;7535.886961695579;7660.080790305087;7673.693052277958;4376.562343765661;4387.561243875587;4399.562937499047;4424.867253982386;4573.977130114395;3052.786304958676;3081.7842751007215;3114.096911895525;3114.704103110201;3115.3613509230554;527.8073503171391;548.6378989866748;549.7965752671537;559.1640497456248;560.1821340842334;588.8280740579695;672.5325898500495;678.6776281266359;683.1938312790954;686.3959715450371;8214.58927053645;8599.914000859912;8640.956795215983;8645.308375329958;8729.430537548644;539.511741873605;596.352957041606;630.6405348951054;660.3099760750027;663.4161937494973;3585.3008663310925;3824.655780979725;3825.9617403825605;3858.3633700439113;3943.6253555912144;51301.74349128231;51724.22413663798;51911.480885190664;52158;52474.47525524696;46025.7698711511;46411.30383044257;46475.30287045697;46708.5329146711;47009.76495117503;41549;41891.79054104771;41941.370879436225;42767.1446571073;43141.35287970683;7800.45396822217;7819.921800781919;7865.331446827084;8078.949736534213;8078.959605201936;32983.50524742179;33159.33681326408;33192;34469.82765086158;34693.30613387768;40387.596124039555;41716.165676686905;41802;41807.58192418037;42041;9461.290403219924;9675.70972870815;9747.171490423394;10344.482775861328;10468.738281543023;10412;10488.790224195322;10817.296875703054;10855.565777369133;11219.439028048402;9839.409635421898;9945.303828732209;10278.640247591298;10336.431496267778;10453.9477302613;9278.58246378928;9806.264530160124;9897.158741507052;9946.552405141641;9977.800443991226;8861.512616806138;9999.900000999896;10748.355098694103;10901.291416057931;11189.048930840645;9634.614615415305;9725.270604704534;10000.199984001118;10026.64906728261;10256.692299231036;7454.813629659302;8267.875981860157;8800.39970022483;9145.405548639317;9183.31125165602 14:24:20 INFO - 2015-12-18 14:24:20,490 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:24:20 INFO - 2015-12-18 14:24:20,490 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:24:20 INFO - 2015-12-18 14:24:20,490 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,853 14:24:20 INFO - 2015-12-18 14:24:20,490 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:24:20 INFO - 2015-12-18 14:24:20,490 DEBUG : BROWSER_OUTPUT: __startTimestamp1450477460478__endTimestamp 14:24:20 INFO - 2015-12-18 14:24:20,490 DEBUG : BROWSER_OUTPUT: 14:24:20 INFO - 2015-12-18 14:24:20,490 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:24:20 INFO - 2015-12-18 14:24:20,490 DEBUG : BROWSER_OUTPUT: Number of tests: 6 14:24:20 INFO - 2015-12-18 14:24:20,490 DEBUG : BROWSER_OUTPUT: 14:24:20 INFO - 2015-12-18 14:24:20,491 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5210.28 Median:5427.62 stddev:577.76 (10.6%) stddev-sans-first:553.84 14:24:20 INFO - 2015-12-18 14:24:20,492 DEBUG : BROWSER_OUTPUT: Values: 3038.4 3098.7 3142.2 3144.1 3165.9 5083.1 5146.0 5182.6 5206.9 5233.8 5160.8 5219.1 5229.3 5230.1 5248.0 4584.7 4711.2 4723.9 4838.5 4865.5 4617.5 4751.6 4779.8 4814.5 4855.4 4745.2 4810.2 4810.4 4812.1 4852.2 4769.2 4778.6 4783.6 4785.8 4786.2 4641.5 4706.1 4753.7 4770.4 4790.5 4959.1 4982.7 4982.9 5039.9 5103.8 5594.0 5599.9 5608.2 5620.2 5656.8 5501.6 5571.8 5617.8 5629.2 5629.8 5309.9 5311.3 5311.4 5317.9 5319.8 5704.8 5723.7 5728.8 5730.1 5771.7 5354.0 5450.2 5455.5 5472.7 5560.9 5683.5 5748.1 5772.8 5775.7 5796.7 5625.3 5656.3 5666.7 5667.4 5669.9 5435.0 5686.3 5701.7 5712.8 5723.8 4297.9 4321.4 4332.1 4334.9 4335.1 3902.8 3917.6 3918.6 3921.8 3923.0 5140.2 5168.2 5204.1 5225.4 5238.6 5604.3 5642.9 5644.6 5644.9 5647.5 5098.9 5202.9 5217.6 5226.6 5269.1 4901.9 4906.5 4918.8 4959.0 4992.8 5724.3 5733.4 5733.8 5740.9 5748.2 5551.3 5685.5 5705.2 5706.4 5714.6 5580.7 5582.0 5700.2 5760.8 5828.7 5583.8 5611.1 5620.2 5628.5 5635.6 5428.2 5691.5 5697.3 5703.9 5704.0 5427.6 5449.8 5477.6 5503.1 5534.8 5487.6 5489.6 5490.6 5490.8 5506.2 5175.5 5309.9 5420.0 5431.7 5474.2 5325.9 5431.8 5503.9 5558.2 5568.6 5353.6 5383.9 5459.0 5483.9 5489.5 14:24:20 INFO - 2015-12-18 14:24:20,492 DEBUG : BROWSER_OUTPUT: 14:24:20 INFO - 2015-12-18 14:24:20,492 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:43719.26 Median:165388.69 stddev:70824.00 (42.8%) stddev-sans-first:70948.62 14:24:20 INFO - 2015-12-18 14:24:20,492 DEBUG : BROWSER_OUTPUT: Values: 579.9 590.4 601.2 608.3 620.9 27698.6 37193.3 37414.6 37469.0 37647.6 40699.4 41267.8 41297.8 41319.8 41382.6 7488.9 7605.0 8685.9 8927.1 8928.1 10391.6 10657.7 10663.6 10674.7 10682.7 10287.7 10293.5 10335.2 10390.5 10424.5 7701.8 7883.3 7888.8 10266.6 10385.6 10184.6 10204.8 10287.8 10295.1 10299.6 37889.6 38270.0 38367.6 38461.8 38483.6 3257.4 3289.4 3311.9 3390.4 3670.9 29251.7 29570.9 29764.0 29844.9 30512.5 3676.3 3678.8 3718.1 3731.9 3812.3 252728.0 253425.0 253738.0 254015.0 254307.0 56366.0 56531.0 56945.0 57009.7 57170.7 58743.4 59346.7 59586.0 60036.4 66207.0 14656.4 14701.9 14803.9 14811.7 14833.3 11222.0 11259.6 11271.7 11273.3 11279.6 19024.0 19276.7 19281.0 19300.3 19318.1 7011.5 7175.0 7251.7 7265.7 7305.7 926.8 938.8 1014.4 1041.5 1054.9 23922.4 23948.0 24002.4 25540.0 26061.6 1027.2 1043.8 1057.3 1097.5 1127.8 14461.0 15060.3 15234.8 15402.9 15475.7 327488.0 332485.0 332498.0 333077.0 333671.0 51699.0 52047.7 52066.5 52085.0 52086.0 1292.7 1310.7 1317.5 1343.7 1346.2 27176.5 28114.0 28241.4 28363.6 28403.9 42110.8 42380.0 44066.8 47204.5 47797.8 213309.0 213945.0 214440.0 214880.0 215496.0 26846.6 26941.3 29480.6 30920.0 43981.0 40362.4 40677.6 40964.8 41561.4 41713.6 34262.5 34488.8 35021.6 35341.1 35684.3 35060.5 35335.6 35491.8 35513.5 35560.3 34362.1 35013.6 35093.6 35234.5 35292.0 30987.7 31621.2 31739.2 32181.0 35530.3 24392.3 24529.9 24621.8 24907.0 24911.4 14:24:20 INFO - 2015-12-18 14:24:20,492 DEBUG : BROWSER_OUTPUT: 14:24:20 INFO - 2015-12-18 14:24:20,492 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30124.48 Median:37363.44 stddev:96519.23 (258.3%) stddev-sans-first:96764.11 14:24:20 INFO - 2015-12-18 14:24:20,493 DEBUG : BROWSER_OUTPUT: Values: 354.1 354.4 355.3 356.2 357.5 13410.5 13850.2 16032.1 16406.0 17060.0 15628.1 15813.4 16809.3 16818.0 16852.3 4670.5 4841.9 4848.6 4850.2 4851.6 3725.6 3827.2 4830.5 5914.9 6062.7 5928.6 5956.9 5962.6 5962.7 6016.4 5807.4 5925.9 5943.5 5947.7 5956.3 5262.6 5362.9 5381.4 5388.9 5418.7 12600.4 12711.2 12841.0 12843.5 12852.2 2115.7 2125.6 2126.2 2171.6 2346.8 9656.8 9936.5 9990.3 10299.0 12062.6 1846.1 1863.4 1865.9 1869.8 1924.4 57216.7 61453.4 61762.1 63097.7 63259.4 9839.5 9929.6 10055.4 10105.6 10145.8 13468.5 13541.1 13542.9 13546.4 13552.5 7701.5 7752.5 7760.5 7797.4 7887.5 7316.6 7344.0 7350.1 7411.1 7421.6 4377.7 4433.9 4438.0 4452.1 4464.0 1691.7 1750.2 1764.4 1797.0 1798.3 432.5 445.7 923.4 964.5 987.3 10119.9 10606.5 11122.4 11213.0 11457.6 443.0 892.9 928.5 980.9 1003.2 8815.3 8961.3 9000.7 9201.6 9208.3 578419.0 585803.0 589946.0 595635.0 601917.0 80225.6 84008.6 85406.6 86391.6 86446.6 1112.4 1151.4 1152.1 1154.2 1156.2 15346.2 15470.1 15499.9 15705.9 15989.4 36377.0 36404.5 36863.6 37342.1 37384.8 45956.8 46277.3 46897.8 47239.5 47365.5 9438.7 9606.3 9905.1 9980.7 9986.6 17162.0 17326.4 17328.9 17734.4 17920.9 14236.0 14296.7 14555.9 14679.1 14875.9 14249.9 14365.4 14463.8 14521.3 15033.2 14738.9 15124.0 15228.9 15340.8 15367.1 9088.3 9566.6 9569.7 9823.9 9828.5 12312.3 14329.9 14485.7 14508.9 15292.6 14:24:20 INFO - 2015-12-18 14:24:20,494 DEBUG : BROWSER_OUTPUT: 14:24:20 INFO - 2015-12-18 14:24:20,494 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5111.48 Median:6018.23 stddev:2580.28 (42.9%) stddev-sans-first:2573.50 14:24:20 INFO - 2015-12-18 14:24:20,494 DEBUG : BROWSER_OUTPUT: Values: 1533.3 1666.9 1713.8 1721.8 1732.2 6015.4 7078.1 7319.3 7328.0 7328.7 7179.3 7335.7 7343.4 7348.0 7353.3 1907.7 1948.5 1949.2 2183.7 2207.6 2174.3 2215.0 2215.9 2216.6 2217.9 2067.5 2074.1 2195.0 2199.4 2199.6 2204.1 2211.6 2236.8 2239.1 2253.6 2239.0 2241.2 2247.2 2260.6 2264.7 7223.2 7279.5 7294.3 7308.5 7328.2 4448.8 4477.1 4540.7 4541.3 4546.0 2847.3 2927.5 2957.5 2960.9 2962.8 1046.7 1062.9 1068.1 1085.2 1109.9 9891.7 9897.8 9905.3 9917.6 9924.4 3984.0 4002.5 4071.5 4096.3 4097.2 8542.5 8548.4 8562.0 8566.7 8583.6 4531.8 4553.9 4563.3 4565.9 4566.7 4140.9 4149.8 4151.8 4165.4 4167.1 3092.7 3183.7 3252.3 3503.4 3569.5 1140.6 1144.5 1153.0 1155.6 1160.5 1074.8 1080.2 1107.5 1209.7 1306.5 6751.8 6794.4 6811.5 6812.0 6833.8 1089.2 1096.1 1097.9 1110.8 1115.7 5452.9 5496.5 5507.4 5530.9 5549.1 8545.2 8623.4 8728.9 8757.0 8820.5 8515.4 8591.9 8629.5 8636.9 8650.6 6826.8 6897.9 6947.0 7002.6 7018.2 6519.3 6532.4 6537.1 6543.4 6552.4 7713.6 7719.5 7758.8 7916.7 7923.9 7897.5 8049.8 8102.5 8116.6 8117.6 6424.5 6522.6 6563.2 6563.2 6578.5 6078.7 6144.3 6522.4 6793.0 7080.7 6433.8 6531.8 6543.7 6596.2 6624.8 6335.5 6339.7 6387.6 6442.0 6506.7 6343.6 6395.7 6398.7 6399.0 6405.5 5987.9 6009.1 6013.5 6018.3 6018.4 5963.5 6018.2 6022.5 6039.5 6111.5 14:24:20 INFO - 2015-12-18 14:24:20,494 DEBUG : BROWSER_OUTPUT: 14:24:20 INFO - 2015-12-18 14:24:20,494 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5302.11 Median:6005.25 stddev:1570.42 (26.2%) stddev-sans-first:1530.03 14:24:20 INFO - 2015-12-18 14:24:20,494 DEBUG : BROWSER_OUTPUT: Values: 340.5 345.2 347.2 351.1 355.7 5919.0 5938.3 5989.1 6021.4 6031.8 5680.8 5732.2 5779.6 5896.1 5908.6 4560.9 4564.2 4580.0 4604.1 4608.4 4572.9 4583.4 4586.1 4590.2 4594.0 4336.6 4421.0 4425.7 4496.4 4505.4 4486.5 4505.9 4515.4 4524.6 4527.5 3986.8 4000.8 4001.3 4001.6 4007.4 5620.2 5627.4 5630.8 5634.9 5634.9 5195.1 5308.0 5346.7 5359.7 5361.8 6276.5 6280.2 6286.0 6287.3 6298.1 4519.0 4519.3 4521.1 4522.9 4523.5 6386.3 6460.9 6503.4 6509.6 6522.2 4574.3 4613.8 4628.1 4630.8 4632.6 4662.8 4669.9 4673.4 4677.9 4678.1 5317.9 5319.1 5319.8 5320.9 5327.3 6162.9 6294.4 6304.7 6310.4 6337.6 1637.1 1656.1 1657.1 1658.1 1659.2 1113.4 1134.1 1140.4 1153.3 1164.4 6036.3 6041.6 6049.3 6054.4 6057.6 6501.5 6530.4 6534.4 6536.2 6554.6 5894.0 5937.9 5981.0 6103.4 6107.0 3534.8 3551.4 3557.4 3559.1 3561.6 6652.5 6663.1 6664.5 6665.1 6666.9 6589.2 6703.1 6712.5 6718.4 6735.1 6631.0 6632.5 6633.9 6643.9 6646.5 6537.8 6564.9 6576.3 6578.5 6603.4 6368.5 6477.3 6487.1 6488.1 6505.0 6638.5 6647.0 6649.9 6653.9 6658.1 4638.5 4644.6 4649.8 4653.7 4654.7 6468.1 6537.6 6587.5 6599.6 6606.4 6383.4 6409.6 6416.1 6416.9 6421.9 6367.1 6368.4 6372.3 6377.6 6388.8 6140.4 6248.8 6252.8 6263.7 6272.9 6401.0 6403.6 6404.8 6409.2 6418.2 6259.5 6262.6 6265.7 6273.7 6274.8 14:24:20 INFO - 2015-12-18 14:24:20,494 DEBUG : BROWSER_OUTPUT: 14:24:20 INFO - 2015-12-18 14:24:20,494 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12874.57 Median:51512.98 stddev:14526.73 (28.2%) stddev-sans-first:14538.87 14:24:20 INFO - 2015-12-18 14:24:20,494 DEBUG : BROWSER_OUTPUT: Values: 734.7 771.0 778.6 844.2 859.9 12970.8 13150.1 13208.8 13211.2 13237.5 12517.3 12632.2 12651.6 12668.4 14755.0 828.1 840.4 840.8 841.3 842.5 687.9 700.0 816.9 833.6 841.7 868.5 869.9 873.3 875.0 875.6 860.9 868.1 868.1 868.5 869.5 524.9 531.8 532.5 532.7 622.4 13127.7 13232.5 13244.9 13266.3 13278.1 11747.9 12140.5 12178.3 12261.6 12634.6 9889.3 10070.7 10084.5 10172.2 10547.9 3555.2 3569.5 3579.9 3646.7 3649.9 43201.6 43295.0 43454.1 43663.0 43868.1 9732.3 9828.5 9875.5 9878.5 9964.4 17369.7 17433.0 17460.0 17466.2 17475.7 7325.1 7390.4 7535.9 7660.1 7673.7 4376.6 4387.6 4399.6 4424.9 4574.0 3052.8 3081.8 3114.1 3114.7 3115.4 527.8 548.6 549.8 559.2 560.2 588.8 672.5 678.7 683.2 686.4 8214.6 8599.9 8641.0 8645.3 8729.4 539.5 596.4 630.6 660.3 663.4 3585.3 3824.7 3826.0 3858.4 3943.6 51301.7 51724.2 51911.5 52158.0 52474.5 46025.8 46411.3 46475.3 46708.5 47009.8 41549.0 41891.8 41941.4 42767.1 43141.4 7800.5 7819.9 7865.3 8078.9 8079.0 32983.5 33159.3 33192.0 34469.8 34693.3 40387.6 41716.2 41802.0 41807.6 42041.0 9461.3 9675.7 9747.2 10344.5 10468.7 10412.0 10488.8 10817.3 10855.6 11219.4 9839.4 9945.3 10278.6 10336.4 10453.9 9278.6 9806.3 9897.2 9946.6 9977.8 8861.5 9999.9 10748.4 10901.3 11189.0 9634.6 9725.3 10000.2 10026.6 10256.7 7454.8 8267.9 8800.4 9145.4 9183.3 14:24:20 INFO - 2015-12-18 14:24:20,495 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:24:20 INFO - 2015-12-18 14:24:20,495 DEBUG : BROWSER_OUTPUT: 14:24:21 INFO - 2015-12-18 14:24:21,150 INFO : Browser exited with error code: 0 14:24:21 INFO - 2015-12-18 14:24:21,171 INFO : Completed test dromaeo_css (00:18:32) 14:24:21 INFO - 2015-12-18 14:24:21,171 INFO : Starting test kraken 14:24:21 INFO - 2015-12-18 14:24:21,171 DEBUG : operating with platform_type : linux_ 14:24:21 INFO - 2015-12-18 14:24:21,171 INFO : Initialising browser for kraken test... 14:24:21 INFO - 2015-12-18 14:24:21,177 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0iw2yp/profile http://localhost:46069/getInfo.html 14:24:24 INFO - 2015-12-18 14:24:24,735 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:24:24 INFO - 2015-12-18 14:24:24,735 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:24:24 INFO - 2015-12-18 14:24:24,735 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:24:24 INFO - 2015-12-18 14:24:24,735 DEBUG : BROWSER_OUTPUT: __metrics 14:24:24 INFO - 2015-12-18 14:24:24,832 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 14:24:24 INFO - 2015-12-18 14:24:24,888 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:24:25 INFO - 2015-12-18 14:24:25,997 INFO : Browser initialized. 14:24:25 INFO - 2015-12-18 14:24:25,998 INFO : Running cycle 1/1 for kraken test... 14:24:25 INFO - 2015-12-18 14:24:25,998 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp0iw2yp/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:24:37 INFO - 2015-12-18 14:24:37,781 DEBUG : BROWSER_OUTPUT: RSS: Main: 149786624 14:24:37 INFO - 2015-12-18 14:24:37,782 DEBUG : BROWSER_OUTPUT: 14:24:40 INFO - 2015-12-18 14:24:40,277 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46069/tests/kraken/driver.html?testName=audio-beat-detection) 14:24:40 INFO - 2015-12-18 14:24:40,850 DEBUG : BROWSER_OUTPUT: RSS: Main: 186728448 14:24:40 INFO - 2015-12-18 14:24:40,851 DEBUG : BROWSER_OUTPUT: 14:24:43 INFO - 2015-12-18 14:24:43,439 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46069/tests/kraken/driver.html?testName=audio-dft) 14:24:44 INFO - 2015-12-18 14:24:44,006 DEBUG : BROWSER_OUTPUT: RSS: Main: 177487872 14:24:44 INFO - 2015-12-18 14:24:44,007 DEBUG : BROWSER_OUTPUT: 14:24:46 INFO - 2015-12-18 14:24:46,966 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46069/tests/kraken/driver.html?testName=audio-fft) 14:24:47 INFO - 2015-12-18 14:24:47,527 DEBUG : BROWSER_OUTPUT: RSS: Main: 161468416 14:24:47 INFO - 2015-12-18 14:24:47,527 DEBUG : BROWSER_OUTPUT: 14:24:49 INFO - 2015-12-18 14:24:49,539 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46069/tests/kraken/driver.html?testName=audio-oscillator) 14:24:50 INFO - 2015-12-18 14:24:50,100 DEBUG : BROWSER_OUTPUT: RSS: Main: 164274176 14:24:50 INFO - 2015-12-18 14:24:50,101 DEBUG : BROWSER_OUTPUT: 14:24:52 INFO - 2015-12-18 14:24:52,286 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46069/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:24:52 INFO - 2015-12-18 14:24:52,847 DEBUG : BROWSER_OUTPUT: RSS: Main: 164421632 14:24:52 INFO - 2015-12-18 14:24:52,847 DEBUG : BROWSER_OUTPUT: 14:24:55 INFO - 2015-12-18 14:24:55,749 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46069/tests/kraken/driver.html?testName=imaging-darkroom) 14:24:56 INFO - 2015-12-18 14:24:56,319 DEBUG : BROWSER_OUTPUT: RSS: Main: 164806656 14:24:56 INFO - 2015-12-18 14:24:56,319 DEBUG : BROWSER_OUTPUT: 14:24:59 INFO - 2015-12-18 14:24:59,720 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46069/tests/kraken/driver.html?testName=imaging-desaturate) 14:25:00 INFO - 2015-12-18 14:25:00,301 DEBUG : BROWSER_OUTPUT: RSS: Main: 165871616 14:25:00 INFO - 2015-12-18 14:25:00,302 DEBUG : BROWSER_OUTPUT: 14:25:03 INFO - 2015-12-18 14:25:03,025 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46069/tests/kraken/driver.html?testName=json-parse-financial) 14:25:03 INFO - 2015-12-18 14:25:03,606 DEBUG : BROWSER_OUTPUT: RSS: Main: 165888000 14:25:03 INFO - 2015-12-18 14:25:03,606 DEBUG : BROWSER_OUTPUT: 14:25:05 INFO - 2015-12-18 14:25:05,175 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46069/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:25:05 INFO - 2015-12-18 14:25:05,734 DEBUG : BROWSER_OUTPUT: RSS: Main: 175669248 14:25:05 INFO - 2015-12-18 14:25:05,734 DEBUG : BROWSER_OUTPUT: 14:25:07 INFO - 2015-12-18 14:25:07,516 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46069/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:25:08 INFO - 2015-12-18 14:25:08,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 172376064 14:25:08 INFO - 2015-12-18 14:25:08,117 DEBUG : BROWSER_OUTPUT: 14:25:10 INFO - 2015-12-18 14:25:10,197 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46069/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:25:10 INFO - 2015-12-18 14:25:10,771 DEBUG : BROWSER_OUTPUT: RSS: Main: 183660544 14:25:10 INFO - 2015-12-18 14:25:10,771 DEBUG : BROWSER_OUTPUT: 14:25:13 INFO - 2015-12-18 14:25:13,165 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46069/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:25:13 INFO - 2015-12-18 14:25:13,747 DEBUG : BROWSER_OUTPUT: RSS: Main: 192544768 14:25:13 INFO - 2015-12-18 14:25:13,747 DEBUG : BROWSER_OUTPUT: 14:25:16 INFO - 2015-12-18 14:25:16,293 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46069/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:25:16 INFO - 2015-12-18 14:25:16,875 DEBUG : BROWSER_OUTPUT: RSS: Main: 189849600 14:25:16 INFO - 2015-12-18 14:25:16,875 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,569 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46069/tests/kraken/driver.html?testName=ai-astar) 14:25:18 INFO - 2015-12-18 14:25:18,819 DEBUG : BROWSER_OUTPUT: RSS: Main: 247533568 14:25:18 INFO - 2015-12-18 14:25:18,819 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,820 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:25:18 INFO - 2015-12-18 14:25:18,820 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:25:18 INFO - 2015-12-18 14:25:18,820 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:25:18 INFO - 2015-12-18 14:25:18,820 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:25:18 INFO - 2015-12-18 14:25:18,820 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;111;104;111;110;111;101;112;112;119 14:25:18 INFO - 2015-12-18 14:25:18,820 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;124;151;163;130;146;161;125;161;125 14:25:18 INFO - 2015-12-18 14:25:18,820 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;162;160;160;161;162;159;164;162;162 14:25:18 INFO - 2015-12-18 14:25:18,820 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;72;79;98;71;72;72;99;88;72 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;142;93;93;93;132;93;94;137 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;110;110;109;110;109;109;110;109;109 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;148;146;147;146;145;146;146;146 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;96;95;96;96;95;96;96;97;96 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;60;58;59;58;58;61;58;66;56;57 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;67;68;68;68;68;67;67;67;67;67 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;86;85;85;86;84;85;85;87;85 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;117;121;116;116;116;114;120;123;116;119 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;139;153;150;151;143;151;150;150;139 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;68;68;68;67;68;68;68;69;67 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: __start_cc_report 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,919 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: __end_cc_report 14:25:18 INFO - 2015-12-18 14:25:18,821 DEBUG : BROWSER_OUTPUT: __startTimestamp1450477518819__endTimestamp 14:25:18 INFO - 2015-12-18 14:25:18,822 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,825 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:25:18 INFO - 2015-12-18 14:25:18,825 DEBUG : BROWSER_OUTPUT: Number of tests: 14 14:25:18 INFO - 2015-12-18 14:25:18,825 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,825 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.00 Median:111.00 stddev:4.83 (4.4%) stddev-sans-first:5.11 14:25:18 INFO - 2015-12-18 14:25:18,825 DEBUG : BROWSER_OUTPUT: Values: 109.0 111.0 104.0 111.0 110.0 111.0 101.0 112.0 112.0 119.0 14:25:18 INFO - 2015-12-18 14:25:18,825 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,825 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.70 Median:151.00 stddev:16.19 (10.7%) stddev-sans-first:16.95 14:25:18 INFO - 2015-12-18 14:25:18,825 DEBUG : BROWSER_OUTPUT: Values: 151.0 124.0 151.0 163.0 130.0 146.0 161.0 125.0 161.0 125.0 14:25:18 INFO - 2015-12-18 14:25:18,825 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,825 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.30 Median:162.00 stddev:1.42 (0.9%) stddev-sans-first:1.50 14:25:18 INFO - 2015-12-18 14:25:18,826 DEBUG : BROWSER_OUTPUT: Values: 161.0 162.0 160.0 160.0 161.0 162.0 159.0 164.0 162.0 162.0 14:25:18 INFO - 2015-12-18 14:25:18,826 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,826 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:79.40 Median:75.50 stddev:11.37 (15.1%) stddev-sans-first:11.65 14:25:18 INFO - 2015-12-18 14:25:18,826 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 79.0 98.0 71.0 72.0 72.0 99.0 88.0 72.0 14:25:18 INFO - 2015-12-18 14:25:18,826 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,826 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.40 Median:93.00 stddev:21.25 (22.9%) stddev-sans-first:21.98 14:25:18 INFO - 2015-12-18 14:25:18,826 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 142.0 93.0 93.0 93.0 132.0 93.0 94.0 137.0 14:25:18 INFO - 2015-12-18 14:25:18,826 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,827 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.50 Median:110.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 14:25:18 INFO - 2015-12-18 14:25:18,827 DEBUG : BROWSER_OUTPUT: Values: 110.0 110.0 110.0 109.0 110.0 109.0 109.0 110.0 109.0 109.0 14:25:18 INFO - 2015-12-18 14:25:18,827 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,827 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.83 14:25:18 INFO - 2015-12-18 14:25:18,827 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 148.0 146.0 147.0 146.0 145.0 146.0 146.0 146.0 14:25:18 INFO - 2015-12-18 14:25:18,827 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,827 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 14:25:18 INFO - 2015-12-18 14:25:18,827 DEBUG : BROWSER_OUTPUT: Values: 95.0 96.0 95.0 96.0 96.0 95.0 96.0 96.0 97.0 96.0 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:59.10 Median:58.50 stddev:2.81 (4.8%) stddev-sans-first:2.96 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: Values: 60.0 58.0 59.0 58.0 58.0 61.0 58.0 66.0 56.0 57.0 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:67.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: Values: 67.0 68.0 68.0 68.0 68.0 67.0 67.0 67.0 67.0 67.0 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.00 stddev:0.82 (1.0%) stddev-sans-first:0.87 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: Values: 85.0 86.0 85.0 85.0 86.0 84.0 85.0 85.0 87.0 85.0 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.80 Median:118.00 stddev:2.82 (2.4%) stddev-sans-first:2.98 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: Values: 117.0 121.0 116.0 116.0 116.0 114.0 120.0 123.0 116.0 119.0 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.70 Median:150.00 stddev:5.52 (3.7%) stddev-sans-first:5.45 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: Values: 141.0 139.0 153.0 150.0 151.0 143.0 151.0 150.0 150.0 139.0 14:25:18 INFO - 2015-12-18 14:25:18,828 DEBUG : BROWSER_OUTPUT: 14:25:18 INFO - 2015-12-18 14:25:18,829 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:68.00 stddev:0.57 (0.8%) stddev-sans-first:0.60 14:25:18 INFO - 2015-12-18 14:25:18,829 DEBUG : BROWSER_OUTPUT: Values: 68.0 68.0 68.0 68.0 67.0 68.0 68.0 68.0 69.0 67.0 14:25:18 INFO - 2015-12-18 14:25:18,829 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:25:18 INFO - 2015-12-18 14:25:18,829 DEBUG : BROWSER_OUTPUT: 14:25:19 INFO - 2015-12-18 14:25:19,698 INFO : Browser exited with error code: 0 14:25:19 INFO - 2015-12-18 14:25:19,749 INFO : Completed test kraken (00:00:58) 14:25:19 INFO - 2015-12-18 14:25:19,749 INFO : Starting test v8_7 14:25:19 INFO - 2015-12-18 14:25:19,749 DEBUG : operating with platform_type : linux_ 14:25:19 INFO - 2015-12-18 14:25:19,749 INFO : Initialising browser for v8_7 test... 14:25:19 INFO - 2015-12-18 14:25:19,755 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpIaFXD0/profile http://localhost:46069/getInfo.html 14:25:23 INFO - 2015-12-18 14:25:23,673 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 14:25:23 INFO - 2015-12-18 14:25:23,673 DEBUG : BROWSER_OUTPUT: colorDepth:24 14:25:23 INFO - 2015-12-18 14:25:23,673 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 14:25:23 INFO - 2015-12-18 14:25:23,673 DEBUG : BROWSER_OUTPUT: __metrics 14:25:23 INFO - 2015-12-18 14:25:23,762 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 14:25:23 INFO - 2015-12-18 14:25:23,823 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:25:24 INFO - 2015-12-18 14:25:24,576 INFO : Browser initialized. 14:25:24 INFO - 2015-12-18 14:25:24,576 INFO : Running cycle 1/1 for v8_7 test... 14:25:24 INFO - 2015-12-18 14:25:24,576 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpIaFXD0/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 14:25:36 INFO - 2015-12-18 14:25:36,409 DEBUG : BROWSER_OUTPUT: RSS: Main: 149622784 14:25:36 INFO - 2015-12-18 14:25:36,409 DEBUG : BROWSER_OUTPUT: 14:25:57 INFO - 2015-12-18 14:25:57,736 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:46069/tests/v8_7/run.html (next: http://localhost:46069/tests/v8_7/run.html) 14:25:57 INFO - 2015-12-18 14:25:57,994 DEBUG : BROWSER_OUTPUT: RSS: Main: 557195264 14:25:57 INFO - 2015-12-18 14:25:57,995 DEBUG : BROWSER_OUTPUT: 14:25:57 INFO - 2015-12-18 14:25:57,995 DEBUG : BROWSER_OUTPUT: __start_tp_report 14:25:57 INFO - 2015-12-18 14:25:57,995 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 14:25:57 INFO - 2015-12-18 14:25:57,995 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 14:25:57 INFO - 2015-12-18 14:25:57,995 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 14:25:57 INFO - 2015-12-18 14:25:57,995 DEBUG : BROWSER_OUTPUT: |0;Richards;166.00265604249668 14:25:57 INFO - 2015-12-18 14:25:57,995 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;189.5016107636915 14:25:57 INFO - 2015-12-18 14:25:57,995 DEBUG : BROWSER_OUTPUT: |2;Encrypt;283.36639274582035 14:25:57 INFO - 2015-12-18 14:25:57,995 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5154.639175257732 14:25:57 INFO - 2015-12-18 14:25:57,995 DEBUG : BROWSER_OUTPUT: |4;RayTrace;2042.857142857143 14:25:57 INFO - 2015-12-18 14:25:57,996 DEBUG : BROWSER_OUTPUT: |5;Earley;793.0214115781126 14:25:57 INFO - 2015-12-18 14:25:57,996 DEBUG : BROWSER_OUTPUT: |6;Boyer;12060.240963855422 14:25:57 INFO - 2015-12-18 14:25:57,996 DEBUG : BROWSER_OUTPUT: |7;RegExp;25769.23076923077 14:25:57 INFO - 2015-12-18 14:25:57,996 DEBUG : BROWSER_OUTPUT: |8;Splay;597.3715651135007 14:25:57 INFO - 2015-12-18 14:25:57,996 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 14:25:57 INFO - 2015-12-18 14:25:57,996 DEBUG : BROWSER_OUTPUT: __end_tp_report 14:25:57 INFO - 2015-12-18 14:25:57,996 DEBUG : BROWSER_OUTPUT: __startTimestamp1450477557987__endTimestamp 14:25:57 INFO - 2015-12-18 14:25:57,996 DEBUG : BROWSER_OUTPUT: 14:25:57 INFO - 2015-12-18 14:25:57,996 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 14:25:57 INFO - 2015-12-18 14:25:57,996 DEBUG : BROWSER_OUTPUT: Number of tests: 10 14:25:57 INFO - 2015-12-18 14:25:57,997 DEBUG : BROWSER_OUTPUT: 14:25:57 INFO - 2015-12-18 14:25:57,997 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.00 Median:166.00 stddev:0.00 (0.0%) 14:25:57 INFO - 2015-12-18 14:25:57,997 DEBUG : BROWSER_OUTPUT: Values: 166.0 14:25:57 INFO - 2015-12-18 14:25:57,997 DEBUG : BROWSER_OUTPUT: 14:25:58 INFO - 2015-12-18 14:25:57,997 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:189.50 Median:189.50 stddev:0.00 (0.0%) 14:25:58 INFO - 2015-12-18 14:25:57,997 DEBUG : BROWSER_OUTPUT: Values: 189.5 14:25:58 INFO - 2015-12-18 14:25:57,997 DEBUG : BROWSER_OUTPUT: 14:25:58 INFO - 2015-12-18 14:25:57,997 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:283.37 Median:283.37 stddev:0.00 (0.0%) 14:25:58 INFO - 2015-12-18 14:25:57,998 DEBUG : BROWSER_OUTPUT: Values: 283.4 14:25:58 INFO - 2015-12-18 14:25:57,998 DEBUG : BROWSER_OUTPUT: 14:25:58 INFO - 2015-12-18 14:25:57,998 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5154.64 Median:5154.64 stddev:0.00 (0.0%) 14:25:58 INFO - 2015-12-18 14:25:57,998 DEBUG : BROWSER_OUTPUT: Values: 5154.6 14:25:58 INFO - 2015-12-18 14:25:57,998 DEBUG : BROWSER_OUTPUT: 14:25:58 INFO - 2015-12-18 14:25:57,999 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:2042.86 Median:2042.86 stddev:0.00 (0.0%) 14:25:58 INFO - 2015-12-18 14:25:57,999 DEBUG : BROWSER_OUTPUT: Values: 2042.9 14:25:58 INFO - 2015-12-18 14:25:57,999 DEBUG : BROWSER_OUTPUT: 14:25:58 INFO - 2015-12-18 14:25:57,999 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:793.02 Median:793.02 stddev:0.00 (0.0%) 14:25:58 INFO - 2015-12-18 14:25:57,999 DEBUG : BROWSER_OUTPUT: Values: 793.0 14:25:58 INFO - 2015-12-18 14:25:58,000 DEBUG : BROWSER_OUTPUT: 14:25:58 INFO - 2015-12-18 14:25:58,000 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12060.24 Median:12060.24 stddev:0.00 (0.0%) 14:25:58 INFO - 2015-12-18 14:25:58,000 DEBUG : BROWSER_OUTPUT: Values: 12060.2 14:25:58 INFO - 2015-12-18 14:25:58,000 DEBUG : BROWSER_OUTPUT: 14:25:58 INFO - 2015-12-18 14:25:58,000 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:25769.23 Median:25769.23 stddev:0.00 (0.0%) 14:25:58 INFO - 2015-12-18 14:25:58,001 DEBUG : BROWSER_OUTPUT: Values: 25769.2 14:25:58 INFO - 2015-12-18 14:25:58,001 DEBUG : BROWSER_OUTPUT: 14:25:58 INFO - 2015-12-18 14:25:58,001 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:597.37 Median:597.37 stddev:0.00 (0.0%) 14:25:58 INFO - 2015-12-18 14:25:58,001 DEBUG : BROWSER_OUTPUT: Values: 597.4 14:25:58 INFO - 2015-12-18 14:25:58,001 DEBUG : BROWSER_OUTPUT: 14:25:58 INFO - 2015-12-18 14:25:58,001 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 14:25:58 INFO - 2015-12-18 14:25:58,001 DEBUG : BROWSER_OUTPUT: Values: 4826.9 14:25:58 INFO - 2015-12-18 14:25:58,002 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 14:25:58 INFO - 2015-12-18 14:25:58,002 DEBUG : BROWSER_OUTPUT: 14:25:58 INFO - 2015-12-18 14:25:58,750 INFO : Browser exited with error code: 0 14:25:58 INFO - 2015-12-18 14:25:58,764 INFO : Completed test v8_7 (00:00:39) 14:25:59 INFO - 2015-12-18 14:25:59,053 INFO : Completed test suite (00:20:10) 14:25:59 INFO - 2015-12-18 14:25:59,053 DEBUG : Working with test: dromaeo_css 14:25:59 INFO - 2015-12-18 14:25:59,053 DEBUG : Generating results file: dromaeo_css 14:25:59 INFO - 2015-12-18 14:25:59,053 DEBUG : Working with test: kraken 14:25:59 INFO - 2015-12-18 14:25:59,053 DEBUG : Generating results file: kraken 14:25:59 INFO - 2015-12-18 14:25:59,053 INFO : javascript benchmark 14:25:59 INFO - 2015-12-18 14:25:59,054 DEBUG : Working with test: v8_7 14:25:59 INFO - 2015-12-18 14:25:59,054 DEBUG : Generating results file: v8_7 14:25:59 INFO - 2015-12-18 14:25:59,054 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:25:59 INFO - 2015-12-18 14:25:59,187 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 14:25:59 INFO - 2015-12-18 14:25:59,187 DEBUG : process_Request line: dromaeo_css 6821.98 graph.html#tests=[[72,64,35]] 14:25:59 INFO - 2015-12-18 14:25:59,187 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:25:59 INFO - 2015-12-18 14:25:59,216 DEBUG : process_Request line: kraken 1496.60 graph.html#tests=[[232,64,35]] 14:25:59 INFO - 2015-12-18 14:25:59,216 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 14:25:59 INFO - 2015-12-18 14:25:59,246 DEBUG : process_Request line: v8_7 19813.18 graph.html#tests=[[230,64,35]] 14:25:59 INFO - 2015-12-18 14:25:59,247 INFO : javascript benchmark 14:25:59 INFO - 2015-12-18 14:25:59,250 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3038.3923215356926, 3098.7056229658174, 3142.2301536123637, 3144.135362775238, 3165.9208519787003, 5083.135866902626, 5146.022255771405, 5182.559482443997, 5206.947930520699, 5233.816816411427, 5160.767765450554, 5219.060569097561, 5229.294045303883, 5230.084735171339, 5248.0, 4584.679072464929, 4711.222648263032, 4723.881902952437, 4838.516148385151, 4865.464798872121, 4617.5382461753725, 4751.572358487735, 4779.80880764769, 4814.542618451242, 4855.377058018912, 4745.21703918853, 4810.158222311082, 4810.446798618152, 4812.109759694467, 4852.2479015752615, 4769.189237829577, 4778.593819525345, 4783.641226907961, 4785.760711964388, 4786.21027530457, 4641.535846415365, 4706.10583989041, 4753.667243292971, 4770.356001939728, 4790.520947905216, 4959.132151873444, 4982.701037937735, 4982.90034199314, 5039.899202015939, 5103.821366252163, 5593.972030139864, 5599.860003499904, 5608.158776183564, 5620.185073164409, 5656.830295091154, 5501.559875209973, 5571.804986825482, 5617.8033768817895, 5629.211910332557, 5629.80295689653, 5309.893802123975, 5311.282976798083, 5311.362636483646, 5317.9468205317835, 5319.840404787863, 5704.771809127588, 5723.742431590588, 5728.799492017843, 5730.083186690193, 5771.653700777967, 5353.9732301338245, 5450.209719590716, 5455.509004189642, 5472.699001554953, 5560.916586251209, 5683.54531637468, 5748.080307150836, 5772.797952071657, 5775.653460792366, 5796.681182534917, 5625.296837895263, 5656.321241451053, 5666.716664166775, 5667.4332566743, 5669.914951275734, 5435.0, 5686.2607860977805, 5701.71491425427, 5712.80005199816, 5723.828285151452, 4297.935530967038, 4321.416608757841, 4332.133573285292, 4334.869953901388, 4335.132973405269, 3902.785346805953, 3917.5690674025836, 3918.5885482024546, 3921.843126274974, 3923.0388554803685, 5140.228965655182, 5168.224766285012, 5204.115300398941, 5225.399079105951, 5238.580913526908, 5604.299462567179, 5642.915356269659, 5644.57665675076, 5644.943550564524, 5647.463490968352, 5098.898022039612, 5202.947970520247, 5217.6347655664495, 5226.608004399685, 5269.077911365495, 4901.852944411673, 4906.460289368167, 4918.803247870045, 4959.0, 4992.80028798844, 5724.284464441945, 5733.369329373765, 5733.827985160452, 5740.913886291709, 5748.19525266463, 5551.278333816578, 5685.5167310778215, 5705.22979397776, 5706.372299047019, 5714.571407144564, 5580.693061881636, 5581.972090139686, 5700.173474846207, 5760.8271751847515, 5828.679422631796, 5583.776648933997, 5611.074172761449, 5620.213170156263, 5628.521575666115, 5635.633683810539, 5428.158635411518, 5691.459311365414, 5697.316322041382, 5703.885922281448, 5703.971480142573, 5427.6200665953, 5449.782008719607, 5477.561795056306, 5503.064479038493, 5534.750936207961, 5487.588430867621, 5489.5608351331, 5490.615656903979, 5490.752916118704, 5506.174073888788, 5175.456577059505, 5309.893802124013, 5419.972900135474, 5431.6740995540395, 5474.178873169057, 5325.866853328698, 5431.8098866539485, 5503.862403439946, 5558.249636299048, 5568.638038527482, 5353.598480114085, 5383.892322153613, 5458.972705136449, 5483.862903427446, 5489.533389661764], "unit": "score", "name": "dojo.html", "value": 5172.930684209507}, {"lowerIsBetter": false, "replicates": [579.8666306749448, 590.3683059126735, 601.2244204975582, 608.2913405882148, 620.9379062093791, 27698.584521232173, 37193.25613487729, 37414.56805307653, 37469.0, 37647.623523764785, 40699.38950915739, 41267.79366103173, 41297.7935110324, 41319.79340103303, 41382.58617413825, 7488.887666685004, 7605.0, 8685.913140868573, 8927.107289271053, 8928.107189281085, 10391.636292729756, 10657.733556661107, 10663.573457061708, 10674.679759607185, 10682.679519614385, 10287.691369258935, 10293.536790844393, 10335.224858135633, 10390.480475976208, 10424.478776061205, 7701.768946931601, 7883.25109114639, 7888.763337099839, 10266.640667576636, 10385.584576616926, 10184.643537476228, 10204.795904081951, 10287.79424411515, 10295.073443390056, 10299.588016479333, 37889.621103788886, 38270.0, 38367.616323836686, 38461.80769096137, 38483.61516384856, 3257.4136655402017, 3289.3750187464307, 3311.867525298985, 3390.3558323918624, 3670.926581468382, 29251.707482925325, 29570.852145739133, 29764.0, 29844.8507757462, 30512.542311865338, 3676.3382591133586, 3678.797666128362, 3718.0704823794053, 3731.869384571527, 3812.294725475796, 252727.99999999817, 253425.0, 253738.0, 254015.0, 254307.0, 56366.0, 56531.0, 56945.0, 57009.714951425805, 57170.714146429, 58743.41256587465, 59346.70326648339, 59586.0, 60036.39963600308, 66207.0, 14656.413743450143, 14701.852981470049, 14803.925980370244, 14811.703765924622, 14833.332500037524, 11222.0, 11259.605913792979, 11271.661850144508, 11273.323600583992, 11279.605213817476, 19024.0, 19276.710849337273, 19281.0, 19300.32448864311, 19318.13068411925, 7011.474139439562, 7175.0, 7251.74618888347, 7265.745698900513, 7305.671244793997, 926.7868390270276, 938.7700013496737, 1014.4167103915131, 1041.4688508860384, 1054.9261551691309, 23922.40193995129, 23948.0, 24002.39994000129, 25540.0, 26061.60907586388, 1027.198784947742, 1043.7964596903682, 1057.3444464431948, 1097.5280629329304, 1127.8251870960014, 14461.008315229563, 15060.32228549718, 15234.847651523565, 15402.922985385001, 15475.690486190213, 327488.0, 332485.0, 332498.0, 333077.0, 333671.0, 51699.0, 52047.73976130171, 52066.47933520616, 52085.0, 52086.0, 1292.702678383977, 1310.711643438442, 1317.4927652853721, 1343.737971095627, 1346.158650843223, 27176.456470870868, 28114.0, 28241.3986027838, 28363.574546382235, 28403.857980709963, 42110.78944605257, 42380.0, 44066.779666102746, 47204.52795472001, 47797.76101119472, 213309.0, 213945.0, 214440.0, 214880.0, 215496.0, 26846.597301040892, 26941.326466838487, 29480.557791632713, 30920.0, 43981.0, 40362.39456408215, 40677.59322406738, 40964.79517602393, 41561.37657935073, 41713.58286417097, 34262.48606270958, 34488.82755586206, 35021.64978350286, 35341.116472088404, 35684.28631427409, 35060.474092888115, 35335.64664353323, 35491.82254088713, 35513.467297990035, 35560.288794224485, 34362.14094647654, 35013.649863501036, 35093.64906350904, 35234.47148292829, 35292.0, 30987.69012309848, 31621.20946976344, 31739.20651983719, 32181.0, 35530.28939421249, 24392.26823195307, 24529.877350613133, 24621.753782461947, 24907.0, 24911.377215569755], "unit": "score", "name": "ext.html", "value": 18204.059499959745}, {"lowerIsBetter": false, "replicates": [354.13414202275413, 354.4222916645867, 355.2823296940181, 356.1861147278469, 357.45845044757215, 13410.530631427877, 13850.168989860615, 16032.118233497153, 16406.0, 17059.999999999985, 15628.14045227512, 15813.446529371475, 16809.32762689491, 16818.0, 16852.32590696374, 4670.462896766864, 4841.8547443576745, 4848.587870031051, 4850.151223535885, 4851.63612729045, 3725.590185079639, 3827.2345530893795, 4830.516948305177, 5914.88170236595, 6062.666553339564, 5928.614640048405, 5956.940430595682, 5962.642241465526, 5962.701864906738, 6016.398360163992, 5807.39022402648, 5925.940740592603, 5943.465088142045, 5947.702614869261, 5956.255468066492, 5262.552683021948, 5362.9006053758885, 5381.381141168777, 5388.892222155574, 5418.674879507243, 12600.369981500888, 12711.237325760483, 12841.0, 12843.486260549567, 12852.164609300366, 2115.6509175985943, 2125.574885022986, 2126.213301078611, 2171.6416791229426, 2346.8239882008747, 9656.806863862683, 9936.503174841302, 9990.300678952477, 10299.0, 12062.552493700725, 1846.1138653446287, 1863.3664554051688, 1865.897375644339, 1869.83171514563, 1924.3553409607798, 57216.71391643057, 61453.38546614566, 61762.073568896056, 63097.68451157715, 63259.36740632581, 9839.458829764431, 9929.602815887283, 10055.39667619945, 10105.646302379382, 10145.797084058277, 13468.461261549624, 13541.05212635106, 13542.932285338511, 13546.390412431465, 13552.525661601796, 7701.499402538929, 7752.496087754278, 7760.456768026184, 7797.415193860483, 7887.48731332473, 7316.6341682915645, 7344.045274114331, 7350.117985841734, 7411.147718012389, 7421.591812450259, 4377.693561450668, 4433.866983990485, 4438.045820148684, 4452.131834292282, 4464.0, 1691.7208660571114, 1750.159923236828, 1764.4001039646582, 1796.9820301797076, 1798.3346161920174, 432.5285438871645, 445.65684422994764, 923.4090182283344, 964.508100868548, 987.3483500889379, 10119.898801012043, 10606.469676516142, 11122.38826864514, 11213.0, 11457.598984035685, 442.97342159470537, 892.8616064510011, 928.4661319741176, 980.887197972228, 1003.1623594298842, 8815.294776417872, 8961.283097352196, 9000.684976025808, 9201.631934722536, 9208.26333893287, 578419.0, 585803.0, 589946.0, 595635.0, 601917.0, 80225.59887200643, 84008.57995709982, 85406.57296713477, 86391.56804216064, 86446.56776716076, 1112.4437781109445, 1151.3552410650063, 1152.0840931459509, 1154.2324354304294, 1156.19066653343, 15346.23268836576, 15470.14914179686, 15499.922500387425, 15705.921470392575, 15989.440369587008, 36377.0, 36404.453933190496, 36863.63136368602, 37342.06644833792, 37384.81307593445, 45956.7702161487, 46277.30584041309, 46897.76551117359, 47239.52760472351, 47365.526344736114, 9438.716838494856, 9606.327557070937, 9905.108540231126, 9980.700578982642, 9986.60053597848, 17162.0, 17326.39357622477, 17328.913355433142, 17734.379296724554, 17920.91039544794, 14236.003479756318, 14296.714065718836, 14555.854441455449, 14679.119252844437, 14875.851241487879, 14249.857501424853, 14365.425382984564, 14463.783043254149, 14521.34653940554, 15033.173175475455, 14738.852611473749, 15124.0, 15228.923855380652, 15340.846591533942, 15367.077975321516, 9088.318376121684, 9566.61733530651, 9569.712908612753, 9823.85264221023, 9828.508574571386, 12312.322822244863, 14329.85670143327, 14485.710285794014, 14508.85491145075, 15292.617684557976], "unit": "score", "name": "jquery.html", "value": 8666.084089335864}, {"lowerIsBetter": false, "replicates": [1533.3253368517858, 1666.949991500255, 1713.768641233434, 1721.8192089830563, 1732.1512458895138, 6015.398460153982, 7078.079849619548, 7319.2680731926785, 7327.963360183192, 7328.670209840557, 7179.317964793349, 7335.743248986287, 7343.375813055896, 7348.0, 7353.30143636355, 1907.6947688369864, 1948.4641723526001, 1949.1618604000346, 2183.650615901456, 2207.61366760817, 2174.282486779359, 2214.977850221493, 2215.900284487202, 2216.556688662274, 2217.911283548664, 2067.4727944374163, 2074.087401543316, 2195.034184958621, 2199.3841724317217, 2199.59307528107, 2204.1073365287084, 2211.5908556916943, 2236.7651396603287, 2239.1155493580027, 2253.6056190166655, 2238.9552208955893, 2241.181968581472, 2247.224707475918, 2260.615695331781, 2264.6603009548535, 7223.169335526377, 7279.45404094695, 7294.343509084208, 7308.45186611001, 7328.157261914895, 4448.799804008828, 4477.126960242754, 4540.682152249311, 4541.2733962565835, 4546.045330480603, 2847.288177955511, 2927.473054850126, 2957.526795712697, 2960.925976850596, 2962.7777916656337, 1046.685994201744, 1062.8565143705578, 1068.1027936533271, 1085.1969542538543, 1109.8668159820793, 9891.703248902473, 9897.80204395908, 9905.306628535933, 9917.603295868157, 9924.354916930377, 3983.980080099639, 4002.539707933625, 4071.511418629724, 4096.262672718939, 4097.201045796041, 8542.487450752975, 8548.358873084544, 8561.957190214009, 8566.657333706582, 8583.570821458901, 4531.796069176895, 4553.908921821545, 4563.292689633112, 4565.863024109282, 4566.657500687462, 4140.937885931713, 4149.813258403379, 4151.7924103794685, 4165.375193720905, 4167.06241095751, 3092.737117345051, 3183.6657150999276, 3252.284497410566, 3503.4394496880377, 3569.5002699622073, 1140.5836869542723, 1144.542183126756, 1152.9884701153048, 1155.607093588184, 1160.5415860734963, 1074.838774183863, 1080.2492267873909, 1107.4739498738036, 1209.673388185185, 1306.5361796562274, 6751.797446076625, 6794.3545363190415, 6811.4550835933005, 6811.965940170367, 6833.829154271084, 1089.1667874076338, 1096.0792933935531, 1097.8956999085074, 1110.8278216876236, 1115.7489564848006, 5452.91820622683, 5496.532794712414, 5507.366652834895, 5530.889382212334, 5549.139883318092, 8545.2309292164, 8623.439476434012, 8728.869066964, 8757.0, 8820.470771753715, 8515.361347898808, 8591.871121933176, 8629.482231066157, 8636.920384951882, 8650.610722517502, 6826.829329266808, 6897.862042759217, 6947.0, 7002.649867506706, 7018.192907815666, 6519.250286216956, 6532.41208291256, 6537.05212744159, 6543.443807276435, 6552.3775241352, 7713.575753333621, 7719.498232614862, 7758.7672369829, 7916.683332666629, 7923.881141782993, 7897.486663366863, 8049.758507244792, 8102.473339233166, 8116.634751436318, 8117.594120294079, 6424.518161138027, 6522.5760325578685, 6563.245226798979, 6563.245226798979, 6578.5395022347975, 6078.665673387829, 6144.324124346408, 6522.445592124533, 6792.96603516999, 7080.681369338289, 6433.806985790434, 6531.771388001397, 6543.672816359068, 6596.241432235354, 6624.8343791405605, 6335.461485773577, 6339.714712838026, 6387.584806987531, 6442.033694945796, 6506.73973041092, 6343.5876668016435, 6395.712192951235, 6398.680065996775, 6398.968005159944, 6405.487560995202, 5987.850303742442, 6009.098635204754, 6013.458788709037, 6018.2778066630535, 6018.3981601841215, 5963.52291816645, 6018.187544681412, 6022.45797878193, 6039.48664363534, 6111.54163437735], "unit": "score", "name": "mootools.html", "value": 4258.975619600544}, {"lowerIsBetter": false, "replicates": [340.5436714802165, 345.1905282113443, 347.19624070277314, 351.14145913242135, 355.6941030713586, 5919.0, 5938.257717785277, 5989.071693887444, 6021.427964343391, 6031.819045428644, 5680.772769089232, 5732.197492351074, 5779.595428320009, 5896.115582662592, 5908.645481271126, 4560.8859778505475, 4564.246899261618, 4579.9771001144945, 4604.125216208918, 4608.354830323758, 4572.862814115582, 4583.427071616048, 4586.082783443308, 4590.219662657356, 4594.0, 4336.566343365654, 4421.0, 4425.66807489438, 4496.415465989417, 4505.391772110772, 4486.528914463999, 4505.864824055284, 4515.435570553681, 4524.638028957708, 4527.547245275463, 3986.7807270600106, 4000.7599544027453, 4001.339778936472, 4001.559828418872, 4007.438958545806, 5620.213170156181, 5627.380988091307, 5630.831075067755, 5634.859128521779, 5634.943650563483, 5195.142801437758, 5308.0, 5346.679199248018, 5359.732013399314, 5361.785528578852, 6276.466500347476, 6280.246370435577, 6286.0, 6287.308396076428, 6298.118263443122, 4518.977405112986, 4519.322101684741, 4521.1409832131785, 4522.909541809178, 4523.479799822997, 6386.329435409261, 6460.870782584322, 6503.382178693017, 6509.567895063078, 6522.184726909137, 4574.336721175478, 4613.815447382134, 4628.143793398232, 4630.814767409266, 4632.62938964882, 4662.836800711959, 4669.929951050669, 4673.415823022122, 4677.859664210011, 4678.111158879802, 5317.867053323698, 5319.069162896478, 5319.760610772447, 5320.893582128336, 5327.254184414185, 6162.938370616326, 6294.370562943761, 6304.716287767062, 6310.390850333136, 6337.556371053982, 1637.0668718830152, 1656.1470842516114, 1657.1051632118522, 1658.07147997122, 1659.1621231278368, 1113.3709454158375, 1134.13805507813, 1140.4126874659557, 1153.2907262033789, 1164.4061528620464, 6036.3058248302, 6041.607295525777, 6049.274087109575, 6054.424829641177, 6057.606255593372, 6501.512386570931, 6530.379613936669, 6534.411902928759, 6536.24833144185, 6554.573952693059, 5893.970530147321, 5937.910931336033, 5981.043033114681, 6103.38966103395, 6106.969465152646, 3534.8232588370993, 3551.3785087609567, 3557.4130268505924, 3559.14580500678, 3561.554805649294, 6652.467802575782, 6663.06717059612, 6664.5334826561675, 6665.133532640726, 6666.899996500057, 6589.209294884645, 6703.128593282842, 6712.463002959751, 6718.428933540704, 6735.124433823669, 6631.0, 6632.502562307846, 6633.900491492632, 6643.86712265752, 6646.468282537386, 6537.823191825517, 6564.934350656624, 6576.342365763386, 6578.5395022347975, 6603.372679595431, 6368.52236082305, 6477.251142191488, 6487.091807147098, 6488.091667166506, 6504.9674751625935, 6638.468922486093, 6647.0, 6649.933500664932, 6653.900191497035, 6658.067870498231, 4638.489766125656, 4644.58198762113, 4649.8140074398, 4653.650976176868, 4654.743989080633, 6468.094466774562, 6537.5750576212395, 6587.538872278895, 6599.571027883172, 6606.405423511907, 6383.39357761012, 6409.551331406757, 6416.13382193398, 6416.871662566629, 6421.8715625688155, 6367.140436041261, 6368.395002474757, 6372.330905254881, 6377.649229292433, 6388.776392826228, 6140.385961403824, 6248.812535623939, 6252.812415627538, 6263.749450021948, 6272.905906411499, 6400.967995159995, 6403.551751377358, 6404.839879003062, 6409.198850143732, 6418.229812422539, 6259.530535209787, 6262.5929314594405, 6265.686715664107, 6273.717682704198, 6274.780382686584], "unit": "score", "name": "prototype.html", "value": 4800.227199964779}, {"lowerIsBetter": false, "replicates": [734.6894018312411, 770.9553554933062, 778.5514556719119, 844.1727107434714, 859.8839156713842, 12970.805437918427, 13150.145240559357, 13208.801867971988, 13211.207327560342, 13237.536686215984, 12517.311547864863, 12632.17890837095, 12651.557195498159, 12668.429920653569, 14755.0, 828.0891019878146, 840.3907167303717, 840.7813968368238, 841.2933136165619, 842.49029337251, 687.9174499060119, 700.0374485082986, 816.9264766171043, 833.6457005772553, 841.6549214821924, 868.5006121480175, 869.9478031318109, 873.2708188662474, 875.0156074416282, 875.5753459572103, 860.8665656823202, 868.0885070675797, 868.1188593577492, 868.4702331577732, 869.4609342207872, 524.8871492629103, 531.8351311093573, 532.4941305759505, 532.6511135206446, 622.3807311724818, 13127.671808204776, 13232.536861209812, 13244.86755132446, 13266.270355130464, 13278.136921100195, 11747.882521174764, 12140.514379424812, 12178.330191839445, 12261.632151035394, 12634.620961371174, 9889.270904545478, 10070.69787906364, 10084.495775211284, 10172.23708221879, 10547.947260263649, 3555.2000799820094, 3569.464580312948, 3579.94630080549, 3646.6717995380277, 3649.927001459956, 43201.56798432007, 43295.0, 43454.13091738147, 43663.0, 43868.12263754707, 9732.318737688436, 9828.459434731158, 9875.506224688737, 9878.506074696237, 9964.352317099365, 17369.7394539082, 17433.0, 17460.0, 17466.214020369112, 17475.737863932052, 7325.120985481776, 7390.371818395497, 7535.886961695579, 7660.080790305087, 7673.693052277958, 4376.562343765661, 4387.561243875587, 4399.562937499047, 4424.867253982386, 4573.977130114395, 3052.786304958676, 3081.7842751007215, 3114.096911895525, 3114.704103110201, 3115.3613509230554, 527.8073503171391, 548.6378989866748, 549.7965752671537, 559.1640497456248, 560.1821340842334, 588.8280740579695, 672.5325898500495, 678.6776281266359, 683.1938312790954, 686.3959715450371, 8214.58927053645, 8599.914000859912, 8640.956795215983, 8645.308375329958, 8729.430537548644, 539.511741873605, 596.352957041606, 630.6405348951054, 660.3099760750027, 663.4161937494973, 3585.3008663310925, 3824.655780979725, 3825.9617403825605, 3858.3633700439113, 3943.6253555912144, 51301.74349128231, 51724.22413663798, 51911.480885190664, 52158.0, 52474.47525524696, 46025.7698711511, 46411.30383044257, 46475.30287045697, 46708.5329146711, 47009.76495117503, 41549.0, 41891.79054104771, 41941.370879436225, 42767.1446571073, 43141.35287970683, 7800.45396822217, 7819.921800781919, 7865.331446827084, 8078.949736534213, 8078.959605201936, 32983.50524742179, 33159.33681326408, 33192.0, 34469.82765086158, 34693.30613387768, 40387.596124039555, 41716.165676686905, 41802.0, 41807.58192418037, 42041.0, 9461.290403219924, 9675.70972870815, 9747.171490423394, 10344.482775861328, 10468.738281543023, 10412.0, 10488.790224195322, 10817.296875703054, 10855.565777369133, 11219.439028048402, 9839.409635421898, 9945.303828732209, 10278.640247591298, 10336.431496267778, 10453.9477302613, 9278.58246378928, 9806.264530160124, 9897.158741507052, 9946.552405141641, 9977.800443991226, 8861.512616806138, 9999.900000999896, 10748.355098694103, 10901.291416057931, 11189.048930840645, 9634.614615415305, 9725.270604704534, 10000.199984001118, 10026.64906728261, 10256.692299231036, 7454.813629659302, 8267.875981860157, 8800.39970022483, 9145.405548639317, 9183.31125165602], "unit": "score", "name": "yui.html", "value": 6041.227728422178}], "name": "dromaeo_css", "value": 6821.973690844401}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 111.0, 104.0, 111.0, 110.0, 111.0, 101.0, 112.0, 112.0, 119.0], "unit": "score", "name": "ai-astar", "value": 110.0}, {"lowerIsBetter": true, "replicates": [151.0, 124.0, 151.0, 163.0, 130.0, 146.0, 161.0, 125.0, 161.0, 125.0], "unit": "score", "name": "audio-beat-detection", "value": 143.7}, {"lowerIsBetter": true, "replicates": [161.0, 162.0, 160.0, 160.0, 161.0, 162.0, 159.0, 164.0, 162.0, 162.0], "unit": "score", "name": "audio-dft", "value": 161.3}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 79.0, 98.0, 71.0, 72.0, 72.0, 99.0, 88.0, 72.0], "unit": "score", "name": "audio-fft", "value": 79.4}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 142.0, 93.0, 93.0, 93.0, 132.0, 93.0, 94.0, 137.0], "unit": "score", "name": "audio-oscillator", "value": 106.4}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 109.0, 110.0, 109.0, 109.0, 110.0, 109.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.5}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 148.0, 146.0, 147.0, 146.0, 145.0, 146.0, 146.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.3}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0, 97.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 95.8}, {"lowerIsBetter": true, "replicates": [60.0, 58.0, 59.0, 58.0, 58.0, 61.0, 58.0, 66.0, 56.0, 57.0], "unit": "score", "name": "json-parse-financial", "value": 59.1}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 68.0, 68.0, 68.0, 67.0, 67.0, 67.0, 67.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.4}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 85.0, 85.0, 86.0, 84.0, 85.0, 85.0, 87.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.3}, {"lowerIsBetter": true, "replicates": [117.0, 121.0, 116.0, 116.0, 116.0, 114.0, 120.0, 123.0, 116.0, 119.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 117.8}, {"lowerIsBetter": true, "replicates": [141.0, 139.0, 153.0, 150.0, 151.0, 143.0, 151.0, 150.0, 150.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 146.7}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 68.0, 68.0, 67.0, 68.0, 68.0, 68.0, 69.0, 67.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 67.9}], "name": "kraken", "value": 1496.6}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.00265604249668], "unit": "score", "name": "Richards", "value": 212.659248}, {"lowerIsBetter": false, "replicates": [189.5016107636915], "unit": "score", "name": "DeltaBlue", "value": 348.90468599999986}, {"lowerIsBetter": false, "replicates": [283.36639274582035], "unit": "score", "name": "Encrypt", "value": 939.3527489999996}, {"lowerIsBetter": false, "replicates": [5154.639175257732], "unit": "score", "name": "Decrypt", "value": 51.63911399999997}, {"lowerIsBetter": false, "replicates": [2042.857142857143], "unit": "score", "name": "RayTrace", "value": 362.23237762237744}, {"lowerIsBetter": false, "replicates": [793.0214115781126], "unit": "score", "name": "Earley", "value": 840.4098429999997}, {"lowerIsBetter": false, "replicates": [12060.240963855422], "unit": "score", "name": "Boyer", "value": 55.2611678321678}, {"lowerIsBetter": false, "replicates": [25769.23076923077], "unit": "score", "name": "RegExp", "value": 35.3516567164179}, {"lowerIsBetter": false, "replicates": [597.3715651135007], "unit": "score", "name": "Splay", "value": 136.41593400000002}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 19813.182988557845}]} 14:25:59 INFO - RETURN: dromaeo_css: 6821.98 14:25:59 INFO - RETURN: kraken: 1496.60 14:25:59 INFO - RETURN: v8_7: 19813.18 14:25:59 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "19813.18"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1496.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6821.98"}}} 14:25:59 INFO - Return code: 0 14:25:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 14:25:59 INFO - # TBPL SUCCESS # 14:25:59 INFO - Running post-action listener: _resource_record_post_action 14:25:59 INFO - Running post-run listener: _resource_record_post_run 14:26:00 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 4096; Write bytes: 419676160; Read time: 4; Write time: 327964 14:26:00 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 112877568; Read time: 0; Write time: 130420 14:26:00 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 4096; Write bytes: 306798592; Read time: 4; Write time: 197544 14:26:00 INFO - Running post-run listener: _upload_blobber_files 14:26:00 INFO - Blob upload gear active. 14:26:00 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 14:26:00 INFO - Copying logs to upload dir... 14:26:00 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1293.701733 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2015-12-18 14:25:59.884091) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-18 14:25:59.887867) ========= 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=fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010145 build_url: 'https://queue.taskcluster.net/v1/task/IXXb3hXPSYivPVMXRQRQrg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-18 14:25:59.938354) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 14:25:59.938693) ========= 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=fce1c2174958ee92775f70ae000001a3-1450474137.39718-1479379985 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004547 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-18 14:25:59.988341) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 14:25:59.988658) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-18 14:25:59.988935) ========= ========= Total master_lag: 0.27 =========