builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1450853365.04 results: success (0) buildid: 20151222203249 builduid: ca7c2bdad83c428385669b13a4d00871 revision: dd26de01e3d255d6f62e679a58a0113ac1128183 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.039388) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.040245) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.040580) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006334 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.080655) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.081107) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.105239) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.105541) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004607 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.159766) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.160092) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.160483) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.163212) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-22 22:49: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% 43.1M=0s 2015-12-22 22:49:25 (43.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.172485 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.348921) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.349341) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017232 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:25.400254) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-22 22:49:25.400658) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev dd26de01e3d255d6f62e679a58a0113ac1128183 --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 dd26de01e3d255d6f62e679a58a0113ac1128183 --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=0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-22 22:49:25,614 truncating revision to first 12 chars 2015-12-22 22:49:25,615 Setting DEBUG logging. 2015-12-22 22:49:25,615 attempt 1/10 2015-12-22 22:49:25,615 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/dd26de01e3d2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-22 22:49:26,764 unpacking tar archive at: fx-team-dd26de01e3d2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.461176 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-12-22 22:49:26.876049) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:26.876358) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:26.886054) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:26.886308) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-22 22:49:26.886685) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2015-12-22 22:49:26.886956) ========= /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=0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:49:27 INFO - MultiFileLogger online at 20151222 22:49:27 in /builds/slave/test-pgo 22:49:27 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 22:49:27 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 22:49:27 INFO - {'append_to_log': False, 22:49:27 INFO - 'base_work_dir': '/builds/slave/test-pgo', 22:49:27 INFO - 'blob_upload_branch': 'Fx-Team', 22:49:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 22:49:27 INFO - 'branch': 'Fx-Team', 22:49:27 INFO - 'buildbot_json_path': 'buildprops.json', 22:49:27 INFO - 'config_files': ('talos/linux_config.py',), 22:49:27 INFO - 'default_actions': ('clobber', 22:49:27 INFO - 'read-buildbot-config', 22:49:27 INFO - 'download-and-extract', 22:49:27 INFO - 'populate-webroot', 22:49:27 INFO - 'create-virtualenv', 22:49:27 INFO - 'install', 22:49:27 INFO - 'run-tests'), 22:49:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:49:27 INFO - 'download_minidump_stackwalk': True, 22:49:27 INFO - 'download_symbols': 'ondemand', 22:49:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:49:27 INFO - 'tooltool.py': '/tools/tooltool.py', 22:49:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:49:27 INFO - '/tools/misc-python/virtualenv.py')}, 22:49:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:49:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:49:27 INFO - 'installer_path': 'installer.exe', 22:49:27 INFO - 'log_level': 'info', 22:49:27 INFO - 'log_name': 'talos', 22:49:27 INFO - 'log_to_console': True, 22:49:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:49:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:49:27 INFO - 'opt_config_files': (), 22:49:27 INFO - 'pip_index': False, 22:49:27 INFO - 'sps_profile': False, 22:49:27 INFO - 'sps_profile_interval': 0, 22:49:27 INFO - 'suite': 'dromaeojs', 22:49:27 INFO - 'system_bits': '64', 22:49:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:49:27 INFO - 'title': 'talos-linux64-ix-035', 22:49:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:49:27 INFO - 'use_talos_json': True, 22:49:27 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 22:49:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:49:27 INFO - 'work_dir': 'build'} 22:49:27 INFO - ##### 22:49:27 INFO - ##### Running clobber step. 22:49:27 INFO - ##### 22:49:27 INFO - Running pre-action listener: _resource_record_pre_action 22:49:27 INFO - Running main action method: clobber 22:49:27 INFO - rmtree: /builds/slave/test-pgo/build 22:49:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 22:49:27 INFO - Running post-action listener: _resource_record_post_action 22:49:27 INFO - ##### 22:49:27 INFO - ##### Running read-buildbot-config step. 22:49:27 INFO - ##### 22:49:27 INFO - Running pre-action listener: _resource_record_pre_action 22:49:27 INFO - Running main action method: read_buildbot_config 22:49:27 INFO - Using buildbot properties: 22:49:27 INFO - { 22:49:27 INFO - "project": "", 22:49:27 INFO - "product": "firefox", 22:49:27 INFO - "script_repo_revision": "production", 22:49:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:49:27 INFO - "repository": "", 22:49:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 22:49:27 INFO - "buildid": "20151222203249", 22:49:27 INFO - "pgo_build": "True", 22:49:27 INFO - "basedir": "/builds/slave/test-pgo", 22:49:27 INFO - "buildnumber": 848, 22:49:27 INFO - "slavename": "talos-linux64-ix-035", 22:49:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:49:27 INFO - "platform": "ubuntu64_hw", 22:49:27 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 22:49:27 INFO - "branch": "fx-team", 22:49:27 INFO - "repo_path": "integration/fx-team", 22:49:27 INFO - "revision": "dd26de01e3d255d6f62e679a58a0113ac1128183", 22:49:27 INFO - "stage_platform": "linux64-pgo", 22:49:27 INFO - "builduid": "ca7c2bdad83c428385669b13a4d00871", 22:49:27 INFO - "slavebuilddir": "test" 22:49:27 INFO - } 22:49:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 22:49:27 INFO - Running post-action listener: _resource_record_post_action 22:49:27 INFO - ##### 22:49:27 INFO - ##### Running download-and-extract step. 22:49:27 INFO - ##### 22:49:27 INFO - Running pre-action listener: _resource_record_pre_action 22:49:27 INFO - Running main action method: download_and_extract 22:49:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 22:49: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')]} 22:49:27 INFO - https://queue.taskcluster.net/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:49:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/test_packages.json 22:49:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/test_packages.json 22:49:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 22:49:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 22:49:31 INFO - Downloaded 1302 bytes. 22:49:31 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 22:49:31 INFO - Using the following test package requirements: 22:49:31 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:49:31 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:49:31 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:31 INFO - u'jsshell-linux-x86_64.zip'], 22:49:31 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:49:31 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:49:31 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:49:31 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:49:31 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:49:31 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:49:31 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:49:31 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:49:31 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:31 INFO - https://queue.taskcluster.net/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:49:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:49:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:49:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/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 22:49:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/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 22:49:33 INFO - Downloaded 23176069 bytes. 22:49:33 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 22:49:33 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:49:33 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 22:49:34 INFO - Return code: 0 22:49:34 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 22:49:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:34 INFO - https://queue.taskcluster.net/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:49:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:49:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:49:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/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 22:49:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/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 22:49:35 INFO - Downloaded 23176069 bytes. 22:49:35 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 22:49:35 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:49:35 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 22:49:36 INFO - Return code: 0 22:49:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:36 INFO - https://queue.taskcluster.net/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:49:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:49:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:49:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/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 22:49:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/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 22:49:37 INFO - Downloaded 11132277 bytes. 22:49:37 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 22:49:37 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:49:37 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 22:49:37 INFO - Return code: 0 22:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:37 INFO - https://queue.taskcluster.net/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:49:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:49:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:49:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/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 22:49:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/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 22:49:38 INFO - Downloaded 54924128 bytes. 22:49:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:49:38 INFO - mkdir: /builds/slave/test-pgo/properties 22:49:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 22:49:38 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 22:49:38 INFO - Contents: 22:49:38 INFO - build_url:https://queue.taskcluster.net/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:49:38 INFO - Running post-action listener: _resource_record_post_action 22:49:38 INFO - Running post-action listener: set_extra_try_arguments 22:49:38 INFO - ##### 22:49:38 INFO - ##### Running populate-webroot step. 22:49:38 INFO - ##### 22:49:38 INFO - Running pre-action listener: _resource_record_pre_action 22:49:38 INFO - Running main action method: populate_webroot 22:49:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:49:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:49:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:49:38 INFO - '--tppagecycles', 22:49:38 INFO - '7'], 22:49:38 INFO - 'tests': ['tsvgm']}}, 22:49:38 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:49:38 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:49:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:49:38 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:49:38 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:49:38 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:49:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:49:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:38 INFO - 'talos_options': ['--e10s'], 22:49:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:49:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:38 INFO - 'tests': ['damp', 'tps']}, 22:49:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:38 INFO - 'talos_options': ['--e10s'], 22:49:38 INFO - 'tests': ['damp', 'tps']}, 22:49:38 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:49:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:49:38 INFO - 'other': {'tests': ['a11yr', 22:49:38 INFO - 'ts_paint', 22:49:38 INFO - 'tpaint', 22:49:38 INFO - 'sessionrestore', 22:49:38 INFO - 'sessionrestore_no_auto_restore']}, 22:49:38 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:49:38 INFO - 'tests': ['a11yr', 22:49:38 INFO - 'ts_paint', 22:49:38 INFO - 'tpaint', 22:49:38 INFO - 'sessionrestore', 22:49:38 INFO - 'sessionrestore_no_auto_restore']}, 22:49:38 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:49:38 INFO - 'tests': ['a11yr', 22:49:38 INFO - 'ts_paint', 22:49:38 INFO - 'tpaint', 22:49:38 INFO - 'sessionrestore', 22:49:38 INFO - 'sessionrestore_no_auto_restore']}, 22:49:38 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:49:38 INFO - 'tests': ['a11yr', 22:49:38 INFO - 'ts_paint', 22:49:38 INFO - 'tpaint', 22:49:38 INFO - 'sessionrestore', 22:49:38 INFO - 'sessionrestore_no_auto_restore']}, 22:49:38 INFO - 'other_l64': {'tests': ['a11yr', 22:49:38 INFO - 'ts_paint', 22:49:38 INFO - 'tpaint', 22:49:38 INFO - 'sessionrestore', 22:49:38 INFO - 'sessionrestore_no_auto_restore']}, 22:49:38 INFO - 'other_nol64': {'tests': ['a11yr', 22:49:38 INFO - 'ts_paint', 22:49:38 INFO - 'tpaint', 22:49:38 INFO - 'sessionrestore', 22:49:38 INFO - 'sessionrestore_no_auto_restore']}, 22:49:38 INFO - 'svgr': {'tests': ['tsvgx', 22:49:38 INFO - 'tsvgr_opacity', 22:49:38 INFO - 'tart', 22:49:38 INFO - 'tscrollx', 22:49:38 INFO - 'cart']}, 22:49:38 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:49:38 INFO - 'tests': ['tsvgx', 22:49:38 INFO - 'tsvgr_opacity', 22:49:38 INFO - 'tart', 22:49:38 INFO - 'tscrollx', 22:49:38 INFO - 'cart']}, 22:49:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:38 INFO - 'tests': ['tp5o']}, 22:49:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:38 INFO - 'talos_options': ['--e10s'], 22:49:38 INFO - 'tests': ['tp5o']}, 22:49:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:38 INFO - 'talos_options': ['--xperf_path', 22:49:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:49:38 INFO - 'tests': ['tp5n']}, 22:49:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:49:38 INFO - 'talos_options': ['--e10s', 22:49:38 INFO - '--xperf_path', 22:49:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:49:38 INFO - 'tests': ['tp5n']}}, 22:49:38 INFO - 'talos.zip': {'path': '', 22:49:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:49:38 INFO - Running post-action listener: _resource_record_post_action 22:49:38 INFO - ##### 22:49:38 INFO - ##### Running create-virtualenv step. 22:49:38 INFO - ##### 22:49:38 INFO - Running pre-action listener: _resource_record_pre_action 22:49:38 INFO - Running main action method: create_virtualenv 22:49:38 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 22:49:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 22:49:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 22:49:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:49:39 INFO - Using real prefix '/usr' 22:49:39 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 22:49:39 INFO - Installing distribute.............................................................................................................................................................................................done. 22:49:41 INFO - Installing pip.................done. 22:49:41 INFO - Return code: 0 22:49:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 22:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:41 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 0x7f2a9b9e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2296630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2327840>, '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 0x22946b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294b60>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:49:41 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 22:49:41 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 22:49:41 INFO - Using env: {'DISPLAY': ':0', 22:49:41 INFO - 'HOME': '/home/cltbld', 22:49:41 INFO - 'LANG': 'en_US.UTF-8', 22:49:41 INFO - 'LANGUAGE': 'en_US:en', 22:49:41 INFO - 'LOGNAME': 'cltbld', 22:49:41 INFO - 'MAIL': '/var/mail/cltbld', 22:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:41 INFO - 'MOZ_NO_REMOTE': '1', 22:49:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:41 INFO - 'NO_EM_RESTART': '1', 22:49:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:49:41 INFO - 'PWD': '/builds/slave/test-pgo', 22:49:41 INFO - 'SHELL': '/bin/bash', 22:49:41 INFO - 'SHLVL': '1', 22:49:41 INFO - 'TERM': 'linux', 22:49:41 INFO - 'TMOUT': '86400', 22:49:41 INFO - 'USER': 'cltbld', 22:49:41 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 22:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:41 INFO - '_': '/tools/buildbot/bin/python'} 22:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:49:41 INFO - Cleaning up... 22:49:41 INFO - Return code: 0 22:49:41 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 22:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:41 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 0x7f2a9b9e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2296630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2327840>, '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 0x22946b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294b60>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:49:41 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 22:49:41 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 22:49:41 INFO - Using env: {'DISPLAY': ':0', 22:49:41 INFO - 'HOME': '/home/cltbld', 22:49:41 INFO - 'LANG': 'en_US.UTF-8', 22:49:41 INFO - 'LANGUAGE': 'en_US:en', 22:49:41 INFO - 'LOGNAME': 'cltbld', 22:49:41 INFO - 'MAIL': '/var/mail/cltbld', 22:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:41 INFO - 'MOZ_NO_REMOTE': '1', 22:49:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:41 INFO - 'NO_EM_RESTART': '1', 22:49:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:49:41 INFO - 'PWD': '/builds/slave/test-pgo', 22:49:41 INFO - 'SHELL': '/bin/bash', 22:49:41 INFO - 'SHLVL': '1', 22:49:41 INFO - 'TERM': 'linux', 22:49:41 INFO - 'TMOUT': '86400', 22:49:41 INFO - 'USER': 'cltbld', 22:49:41 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 22:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:41 INFO - '_': '/tools/buildbot/bin/python'} 22:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:41 INFO - Downloading/unpacking psutil>=0.7.1 22:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:43 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 22:49:43 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 22:49:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:49:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:49:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:49:43 INFO - Installing collected packages: psutil 22:49:43 INFO - Running setup.py install for psutil 22:49:43 INFO - building 'psutil._psutil_linux' extension 22:49:43 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:49:43 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:49:43 INFO - building 'psutil._psutil_posix' extension 22:49:43 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:49:44 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:49:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:49:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:49:44 INFO - Successfully installed psutil 22:49:44 INFO - Cleaning up... 22:49:44 INFO - Return code: 0 22:49:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:44 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 0x7f2a9b9e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2296630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2327840>, '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 0x22946b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294b60>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:49:44 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 22:49:44 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 22:49:44 INFO - Using env: {'DISPLAY': ':0', 22:49:44 INFO - 'HOME': '/home/cltbld', 22:49:44 INFO - 'LANG': 'en_US.UTF-8', 22:49:44 INFO - 'LANGUAGE': 'en_US:en', 22:49:44 INFO - 'LOGNAME': 'cltbld', 22:49:44 INFO - 'MAIL': '/var/mail/cltbld', 22:49:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:44 INFO - 'MOZ_NO_REMOTE': '1', 22:49:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:44 INFO - 'NO_EM_RESTART': '1', 22:49:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:49:44 INFO - 'PWD': '/builds/slave/test-pgo', 22:49:44 INFO - 'SHELL': '/bin/bash', 22:49:44 INFO - 'SHLVL': '1', 22:49:44 INFO - 'TERM': 'linux', 22:49:44 INFO - 'TMOUT': '86400', 22:49:44 INFO - 'USER': 'cltbld', 22:49:44 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 22:49:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:44 INFO - '_': '/tools/buildbot/bin/python'} 22:49:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:49:46 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 22:49:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:49:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:49:46 INFO - Installing collected packages: mozsystemmonitor 22:49:46 INFO - Running setup.py install for mozsystemmonitor 22:49:46 INFO - Successfully installed mozsystemmonitor 22:49:46 INFO - Cleaning up... 22:49:46 INFO - Return code: 0 22:49:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22:49:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:46 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 0x7f2a9b9e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2296630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2327840>, '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 0x22946b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294b60>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:49:46 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 22:49:46 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 22:49:46 INFO - Using env: {'DISPLAY': ':0', 22:49:46 INFO - 'HOME': '/home/cltbld', 22:49:46 INFO - 'LANG': 'en_US.UTF-8', 22:49:46 INFO - 'LANGUAGE': 'en_US:en', 22:49:46 INFO - 'LOGNAME': 'cltbld', 22:49:46 INFO - 'MAIL': '/var/mail/cltbld', 22:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:46 INFO - 'MOZ_NO_REMOTE': '1', 22:49:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:46 INFO - 'NO_EM_RESTART': '1', 22:49:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:49:46 INFO - 'PWD': '/builds/slave/test-pgo', 22:49:46 INFO - 'SHELL': '/bin/bash', 22:49:46 INFO - 'SHLVL': '1', 22:49:46 INFO - 'TERM': 'linux', 22:49:46 INFO - 'TMOUT': '86400', 22:49:46 INFO - 'USER': 'cltbld', 22:49:46 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 22:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:46 INFO - '_': '/tools/buildbot/bin/python'} 22:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:46 INFO - Downloading/unpacking blobuploader==1.2.4 22:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:48 INFO - Downloading blobuploader-1.2.4.tar.gz 22:49:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 22:49:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:49:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 22:49:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 22:49:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:48 INFO - Downloading docopt-0.6.1.tar.gz 22:49:48 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 22:49:48 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 22:49:48 INFO - Installing collected packages: blobuploader, requests, docopt 22:49:48 INFO - Running setup.py install for blobuploader 22:49:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:49:49 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 22:49:49 INFO - Running setup.py install for requests 22:49:49 INFO - Running setup.py install for docopt 22:49:49 INFO - Successfully installed blobuploader requests docopt 22:49:49 INFO - Cleaning up... 22:49:49 INFO - Return code: 0 22:49:49 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:49 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 0x7f2a9b9e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2296630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2327840>, '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 0x22946b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294b60>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:49:49 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 22:49:49 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 22:49:49 INFO - Using env: {'DISPLAY': ':0', 22:49:49 INFO - 'HOME': '/home/cltbld', 22:49:49 INFO - 'LANG': 'en_US.UTF-8', 22:49:49 INFO - 'LANGUAGE': 'en_US:en', 22:49:49 INFO - 'LOGNAME': 'cltbld', 22:49:49 INFO - 'MAIL': '/var/mail/cltbld', 22:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:49 INFO - 'MOZ_NO_REMOTE': '1', 22:49:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:49 INFO - 'NO_EM_RESTART': '1', 22:49:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:49:49 INFO - 'PWD': '/builds/slave/test-pgo', 22:49:49 INFO - 'SHELL': '/bin/bash', 22:49:49 INFO - 'SHLVL': '1', 22:49:49 INFO - 'TERM': 'linux', 22:49:49 INFO - 'TMOUT': '86400', 22:49:49 INFO - 'USER': 'cltbld', 22:49:49 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 22:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:49 INFO - '_': '/tools/buildbot/bin/python'} 22:49:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:49:49 INFO - Running setup.py (path:/tmp/pip-zRkPdB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:49:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:49:49 INFO - Running setup.py (path:/tmp/pip-yhY6SP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:49:49 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:49:49 INFO - Running setup.py (path:/tmp/pip-_urPIt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:49:50 INFO - Running setup.py (path:/tmp/pip-5JenOo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:49:50 INFO - Running setup.py (path:/tmp/pip-lZSs3W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:49:50 INFO - Running setup.py (path:/tmp/pip-4aFIjV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:49:50 INFO - Running setup.py (path:/tmp/pip-EAETfd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:49:50 INFO - Running setup.py (path:/tmp/pip-lyuhRe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:49:50 INFO - Running setup.py (path:/tmp/pip-FNIs7r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:49:50 INFO - Running setup.py (path:/tmp/pip-3DHm0g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:49:50 INFO - Running setup.py (path:/tmp/pip-yEXxY3-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:49:50 INFO - Running setup.py (path:/tmp/pip-GBXeUt-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:49:50 INFO - Running setup.py (path:/tmp/pip-Pk7PTz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:49:50 INFO - Running setup.py (path:/tmp/pip-wFPtgT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:49:50 INFO - Running setup.py (path:/tmp/pip-YIEaFF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:49:50 INFO - Running setup.py (path:/tmp/pip-ufAwMv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:49:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:49:50 INFO - Running setup.py (path:/tmp/pip-sySTLE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:49:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:49:51 INFO - Running setup.py install for manifestparser 22:49:51 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 22:49:51 INFO - Running setup.py install for mozcrash 22:49:51 INFO - Running setup.py install for mozdebug 22:49:51 INFO - Running setup.py install for mozdevice 22:49:51 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 22:49:51 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 22:49:51 INFO - Running setup.py install for mozfile 22:49:51 INFO - Running setup.py install for mozhttpd 22:49:51 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 22:49:51 INFO - Running setup.py install for mozinfo 22:49:51 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 22:49:51 INFO - Running setup.py install for mozInstall 22:49:51 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 22:49:51 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 22:49:51 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 22:49:51 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 22:49:51 INFO - Running setup.py install for mozleak 22:49:51 INFO - Running setup.py install for mozlog 22:49:52 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 22:49:52 INFO - Running setup.py install for moznetwork 22:49:52 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 22:49:52 INFO - Running setup.py install for mozprocess 22:49:52 INFO - Running setup.py install for mozprofile 22:49:52 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 22:49:52 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 22:49:52 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 22:49:52 INFO - Running setup.py install for mozrunner 22:49:52 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 22:49:52 INFO - Running setup.py install for mozscreenshot 22:49:52 INFO - Running setup.py install for moztest 22:49:52 INFO - Running setup.py install for mozversion 22:49:52 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 22:49:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:49:52 INFO - Cleaning up... 22:49:52 INFO - Return code: 0 22:49:52 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:52 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 0x7f2a9b9e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2296630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2327840>, '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 0x22946b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294b60>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:49:52 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 22:49:52 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 22:49:52 INFO - Using env: {'DISPLAY': ':0', 22:49:52 INFO - 'HOME': '/home/cltbld', 22:49:52 INFO - 'LANG': 'en_US.UTF-8', 22:49:52 INFO - 'LANGUAGE': 'en_US:en', 22:49:52 INFO - 'LOGNAME': 'cltbld', 22:49:52 INFO - 'MAIL': '/var/mail/cltbld', 22:49:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:52 INFO - 'MOZ_NO_REMOTE': '1', 22:49:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:52 INFO - 'NO_EM_RESTART': '1', 22:49:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:49:52 INFO - 'PWD': '/builds/slave/test-pgo', 22:49:52 INFO - 'SHELL': '/bin/bash', 22:49:52 INFO - 'SHLVL': '1', 22:49:52 INFO - 'TERM': 'linux', 22:49:52 INFO - 'TMOUT': '86400', 22:49:52 INFO - 'USER': 'cltbld', 22:49:52 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 22:49:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:52 INFO - '_': '/tools/buildbot/bin/python'} 22:49:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:49:53 INFO - Running setup.py (path:/tmp/pip-yf2D9k-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 22:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 1)) 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:49:53 INFO - Running setup.py (path:/tmp/pip-ulW7hU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 22:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:49:53 INFO - Running setup.py (path:/tmp/pip-8fMcn0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 22:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:49:53 INFO - Running setup.py (path:/tmp/pip-wo46ss-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 22:49:53 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)) 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 22:49:53 INFO - Running setup.py (path:/tmp/pip-POWwuq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 22:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:49:53 INFO - Running setup.py (path:/tmp/pip-GxzSAG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 22:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 6)) 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:49:53 INFO - Running setup.py (path:/tmp/pip-TpB3sD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 22:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 7)) 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:49:53 INFO - Running setup.py (path:/tmp/pip-VD84qq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 22:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 8)) 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 22:49:53 INFO - Running setup.py (path:/tmp/pip-2xXIAd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 22:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 9)) 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 22:49:53 INFO - Running setup.py (path:/tmp/pip-aUXhCE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 22:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:49:53 INFO - Running setup.py (path:/tmp/pip-b_sMTK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 22:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 11)) 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:49:53 INFO - Running setup.py (path:/tmp/pip-P7jtjf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 22:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 12)) 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:49:53 INFO - Running setup.py (path:/tmp/pip-2BmKiT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 22:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 22:49:53 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:49:53 INFO - Running setup.py (path:/tmp/pip-1Twsix-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 22:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 14)) 22:49:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:49:54 INFO - Running setup.py (path:/tmp/pip-rRAAkf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 22:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 15)) 22:49:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 22:49:54 INFO - Running setup.py (path:/tmp/pip-7MC7oA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 22:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test-pgo/build/tests/mozbase/moztest in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 16)) 22:49:54 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 22:49:54 INFO - Running setup.py (path:/tmp/pip-dnumlg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 22:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 17)) 22:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 22:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 22:49:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 3)) 22:49:54 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)) 22:49:54 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)) 22:49:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 22:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:55 INFO - Downloading blessings-1.5.1.tar.gz 22:49:55 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 22:49:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 22:49:56 INFO - Installing collected packages: blessings 22:49:56 INFO - Running setup.py install for blessings 22:49:56 INFO - Successfully installed blessings 22:49:56 INFO - Cleaning up... 22:49:56 INFO - Return code: 0 22:49:56 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 22:49:56 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:49:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:49:56 INFO - Reading from file tmpfile_stdout 22:49:56 INFO - Current package versions: 22:49:56 INFO - argparse == 1.2.1 22:49:56 INFO - blessings == 1.5.1 22:49:56 INFO - blobuploader == 1.2.4 22:49:56 INFO - docopt == 0.6.1 22:49:56 INFO - manifestparser == 1.1 22:49:56 INFO - mozInstall == 1.12 22:49:56 INFO - mozcrash == 0.16 22:49:56 INFO - mozdebug == 0.1 22:49:56 INFO - mozdevice == 0.47 22:49:56 INFO - mozfile == 1.2 22:49:56 INFO - mozhttpd == 0.7 22:49:56 INFO - mozinfo == 0.9 22:49:56 INFO - mozleak == 0.1 22:49:56 INFO - mozlog == 3.1 22:49:56 INFO - moznetwork == 0.27 22:49:56 INFO - mozprocess == 0.22 22:49:56 INFO - mozprofile == 0.28 22:49:56 INFO - mozrunner == 6.11 22:49:56 INFO - mozscreenshot == 0.1 22:49:56 INFO - mozsystemmonitor == 0.0 22:49:56 INFO - moztest == 0.7 22:49:56 INFO - mozversion == 1.4 22:49:56 INFO - psutil == 3.1.1 22:49:56 INFO - requests == 1.2.3 22:49:56 INFO - wsgiref == 0.1.2 22:49:56 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 22:49:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:56 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 0x7f2a9b9e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2296630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2327840>, '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 0x22946b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294b60>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:49:56 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 22:49:56 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 22:49:56 INFO - Using env: {'DISPLAY': ':0', 22:49:56 INFO - 'HOME': '/home/cltbld', 22:49:56 INFO - 'LANG': 'en_US.UTF-8', 22:49:56 INFO - 'LANGUAGE': 'en_US:en', 22:49:56 INFO - 'LOGNAME': 'cltbld', 22:49:56 INFO - 'MAIL': '/var/mail/cltbld', 22:49:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:56 INFO - 'MOZ_NO_REMOTE': '1', 22:49:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:56 INFO - 'NO_EM_RESTART': '1', 22:49:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:49:56 INFO - 'PWD': '/builds/slave/test-pgo', 22:49:56 INFO - 'SHELL': '/bin/bash', 22:49:56 INFO - 'SHLVL': '1', 22:49:56 INFO - 'TERM': 'linux', 22:49:56 INFO - 'TMOUT': '86400', 22:49:56 INFO - 'USER': 'cltbld', 22:49:56 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 22:49:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:56 INFO - '_': '/tools/buildbot/bin/python'} 22:49:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 22:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 22:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 22:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 22:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 22:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 22:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 22:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 22:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 9)) 22:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 22:49:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 22:49:56 INFO - Cleaning up... 22:49:56 INFO - Return code: 0 22:49:56 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 22:49:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:56 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 0x7f2a9b9e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2296630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2327840>, '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 0x22946b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294b60>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:49:56 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 22:49:56 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 22:49:56 INFO - Using env: {'DISPLAY': ':0', 22:49:56 INFO - 'HOME': '/home/cltbld', 22:49:56 INFO - 'LANG': 'en_US.UTF-8', 22:49:56 INFO - 'LANGUAGE': 'en_US:en', 22:49:56 INFO - 'LOGNAME': 'cltbld', 22:49:56 INFO - 'MAIL': '/var/mail/cltbld', 22:49:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:56 INFO - 'MOZ_NO_REMOTE': '1', 22:49:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:56 INFO - 'NO_EM_RESTART': '1', 22:49:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:49:56 INFO - 'PWD': '/builds/slave/test-pgo', 22:49:56 INFO - 'SHELL': '/bin/bash', 22:49:56 INFO - 'SHLVL': '1', 22:49:56 INFO - 'TERM': 'linux', 22:49:56 INFO - 'TMOUT': '86400', 22:49:56 INFO - 'USER': 'cltbld', 22:49:56 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 22:49:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:56 INFO - '_': '/tools/buildbot/bin/python'} 22:49:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:56 INFO - Downloading/unpacking jsonschema 22:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:49:58 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 22:49:58 INFO - Downloading/unpacking functools32 (from jsonschema) 22:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:58 INFO - Downloading functools32-3.2.3-2.tar.gz 22:49:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 22:49:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 22:49:58 INFO - warning: no files found matching '*.txt' 22:49:58 INFO - no previously-included directories found matching 'build' 22:49:58 INFO - no previously-included directories found matching 'dist' 22:49:58 INFO - no previously-included directories found matching '.git*' 22:49:58 INFO - Installing collected packages: jsonschema, functools32 22:49:58 INFO - Running setup.py install for functools32 22:49:58 INFO - warning: no files found matching '*.txt' 22:49:58 INFO - no previously-included directories found matching 'build' 22:49:58 INFO - no previously-included directories found matching 'dist' 22:49:58 INFO - no previously-included directories found matching '.git*' 22:49:58 INFO - Successfully installed jsonschema functools32 22:49:58 INFO - Cleaning up... 22:49:58 INFO - Return code: 0 22:49:58 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 22:49:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:49:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:49:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:49:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:49:58 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 0x7f2a9b9e3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21c68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2296630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2327840>, '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 0x22946b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294b60>, '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': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test-pgo', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:49:58 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 22:49:58 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 22:49:58 INFO - Using env: {'DISPLAY': ':0', 22:49:58 INFO - 'HOME': '/home/cltbld', 22:49:58 INFO - 'LANG': 'en_US.UTF-8', 22:49:58 INFO - 'LANGUAGE': 'en_US:en', 22:49:58 INFO - 'LOGNAME': 'cltbld', 22:49:58 INFO - 'MAIL': '/var/mail/cltbld', 22:49:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:49:58 INFO - 'MOZ_NO_REMOTE': '1', 22:49:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:49:58 INFO - 'NO_EM_RESTART': '1', 22:49:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:49:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:49:58 INFO - 'PWD': '/builds/slave/test-pgo', 22:49:58 INFO - 'SHELL': '/bin/bash', 22:49:58 INFO - 'SHLVL': '1', 22:49:58 INFO - 'TERM': 'linux', 22:49:58 INFO - 'TMOUT': '86400', 22:49:58 INFO - 'USER': 'cltbld', 22:49:58 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 22:49:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:49:58 INFO - '_': '/tools/buildbot/bin/python'} 22:49:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:49:59 INFO - Downloading/unpacking flake8 22:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:49:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:49:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:49:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:00 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:50:00 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 22:50:00 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:50:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:50:01 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 22:50:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:50:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:01 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 22:50:01 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:50:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:50:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:50:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 22:50:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 22:50:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:50:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:50:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:50:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:50:01 INFO - no previously-included directories found matching 'docs/_build' 22:50:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:50:01 INFO - Running setup.py install for pep8 22:50:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:50:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:50:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:50:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:50:01 INFO - no previously-included directories found matching 'docs/_build' 22:50:01 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 22:50:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:50:01 INFO - Cleaning up... 22:50:01 INFO - Return code: 0 22:50:01 INFO - Running post-action listener: _resource_record_post_action 22:50:01 INFO - Running post-action listener: _start_resource_monitoring 22:50:01 INFO - Starting resource monitoring. 22:50:01 INFO - ##### 22:50:01 INFO - ##### Running install step. 22:50:01 INFO - ##### 22:50:01 INFO - Running pre-action listener: _resource_record_pre_action 22:50:01 INFO - Running main action method: install 22:50:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 22:50:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 22:50:01 INFO - Reading from file tmpfile_stdout 22:50:01 INFO - Detecting whether we're running mozinstall >=1.0... 22:50:01 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 22:50:01 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 22:50:02 INFO - Reading from file tmpfile_stdout 22:50:02 INFO - Output received: 22:50:02 INFO - Usage: mozinstall [options] installer 22:50:02 INFO - Options: 22:50:02 INFO - -h, --help show this help message and exit 22:50:02 INFO - -d DEST, --destination=DEST 22:50:02 INFO - Directory to install application into. [default: 22:50:02 INFO - "/builds/slave/test-pgo"] 22:50:02 INFO - --app=APP Application being installed. [default: firefox] 22:50:02 INFO - mkdir: /builds/slave/test-pgo/build/application 22:50:02 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'] 22:50:02 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 22:50:17 INFO - Reading from file tmpfile_stdout 22:50:17 INFO - Output received: 22:50:17 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 22:50:17 INFO - Running post-action listener: _resource_record_post_action 22:50:17 INFO - ##### 22:50:17 INFO - ##### Running run-tests step. 22:50:17 INFO - ##### 22:50:17 INFO - Running pre-action listener: _resource_record_pre_action 22:50:17 INFO - Running main action method: run_tests 22:50:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 22:50:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 22:50:17 INFO - Python 2.7.3 22:50:17 INFO - Return code: 0 22:50:17 INFO - grabbing minidump binary from tooltool 22:50:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:50:17 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 0x2327840>, '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 0x22946b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2294b60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 22:50:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 22:50:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:50:17 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:50:17 INFO - Return code: 0 22:50:17 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 22:50:17 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 22:50:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 22:50:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 22:50:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 22:50:17 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 22:50:17 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 22:50:18 INFO - Return code: 0 22:50:18 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test-pgo/build 22:50:18 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log 22:50:18 INFO - Using env: {'DISPLAY': ':0', 22:50:18 INFO - 'HOME': '/home/cltbld', 22:50:18 INFO - 'LANG': 'en_US.UTF-8', 22:50:18 INFO - 'LANGUAGE': 'en_US:en', 22:50:18 INFO - 'LOGNAME': 'cltbld', 22:50:18 INFO - 'MAIL': '/var/mail/cltbld', 22:50:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:50:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 22:50:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:50:18 INFO - 'MOZ_NO_REMOTE': '1', 22:50:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 22:50:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:50:18 INFO - 'NO_EM_RESTART': '1', 22:50:18 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:50:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:50:18 INFO - 'PWD': '/builds/slave/test-pgo', 22:50:18 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 22:50:18 INFO - 'SHELL': '/bin/bash', 22:50:18 INFO - 'SHLVL': '1', 22:50:18 INFO - 'TERM': 'linux', 22:50:18 INFO - 'TMOUT': '86400', 22:50:18 INFO - 'USER': 'cltbld', 22:50:18 INFO - 'XDG_SESSION_COOKIE': '0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275', 22:50:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:50:18 INFO - '_': '/tools/buildbot/bin/python'} 22:50:18 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:50:19 INFO - mozversion application_buildid: 20151222203249 22:50:19 INFO - mozversion application_changeset: dd26de01e3d255d6f62e679a58a0113ac1128183 22:50:19 INFO - mozversion application_display_name: Nightly 22:50:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:50:19 INFO - mozversion application_name: Firefox 22:50:19 INFO - mozversion application_remotingname: firefox 22:50:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:50:19 INFO - mozversion application_vendor: Mozilla 22:50:19 INFO - mozversion application_version: 46.0a1 22:50:19 INFO - mozversion platform_buildid: 20151222203249 22:50:19 INFO - mozversion platform_changeset: dd26de01e3d255d6f62e679a58a0113ac1128183 22:50:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:50:19 INFO - mozversion platform_version: 46.0a1 22:50:19 INFO - using testdate: 1450853419 22:50:19 INFO - actual date: 1450853419 22:50:19 INFO - starting webserver on 'localhost:47716' 22:50:19 INFO - SUITE-START | Running 3 tests 22:50:19 INFO - TEST-START | dromaeo_css 22:50:19 INFO - operating with platform_type : linux_ 22:50:19 INFO - Initialising browser for dromaeo_css test... 22:50:19 INFO - TEST-INFO | started process 10182 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp30nQgO/profile http://localhost:47716/getInfo.html) 22:50:22 INFO - PROCESS | 10182 | __metrics Screen width/height:1600/1200 22:50:22 INFO - PROCESS | 10182 | colorDepth:24 22:50:22 INFO - PROCESS | 10182 | Browser inner width/height: 1024/697 22:50:22 INFO - PROCESS | 10182 | __metrics 22:50:23 INFO - PROCESS | 10182 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 22:50:23 INFO - PROCESS | 10182 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:50:24 INFO - TEST-INFO | 10182: exit 0 22:50:24 INFO - Browser initialized. 22:50:24 INFO - Running cycle 1/1 for dromaeo_css test... 22:50:24 INFO - TEST-INFO | started process 10272 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmp30nQgO/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:50:36 INFO - PROCESS | 10272 | RSS: Main: 150130688 22:50:36 INFO - PROCESS | 10272 | 22:51:27 INFO - PROCESS | 10272 | 1450853487341 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:53:35 INFO - PROCESS | 10272 | Cycle 1(1): loaded http://localhost:47716/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47716/tests/dromaeo/cssquery-ext.html) 22:53:36 INFO - PROCESS | 10272 | RSS: Main: 241201152 22:53:36 INFO - PROCESS | 10272 | 22:56:39 INFO - PROCESS | 10272 | Cycle 1(1): loaded http://localhost:47716/tests/dromaeo/cssquery-ext.html (next: http://localhost:47716/tests/dromaeo/cssquery-jquery.html) 22:56:39 INFO - PROCESS | 10272 | RSS: Main: 234786816 22:56:39 INFO - PROCESS | 10272 | 22:59:41 INFO - PROCESS | 10272 | Cycle 1(1): loaded http://localhost:47716/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47716/tests/dromaeo/cssquery-mootools.html) 22:59:42 INFO - PROCESS | 10272 | RSS: Main: 221876224 22:59:42 INFO - PROCESS | 10272 | 23:02:44 INFO - PROCESS | 10272 | Cycle 1(1): loaded http://localhost:47716/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47716/tests/dromaeo/cssquery-prototype.html) 23:02:44 INFO - PROCESS | 10272 | RSS: Main: 213413888 23:02:44 INFO - PROCESS | 10272 | 23:05:47 INFO - PROCESS | 10272 | Cycle 1(1): loaded http://localhost:47716/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47716/tests/dromaeo/cssquery-yui.html) 23:05:47 INFO - PROCESS | 10272 | RSS: Main: 216551424 23:05:47 INFO - PROCESS | 10272 | 23:08:50 INFO - PROCESS | 10272 | Cycle 1(1): loaded http://localhost:47716/tests/dromaeo/cssquery-yui.html (next: http://localhost:47716/tests/dromaeo/cssquery-dojo.html) 23:08:50 INFO - PROCESS | 10272 | RSS: Main: 292339712 23:08:50 INFO - PROCESS | 10272 | 23:08:50 INFO - PROCESS | 10272 | __start_tp_report 23:08:50 INFO - PROCESS | 10272 | _x_x_mozilla_page_load 23:08:50 INFO - PROCESS | 10272 | _x_x_mozilla_page_load_details 23:08:50 INFO - PROCESS | 10272 | |i|pagename|runs| 23:08:50 INFO - PROCESS | 10272 | |0;dojo.html;3073.877044918203;3083.5066389377703;3159.2891599390127;3163.6836316368367;3176;4944.826931057413;5062.848114556569;5148.202028685555;5257.185136303874;5290.206469029643;5108.540231379176;5127.743612819363;5148.124818780807;5161.122609156443;5218.686878787266;4583.679142460012;4715.575598196162;4734.739589322595;4774.40319960005;4926.187179115442;4871.2449570316485;4881.682690625098;4926.5812405945535;4927.384076990376;4942.382202224722;4622.676412651116;4684.203685373494;4705.035467729107;4781.6174706023785;4820.0841840050625;4778.474367819537;4789.2097803862325;4790.161721698723;4793.209120495114;4822.035592881402;4725.621950243973;4760.476347601761;4764.213904705754;4795.088933102699;4829.106615276185;4922.753862306906;5021.39743230811;5021.698698078127;5024.070546948826;5034.270030845507;5505.889882202374;5581.748821303051;5599;5622.634528755618;5635.8591035224035;5666.065099258617;5672.290963629546;5685.346185188673;5686.715664216814;5687.118496633028;5292.364916210041;5293.602979776532;5296.470352964711;5296.894062118736;5298.046351656701;5523.558115350762;5528.917066244009;5590.105583106683;5600.047991841316;5761.711914404263;5559.694216818114;5584.888302233932;5597.972010139923;5600.467955544217;5601.383847776741;5602.887942241214;5670.064439367458;5672.319321681424;5674.659520428788;5679.460451257207;5441.129419292894;5499.340079190442;5545.500904918576;5577.832665020056;5641.041023026095;5629.493345598834;5636.351819540723;5639.069553523623;5639.746211420496;5646.604737668324;4387.648988080946;4389;4391.231534481453;4394.956050439519;4426.645868330525;3819.9809000955374;3831.4635950966886;3894.3963685628773;3903.2974064668065;3933.272344616255;5337.359516858002;5356.437574054746;5374.785008599612;5376.086065368897;5427.48438898304;5387.380451248078;5672.773089076473;5676.545876329884;5679.744411501492;5680.318361796528;5223.791048358099;5236.1098613235845;5246.055709972242;5346.599005074635;5363.249145119687;4943.728094954812;4944.629152813553;4946.307516947631;4950;4986.202207646832;5762.193292938908;5768.101809932269;5769.249997500298;5774.191613174159;5828.766849326064;5372.5970552207955;5474.042042642602;5480.451954804487;5604.775808967595;5670;5644.491995720405;5706.058500347563;5710.65736055838;5751.367349591458;5756.165356023437;5530.91703624454;5535.529479994246;5545.667259964415;5548.361938377138;5548.833534993957;5464.071107911744;5519.061759500814;5563.8609034772835;5621.353544342453;5622.18478320633;5385.142125966535;5440.972795135999;5449.2371068049715;5464.972675136758;5486.670799752028;5501.119820828648;5509.834704958857;5518.42056584069;5521.503064724194;5525;5216.217567364925;5321.813736519203;5433.049216387196;5439.0753571893665;5491.972540137273;5401.1088170453;5460.153676180198;5461.044317244387;5514.724263786875;5521.199426083276;5341.572674186134;5380;5387.838364849061;5391.406945235943;5397.163439666858 23:08:50 INFO - PROCESS | 10272 | |1;ext.html;561.5647872898503;586.3169407640099;601.3925934805847;602.5450784657583;613.8189234175918;29602.555961660564;30290.39419211617;30372.69627303726;37612;38094.23811523768;42196;42533.7873310633;42815.785921070354;42820.785896070556;42837.571624283744;8617.224449799502;8773.912260877374;8780.429272097324;8795.736127916141;8804.64781408743;7790.104138024115;7977.720779772709;10646.25476216665;10864.891351086468;10889.945550272238;10481.790364192711;10571.629992950247;10581.047705706485;10582.735431614232;10592.046715795577;7807.180246074137;8004.319632831216;10437.791244175112;10544;10545;10613;10940.179486538462;10946.179036572288;10949.343039417661;10951.288166269167;39407.21185576301;39871.8006409969;39917;39922.60077399218;39972.20055598872;3421.469672200813;3506.8071256080907;3582.3372676055014;3609.043603445089;4061.025353915039;29304.8534757327;29745.40509189804;30672.53991190134;30873.228169295722;31229.843850780828;3968.761874287552;4037.9097643636346;4043.716939814214;4128.649064829493;4200.3699445083175;256870;257933;258014;259593;259654;53370.73314633402;54072.45927540753;64459.67770161213;66349;66645;59560.40439595635;59941;60175.38947663154;60190.39809601936;60208;15995.360185592679;16048.679026419406;16098.919505402633;16190;16206;12262;12301.200421972542;12369.196002259825;12371.443285052193;12409.813852792215;20272;20492.18031278762;20585.279515216895;20610.79389206119;20632.381028569165;7739.496932699357;7877.645505952246;7913.129555748863;8090.110087890327;8102.837943241102;891.5453118909273;1018.4296793795498;1036.259074761549;1051.4007016000805;1057.7302787789065;28302.433951320858;28390.85804570964;28727.425451490853;28738.281542961176;28763.42473150567;1033.0650761061252;1033.6382266206917;1042.6038105519854;1084.013547671615;1128.4245035032193;14311.713765724628;14412.495562655256;14452.494162704255;14526.491572795112;14644.487442939659;347422;355146;355869;356337;357447;52386;53133;53135;53279;53426.198607020924;1313.0546006875036;1320.2474589483902;1383.391307824554;1386.0297791545959;1462.8829693624698;26536.867315663298;27306.45387092287;27689.58465622977;27690.723092768814;27704.307392315353;42658;43106.13787724291;43179;43557.91105222395;43720.78139609409;283984;290490;291264;292284;293237;29802.403951921275;30255.243618909702;30267;30271.091867244017;30286;38464;38483.230335393695;38941;39153.41269881011;39848.80075599603;33980;34350.65649343475;34364.48453273253;37199;40317.59682403138;35249.59001639906;36399.81800090983;40416.1916761669;41465;41847.58152418437;35986;36224.818875905454;36260.81869590635;40664.39003414892;41946.790266048476;31894.68105318917;33277.50083748697;33287;33746;40557;29980;30704.53943190809;31209.219769505944;31870.36259274755;34199.31601367909 23:08:50 INFO - PROCESS | 10272 | |2;jquery.html;361.47946956382805;365.24212259561415;365.89206184175663;367.9668829805317;370.79791513625105;13364.799528007088;13384.390043941436;15964.680706385865;16425.50723478296;16881.662366752673;12876;13252.734945301088;15336.693266134671;15653.686926261467;16455.177241137953;5206.661566998143;5327.813526526573;5328.440513746059;5330.573554115662;5333.893322133575;3802.372608519591;3947.8420863165584;5149.665271757342;5235.293235413227;5309.123994540915;3929.2141571685634;5933;6100.542459315547;6128.877422451548;6141.4165654263;5923.348431672491;5984.8503787405225;5989.161517387569;6041.486473649723;6090.66501342426;5266.499682530153;5285.471452854722;5288.92066619001;5294.49702278283;5297.231901374318;20460.69308960367;20486.078126484193;20938.89530552353;20943;21019.579608407745;2138.2088627207877;2169.934901952944;2201.086549082129;2210.458437682762;2585.767280944724;12697.936510317482;13046.217226966317;13055;13266;13362.265075420848;1695.203254470397;1747.405882000126;1769.4426255729404;1815.936442224529;1913.9904300478547;55733;57146;57780.71109644467;59441.40558594402;60854.695726521524;9299.070092990782;9497.477638729799;9739.123478886933;9776.951115244521;9853.507324633883;12819.743605127846;12821.1025228235;12828.743425131444;12831.743365132832;12836.679083022813;6976.441884649216;7017.859642807115;7557.697692092364;7623.885641715378;7650.349720273728;7537;7560.3573696235335;7581.12817026038;7638.923610763822;7655.808604784925;5797.797077102281;5855.531557475391;5857.560682948796;5877.853053673693;5917.408259174134;1642.7782249396419;1735.2191513818832;1752.088913764835;1779.1904683369037;1788.9016104114398;442.9844955426545;448.49544262704455;454.7703409778041;472.6203565049957;781.2187812187813;10811.621593244356;10920.617778377718;11011.889881101086;11052.557897684002;11343.376114313793;408.7159424200212;447.89698369375225;477.7229207059897;488.2164126576864;489.62788280906767;5310.044192045469;5447.291852059207;5498.642588231752;5530.115181570929;5554.1391084381985;506490;512499;516578;529075;541927;75943.34198303144;80878.19121808825;81214.59392703116;83286;83965.44311012038;1104.216006635282;1109.8501702270091;1127.1208457403238;1144.456383217965;1145.3471521232984;14522.782158267422;15313.540593782205;15431.228438578251;15577;15875.920620396824;33306;33359;34560.82719586386;36531.26937461289;37331.6266837339;42605.57394426016;44196;45144.77427612841;45206.32190517211;45595.08809823719;9293.30300227472;9597.952010239904;9640.180584650383;9647.179989700953;9647.951760241154;17005.0622178999;17018.404355847484;17043.31826726917;17137.828621713623;17487.54067712725;11676.94161529221;12910.22538647684;13640.727185456433;13859.584212473643;14282.42870285177;13309.401076951366;13381.53164639233;13680.794788078387;13949.232792196526;14067.226302553048;13545.458181672622;13612;14363.640908977359;14514.274286285854;14628.561143165722;9004.594793234452;9125.183271660828;9428.622855085994;9463.810723785347;9491.905080949102;10117.645882394081;10373.58505659765;13050.869491304966;14004.789928150882;14025.929870350583 23:08:50 INFO - PROCESS | 10272 | |3;mootools.html;995.6017592962819;1483.2583708145928;1533.5782659768563;1614.6609212065466;1704.0457343887417;6415.294317625057;7254.709811607536;7362.815929601763;7377.963110184455;7418.480706350558;7144.285571442853;7262.52793568419;7295.8540829183385;7300.926990730091;7306.269373062691;1964.2928545723528;1997.710332001859;2252.6170551006285;2255.9436014099615;2284.565932472833;2236.9776302236933;2302.838801283911;2307.630779075348;2309.099451214028;2310.1336998625516;2255.560165767676;2302.5049614332916;2304.6888670029584;2306.6540018997116;2311.2141871763597;2252.954940901181;2257;2293.850899691523;2303.1593468384085;2304.135949019118;2284.383216531544;2286.0512887151813;2312.7571604981404;2315.8842057897205;2316.2356422380744;7270.454715896329;7359.227281135457;7366.889496657554;7370.078740157481;7380;4560.543945605446;4614.123316569841;4617.284320930261;4623.029163875556;4638.281066434674;2928.282570770175;2936.207224049516;2956.689547597493;2967.5697023931625;3045.5279431688123;958.4488918871676;970.9563069661882;988.402016779845;994.7712026233934;999.5252255178805;9928.652497162637;10017.449040302781;10043.096121349114;10056;10057.69826905194;4087.6116768906904;4111.650509706708;4139.544650088488;4177.791110444466;4192.035831758712;8964.103589640983;8968.968568614562;8980.236679882153;8982.640694372283;8983.55082245885;4492.9550704493195;4553.317002449593;4555.63554915606;4555.863324100283;4577.633789296849;4095.1809638072505;4096.446979657708;4105.58944105587;4123.876283711494;4130.91738165235;3322.1362445764275;3340.599128104643;3395.4567269236804;3450.051235910145;3538.398472259722;1185.887340702632;1186.6440067979572;1193.731410432663;1197.233770386953;1207.2635692227734;1071.3893080943787;1071.6945670483874;1110.6612483192541;1203.807390817465;1307.7057662025968;6725.192976842713;7017.508774385744;7071.398931090915;7082.327178917994;7093.361597456254;1097.6542389147553;1104.6244276945715;1109.2124591540096;1122.8428020077197;1147.162571322939;5589.720513974285;5632.549396048306;5644.520215781622;5645.604807663506;5647.435256474319;8472.872906906401;8920.598573064228;8947.776305592412;9038.638454461749;9043.276537876955;8884.37809353352;8895.199432051146;8906.153915378029;8912.376133670581;8926.910730892738;6773.288804675537;6952.270011648805;7006.0892084029765;7032.437405007587;7032.789016329519;6774.119364482585;6824.351686589766;6825.35159159879;6825.55633883796;6826.624535650587;7754.106520657043;8050.114487406264;8066.1127275998415;8068.193180681885;8084;8105.108438071925;8140.796480088045;8311.210435008665;8323.667053318042;8331.041930178108;5869.266341707286;5930.347661757117;6668.233153187445;6813.454923606;6827;6256.249250089836;6383.7127329271225;6384.712687929147;6388.425041746265;6930.757423490154;6422.967885160731;6439.291677915332;6767.458603311822;6816.863662726619;6873;6562.934370656423;6677.499187560855;6739.359760822714;6769.695363708744;6771;6661.300563440769;6709.664516774239;6711.530192886451;6723.865522689616;6725.092112564741;6192.473639740672;6209.348018458178;6224.595401298901;6238.563300568917;6243.094751261133;6051.273847138372;6053.485453736482;6093.817185484442;6130.846728831815;6140.631562106288 23:08:50 INFO - PROCESS | 10272 | |4;prototype.html;339.4263694356539;339.4873740651616;340.67976102463683;343.5979903512891;348.61478066736265;5836.387179346171;5863.325717542484;5866.325372582154;5873.941260587393;5952;5820.679862607555;5821.388754180803;5827.562932780037;5833.154192642064;5833.82498525045;4547.909041819178;4555.612772914306;4555.931661025087;4558.475775285843;4561.452625684922;4400.44994375703;4519.27691569349;4551.795169217393;4551.840685576006;4554.339620754989;4467.195904737146;4478.462584489866;4482.753448560328;4493.438320209973;4493.887652808673;4305.117450922569;4371.803268852909;4417.072414792865;4498.617617502517;4507.504174540798;3901.941470877939;3929.469521614574;3937.8424863005444;3937.862174823896;3953.367461206193;5549.639273447254;5558.777648894039;5567.220589117527;5579;5587.888242235133;4925.802967881281;4985.651004429691;4986.077575648516;5132.717700526469;5234.816781412632;6150.384961503813;6192.969035154795;6214.968925155391;6216.471599913967;6219.502439804804;4348.91302173958;4352.978235108836;4353.455818022747;4357.455318085239;4359.542248063939;6348.682565871734;6428;6503.772367967145;6503.9593665013845;6513.348665133449;4422.911541769146;4446.221911165533;4453.75504347264;4454.198244316054;4456.264716321835;4509.323601459807;4510.706804057726;4512.165249428866;4515.345274935182;4517.864464066083;5183;5193.454687257859;5196.194589838581;5202.375714914158;5205.973970130125;6131.754729810847;6261.686915654199;6265.65538895356;6268.623882567061;6277.372262773777;1581.644130070725;1599.3682495414246;1599.5681166085092;1599.9520314194222;1603.158341870504;1074.7796701676139;1082.6589624268488;1083.9620613278498;1098.8846171152074;1100.4112799652307;6046.758129674852;6051.939480605138;6052.848678783065;6057.818265452043;6065.787697430569;6419.871602567922;6441.066045423388;6455.096286519891;6472.935270647327;6473.06140609609;5913.733881975321;6047.425494578008;6049.455549000523;6052.697365131726;6056.848578785565;3480.91297717554;3499.2826470573473;3500.8599656013985;3505.5968563615;3506.649335066524;6585.341465853377;6593.340665933369;6594.835129121714;6597.1423714916755;6600.669966501751;6397.392247736458;6592.110065141144;6597.076409302702;6599.934000660028;6615.669216539154;6453.354664533603;6644.8671026579195;6654.70053847578;6673.866522669519;6676.432503237279;6494.220693516808;6497.545171837926;6498.187726534184;6500.577462464925;6503.219613646393;6312.715927783169;6424.614523128627;6426.710798014007;6444.742210311535;6449.6130232186215;6648.069270302259;6653.534252602271;6655.3344665535;6655.500837437115;6661.3338666132995;4434.091011342732;4449.28811390176;4455.75493347869;4457.576530229623;4459.732416055047;6376.617402955838;6501.114676743691;6503.406665367015;6508.414242718178;6511.316311787384;6309.148264984168;6316.11574379578;6316.231455192554;6318.842028949313;6323.588966717149;6271.43557079865;6294.276158241861;6302.905456418066;6303.086052522451;6321.936780632135;6073.514118870391;6195.876082478415;6196;6207.224096987877;6210.223722034746;6334.556581039467;6343.207099112611;6348.77779277723;6351.93648063532;6359.173307470184;6193.721282542207;6200.7519699211525;6207.503399728102;6207.720652570555;6211.689415529296 23:08:50 INFO - PROCESS | 10272 | |5;yui.html;693.452172783501;730.375528922771;766.375404045703;768.8846672999051;798.4849771897126;12941.2235265884;13266.535671251508;13290.601281961532;13310.068295219326;13319.467221311148;12779.936100319486;13402.128861623987;13426.66433339167;13437.529686460975;13441;689.4208864553774;696.919854216764;816.963236654349;831.2269589281965;845.8223773007676;846.699421705295;853.2534032721369;856.6616186606287;858.9226969572737;859.3383095016833;819.4304958054155;875.4090988582712;888.7067267801642;889.0709208876707;889.3774357949429;711.0187940641907;719.4999475364624;866.5017614871412;880.2913654508119;882.161946151159;649.7401039584204;649.9902501462482;650.5413683353224;651.2478087808589;652.4682383857141;13116.86883131166;13146.737065258641;13157.60527184186;13209.669758256025;13248.205107693473;11314.773704525946;11946.940265298705;12168.756624867541;12177.33024683642;12246.387680616022;9939.204863610894;9948.950255248677;10000.949710338344;10054.899451005469;10085.142762865165;3485.8779942702135;3522.137076416269;3552.1297282166033;3554.3246783111126;3555.839987200556;47043.294350584765;48412;48465.27302090436;48499.272510912015;48539.51460485421;10136.341137826017;10156.949215253877;10235.283530152818;10241.283110182216;10372.844407333896;16861.578460538585;16923.91538042302;16947.91526042362;16948;16955.745663815054;7393.112826460859;7411.332980031823;7466.738664146837;7571.129320128146;7601.733939312097;4386.254336762758;4388.539203383599;4410.977945110254;4440.000999775089;4494.348319493655;2865.469888070672;2886.076455534209;2886.437144756794;2888.595596616475;2892.450434417454;537.5726297593344;538.37548443805;539.8484271723687;543.4836904940322;558.7122631844603;585.2216551985848;608.385530614083;626.9216347956506;630.7035693224177;634.4765568406084;8556.443831150955;8559.614817333111;8681.2186903179;8786.209241168326;8834.072422395684;497.1920628977911;608.5831205624073;617.3301967365369;622.4926684751913;662.2979641579941;3842.7310088293552;4103.876883693494;4136.131412403369;4156.43888075112;4170.4995400552125;58101.4189858096;58350.7082464585;58427.70786146042;59720.701396493605;59732.40267597355;51700;51778.74110629423;51787;51804;51985;47248.52751472441;47344;47390;47469.28796068062;47553;8188.959055204686;8243.670253189804;8254.417941199863;8276.172382761917;8348.605577689243;35219.823900881354;35369.82315088408;35633.82183089068;35665.46501802527;36118.27763444664;41601;43548.129037419705;46170;46298.30552541647;46646.76676616595;9534.570944307661;9549.952250238704;9644.614215431304;10760.730981725519;11144.721381965192;11167.72080697989;11416.200865939305;11480.885191148316;11534.250273732516;11801.232919860526;10602.416867072385;10918.563257469612;10993.450327483433;11282.214003113344;11355;10243.38539687621;10383.428911409945;10402.063814256753;10417.322874013165;10622.946885265525;10258.384496930208;10474.842877356694;10518.421486817992;10717.35695858251;11007.119430445706;8872.116064404827;8925.954701468338;9266.166045055978;9418.434893906113;9444.622215111318;8666.913330866611;8718.17177368149;8735.694250701195;8921.197092261727;9388.155066044088 23:08:50 INFO - PROCESS | 10272 | __end_tp_report 23:08:50 INFO - PROCESS | 10272 | __start_cc_report 23:08:50 INFO - PROCESS | 10272 | _x_x_mozilla_cycle_collect,769 23:08:50 INFO - PROCESS | 10272 | __end_cc_report 23:08:50 INFO - PROCESS | 10272 | __startTimestamp1450854530610__endTimestamp 23:08:50 INFO - PROCESS | 10272 | 23:08:50 INFO - PROCESS | 10272 | ------- Summary: start ------- 23:08:50 INFO - PROCESS | 10272 | Number of tests: 6 23:08:50 INFO - PROCESS | 10272 | 23:08:50 INFO - PROCESS | 10272 | [#0] dojo.html Cycles:165 Average:5205.57 Median:5391.41 stddev:563.87 (10.5%) stddev-sans-first:540.23 23:08:50 INFO - PROCESS | 10272 | Values: 3073.9 3083.5 3159.3 3163.7 3176.0 4944.8 5062.8 5148.2 5257.2 5290.2 5108.5 5127.7 5148.1 5161.1 5218.7 4583.7 4715.6 4734.7 4774.4 4926.2 4871.2 4881.7 4926.6 4927.4 4942.4 4622.7 4684.2 4705.0 4781.6 4820.1 4778.5 4789.2 4790.2 4793.2 4822.0 4725.6 4760.5 4764.2 4795.1 4829.1 4922.8 5021.4 5021.7 5024.1 5034.3 5505.9 5581.7 5599.0 5622.6 5635.9 5666.1 5672.3 5685.3 5686.7 5687.1 5292.4 5293.6 5296.5 5296.9 5298.0 5523.6 5528.9 5590.1 5600.0 5761.7 5559.7 5584.9 5598.0 5600.5 5601.4 5602.9 5670.1 5672.3 5674.7 5679.5 5441.1 5499.3 5545.5 5577.8 5641.0 5629.5 5636.4 5639.1 5639.7 5646.6 4387.6 4389.0 4391.2 4395.0 4426.6 3820.0 3831.5 3894.4 3903.3 3933.3 5337.4 5356.4 5374.8 5376.1 5427.5 5387.4 5672.8 5676.5 5679.7 5680.3 5223.8 5236.1 5246.1 5346.6 5363.2 4943.7 4944.6 4946.3 4950.0 4986.2 5762.2 5768.1 5769.2 5774.2 5828.8 5372.6 5474.0 5480.5 5604.8 5670.0 5644.5 5706.1 5710.7 5751.4 5756.2 5530.9 5535.5 5545.7 5548.4 5548.8 5464.1 5519.1 5563.9 5621.4 5622.2 5385.1 5441.0 5449.2 5465.0 5486.7 5501.1 5509.8 5518.4 5521.5 5525.0 5216.2 5321.8 5433.0 5439.1 5492.0 5401.1 5460.2 5461.0 5514.7 5521.2 5341.6 5380.0 5387.8 5391.4 5397.2 23:08:50 INFO - PROCESS | 10272 | 23:08:50 INFO - PROCESS | 10272 | [#1] ext.html Cycles:180 Average:47318.80 Median:33863.00 stddev:79400.28 (234.5%) stddev-sans-first:79545.41 23:08:50 INFO - PROCESS | 10272 | Values: 561.6 586.3 601.4 602.5 613.8 29602.6 30290.4 30372.7 37612.0 38094.2 42196.0 42533.8 42815.8 42820.8 42837.6 8617.2 8773.9 8780.4 8795.7 8804.6 7790.1 7977.7 10646.3 10864.9 10889.9 10481.8 10571.6 10581.0 10582.7 10592.0 7807.2 8004.3 10437.8 10544.0 10545.0 10613.0 10940.2 10946.2 10949.3 10951.3 39407.2 39871.8 39917.0 39922.6 39972.2 3421.5 3506.8 3582.3 3609.0 4061.0 29304.9 29745.4 30672.5 30873.2 31229.8 3968.8 4037.9 4043.7 4128.6 4200.4 256870.0 257933.0 258014.0 259593.0 259654.0 53370.7 54072.5 64459.7 66349.0 66645.0 59560.4 59941.0 60175.4 60190.4 60208.0 15995.4 16048.7 16098.9 16190.0 16206.0 12262.0 12301.2 12369.2 12371.4 12409.8 20272.0 20492.2 20585.3 20610.8 20632.4 7739.5 7877.6 7913.1 8090.1 8102.8 891.5 1018.4 1036.3 1051.4 1057.7 28302.4 28390.9 28727.4 28738.3 28763.4 1033.1 1033.6 1042.6 1084.0 1128.4 14311.7 14412.5 14452.5 14526.5 14644.5 347422.0 355146.0 355869.0 356337.0 357447.0 52386.0 53133.0 53135.0 53279.0 53426.2 1313.1 1320.2 1383.4 1386.0 1462.9 26536.9 27306.5 27689.6 27690.7 27704.3 42658.0 43106.1 43179.0 43557.9 43720.8 283984.0 290490.0 291264.0 292284.0 293237.0 29802.4 30255.2 30267.0 30271.1 30286.0 38464.0 38483.2 38941.0 39153.4 39848.8 33980.0 34350.7 34364.5 37199.0 40317.6 35249.6 36399.8 40416.2 41465.0 41847.6 35986.0 36224.8 36260.8 40664.4 41946.8 31894.7 33277.5 33287.0 33746.0 40557.0 29980.0 30704.5 31209.2 31870.4 34199.3 23:08:50 INFO - PROCESS | 10272 | 23:08:50 INFO - PROCESS | 10272 | [#2] jquery.html Cycles:180 Average:27878.58 Median:3865.79 stddev:85274.54 (2205.9%) stddev-sans-first:85488.72 23:08:50 INFO - PROCESS | 10272 | Values: 361.5 365.2 365.9 368.0 370.8 13364.8 13384.4 15964.7 16425.5 16881.7 12876.0 13252.7 15336.7 15653.7 16455.2 5206.7 5327.8 5328.4 5330.6 5333.9 3802.4 3947.8 5149.7 5235.3 5309.1 3929.2 5933.0 6100.5 6128.9 6141.4 5923.3 5984.9 5989.2 6041.5 6090.7 5266.5 5285.5 5288.9 5294.5 5297.2 20460.7 20486.1 20938.9 20943.0 21019.6 2138.2 2169.9 2201.1 2210.5 2585.8 12697.9 13046.2 13055.0 13266.0 13362.3 1695.2 1747.4 1769.4 1815.9 1914.0 55733.0 57146.0 57780.7 59441.4 60854.7 9299.1 9497.5 9739.1 9777.0 9853.5 12819.7 12821.1 12828.7 12831.7 12836.7 6976.4 7017.9 7557.7 7623.9 7650.3 7537.0 7560.4 7581.1 7638.9 7655.8 5797.8 5855.5 5857.6 5877.9 5917.4 1642.8 1735.2 1752.1 1779.2 1788.9 443.0 448.5 454.8 472.6 781.2 10811.6 10920.6 11011.9 11052.6 11343.4 408.7 447.9 477.7 488.2 489.6 5310.0 5447.3 5498.6 5530.1 5554.1 506490.0 512499.0 516578.0 529075.0 541927.0 75943.3 80878.2 81214.6 83286.0 83965.4 1104.2 1109.9 1127.1 1144.5 1145.3 14522.8 15313.5 15431.2 15577.0 15875.9 33306.0 33359.0 34560.8 36531.3 37331.6 42605.6 44196.0 45144.8 45206.3 45595.1 9293.3 9598.0 9640.2 9647.2 9648.0 17005.1 17018.4 17043.3 17137.8 17487.5 11676.9 12910.2 13640.7 13859.6 14282.4 13309.4 13381.5 13680.8 13949.2 14067.2 13545.5 13612.0 14363.6 14514.3 14628.6 9004.6 9125.2 9428.6 9463.8 9491.9 10117.6 10373.6 13050.9 14004.8 14025.9 23:08:50 INFO - PROCESS | 10272 | 23:08:50 INFO - PROCESS | 10272 | [#3] mootools.html Cycles:180 Average:5208.86 Median:6200.91 stddev:2649.05 (42.7%) stddev-sans-first:2637.53 23:08:50 INFO - PROCESS | 10272 | Values: 995.6 1483.3 1533.6 1614.7 1704.0 6415.3 7254.7 7362.8 7378.0 7418.5 7144.3 7262.5 7295.9 7300.9 7306.3 1964.3 1997.7 2252.6 2255.9 2284.6 2237.0 2302.8 2307.6 2309.1 2310.1 2255.6 2302.5 2304.7 2306.7 2311.2 2253.0 2257.0 2293.9 2303.2 2304.1 2284.4 2286.1 2312.8 2315.9 2316.2 7270.5 7359.2 7366.9 7370.1 7380.0 4560.5 4614.1 4617.3 4623.0 4638.3 2928.3 2936.2 2956.7 2967.6 3045.5 958.4 971.0 988.4 994.8 999.5 9928.7 10017.4 10043.1 10056.0 10057.7 4087.6 4111.7 4139.5 4177.8 4192.0 8964.1 8969.0 8980.2 8982.6 8983.6 4493.0 4553.3 4555.6 4555.9 4577.6 4095.2 4096.4 4105.6 4123.9 4130.9 3322.1 3340.6 3395.5 3450.1 3538.4 1185.9 1186.6 1193.7 1197.2 1207.3 1071.4 1071.7 1110.7 1203.8 1307.7 6725.2 7017.5 7071.4 7082.3 7093.4 1097.7 1104.6 1109.2 1122.8 1147.2 5589.7 5632.5 5644.5 5645.6 5647.4 8472.9 8920.6 8947.8 9038.6 9043.3 8884.4 8895.2 8906.2 8912.4 8926.9 6773.3 6952.3 7006.1 7032.4 7032.8 6774.1 6824.4 6825.4 6825.6 6826.6 7754.1 8050.1 8066.1 8068.2 8084.0 8105.1 8140.8 8311.2 8323.7 8331.0 5869.3 5930.3 6668.2 6813.5 6827.0 6256.2 6383.7 6384.7 6388.4 6930.8 6423.0 6439.3 6767.5 6816.9 6873.0 6562.9 6677.5 6739.4 6769.7 6771.0 6661.3 6709.7 6711.5 6723.9 6725.1 6192.5 6209.3 6224.6 6238.6 6243.1 6051.3 6053.5 6093.8 6130.8 6140.6 23:08:50 INFO - PROCESS | 10272 | 23:08:50 INFO - PROCESS | 10272 | [#4] prototype.html Cycles:180 Average:5231.03 Median:5999.38 stddev:1571.82 (26.2%) stddev-sans-first:1532.75 23:08:50 INFO - PROCESS | 10272 | Values: 339.4 339.5 340.7 343.6 348.6 5836.4 5863.3 5866.3 5873.9 5952.0 5820.7 5821.4 5827.6 5833.2 5833.8 4547.9 4555.6 4555.9 4558.5 4561.5 4400.4 4519.3 4551.8 4551.8 4554.3 4467.2 4478.5 4482.8 4493.4 4493.9 4305.1 4371.8 4417.1 4498.6 4507.5 3901.9 3929.5 3937.8 3937.9 3953.4 5549.6 5558.8 5567.2 5579.0 5587.9 4925.8 4985.7 4986.1 5132.7 5234.8 6150.4 6193.0 6215.0 6216.5 6219.5 4348.9 4353.0 4353.5 4357.5 4359.5 6348.7 6428.0 6503.8 6504.0 6513.3 4422.9 4446.2 4453.8 4454.2 4456.3 4509.3 4510.7 4512.2 4515.3 4517.9 5183.0 5193.5 5196.2 5202.4 5206.0 6131.8 6261.7 6265.7 6268.6 6277.4 1581.6 1599.4 1599.6 1600.0 1603.2 1074.8 1082.7 1084.0 1098.9 1100.4 6046.8 6051.9 6052.8 6057.8 6065.8 6419.9 6441.1 6455.1 6472.9 6473.1 5913.7 6047.4 6049.5 6052.7 6056.8 3480.9 3499.3 3500.9 3505.6 3506.6 6585.3 6593.3 6594.8 6597.1 6600.7 6397.4 6592.1 6597.1 6599.9 6615.7 6453.4 6644.9 6654.7 6673.9 6676.4 6494.2 6497.5 6498.2 6500.6 6503.2 6312.7 6424.6 6426.7 6444.7 6449.6 6648.1 6653.5 6655.3 6655.5 6661.3 4434.1 4449.3 4455.8 4457.6 4459.7 6376.6 6501.1 6503.4 6508.4 6511.3 6309.1 6316.1 6316.2 6318.8 6323.6 6271.4 6294.3 6302.9 6303.1 6321.9 6073.5 6195.9 6196.0 6207.2 6210.2 6334.6 6343.2 6348.8 6351.9 6359.2 6193.7 6200.8 6207.5 6207.7 6211.7 23:08:50 INFO - PROCESS | 10272 | 23:08:50 INFO - PROCESS | 10272 | [#5] yui.html Cycles:180 Average:13697.57 Median:47982.50 stddev:16213.64 (33.8%) stddev-sans-first:16229.72 23:08:50 INFO - PROCESS | 10272 | Values: 693.5 730.4 766.4 768.9 798.5 12941.2 13266.5 13290.6 13310.1 13319.5 12779.9 13402.1 13426.7 13437.5 13441.0 689.4 696.9 817.0 831.2 845.8 846.7 853.3 856.7 858.9 859.3 819.4 875.4 888.7 889.1 889.4 711.0 719.5 866.5 880.3 882.2 649.7 650.0 650.5 651.2 652.5 13116.9 13146.7 13157.6 13209.7 13248.2 11314.8 11946.9 12168.8 12177.3 12246.4 9939.2 9949.0 10000.9 10054.9 10085.1 3485.9 3522.1 3552.1 3554.3 3555.8 47043.3 48412.0 48465.3 48499.3 48539.5 10136.3 10156.9 10235.3 10241.3 10372.8 16861.6 16923.9 16947.9 16948.0 16955.7 7393.1 7411.3 7466.7 7571.1 7601.7 4386.3 4388.5 4411.0 4440.0 4494.3 2865.5 2886.1 2886.4 2888.6 2892.5 537.6 538.4 539.8 543.5 558.7 585.2 608.4 626.9 630.7 634.5 8556.4 8559.6 8681.2 8786.2 8834.1 497.2 608.6 617.3 622.5 662.3 3842.7 4103.9 4136.1 4156.4 4170.5 58101.4 58350.7 58427.7 59720.7 59732.4 51700.0 51778.7 51787.0 51804.0 51985.0 47248.5 47344.0 47390.0 47469.3 47553.0 8189.0 8243.7 8254.4 8276.2 8348.6 35219.8 35369.8 35633.8 35665.5 36118.3 41601.0 43548.1 46170.0 46298.3 46646.8 9534.6 9550.0 9644.6 10760.7 11144.7 11167.7 11416.2 11480.9 11534.3 11801.2 10602.4 10918.6 10993.5 11282.2 11355.0 10243.4 10383.4 10402.1 10417.3 10622.9 10258.4 10474.8 10518.4 10717.4 11007.1 8872.1 8926.0 9266.2 9418.4 9444.6 8666.9 8718.2 8735.7 8921.2 9388.2 23:08:50 INFO - PROCESS | 10272 | -------- Summary: end -------- 23:08:50 INFO - PROCESS | 10272 | 23:08:51 INFO - TEST-INFO | 10272: exit 0 23:08:51 INFO - TEST-OK | dromaeo_css | took 1112265ms 23:08:51 INFO - TEST-START | kraken 23:08:51 INFO - operating with platform_type : linux_ 23:08:51 INFO - Initialising browser for kraken test... 23:08:51 INFO - TEST-INFO | started process 10463 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphLQuUc/profile http://localhost:47716/getInfo.html) 23:08:55 INFO - PROCESS | 10463 | __metrics Screen width/height:1600/1200 23:08:55 INFO - PROCESS | 10463 | colorDepth:24 23:08:55 INFO - PROCESS | 10463 | Browser inner width/height: 1024/697 23:08:55 INFO - PROCESS | 10463 | __metrics 23:08:55 INFO - PROCESS | 10463 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 23:08:55 INFO - PROCESS | 10463 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:08:55 ERROR - PROCESS | 10463 | JavaScript error: chrome://browser/content/tab-content.js, line 408: TypeError: content is null 23:08:56 INFO - TEST-INFO | 10463: exit 0 23:08:56 INFO - Browser initialized. 23:08:56 INFO - Running cycle 1/1 for kraken test... 23:08:56 INFO - TEST-INFO | started process 10547 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmphLQuUc/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:09:07 INFO - PROCESS | 10547 | RSS: Main: 150302720 23:09:07 INFO - PROCESS | 10547 | 23:09:10 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47716/tests/kraken/driver.html?testName=audio-beat-detection) 23:09:11 INFO - PROCESS | 10547 | RSS: Main: 187043840 23:09:11 INFO - PROCESS | 10547 | 23:09:13 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47716/tests/kraken/driver.html?testName=audio-dft) 23:09:14 INFO - PROCESS | 10547 | RSS: Main: 176488448 23:09:14 INFO - PROCESS | 10547 | 23:09:17 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47716/tests/kraken/driver.html?testName=audio-fft) 23:09:17 INFO - PROCESS | 10547 | RSS: Main: 161939456 23:09:17 INFO - PROCESS | 10547 | 23:09:19 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47716/tests/kraken/driver.html?testName=audio-oscillator) 23:09:20 INFO - PROCESS | 10547 | RSS: Main: 164274176 23:09:20 INFO - PROCESS | 10547 | 23:09:22 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47716/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:09:23 INFO - PROCESS | 10547 | RSS: Main: 165462016 23:09:23 INFO - PROCESS | 10547 | 23:09:25 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47716/tests/kraken/driver.html?testName=imaging-darkroom) 23:09:26 INFO - PROCESS | 10547 | RSS: Main: 165068800 23:09:26 INFO - PROCESS | 10547 | 23:09:29 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47716/tests/kraken/driver.html?testName=imaging-desaturate) 23:09:30 INFO - PROCESS | 10547 | RSS: Main: 166830080 23:09:30 INFO - PROCESS | 10547 | 23:09:33 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47716/tests/kraken/driver.html?testName=json-parse-financial) 23:09:33 INFO - PROCESS | 10547 | RSS: Main: 165244928 23:09:33 INFO - PROCESS | 10547 | 23:09:35 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47716/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:09:35 INFO - PROCESS | 10547 | RSS: Main: 177160192 23:09:35 INFO - PROCESS | 10547 | 23:09:37 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47716/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:09:38 INFO - PROCESS | 10547 | RSS: Main: 173772800 23:09:38 INFO - PROCESS | 10547 | 23:09:40 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47716/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:09:40 INFO - PROCESS | 10547 | RSS: Main: 187150336 23:09:40 INFO - PROCESS | 10547 | 23:09:43 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47716/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:09:43 INFO - PROCESS | 10547 | RSS: Main: 196050944 23:09:43 INFO - PROCESS | 10547 | 23:09:46 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47716/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:09:46 INFO - PROCESS | 10547 | RSS: Main: 192974848 23:09:46 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | Cycle 1(1): loaded http://localhost:47716/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47716/tests/kraken/driver.html?testName=ai-astar) 23:09:48 INFO - PROCESS | 10547 | RSS: Main: 251043840 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | __start_tp_report 23:09:48 INFO - PROCESS | 10547 | _x_x_mozilla_page_load 23:09:48 INFO - PROCESS | 10547 | _x_x_mozilla_page_load_details 23:09:48 INFO - PROCESS | 10547 | |i|pagename|runs| 23:09:48 INFO - PROCESS | 10547 | |0;ai-astar;108;111;104;111;110;110;99;110;109;117 23:09:48 INFO - PROCESS | 10547 | |1;audio-beat-detection;155;123;152;167;129;143;148;126;161;123 23:09:48 INFO - PROCESS | 10547 | |2;audio-dft;159;160;164;160;162;161;160;162;162;160 23:09:48 INFO - PROCESS | 10547 | |3;audio-fft;71;71;84;94;71;72;77;105;95;72 23:09:48 INFO - PROCESS | 10547 | |4;audio-oscillator;94;93;146;92;93;93;133;93;94;132 23:09:48 INFO - PROCESS | 10547 | |5;imaging-gaussian-blur;110;109;109;109;109;109;108;109;109;110 23:09:48 INFO - PROCESS | 10547 | |6;imaging-darkroom;145;145;145;145;145;147;145;146;146;147 23:09:48 INFO - PROCESS | 10547 | |7;imaging-desaturate;95;96;96;95;96;95;95;95;95;95 23:09:48 INFO - PROCESS | 10547 | |8;json-parse-financial;60;58;60;57;57;58;58;58;58;57 23:09:48 INFO - PROCESS | 10547 | |9;json-stringify-tinderbox;67;67;67;68;67;75;67;67;68;66 23:09:48 INFO - PROCESS | 10547 | |10;stanford-crypto-aes;84;86;84;85;86;91;84;84;85;84 23:09:48 INFO - PROCESS | 10547 | |11;stanford-crypto-ccm;116;130;117;118;122;115;120;120;122;121 23:09:48 INFO - PROCESS | 10547 | |12;stanford-crypto-pbkdf2;141;151;150;152;150;138;150;150;149;139 23:09:48 INFO - PROCESS | 10547 | |13;stanford-crypto-sha256-iterative;68;66;69;67;67;67;68;66;65;66 23:09:48 INFO - PROCESS | 10547 | __end_tp_report 23:09:48 INFO - PROCESS | 10547 | __start_cc_report 23:09:48 INFO - PROCESS | 10547 | _x_x_mozilla_cycle_collect,918 23:09:48 INFO - PROCESS | 10547 | __end_cc_report 23:09:48 INFO - PROCESS | 10547 | __startTimestamp1450854588888__endTimestamp 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | ------- Summary: start ------- 23:09:48 INFO - PROCESS | 10547 | Number of tests: 14 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#0] ai-astar Cycles:10 Average:108.90 Median:110.50 stddev:4.72 (4.3%) stddev-sans-first:5.00 23:09:48 INFO - PROCESS | 10547 | Values: 108.0 111.0 104.0 111.0 110.0 110.0 99.0 110.0 109.0 117.0 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#1] audio-beat-detection Cycles:10 Average:142.70 Median:150.00 stddev:16.45 (11.0%) stddev-sans-first:16.83 23:09:48 INFO - PROCESS | 10547 | Values: 155.0 123.0 152.0 167.0 129.0 143.0 148.0 126.0 161.0 123.0 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.50 stddev:1.49 (0.9%) stddev-sans-first:1.39 23:09:48 INFO - PROCESS | 10547 | Values: 159.0 160.0 164.0 160.0 162.0 161.0 160.0 162.0 162.0 160.0 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#3] audio-fft Cycles:10 Average:81.20 Median:74.50 stddev:12.59 (16.9%) stddev-sans-first:12.81 23:09:48 INFO - PROCESS | 10547 | Values: 71.0 71.0 84.0 94.0 71.0 72.0 77.0 105.0 95.0 72.0 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#4] audio-oscillator Cycles:10 Average:106.30 Median:93.00 stddev:21.51 (23.1%) stddev-sans-first:22.35 23:09:48 INFO - PROCESS | 10547 | Values: 94.0 93.0 146.0 92.0 93.0 93.0 133.0 93.0 94.0 132.0 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.57 (0.5%) stddev-sans-first:0.50 23:09:48 INFO - PROCESS | 10547 | Values: 110.0 109.0 109.0 109.0 109.0 109.0 108.0 109.0 109.0 110.0 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:145.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 23:09:48 INFO - PROCESS | 10547 | Values: 145.0 145.0 145.0 145.0 145.0 147.0 145.0 146.0 146.0 147.0 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 23:09:48 INFO - PROCESS | 10547 | Values: 95.0 96.0 96.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#8] json-parse-financial Cycles:10 Average:58.10 Median:58.00 stddev:1.10 (1.9%) stddev-sans-first:0.93 23:09:48 INFO - PROCESS | 10547 | Values: 60.0 58.0 60.0 57.0 57.0 58.0 58.0 58.0 58.0 57.0 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:67.00 stddev:2.56 (3.8%) stddev-sans-first:2.69 23:09:48 INFO - PROCESS | 10547 | Values: 67.0 67.0 67.0 68.0 67.0 75.0 67.0 67.0 68.0 66.0 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.00 stddev:2.16 (2.5%) stddev-sans-first:2.24 23:09:48 INFO - PROCESS | 10547 | Values: 84.0 86.0 84.0 85.0 86.0 91.0 84.0 84.0 85.0 84.0 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#11] stanford-crypto-ccm Cycles:10 Average:120.10 Median:120.50 stddev:4.25 (3.5%) stddev-sans-first:4.25 23:09:48 INFO - PROCESS | 10547 | Values: 116.0 130.0 117.0 118.0 122.0 115.0 120.0 120.0 122.0 121.0 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:147.00 Median:150.00 stddev:5.40 (3.6%) stddev-sans-first:5.27 23:09:48 INFO - PROCESS | 10547 | Values: 141.0 151.0 150.0 152.0 150.0 138.0 150.0 150.0 149.0 139.0 23:09:48 INFO - PROCESS | 10547 | 23:09:48 INFO - PROCESS | 10547 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:1.20 (1.8%) stddev-sans-first:1.20 23:09:48 INFO - PROCESS | 10547 | Values: 68.0 66.0 69.0 67.0 67.0 67.0 68.0 66.0 65.0 66.0 23:09:48 INFO - PROCESS | 10547 | -------- Summary: end -------- 23:09:48 INFO - PROCESS | 10547 | 23:09:49 INFO - TEST-INFO | 10547: exit 0 23:09:49 INFO - TEST-OK | kraken | took 58471ms 23:09:49 INFO - TEST-START | v8_7 23:09:49 INFO - operating with platform_type : linux_ 23:09:49 INFO - Initialising browser for v8_7 test... 23:09:49 INFO - TEST-INFO | started process 10795 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkEZbCY/profile http://localhost:47716/getInfo.html) 23:09:53 INFO - PROCESS | 10795 | __metrics Screen width/height:1600/1200 23:09:53 INFO - PROCESS | 10795 | colorDepth:24 23:09:53 INFO - PROCESS | 10795 | Browser inner width/height: 1024/697 23:09:53 INFO - PROCESS | 10795 | __metrics 23:09:53 INFO - PROCESS | 10795 | JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 23:09:53 INFO - PROCESS | 10795 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:09:54 INFO - TEST-INFO | 10795: exit 0 23:09:54 INFO - Browser initialized. 23:09:54 INFO - Running cycle 1/1 for v8_7 test... 23:09:54 INFO - TEST-INFO | started process 10879 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpkEZbCY/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:10:06 INFO - PROCESS | 10879 | RSS: Main: 149577728 23:10:06 INFO - PROCESS | 10879 | 23:10:27 INFO - PROCESS | 10879 | Cycle 1(1): loaded http://localhost:47716/tests/v8_7/run.html (next: http://localhost:47716/tests/v8_7/run.html) 23:10:28 INFO - PROCESS | 10879 | RSS: Main: 575418368 23:10:28 INFO - PROCESS | 10879 | 23:10:28 INFO - PROCESS | 10879 | __start_tp_report 23:10:28 INFO - PROCESS | 10879 | _x_x_mozilla_page_load 23:10:28 INFO - PROCESS | 10879 | _x_x_mozilla_page_load_details 23:10:28 INFO - PROCESS | 10879 | |i|pagename|runs| 23:10:28 INFO - PROCESS | 10879 | |0;Richards;168.15200941651253 23:10:28 INFO - PROCESS | 10879 | |1;DeltaBlue;189.17896329928112 23:10:28 INFO - PROCESS | 10879 | |2;Encrypt;280.58361391694723 23:10:28 INFO - PROCESS | 10879 | |3;Decrypt;5208.333333333333 23:10:28 INFO - PROCESS | 10879 | |4;RayTrace;1943.7984496124031 23:10:28 INFO - PROCESS | 10879 | |5;Earley;763.539282990084 23:10:28 INFO - PROCESS | 10879 | |6;Boyer;12207.317073170732 23:10:28 INFO - PROCESS | 10879 | |7;RegExp;25200 23:10:28 INFO - PROCESS | 10879 | |8;Splay;619.8142414860681 23:10:28 INFO - PROCESS | 10879 | |9;NavierStokes;4826.923076923077 23:10:28 INFO - PROCESS | 10879 | __end_tp_report 23:10:28 INFO - PROCESS | 10879 | __startTimestamp1450854628182__endTimestamp 23:10:28 INFO - PROCESS | 10879 | 23:10:28 INFO - PROCESS | 10879 | ------- Summary: start ------- 23:10:28 INFO - PROCESS | 10879 | Number of tests: 10 23:10:28 INFO - PROCESS | 10879 | 23:10:28 INFO - PROCESS | 10879 | [#0] Richards Cycles:1 Average:168.15 Median:168.15 stddev:0.00 (0.0%) 23:10:28 INFO - PROCESS | 10879 | Values: 168.2 23:10:28 INFO - PROCESS | 10879 | 23:10:28 INFO - PROCESS | 10879 | [#1] DeltaBlue Cycles:1 Average:189.18 Median:189.18 stddev:0.00 (0.0%) 23:10:28 INFO - PROCESS | 10879 | Values: 189.2 23:10:28 INFO - PROCESS | 10879 | 23:10:28 INFO - PROCESS | 10879 | [#2] Encrypt Cycles:1 Average:280.58 Median:280.58 stddev:0.00 (0.0%) 23:10:28 INFO - PROCESS | 10879 | Values: 280.6 23:10:28 INFO - PROCESS | 10879 | 23:10:28 INFO - PROCESS | 10879 | [#3] Decrypt Cycles:1 Average:5208.33 Median:5208.33 stddev:0.00 (0.0%) 23:10:28 INFO - PROCESS | 10879 | Values: 5208.3 23:10:28 INFO - PROCESS | 10879 | 23:10:28 INFO - PROCESS | 10879 | [#4] RayTrace Cycles:1 Average:1943.80 Median:1943.80 stddev:0.00 (0.0%) 23:10:28 INFO - PROCESS | 10879 | Values: 1943.8 23:10:28 INFO - PROCESS | 10879 | 23:10:28 INFO - PROCESS | 10879 | [#5] Earley Cycles:1 Average:763.54 Median:763.54 stddev:0.00 (0.0%) 23:10:28 INFO - PROCESS | 10879 | Values: 763.5 23:10:28 INFO - PROCESS | 10879 | 23:10:28 INFO - PROCESS | 10879 | [#6] Boyer Cycles:1 Average:12207.32 Median:12207.32 stddev:0.00 (0.0%) 23:10:28 INFO - PROCESS | 10879 | Values: 12207.3 23:10:28 INFO - PROCESS | 10879 | 23:10:28 INFO - PROCESS | 10879 | [#7] RegExp Cycles:1 Average:25200.00 Median:25200.00 stddev:0.00 (0.0%) 23:10:28 INFO - PROCESS | 10879 | Values: 25200.0 23:10:28 INFO - PROCESS | 10879 | 23:10:28 INFO - PROCESS | 10879 | [#8] Splay Cycles:1 Average:619.81 Median:619.81 stddev:0.00 (0.0%) 23:10:28 INFO - PROCESS | 10879 | Values: 619.8 23:10:28 INFO - PROCESS | 10879 | 23:10:28 INFO - PROCESS | 10879 | [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 23:10:28 INFO - PROCESS | 10879 | Values: 4826.9 23:10:28 INFO - PROCESS | 10879 | -------- Summary: end -------- 23:10:28 INFO - PROCESS | 10879 | 23:10:29 INFO - TEST-INFO | 10879: exit 0 23:10:29 INFO - TEST-OK | v8_7 | took 39201ms 23:10:29 INFO - SUITE-END | took 1209s 23:10:29 INFO - Completed test suite (00:20:10) 23:10:29 INFO - Working with test: dromaeo_css 23:10:29 INFO - Generating results file: dromaeo_css 23:10:29 INFO - Working with test: kraken 23:10:29 INFO - Generating results file: kraken 23:10:29 INFO - javascript benchmark 23:10:29 INFO - Working with test: v8_7 23:10:29 INFO - Generating results file: v8_7 23:10:29 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:10:29 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 23:10:29 INFO - process_Request line: dromaeo_css 6848.43 graph.html#tests=[[72,64,35]] 23:10:29 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:10:29 INFO - process_Request line: kraken 1495.40 graph.html#tests=[[232,64,35]] 23:10:29 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:10:29 INFO - process_Request line: v8_7 19911.44 graph.html#tests=[[230,64,35]] 23:10:29 INFO - RETURN: dromaeo_css: 6848.43 23:10:29 INFO - RETURN: kraken: 1495.40 23:10:29 INFO - RETURN: v8_7: 19911.44 23:10:29 INFO - javascript benchmark 23:10:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3073.877044918203, 3083.5066389377703, 3159.2891599390127, 3163.6836316368367, 3176.0, 4944.826931057413, 5062.848114556569, 5148.202028685555, 5257.185136303874, 5290.206469029643, 5108.540231379176, 5127.743612819363, 5148.124818780807, 5161.122609156443, 5218.686878787266, 4583.679142460012, 4715.575598196162, 4734.739589322595, 4774.40319960005, 4926.187179115442, 4871.2449570316485, 4881.682690625098, 4926.5812405945535, 4927.384076990376, 4942.382202224722, 4622.676412651116, 4684.203685373494, 4705.035467729107, 4781.6174706023785, 4820.0841840050625, 4778.474367819537, 4789.2097803862325, 4790.161721698723, 4793.209120495114, 4822.035592881402, 4725.621950243973, 4760.476347601761, 4764.213904705754, 4795.088933102699, 4829.106615276185, 4922.753862306906, 5021.39743230811, 5021.698698078127, 5024.070546948826, 5034.270030845507, 5505.889882202374, 5581.748821303051, 5599.0, 5622.634528755618, 5635.8591035224035, 5666.065099258617, 5672.290963629546, 5685.346185188673, 5686.715664216814, 5687.118496633028, 5292.364916210041, 5293.602979776532, 5296.470352964711, 5296.894062118736, 5298.046351656701, 5523.558115350762, 5528.917066244009, 5590.105583106683, 5600.047991841316, 5761.711914404263, 5559.694216818114, 5584.888302233932, 5597.972010139923, 5600.467955544217, 5601.383847776741, 5602.887942241214, 5670.064439367458, 5672.319321681424, 5674.659520428788, 5679.460451257207, 5441.129419292894, 5499.340079190442, 5545.500904918576, 5577.832665020056, 5641.041023026095, 5629.493345598834, 5636.351819540723, 5639.069553523623, 5639.746211420496, 5646.604737668324, 4387.648988080946, 4389.0, 4391.231534481453, 4394.956050439519, 4426.645868330525, 3819.9809000955374, 3831.4635950966886, 3894.3963685628773, 3903.2974064668065, 3933.272344616255, 5337.359516858002, 5356.437574054746, 5374.785008599612, 5376.086065368897, 5427.48438898304, 5387.380451248078, 5672.773089076473, 5676.545876329884, 5679.744411501492, 5680.318361796528, 5223.791048358099, 5236.1098613235845, 5246.055709972242, 5346.599005074635, 5363.249145119687, 4943.728094954812, 4944.629152813553, 4946.307516947631, 4950.0, 4986.202207646832, 5762.193292938908, 5768.101809932269, 5769.249997500298, 5774.191613174159, 5828.766849326064, 5372.5970552207955, 5474.042042642602, 5480.451954804487, 5604.775808967595, 5670.0, 5644.491995720405, 5706.058500347563, 5710.65736055838, 5751.367349591458, 5756.165356023437, 5530.91703624454, 5535.529479994246, 5545.667259964415, 5548.361938377138, 5548.833534993957, 5464.071107911744, 5519.061759500814, 5563.8609034772835, 5621.353544342453, 5622.18478320633, 5385.142125966535, 5440.972795135999, 5449.2371068049715, 5464.972675136758, 5486.670799752028, 5501.119820828648, 5509.834704958857, 5518.42056584069, 5521.503064724194, 5525.0, 5216.217567364925, 5321.813736519203, 5433.049216387196, 5439.0753571893665, 5491.972540137273, 5401.1088170453, 5460.153676180198, 5461.044317244387, 5514.724263786875, 5521.199426083276, 5341.572674186134, 5380.0, 5387.838364849061, 5391.406945235943, 5397.163439666858], "unit": "score", "name": "dojo.html", "value": 5169.765410084916}, {"lowerIsBetter": false, "replicates": [561.5647872898503, 586.3169407640099, 601.3925934805847, 602.5450784657583, 613.8189234175918, 29602.555961660564, 30290.39419211617, 30372.69627303726, 37612.0, 38094.23811523768, 42196.0, 42533.7873310633, 42815.785921070354, 42820.785896070556, 42837.571624283744, 8617.224449799502, 8773.912260877374, 8780.429272097324, 8795.736127916141, 8804.64781408743, 7790.104138024115, 7977.720779772709, 10646.25476216665, 10864.891351086468, 10889.945550272238, 10481.790364192711, 10571.629992950247, 10581.047705706485, 10582.735431614232, 10592.046715795577, 7807.180246074137, 8004.319632831216, 10437.791244175112, 10544.0, 10545.0, 10613.0, 10940.179486538462, 10946.179036572288, 10949.343039417661, 10951.288166269167, 39407.21185576301, 39871.8006409969, 39917.0, 39922.60077399218, 39972.20055598872, 3421.469672200813, 3506.8071256080907, 3582.3372676055014, 3609.043603445089, 4061.025353915039, 29304.8534757327, 29745.40509189804, 30672.53991190134, 30873.228169295722, 31229.843850780828, 3968.761874287552, 4037.9097643636346, 4043.716939814214, 4128.649064829493, 4200.3699445083175, 256870.0, 257933.0, 258014.0, 259593.0, 259654.0, 53370.73314633402, 54072.45927540753, 64459.67770161213, 66349.0, 66645.0, 59560.40439595635, 59941.0, 60175.38947663154, 60190.39809601936, 60208.0, 15995.360185592679, 16048.679026419406, 16098.919505402633, 16190.0, 16206.0, 12262.0, 12301.200421972542, 12369.196002259825, 12371.443285052193, 12409.813852792215, 20272.0, 20492.18031278762, 20585.279515216895, 20610.79389206119, 20632.381028569165, 7739.496932699357, 7877.645505952246, 7913.129555748863, 8090.110087890327, 8102.837943241102, 891.5453118909273, 1018.4296793795498, 1036.259074761549, 1051.4007016000805, 1057.7302787789065, 28302.433951320858, 28390.85804570964, 28727.425451490853, 28738.281542961176, 28763.42473150567, 1033.0650761061252, 1033.6382266206917, 1042.6038105519854, 1084.013547671615, 1128.4245035032193, 14311.713765724628, 14412.495562655256, 14452.494162704255, 14526.491572795112, 14644.487442939659, 347422.0, 355146.0, 355869.0, 356337.0, 357447.0, 52386.0, 53133.0, 53135.0, 53279.0, 53426.198607020924, 1313.0546006875036, 1320.2474589483902, 1383.391307824554, 1386.0297791545959, 1462.8829693624698, 26536.867315663298, 27306.45387092287, 27689.58465622977, 27690.723092768814, 27704.307392315353, 42658.0, 43106.13787724291, 43179.0, 43557.91105222395, 43720.78139609409, 283984.0, 290490.0, 291264.0, 292284.0, 293237.0, 29802.403951921275, 30255.243618909702, 30267.0, 30271.091867244017, 30286.0, 38464.0, 38483.230335393695, 38941.0, 39153.41269881011, 39848.80075599603, 33980.0, 34350.65649343475, 34364.48453273253, 37199.0, 40317.59682403138, 35249.59001639906, 36399.81800090983, 40416.1916761669, 41465.0, 41847.58152418437, 35986.0, 36224.818875905454, 36260.81869590635, 40664.39003414892, 41946.790266048476, 31894.68105318917, 33277.50083748697, 33287.0, 33746.0, 40557.0, 29980.0, 30704.53943190809, 31209.219769505944, 31870.36259274755, 34199.31601367909], "unit": "score", "name": "ext.html", "value": 19035.87209221343}, {"lowerIsBetter": false, "replicates": [361.47946956382805, 365.24212259561415, 365.89206184175663, 367.9668829805317, 370.79791513625105, 13364.799528007088, 13384.390043941436, 15964.680706385865, 16425.50723478296, 16881.662366752673, 12876.0, 13252.734945301088, 15336.693266134671, 15653.686926261467, 16455.177241137953, 5206.661566998143, 5327.813526526573, 5328.440513746059, 5330.573554115662, 5333.893322133575, 3802.372608519591, 3947.8420863165584, 5149.665271757342, 5235.293235413227, 5309.123994540915, 3929.2141571685634, 5933.0, 6100.542459315547, 6128.877422451548, 6141.4165654263, 5923.348431672491, 5984.8503787405225, 5989.161517387569, 6041.486473649723, 6090.66501342426, 5266.499682530153, 5285.471452854722, 5288.92066619001, 5294.49702278283, 5297.231901374318, 20460.69308960367, 20486.078126484193, 20938.89530552353, 20943.0, 21019.579608407745, 2138.2088627207877, 2169.934901952944, 2201.086549082129, 2210.458437682762, 2585.767280944724, 12697.936510317482, 13046.217226966317, 13055.0, 13266.0, 13362.265075420848, 1695.203254470397, 1747.405882000126, 1769.4426255729404, 1815.936442224529, 1913.9904300478547, 55733.0, 57146.0, 57780.71109644467, 59441.40558594402, 60854.695726521524, 9299.070092990782, 9497.477638729799, 9739.123478886933, 9776.951115244521, 9853.507324633883, 12819.743605127846, 12821.1025228235, 12828.743425131444, 12831.743365132832, 12836.679083022813, 6976.441884649216, 7017.859642807115, 7557.697692092364, 7623.885641715378, 7650.349720273728, 7537.0, 7560.3573696235335, 7581.12817026038, 7638.923610763822, 7655.808604784925, 5797.797077102281, 5855.531557475391, 5857.560682948796, 5877.853053673693, 5917.408259174134, 1642.7782249396419, 1735.2191513818832, 1752.088913764835, 1779.1904683369037, 1788.9016104114398, 442.9844955426545, 448.49544262704455, 454.7703409778041, 472.6203565049957, 781.2187812187813, 10811.621593244356, 10920.617778377718, 11011.889881101086, 11052.557897684002, 11343.376114313793, 408.7159424200212, 447.89698369375225, 477.7229207059897, 488.2164126576864, 489.62788280906767, 5310.044192045469, 5447.291852059207, 5498.642588231752, 5530.115181570929, 5554.1391084381985, 506490.0, 512499.0, 516578.0, 529075.0, 541927.0, 75943.34198303144, 80878.19121808825, 81214.59392703116, 83286.0, 83965.44311012038, 1104.216006635282, 1109.8501702270091, 1127.1208457403238, 1144.456383217965, 1145.3471521232984, 14522.782158267422, 15313.540593782205, 15431.228438578251, 15577.0, 15875.920620396824, 33306.0, 33359.0, 34560.82719586386, 36531.26937461289, 37331.6266837339, 42605.57394426016, 44196.0, 45144.77427612841, 45206.32190517211, 45595.08809823719, 9293.30300227472, 9597.952010239904, 9640.180584650383, 9647.179989700953, 9647.951760241154, 17005.0622178999, 17018.404355847484, 17043.31826726917, 17137.828621713623, 17487.54067712725, 11676.94161529221, 12910.22538647684, 13640.727185456433, 13859.584212473643, 14282.42870285177, 13309.401076951366, 13381.53164639233, 13680.794788078387, 13949.232792196526, 14067.226302553048, 13545.458181672622, 13612.0, 14363.640908977359, 14514.274286285854, 14628.561143165722, 9004.594793234452, 9125.183271660828, 9428.622855085994, 9463.810723785347, 9491.905080949102, 10117.645882394081, 10373.58505659765, 13050.869491304966, 14004.789928150882, 14025.929870350583], "unit": "score", "name": "jquery.html", "value": 8318.202256269433}, {"lowerIsBetter": false, "replicates": [995.6017592962819, 1483.2583708145928, 1533.5782659768563, 1614.6609212065466, 1704.0457343887417, 6415.294317625057, 7254.709811607536, 7362.815929601763, 7377.963110184455, 7418.480706350558, 7144.285571442853, 7262.52793568419, 7295.8540829183385, 7300.926990730091, 7306.269373062691, 1964.2928545723528, 1997.710332001859, 2252.6170551006285, 2255.9436014099615, 2284.565932472833, 2236.9776302236933, 2302.838801283911, 2307.630779075348, 2309.099451214028, 2310.1336998625516, 2255.560165767676, 2302.5049614332916, 2304.6888670029584, 2306.6540018997116, 2311.2141871763597, 2252.954940901181, 2257.0, 2293.850899691523, 2303.1593468384085, 2304.135949019118, 2284.383216531544, 2286.0512887151813, 2312.7571604981404, 2315.8842057897205, 2316.2356422380744, 7270.454715896329, 7359.227281135457, 7366.889496657554, 7370.078740157481, 7380.0, 4560.543945605446, 4614.123316569841, 4617.284320930261, 4623.029163875556, 4638.281066434674, 2928.282570770175, 2936.207224049516, 2956.689547597493, 2967.5697023931625, 3045.5279431688123, 958.4488918871676, 970.9563069661882, 988.402016779845, 994.7712026233934, 999.5252255178805, 9928.652497162637, 10017.449040302781, 10043.096121349114, 10056.0, 10057.69826905194, 4087.6116768906904, 4111.650509706708, 4139.544650088488, 4177.791110444466, 4192.035831758712, 8964.103589640983, 8968.968568614562, 8980.236679882153, 8982.640694372283, 8983.55082245885, 4492.9550704493195, 4553.317002449593, 4555.63554915606, 4555.863324100283, 4577.633789296849, 4095.1809638072505, 4096.446979657708, 4105.58944105587, 4123.876283711494, 4130.91738165235, 3322.1362445764275, 3340.599128104643, 3395.4567269236804, 3450.051235910145, 3538.398472259722, 1185.887340702632, 1186.6440067979572, 1193.731410432663, 1197.233770386953, 1207.2635692227734, 1071.3893080943787, 1071.6945670483874, 1110.6612483192541, 1203.807390817465, 1307.7057662025968, 6725.192976842713, 7017.508774385744, 7071.398931090915, 7082.327178917994, 7093.361597456254, 1097.6542389147553, 1104.6244276945715, 1109.2124591540096, 1122.8428020077197, 1147.162571322939, 5589.720513974285, 5632.549396048306, 5644.520215781622, 5645.604807663506, 5647.435256474319, 8472.872906906401, 8920.598573064228, 8947.776305592412, 9038.638454461749, 9043.276537876955, 8884.37809353352, 8895.199432051146, 8906.153915378029, 8912.376133670581, 8926.910730892738, 6773.288804675537, 6952.270011648805, 7006.0892084029765, 7032.437405007587, 7032.789016329519, 6774.119364482585, 6824.351686589766, 6825.35159159879, 6825.55633883796, 6826.624535650587, 7754.106520657043, 8050.114487406264, 8066.1127275998415, 8068.193180681885, 8084.0, 8105.108438071925, 8140.796480088045, 8311.210435008665, 8323.667053318042, 8331.041930178108, 5869.266341707286, 5930.347661757117, 6668.233153187445, 6813.454923606, 6827.0, 6256.249250089836, 6383.7127329271225, 6384.712687929147, 6388.425041746265, 6930.757423490154, 6422.967885160731, 6439.291677915332, 6767.458603311822, 6816.863662726619, 6873.0, 6562.934370656423, 6677.499187560855, 6739.359760822714, 6769.695363708744, 6771.0, 6661.300563440769, 6709.664516774239, 6711.530192886451, 6723.865522689616, 6725.092112564741, 6192.473639740672, 6209.348018458178, 6224.595401298901, 6238.563300568917, 6243.094751261133, 6051.273847138372, 6053.485453736482, 6093.817185484442, 6130.846728831815, 6140.631562106288], "unit": "score", "name": "mootools.html", "value": 4320.241332052575}, {"lowerIsBetter": false, "replicates": [339.4263694356539, 339.4873740651616, 340.67976102463683, 343.5979903512891, 348.61478066736265, 5836.387179346171, 5863.325717542484, 5866.325372582154, 5873.941260587393, 5952.0, 5820.679862607555, 5821.388754180803, 5827.562932780037, 5833.154192642064, 5833.82498525045, 4547.909041819178, 4555.612772914306, 4555.931661025087, 4558.475775285843, 4561.452625684922, 4400.44994375703, 4519.27691569349, 4551.795169217393, 4551.840685576006, 4554.339620754989, 4467.195904737146, 4478.462584489866, 4482.753448560328, 4493.438320209973, 4493.887652808673, 4305.117450922569, 4371.803268852909, 4417.072414792865, 4498.617617502517, 4507.504174540798, 3901.941470877939, 3929.469521614574, 3937.8424863005444, 3937.862174823896, 3953.367461206193, 5549.639273447254, 5558.777648894039, 5567.220589117527, 5579.0, 5587.888242235133, 4925.802967881281, 4985.651004429691, 4986.077575648516, 5132.717700526469, 5234.816781412632, 6150.384961503813, 6192.969035154795, 6214.968925155391, 6216.471599913967, 6219.502439804804, 4348.91302173958, 4352.978235108836, 4353.455818022747, 4357.455318085239, 4359.542248063939, 6348.682565871734, 6428.0, 6503.772367967145, 6503.9593665013845, 6513.348665133449, 4422.911541769146, 4446.221911165533, 4453.75504347264, 4454.198244316054, 4456.264716321835, 4509.323601459807, 4510.706804057726, 4512.165249428866, 4515.345274935182, 4517.864464066083, 5183.0, 5193.454687257859, 5196.194589838581, 5202.375714914158, 5205.973970130125, 6131.754729810847, 6261.686915654199, 6265.65538895356, 6268.623882567061, 6277.372262773777, 1581.644130070725, 1599.3682495414246, 1599.5681166085092, 1599.9520314194222, 1603.158341870504, 1074.7796701676139, 1082.6589624268488, 1083.9620613278498, 1098.8846171152074, 1100.4112799652307, 6046.758129674852, 6051.939480605138, 6052.848678783065, 6057.818265452043, 6065.787697430569, 6419.871602567922, 6441.066045423388, 6455.096286519891, 6472.935270647327, 6473.06140609609, 5913.733881975321, 6047.425494578008, 6049.455549000523, 6052.697365131726, 6056.848578785565, 3480.91297717554, 3499.2826470573473, 3500.8599656013985, 3505.5968563615, 3506.649335066524, 6585.341465853377, 6593.340665933369, 6594.835129121714, 6597.1423714916755, 6600.669966501751, 6397.392247736458, 6592.110065141144, 6597.076409302702, 6599.934000660028, 6615.669216539154, 6453.354664533603, 6644.8671026579195, 6654.70053847578, 6673.866522669519, 6676.432503237279, 6494.220693516808, 6497.545171837926, 6498.187726534184, 6500.577462464925, 6503.219613646393, 6312.715927783169, 6424.614523128627, 6426.710798014007, 6444.742210311535, 6449.6130232186215, 6648.069270302259, 6653.534252602271, 6655.3344665535, 6655.500837437115, 6661.3338666132995, 4434.091011342732, 4449.28811390176, 4455.75493347869, 4457.576530229623, 4459.732416055047, 6376.617402955838, 6501.114676743691, 6503.406665367015, 6508.414242718178, 6511.316311787384, 6309.148264984168, 6316.11574379578, 6316.231455192554, 6318.842028949313, 6323.588966717149, 6271.43557079865, 6294.276158241861, 6302.905456418066, 6303.086052522451, 6321.936780632135, 6073.514118870391, 6195.876082478415, 6196.0, 6207.224096987877, 6210.223722034746, 6334.556581039467, 6343.207099112611, 6348.77779277723, 6351.93648063532, 6359.173307470184, 6193.721282542207, 6200.7519699211525, 6207.503399728102, 6207.720652570555, 6211.689415529296], "unit": "score", "name": "prototype.html", "value": 4725.7428891924355}, {"lowerIsBetter": false, "replicates": [693.452172783501, 730.375528922771, 766.375404045703, 768.8846672999051, 798.4849771897126, 12941.2235265884, 13266.535671251508, 13290.601281961532, 13310.068295219326, 13319.467221311148, 12779.936100319486, 13402.128861623987, 13426.66433339167, 13437.529686460975, 13441.0, 689.4208864553774, 696.919854216764, 816.963236654349, 831.2269589281965, 845.8223773007676, 846.699421705295, 853.2534032721369, 856.6616186606287, 858.9226969572737, 859.3383095016833, 819.4304958054155, 875.4090988582712, 888.7067267801642, 889.0709208876707, 889.3774357949429, 711.0187940641907, 719.4999475364624, 866.5017614871412, 880.2913654508119, 882.161946151159, 649.7401039584204, 649.9902501462482, 650.5413683353224, 651.2478087808589, 652.4682383857141, 13116.86883131166, 13146.737065258641, 13157.60527184186, 13209.669758256025, 13248.205107693473, 11314.773704525946, 11946.940265298705, 12168.756624867541, 12177.33024683642, 12246.387680616022, 9939.204863610894, 9948.950255248677, 10000.949710338344, 10054.899451005469, 10085.142762865165, 3485.8779942702135, 3522.137076416269, 3552.1297282166033, 3554.3246783111126, 3555.839987200556, 47043.294350584765, 48412.0, 48465.27302090436, 48499.272510912015, 48539.51460485421, 10136.341137826017, 10156.949215253877, 10235.283530152818, 10241.283110182216, 10372.844407333896, 16861.578460538585, 16923.91538042302, 16947.91526042362, 16948.0, 16955.745663815054, 7393.112826460859, 7411.332980031823, 7466.738664146837, 7571.129320128146, 7601.733939312097, 4386.254336762758, 4388.539203383599, 4410.977945110254, 4440.000999775089, 4494.348319493655, 2865.469888070672, 2886.076455534209, 2886.437144756794, 2888.595596616475, 2892.450434417454, 537.5726297593344, 538.37548443805, 539.8484271723687, 543.4836904940322, 558.7122631844603, 585.2216551985848, 608.385530614083, 626.9216347956506, 630.7035693224177, 634.4765568406084, 8556.443831150955, 8559.614817333111, 8681.2186903179, 8786.209241168326, 8834.072422395684, 497.1920628977911, 608.5831205624073, 617.3301967365369, 622.4926684751913, 662.2979641579941, 3842.7310088293552, 4103.876883693494, 4136.131412403369, 4156.43888075112, 4170.4995400552125, 58101.4189858096, 58350.7082464585, 58427.70786146042, 59720.701396493605, 59732.40267597355, 51700.0, 51778.74110629423, 51787.0, 51804.0, 51985.0, 47248.52751472441, 47344.0, 47390.0, 47469.28796068062, 47553.0, 8188.959055204686, 8243.670253189804, 8254.417941199863, 8276.172382761917, 8348.605577689243, 35219.823900881354, 35369.82315088408, 35633.82183089068, 35665.46501802527, 36118.27763444664, 41601.0, 43548.129037419705, 46170.0, 46298.30552541647, 46646.76676616595, 9534.570944307661, 9549.952250238704, 9644.614215431304, 10760.730981725519, 11144.721381965192, 11167.72080697989, 11416.200865939305, 11480.885191148316, 11534.250273732516, 11801.232919860526, 10602.416867072385, 10918.563257469612, 10993.450327483433, 11282.214003113344, 11355.0, 10243.38539687621, 10383.428911409945, 10402.063814256753, 10417.322874013165, 10622.946885265525, 10258.384496930208, 10474.842877356694, 10518.421486817992, 10717.35695858251, 11007.119430445706, 8872.116064404827, 8925.954701468338, 9266.166045055978, 9418.434893906113, 9444.622215111318, 8666.913330866611, 8718.17177368149, 8735.694250701195, 8921.197092261727, 9388.155066044088], "unit": "score", "name": "yui.html", "value": 6172.424743641805}], "name": "dromaeo_css", "value": 6848.4357285993465}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 111.0, 104.0, 111.0, 110.0, 110.0, 99.0, 110.0, 109.0, 117.0], "unit": "score", "name": "ai-astar", "value": 108.9}, {"lowerIsBetter": true, "replicates": [155.0, 123.0, 152.0, 167.0, 129.0, 143.0, 148.0, 126.0, 161.0, 123.0], "unit": "score", "name": "audio-beat-detection", "value": 142.7}, {"lowerIsBetter": true, "replicates": [159.0, 160.0, 164.0, 160.0, 162.0, 161.0, 160.0, 162.0, 162.0, 160.0], "unit": "score", "name": "audio-dft", "value": 161.0}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 84.0, 94.0, 71.0, 72.0, 77.0, 105.0, 95.0, 72.0], "unit": "score", "name": "audio-fft", "value": 81.2}, {"lowerIsBetter": true, "replicates": [94.0, 93.0, 146.0, 92.0, 93.0, 93.0, 133.0, 93.0, 94.0, 132.0], "unit": "score", "name": "audio-oscillator", "value": 106.3}, {"lowerIsBetter": true, "replicates": [110.0, 109.0, 109.0, 109.0, 109.0, 109.0, 108.0, 109.0, 109.0, 110.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 145.0, 145.0, 145.0, 147.0, 145.0, 146.0, 146.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 145.6}, {"lowerIsBetter": true, "replicates": [95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [60.0, 58.0, 60.0, 57.0, 57.0, 58.0, 58.0, 58.0, 58.0, 57.0], "unit": "score", "name": "json-parse-financial", "value": 58.1}, {"lowerIsBetter": true, "replicates": [67.0, 67.0, 67.0, 68.0, 67.0, 75.0, 67.0, 67.0, 68.0, 66.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.9}, {"lowerIsBetter": true, "replicates": [84.0, 86.0, 84.0, 85.0, 86.0, 91.0, 84.0, 84.0, 85.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.3}, {"lowerIsBetter": true, "replicates": [116.0, 130.0, 117.0, 118.0, 122.0, 115.0, 120.0, 120.0, 122.0, 121.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 120.1}, {"lowerIsBetter": true, "replicates": [141.0, 151.0, 150.0, 152.0, 150.0, 138.0, 150.0, 150.0, 149.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 147.0}, {"lowerIsBetter": true, "replicates": [68.0, 66.0, 69.0, 67.0, 67.0, 67.0, 68.0, 66.0, 65.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.9}], "name": "kraken", "value": 1495.4}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [168.15200941651253], "unit": "score", "name": "Richards", "value": 209.94099399999996}, {"lowerIsBetter": false, "replicates": [189.17896329928112], "unit": "score", "name": "DeltaBlue", "value": 349.49974799999995}, {"lowerIsBetter": false, "replicates": [280.58361391694723], "unit": "score", "name": "Encrypt", "value": 948.6690839999999}, {"lowerIsBetter": false, "replicates": [5208.333333333333], "unit": "score", "name": "Decrypt", "value": 51.10675199999996}, {"lowerIsBetter": false, "replicates": [1943.7984496124031], "unit": "score", "name": "RayTrace", "value": 380.6922472582253}, {"lowerIsBetter": false, "replicates": [763.539282990084], "unit": "score", "name": "Earley", "value": 872.8601328671325}, {"lowerIsBetter": false, "replicates": [12207.317073170732], "unit": "score", "name": "Boyer", "value": 54.595370629370635}, {"lowerIsBetter": false, "replicates": [25200.0], "unit": "score", "name": "RegExp", "value": 36.150198412698415}, {"lowerIsBetter": false, "replicates": [619.8142414860681], "unit": "score", "name": "Splay", "value": 131.47648851148847}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 19911.436621677098}]} 23:10:29 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "19911.44"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1495.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6848.43"}}} 23:10:29 INFO - Return code: 0 23:10:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 23:10:29 ERROR - # TBPL SUCCESS # 23:10:29 INFO - Running post-action listener: _resource_record_post_action 23:10:29 INFO - Running post-run listener: _resource_record_post_run 23:10:30 INFO - Total resource usage - Wall time: 1227s; CPU: 13.0%; Read bytes: 221184; Write bytes: 558669824; Read time: 68; Write time: 526876 23:10:30 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 246718464; Read time: 0; Write time: 281168 23:10:30 INFO - run-tests - Wall time: 1212s; CPU: 13.0%; Read bytes: 221184; Write bytes: 311951360; Read time: 68; Write time: 245708 23:10:30 INFO - Running post-run listener: _upload_blobber_files 23:10:30 INFO - Blob upload gear active. 23:10:30 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 23:10:30 INFO - Files from /builds/slave/test-pgo/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:10:30 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test-pgo/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test-pgo/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test-pgo/build/uploaded_files.json'] 23:10:30 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test-pgo/oauth.txt -b Fx-Team -d /builds/slave/test-pgo/build/blobber_upload_dir --output-manifest /builds/slave/test-pgo/build/uploaded_files.json 23:10:31 INFO - (blobuploader) - INFO - Open directory for files ... 23:10:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:10:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:10:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:10:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:10:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:10:32 INFO - (blobuploader) - INFO - Done attempting. 23:10:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 23:10:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:10:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:10:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:10:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:10:33 INFO - (blobuploader) - INFO - Done attempting. 23:10:33 INFO - (blobuploader) - INFO - Iteration through files over. 23:10:33 INFO - Return code: 0 23:10:33 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 23:10:33 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 23:10:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0fd93bb81ccbae9dd0ddf79f27e18644b735756734de2009e9e5aeb1b23d3736bc1d7c9df1c64c1135c78b8dfff672b916510b31d6efd74ee807e92bb3ee3565", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/edfbc751371d270773fdcf4d5c0824bdee20f0ad8c288a8c193bcd353658430c391b1ea5266fc725222086242a78e9d375d9a405d9881b7216a7d476ae0c3d6a"} 23:10:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 23:10:33 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 23:10:33 INFO - Contents: 23:10:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0fd93bb81ccbae9dd0ddf79f27e18644b735756734de2009e9e5aeb1b23d3736bc1d7c9df1c64c1135c78b8dfff672b916510b31d6efd74ee807e92bb3ee3565", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/edfbc751371d270773fdcf4d5c0824bdee20f0ad8c288a8c193bcd353658430c391b1ea5266fc725222086242a78e9d375d9a405d9881b7216a7d476ae0c3d6a"} 23:10:33 INFO - Copying logs to upload dir... 23:10:33 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1266.445238 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2015-12-22 23:10:33.341582) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-22 23:10:33.345483) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0fd93bb81ccbae9dd0ddf79f27e18644b735756734de2009e9e5aeb1b23d3736bc1d7c9df1c64c1135c78b8dfff672b916510b31d6efd74ee807e92bb3ee3565", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/edfbc751371d270773fdcf4d5c0824bdee20f0ad8c288a8c193bcd353658430c391b1ea5266fc725222086242a78e9d375d9a405d9881b7216a7d476ae0c3d6a"} build_url:https://queue.taskcluster.net/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011389 build_url: 'https://queue.taskcluster.net/v1/task/UyFiu_IwTeKGqDG6Ds7MLg/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0fd93bb81ccbae9dd0ddf79f27e18644b735756734de2009e9e5aeb1b23d3736bc1d7c9df1c64c1135c78b8dfff672b916510b31d6efd74ee807e92bb3ee3565", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/edfbc751371d270773fdcf4d5c0824bdee20f0ad8c288a8c193bcd353658430c391b1ea5266fc725222086242a78e9d375d9a405d9881b7216a7d476ae0c3d6a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-12-22 23:10:33.373173) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-22 23:10:33.373492) ========= 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=0d7d1540fd7fba0c6b8b7f12000001dc-1450850594.384221-1745231275 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004624 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-22 23:10:33.457673) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-22 23:10:33.458050) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-22 23:10:33.458433) ========= ========= Total master_lag: 0.25 =========