builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-062 starttime: 1452409164.67 results: success (0) buildid: 20160109203238 builduid: c5452705e5b44dd9870e7587d1adc964 revision: 1764c03305e60d6b9bee7308670d237ddb7767a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:24.667293) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:24.668172) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:24.668500) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006179 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.07 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:24.739853) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:24.740546) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:24.869521) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:24.869863) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.035884 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:24.938637) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:24.938953) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:24.939338) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:24.939644) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-01-09 22:59: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.6M=0s 2016-01-09 22:59:25 (43.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.242446 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:25.204085) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:25.204436) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.058146 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:25.275729) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:25.276120) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1764c03305e60d6b9bee7308670d237ddb7767a0 --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 1764c03305e60d6b9bee7308670d237ddb7767a0 --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=a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-01-09 22:59:25,393 truncating revision to first 12 chars 2016-01-09 22:59:25,394 Setting DEBUG logging. 2016-01-09 22:59:25,394 attempt 1/10 2016-01-09 22:59:25,394 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1764c03305e6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-01-09 22:59:25,757 unpacking tar archive at: fx-team-1764c03305e6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.697595 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:25.987822) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:25.988174) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:25.996054) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:25.996359) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2016-01-09 22:59:25.996798) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-01-09 22:59:25.997125) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:59:26 INFO - MultiFileLogger online at 20160109 22:59:26 in /builds/slave/test-pgo 22:59:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:59:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 22:59:26 INFO - {'append_to_log': False, 22:59:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 22:59:26 INFO - 'blob_upload_branch': 'Fx-Team', 22:59:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 22:59:26 INFO - 'branch': 'Fx-Team', 22:59:26 INFO - 'buildbot_json_path': 'buildprops.json', 22:59:26 INFO - 'config_files': ('talos/linux_config.py',), 22:59:26 INFO - 'default_actions': ('clobber', 22:59:26 INFO - 'read-buildbot-config', 22:59:26 INFO - 'download-and-extract', 22:59:26 INFO - 'populate-webroot', 22:59:26 INFO - 'create-virtualenv', 22:59:26 INFO - 'install', 22:59:26 INFO - 'run-tests'), 22:59:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:59:26 INFO - 'download_minidump_stackwalk': True, 22:59:26 INFO - 'download_symbols': 'ondemand', 22:59:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:59:26 INFO - 'tooltool.py': '/tools/tooltool.py', 22:59:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:59:26 INFO - '/tools/misc-python/virtualenv.py')}, 22:59:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:59:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:59:26 INFO - 'installer_path': 'installer.exe', 22:59:26 INFO - 'log_level': 'info', 22:59:26 INFO - 'log_name': 'talos', 22:59:26 INFO - 'log_to_console': True, 22:59:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:59:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:59:26 INFO - 'opt_config_files': (), 22:59:26 INFO - 'pip_index': False, 22:59:26 INFO - 'sps_profile': False, 22:59:26 INFO - 'sps_profile_interval': 0, 22:59:26 INFO - 'suite': 'dromaeojs', 22:59:26 INFO - 'system_bits': '32', 22:59:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:59:26 INFO - 'title': 'talos-linux64-ix-062', 22:59:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:59:26 INFO - 'use_talos_json': True, 22:59:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 22:59:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:59:26 INFO - 'work_dir': 'build'} 22:59:26 INFO - ##### 22:59:26 INFO - ##### Running clobber step. 22:59:26 INFO - ##### 22:59:26 INFO - Running pre-action listener: _resource_record_pre_action 22:59:26 INFO - Running main action method: clobber 22:59:26 INFO - rmtree: /builds/slave/test-pgo/build 22:59:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 22:59:27 INFO - Running post-action listener: _resource_record_post_action 22:59:27 INFO - ##### 22:59:27 INFO - ##### Running read-buildbot-config step. 22:59:27 INFO - ##### 22:59:27 INFO - Running pre-action listener: _resource_record_pre_action 22:59:27 INFO - Running main action method: read_buildbot_config 22:59:27 INFO - Using buildbot properties: 22:59:27 INFO - { 22:59:27 INFO - "project": "", 22:59:27 INFO - "product": "firefox", 22:59:27 INFO - "script_repo_revision": "production", 22:59:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:59:27 INFO - "repository": "", 22:59:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 22:59:27 INFO - "buildid": "20160109203238", 22:59:27 INFO - "pgo_build": "True", 22:59:27 INFO - "basedir": "/builds/slave/test-pgo", 22:59:27 INFO - "buildnumber": 887, 22:59:27 INFO - "slavename": "talos-linux64-ix-062", 22:59:27 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:59:27 INFO - "platform": "ubuntu64_hw", 22:59:27 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 22:59:27 INFO - "branch": "fx-team", 22:59:27 INFO - "repo_path": "integration/fx-team", 22:59:27 INFO - "revision": "1764c03305e60d6b9bee7308670d237ddb7767a0", 22:59:27 INFO - "stage_platform": "linux64-pgo", 22:59:27 INFO - "builduid": "c5452705e5b44dd9870e7587d1adc964", 22:59:27 INFO - "slavebuilddir": "test" 22:59:27 INFO - } 22:59:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2. 22:59:27 INFO - Running post-action listener: _resource_record_post_action 22:59:27 INFO - ##### 22:59:27 INFO - ##### Running download-and-extract step. 22:59:27 INFO - ##### 22:59:27 INFO - Running pre-action listener: _resource_record_pre_action 22:59:27 INFO - Running main action method: download_and_extract 22:59:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 22:59: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:59:27 INFO - https://queue.taskcluster.net/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 22:59:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/test_packages.json 22:59:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/test_packages.json 22:59:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 22:59:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 22:59:35 INFO - Downloaded 1302 bytes. 22:59:35 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 22:59:35 INFO - Using the following test package requirements: 22:59:35 INFO - {u'common': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:59:35 INFO - u'cppunittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:59:35 INFO - u'jittest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:35 INFO - u'jsshell-linux-x86_64.zip'], 22:59:35 INFO - u'mochitest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:59:35 INFO - u'mozbase': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:59:35 INFO - u'reftest': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:59:35 INFO - u'talos': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:59:35 INFO - u'web-platform': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:59:35 INFO - u'webapprt': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'], 22:59:35 INFO - u'xpcshell': [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:35 INFO - u'firefox-46.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:59:35 INFO - Downloading packages: [u'firefox-46.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:59:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:35 INFO - https://queue.taskcluster.net/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:59:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:59:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:59:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/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:59:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/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:59:40 INFO - Downloaded 24028408 bytes. 22:59:40 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:59:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:59:40 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:59:41 INFO - Return code: 0 22:59:41 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:59: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:59:41 INFO - https://queue.taskcluster.net/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:59:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:59:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:59:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/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:59:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/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:59:44 INFO - Downloaded 24028408 bytes. 22:59:44 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:59:44 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.common.tests.zip 22:59:44 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:59:46 INFO - Return code: 0 22:59: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:59:46 INFO - https://queue.taskcluster.net/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:59:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:59:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:59:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/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:59:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/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:59:49 INFO - Downloaded 11132285 bytes. 22:59:49 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:59:49 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-46.0a1.en-US.linux-x86_64.talos.tests.zip 22:59:49 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:59:49 INFO - Return code: 0 22:59: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:59:49 INFO - https://queue.taskcluster.net/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:59:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:59:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:59:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/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:59:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TbZ5_RzLQKyohc9nEFravw/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:59:50 INFO - Downloaded 55920477 bytes. 22:59:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:59:50 INFO - mkdir: /builds/slave/test-pgo/properties 22:59:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 22:59:50 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 22:59:50 INFO - Contents: 22:59:50 INFO - build_url:https://queue.taskcluster.net/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 22:59:50 INFO - Running post-action listener: _resource_record_post_action 22:59:50 INFO - Running post-action listener: set_extra_try_arguments 22:59:50 INFO - ##### 22:59:50 INFO - ##### Running populate-webroot step. 22:59:50 INFO - ##### 22:59:50 INFO - Running pre-action listener: _resource_record_pre_action 22:59:50 INFO - Running main action method: populate_webroot 22:59:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:59:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:59:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:59:50 INFO - '--tppagecycles', 22:59:50 INFO - '7'], 22:59:50 INFO - 'tests': ['tsvgm']}}, 22:59:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 22:59:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 22:59:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:59:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:59:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 22:59:50 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 22:59:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:59:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:50 INFO - 'talos_options': ['--e10s'], 22:59:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:59:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:50 INFO - 'tests': ['damp', 'tps']}, 22:59:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:50 INFO - 'talos_options': ['--e10s'], 22:59:50 INFO - 'tests': ['damp', 'tps']}, 22:59:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 22:59:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:59:50 INFO - 'other': {'tests': ['a11yr', 22:59:50 INFO - 'ts_paint', 22:59:50 INFO - 'tpaint', 22:59:50 INFO - 'sessionrestore', 22:59:50 INFO - 'sessionrestore_no_auto_restore']}, 22:59:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 22:59:50 INFO - 'tests': ['a11yr', 22:59:50 INFO - 'ts_paint', 22:59:50 INFO - 'tpaint', 22:59:50 INFO - 'sessionrestore', 22:59:50 INFO - 'sessionrestore_no_auto_restore']}, 22:59:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 22:59:50 INFO - 'tests': ['a11yr', 22:59:50 INFO - 'ts_paint', 22:59:50 INFO - 'tpaint', 22:59:50 INFO - 'sessionrestore', 22:59:50 INFO - 'sessionrestore_no_auto_restore']}, 22:59:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 22:59:50 INFO - 'tests': ['a11yr', 22:59:50 INFO - 'ts_paint', 22:59:50 INFO - 'tpaint', 22:59:50 INFO - 'sessionrestore', 22:59:50 INFO - 'sessionrestore_no_auto_restore']}, 22:59:50 INFO - 'other_l64': {'tests': ['a11yr', 22:59:50 INFO - 'ts_paint', 22:59:50 INFO - 'tpaint', 22:59:50 INFO - 'sessionrestore', 22:59:50 INFO - 'sessionrestore_no_auto_restore']}, 22:59:50 INFO - 'other_nol64': {'tests': ['a11yr', 22:59:50 INFO - 'ts_paint', 22:59:50 INFO - 'tpaint', 22:59:50 INFO - 'sessionrestore', 22:59:50 INFO - 'sessionrestore_no_auto_restore']}, 22:59:50 INFO - 'svgr': {'tests': ['tsvgx', 22:59:50 INFO - 'tsvgr_opacity', 22:59:50 INFO - 'tart', 22:59:50 INFO - 'tscrollx', 22:59:50 INFO - 'cart']}, 22:59:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 22:59:50 INFO - 'tests': ['tsvgx', 22:59:50 INFO - 'tsvgr_opacity', 22:59:50 INFO - 'tart', 22:59:50 INFO - 'tscrollx', 22:59:50 INFO - 'cart']}, 22:59:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:50 INFO - 'tests': ['tp5o']}, 22:59:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:50 INFO - 'talos_options': ['--e10s'], 22:59:50 INFO - 'tests': ['tp5o']}, 22:59:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:50 INFO - 'talos_options': ['--xperf_path', 22:59:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:59:50 INFO - 'tests': ['tp5n']}, 22:59:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:50 INFO - 'talos_options': ['--e10s', 22:59:50 INFO - '--xperf_path', 22:59:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:59:50 INFO - 'tests': ['tp5n']}}, 22:59:50 INFO - 'talos.zip': {'path': '', 22:59:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:59:50 INFO - Running post-action listener: _resource_record_post_action 22:59:50 INFO - ##### 22:59:50 INFO - ##### Running create-virtualenv step. 22:59:50 INFO - ##### 22:59:50 INFO - Running pre-action listener: _resource_record_pre_action 22:59:50 INFO - Running main action method: create_virtualenv 22:59:50 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 22:59:50 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:59:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 22:59:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:59:50 INFO - Using real prefix '/usr' 22:59:50 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 22:59:51 INFO - Installing distribute.............................................................................................................................................................................................done. 22:59:53 INFO - Installing pip.................done. 22:59:53 INFO - Return code: 0 22:59:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 22:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:53 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 0x7fb50a333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd21630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb2840>, '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 0xd1f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd1fb60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', '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:59:53 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:59:53 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:59:53 INFO - Using env: {'DISPLAY': ':0', 22:59:53 INFO - 'HOME': '/home/cltbld', 22:59:53 INFO - 'LANG': 'en_US.UTF-8', 22:59:53 INFO - 'LANGUAGE': 'en_US:en', 22:59:53 INFO - 'LOGNAME': 'cltbld', 22:59:53 INFO - 'MAIL': '/var/mail/cltbld', 22:59:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:53 INFO - 'MOZ_NO_REMOTE': '1', 22:59:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:53 INFO - 'NO_EM_RESTART': '1', 22:59:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:59:53 INFO - 'PWD': '/builds/slave/test-pgo', 22:59:53 INFO - 'SHELL': '/bin/bash', 22:59:53 INFO - 'SHLVL': '1', 22:59:53 INFO - 'TERM': 'linux', 22:59:53 INFO - 'TMOUT': '86400', 22:59:53 INFO - 'USER': 'cltbld', 22:59:53 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', 22:59:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:53 INFO - '_': '/tools/buildbot/bin/python'} 22:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:54 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:59:54 INFO - Cleaning up... 22:59:54 INFO - Return code: 0 22:59:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 22:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:54 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 0x7fb50a333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd21630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb2840>, '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 0xd1f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd1fb60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', '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:59:54 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:59:54 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:59:54 INFO - Using env: {'DISPLAY': ':0', 22:59:54 INFO - 'HOME': '/home/cltbld', 22:59:54 INFO - 'LANG': 'en_US.UTF-8', 22:59:54 INFO - 'LANGUAGE': 'en_US:en', 22:59:54 INFO - 'LOGNAME': 'cltbld', 22:59:54 INFO - 'MAIL': '/var/mail/cltbld', 22:59:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:54 INFO - 'MOZ_NO_REMOTE': '1', 22:59:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:54 INFO - 'NO_EM_RESTART': '1', 22:59:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:59:54 INFO - 'PWD': '/builds/slave/test-pgo', 22:59:54 INFO - 'SHELL': '/bin/bash', 22:59:54 INFO - 'SHLVL': '1', 22:59:54 INFO - 'TERM': 'linux', 22:59:54 INFO - 'TMOUT': '86400', 22:59:54 INFO - 'USER': 'cltbld', 22:59:54 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', 22:59:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:54 INFO - '_': '/tools/buildbot/bin/python'} 22:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:54 INFO - Downloading/unpacking psutil>=0.7.1 22:59: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:59: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:59: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:59: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:59: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:59: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:59:56 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 22:59:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 22:59:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 22:59:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:59:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:59:56 INFO - Installing collected packages: psutil 22:59:56 INFO - Running setup.py install for psutil 22:59:56 INFO - building 'psutil._psutil_linux' extension 22:59:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:59:57 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:59:57 INFO - building 'psutil._psutil_posix' extension 22:59:57 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:59:57 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:59:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:59:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:59:57 INFO - Successfully installed psutil 22:59:57 INFO - Cleaning up... 22:59:57 INFO - Return code: 0 22:59:57 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 22:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:57 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 0x7fb50a333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd21630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb2840>, '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 0xd1f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd1fb60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', '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:59:57 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:59:57 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:59:57 INFO - Using env: {'DISPLAY': ':0', 22:59:57 INFO - 'HOME': '/home/cltbld', 22:59:57 INFO - 'LANG': 'en_US.UTF-8', 22:59:57 INFO - 'LANGUAGE': 'en_US:en', 22:59:57 INFO - 'LOGNAME': 'cltbld', 22:59:57 INFO - 'MAIL': '/var/mail/cltbld', 22:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:57 INFO - 'MOZ_NO_REMOTE': '1', 22:59:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:57 INFO - 'NO_EM_RESTART': '1', 22:59:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:59:57 INFO - 'PWD': '/builds/slave/test-pgo', 22:59:57 INFO - 'SHELL': '/bin/bash', 22:59:57 INFO - 'SHLVL': '1', 22:59:57 INFO - 'TERM': 'linux', 22:59:57 INFO - 'TMOUT': '86400', 22:59:57 INFO - 'USER': 'cltbld', 22:59:57 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', 22:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:57 INFO - '_': '/tools/buildbot/bin/python'} 22:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:59:57 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:59:57 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:59:57 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:59:57 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:59:57 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:59:57 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:59:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:59:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 22:59:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:59:59 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:59:59 INFO - Installing collected packages: mozsystemmonitor 22:59:59 INFO - Running setup.py install for mozsystemmonitor 22:59:59 INFO - Successfully installed mozsystemmonitor 22:59:59 INFO - Cleaning up... 22:59:59 INFO - Return code: 0 22:59:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 22:59:59 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:59:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:59 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:59:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:59 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 0x7fb50a333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd21630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb2840>, '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 0xd1f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd1fb60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', '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:59:59 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:59:59 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:59:59 INFO - Using env: {'DISPLAY': ':0', 22:59:59 INFO - 'HOME': '/home/cltbld', 22:59:59 INFO - 'LANG': 'en_US.UTF-8', 22:59:59 INFO - 'LANGUAGE': 'en_US:en', 22:59:59 INFO - 'LOGNAME': 'cltbld', 22:59:59 INFO - 'MAIL': '/var/mail/cltbld', 22:59:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:59 INFO - 'MOZ_NO_REMOTE': '1', 22:59:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:59 INFO - 'NO_EM_RESTART': '1', 22:59:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 22:59:59 INFO - 'PWD': '/builds/slave/test-pgo', 22:59:59 INFO - 'SHELL': '/bin/bash', 22:59:59 INFO - 'SHLVL': '1', 22:59:59 INFO - 'TERM': 'linux', 22:59:59 INFO - 'TMOUT': '86400', 22:59:59 INFO - 'USER': 'cltbld', 22:59:59 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', 22:59:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:59 INFO - '_': '/tools/buildbot/bin/python'} 23:00:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:00 INFO - Downloading/unpacking blobuploader==1.2.4 23:00: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 23:00: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 23:00: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 23:00: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 23:00: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 23:00: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 23:00:01 INFO - Downloading blobuploader-1.2.4.tar.gz 23:00:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 23:00:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:00:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:00: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 23:00: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 23:00: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 23:00: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 23:00: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 23:00: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 23:00:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 23:00:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 23:00:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:00:02 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 23:00:02 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 23:00:02 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 23:00:02 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 23:00:02 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 23:00:02 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 23:00:02 INFO - Downloading docopt-0.6.1.tar.gz 23:00:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 23:00:02 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 23:00:02 INFO - Installing collected packages: blobuploader, requests, docopt 23:00:02 INFO - Running setup.py install for blobuploader 23:00:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:00:02 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 23:00:02 INFO - Running setup.py install for requests 23:00:02 INFO - Running setup.py install for docopt 23:00:03 INFO - Successfully installed blobuploader requests docopt 23:00:03 INFO - Cleaning up... 23:00:03 INFO - Return code: 0 23:00:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 23:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:03 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 0x7fb50a333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd21630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb2840>, '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 0xd1f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd1fb60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', '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 23:00:03 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 23:00:03 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 23:00:03 INFO - Using env: {'DISPLAY': ':0', 23:00:03 INFO - 'HOME': '/home/cltbld', 23:00:03 INFO - 'LANG': 'en_US.UTF-8', 23:00:03 INFO - 'LANGUAGE': 'en_US:en', 23:00:03 INFO - 'LOGNAME': 'cltbld', 23:00:03 INFO - 'MAIL': '/var/mail/cltbld', 23:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:03 INFO - 'MOZ_NO_REMOTE': '1', 23:00:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:03 INFO - 'NO_EM_RESTART': '1', 23:00:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:00:03 INFO - 'PWD': '/builds/slave/test-pgo', 23:00:03 INFO - 'SHELL': '/bin/bash', 23:00:03 INFO - 'SHLVL': '1', 23:00:03 INFO - 'TERM': 'linux', 23:00:03 INFO - 'TMOUT': '86400', 23:00:03 INFO - 'USER': 'cltbld', 23:00:03 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', 23:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:03 INFO - '_': '/tools/buildbot/bin/python'} 23:00:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 23:00:03 INFO - Running setup.py (path:/tmp/pip-bXYuMg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 23:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 23:00:03 INFO - Running setup.py (path:/tmp/pip-SBkqxu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 23:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 23:00:03 INFO - Running setup.py (path:/tmp/pip-peIJ18-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 23:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 23:00:03 INFO - Running setup.py (path:/tmp/pip-qor3g8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 23:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 23:00:03 INFO - Running setup.py (path:/tmp/pip-UCJwwK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 23:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 23:00:03 INFO - Running setup.py (path:/tmp/pip-5NTofR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 23:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 23:00:03 INFO - Running setup.py (path:/tmp/pip-pflFoy-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 23:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 23:00:03 INFO - Running setup.py (path:/tmp/pip-Sx3FKL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 23:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 23:00:03 INFO - Running setup.py (path:/tmp/pip-v0RyHK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 23:00:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 23:00:03 INFO - Running setup.py (path:/tmp/pip-TLEr3N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 23:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 23:00:04 INFO - Running setup.py (path:/tmp/pip-CTdzYD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 23:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 23:00:04 INFO - Running setup.py (path:/tmp/pip-NzmuKo-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 23:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 23:00:04 INFO - Running setup.py (path:/tmp/pip-2nFANZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 23:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 23:00:04 INFO - Running setup.py (path:/tmp/pip-soAidc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 23:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 23:00:04 INFO - Running setup.py (path:/tmp/pip-UQPx1m-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 23:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 23:00:04 INFO - Running setup.py (path:/tmp/pip-IpY6YL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 23:00:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 23:00:04 INFO - Running setup.py (path:/tmp/pip-fw_4JH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 23:00:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:00:04 INFO - Running setup.py install for manifestparser 23:00:04 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 23:00:04 INFO - Running setup.py install for mozcrash 23:00:04 INFO - Running setup.py install for mozdebug 23:00:04 INFO - Running setup.py install for mozdevice 23:00:04 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 23:00:04 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 23:00:04 INFO - Running setup.py install for mozfile 23:00:05 INFO - Running setup.py install for mozhttpd 23:00:05 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 23:00:05 INFO - Running setup.py install for mozinfo 23:00:05 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 23:00:05 INFO - Running setup.py install for mozInstall 23:00:05 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 23:00:05 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 23:00:05 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 23:00:05 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 23:00:05 INFO - Running setup.py install for mozleak 23:00:05 INFO - Running setup.py install for mozlog 23:00:05 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 23:00:05 INFO - Running setup.py install for moznetwork 23:00:05 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 23:00:05 INFO - Running setup.py install for mozprocess 23:00:05 INFO - Running setup.py install for mozprofile 23:00:05 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 23:00:05 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 23:00:05 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 23:00:05 INFO - Running setup.py install for mozrunner 23:00:06 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 23:00:06 INFO - Running setup.py install for mozscreenshot 23:00:06 INFO - Running setup.py install for moztest 23:00:06 INFO - Running setup.py install for mozversion 23:00:06 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 23:00:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:00:06 INFO - Cleaning up... 23:00:06 INFO - Return code: 0 23:00:06 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 23:00:06 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')]} 23:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:06 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')]} 23:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:06 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 0x7fb50a333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd21630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb2840>, '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 0xd1f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd1fb60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', '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 23:00:06 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 23:00:06 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 23:00:06 INFO - Using env: {'DISPLAY': ':0', 23:00:06 INFO - 'HOME': '/home/cltbld', 23:00:06 INFO - 'LANG': 'en_US.UTF-8', 23:00:06 INFO - 'LANGUAGE': 'en_US:en', 23:00:06 INFO - 'LOGNAME': 'cltbld', 23:00:06 INFO - 'MAIL': '/var/mail/cltbld', 23:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:06 INFO - 'MOZ_NO_REMOTE': '1', 23:00:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:06 INFO - 'NO_EM_RESTART': '1', 23:00:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:00:06 INFO - 'PWD': '/builds/slave/test-pgo', 23:00:06 INFO - 'SHELL': '/bin/bash', 23:00:06 INFO - 'SHLVL': '1', 23:00:06 INFO - 'TERM': 'linux', 23:00:06 INFO - 'TMOUT': '86400', 23:00:06 INFO - 'USER': 'cltbld', 23:00:06 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', 23:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:06 INFO - '_': '/tools/buildbot/bin/python'} 23:00:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 23:00:06 INFO - Running setup.py (path:/tmp/pip-UFnq73-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 23:00:06 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)) 23:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 23:00:06 INFO - Running setup.py (path:/tmp/pip-ALqP5Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 23:00:06 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)) 23:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 23:00:06 INFO - Running setup.py (path:/tmp/pip-KOn1jI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 23:00:06 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)) 23:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 23:00:06 INFO - Running setup.py (path:/tmp/pip-AvnnJH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 23:00:06 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)) 23:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 23:00:06 INFO - Running setup.py (path:/tmp/pip-0UDb5J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 23:00:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 23:00:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 23:00:06 INFO - Running setup.py (path:/tmp/pip-SgRS51-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 23:00:07 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)) 23:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 23:00:07 INFO - Running setup.py (path:/tmp/pip-N0IOOz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 23:00:07 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)) 23:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 23:00:07 INFO - Running setup.py (path:/tmp/pip-52mZmF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 23:00:07 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)) 23:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 23:00:07 INFO - Running setup.py (path:/tmp/pip-Jn4C2s-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 23:00:07 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)) 23:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 23:00:07 INFO - Running setup.py (path:/tmp/pip-kpN3Ql-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 23:00:07 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)) 23:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 23:00:07 INFO - Running setup.py (path:/tmp/pip-b_0YgN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 23:00:07 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)) 23:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 23:00:07 INFO - Running setup.py (path:/tmp/pip-NNpECN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 23:00:07 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)) 23:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 23:00:07 INFO - Running setup.py (path:/tmp/pip-yz02Gz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 23:00:07 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)) 23:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 23:00:07 INFO - Running setup.py (path:/tmp/pip-1NizxE-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 23:00:07 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)) 23:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 23:00:07 INFO - Running setup.py (path:/tmp/pip-dlvH_N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 23:00:07 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)) 23:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 23:00:07 INFO - Running setup.py (path:/tmp/pip-VE1TDc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 23:00:07 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)) 23:00:07 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 23:00:07 INFO - Running setup.py (path:/tmp/pip-I7b7MD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 23:00:07 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)) 23:00:07 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)) 23:00:07 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)) 23:00:07 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)) 23:00:07 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)) 23:00:07 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)) 23:00:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 23:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:09 INFO - Downloading blessings-1.5.1.tar.gz 23:00:09 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 23:00:09 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 23:00:09 INFO - Installing collected packages: blessings 23:00:09 INFO - Running setup.py install for blessings 23:00:09 INFO - Successfully installed blessings 23:00:09 INFO - Cleaning up... 23:00:09 INFO - Return code: 0 23:00:09 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 23:00:09 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 23:00:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 23:00:09 INFO - Reading from file tmpfile_stdout 23:00:09 INFO - Current package versions: 23:00:09 INFO - argparse == 1.2.1 23:00:09 INFO - blessings == 1.5.1 23:00:09 INFO - blobuploader == 1.2.4 23:00:09 INFO - docopt == 0.6.1 23:00:09 INFO - manifestparser == 1.1 23:00:09 INFO - mozInstall == 1.12 23:00:09 INFO - mozcrash == 0.16 23:00:09 INFO - mozdebug == 0.1 23:00:09 INFO - mozdevice == 0.47 23:00:09 INFO - mozfile == 1.2 23:00:09 INFO - mozhttpd == 0.7 23:00:09 INFO - mozinfo == 0.9 23:00:09 INFO - mozleak == 0.1 23:00:09 INFO - mozlog == 3.1 23:00:09 INFO - moznetwork == 0.27 23:00:09 INFO - mozprocess == 0.22 23:00:09 INFO - mozprofile == 0.28 23:00:09 INFO - mozrunner == 6.11 23:00:09 INFO - mozscreenshot == 0.1 23:00:09 INFO - mozsystemmonitor == 0.0 23:00:09 INFO - moztest == 0.7 23:00:09 INFO - mozversion == 1.4 23:00:09 INFO - psutil == 3.1.1 23:00:09 INFO - requests == 1.2.3 23:00:09 INFO - wsgiref == 0.1.2 23:00:09 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 23:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:09 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 0x7fb50a333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd21630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb2840>, '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 0xd1f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd1fb60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', '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 23:00:09 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 23:00:09 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 23:00:09 INFO - Using env: {'DISPLAY': ':0', 23:00:09 INFO - 'HOME': '/home/cltbld', 23:00:09 INFO - 'LANG': 'en_US.UTF-8', 23:00:09 INFO - 'LANGUAGE': 'en_US:en', 23:00:09 INFO - 'LOGNAME': 'cltbld', 23:00:09 INFO - 'MAIL': '/var/mail/cltbld', 23:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:09 INFO - 'MOZ_NO_REMOTE': '1', 23:00:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:09 INFO - 'NO_EM_RESTART': '1', 23:00:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:00:09 INFO - 'PWD': '/builds/slave/test-pgo', 23:00:09 INFO - 'SHELL': '/bin/bash', 23:00:09 INFO - 'SHLVL': '1', 23:00:09 INFO - 'TERM': 'linux', 23:00:09 INFO - 'TMOUT': '86400', 23:00:09 INFO - 'USER': 'cltbld', 23:00:09 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', 23:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:09 INFO - '_': '/tools/buildbot/bin/python'} 23:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:10 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)) 23:00:10 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)) 23:00:10 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)) 23:00:10 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)) 23:00:10 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)) 23:00:10 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)) 23:00:10 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)) 23:00:10 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)) 23:00:10 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)) 23:00:10 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)) 23:00:10 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)) 23:00:10 INFO - Cleaning up... 23:00:10 INFO - Return code: 0 23:00:10 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 23:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb50a333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd21630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb2840>, '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 0xd1f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd1fb60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', '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 23:00:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 23:00:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:00:10 INFO - Using env: {'DISPLAY': ':0', 23:00:10 INFO - 'HOME': '/home/cltbld', 23:00:10 INFO - 'LANG': 'en_US.UTF-8', 23:00:10 INFO - 'LANGUAGE': 'en_US:en', 23:00:10 INFO - 'LOGNAME': 'cltbld', 23:00:10 INFO - 'MAIL': '/var/mail/cltbld', 23:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:10 INFO - 'MOZ_NO_REMOTE': '1', 23:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:10 INFO - 'NO_EM_RESTART': '1', 23:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:00:10 INFO - 'PWD': '/builds/slave/test-pgo', 23:00:10 INFO - 'SHELL': '/bin/bash', 23:00:10 INFO - 'SHLVL': '1', 23:00:10 INFO - 'TERM': 'linux', 23:00:10 INFO - 'TMOUT': '86400', 23:00:10 INFO - 'USER': 'cltbld', 23:00:10 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', 23:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:10 INFO - '_': '/tools/buildbot/bin/python'} 23:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:10 INFO - Downloading/unpacking jsonschema 23:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:00:12 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 23:00:12 INFO - Downloading/unpacking functools32 (from jsonschema) 23:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:12 INFO - Downloading functools32-3.2.3-2.tar.gz 23:00:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 23:00:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 23:00:12 INFO - warning: no files found matching '*.txt' 23:00:12 INFO - no previously-included directories found matching 'build' 23:00:12 INFO - no previously-included directories found matching 'dist' 23:00:12 INFO - no previously-included directories found matching '.git*' 23:00:12 INFO - Installing collected packages: jsonschema, functools32 23:00:12 INFO - Running setup.py install for functools32 23:00:12 INFO - warning: no files found matching '*.txt' 23:00:12 INFO - no previously-included directories found matching 'build' 23:00:12 INFO - no previously-included directories found matching 'dist' 23:00:12 INFO - no previously-included directories found matching '.git*' 23:00:12 INFO - Successfully installed jsonschema functools32 23:00:12 INFO - Cleaning up... 23:00:12 INFO - Return code: 0 23:00:12 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 23:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:00:12 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb50a333f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xc518a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd21630>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdb2840>, '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 0xd1f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd1fb60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', '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 23:00:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 23:00:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:00:12 INFO - Using env: {'DISPLAY': ':0', 23:00:12 INFO - 'HOME': '/home/cltbld', 23:00:12 INFO - 'LANG': 'en_US.UTF-8', 23:00:12 INFO - 'LANGUAGE': 'en_US:en', 23:00:12 INFO - 'LOGNAME': 'cltbld', 23:00:12 INFO - 'MAIL': '/var/mail/cltbld', 23:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:12 INFO - 'MOZ_NO_REMOTE': '1', 23:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:12 INFO - 'NO_EM_RESTART': '1', 23:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:00:12 INFO - 'PWD': '/builds/slave/test-pgo', 23:00:12 INFO - 'SHELL': '/bin/bash', 23:00:12 INFO - 'SHLVL': '1', 23:00:12 INFO - 'TERM': 'linux', 23:00:12 INFO - 'TMOUT': '86400', 23:00:12 INFO - 'USER': 'cltbld', 23:00:12 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', 23:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:12 INFO - '_': '/tools/buildbot/bin/python'} 23:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:00:12 INFO - Downloading/unpacking flake8 23:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:14 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:00:14 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 23:00:14 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:14 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:00:14 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 23:00:14 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:14 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 23:00:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:00:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:00:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 23:00:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 23:00:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:00:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:00:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:00:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:00:15 INFO - no previously-included directories found matching 'docs/_build' 23:00:15 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:00:15 INFO - Running setup.py install for pep8 23:00:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:00:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:00:15 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:00:15 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:00:15 INFO - no previously-included directories found matching 'docs/_build' 23:00:15 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 23:00:15 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:00:15 INFO - Cleaning up... 23:00:15 INFO - Return code: 0 23:00:15 INFO - Running post-action listener: _resource_record_post_action 23:00:15 INFO - Running post-action listener: _start_resource_monitoring 23:00:15 INFO - Starting resource monitoring. 23:00:15 INFO - ##### 23:00:15 INFO - ##### Running install step. 23:00:15 INFO - ##### 23:00:15 INFO - Running pre-action listener: _resource_record_pre_action 23:00:15 INFO - Running main action method: install 23:00:15 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 23:00:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 23:00:15 INFO - Reading from file tmpfile_stdout 23:00:15 INFO - Detecting whether we're running mozinstall >=1.0... 23:00:15 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 23:00:15 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 23:00:15 INFO - Reading from file tmpfile_stdout 23:00:15 INFO - Output received: 23:00:15 INFO - Usage: mozinstall [options] installer 23:00:15 INFO - Options: 23:00:15 INFO - -h, --help show this help message and exit 23:00:15 INFO - -d DEST, --destination=DEST 23:00:15 INFO - Directory to install application into. [default: 23:00:15 INFO - "/builds/slave/test-pgo"] 23:00:15 INFO - --app=APP Application being installed. [default: firefox] 23:00:15 INFO - mkdir: /builds/slave/test-pgo/build/application 23:00:15 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'] 23:00:15 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 23:00:31 INFO - Reading from file tmpfile_stdout 23:00:31 INFO - Output received: 23:00:31 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 23:00:31 INFO - Running post-action listener: _resource_record_post_action 23:00:31 INFO - ##### 23:00:31 INFO - ##### Running run-tests step. 23:00:31 INFO - ##### 23:00:31 INFO - Running pre-action listener: _resource_record_pre_action 23:00:31 INFO - Running main action method: run_tests 23:00:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 23:00:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 23:00:31 INFO - Python 2.7.3 23:00:31 INFO - Return code: 0 23:00:31 INFO - grabbing minidump binary from tooltool 23:00: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')]} 23:00:31 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 0xdb2840>, '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 0xd1f6b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xd1fb60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 23:00:31 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 23:00:31 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 23:00:31 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:00:31 INFO - Return code: 0 23:00:31 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 23:00:31 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 23:00:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 23:00:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 23:00:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 23:00:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 23:00:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 23:00:32 INFO - Return code: 0 23:00:32 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/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 23:00:32 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/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-062 --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 23:00:32 INFO - Using env: {'DISPLAY': ':0', 23:00:32 INFO - 'HOME': '/home/cltbld', 23:00:32 INFO - 'LANG': 'en_US.UTF-8', 23:00:32 INFO - 'LANGUAGE': 'en_US:en', 23:00:32 INFO - 'LOGNAME': 'cltbld', 23:00:32 INFO - 'MAIL': '/var/mail/cltbld', 23:00:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 23:00:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 23:00:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:32 INFO - 'MOZ_NO_REMOTE': '1', 23:00:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 23:00:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:32 INFO - 'NO_EM_RESTART': '1', 23:00:32 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 23:00:32 INFO - 'PWD': '/builds/slave/test-pgo', 23:00:32 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 23:00:32 INFO - 'SHELL': '/bin/bash', 23:00:32 INFO - 'SHLVL': '1', 23:00:32 INFO - 'TERM': 'linux', 23:00:32 INFO - 'TMOUT': '86400', 23:00:32 INFO - 'USER': 'cltbld', 23:00:32 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356', 23:00:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:32 INFO - '_': '/tools/buildbot/bin/python'} 23:00:32 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/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--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 23:00:33 INFO - mozversion application_buildid: 20160109203238 23:00:33 INFO - mozversion application_changeset: 1764c03305e60d6b9bee7308670d237ddb7767a0 23:00:33 INFO - mozversion application_display_name: Nightly 23:00:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:00:33 INFO - mozversion application_name: Firefox 23:00:33 INFO - mozversion application_remotingname: firefox 23:00:33 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:00:33 INFO - mozversion application_vendor: Mozilla 23:00:33 INFO - mozversion application_version: 46.0a1 23:00:33 INFO - mozversion platform_buildid: 20160109203238 23:00:33 INFO - mozversion platform_changeset: 1764c03305e60d6b9bee7308670d237ddb7767a0 23:00:33 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:00:33 INFO - mozversion platform_version: 46.0a1 23:00:33 INFO - using testdate: 1452409233 23:00:33 INFO - actual date: 1452409233 23:00:33 INFO - starting webserver on 'localhost:41883' 23:00:33 INFO - SUITE-START | Running 3 tests 23:00:33 INFO - TEST-START | dromaeo_css 23:00:33 INFO - operating with platform_type : linux_ 23:00:33 INFO - Initialising browser for dromaeo_css test... 23:00:33 INFO - TEST-INFO | started process 3367 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoeclAO/profile http://localhost:41883/getInfo.html) 23:00:39 INFO - PROCESS | 3367 | __metrics Screen width/height:1600/1200 23:00:39 INFO - PROCESS | 3367 | colorDepth:24 23:00:39 INFO - PROCESS | 3367 | Browser inner width/height: 1024/697 23:00:39 INFO - PROCESS | 3367 | __metrics 23:00:39 INFO - PROCESS | 3367 | 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:00:39 INFO - PROCESS | 3367 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:00:40 INFO - TEST-INFO | 3367: exit 0 23:00:40 INFO - Browser initialized. 23:00:40 INFO - Running cycle 1/1 for dromaeo_css test... 23:00:40 INFO - TEST-INFO | started process 3465 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoeclAO/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:00:52 INFO - PROCESS | 3465 | RSS: Main: 149057536 23:00:52 INFO - PROCESS | 3465 | 23:01:44 INFO - PROCESS | 3465 | 1452409304424 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] 23:03:53 INFO - PROCESS | 3465 | Cycle 1(1): loaded http://localhost:41883/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41883/tests/dromaeo/cssquery-ext.html) 23:03:54 INFO - PROCESS | 3465 | RSS: Main: 238411776 23:03:54 INFO - PROCESS | 3465 | 23:06:56 INFO - PROCESS | 3465 | Cycle 1(1): loaded http://localhost:41883/tests/dromaeo/cssquery-ext.html (next: http://localhost:41883/tests/dromaeo/cssquery-jquery.html) 23:06:57 INFO - PROCESS | 3465 | RSS: Main: 239624192 23:06:57 INFO - PROCESS | 3465 | 23:09:59 INFO - PROCESS | 3465 | Cycle 1(1): loaded http://localhost:41883/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41883/tests/dromaeo/cssquery-mootools.html) 23:09:59 INFO - PROCESS | 3465 | RSS: Main: 227655680 23:09:59 INFO - PROCESS | 3465 | 23:13:01 INFO - PROCESS | 3465 | Cycle 1(1): loaded http://localhost:41883/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41883/tests/dromaeo/cssquery-prototype.html) 23:13:02 INFO - PROCESS | 3465 | RSS: Main: 216195072 23:13:02 INFO - PROCESS | 3465 | 23:16:04 INFO - PROCESS | 3465 | Cycle 1(1): loaded http://localhost:41883/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41883/tests/dromaeo/cssquery-yui.html) 23:16:05 INFO - PROCESS | 3465 | RSS: Main: 217387008 23:16:05 INFO - PROCESS | 3465 | 23:19:08 INFO - PROCESS | 3465 | Cycle 1(1): loaded http://localhost:41883/tests/dromaeo/cssquery-yui.html (next: http://localhost:41883/tests/dromaeo/cssquery-dojo.html) 23:19:08 INFO - PROCESS | 3465 | RSS: Main: 295374848 23:19:08 INFO - PROCESS | 3465 | 23:19:08 INFO - PROCESS | 3465 | __start_tp_report 23:19:08 INFO - PROCESS | 3465 | _x_x_mozilla_page_load 23:19:08 INFO - PROCESS | 3465 | _x_x_mozilla_page_load_details 23:19:08 INFO - PROCESS | 3465 | |i|pagename|runs| 23:19:08 INFO - PROCESS | 3465 | |0;dojo.html;3078.230442389403;3137.545055966886;3181.156993396749;3186.7131958123764;3197.5683282756827;4990.675606085602;5084.745762711859;5124.590032797372;5143.588512918966;5172.327597412336;5089.109405853979;5123.692578445296;5143.5113664201845;5208.583313334933;5238.476152384759;4631.907361852761;4671.88320291992;4752.405949256343;4839.879003024917;4930.654854160211;4833;4894.192458244385;4905.871649520613;4916.901661966758;4930.161872481669;4627.514111018345;4646.372739680134;4730.834420795256;4757.762111894392;4816.3016362627395;4640.814367425299;4662.277347011219;4662.743549104798;4683.531646835289;4686.53134686532;4644.814207431698;4703.176944034814;4724.881877953045;4738.7442327783165;4739.3838800955655;4905.779239934212;4924.901501969976;4975.527324904165;5015.623828212899;5054.064997975386;5418.972905135489;5419.078756611386;5423.566114710853;5433.809816656398;5473.233747275384;5446.183072539111;5448.673079615236;5452.073147564924;5456.836294911159;5466.562674986031;5065.214891691794;5072.594192464594;5163.870903227449;5170.25031370453;5191.480851914816;5584.078627026495;5598.944010559923;5606.663600183921;5638.661680299195;5653.406392328829;5401.567874570025;5404.189371594292;5404.729763511808;5407.134858422633;5419.160030195326;5398.217258497496;5419.674819510842;5456.836294911159;5483.835484935458;5491.670499770026;5555.472230138131;5559.610827242054;5567.498925096761;5572.136318870582;5596;5503.614746967673;5556.138798486241;5559.138333558305;5564.415736347625;5583.944160558423;4262.936055959163;4332.328489084197;4336.457942757155;4337.306031035019;4376.081022985209;3795.5824859266013;3858.82635281413;3873.4770805941403;3898.161895192547;3910.6284902934744;5015.14742493777;5022.397312322545;5036.597072234213;5049.2173713074535;5067.315912351859;5354.223637572531;5387.676739395649;5396.973015134978;5407.215953686693;5456.645318054393;4994.300797888299;5045.091883461012;5060.038592667382;5090.541851233407;5107.489251074863;4692.366530518405;4724.763761811964;4726.527347265246;4747.691400058985;4764.904701905943;5682.857928551819;5767.942320576824;5773.1051686988585;5781.248437703156;5839.708014599253;5400.324959380077;5443.863903402446;5452.645578037414;5518.558515318845;5523.861903452445;5678.68767217807;5781.4507621775865;5820.621659592282;5831.096180092092;5834.504067154339;5391.838244852661;5399.163129714901;5405.972970135282;5409.648372855751;5411.485908838654;5397.190421436816;5433.185022246536;5499.202615620793;5507.862303442285;5553.555715542828;5385.838424847261;5403.675779453245;5412.918806217831;5475.808346707846;5512.972435137799;5397.3523177218985;5414.918776218438;5437.700926448925;5443.564514838883;5449.1826226066405;5178.766955486937;5353.839384818461;5361.597880158925;5368.1679339702405;5414.512693857414;5377.139657654757;5411.269478620336;5438.45615438453;5458.044842152687;5486.231927530229;5323.1749078892835;5323.547498462674;5326.680399176062;5330.680159190306;5344.385395679391 23:19:08 INFO - PROCESS | 3465 | |1;ext.html;507.1935622360446;526.4656373780614;532.042323817129;539.7921800106959;552.7899398228673;27249.182524524247;27874.163775086756;34757.65242347576;35375.469367959435;35481.64518354816;38080.23839523208;38390.61609383905;38632.42051369225;38691.80654096733;38757.806210968905;8224.7943801405;8432.578371081452;8436.072032076467;8438.957805210965;8444.408891377607;7382.778516644509;7576.052993375828;9961.95019024904;10093.64672236472;10118.443485608288;9817.558209880572;9890.653827116052;9895.851562226573;9930.056644618786;9944.403335799876;7126.429885609191;7271.091113610799;9771.12059914604;9792.412455252708;9879.604815807359;7246.275372462765;7355.742549010813;7366.963165184194;9737.902620973771;9969.152622027137;30812.07563773068;31075.689243107507;31077.378452430825;31109.533356999662;31135.287559184235;3327.2513684421056;3390.627031026585;3689.7786132831848;3907.3943538751255;4010.057636455431;26737.73262267372;27005.921264524324;27351.45297094047;27460.588091178648;27699.86150069257;3662.359087159736;3663.9633603664156;3715.9442608360896;3824.9426258606145;3876.186000939778;264283;265782;267455;267681;268067;48655.27017094676;49656;54557;54578;55084.72457637686;59374.40625593775;59965.70017149974;60074;60076.69961650164;60179;13308.866911330762;13679.316034198251;13683.452661893612;13693.109947853358;13711.520096796565;9821.558029888673;9882.703518894445;9896.455694936847;9912.35569687968;9936.056074672895;17551.73672394915;17587.472375828747;17718;18387.816121838878;18418.35535756242;6882.902693787815;7306.342429181399;7307.5980821055355;7311.634418279064;7322.816929576803;776.8873513340534;813.4549851599443;925.9675911343205;942.6936245720168;992.692265397729;26784.33039173997;27198.1840544784;27311.649816864297;27394.5890811638;27534.173974780788;886.9955650221708;1044.7544826965657;1045.8012977534245;1108.8503052088026;1165.2542372881362;13985.58043258704;14122.929385353213;14378.928105359615;14487.927560362341;14582.197979111039;325725;329385;329990;330206;331215;48650.75674621675;48895;48927.75536122368;49027.26459103116;49127.50872491301;1141.9828702569469;1271.7265787855656;1289.7613941420868;1304.7520971015667;1305.053835968934;26072.8696356517;27255.72744272611;27323.453530928873;27396.58905116385;27441.588376173975;34462.13844653904;34618.65381346154;34622.480662789574;34705.65294347024;34716.13209669779;281799;288337;406612;416040;416052;31852.203694907814;31927.521087184174;31941;32005.6799432012;32011.839940801077;41312.38031429471;42017.36973945332;42058.7897060525;42332.36501452419;42869.57130428656;34898.73847937767;35506.822465887504;35763.64236357603;36048.81975590105;36752.448713269856;34079.4888076684;35131.824340878135;35470.822645886605;36534.26931461409;36560.72037478675;33149.33701326008;33406.498902515996;33999.32001359909;34008.489872652426;34095.82952085224;29502.262443439085;30966;31469;31534.369312613162;31600.841995789873;21773.564528709652;21840.563188735818;21908.23321183751;21965.121395144015;21998 23:19:08 INFO - PROCESS | 3465 | |2;jquery.html;360.6483678413547;362.6844645158711;363.1230578153759;364.8467643589694;366.7029705938192;13227.801582976263;13296.933515332434;15905;16401.75397369039;17247.913760431213;13679.726405471885;14405.855941440583;14818.851811481882;14827.703445931074;15982.360705571764;5288.682679039261;5471.452854714536;5479.726013699319;5497.120460726284;5497.835064948058;3980.2039592081555;4091.856785012525;5380.838574842742;5452.309783966964;5744.16709577111;5688.97155514224;5908.054711246202;5948.078047902561;5959.910601340962;5962.433568810978;5795.942040579604;5859.9414005860035;5860.560457965648;5871.677057761822;5882.882342353172;4737.099951009332;4920.212765957465;4927.187014142698;5208.895822083575;5215.556677682402;11656.358900260482;11887.28676279426;13127.40926658293;18284.54288642781;18297;1959.1379792891225;1987.28457755208;2058.763242227148;2066.917323307066;2126.0220298662634;8697.52163631;8704.782380440476;8831.735047948572;9113.362064655477;12157.939210303892;1666.9083200423972;1778.8399044085966;1788.4277031349973;1791.552111972007;1814.9001804900724;53472.465275347524;54871.9999999996;55942;56029;56053.719731401085;9719.514024298756;10010.49947502622;10022.448765317977;10026.899731002743;10125.139363154067;13087.869121308855;13180.209187448783;13200.273984930922;13233.272170030741;13239.139455935527;7107.6446177690905;7146.213916469184;7218;7219.8917016244795;7288.562686238736;6832.248452670202;6856.554323968926;6908.378245957788;6921.411680007256;6927.653617319113;3984.322665146932;4297.312430011246;4330.870073897788;4434.33484977256;4468.843590474317;1661.2109248107295;1708.0776380754503;1719.75063615775;1733.1247719901369;1766.2140347545476;872.1540106097076;887.7159309021051;923.2844545477309;966.9122237482832;995.5569771451779;13200.405981730653;14662.193579353238;15048.924755376152;15370.615734606725;16312.184390780414;865.4720620421538;874.152072489684;919.3059240273684;934.5700977550403;943.4292253186724;8914.153155450222;8929.196372326522;8931.19619234272;9032.23226025782;9158.95420522893;377527;379380;381754;435074;505399;69100.65449672719;69781;72074;72351.63824180845;73117.63441182867;1075.3118004477142;1076.3272954403499;1086.5002099034375;1093.6718984304837;1105.662772854271;13433.596992090253;14331.498397556035;14478.336429555924;14677.853221468076;14991.925040374728;29829.403411931205;30151;30564.694353057075;30652;30998.22504437407;35529;35952;36137.457938131476;37712.245755084194;39005.80497097496;6718.832029199114;6834.8633027340165;6945.409640180641;7000;7037;4460.665450091191;9357.953210233905;10388.688339349832;10487;10757.838632420677;8841.463414634198;9067.501287428991;9549.52252387364;9598.42409455435;9777.41335519871;9677.516124193902;9889.50552472388;9956.601735930482;10113.29206955506;10270.127039201452;9912.405255684682;10309.821100416291;10320.793584128425;10476.528556214836;10486.47567621631;7144.535605185646;7317.304856038668;7346.485745997729;7390.815229619303;7489.662965166472;8971.237444817263;9419.293552983416;9508.857367139637;9536.666216682383;9544.33189676716 23:19:08 INFO - PROCESS | 3465 | |3;mootools.html;1224.9203801752885;1264.4562837979665;1266.8099785032248;1293.049608537724;1745.0227872391463;6888.793336199916;7435.516691415061;7579.621018949044;7597.772066838003;7606.581638009914;7500.287472690085;7517.54894706318;7680.692772289101;7702;7715.382769378451;2245.4947636781676;2266.8073213776847;2267.6598510223434;2272.091163534583;2291.656251562271;2229.1640634761966;2261.050358849287;2272.227442669492;2275.7382900966354;2290.7480177180496;2241.316398498457;2253.0086761824746;2263.0608297556496;2263.3323169664986;2271.034810205656;2240.0703707961175;2287.359539328982;2294.3461113582716;2298.2186056740707;2298.9999350282596;2242.5963326601204;2243.9887800561055;2252.4594097416502;2253.4028482452163;2257.57106149831;7768.611569421506;7768.805779855492;7818.100918394401;7839.451238413313;7841.647125879292;4744.14605371033;4883.19427294496;4887.315775791392;4889.559939605417;4891.902161956741;3079.0916679579354;3133.107064486633;3149.102505785863;3164.9683503164906;3170.1123685368134;984.8473486609587;992.8609994600762;1037.5797801890246;1084.2247792828086;1090.3512410115968;10708.678739637824;10716.571337146504;10724.195685323553;10733.516991735469;10776.67669969902;4355.303151495746;4389.990302230441;4409.5149533551285;4420.138073075719;4467.5755803198645;9188.494632795127;9191.816163676824;9201.585928633227;9275.211607013345;9306.813863722688;4905.3132561441425;4941.43173535048;4943.876403089952;4948.4309304429735;4950.183219768698;3899.74651647642;3929.390944403622;4266.637335826427;4467.419235499429;4481.327800829901;3718.3121122592406;3778.3576791945434;3788.45067465216;3823.330917089498;3832.865849695247;1234.3643023842728;1236.3014896583402;1240.6898275431142;1246.052999720219;1250.8248845161684;1028.619410818002;1048.6015314180563;1057.0909018244304;1160.3675996581883;1316.3352506983913;7047.6828542714675;7221.350078492961;7260;7271.745488907863;7286.417086633057;476.8068932082512;479.7505297245413;497.8431793984883;499.7076710124545;501.73658829115004;5590.748416321276;5680.829575112753;5699.914501282484;5704.971475142681;5721.342045664802;8797.208251257418;9032.412893161923;9107.499087550248;9187.173154416134;9242.537873106317;8889.022207557164;8972.37193396456;8996.190342869042;9005.459672419676;9104;6961.721531138799;6972.267911869282;6992.545484543489;7053.964730176316;7088.574685518885;7132.821679458055;7212.459065570209;7212.4951253411755;7219.061522002106;7224.819379515344;8104.432689711663;8187.71343002992;8572.614232359683;8599.054104048675;8644.654213831376;8759.956200218958;8843.292536597186;8890.377673563047;8898.199162075443;8906.376553641441;6011.72947217385;6114.908276375769;6400.135981642418;6547.607143571401;6693.564918280296;7194.460415468965;7201.855962880608;7229.240929702306;7233.4574906883245;7293.708251669874;6613.735450582115;6632.071509988509;6668.533202675961;6692.933070669425;6908.1364829396325;6685.732570697312;6694.59832410057;6737.797866064026;6747.460203183831;6815.318468153145;6793.966030169818;6950.513464057467;6987.615681137383;7024.367806897404;7062.823429414306;6466.450351719968;6536.379043990813;6538.83652908662;6546.312637173219;6550.344965503412;6554.213494380705;6599.373059559334;6599.835004124935;6601.53789234749;6608.768693095911 23:19:08 INFO - PROCESS | 3465 | |4;prototype.html;331.48785126978817;335.1654380592326;335.3159554508802;340.3856039848074;343.8315225539486;5723.112917497784;5823.417658234174;5826.854328641781;5833.649981001137;5916.1125831125355;5608.270924779773;5756.798512052069;5797.362290148091;5803;5815.331236907757;4493.550644935497;4500.729956202638;4506.391637128979;4512.481064677555;4518.480374756912;4359.891002724926;4428.31361139024;4430.1804166229185;4489.753063581502;4500.212462818994;4447.444069491314;4448.911021779562;4473.373727678111;4477.126960242754;4512.458504979407;4488.730676159425;4510.233260345716;4516.751578663172;4518.88702782433;4520.3219517072375;3990.3615421532704;4000.4599379084034;4003.6396724294664;4011.478507793968;4014.036631208518;5442.836714898559;5565.276514053187;5568.470995255444;5611.80358687444;5626.690532020737;5257.185136303879;5258.316418865523;5263.421023687391;5273.789048438096;5277.287566178555;5940.910886336708;6215.720292586844;6216.968915155395;6218.782342617987;6220.968895155541;4268.445102136733;4320.783960801948;4328.848490302824;4338.869833904988;4343.587359200902;6297.87404251917;6476.9676151619415;6479.546431749779;6480.935190648081;6486.059521369375;4495.19086564415;4537.38745269391;4543.863684089482;4545.977270113628;4555.772211389483;4490.169318676056;4543.136804007228;4573.885652858705;4577.29051996941;4614.4693360264;5235.764390602432;5244.082285600005;5252.921206181911;5253.369595648546;5254.106801843695;6225.221847269091;6230.813075607739;6240.251169859646;6241.531885108635;6242.625442473466;1588.1820862255947;1604.646977664912;1605.1252067623172;1609.147152009437;1609.8068231812258;1064.3347907557777;1072.9967480405846;1074.532578328414;1099.9890001100046;1120.7422292872523;5821.359650438449;5822.272215973004;5825.417458254141;5826.563007774434;5830.2420685310635;6266.467350275278;6268.655223962634;6270.059491076375;6326.620402775849;6330.778422755181;5787.565932554991;5793.362730099686;5807.128930660435;5816.098504731774;5827.067669172912;3289.5723555937575;3395.049386171876;3429.0741499795413;3429.6741809528053;3435.3301106284034;6551.574147680385;6625.17185351831;6641.734330626818;6660.83347916306;6661.433778128913;6521.380468855451;6523.673816309166;6523.7716679915975;6528.281888992207;6530.967345163243;6483.3516648335735;6622.86754264912;6643.202815662152;6649.401553860079;6662.600243985376;6183.721732521957;6327.651979141191;6347.619142851444;6403.423691867568;6414.294427613052;6210.720517576629;6325.4939604832425;6326.841828954129;6367.458766004941;6396.840078998062;6716.429103526255;6721.596704197764;6727.73089076451;6733.42765864907;6743.35938085881;4257.659387248951;4417.624501917373;4435.1351486459835;4459.843905463294;4470.351798989063;6353.491720662429;6393.424591786761;6401.327860574759;6406.519511036598;6407.775727849503;6195.752169913153;6199.659018754011;6200.596961197508;6244.718987645476;6266.404691554295;6026.246719160105;6153.630782153085;6187.133801267736;6188.288346840171;6247.062940558771;6144.4777193937225;6146.969265153645;6148.231471066117;6157.84605384869;6159.6612186330185;6255.124282600531;6259.937400625936;6264.937350626436;6272.121902933502;6273.090401891609;5988.251468566429;6117.388261173847;6129.6322220665115;6131.938680613137;6135.570510064253 23:19:08 INFO - PROCESS | 3465 | |5;yui.html;748.5583505731621;776.4875182379627;815.6044318505526;820.1347578304886;829.7759604906676;12454.564090256843;12500.812487812678;12563.560275390364;12641.873581264184;12676.429560669769;12454.439550220239;13243.47026118954;13331.733365332688;13337.066405351628;13428;664.0238848892121;668.4151367553391;792.1919641965192;793.5397469467695;805.9798505037362;790.6639678136797;799.6081919859286;803.9477433966773;807.2573232625998;809.5304723260524;835.0897521701345;841.028611953192;842.010637500934;842.5450256861287;843.026304618164;685.1504134872747;692.6225207262054;818.643889907892;832.3590835056972;835.761807884756;617.9042248451498;618.0482057631287;619.1734035063196;619.2259675405743;620.5407998081432;12303.261804291682;12323.198992065576;12335.444904979298;12341.197822141532;12357.567485137977;11564.653060408202;11815.94092029543;11940;12036.63890083299;12163.148579599432;10243.743906402326;10244.94877525615;10440.895591044069;10562.049415552638;10619.787604247871;3567.1438854674952;3605.675489205958;3607.8917632471066;3629.764065335745;3635.5455568053994;44496.77751611221;44608.55391446076;44650;44985.325220121726;45063.54936450626;9596.712098637052;10104.545295461721;10241.795164096677;10258.846117308247;10267.281290309607;18227.17977691007;18266.26934922588;18294.908525457555;18295.63408731818;18330.63338733218;7279.963600182071;7348.70605175787;7436.776896693108;7619.771406857803;7832.882506762403;4193.895152621209;4208.074223670787;4213.641840443596;4229.577042295746;4285.528591854893;2840.091170825316;2875.856207189632;2878.4387044526534;2892.7251911068415;2902.0568315297614;523.557593833218;524.9133892907704;526.9025230332325;538.27064327836;543.8857839853675;589.1633879890566;597.6175247841385;609.8079105081885;615.3692962584985;674.6727836999052;7761.689532418753;7846.136924938252;7909;7918.920810791934;8084.434089613788;491.665667346208;551.9420460851633;648.3484098481091;656.5076192855358;660.3330636057616;3467.4278744006956;3653.50677658518;3661;3717.219383929405;3761.6426439488205;46884.296735549;47227.76386118116;47348;47499.762501187965;47657;42598;42739.35890961638;46634.06731865344;46944;46977.060458790635;42851.35722964158;43232.56767432348;43460.3480947786;43628.345574816405;43747;7568.621568921642;7573.659185336784;7978.880316795369;8180.263776260165;8536.786580335542;33669.15827104342;33694.326113478084;34626;35587;36159.45760813537;40638.39042414307;40660.79669601633;40718.59281407148;44647.77676111599;44800;10522;10754.677359679223;10859.548381318371;11309.886901131213;11332.546698131982;12440.937795310965;12503.56237531682;12679.112462127932;12681.492740290285;12744.74510509803;11712.941435292769;11974.101942354184;12002.879971200176;12490.625281241577;12522.812157817822;10053.798924021332;10305.74235644115;10624.787504249716;10645.574177032831;10676.359418434919;11381.772364552828;11656.708582285512;11900.107491937966;11923.880761192624;12326.137170397986;9118.635254589743;9125.680601178927;9279.907200927904;9759.219262458844;9970.152537034433;9032.187103160748;9189.908100918905;9241.21449676785;9301.860472092789;9869.062439068277 23:19:08 INFO - PROCESS | 3465 | __end_tp_report 23:19:08 INFO - PROCESS | 3465 | __start_cc_report 23:19:08 INFO - PROCESS | 3465 | _x_x_mozilla_cycle_collect,1004 23:19:08 INFO - PROCESS | 3465 | __end_cc_report 23:19:08 INFO - PROCESS | 3465 | __startTimestamp1452410348318__endTimestamp 23:19:08 INFO - PROCESS | 3465 | 23:19:08 INFO - PROCESS | 3465 | ------- Summary: start ------- 23:19:08 INFO - PROCESS | 3465 | Number of tests: 6 23:19:08 INFO - PROCESS | 3465 | 23:19:08 INFO - PROCESS | 3465 | [#0] dojo.html Cycles:165 Average:5123.25 Median:5354.22 stddev:544.25 (10.2%) stddev-sans-first:521.74 23:19:08 INFO - PROCESS | 3465 | Values: 3078.2 3137.5 3181.2 3186.7 3197.6 4990.7 5084.7 5124.6 5143.6 5172.3 5089.1 5123.7 5143.5 5208.6 5238.5 4631.9 4671.9 4752.4 4839.9 4930.7 4833.0 4894.2 4905.9 4916.9 4930.2 4627.5 4646.4 4730.8 4757.8 4816.3 4640.8 4662.3 4662.7 4683.5 4686.5 4644.8 4703.2 4724.9 4738.7 4739.4 4905.8 4924.9 4975.5 5015.6 5054.1 5419.0 5419.1 5423.6 5433.8 5473.2 5446.2 5448.7 5452.1 5456.8 5466.6 5065.2 5072.6 5163.9 5170.3 5191.5 5584.1 5598.9 5606.7 5638.7 5653.4 5401.6 5404.2 5404.7 5407.1 5419.2 5398.2 5419.7 5456.8 5483.8 5491.7 5555.5 5559.6 5567.5 5572.1 5596.0 5503.6 5556.1 5559.1 5564.4 5583.9 4262.9 4332.3 4336.5 4337.3 4376.1 3795.6 3858.8 3873.5 3898.2 3910.6 5015.1 5022.4 5036.6 5049.2 5067.3 5354.2 5387.7 5397.0 5407.2 5456.6 4994.3 5045.1 5060.0 5090.5 5107.5 4692.4 4724.8 4726.5 4747.7 4764.9 5682.9 5767.9 5773.1 5781.2 5839.7 5400.3 5443.9 5452.6 5518.6 5523.9 5678.7 5781.5 5820.6 5831.1 5834.5 5391.8 5399.2 5406.0 5409.6 5411.5 5397.2 5433.2 5499.2 5507.9 5553.6 5385.8 5403.7 5412.9 5475.8 5513.0 5397.4 5414.9 5437.7 5443.6 5449.2 5178.8 5353.8 5361.6 5368.2 5414.5 5377.1 5411.3 5438.5 5458.0 5486.2 5323.2 5323.5 5326.7 5330.7 5344.4 23:19:08 INFO - PROCESS | 3465 | 23:19:08 INFO - PROCESS | 3465 | [#1] ext.html Cycles:180 Average:46878.18 Median:35253.65 stddev:85242.35 (241.8%) stddev-sans-first:85410.38 23:19:08 INFO - PROCESS | 3465 | Values: 507.2 526.5 532.0 539.8 552.8 27249.2 27874.2 34757.7 35375.5 35481.6 38080.2 38390.6 38632.4 38691.8 38757.8 8224.8 8432.6 8436.1 8439.0 8444.4 7382.8 7576.1 9962.0 10093.6 10118.4 9817.6 9890.7 9895.9 9930.1 9944.4 7126.4 7271.1 9771.1 9792.4 9879.6 7246.3 7355.7 7367.0 9737.9 9969.2 30812.1 31075.7 31077.4 31109.5 31135.3 3327.3 3390.6 3689.8 3907.4 4010.1 26737.7 27005.9 27351.5 27460.6 27699.9 3662.4 3664.0 3715.9 3824.9 3876.2 264283.0 265782.0 267455.0 267681.0 268067.0 48655.3 49656.0 54557.0 54578.0 55084.7 59374.4 59965.7 60074.0 60076.7 60179.0 13308.9 13679.3 13683.5 13693.1 13711.5 9821.6 9882.7 9896.5 9912.4 9936.1 17551.7 17587.5 17718.0 18387.8 18418.4 6882.9 7306.3 7307.6 7311.6 7322.8 776.9 813.5 926.0 942.7 992.7 26784.3 27198.2 27311.6 27394.6 27534.2 887.0 1044.8 1045.8 1108.9 1165.3 13985.6 14122.9 14378.9 14487.9 14582.2 325725.0 329385.0 329990.0 330206.0 331215.0 48650.8 48895.0 48927.8 49027.3 49127.5 1142.0 1271.7 1289.8 1304.8 1305.1 26072.9 27255.7 27323.5 27396.6 27441.6 34462.1 34618.7 34622.5 34705.7 34716.1 281799.0 288337.0 406612.0 416040.0 416052.0 31852.2 31927.5 31941.0 32005.7 32011.8 41312.4 42017.4 42058.8 42332.4 42869.6 34898.7 35506.8 35763.6 36048.8 36752.4 34079.5 35131.8 35470.8 36534.3 36560.7 33149.3 33406.5 33999.3 34008.5 34095.8 29502.3 30966.0 31469.0 31534.4 31600.8 21773.6 21840.6 21908.2 21965.1 21998.0 23:19:08 INFO - PROCESS | 3465 | 23:19:08 INFO - PROCESS | 3465 | [#2] jquery.html Cycles:180 Average:23388.19 Median:4602.97 stddev:68609.90 (1490.6%) stddev-sans-first:68780.58 23:19:08 INFO - PROCESS | 3465 | Values: 360.6 362.7 363.1 364.8 366.7 13227.8 13296.9 15905.0 16401.8 17247.9 13679.7 14405.9 14818.9 14827.7 15982.4 5288.7 5471.5 5479.7 5497.1 5497.8 3980.2 4091.9 5380.8 5452.3 5744.2 5689.0 5908.1 5948.1 5959.9 5962.4 5795.9 5859.9 5860.6 5871.7 5882.9 4737.1 4920.2 4927.2 5208.9 5215.6 11656.4 11887.3 13127.4 18284.5 18297.0 1959.1 1987.3 2058.8 2066.9 2126.0 8697.5 8704.8 8831.7 9113.4 12157.9 1666.9 1778.8 1788.4 1791.6 1814.9 53472.5 54872.0 55942.0 56029.0 56053.7 9719.5 10010.5 10022.4 10026.9 10125.1 13087.9 13180.2 13200.3 13233.3 13239.1 7107.6 7146.2 7218.0 7219.9 7288.6 6832.2 6856.6 6908.4 6921.4 6927.7 3984.3 4297.3 4330.9 4434.3 4468.8 1661.2 1708.1 1719.8 1733.1 1766.2 872.2 887.7 923.3 966.9 995.6 13200.4 14662.2 15048.9 15370.6 16312.2 865.5 874.2 919.3 934.6 943.4 8914.2 8929.2 8931.2 9032.2 9159.0 377527.0 379380.0 381754.0 435074.0 505399.0 69100.7 69781.0 72074.0 72351.6 73117.6 1075.3 1076.3 1086.5 1093.7 1105.7 13433.6 14331.5 14478.3 14677.9 14991.9 29829.4 30151.0 30564.7 30652.0 30998.2 35529.0 35952.0 36137.5 37712.2 39005.8 6718.8 6834.9 6945.4 7000.0 7037.0 4460.7 9358.0 10388.7 10487.0 10757.8 8841.5 9067.5 9549.5 9598.4 9777.4 9677.5 9889.5 9956.6 10113.3 10270.1 9912.4 10309.8 10320.8 10476.5 10486.5 7144.5 7317.3 7346.5 7390.8 7489.7 8971.2 9419.3 9508.9 9536.7 9544.3 23:19:08 INFO - PROCESS | 3465 | 23:19:08 INFO - PROCESS | 3465 | [#3] mootools.html Cycles:180 Average:5397.34 Median:6433.29 stddev:2810.36 (43.7%) stddev-sans-first:2800.74 23:19:08 INFO - PROCESS | 3465 | Values: 1224.9 1264.5 1266.8 1293.0 1745.0 6888.8 7435.5 7579.6 7597.8 7606.6 7500.3 7517.5 7680.7 7702.0 7715.4 2245.5 2266.8 2267.7 2272.1 2291.7 2229.2 2261.1 2272.2 2275.7 2290.7 2241.3 2253.0 2263.1 2263.3 2271.0 2240.1 2287.4 2294.3 2298.2 2299.0 2242.6 2244.0 2252.5 2253.4 2257.6 7768.6 7768.8 7818.1 7839.5 7841.6 4744.1 4883.2 4887.3 4889.6 4891.9 3079.1 3133.1 3149.1 3165.0 3170.1 984.8 992.9 1037.6 1084.2 1090.4 10708.7 10716.6 10724.2 10733.5 10776.7 4355.3 4390.0 4409.5 4420.1 4467.6 9188.5 9191.8 9201.6 9275.2 9306.8 4905.3 4941.4 4943.9 4948.4 4950.2 3899.7 3929.4 4266.6 4467.4 4481.3 3718.3 3778.4 3788.5 3823.3 3832.9 1234.4 1236.3 1240.7 1246.1 1250.8 1028.6 1048.6 1057.1 1160.4 1316.3 7047.7 7221.4 7260.0 7271.7 7286.4 476.8 479.8 497.8 499.7 501.7 5590.7 5680.8 5699.9 5705.0 5721.3 8797.2 9032.4 9107.5 9187.2 9242.5 8889.0 8972.4 8996.2 9005.5 9104.0 6961.7 6972.3 6992.5 7054.0 7088.6 7132.8 7212.5 7212.5 7219.1 7224.8 8104.4 8187.7 8572.6 8599.1 8644.7 8760.0 8843.3 8890.4 8898.2 8906.4 6011.7 6114.9 6400.1 6547.6 6693.6 7194.5 7201.9 7229.2 7233.5 7293.7 6613.7 6632.1 6668.5 6692.9 6908.1 6685.7 6694.6 6737.8 6747.5 6815.3 6794.0 6950.5 6987.6 7024.4 7062.8 6466.5 6536.4 6538.8 6546.3 6550.3 6554.2 6599.4 6599.8 6601.5 6608.8 23:19:08 INFO - PROCESS | 3465 | 23:19:08 INFO - PROCESS | 3465 | [#4] prototype.html Cycles:180 Average:5195.92 Median:5821.82 stddev:1548.45 (26.6%) stddev-sans-first:1509.13 23:19:08 INFO - PROCESS | 3465 | Values: 331.5 335.2 335.3 340.4 343.8 5723.1 5823.4 5826.9 5833.6 5916.1 5608.3 5756.8 5797.4 5803.0 5815.3 4493.6 4500.7 4506.4 4512.5 4518.5 4359.9 4428.3 4430.2 4489.8 4500.2 4447.4 4448.9 4473.4 4477.1 4512.5 4488.7 4510.2 4516.8 4518.9 4520.3 3990.4 4000.5 4003.6 4011.5 4014.0 5442.8 5565.3 5568.5 5611.8 5626.7 5257.2 5258.3 5263.4 5273.8 5277.3 5940.9 6215.7 6217.0 6218.8 6221.0 4268.4 4320.8 4328.8 4338.9 4343.6 6297.9 6477.0 6479.5 6480.9 6486.1 4495.2 4537.4 4543.9 4546.0 4555.8 4490.2 4543.1 4573.9 4577.3 4614.5 5235.8 5244.1 5252.9 5253.4 5254.1 6225.2 6230.8 6240.3 6241.5 6242.6 1588.2 1604.6 1605.1 1609.1 1609.8 1064.3 1073.0 1074.5 1100.0 1120.7 5821.4 5822.3 5825.4 5826.6 5830.2 6266.5 6268.7 6270.1 6326.6 6330.8 5787.6 5793.4 5807.1 5816.1 5827.1 3289.6 3395.0 3429.1 3429.7 3435.3 6551.6 6625.2 6641.7 6660.8 6661.4 6521.4 6523.7 6523.8 6528.3 6531.0 6483.4 6622.9 6643.2 6649.4 6662.6 6183.7 6327.7 6347.6 6403.4 6414.3 6210.7 6325.5 6326.8 6367.5 6396.8 6716.4 6721.6 6727.7 6733.4 6743.4 4257.7 4417.6 4435.1 4459.8 4470.4 6353.5 6393.4 6401.3 6406.5 6407.8 6195.8 6199.7 6200.6 6244.7 6266.4 6026.2 6153.6 6187.1 6188.3 6247.1 6144.5 6147.0 6148.2 6157.8 6159.7 6255.1 6259.9 6264.9 6272.1 6273.1 5988.3 6117.4 6129.6 6131.9 6135.6 23:19:08 INFO - PROCESS | 3465 | 23:19:08 INFO - PROCESS | 3465 | [#5] yui.html Cycles:180 Average:12980.57 Median:43346.46 stddev:14346.34 (33.1%) stddev-sans-first:14357.18 23:19:08 INFO - PROCESS | 3465 | Values: 748.6 776.5 815.6 820.1 829.8 12454.6 12500.8 12563.6 12641.9 12676.4 12454.4 13243.5 13331.7 13337.1 13428.0 664.0 668.4 792.2 793.5 806.0 790.7 799.6 803.9 807.3 809.5 835.1 841.0 842.0 842.5 843.0 685.2 692.6 818.6 832.4 835.8 617.9 618.0 619.2 619.2 620.5 12303.3 12323.2 12335.4 12341.2 12357.6 11564.7 11815.9 11940.0 12036.6 12163.1 10243.7 10244.9 10440.9 10562.0 10619.8 3567.1 3605.7 3607.9 3629.8 3635.5 44496.8 44608.6 44650.0 44985.3 45063.5 9596.7 10104.5 10241.8 10258.8 10267.3 18227.2 18266.3 18294.9 18295.6 18330.6 7280.0 7348.7 7436.8 7619.8 7832.9 4193.9 4208.1 4213.6 4229.6 4285.5 2840.1 2875.9 2878.4 2892.7 2902.1 523.6 524.9 526.9 538.3 543.9 589.2 597.6 609.8 615.4 674.7 7761.7 7846.1 7909.0 7918.9 8084.4 491.7 551.9 648.3 656.5 660.3 3467.4 3653.5 3661.0 3717.2 3761.6 46884.3 47227.8 47348.0 47499.8 47657.0 42598.0 42739.4 46634.1 46944.0 46977.1 42851.4 43232.6 43460.3 43628.3 43747.0 7568.6 7573.7 7978.9 8180.3 8536.8 33669.2 33694.3 34626.0 35587.0 36159.5 40638.4 40660.8 40718.6 44647.8 44800.0 10522.0 10754.7 10859.5 11309.9 11332.5 12440.9 12503.6 12679.1 12681.5 12744.7 11712.9 11974.1 12002.9 12490.6 12522.8 10053.8 10305.7 10624.8 10645.6 10676.4 11381.8 11656.7 11900.1 11923.9 12326.1 9118.6 9125.7 9279.9 9759.2 9970.2 9032.2 9189.9 9241.2 9301.9 9869.1 23:19:08 INFO - PROCESS | 3465 | -------- Summary: end -------- 23:19:08 INFO - PROCESS | 3465 | 23:19:09 INFO - TEST-INFO | 3465: exit 0 23:19:09 INFO - TEST-OK | dromaeo_css | took 1115797ms 23:19:09 INFO - TEST-START | kraken 23:19:09 INFO - operating with platform_type : linux_ 23:19:09 INFO - Initialising browser for kraken test... 23:19:09 INFO - TEST-INFO | started process 3661 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoqGESh/profile http://localhost:41883/getInfo.html) 23:19:13 INFO - PROCESS | 3661 | __metrics Screen width/height:1600/1200 23:19:13 INFO - PROCESS | 3661 | colorDepth:24 23:19:13 INFO - PROCESS | 3661 | Browser inner width/height: 1024/697 23:19:13 INFO - PROCESS | 3661 | __metrics 23:19:13 INFO - PROCESS | 3661 | 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:19:13 INFO - PROCESS | 3661 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:19:14 INFO - TEST-INFO | 3661: exit 0 23:19:14 INFO - Browser initialized. 23:19:14 INFO - Running cycle 1/1 for kraken test... 23:19:14 INFO - TEST-INFO | started process 3744 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoqGESh/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:19:26 INFO - PROCESS | 3744 | RSS: Main: 149221376 23:19:26 INFO - PROCESS | 3744 | 23:19:28 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41883/tests/kraken/driver.html?testName=audio-beat-detection) 23:19:29 INFO - PROCESS | 3744 | RSS: Main: 188837888 23:19:29 INFO - PROCESS | 3744 | 23:19:31 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41883/tests/kraken/driver.html?testName=audio-dft) 23:19:32 INFO - PROCESS | 3744 | RSS: Main: 176611328 23:19:32 INFO - PROCESS | 3744 | 23:19:35 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41883/tests/kraken/driver.html?testName=audio-fft) 23:19:35 INFO - PROCESS | 3744 | RSS: Main: 160665600 23:19:35 INFO - PROCESS | 3744 | 23:19:37 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41883/tests/kraken/driver.html?testName=audio-oscillator) 23:19:38 INFO - PROCESS | 3744 | RSS: Main: 162754560 23:19:38 INFO - PROCESS | 3744 | 23:19:40 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41883/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:19:41 INFO - PROCESS | 3744 | RSS: Main: 163872768 23:19:41 INFO - PROCESS | 3744 | 23:19:44 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41883/tests/kraken/driver.html?testName=imaging-darkroom) 23:19:44 INFO - PROCESS | 3744 | RSS: Main: 164306944 23:19:44 INFO - PROCESS | 3744 | 23:19:48 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41883/tests/kraken/driver.html?testName=imaging-desaturate) 23:19:48 INFO - PROCESS | 3744 | RSS: Main: 165916672 23:19:48 INFO - PROCESS | 3744 | 23:19:51 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41883/tests/kraken/driver.html?testName=json-parse-financial) 23:19:52 INFO - PROCESS | 3744 | RSS: Main: 166100992 23:19:52 INFO - PROCESS | 3744 | 23:19:53 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41883/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:19:54 INFO - PROCESS | 3744 | RSS: Main: 177856512 23:19:54 INFO - PROCESS | 3744 | 23:19:55 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41883/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:19:56 INFO - PROCESS | 3744 | RSS: Main: 176930816 23:19:56 INFO - PROCESS | 3744 | 23:19:59 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41883/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:19:59 INFO - PROCESS | 3744 | RSS: Main: 184430592 23:19:59 INFO - PROCESS | 3744 | 23:20:01 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41883/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:20:02 INFO - PROCESS | 3744 | RSS: Main: 191975424 23:20:02 INFO - PROCESS | 3744 | 23:20:04 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41883/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:20:05 INFO - PROCESS | 3744 | RSS: Main: 191434752 23:20:05 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | Cycle 1(1): loaded http://localhost:41883/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41883/tests/kraken/driver.html?testName=ai-astar) 23:20:07 INFO - PROCESS | 3744 | RSS: Main: 247869440 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | __start_tp_report 23:20:07 INFO - PROCESS | 3744 | _x_x_mozilla_page_load 23:20:07 INFO - PROCESS | 3744 | _x_x_mozilla_page_load_details 23:20:07 INFO - PROCESS | 3744 | |i|pagename|runs| 23:20:07 INFO - PROCESS | 3744 | |0;ai-astar;110;112;107;111;112;112;100;110;110;117 23:20:07 INFO - PROCESS | 3744 | |1;audio-beat-detection;156;138;150;171;123;153;147;140;169;126 23:20:07 INFO - PROCESS | 3744 | |2;audio-dft;161;160;161;159;160;163;162;163;162;163 23:20:07 INFO - PROCESS | 3744 | |3;audio-fft;72;73;89;97;74;72;74;110;94;71 23:20:07 INFO - PROCESS | 3744 | |4;audio-oscillator;97;98;138;95;96;96;142;97;98;138 23:20:07 INFO - PROCESS | 3744 | |5;imaging-gaussian-blur;110;110;110;109;110;109;110;111;109;109 23:20:07 INFO - PROCESS | 3744 | |6;imaging-darkroom;146;146;146;146;147;146;146;146;146;146 23:20:07 INFO - PROCESS | 3744 | |7;imaging-desaturate;96;96;96;96;97;96;96;96;96;96 23:20:07 INFO - PROCESS | 3744 | |8;json-parse-financial;55;56;55;55;54;54;57;63;53;55 23:20:07 INFO - PROCESS | 3744 | |9;json-stringify-tinderbox;69;69;70;70;69;73;69;69;76;70 23:20:07 INFO - PROCESS | 3744 | |10;stanford-crypto-aes;88;86;86;87;85;100;87;87;90;95 23:20:07 INFO - PROCESS | 3744 | |11;stanford-crypto-ccm;119;121;127;122;134;119;117;121;120;123 23:20:07 INFO - PROCESS | 3744 | |12;stanford-crypto-pbkdf2;156;152;152;151;152;140;153;151;151;139 23:20:07 INFO - PROCESS | 3744 | |13;stanford-crypto-sha256-iterative;67;68;67;69;67;66;71;71;72;68 23:20:07 INFO - PROCESS | 3744 | __end_tp_report 23:20:07 INFO - PROCESS | 3744 | __start_cc_report 23:20:07 INFO - PROCESS | 3744 | _x_x_mozilla_cycle_collect,966 23:20:07 INFO - PROCESS | 3744 | __end_cc_report 23:20:07 INFO - PROCESS | 3744 | __startTimestamp1452410407378__endTimestamp 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | ------- Summary: start ------- 23:20:07 INFO - PROCESS | 3744 | Number of tests: 14 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#0] ai-astar Cycles:10 Average:110.10 Median:111.50 stddev:4.36 (3.9%) stddev-sans-first:4.62 23:20:07 INFO - PROCESS | 3744 | Values: 110.0 112.0 107.0 111.0 112.0 112.0 100.0 110.0 110.0 117.0 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#1] audio-beat-detection Cycles:10 Average:147.30 Median:151.50 stddev:16.10 (10.6%) stddev-sans-first:16.76 23:20:07 INFO - PROCESS | 3744 | Values: 156.0 138.0 150.0 171.0 123.0 153.0 147.0 140.0 169.0 126.0 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:1.43 (0.9%) stddev-sans-first:1.51 23:20:07 INFO - PROCESS | 3744 | Values: 161.0 160.0 161.0 159.0 160.0 163.0 162.0 163.0 162.0 163.0 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#3] audio-fft Cycles:10 Average:82.60 Median:74.00 stddev:13.86 (18.7%) stddev-sans-first:14.16 23:20:07 INFO - PROCESS | 3744 | Values: 72.0 73.0 89.0 97.0 74.0 72.0 74.0 110.0 94.0 71.0 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#4] audio-oscillator Cycles:10 Average:109.50 Median:96.50 stddev:20.64 (21.4%) stddev-sans-first:21.39 23:20:07 INFO - PROCESS | 3744 | Values: 97.0 98.0 138.0 95.0 96.0 96.0 142.0 97.0 98.0 138.0 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#5] imaging-gaussian-blur Cycles:10 Average:109.70 Median:110.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 23:20:07 INFO - PROCESS | 3744 | Values: 110.0 110.0 110.0 109.0 110.0 109.0 110.0 111.0 109.0 109.0 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.32 (0.2%) stddev-sans-first:0.33 23:20:07 INFO - PROCESS | 3744 | Values: 146.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 23:20:07 INFO - PROCESS | 3744 | Values: 96.0 96.0 96.0 96.0 97.0 96.0 96.0 96.0 96.0 96.0 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:55.00 stddev:2.79 (5.1%) stddev-sans-first:2.95 23:20:07 INFO - PROCESS | 3744 | Values: 55.0 56.0 55.0 55.0 54.0 54.0 57.0 63.0 53.0 55.0 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:70.00 stddev:2.32 (3.3%) stddev-sans-first:2.40 23:20:07 INFO - PROCESS | 3744 | Values: 69.0 69.0 70.0 70.0 69.0 73.0 69.0 69.0 76.0 70.0 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:87.00 stddev:4.77 (5.5%) stddev-sans-first:5.04 23:20:07 INFO - PROCESS | 3744 | Values: 88.0 86.0 86.0 87.0 85.0 100.0 87.0 87.0 90.0 95.0 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#11] stanford-crypto-ccm Cycles:10 Average:122.30 Median:121.50 stddev:4.92 (4.1%) stddev-sans-first:5.07 23:20:07 INFO - PROCESS | 3744 | Values: 119.0 121.0 127.0 122.0 134.0 119.0 117.0 121.0 120.0 123.0 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:149.70 Median:152.00 stddev:5.58 (3.7%) stddev-sans-first:5.43 23:20:07 INFO - PROCESS | 3744 | Values: 156.0 152.0 152.0 151.0 152.0 140.0 153.0 151.0 151.0 139.0 23:20:07 INFO - PROCESS | 3744 | 23:20:07 INFO - PROCESS | 3744 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:68.50 stddev:2.07 (3.0%) stddev-sans-first:2.11 23:20:07 INFO - PROCESS | 3744 | Values: 67.0 68.0 67.0 69.0 67.0 66.0 71.0 71.0 72.0 68.0 23:20:07 INFO - PROCESS | 3744 | -------- Summary: end -------- 23:20:07 INFO - PROCESS | 3744 | 23:20:08 INFO - TEST-INFO | 3744: exit 0 23:20:08 INFO - TEST-OK | kraken | took 59191ms 23:20:08 INFO - TEST-START | v8_7 23:20:08 INFO - operating with platform_type : linux_ 23:20:08 INFO - Initialising browser for v8_7 test... 23:20:08 INFO - TEST-INFO | started process 3993 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmMQ11z/profile http://localhost:41883/getInfo.html) 23:20:12 INFO - PROCESS | 3993 | __metrics Screen width/height:1600/1200 23:20:12 INFO - PROCESS | 3993 | colorDepth:24 23:20:12 INFO - PROCESS | 3993 | Browser inner width/height: 1024/697 23:20:12 INFO - PROCESS | 3993 | __metrics 23:20:12 INFO - PROCESS | 3993 | 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:20:12 INFO - PROCESS | 3993 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:20:13 INFO - TEST-INFO | 3993: exit 0 23:20:13 INFO - Browser initialized. 23:20:13 INFO - Running cycle 1/1 for v8_7 test... 23:20:13 INFO - TEST-INFO | started process 4074 (/builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpmMQ11z/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:20:25 INFO - PROCESS | 4074 | RSS: Main: 148975616 23:20:25 INFO - PROCESS | 4074 | 23:20:47 INFO - PROCESS | 4074 | Cycle 1(1): loaded http://localhost:41883/tests/v8_7/run.html (next: http://localhost:41883/tests/v8_7/run.html) 23:20:47 INFO - PROCESS | 4074 | RSS: Main: 564158464 23:20:47 INFO - PROCESS | 4074 | 23:20:47 INFO - PROCESS | 4074 | __start_tp_report 23:20:47 INFO - PROCESS | 4074 | _x_x_mozilla_page_load 23:20:47 INFO - PROCESS | 4074 | _x_x_mozilla_page_load_details 23:20:47 INFO - PROCESS | 4074 | |i|pagename|runs| 23:20:47 INFO - PROCESS | 4074 | |0;Richards;167.9261125104954 23:20:47 INFO - PROCESS | 4074 | |1;DeltaBlue;199.20318725099602 23:20:47 INFO - PROCESS | 4074 | |2;Encrypt;288.2675122513693 23:20:47 INFO - PROCESS | 4074 | |3;Decrypt;5196.891191709845 23:20:47 INFO - PROCESS | 4074 | |4;RayTrace;1968.5039370078741 23:20:47 INFO - PROCESS | 4074 | |5;Earley;801.924619085806 23:20:47 INFO - PROCESS | 4074 | |6;Boyer;12108.43373493976 23:20:47 INFO - PROCESS | 4074 | |7;RegExp;43250 23:20:47 INFO - PROCESS | 4074 | |8;Splay;561.7283950617284 23:20:47 INFO - PROCESS | 4074 | |9;NavierStokes;4854.368932038835 23:20:47 INFO - PROCESS | 4074 | __end_tp_report 23:20:47 INFO - PROCESS | 4074 | __startTimestamp1452410447928__endTimestamp 23:20:47 INFO - PROCESS | 4074 | 23:20:47 INFO - PROCESS | 4074 | ------- Summary: start ------- 23:20:47 INFO - PROCESS | 4074 | Number of tests: 10 23:20:47 INFO - PROCESS | 4074 | 23:20:47 INFO - PROCESS | 4074 | [#0] Richards Cycles:1 Average:167.93 Median:167.93 stddev:0.00 (0.0%) 23:20:47 INFO - PROCESS | 4074 | Values: 167.9 23:20:47 INFO - PROCESS | 4074 | 23:20:47 INFO - PROCESS | 4074 | [#1] DeltaBlue Cycles:1 Average:199.20 Median:199.20 stddev:0.00 (0.0%) 23:20:47 INFO - PROCESS | 4074 | Values: 199.2 23:20:47 INFO - PROCESS | 4074 | 23:20:47 INFO - PROCESS | 4074 | [#2] Encrypt Cycles:1 Average:288.27 Median:288.27 stddev:0.00 (0.0%) 23:20:47 INFO - PROCESS | 4074 | Values: 288.3 23:20:47 INFO - PROCESS | 4074 | 23:20:47 INFO - PROCESS | 4074 | [#3] Decrypt Cycles:1 Average:5196.89 Median:5196.89 stddev:0.00 (0.0%) 23:20:47 INFO - PROCESS | 4074 | Values: 5196.9 23:20:47 INFO - PROCESS | 4074 | 23:20:47 INFO - PROCESS | 4074 | [#4] RayTrace Cycles:1 Average:1968.50 Median:1968.50 stddev:0.00 (0.0%) 23:20:47 INFO - PROCESS | 4074 | Values: 1968.5 23:20:47 INFO - PROCESS | 4074 | 23:20:47 INFO - PROCESS | 4074 | [#5] Earley Cycles:1 Average:801.92 Median:801.92 stddev:0.00 (0.0%) 23:20:47 INFO - PROCESS | 4074 | Values: 801.9 23:20:47 INFO - PROCESS | 4074 | 23:20:47 INFO - PROCESS | 4074 | [#6] Boyer Cycles:1 Average:12108.43 Median:12108.43 stddev:0.00 (0.0%) 23:20:47 INFO - PROCESS | 4074 | Values: 12108.4 23:20:47 INFO - PROCESS | 4074 | 23:20:47 INFO - PROCESS | 4074 | [#7] RegExp Cycles:1 Average:43250.00 Median:43250.00 stddev:0.00 (0.0%) 23:20:47 INFO - PROCESS | 4074 | Values: 43250.0 23:20:47 INFO - PROCESS | 4074 | 23:20:47 INFO - PROCESS | 4074 | [#8] Splay Cycles:1 Average:561.73 Median:561.73 stddev:0.00 (0.0%) 23:20:47 INFO - PROCESS | 4074 | Values: 561.7 23:20:47 INFO - PROCESS | 4074 | 23:20:47 INFO - PROCESS | 4074 | [#9] NavierStokes Cycles:1 Average:4854.37 Median:4854.37 stddev:0.00 (0.0%) 23:20:47 INFO - PROCESS | 4074 | Values: 4854.4 23:20:47 INFO - PROCESS | 4074 | -------- Summary: end -------- 23:20:47 INFO - PROCESS | 4074 | 23:20:48 INFO - TEST-INFO | 4074: exit 0 23:20:48 INFO - TEST-OK | v8_7 | took 40606ms 23:20:48 INFO - SUITE-END | took 1215s 23:20:48 INFO - Completed test suite (00:20:15) 23:20:48 INFO - Working with test: dromaeo_css 23:20:48 INFO - Generating results file: dromaeo_css 23:20:48 INFO - Working with test: kraken 23:20:48 INFO - Generating results file: kraken 23:20:48 INFO - javascript benchmark 23:20:48 INFO - Working with test: v8_7 23:20:48 INFO - Generating results file: v8_7 23:20:48 INFO - Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:20:49 INFO - process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 23:20:49 INFO - process_Request line: dromaeo_css 6648.69 graph.html#tests=[[72,64,35]] 23:20:49 INFO - Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:20:49 INFO - process_Request line: kraken 1518.60 graph.html#tests=[[232,64,35]] 23:20:49 INFO - Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 23:20:49 INFO - process_Request line: v8_7 18826.16 graph.html#tests=[[230,64,35]] 23:20:49 INFO - RETURN: dromaeo_css: 6648.69 23:20:49 INFO - RETURN: kraken: 1518.60 23:20:49 INFO - RETURN: v8_7: 18826.16 23:20:49 INFO - javascript benchmark 23:20:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3078.230442389403, 3137.545055966886, 3181.156993396749, 3186.7131958123764, 3197.5683282756827, 4990.675606085602, 5084.745762711859, 5124.590032797372, 5143.588512918966, 5172.327597412336, 5089.109405853979, 5123.692578445296, 5143.5113664201845, 5208.583313334933, 5238.476152384759, 4631.907361852761, 4671.88320291992, 4752.405949256343, 4839.879003024917, 4930.654854160211, 4833.0, 4894.192458244385, 4905.871649520613, 4916.901661966758, 4930.161872481669, 4627.514111018345, 4646.372739680134, 4730.834420795256, 4757.762111894392, 4816.3016362627395, 4640.814367425299, 4662.277347011219, 4662.743549104798, 4683.531646835289, 4686.53134686532, 4644.814207431698, 4703.176944034814, 4724.881877953045, 4738.7442327783165, 4739.3838800955655, 4905.779239934212, 4924.901501969976, 4975.527324904165, 5015.623828212899, 5054.064997975386, 5418.972905135489, 5419.078756611386, 5423.566114710853, 5433.809816656398, 5473.233747275384, 5446.183072539111, 5448.673079615236, 5452.073147564924, 5456.836294911159, 5466.562674986031, 5065.214891691794, 5072.594192464594, 5163.870903227449, 5170.25031370453, 5191.480851914816, 5584.078627026495, 5598.944010559923, 5606.663600183921, 5638.661680299195, 5653.406392328829, 5401.567874570025, 5404.189371594292, 5404.729763511808, 5407.134858422633, 5419.160030195326, 5398.217258497496, 5419.674819510842, 5456.836294911159, 5483.835484935458, 5491.670499770026, 5555.472230138131, 5559.610827242054, 5567.498925096761, 5572.136318870582, 5596.0, 5503.614746967673, 5556.138798486241, 5559.138333558305, 5564.415736347625, 5583.944160558423, 4262.936055959163, 4332.328489084197, 4336.457942757155, 4337.306031035019, 4376.081022985209, 3795.5824859266013, 3858.82635281413, 3873.4770805941403, 3898.161895192547, 3910.6284902934744, 5015.14742493777, 5022.397312322545, 5036.597072234213, 5049.2173713074535, 5067.315912351859, 5354.223637572531, 5387.676739395649, 5396.973015134978, 5407.215953686693, 5456.645318054393, 4994.300797888299, 5045.091883461012, 5060.038592667382, 5090.541851233407, 5107.489251074863, 4692.366530518405, 4724.763761811964, 4726.527347265246, 4747.691400058985, 4764.904701905943, 5682.857928551819, 5767.942320576824, 5773.1051686988585, 5781.248437703156, 5839.708014599253, 5400.324959380077, 5443.863903402446, 5452.645578037414, 5518.558515318845, 5523.861903452445, 5678.68767217807, 5781.4507621775865, 5820.621659592282, 5831.096180092092, 5834.504067154339, 5391.838244852661, 5399.163129714901, 5405.972970135282, 5409.648372855751, 5411.485908838654, 5397.190421436816, 5433.185022246536, 5499.202615620793, 5507.862303442285, 5553.555715542828, 5385.838424847261, 5403.675779453245, 5412.918806217831, 5475.808346707846, 5512.972435137799, 5397.3523177218985, 5414.918776218438, 5437.700926448925, 5443.564514838883, 5449.1826226066405, 5178.766955486937, 5353.839384818461, 5361.597880158925, 5368.1679339702405, 5414.512693857414, 5377.139657654757, 5411.269478620336, 5438.45615438453, 5458.044842152687, 5486.231927530229, 5323.1749078892835, 5323.547498462674, 5326.680399176062, 5330.680159190306, 5344.385395679391], "unit": "score", "name": "dojo.html", "value": 5089.814809320238}, {"lowerIsBetter": false, "replicates": [507.1935622360446, 526.4656373780614, 532.042323817129, 539.7921800106959, 552.7899398228673, 27249.182524524247, 27874.163775086756, 34757.65242347576, 35375.469367959435, 35481.64518354816, 38080.23839523208, 38390.61609383905, 38632.42051369225, 38691.80654096733, 38757.806210968905, 8224.7943801405, 8432.578371081452, 8436.072032076467, 8438.957805210965, 8444.408891377607, 7382.778516644509, 7576.052993375828, 9961.95019024904, 10093.64672236472, 10118.443485608288, 9817.558209880572, 9890.653827116052, 9895.851562226573, 9930.056644618786, 9944.403335799876, 7126.429885609191, 7271.091113610799, 9771.12059914604, 9792.412455252708, 9879.604815807359, 7246.275372462765, 7355.742549010813, 7366.963165184194, 9737.902620973771, 9969.152622027137, 30812.07563773068, 31075.689243107507, 31077.378452430825, 31109.533356999662, 31135.287559184235, 3327.2513684421056, 3390.627031026585, 3689.7786132831848, 3907.3943538751255, 4010.057636455431, 26737.73262267372, 27005.921264524324, 27351.45297094047, 27460.588091178648, 27699.86150069257, 3662.359087159736, 3663.9633603664156, 3715.9442608360896, 3824.9426258606145, 3876.186000939778, 264283.0, 265782.0, 267455.0, 267681.0, 268067.0, 48655.27017094676, 49656.0, 54557.0, 54578.0, 55084.72457637686, 59374.40625593775, 59965.70017149974, 60074.0, 60076.69961650164, 60179.0, 13308.866911330762, 13679.316034198251, 13683.452661893612, 13693.109947853358, 13711.520096796565, 9821.558029888673, 9882.703518894445, 9896.455694936847, 9912.35569687968, 9936.056074672895, 17551.73672394915, 17587.472375828747, 17718.0, 18387.816121838878, 18418.35535756242, 6882.902693787815, 7306.342429181399, 7307.5980821055355, 7311.634418279064, 7322.816929576803, 776.8873513340534, 813.4549851599443, 925.9675911343205, 942.6936245720168, 992.692265397729, 26784.33039173997, 27198.1840544784, 27311.649816864297, 27394.5890811638, 27534.173974780788, 886.9955650221708, 1044.7544826965657, 1045.8012977534245, 1108.8503052088026, 1165.2542372881362, 13985.58043258704, 14122.929385353213, 14378.928105359615, 14487.927560362341, 14582.197979111039, 325725.0, 329385.0, 329990.0, 330206.0, 331215.0, 48650.75674621675, 48895.0, 48927.75536122368, 49027.26459103116, 49127.50872491301, 1141.9828702569469, 1271.7265787855656, 1289.7613941420868, 1304.7520971015667, 1305.053835968934, 26072.8696356517, 27255.72744272611, 27323.453530928873, 27396.58905116385, 27441.588376173975, 34462.13844653904, 34618.65381346154, 34622.480662789574, 34705.65294347024, 34716.13209669779, 281799.0, 288337.0, 406612.0, 416040.0, 416052.0, 31852.203694907814, 31927.521087184174, 31941.0, 32005.6799432012, 32011.839940801077, 41312.38031429471, 42017.36973945332, 42058.7897060525, 42332.36501452419, 42869.57130428656, 34898.73847937767, 35506.822465887504, 35763.64236357603, 36048.81975590105, 36752.448713269856, 34079.4888076684, 35131.824340878135, 35470.822645886605, 36534.26931461409, 36560.72037478675, 33149.33701326008, 33406.498902515996, 33999.32001359909, 34008.489872652426, 34095.82952085224, 29502.262443439085, 30966.0, 31469.0, 31534.369312613162, 31600.841995789873, 21773.564528709652, 21840.563188735818, 21908.23321183751, 21965.121395144015, 21998.0], "unit": "score", "name": "ext.html", "value": 17539.689933019392}, {"lowerIsBetter": false, "replicates": [360.6483678413547, 362.6844645158711, 363.1230578153759, 364.8467643589694, 366.7029705938192, 13227.801582976263, 13296.933515332434, 15905.0, 16401.75397369039, 17247.913760431213, 13679.726405471885, 14405.855941440583, 14818.851811481882, 14827.703445931074, 15982.360705571764, 5288.682679039261, 5471.452854714536, 5479.726013699319, 5497.120460726284, 5497.835064948058, 3980.2039592081555, 4091.856785012525, 5380.838574842742, 5452.309783966964, 5744.16709577111, 5688.97155514224, 5908.054711246202, 5948.078047902561, 5959.910601340962, 5962.433568810978, 5795.942040579604, 5859.9414005860035, 5860.560457965648, 5871.677057761822, 5882.882342353172, 4737.099951009332, 4920.212765957465, 4927.187014142698, 5208.895822083575, 5215.556677682402, 11656.358900260482, 11887.28676279426, 13127.40926658293, 18284.54288642781, 18297.0, 1959.1379792891225, 1987.28457755208, 2058.763242227148, 2066.917323307066, 2126.0220298662634, 8697.52163631, 8704.782380440476, 8831.735047948572, 9113.362064655477, 12157.939210303892, 1666.9083200423972, 1778.8399044085966, 1788.4277031349973, 1791.552111972007, 1814.9001804900724, 53472.465275347524, 54871.9999999996, 55942.0, 56029.0, 56053.719731401085, 9719.514024298756, 10010.49947502622, 10022.448765317977, 10026.899731002743, 10125.139363154067, 13087.869121308855, 13180.209187448783, 13200.273984930922, 13233.272170030741, 13239.139455935527, 7107.6446177690905, 7146.213916469184, 7218.0, 7219.8917016244795, 7288.562686238736, 6832.248452670202, 6856.554323968926, 6908.378245957788, 6921.411680007256, 6927.653617319113, 3984.322665146932, 4297.312430011246, 4330.870073897788, 4434.33484977256, 4468.843590474317, 1661.2109248107295, 1708.0776380754503, 1719.75063615775, 1733.1247719901369, 1766.2140347545476, 872.1540106097076, 887.7159309021051, 923.2844545477309, 966.9122237482832, 995.5569771451779, 13200.405981730653, 14662.193579353238, 15048.924755376152, 15370.615734606725, 16312.184390780414, 865.4720620421538, 874.152072489684, 919.3059240273684, 934.5700977550403, 943.4292253186724, 8914.153155450222, 8929.196372326522, 8931.19619234272, 9032.23226025782, 9158.95420522893, 377527.0, 379380.0, 381754.0, 435074.0, 505399.0, 69100.65449672719, 69781.0, 72074.0, 72351.63824180845, 73117.63441182867, 1075.3118004477142, 1076.3272954403499, 1086.5002099034375, 1093.6718984304837, 1105.662772854271, 13433.596992090253, 14331.498397556035, 14478.336429555924, 14677.853221468076, 14991.925040374728, 29829.403411931205, 30151.0, 30564.694353057075, 30652.0, 30998.22504437407, 35529.0, 35952.0, 36137.457938131476, 37712.245755084194, 39005.80497097496, 6718.832029199114, 6834.8633027340165, 6945.409640180641, 7000.0, 7037.0, 4460.665450091191, 9357.953210233905, 10388.688339349832, 10487.0, 10757.838632420677, 8841.463414634198, 9067.501287428991, 9549.52252387364, 9598.42409455435, 9777.41335519871, 9677.516124193902, 9889.50552472388, 9956.601735930482, 10113.29206955506, 10270.127039201452, 9912.405255684682, 10309.821100416291, 10320.793584128425, 10476.528556214836, 10486.47567621631, 7144.535605185646, 7317.304856038668, 7346.485745997729, 7390.815229619303, 7489.662965166472, 8971.237444817263, 9419.293552983416, 9508.857367139637, 9536.666216682383, 9544.33189676716], "unit": "score", "name": "jquery.html", "value": 7790.537048499873}, {"lowerIsBetter": false, "replicates": [1224.9203801752885, 1264.4562837979665, 1266.8099785032248, 1293.049608537724, 1745.0227872391463, 6888.793336199916, 7435.516691415061, 7579.621018949044, 7597.772066838003, 7606.581638009914, 7500.287472690085, 7517.54894706318, 7680.692772289101, 7702.0, 7715.382769378451, 2245.4947636781676, 2266.8073213776847, 2267.6598510223434, 2272.091163534583, 2291.656251562271, 2229.1640634761966, 2261.050358849287, 2272.227442669492, 2275.7382900966354, 2290.7480177180496, 2241.316398498457, 2253.0086761824746, 2263.0608297556496, 2263.3323169664986, 2271.034810205656, 2240.0703707961175, 2287.359539328982, 2294.3461113582716, 2298.2186056740707, 2298.9999350282596, 2242.5963326601204, 2243.9887800561055, 2252.4594097416502, 2253.4028482452163, 2257.57106149831, 7768.611569421506, 7768.805779855492, 7818.100918394401, 7839.451238413313, 7841.647125879292, 4744.14605371033, 4883.19427294496, 4887.315775791392, 4889.559939605417, 4891.902161956741, 3079.0916679579354, 3133.107064486633, 3149.102505785863, 3164.9683503164906, 3170.1123685368134, 984.8473486609587, 992.8609994600762, 1037.5797801890246, 1084.2247792828086, 1090.3512410115968, 10708.678739637824, 10716.571337146504, 10724.195685323553, 10733.516991735469, 10776.67669969902, 4355.303151495746, 4389.990302230441, 4409.5149533551285, 4420.138073075719, 4467.5755803198645, 9188.494632795127, 9191.816163676824, 9201.585928633227, 9275.211607013345, 9306.813863722688, 4905.3132561441425, 4941.43173535048, 4943.876403089952, 4948.4309304429735, 4950.183219768698, 3899.74651647642, 3929.390944403622, 4266.637335826427, 4467.419235499429, 4481.327800829901, 3718.3121122592406, 3778.3576791945434, 3788.45067465216, 3823.330917089498, 3832.865849695247, 1234.3643023842728, 1236.3014896583402, 1240.6898275431142, 1246.052999720219, 1250.8248845161684, 1028.619410818002, 1048.6015314180563, 1057.0909018244304, 1160.3675996581883, 1316.3352506983913, 7047.6828542714675, 7221.350078492961, 7260.0, 7271.745488907863, 7286.417086633057, 476.8068932082512, 479.7505297245413, 497.8431793984883, 499.7076710124545, 501.73658829115004, 5590.748416321276, 5680.829575112753, 5699.914501282484, 5704.971475142681, 5721.342045664802, 8797.208251257418, 9032.412893161923, 9107.499087550248, 9187.173154416134, 9242.537873106317, 8889.022207557164, 8972.37193396456, 8996.190342869042, 9005.459672419676, 9104.0, 6961.721531138799, 6972.267911869282, 6992.545484543489, 7053.964730176316, 7088.574685518885, 7132.821679458055, 7212.459065570209, 7212.4951253411755, 7219.061522002106, 7224.819379515344, 8104.432689711663, 8187.71343002992, 8572.614232359683, 8599.054104048675, 8644.654213831376, 8759.956200218958, 8843.292536597186, 8890.377673563047, 8898.199162075443, 8906.376553641441, 6011.72947217385, 6114.908276375769, 6400.135981642418, 6547.607143571401, 6693.564918280296, 7194.460415468965, 7201.855962880608, 7229.240929702306, 7233.4574906883245, 7293.708251669874, 6613.735450582115, 6632.071509988509, 6668.533202675961, 6692.933070669425, 6908.1364829396325, 6685.732570697312, 6694.59832410057, 6737.797866064026, 6747.460203183831, 6815.318468153145, 6793.966030169818, 6950.513464057467, 6987.615681137383, 7024.367806897404, 7062.823429414306, 6466.450351719968, 6536.379043990813, 6538.83652908662, 6546.312637173219, 6550.344965503412, 6554.213494380705, 6599.373059559334, 6599.835004124935, 6601.53789234749, 6608.768693095911], "unit": "score", "name": "mootools.html", "value": 4359.8214670711295}, {"lowerIsBetter": false, "replicates": [331.48785126978817, 335.1654380592326, 335.3159554508802, 340.3856039848074, 343.8315225539486, 5723.112917497784, 5823.417658234174, 5826.854328641781, 5833.649981001137, 5916.1125831125355, 5608.270924779773, 5756.798512052069, 5797.362290148091, 5803.0, 5815.331236907757, 4493.550644935497, 4500.729956202638, 4506.391637128979, 4512.481064677555, 4518.480374756912, 4359.891002724926, 4428.31361139024, 4430.1804166229185, 4489.753063581502, 4500.212462818994, 4447.444069491314, 4448.911021779562, 4473.373727678111, 4477.126960242754, 4512.458504979407, 4488.730676159425, 4510.233260345716, 4516.751578663172, 4518.88702782433, 4520.3219517072375, 3990.3615421532704, 4000.4599379084034, 4003.6396724294664, 4011.478507793968, 4014.036631208518, 5442.836714898559, 5565.276514053187, 5568.470995255444, 5611.80358687444, 5626.690532020737, 5257.185136303879, 5258.316418865523, 5263.421023687391, 5273.789048438096, 5277.287566178555, 5940.910886336708, 6215.720292586844, 6216.968915155395, 6218.782342617987, 6220.968895155541, 4268.445102136733, 4320.783960801948, 4328.848490302824, 4338.869833904988, 4343.587359200902, 6297.87404251917, 6476.9676151619415, 6479.546431749779, 6480.935190648081, 6486.059521369375, 4495.19086564415, 4537.38745269391, 4543.863684089482, 4545.977270113628, 4555.772211389483, 4490.169318676056, 4543.136804007228, 4573.885652858705, 4577.29051996941, 4614.4693360264, 5235.764390602432, 5244.082285600005, 5252.921206181911, 5253.369595648546, 5254.106801843695, 6225.221847269091, 6230.813075607739, 6240.251169859646, 6241.531885108635, 6242.625442473466, 1588.1820862255947, 1604.646977664912, 1605.1252067623172, 1609.147152009437, 1609.8068231812258, 1064.3347907557777, 1072.9967480405846, 1074.532578328414, 1099.9890001100046, 1120.7422292872523, 5821.359650438449, 5822.272215973004, 5825.417458254141, 5826.563007774434, 5830.2420685310635, 6266.467350275278, 6268.655223962634, 6270.059491076375, 6326.620402775849, 6330.778422755181, 5787.565932554991, 5793.362730099686, 5807.128930660435, 5816.098504731774, 5827.067669172912, 3289.5723555937575, 3395.049386171876, 3429.0741499795413, 3429.6741809528053, 3435.3301106284034, 6551.574147680385, 6625.17185351831, 6641.734330626818, 6660.83347916306, 6661.433778128913, 6521.380468855451, 6523.673816309166, 6523.7716679915975, 6528.281888992207, 6530.967345163243, 6483.3516648335735, 6622.86754264912, 6643.202815662152, 6649.401553860079, 6662.600243985376, 6183.721732521957, 6327.651979141191, 6347.619142851444, 6403.423691867568, 6414.294427613052, 6210.720517576629, 6325.4939604832425, 6326.841828954129, 6367.458766004941, 6396.840078998062, 6716.429103526255, 6721.596704197764, 6727.73089076451, 6733.42765864907, 6743.35938085881, 4257.659387248951, 4417.624501917373, 4435.1351486459835, 4459.843905463294, 4470.351798989063, 6353.491720662429, 6393.424591786761, 6401.327860574759, 6406.519511036598, 6407.775727849503, 6195.752169913153, 6199.659018754011, 6200.596961197508, 6244.718987645476, 6266.404691554295, 6026.246719160105, 6153.630782153085, 6187.133801267736, 6188.288346840171, 6247.062940558771, 6144.4777193937225, 6146.969265153645, 6148.231471066117, 6157.84605384869, 6159.6612186330185, 6255.124282600531, 6259.937400625936, 6264.937350626436, 6272.121902933502, 6273.090401891609, 5988.251468566429, 6117.388261173847, 6129.6322220665115, 6131.938680613137, 6135.570510064253], "unit": "score", "name": "prototype.html", "value": 4697.391644402641}, {"lowerIsBetter": false, "replicates": [748.5583505731621, 776.4875182379627, 815.6044318505526, 820.1347578304886, 829.7759604906676, 12454.564090256843, 12500.812487812678, 12563.560275390364, 12641.873581264184, 12676.429560669769, 12454.439550220239, 13243.47026118954, 13331.733365332688, 13337.066405351628, 13428.0, 664.0238848892121, 668.4151367553391, 792.1919641965192, 793.5397469467695, 805.9798505037362, 790.6639678136797, 799.6081919859286, 803.9477433966773, 807.2573232625998, 809.5304723260524, 835.0897521701345, 841.028611953192, 842.010637500934, 842.5450256861287, 843.026304618164, 685.1504134872747, 692.6225207262054, 818.643889907892, 832.3590835056972, 835.761807884756, 617.9042248451498, 618.0482057631287, 619.1734035063196, 619.2259675405743, 620.5407998081432, 12303.261804291682, 12323.198992065576, 12335.444904979298, 12341.197822141532, 12357.567485137977, 11564.653060408202, 11815.94092029543, 11940.0, 12036.63890083299, 12163.148579599432, 10243.743906402326, 10244.94877525615, 10440.895591044069, 10562.049415552638, 10619.787604247871, 3567.1438854674952, 3605.675489205958, 3607.8917632471066, 3629.764065335745, 3635.5455568053994, 44496.77751611221, 44608.55391446076, 44650.0, 44985.325220121726, 45063.54936450626, 9596.712098637052, 10104.545295461721, 10241.795164096677, 10258.846117308247, 10267.281290309607, 18227.17977691007, 18266.26934922588, 18294.908525457555, 18295.63408731818, 18330.63338733218, 7279.963600182071, 7348.70605175787, 7436.776896693108, 7619.771406857803, 7832.882506762403, 4193.895152621209, 4208.074223670787, 4213.641840443596, 4229.577042295746, 4285.528591854893, 2840.091170825316, 2875.856207189632, 2878.4387044526534, 2892.7251911068415, 2902.0568315297614, 523.557593833218, 524.9133892907704, 526.9025230332325, 538.27064327836, 543.8857839853675, 589.1633879890566, 597.6175247841385, 609.8079105081885, 615.3692962584985, 674.6727836999052, 7761.689532418753, 7846.136924938252, 7909.0, 7918.920810791934, 8084.434089613788, 491.665667346208, 551.9420460851633, 648.3484098481091, 656.5076192855358, 660.3330636057616, 3467.4278744006956, 3653.50677658518, 3661.0, 3717.219383929405, 3761.6426439488205, 46884.296735549, 47227.76386118116, 47348.0, 47499.762501187965, 47657.0, 42598.0, 42739.35890961638, 46634.06731865344, 46944.0, 46977.060458790635, 42851.35722964158, 43232.56767432348, 43460.3480947786, 43628.345574816405, 43747.0, 7568.621568921642, 7573.659185336784, 7978.880316795369, 8180.263776260165, 8536.786580335542, 33669.15827104342, 33694.326113478084, 34626.0, 35587.0, 36159.45760813537, 40638.39042414307, 40660.79669601633, 40718.59281407148, 44647.77676111599, 44800.0, 10522.0, 10754.677359679223, 10859.548381318371, 11309.886901131213, 11332.546698131982, 12440.937795310965, 12503.56237531682, 12679.112462127932, 12681.492740290285, 12744.74510509803, 11712.941435292769, 11974.101942354184, 12002.879971200176, 12490.625281241577, 12522.812157817822, 10053.798924021332, 10305.74235644115, 10624.787504249716, 10645.574177032831, 10676.359418434919, 11381.772364552828, 11656.708582285512, 11900.107491937966, 11923.880761192624, 12326.137170397986, 9118.635254589743, 9125.680601178927, 9279.907200927904, 9759.219262458844, 9970.152537034433, 9032.187103160748, 9189.908100918905, 9241.21449676785, 9301.860472092789, 9869.062439068277], "unit": "score", "name": "yui.html", "value": 6064.0402298032395}], "name": "dromaeo_css", "value": 6648.689800092759}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 112.0, 107.0, 111.0, 112.0, 112.0, 100.0, 110.0, 110.0, 117.0], "unit": "score", "name": "ai-astar", "value": 110.1}, {"lowerIsBetter": true, "replicates": [156.0, 138.0, 150.0, 171.0, 123.0, 153.0, 147.0, 140.0, 169.0, 126.0], "unit": "score", "name": "audio-beat-detection", "value": 147.3}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 161.0, 159.0, 160.0, 163.0, 162.0, 163.0, 162.0, 163.0], "unit": "score", "name": "audio-dft", "value": 161.4}, {"lowerIsBetter": true, "replicates": [72.0, 73.0, 89.0, 97.0, 74.0, 72.0, 74.0, 110.0, 94.0, 71.0], "unit": "score", "name": "audio-fft", "value": 82.6}, {"lowerIsBetter": true, "replicates": [97.0, 98.0, 138.0, 95.0, 96.0, 96.0, 142.0, 97.0, 98.0, 138.0], "unit": "score", "name": "audio-oscillator", "value": 109.5}, {"lowerIsBetter": true, "replicates": [110.0, 110.0, 110.0, 109.0, 110.0, 109.0, 110.0, 111.0, 109.0, 109.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 109.7}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0], "unit": "score", "name": "imaging-darkroom", "value": 146.1}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 96.0, 97.0, 96.0, 96.0, 96.0, 96.0, 96.0], "unit": "score", "name": "imaging-desaturate", "value": 96.1}, {"lowerIsBetter": true, "replicates": [55.0, 56.0, 55.0, 55.0, 54.0, 54.0, 57.0, 63.0, 53.0, 55.0], "unit": "score", "name": "json-parse-financial", "value": 55.7}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 70.0, 70.0, 69.0, 73.0, 69.0, 69.0, 76.0, 70.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 70.4}, {"lowerIsBetter": true, "replicates": [88.0, 86.0, 86.0, 87.0, 85.0, 100.0, 87.0, 87.0, 90.0, 95.0], "unit": "score", "name": "stanford-crypto-aes", "value": 89.1}, {"lowerIsBetter": true, "replicates": [119.0, 121.0, 127.0, 122.0, 134.0, 119.0, 117.0, 121.0, 120.0, 123.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.3}, {"lowerIsBetter": true, "replicates": [156.0, 152.0, 152.0, 151.0, 152.0, 140.0, 153.0, 151.0, 151.0, 139.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 149.7}, {"lowerIsBetter": true, "replicates": [67.0, 68.0, 67.0, 69.0, 67.0, 66.0, 71.0, 71.0, 72.0, 68.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 68.6}], "name": "kraken", "value": 1518.6}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.9261125104954], "unit": "score", "name": "Richards", "value": 210.22340999999997}, {"lowerIsBetter": false, "replicates": [199.20318725099602], "unit": "score", "name": "DeltaBlue", "value": 331.91236000000015}, {"lowerIsBetter": false, "replicates": [288.2675122513693], "unit": "score", "name": "Encrypt", "value": 923.381889}, {"lowerIsBetter": false, "replicates": [5196.891191709845], "unit": "score", "name": "Decrypt", "value": 51.219275174476564}, {"lowerIsBetter": false, "replicates": [1968.5039370078741], "unit": "score", "name": "RayTrace", "value": 375.9144119999999}, {"lowerIsBetter": false, "replicates": [801.924619085806], "unit": "score", "name": "Earley", "value": 831.079361}, {"lowerIsBetter": false, "replicates": [12108.43373493976], "unit": "score", "name": "Boyer", "value": 55.0412228855721}, {"lowerIsBetter": false, "replicates": [43250.0], "unit": "score", "name": "RegExp", "value": 21.06323699421965}, {"lowerIsBetter": false, "replicates": [561.7283950617284], "unit": "score", "name": "Splay", "value": 145.07189010989015}, {"lowerIsBetter": false, "replicates": [4854.368932038835], "unit": "score", "name": "NavierStokes", "value": 305.7040000000001}], "name": "v8_7", "value": 18826.158169436643}]} 23:20:49 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "18826.16"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1518.60"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6648.69"}}} 23:20:49 INFO - Return code: 0 23:20:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 23:20:49 INFO - # TBPL SUCCESS # 23:20:49 INFO - Running post-action listener: _resource_record_post_action 23:20:49 INFO - Running post-run listener: _resource_record_post_run 23:20:50 INFO - Total resource usage - Wall time: 1233s; CPU: 13.0%; Read bytes: 10502144; Write bytes: 456765440; Read time: 3280; Write time: 478376 23:20:50 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 12361728; Read time: 0; Write time: 14840 23:20:50 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 8740864; Write bytes: 389001216; Read time: 3164; Write time: 412264 23:20:50 INFO - Running post-run listener: _upload_blobber_files 23:20:50 INFO - Blob upload gear active. 23:20:50 INFO - Preparing to upload files from /builds/slave/test-pgo/build/blobber_upload_dir. 23:20:50 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:20:50 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:20:50 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:20:51 INFO - (blobuploader) - INFO - Open directory for files ... 23:20:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_raw.log ... 23:20:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:20:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:20:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:20:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:20:52 INFO - (blobuploader) - INFO - Done attempting. 23:20:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test-pgo/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:20:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:20:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:20:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:20:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:20:52 INFO - (blobuploader) - INFO - Done attempting. 23:20:52 INFO - (blobuploader) - INFO - Iteration through files over. 23:20:52 INFO - Return code: 0 23:20:52 INFO - rmtree: /builds/slave/test-pgo/build/uploaded_files.json 23:20:52 INFO - retry: Calling remove with args: ('/builds/slave/test-pgo/build/uploaded_files.json',), kwargs: {}, attempt #1 23:20:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a0a1d4af21b83639c8dc55f9c154309c84c0bdab4bbd4a9f1001633119277de879dcb073ba9555e503c69901ed4ceb81ac69cc79f14ec59d8d41dc259270880", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/22bfc314fe1b5c585c29a737d1a6df6e762b9382dec7d0173cc3f24b82f2f177783e108657e3e195c30fc9afc80d8d10e05719f227d3bae6ccec562d645d0bd9"} 23:20:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test-pgo/properties/blobber_files 23:20:52 INFO - Writing to file /builds/slave/test-pgo/properties/blobber_files 23:20:52 INFO - Contents: 23:20:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6a0a1d4af21b83639c8dc55f9c154309c84c0bdab4bbd4a9f1001633119277de879dcb073ba9555e503c69901ed4ceb81ac69cc79f14ec59d8d41dc259270880", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/22bfc314fe1b5c585c29a737d1a6df6e762b9382dec7d0173cc3f24b82f2f177783e108657e3e195c30fc9afc80d8d10e05719f227d3bae6ccec562d645d0bd9"} 23:20:52 INFO - Copying logs to upload dir... 23:20:52 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1286.674845 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2016-01-09 23:20:52.686683) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-09 23:20:52.690165) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356 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/6a0a1d4af21b83639c8dc55f9c154309c84c0bdab4bbd4a9f1001633119277de879dcb073ba9555e503c69901ed4ceb81ac69cc79f14ec59d8d41dc259270880", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/22bfc314fe1b5c585c29a737d1a6df6e762b9382dec7d0173cc3f24b82f2f177783e108657e3e195c30fc9afc80d8d10e05719f227d3bae6ccec562d645d0bd9"} build_url:https://queue.taskcluster.net/v1/task/TbZ5_RzLQKyohc9nEFravw/artifacts/public/build/firefox-46.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011255 build_url: 'https://queue.taskcluster.net/v1/task/TbZ5_RzLQKyohc9nEFravw/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/6a0a1d4af21b83639c8dc55f9c154309c84c0bdab4bbd4a9f1001633119277de879dcb073ba9555e503c69901ed4ceb81ac69cc79f14ec59d8d41dc259270880", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/22bfc314fe1b5c585c29a737d1a6df6e762b9382dec7d0173cc3f24b82f2f177783e108657e3e195c30fc9afc80d8d10e05719f227d3bae6ccec562d645d0bd9"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-01-09 23:20:52.714779) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 23:20:52.715154) ========= 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=a4ce256d2ec601f24a60a71e000001bc-1452407702.801071-629008356 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004494 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-01-09 23:20:52.798193) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-09 23:20:52.798564) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-01-09 23:20:52.798945) ========= ========= Total master_lag: 0.25 =========