builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1447987733.21 results: success (0) buildid: 20151119173011 builduid: 86bf0d0c4c44486c951fdfaf47cbe6ec revision: c43d530f61c2672dd059a64c1ad04279f9650dd5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.210747) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.211258) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.211565) ========= 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=d16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.009106 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.237296) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.237607) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.256932) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.257213) ========= 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=d16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004579 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.306336) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.306623) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.307047) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.307329) ========= 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=d16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-19 18:48:53-- 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% 140M=0s 2015-11-19 18:48:54 (140 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.181552 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.500951) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.501270) ========= 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=d16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017515 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:53.551445) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 18:48:53.551821) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c43d530f61c2672dd059a64c1ad04279f9650dd5 --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 c43d530f61c2672dd059a64c1ad04279f9650dd5 --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=d16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-19 18:48:54,208 truncating revision to first 12 chars 2015-11-19 18:48:54,208 Setting DEBUG logging. 2015-11-19 18:48:54,208 attempt 1/10 2015-11-19 18:48:54,208 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c43d530f61c2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-19 18:48:55,231 unpacking tar archive at: fx-team-c43d530f61c2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.331624 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-19 18:48:54.896467) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:54.896842) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:54.906485) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:54.906751) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-19 18:48:54.907198) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2015-11-19 18:48:54.907489) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:48:55 INFO - MultiFileLogger online at 20151119 18:48:55 in /builds/slave/test-pgo 18:48:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:48:55 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 18:48:55 INFO - {'append_to_log': False, 18:48:55 INFO - 'base_work_dir': '/builds/slave/test-pgo', 18:48:55 INFO - 'blob_upload_branch': 'Fx-Team', 18:48:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 18:48:55 INFO - 'branch': 'Fx-Team', 18:48:55 INFO - 'buildbot_json_path': 'buildprops.json', 18:48:55 INFO - 'config_files': ('talos/linux_config.py',), 18:48:55 INFO - 'default_actions': ('clobber', 18:48:55 INFO - 'read-buildbot-config', 18:48:55 INFO - 'download-and-extract', 18:48:55 INFO - 'populate-webroot', 18:48:55 INFO - 'create-virtualenv', 18:48:55 INFO - 'install', 18:48:55 INFO - 'run-tests'), 18:48:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:48:55 INFO - 'download_minidump_stackwalk': True, 18:48:55 INFO - 'download_symbols': 'ondemand', 18:48:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:48:55 INFO - 'tooltool.py': '/tools/tooltool.py', 18:48:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:48:55 INFO - '/tools/misc-python/virtualenv.py')}, 18:48:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:48:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:48:55 INFO - 'installer_path': 'installer.exe', 18:48:55 INFO - 'log_level': 'info', 18:48:55 INFO - 'log_name': 'talos', 18:48:55 INFO - 'log_to_console': True, 18:48:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:48:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:48:55 INFO - 'opt_config_files': (), 18:48:55 INFO - 'pip_index': False, 18:48:55 INFO - 'sps_profile': False, 18:48:55 INFO - 'sps_profile_interval': 0, 18:48:55 INFO - 'suite': 'dromaeojs', 18:48:55 INFO - 'system_bits': '64', 18:48:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:48:55 INFO - 'title': 'talos-linux64-ix-034', 18:48:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:48:55 INFO - 'use_talos_json': True, 18:48:55 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 18:48:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:48:55 INFO - 'work_dir': 'build'} 18:48:55 INFO - ##### 18:48:55 INFO - ##### Running clobber step. 18:48:55 INFO - ##### 18:48:55 INFO - Running pre-action listener: _resource_record_pre_action 18:48:55 INFO - Running main action method: clobber 18:48:55 INFO - rmtree: /builds/slave/test-pgo/build 18:48:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 18:48:56 INFO - Running post-action listener: _resource_record_post_action 18:48:56 INFO - ##### 18:48:56 INFO - ##### Running read-buildbot-config step. 18:48:56 INFO - ##### 18:48:56 INFO - Running pre-action listener: _resource_record_pre_action 18:48:56 INFO - Running main action method: read_buildbot_config 18:48:56 INFO - Using buildbot properties: 18:48:56 INFO - { 18:48:56 INFO - "properties": { 18:48:56 INFO - "buildnumber": 502, 18:48:56 INFO - "product": "firefox", 18:48:56 INFO - "script_repo_revision": "production", 18:48:56 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 18:48:56 INFO - "repository": "", 18:48:56 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 18:48:56 INFO - "buildid": "20151119173011", 18:48:56 INFO - "slavename": "talos-linux64-ix-034", 18:48:56 INFO - "pgo_build": "True", 18:48:56 INFO - "basedir": "/builds/slave/test-pgo", 18:48:56 INFO - "project": "", 18:48:56 INFO - "platform": "ubuntu64_hw", 18:48:56 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 18:48:56 INFO - "slavebuilddir": "test", 18:48:56 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 18:48:56 INFO - "branch": "fx-team", 18:48:56 INFO - "repo_path": "integration/fx-team", 18:48:56 INFO - "stage_platform": "linux64-pgo", 18:48:56 INFO - "builduid": "86bf0d0c4c44486c951fdfaf47cbe6ec", 18:48:56 INFO - "revision": "c43d530f61c2672dd059a64c1ad04279f9650dd5" 18:48:56 INFO - }, 18:48:56 INFO - "sourcestamp": { 18:48:56 INFO - "repository": "", 18:48:56 INFO - "hasPatch": false, 18:48:56 INFO - "project": "", 18:48:56 INFO - "branch": "fx-team-linux64-pgo-talos", 18:48:56 INFO - "changes": [ 18:48:56 INFO - { 18:48:56 INFO - "category": null, 18:48:56 INFO - "files": [ 18:48:56 INFO - { 18:48:56 INFO - "url": null, 18:48:56 INFO - "name": "https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:48:56 INFO - } 18:48:56 INFO - ], 18:48:56 INFO - "repository": "", 18:48:56 INFO - "rev": "c43d530f61c2672dd059a64c1ad04279f9650dd5", 18:48:56 INFO - "who": "sendchange", 18:48:56 INFO - "when": 1447987653, 18:48:56 INFO - "number": 6686492, 18:48:56 INFO - "comments": "", 18:48:56 INFO - "project": "", 18:48:56 INFO - "at": "Thu 19 Nov 2015 18:47:33", 18:48:56 INFO - "branch": "fx-team-linux64-pgo-talos", 18:48:56 INFO - "revlink": "", 18:48:56 INFO - "properties": [ 18:48:56 INFO - [ 18:48:56 INFO - "buildid", 18:48:56 INFO - "20151119173011", 18:48:56 INFO - "Change" 18:48:56 INFO - ], 18:48:56 INFO - [ 18:48:56 INFO - "builduid", 18:48:56 INFO - "86bf0d0c4c44486c951fdfaf47cbe6ec", 18:48:56 INFO - "Change" 18:48:56 INFO - ], 18:48:56 INFO - [ 18:48:56 INFO - "pgo_build", 18:48:56 INFO - "True", 18:48:56 INFO - "Change" 18:48:56 INFO - ] 18:48:56 INFO - ], 18:48:56 INFO - "revision": "c43d530f61c2672dd059a64c1ad04279f9650dd5" 18:48:56 INFO - } 18:48:56 INFO - ], 18:48:56 INFO - "revision": "c43d530f61c2672dd059a64c1ad04279f9650dd5" 18:48:56 INFO - } 18:48:56 INFO - } 18:48:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:48:56 INFO - Running post-action listener: _resource_record_post_action 18:48:56 INFO - ##### 18:48:56 INFO - ##### Running download-and-extract step. 18:48:56 INFO - ##### 18:48:56 INFO - Running pre-action listener: _resource_record_pre_action 18:48:56 INFO - Running main action method: download_and_extract 18:48:56 INFO - mkdir: /builds/slave/test-pgo/build/tests 18:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:48:56 INFO - https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:48:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/test_packages.json 18:48:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/test_packages.json 18:48:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 18:48:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 18:48:58 INFO - Downloaded 1302 bytes. 18:48:58 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 18:48:58 INFO - Using the following test package requirements: 18:48:58 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:48:58 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:48:58 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:48:58 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:48:58 INFO - u'jsshell-linux-x86_64.zip'], 18:48:58 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:48:58 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:48:58 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:48:58 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:48:58 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:48:58 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:48:58 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:48:58 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:48:58 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:48:58 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:48:58 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:48:58 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:48:58 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:48:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:48:58 INFO - https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:48:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:48:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:48:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:48:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:49:04 INFO - Downloaded 22523775 bytes. 18:49:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:49:04 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:49:04 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:49:05 INFO - Return code: 0 18:49:05 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:05 INFO - https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:49:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:49:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:49:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:49:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:49:07 INFO - Downloaded 22523775 bytes. 18:49:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 18:49:07 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:49:07 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 18:49:08 INFO - Return code: 0 18:49:08 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')]} 18:49:08 INFO - https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:49:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:49:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:49:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:49:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:49:09 INFO - Downloaded 11130997 bytes. 18:49:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 18:49:09 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:49:09 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 18:49:09 INFO - Return code: 0 18:49: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')]} 18:49:09 INFO - https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:49:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:49:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:49:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:49:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:49:10 INFO - Downloaded 57345958 bytes. 18:49:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:49:10 INFO - mkdir: /builds/slave/test-pgo/properties 18:49:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 18:49:10 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 18:49:10 INFO - Contents: 18:49:10 INFO - build_url:https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:49:10 INFO - Running post-action listener: _resource_record_post_action 18:49:10 INFO - Running post-action listener: set_extra_try_arguments 18:49:10 INFO - ##### 18:49:10 INFO - ##### Running populate-webroot step. 18:49:10 INFO - ##### 18:49:10 INFO - Running pre-action listener: _resource_record_pre_action 18:49:10 INFO - Running main action method: populate_webroot 18:49:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:49:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:49:10 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 18:49:10 INFO - '../fennec_ids.txt'], 18:49:10 INFO - 'tests': ['tcheck2']}, 18:49:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:49:10 INFO - '--tppagecycles', 18:49:10 INFO - '7'], 18:49:10 INFO - 'tests': ['tsvgm']}}, 18:49:10 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:49:10 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:49:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:49:10 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:49:10 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:49:10 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:49:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:49:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:10 INFO - 'talos_options': ['--e10s'], 18:49:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:49:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:10 INFO - 'tests': ['damp', 'tps']}, 18:49:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:10 INFO - 'talos_options': ['--e10s'], 18:49:10 INFO - 'tests': ['damp', 'tps']}, 18:49:10 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:49:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:49:10 INFO - 'other': {'tests': ['a11yr', 18:49:10 INFO - 'ts_paint', 18:49:10 INFO - 'tpaint', 18:49:10 INFO - 'sessionrestore', 18:49:10 INFO - 'sessionrestore_no_auto_restore']}, 18:49:10 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:49:10 INFO - 'tests': ['a11yr', 18:49:10 INFO - 'ts_paint', 18:49:10 INFO - 'tpaint', 18:49:10 INFO - 'sessionrestore', 18:49:10 INFO - 'sessionrestore_no_auto_restore']}, 18:49:10 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:49:10 INFO - 'tests': ['a11yr', 18:49:10 INFO - 'ts_paint', 18:49:10 INFO - 'tpaint', 18:49:10 INFO - 'sessionrestore', 18:49:10 INFO - 'sessionrestore_no_auto_restore']}, 18:49:10 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:49:10 INFO - 'tests': ['a11yr', 18:49:10 INFO - 'ts_paint', 18:49:10 INFO - 'tpaint', 18:49:10 INFO - 'sessionrestore', 18:49:10 INFO - 'sessionrestore_no_auto_restore']}, 18:49:10 INFO - 'other_l64': {'tests': ['a11yr', 18:49:10 INFO - 'ts_paint', 18:49:10 INFO - 'tpaint', 18:49:10 INFO - 'sessionrestore', 18:49:10 INFO - 'sessionrestore_no_auto_restore']}, 18:49:10 INFO - 'other_nol64': {'tests': ['a11yr', 18:49:10 INFO - 'ts_paint', 18:49:10 INFO - 'tpaint', 18:49:10 INFO - 'sessionrestore', 18:49:10 INFO - 'sessionrestore_no_auto_restore']}, 18:49:10 INFO - 'svgr': {'tests': ['tsvgx', 18:49:10 INFO - 'tsvgr_opacity', 18:49:10 INFO - 'tart', 18:49:10 INFO - 'tscrollx', 18:49:10 INFO - 'cart']}, 18:49:10 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:49:10 INFO - 'tests': ['tsvgx', 18:49:10 INFO - 'tsvgr_opacity', 18:49:10 INFO - 'tart', 18:49:10 INFO - 'tscrollx', 18:49:10 INFO - 'cart']}, 18:49:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:10 INFO - 'tests': ['tp5o']}, 18:49:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:10 INFO - 'talos_options': ['--e10s'], 18:49:10 INFO - 'tests': ['tp5o']}, 18:49:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:10 INFO - 'talos_options': ['--xperf_path', 18:49:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:49:10 INFO - 'tests': ['tp5n']}, 18:49:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:49:10 INFO - 'talos_options': ['--e10s', 18:49:10 INFO - '--xperf_path', 18:49:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:49:10 INFO - 'tests': ['tp5n']}}, 18:49:10 INFO - 'talos.zip': {'path': '', 18:49:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:49:10 INFO - Running post-action listener: _resource_record_post_action 18:49:10 INFO - ##### 18:49:10 INFO - ##### Running create-virtualenv step. 18:49:10 INFO - ##### 18:49:10 INFO - Running pre-action listener: _resource_record_pre_action 18:49:10 INFO - Running main action method: create_virtualenv 18:49:10 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 18:49:10 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 18:49:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 18:49:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:49:11 INFO - Using real prefix '/usr' 18:49:11 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 18:49:11 INFO - Installing distribute.............................................................................................................................................................................................done. 18:49:13 INFO - Installing pip.................done. 18:49:13 INFO - Return code: 0 18:49:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 18:49:13 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')]} 18:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:13 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')]} 18:49:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:13 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 0x7fd95e745c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2baf6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c706e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6a90>, '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 0x2cf8f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cf92e0>, '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': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', '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 18:49:13 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 18:49:13 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 18:49:13 INFO - Using env: {'DISPLAY': ':0', 18:49:13 INFO - 'HOME': '/home/cltbld', 18:49:13 INFO - 'LANG': 'en_US.UTF-8', 18:49:13 INFO - 'LANGUAGE': 'en_US:en', 18:49:13 INFO - 'LOGNAME': 'cltbld', 18:49:13 INFO - 'MAIL': '/var/mail/cltbld', 18:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:13 INFO - 'MOZ_NO_REMOTE': '1', 18:49:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:13 INFO - 'NO_EM_RESTART': '1', 18:49:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:49:13 INFO - 'PWD': '/builds/slave/test-pgo', 18:49:13 INFO - 'SHELL': '/bin/bash', 18:49:13 INFO - 'SHLVL': '1', 18:49:13 INFO - 'TERM': 'linux', 18:49:13 INFO - 'TMOUT': '86400', 18:49:13 INFO - 'USER': 'cltbld', 18:49:13 INFO - 'XDG_SESSION_COOKIE': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', 18:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:13 INFO - '_': '/tools/buildbot/bin/python'} 18:49:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:13 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 18:49:13 INFO - Cleaning up... 18:49:13 INFO - Return code: 0 18:49:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 18:49:13 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')]} 18:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:13 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')]} 18:49:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:13 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 0x7fd95e745c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2baf6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c706e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6a90>, '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 0x2cf8f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cf92e0>, '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': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', '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 18:49:13 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 18:49:13 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 18:49:13 INFO - Using env: {'DISPLAY': ':0', 18:49:13 INFO - 'HOME': '/home/cltbld', 18:49:13 INFO - 'LANG': 'en_US.UTF-8', 18:49:13 INFO - 'LANGUAGE': 'en_US:en', 18:49:13 INFO - 'LOGNAME': 'cltbld', 18:49:13 INFO - 'MAIL': '/var/mail/cltbld', 18:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:13 INFO - 'MOZ_NO_REMOTE': '1', 18:49:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:13 INFO - 'NO_EM_RESTART': '1', 18:49:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:49:13 INFO - 'PWD': '/builds/slave/test-pgo', 18:49:13 INFO - 'SHELL': '/bin/bash', 18:49:13 INFO - 'SHLVL': '1', 18:49:13 INFO - 'TERM': 'linux', 18:49:13 INFO - 'TMOUT': '86400', 18:49:13 INFO - 'USER': 'cltbld', 18:49:13 INFO - 'XDG_SESSION_COOKIE': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', 18:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:13 INFO - '_': '/tools/buildbot/bin/python'} 18:49:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:13 INFO - Downloading/unpacking psutil>=0.7.1 18:49:13 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 18:49:13 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 18:49:13 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 18:49:13 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 18:49:13 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 18:49:13 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 18:49:15 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 18:49:15 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:49:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 18:49:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:49:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:49:15 INFO - Installing collected packages: psutil 18:49:15 INFO - Running setup.py install for psutil 18:49:15 INFO - building 'psutil._psutil_linux' extension 18:49:15 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 18:49:15 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 18:49:16 INFO - building 'psutil._psutil_posix' extension 18:49:16 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 18:49:16 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 18:49:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:49:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:49:16 INFO - Successfully installed psutil 18:49:16 INFO - Cleaning up... 18:49:16 INFO - Return code: 0 18:49:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 18:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:16 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fd95e745c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2baf6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c706e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6a90>, '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 0x2cf8f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cf92e0>, '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': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', '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 18:49:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 18:49:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:49:16 INFO - Using env: {'DISPLAY': ':0', 18:49:16 INFO - 'HOME': '/home/cltbld', 18:49:16 INFO - 'LANG': 'en_US.UTF-8', 18:49:16 INFO - 'LANGUAGE': 'en_US:en', 18:49:16 INFO - 'LOGNAME': 'cltbld', 18:49:16 INFO - 'MAIL': '/var/mail/cltbld', 18:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:16 INFO - 'MOZ_NO_REMOTE': '1', 18:49:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:16 INFO - 'NO_EM_RESTART': '1', 18:49:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:49:16 INFO - 'PWD': '/builds/slave/test-pgo', 18:49:16 INFO - 'SHELL': '/bin/bash', 18:49:16 INFO - 'SHLVL': '1', 18:49:16 INFO - 'TERM': 'linux', 18:49:16 INFO - 'TMOUT': '86400', 18:49:16 INFO - 'USER': 'cltbld', 18:49:16 INFO - 'XDG_SESSION_COOKIE': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', 18:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:16 INFO - '_': '/tools/buildbot/bin/python'} 18:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:49:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:49:18 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:49:18 INFO - Installing collected packages: mozsystemmonitor 18:49:18 INFO - Running setup.py install for mozsystemmonitor 18:49:18 INFO - Successfully installed mozsystemmonitor 18:49:18 INFO - Cleaning up... 18:49:18 INFO - Return code: 0 18:49:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 18:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:18 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7fd95e745c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2baf6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c706e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6a90>, '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 0x2cf8f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cf92e0>, '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': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', '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 18:49:18 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 18:49:18 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 18:49:18 INFO - Using env: {'DISPLAY': ':0', 18:49:18 INFO - 'HOME': '/home/cltbld', 18:49:18 INFO - 'LANG': 'en_US.UTF-8', 18:49:18 INFO - 'LANGUAGE': 'en_US:en', 18:49:18 INFO - 'LOGNAME': 'cltbld', 18:49:18 INFO - 'MAIL': '/var/mail/cltbld', 18:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:18 INFO - 'MOZ_NO_REMOTE': '1', 18:49:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:18 INFO - 'NO_EM_RESTART': '1', 18:49:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:49:18 INFO - 'PWD': '/builds/slave/test-pgo', 18:49:18 INFO - 'SHELL': '/bin/bash', 18:49:18 INFO - 'SHLVL': '1', 18:49:18 INFO - 'TERM': 'linux', 18:49:18 INFO - 'TMOUT': '86400', 18:49:18 INFO - 'USER': 'cltbld', 18:49:18 INFO - 'XDG_SESSION_COOKIE': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', 18:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:18 INFO - '_': '/tools/buildbot/bin/python'} 18:49:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:18 INFO - Downloading/unpacking blobuploader==1.2.4 18:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:20 INFO - Downloading blobuploader-1.2.4.tar.gz 18:49:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:49:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:49:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:49:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 18:49:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:20 INFO - Downloading docopt-0.6.1.tar.gz 18:49:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:49:20 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 18:49:21 INFO - Installing collected packages: blobuploader, requests, docopt 18:49:21 INFO - Running setup.py install for blobuploader 18:49:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:49:21 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 18:49:21 INFO - Running setup.py install for requests 18:49:21 INFO - Running setup.py install for docopt 18:49:21 INFO - Successfully installed blobuploader requests docopt 18:49:21 INFO - Cleaning up... 18:49:21 INFO - Return code: 0 18:49:21 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:49:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:21 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 0x7fd95e745c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2baf6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c706e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6a90>, '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 0x2cf8f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cf92e0>, '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': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', '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 18:49:21 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 18:49:21 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 18:49:21 INFO - Using env: {'DISPLAY': ':0', 18:49:21 INFO - 'HOME': '/home/cltbld', 18:49:21 INFO - 'LANG': 'en_US.UTF-8', 18:49:21 INFO - 'LANGUAGE': 'en_US:en', 18:49:21 INFO - 'LOGNAME': 'cltbld', 18:49:21 INFO - 'MAIL': '/var/mail/cltbld', 18:49:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:21 INFO - 'MOZ_NO_REMOTE': '1', 18:49:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:21 INFO - 'NO_EM_RESTART': '1', 18:49:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:49:21 INFO - 'PWD': '/builds/slave/test-pgo', 18:49:21 INFO - 'SHELL': '/bin/bash', 18:49:21 INFO - 'SHLVL': '1', 18:49:21 INFO - 'TERM': 'linux', 18:49:21 INFO - 'TMOUT': '86400', 18:49:21 INFO - 'USER': 'cltbld', 18:49:21 INFO - 'XDG_SESSION_COOKIE': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', 18:49:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:21 INFO - '_': '/tools/buildbot/bin/python'} 18:49:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:49:21 INFO - Running setup.py (path:/tmp/pip-7iC42D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:49:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:49:21 INFO - Running setup.py (path:/tmp/pip-oxcGt5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:49:21 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:49:21 INFO - Running setup.py (path:/tmp/pip-fqch2C-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:49:22 INFO - Running setup.py (path:/tmp/pip-fq1UcI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:49:22 INFO - Running setup.py (path:/tmp/pip-cAuFBs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:49:22 INFO - Running setup.py (path:/tmp/pip-9iufxR-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:49:22 INFO - Running setup.py (path:/tmp/pip-AORtsb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:49:22 INFO - Running setup.py (path:/tmp/pip-tGfHto-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:49:22 INFO - Running setup.py (path:/tmp/pip-zxQ05U-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:49:22 INFO - Running setup.py (path:/tmp/pip-9LwAAv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:49:22 INFO - Running setup.py (path:/tmp/pip-un2FrD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:49:22 INFO - Running setup.py (path:/tmp/pip-Mdwnwe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:49:22 INFO - Running setup.py (path:/tmp/pip-cBZQM_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:49:22 INFO - Running setup.py (path:/tmp/pip-YEQtkP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:49:22 INFO - Running setup.py (path:/tmp/pip-Ox9jcI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:49:22 INFO - Running setup.py (path:/tmp/pip-x4QP3D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:49:22 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:49:22 INFO - Running setup.py (path:/tmp/pip-eVoU7c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:49:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:49:23 INFO - Running setup.py install for manifestparser 18:49:23 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 18:49:23 INFO - Running setup.py install for mozcrash 18:49:23 INFO - Running setup.py install for mozdebug 18:49:23 INFO - Running setup.py install for mozdevice 18:49:23 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 18:49:23 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 18:49:23 INFO - Running setup.py install for mozfile 18:49:23 INFO - Running setup.py install for mozhttpd 18:49:23 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 18:49:23 INFO - Running setup.py install for mozinfo 18:49:23 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 18:49:23 INFO - Running setup.py install for mozInstall 18:49:23 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 18:49:23 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 18:49:23 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 18:49:23 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 18:49:23 INFO - Running setup.py install for mozleak 18:49:23 INFO - Running setup.py install for mozlog 18:49:24 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 18:49:24 INFO - Running setup.py install for moznetwork 18:49:24 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 18:49:24 INFO - Running setup.py install for mozprocess 18:49:24 INFO - Running setup.py install for mozprofile 18:49:24 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 18:49:24 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 18:49:24 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 18:49:24 INFO - Running setup.py install for mozrunner 18:49:24 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 18:49:24 INFO - Running setup.py install for mozscreenshot 18:49:24 INFO - Running setup.py install for moztest 18:49:24 INFO - Running setup.py install for mozversion 18:49:24 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 18:49:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:49:24 INFO - Cleaning up... 18:49:24 INFO - Return code: 0 18:49:24 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:24 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7fd95e745c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2baf6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c706e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6a90>, '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 0x2cf8f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cf92e0>, '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': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', '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 18:49:24 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 18:49:24 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 18:49:24 INFO - Using env: {'DISPLAY': ':0', 18:49:24 INFO - 'HOME': '/home/cltbld', 18:49:24 INFO - 'LANG': 'en_US.UTF-8', 18:49:24 INFO - 'LANGUAGE': 'en_US:en', 18:49:24 INFO - 'LOGNAME': 'cltbld', 18:49:24 INFO - 'MAIL': '/var/mail/cltbld', 18:49:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:24 INFO - 'MOZ_NO_REMOTE': '1', 18:49:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:24 INFO - 'NO_EM_RESTART': '1', 18:49:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:49:24 INFO - 'PWD': '/builds/slave/test-pgo', 18:49:24 INFO - 'SHELL': '/bin/bash', 18:49:24 INFO - 'SHLVL': '1', 18:49:24 INFO - 'TERM': 'linux', 18:49:24 INFO - 'TMOUT': '86400', 18:49:24 INFO - 'USER': 'cltbld', 18:49:24 INFO - 'XDG_SESSION_COOKIE': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', 18:49:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:24 INFO - '_': '/tools/buildbot/bin/python'} 18:49:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:49:25 INFO - Running setup.py (path:/tmp/pip-x1RPUU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 18:49:25 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)) 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:49:25 INFO - Running setup.py (path:/tmp/pip-rNPLT0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 18:49:25 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)) 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:49:25 INFO - Running setup.py (path:/tmp/pip-8Ngt0o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 18:49:25 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)) 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:49:25 INFO - Running setup.py (path:/tmp/pip-fzTlXS-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 18:49:25 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)) 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 18:49:25 INFO - Running setup.py (path:/tmp/pip-Ct0ypb-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 18:49:25 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)) 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:49:25 INFO - Running setup.py (path:/tmp/pip-Asal1b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 18:49:25 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)) 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:49:25 INFO - Running setup.py (path:/tmp/pip-gt_XTT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 18:49:25 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)) 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:49:25 INFO - Running setup.py (path:/tmp/pip-MObHM_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 18:49:25 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)) 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 18:49:25 INFO - Running setup.py (path:/tmp/pip-XHl6XJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 18:49:25 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)) 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 18:49:25 INFO - Running setup.py (path:/tmp/pip-k3yZSW-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 18:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 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)) 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:49:25 INFO - Running setup.py (path:/tmp/pip-t__msC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 18:49:25 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)) 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:49:25 INFO - Running setup.py (path:/tmp/pip-P1VdB8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 18:49:25 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)) 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:49:25 INFO - Running setup.py (path:/tmp/pip-f2LScs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 18:49:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 13)) 18:49:25 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:49:25 INFO - Running setup.py (path:/tmp/pip-Gpty2g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 18:49:26 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)) 18:49:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:49:26 INFO - Running setup.py (path:/tmp/pip-HBMcdZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 18:49:26 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)) 18:49:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 18:49:26 INFO - Running setup.py (path:/tmp/pip-kQKocN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 18:49:26 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)) 18:49:26 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 18:49:26 INFO - Running setup.py (path:/tmp/pip-Ju61pC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 18:49:26 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)) 18:49:26 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)) 18:49:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 18:49:26 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)) 18:49:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 18:49:26 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)) 18:49:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 18:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:49:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:49:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:49:27 INFO - Downloading blessings-1.5.1.tar.gz 18:49:27 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 18:49:27 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 18:49:27 INFO - Installing collected packages: blessings 18:49:27 INFO - Running setup.py install for blessings 18:49:28 INFO - Successfully installed blessings 18:49:28 INFO - Cleaning up... 18:49:28 INFO - Return code: 0 18:49:28 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 18:49:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:49:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:49:28 INFO - Reading from file tmpfile_stdout 18:49:28 INFO - Current package versions: 18:49:28 INFO - argparse == 1.2.1 18:49:28 INFO - blessings == 1.5.1 18:49:28 INFO - blobuploader == 1.2.4 18:49:28 INFO - docopt == 0.6.1 18:49:28 INFO - manifestparser == 1.1 18:49:28 INFO - mozInstall == 1.12 18:49:28 INFO - mozcrash == 0.16 18:49:28 INFO - mozdebug == 0.1 18:49:28 INFO - mozdevice == 0.47 18:49:28 INFO - mozfile == 1.2 18:49:28 INFO - mozhttpd == 0.7 18:49:28 INFO - mozinfo == 0.9 18:49:28 INFO - mozleak == 0.1 18:49:28 INFO - mozlog == 3.0 18:49:28 INFO - moznetwork == 0.27 18:49:28 INFO - mozprocess == 0.22 18:49:28 INFO - mozprofile == 0.27 18:49:28 INFO - mozrunner == 6.11 18:49:28 INFO - mozscreenshot == 0.1 18:49:28 INFO - mozsystemmonitor == 0.0 18:49:28 INFO - moztest == 0.7 18:49:28 INFO - mozversion == 1.4 18:49:28 INFO - psutil == 3.1.1 18:49:28 INFO - requests == 1.2.3 18:49:28 INFO - wsgiref == 0.1.2 18:49:28 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 18:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:49:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:49:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:49:28 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-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 0x7fd95e745c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2baf6c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c706e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ca6a90>, '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 0x2cf8f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cf92e0>, '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': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', '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 18:49:28 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 18:49:28 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 18:49:28 INFO - Using env: {'DISPLAY': ':0', 18:49:28 INFO - 'HOME': '/home/cltbld', 18:49:28 INFO - 'LANG': 'en_US.UTF-8', 18:49:28 INFO - 'LANGUAGE': 'en_US:en', 18:49:28 INFO - 'LOGNAME': 'cltbld', 18:49:28 INFO - 'MAIL': '/var/mail/cltbld', 18:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:28 INFO - 'MOZ_NO_REMOTE': '1', 18:49:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:28 INFO - 'NO_EM_RESTART': '1', 18:49:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:49:28 INFO - 'PWD': '/builds/slave/test-pgo', 18:49:28 INFO - 'SHELL': '/bin/bash', 18:49:28 INFO - 'SHLVL': '1', 18:49:28 INFO - 'TERM': 'linux', 18:49:28 INFO - 'TMOUT': '86400', 18:49:28 INFO - 'USER': 'cltbld', 18:49:28 INFO - 'XDG_SESSION_COOKIE': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', 18:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:28 INFO - '_': '/tools/buildbot/bin/python'} 18:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 2)) 18:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 4)) 18:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 5)) 18:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 6)) 18:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 7)) 18:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 8)) 18:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 18:49:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 18:49:28 INFO - Cleaning up... 18:49:28 INFO - Return code: 0 18:49:28 INFO - Running post-action listener: _resource_record_post_action 18:49:28 INFO - Running post-action listener: _start_resource_monitoring 18:49:28 INFO - Starting resource monitoring. 18:49:28 INFO - ##### 18:49:28 INFO - ##### Running install step. 18:49:28 INFO - ##### 18:49:28 INFO - Running pre-action listener: _resource_record_pre_action 18:49:28 INFO - Running main action method: install 18:49:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 18:49:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 18:49:28 INFO - Reading from file tmpfile_stdout 18:49:28 INFO - Detecting whether we're running mozinstall >=1.0... 18:49:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 18:49:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 18:49:28 INFO - Reading from file tmpfile_stdout 18:49:28 INFO - Output received: 18:49:28 INFO - Usage: mozinstall [options] installer 18:49:28 INFO - Options: 18:49:28 INFO - -h, --help show this help message and exit 18:49:28 INFO - -d DEST, --destination=DEST 18:49:28 INFO - Directory to install application into. [default: 18:49:28 INFO - "/builds/slave/test-pgo"] 18:49:28 INFO - --app=APP Application being installed. [default: firefox] 18:49:28 INFO - mkdir: /builds/slave/test-pgo/build/application 18:49:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 18:49:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 18:49:43 INFO - Reading from file tmpfile_stdout 18:49:43 INFO - Output received: 18:49:43 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 18:49:43 INFO - Running post-action listener: _resource_record_post_action 18:49:43 INFO - ##### 18:49:43 INFO - ##### Running run-tests step. 18:49:43 INFO - ##### 18:49:43 INFO - Running pre-action listener: _resource_record_pre_action 18:49:43 INFO - Running main action method: run_tests 18:49:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 18:49:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 18:49:43 INFO - Python 2.7.3 18:49:43 INFO - Return code: 0 18:49:43 INFO - grabbing minidump binary from tooltool 18:49:43 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')]} 18:49:43 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 0x2ca6a90>, '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 0x2cf8f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2cf92e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 18:49:43 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 18:49:43 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 18:49:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:49:43 INFO - Return code: 0 18:49:43 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 18:49:43 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 18:49:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 18:49:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 18:49:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 18:49:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 18:49:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --webServer localhost 18:49:43 INFO - Using env: {'DISPLAY': ':0', 18:49:43 INFO - 'HOME': '/home/cltbld', 18:49:43 INFO - 'LANG': 'en_US.UTF-8', 18:49:43 INFO - 'LANGUAGE': 'en_US:en', 18:49:43 INFO - 'LOGNAME': 'cltbld', 18:49:43 INFO - 'MAIL': '/var/mail/cltbld', 18:49:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:49:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 18:49:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:49:43 INFO - 'MOZ_NO_REMOTE': '1', 18:49:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 18:49:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:49:43 INFO - 'NO_EM_RESTART': '1', 18:49:43 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:49:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 18:49:43 INFO - 'PWD': '/builds/slave/test-pgo', 18:49:43 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 18:49:43 INFO - 'SHELL': '/bin/bash', 18:49:43 INFO - 'SHLVL': '1', 18:49:43 INFO - 'TERM': 'linux', 18:49:43 INFO - 'TMOUT': '86400', 18:49:43 INFO - 'USER': 'cltbld', 18:49:43 INFO - 'XDG_SESSION_COOKIE': 'd16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472', 18:49:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:49:43 INFO - '_': '/tools/buildbot/bin/python'} 18:49:43 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost'] with output_timeout 3600 18:49:43 INFO - mozversion INFO | application_buildid: 20151119173011 18:49:43 INFO - mozversion INFO | application_changeset: c43d530f61c2672dd059a64c1ad04279f9650dd5 18:49:43 INFO - mozversion INFO | application_display_name: Nightly 18:49:43 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:49:43 INFO - mozversion INFO | application_name: Firefox 18:49:43 INFO - mozversion INFO | application_remotingname: firefox 18:49:43 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 18:49:43 INFO - mozversion INFO | application_vendor: Mozilla 18:49:43 INFO - mozversion INFO | application_version: 45.0a1 18:49:43 INFO - mozversion INFO | platform_buildid: 20151119173011 18:49:43 INFO - mozversion INFO | platform_changeset: c43d530f61c2672dd059a64c1ad04279f9650dd5 18:49:43 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 18:49:43 INFO - mozversion INFO | platform_version: 45.0a1 18:49:43 INFO - 2015-11-19 18:49:43,613 DEBUG : using testdate: 1447987783 18:49:43 INFO - 2015-11-19 18:49:43,614 DEBUG : actual date: 1447987783 18:49:43 INFO - 2015-11-19 18:49:43,622 INFO : starting webserver on 'localhost:42556' 18:49:43 INFO - 2015-11-19 18:49:43,623 INFO : Starting test suite talos-linux64-ix-034 18:49:43 INFO - 2015-11-19 18:49:43,623 INFO : Starting test dromaeo_css 18:49:43 INFO - 2015-11-19 18:49:43,623 DEBUG : operating with platform_type : linux_ 18:49:43 INFO - 2015-11-19 18:49:43,624 INFO : Initialising browser for dromaeo_css test... 18:49:43 INFO - 2015-11-19 18:49:43,640 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGARCLR/profile http://localhost:42556/getInfo.html 18:49:49 INFO - 2015-11-19 18:49:49,119 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:49:49 INFO - 2015-11-19 18:49:49,119 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:49:49 INFO - 2015-11-19 18:49:49,119 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:49:49 INFO - 2015-11-19 18:49:49,119 DEBUG : BROWSER_OUTPUT: __metrics 18:49:49 INFO - 2015-11-19 18:49:49,216 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:49:50 INFO - 2015-11-19 18:49:50,759 INFO : Browser initialized. 18:49:50 INFO - 2015-11-19 18:49:50,760 INFO : Running cycle 1/1 for dromaeo_css test... 18:49:50 INFO - 2015-11-19 18:49:50,760 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpGARCLR/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:50:02 INFO - 2015-11-19 18:50:02,584 DEBUG : BROWSER_OUTPUT: RSS: Main: 141393920 18:50:02 INFO - 2015-11-19 18:50:02,584 DEBUG : BROWSER_OUTPUT: 18:50:53 INFO - 2015-11-19 18:50:53,295 DEBUG : BROWSER_OUTPUT: 1447987853290 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] 18:53:03 INFO - 2015-11-19 18:53:03,444 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42556/tests/dromaeo/cssquery-ext.html) 18:53:04 INFO - 2015-11-19 18:53:04,650 DEBUG : BROWSER_OUTPUT: RSS: Main: 263020544 18:53:04 INFO - 2015-11-19 18:53:04,651 DEBUG : BROWSER_OUTPUT: 18:56:07 INFO - 2015-11-19 18:56:07,075 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/dromaeo/cssquery-ext.html (next: http://localhost:42556/tests/dromaeo/cssquery-jquery.html) 18:56:07 INFO - 2015-11-19 18:56:07,656 DEBUG : BROWSER_OUTPUT: RSS: Main: 221896704 18:56:07 INFO - 2015-11-19 18:56:07,657 DEBUG : BROWSER_OUTPUT: 18:59:09 INFO - 2015-11-19 18:59:09,572 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42556/tests/dromaeo/cssquery-mootools.html) 18:59:10 INFO - 2015-11-19 18:59:10,154 DEBUG : BROWSER_OUTPUT: RSS: Main: 215232512 18:59:10 INFO - 2015-11-19 18:59:10,154 DEBUG : BROWSER_OUTPUT: 19:02:11 INFO - 2015-11-19 19:02:11,985 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42556/tests/dromaeo/cssquery-prototype.html) 19:02:12 INFO - 2015-11-19 19:02:12,551 DEBUG : BROWSER_OUTPUT: RSS: Main: 199827456 19:02:12 INFO - 2015-11-19 19:02:12,551 DEBUG : BROWSER_OUTPUT: 19:05:15 INFO - 2015-11-19 19:05:15,023 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42556/tests/dromaeo/cssquery-yui.html) 19:05:15 INFO - 2015-11-19 19:05:15,624 DEBUG : BROWSER_OUTPUT: RSS: Main: 205479936 19:05:15 INFO - 2015-11-19 19:05:15,624 DEBUG : BROWSER_OUTPUT: 19:08:18 INFO - 2015-11-19 19:08:18,015 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/dromaeo/cssquery-yui.html (next: http://localhost:42556/tests/dromaeo/cssquery-dojo.html) 19:08:18 INFO - 2015-11-19 19:08:18,266 DEBUG : BROWSER_OUTPUT: RSS: Main: 259121152 19:08:18 INFO - 2015-11-19 19:08:18,266 DEBUG : BROWSER_OUTPUT: 19:08:18 INFO - 2015-11-19 19:08:18,267 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:08:18 INFO - 2015-11-19 19:08:18,267 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:08:18 INFO - 2015-11-19 19:08:18,268 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:08:18 INFO - 2015-11-19 19:08:18,268 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:08:18 INFO - 2015-11-19 19:08:18,269 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3156.842157892105;3225.564548785916;3272.1328847855307;3276.787008844425;3304.636489986102;5249.317588713471;5373.355197376311;5380.650257733254;5477.424870388611;5518.310211223592;5337;5446.673199608026;5446.700431476267;5526.640768350055;5544.334679838415;4988.127077761395;4991.425986011619;4998.250262460642;5013.623978201631;5015.924761128586;4824.565789078983;5026;5026.52248036436;5091.6690415123085;5128.692278463285;4843.128236917353;4898.902021959577;4950.455449900508;4960.479149689267;4971.179755340365;4920.975395123037;4921.311016457699;4929.644347804347;4938.061768264054;4967.329410529568;4885.902281954376;4903;4919.581835543947;4923.655344125913;4951.925721114186;5122.436531981479;5198.818041368534;5239.449857764918;5335.599830012726;5373;5919.023361145406;5925.140854576105;5937.881242375129;5943.791967281168;5946.672932988684;5827.592068555203;5938.168656388109;5950.315713692938;5965.582409231356;5974.462298393166;5397.892042159214;5457.317835270591;5462.3445186577865;5483.095289277305;5530.5852061095575;5958.255218097738;5961.254843144607;5974.790882319185;5986.191864098378;6048.788292409745;5548.278723765965;5814.215080964016;5815.156802263648;5820.679862607597;5846.649201047866;5620.185073164368;5712.80005199816;5716.371199168088;5838.883222335529;5843.620164689281;5808.88382232353;5890.087036509348;5893.263342082239;5904.025835737141;5912.290525137011;5878.11828225761;5885.293764748258;5886.058230683156;5888.941110588925;5894.440028197314;4350.303951367766;4429.069895321954;4435.844745433894;4531.977340113344;4563.133004729091;4024.5371782245415;4026.355783074694;4030.1939612077704;4032.2137183248983;4067.7559346439307;5532.170174473861;5537.335519737577;5543.445655434424;5543.972280138573;5598.412166722517;5678.0915053591225;5715.057015592381;5765.962126817213;5891.646501209942;5893.44012318829;5555.527780138734;5610.102383618601;5630.071038278638;5640.097584386561;5644.32268127819;5123.154679477844;5132.255822905732;5144.845654630367;5150.510701483353;5198.740062996835;5860.677662728591;5993.041113421918;6000.519958403317;6005.489533389711;6129.540284478593;5775.826725198251;5779.971100144472;5783.219265399117;5783.421657834183;5786.652800831964;6002.909956350571;6003.759849605967;6008.7296071676;6011.639301641915;6015.0676645120075;5664.886702265849;5684.8010319638615;5748.367679555162;5790.449907258803;5815.738291776965;5784.479396854304;5793.391693872083;5823.097419899922;5825.41745825431;5846.912296315473;5658.688772117573;5664.688442135557;5680.886382272249;5681.147827825859;5682.403347648437;5439.129739241861;5533.280673512418;5555.361133469541;5558.527525160407;5609.691466969356;5511.889762204813;5577.888442231051;5578.246936663499;5583.525400341016;5584.218209450762;5713.400092990342;5714.657120572779;5715.39988301239;5716.256886604715;5716.88520738452;5268;5421.996930567858;5423.430539793265;5570.58220633446;5685.829425117253 19:08:18 INFO - 2015-11-19 19:08:18,273 DEBUG : BROWSER_OUTPUT: |1;ext.html;543.6792292547397;586.9647821130733;593.4569868570259;601.2874743429037;613.2487702564359;29737.256568585795;41239.587604123946;41257.174856502854;41484;41548;43860;44442.555574444246;44475;44523.33215001778;44590.7770461148;7682.654280557388;7803.492772969767;7811.88282175765;8829.072947340517;8990;10645.627403040895;10767.784644307108;10776.46117694116;10893.07408870247;10904.61833835816;10534.315269507457;10614.681559553188;10671.893281067167;10688.144948404148;10691.358518488876;7916.327112195441;8074.152214017502;8083.191680831928;10422.062014418701;10705.83941240882;10405.79188416235;10544.841827372595;10555.155587552978;10559.524821383056;10559.894401055968;39951.60048399537;40475.59524404777;40514.18971620551;40564.79717601393;40571.59428405737;3398.3713013092656;3431.5367425397503;3538.0800991742076;3555.3600351936643;3797.39241721326;30498.144012629637;30957.53563696524;31162;31340;31388.843055784804;3907.5506316773367;3911.8435262589464;3973.662238709713;3989.2221016901717;4128.071183983609;287794;288746;289052;289193;290604;57766.422335776944;59191.70404147952;60608.696956515814;61165.69417152975;61645.38354616486;56818.43181568214;56845;57165.42834571601;57169.42830571724;57205.14192287119;14657.92671036638;14819.333130009176;14832.629184270263;14833.62915927089;14843.480478183212;11429.942850285861;11550.133739969537;11616.12879034059;11624.244424112574;11625.360605166628;20326.796732032784;20594;20642.380728578166;20642.896785515975;20719.067641955848;7617.847643047108;7658.477100533705;7732.110807257125;7761.301482866569;7766.262205090507;1008.7175590834578;1013.9290249682599;1065.5844220753913;1089.809283375406;1131.5247596009697;26379.340516487242;26425.207243782716;26937.73062269352;27440.588391174148;27504.44991100167;1001.7495626093477;1003.35449862226;1007.2043085962155;1049.2707568240153;1152.233764546567;14504.274786260856;14599.416023358946;14679;14987.775183372258;14988.47540336083;330708;331354;331702;335294;336280;54672.17991730127;54977.72511137499;55003.174952375746;55069.173962390596;55073.17390239069;1292.4700872642359;1319.70306680996;1324.9337533123305;1325.132038514755;1339.8191244182105;27588;28137.296567585156;28303.2924176889;28578.142655720363;29756.404871902872;43328.78335608302;44509.77745111254;44946;49347.25979110388;50932.49067509278;218826;219232;220628;221774;222325;27479.450410992067;30094;30357.39285214239;30385.848070759504;30409.543856842607;37007.44488832616;40526.39210411787;41182.38226426546;41604.3759343604;41809.79095104505;36091.278174435836;36506.452403214505;37302.253954920205;37465;38161.42757858579;35004;35224.64775352316;35653.64346356503;37402.81298593598;37601.62398375981;33054.17364565905;34059.65940340664;34208.14479638029;34262.657373425944;34313.656863431046;28398;32381.676183237865;33816;33943.830280849426;33948;25361.746382535937;25386.365340866625;25656.615150773127;25673.229803105936;27545 19:08:18 INFO - 2015-11-19 19:08:18,273 DEBUG : BROWSER_OUTPUT: |2;jquery.html;356.76275276940834;359.62060026671867;364.7100555058728;364.998175009125;365.9871904483344;14100.57698269051;14204.573862784133;16227.350905963762;16486.917565412172;17088;15711.214439278048;16827.915860420682;16996.4051258206;17022.82977170228;17179.74230386542;5457.481539253775;5602.8599285018;5610.438956104398;5610.747516361753;5624;3943.093088589612;4004.0190153412454;5551.306086739158;5583.972080139593;5945.524358051345;5983;5998.820035398945;6030.66831324277;6031.155638210654;6032.9698351508405;5231.11071117909;5366.812161574346;5369.516743493065;5376.086065368858;5986.580939334227;5100.719460429675;5149.2791009258735;5158.819441319573;5166.560842328407;5339.733013349317;18780.81219187804;18783.530411739815;19093.522661933424;19095.427137185907;19122.330718424928;2020.1414398880418;2031.086011294923;2056.033664177834;2065.2771529964393;2077.5637116205617;9418.246540276761;9513.66702165421;9628.085331893459;9866.65466708669;9900.009999000114;1801.639672065579;1805.6208196278797;1843.2258451450289;1887.5564242403161;1889.9716504252447;59397.703011484664;60281.09578356328;60348.69825650888;62165.6891715543;64069;10013.699589012196;10248.846267305997;10276.845847312296;10295.691129266133;10348;13457.125286856322;13460.596182114552;13464.326783660777;13483.460661573426;13485;7461.216572259943;7508.62456877154;7515.661795219228;7540.359069479046;7599.278068583476;7064.222935477093;7107.6446177690905;7162.033125528092;7221.566705997657;7235.312645298688;4367.235733746582;4413.271810151353;4413.492448368414;4442.333649952533;4453.933191002137;1714.1857617631524;1786.7230579260236;1803.2877013579562;2963.2147480917574;2995.3110784519245;863.313665635821;872.8341615093112;889.7820034091689;915;963.9807203855884;14131.71736565263;15485.690286194213;15875.444359447363;16105.597360065858;16279.51161465158;884.1688812516214;886.7605746448423;957.7270477913901;981.2297346582898;1003.2225025605069;9112.681056163003;9327.207187389147;9334.813303733887;9373.578188981512;9414.058594140668;552512;552917;554523;577418;579777;81935;84390;84492;85610.14389856021;85612;1159.0495793449459;1160.1125139268465;1171.7949358862165;1175.2830773228325;1178.8938995490275;14280.075347633296;14392.712145757236;14539.854601454274;14762.409503620165;14838.851611483748;33933.49099763555;35062;35809.46285805663;36497;37305;47926;48003.5199647999;48059;48138.03723925572;48667.02665946732;9399.389039712396;9511.57197926081;9679.951600242235;9693.757656058655;9740.123388895034;16676.249568769676;17076.146192690067;17114.57213569621;17126.571835703708;17415.825841741927;14588.562343129723;14644.194569298365;14959.700805983603;15250.77123843121;15251.313690884159;14646.63383415423;14657.413703451743;14968.101913885203;15041.6991660164;15343.07941523512;11426.428678565871;14258.928705356408;14460.783088253895;14903.478378257143;15604.375824967328;9305.581248843953;9605;9605;9618.086281803218;9619.807603848023;9876;9896.802063958536;10152;14507.347169377614;14534.345954431865 19:08:18 INFO - 2015-11-19 19:08:18,276 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1305.8367704036996;1376.2980879751326;1425.422703804959;1569.2938177819976;1769.1950162675976;6451.9032214516765;7249.202587715347;7379.3727533159745;7406.703731850733;7415.295546923047;7244.0582724245905;7412.369948554379;7448.441366897478;7462.253774622535;7468.327850493454;1944.057132290838;1978.1592823050219;2164.8159906407973;2205.0518277140864;2224.6774217738416;2238.9664155037685;2244.7979681828715;2255.503789166389;2255.5827171973237;2257.2663884237604;2168.8373371997086;2247.2584047264445;2253.8422310438277;2258.5369999150216;2259.9096036158535;1955.4329244519072;1997.2111016148613;2165.9783402165936;2200.702905107822;2210.0938615167706;2233.039792889057;2236.809871160953;2257.808086312665;2266.4220623741007;2323.1752727781563;7399.408047356253;7448.776536703907;7461.664225109829;7466.029416175917;7483.214262502468;4560.9771951140365;4617.330487079388;4645.372874661911;4666.929996050062;4676.625869930397;3067.1412004638737;3119.9220019499467;3135.1221657935816;3136.2159460134967;3185.8725650973934;1037.4968140451874;1042.0829669890525;1079.3739631013993;1085.93484390936;1093.33853018924;10304.587816487332;10348;10450.16398688103;10450.268481206318;10487.94756026215;4114.958850411517;4140.60664236897;4180;4329.047609525899;4337.110892267077;8100.473469224599;8104.8379032419025;8114.350851931831;8166.346692264605;8177.4684645497855;4555.339475775994;4561.315802629566;4581.633469322446;4593.402857628487;4602.585767280961;4194.203101410722;4214.452121224222;4228.61942425177;4233.068724880522;4236.173946080547;3373.089265898194;3445.672661097192;3450.9482357764655;3453.8963831085107;3461.359648465042;1056.7463808686014;1080.7568297133082;1089.4008295437477;1093.6992327110108;1095.917806164541;1271.3325504110417;1282.4165004922738;1285.1967520299813;1306.288073000217;1337.4650139943906;6983.860322793617;6994.510384273052;7060.364567188978;7067.92932070683;7083.220845706968;479.85364463838863;484.4525685974826;488.1067646207432;493.85431297767246;503.9874003149877;5615.550755939515;5638.097904335286;5653.23681303027;5656.773729050874;5660.575456840754;8504.192101750325;8628.913710862937;8630.093840146694;8731.083236260229;8796.604152813139;8711.912880871238;8809.383343165917;8838.602262898185;8947.821043579092;8967.282617390592;6626.105475760711;6686.699098540577;6692.19310786085;6731.562448440738;6755.4257888080065;6790.151231096113;6843.692033858588;6874;6878.55289406187;6883.518153729391;7741.7677469676;7926.762197134095;7993.760187194393;8000.5599692017495;8005.5196688196565;8068.394870384862;8111.391645626483;8138.633761480866;8236.052853921876;8273.089960104264;6036.396360363929;6115.816525504242;6145.66198859067;6701.0953521274005;6768.593884366954;7140.714371425085;7181.35367816899;7185.173705023989;7186.209516953236;7191.640417978975;5786.363500015079;6546.574472659262;6803.149606299213;6898.379145876896;6978.685959131953;6418.743250269937;6548.410643041959;6566;6747.595144291358;6939.548929319578;6582.736690532325;6591.242007169045;6605.141331626858;6686.231083425274;6785;5986.910196347146;6051.788187413419;6086.908696369469;6093.75624974996;6100.54245931548;5980.820575382745;6315.5894866833505;6323.841903952254;6328.335524769833;6338.429541341301 19:08:18 INFO - 2015-11-19 19:08:18,278 DEBUG : BROWSER_OUTPUT: |4;prototype.html;341.44174275060277;344.95688038995127;351.825846206128;353.20000199548014;353.6975885617797;5985.670788106652;6000.069989151678;6021.427964343391;6023.397660233974;6041.9093713594275;5961.761529538824;5963.463288304052;5967.224260846084;5968.67172305523;5970.014947533663;4539.704919180259;4636.142313671965;4642.32686260492;4644.442666879962;4648.860534183971;4607.9308810367875;4637.652176086807;4638.281066434708;4638.582527572517;4644.094401591691;4590.540945905416;4591.104734576759;4592.540745925414;4604.861854144381;4608.5621865922685;4377.4528183977;4446.466424029105;4460.063386688799;4589.65577581681;4597.586217240448;4043.7573745575064;4086;4094.754314741125;4096.446979657738;4102.610252026053;5675.318961724578;5683.5169010634145;5684.914726279109;5696.487316141567;5705.087186050254;5102.209157580543;5243.8164664236565;5282.2076688496645;5374.140137578008;5381.919271210935;6291.1192433059405;6302.369763023707;6306.306306306303;6311.368863113697;6320.146780184708;4515.683902126852;4516.261239422813;4516.458025036984;4521.570450807168;4524.547545245482;6413.1662883825275;6572.375624315682;6579.736810527573;6583.506237032242;6591.11020012297;4513.390692256569;4538.773061346919;4539.614132798683;4541.318802179633;4544.454665440168;4578.404807375086;4580.290055041474;4593.28804035375;4597.747123908217;4602;5284.788608455696;5287.312649355559;5290.550303224269;5292.708901010405;5306.8142615009065;6178.567500274938;6320.4311611955145;6330.778422755181;6365.140706004723;6409.711562979677;1600.7918970533756;1620.3842539834786;1620.5705488045678;1622.059205660714;1622.107840687617;1079.9028087472166;1081.3944191252924;1090.1006669497697;1108.362691452402;1112.2547892911768;6133.570650054453;6135.478484328793;6138.171346868205;6138.386161383826;6140.8771824564155;6453.774117905851;6473.741050358027;6482.416582507597;6485.254195767453;6488.805335839932;6015.248093988252;6077.453029227391;6102.0541816018585;6112.327643959161;6127.356627554132;3488.424409972326;3552.857885684544;3559.056849934769;3563.7149028077692;3564.483149943244;6787.2534021257625;6819.352161544645;6837.726490940307;6856.040154378391;6862.519623626398;6469.935300647027;6581.308962558863;6631.10480085192;6714.1271634688155;6722.731090756315;6857.55425897315;6886.655667216619;6916.585004899722;6919.619420931796;6938.618375989268;6582.3746744059235;6600.669966501751;6617.338266173344;6643.136392268974;6649.135612370555;6427.132337134614;6550.050242714684;6552.934470655233;6569.113169722026;6573.57271777333;6861.656917154022;6868.416184624363;6874.415674667709;6914;6923.169219693669;4474.530174331779;4478.037221997286;4479.148961697267;4481.036577135931;4488.68579199466;6453.1610890583925;6600.53796234259;6629.46964242869;6631.867362652816;6633.6351500666;6382.872342553216;6411.551191416556;6418.807435776934;6436.645984470899;6437.806865794034;6419.165508483868;6423.42189202974;6426.3573642635365;6427.742890284337;6429.8392540185;6170.629762214281;6275.780347687809;6291.182146320949;6294.590851594632;6298.559100862895;6457.6771161442675;6468;6472.320406357453;6473.482121430368;6473.902891456726;6280.0893870389455;6310.368963103837;6315.431611155018;6317.842053948688;6320.873582528416 19:08:18 INFO - 2015-11-19 19:08:18,279 DEBUG : BROWSER_OUTPUT: |5;yui.html;774.4424014709407;781.1485480825899;786.1195461083587;842.8019415437371;864.425157270415;12457.813132803016;12793.808092878615;12961.092723509346;12993.090483666147;12994.93502532486;12545.49818007281;12602.558910438136;12615.05387095967;12616;12621.495140194404;675.0380707491829;680.928502507237;802.6147449224361;810.3557671651022;820.9630566624486;816.8121332093622;819.1971867569786;823.6911158315631;827.0943317067802;827.6937533112757;866.4931015356025;875.9386842920998;876.071364353787;876.5047748022347;876.8903887014123;695.4610177112728;695.7843068648709;853.7737499562621;862.4868203418956;869.0657543141149;638.3935261501592;640.7244884699577;641.6278558436096;641.8491654461197;641.8716256748669;12580.748385032248;12633.557825476064;12724.491020359266;12726.80909786354;12731.108822382437;11804.46879890407;11840.822387664191;12334.136610437274;12438.875611243953;12475.563355282613;10323.483825808755;10424.113950314233;10659.733506662318;10703.732406689816;10746.892531074745;3570.036090255642;3644.7813131211947;3686.5391826021746;3693.7229707772026;3786.5266841644793;45238.09523809538;45657.771711141235;45665.31502027472;45734.542654573364;45881.77059114683;9353.158215760614;9533.094356036165;9562.190146535217;9796.26528010402;9819.312648114706;17431.73852392215;18049.639007219783;18087.547811304823;18110.63778724418;18455;7008.719651213894;7135.286471352823;7213.8557228855125;7214.098237720285;7248.311410416002;3819.9618003819605;3936.842526298916;3958.5645578986287;3968.3849003404516;3985.980070099631;3239.72462340699;3297.274599588087;3299.109240505098;3301.290222602152;3305.719013883847;546.3471151973354;558.0568838662647;566.1762136091976;578.6817250512202;580.962237454564;553.4106176921566;608.1091201389985;651.465798045598;651.8012006338113;704.8555046215514;7424.3504435995055;7584.2036586158765;7701.537907725555;7884.448088633741;7937.285644291926;608.954328425361;655.4199533412964;673.7608149107094;686.8282929267683;703.4864548879244;3440.501127336522;3751.5498140222803;3763.6800871926193;3779.7354185206773;3802.733808633369;58897.41102589005;60212.397876021554;60263.397366025776;60321.69839150776;60479.69760151171;52242;53077.2038419424;53379.73310133425;53569;54498;47896.76051619789;48390.75804620954;48465;48493.5150648489;49960.500394996314;7470.402367810453;7515.736949206752;7624.5425274483705;7751.186125456971;7779.961100194463;34458.31083378368;34606.480902786985;35313;35659;35719;41753.37369939514;42091;43737.34393984029;44818.77590612026;45107.77446112748;9963.950180249052;10112.59549618007;10359.84460233082;10390.324628899098;10433.947830261104;11111.72220694489;11665.183437159318;11810;11896.583619573274;12350.320732359807;10458.738531536772;10466.895331046897;10481.213908957012;10533.631322903968;10580.15358771312;10023.248256380946;10180.338278011906;10211.591536338463;10432.530536126045;10539.578416863545;10281.640142594973;10296.536655850356;10500.264981451224;10578.365298082139;10591.152707783513;8790.815031577082;9258.49078300673;9267.907320926704;9466.158483421657;9571;7715.498492598188;7812.218778122142;8109.107998120084;8345.54099524532;8739.21347078766 19:08:18 INFO - 2015-11-19 19:08:18,279 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:08:18 INFO - 2015-11-19 19:08:18,279 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:08:18 INFO - 2015-11-19 19:08:18,280 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1009 19:08:18 INFO - 2015-11-19 19:08:18,280 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:08:18 INFO - 2015-11-19 19:08:18,280 DEBUG : BROWSER_OUTPUT: __startTimestamp1447988898266__endTimestamp 19:08:18 INFO - 2015-11-19 19:08:18,280 DEBUG : BROWSER_OUTPUT: 19:08:18 INFO - 2015-11-19 19:08:18,280 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:08:18 INFO - 2015-11-19 19:08:18,280 DEBUG : BROWSER_OUTPUT: Number of tests: 6 19:08:18 INFO - 2015-11-19 19:08:18,280 DEBUG : BROWSER_OUTPUT: 19:08:18 INFO - 2015-11-19 19:08:18,280 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5414.47 Median:5583.53 stddev:599.83 (10.7%) stddev-sans-first:574.93 19:08:18 INFO - 2015-11-19 19:08:18,280 DEBUG : BROWSER_OUTPUT: Values: 3156.8 3225.6 3272.1 3276.8 3304.6 5249.3 5373.4 5380.7 5477.4 5518.3 5337.0 5446.7 5446.7 5526.6 5544.3 4988.1 4991.4 4998.3 5013.6 5015.9 4824.6 5026.0 5026.5 5091.7 5128.7 4843.1 4898.9 4950.5 4960.5 4971.2 4921.0 4921.3 4929.6 4938.1 4967.3 4885.9 4903.0 4919.6 4923.7 4951.9 5122.4 5198.8 5239.4 5335.6 5373.0 5919.0 5925.1 5937.9 5943.8 5946.7 5827.6 5938.2 5950.3 5965.6 5974.5 5397.9 5457.3 5462.3 5483.1 5530.6 5958.3 5961.3 5974.8 5986.2 6048.8 5548.3 5814.2 5815.2 5820.7 5846.6 5620.2 5712.8 5716.4 5838.9 5843.6 5808.9 5890.1 5893.3 5904.0 5912.3 5878.1 5885.3 5886.1 5888.9 5894.4 4350.3 4429.1 4435.8 4532.0 4563.1 4024.5 4026.4 4030.2 4032.2 4067.8 5532.2 5537.3 5543.4 5544.0 5598.4 5678.1 5715.1 5766.0 5891.6 5893.4 5555.5 5610.1 5630.1 5640.1 5644.3 5123.2 5132.3 5144.8 5150.5 5198.7 5860.7 5993.0 6000.5 6005.5 6129.5 5775.8 5780.0 5783.2 5783.4 5786.7 6002.9 6003.8 6008.7 6011.6 6015.1 5664.9 5684.8 5748.4 5790.4 5815.7 5784.5 5793.4 5823.1 5825.4 5846.9 5658.7 5664.7 5680.9 5681.1 5682.4 5439.1 5533.3 5555.4 5558.5 5609.7 5511.9 5577.9 5578.2 5583.5 5584.2 5713.4 5714.7 5715.4 5716.3 5716.9 5268.0 5422.0 5423.4 5570.6 5685.8 19:08:18 INFO - 2015-11-19 19:08:18,280 DEBUG : BROWSER_OUTPUT: 19:08:18 INFO - 2015-11-19 19:08:18,280 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45493.02 Median:181881.09 stddev:74377.02 (40.9%) stddev-sans-first:74509.10 19:08:18 INFO - 2015-11-19 19:08:18,282 DEBUG : BROWSER_OUTPUT: Values: 543.7 587.0 593.5 601.3 613.2 29737.3 41239.6 41257.2 41484.0 41548.0 43860.0 44442.6 44475.0 44523.3 44590.8 7682.7 7803.5 7811.9 8829.1 8990.0 10645.6 10767.8 10776.5 10893.1 10904.6 10534.3 10614.7 10671.9 10688.1 10691.4 7916.3 8074.2 8083.2 10422.1 10705.8 10405.8 10544.8 10555.2 10559.5 10559.9 39951.6 40475.6 40514.2 40564.8 40571.6 3398.4 3431.5 3538.1 3555.4 3797.4 30498.1 30957.5 31162.0 31340.0 31388.8 3907.6 3911.8 3973.7 3989.2 4128.1 287794.0 288746.0 289052.0 289193.0 290604.0 57766.4 59191.7 60608.7 61165.7 61645.4 56818.4 56845.0 57165.4 57169.4 57205.1 14657.9 14819.3 14832.6 14833.6 14843.5 11429.9 11550.1 11616.1 11624.2 11625.4 20326.8 20594.0 20642.4 20642.9 20719.1 7617.8 7658.5 7732.1 7761.3 7766.3 1008.7 1013.9 1065.6 1089.8 1131.5 26379.3 26425.2 26937.7 27440.6 27504.4 1001.7 1003.4 1007.2 1049.3 1152.2 14504.3 14599.4 14679.0 14987.8 14988.5 330708.0 331354.0 331702.0 335294.0 336280.0 54672.2 54977.7 55003.2 55069.2 55073.2 1292.5 1319.7 1324.9 1325.1 1339.8 27588.0 28137.3 28303.3 28578.1 29756.4 43328.8 44509.8 44946.0 49347.3 50932.5 218826.0 219232.0 220628.0 221774.0 222325.0 27479.5 30094.0 30357.4 30385.8 30409.5 37007.4 40526.4 41182.4 41604.4 41809.8 36091.3 36506.5 37302.3 37465.0 38161.4 35004.0 35224.6 35653.6 37402.8 37601.6 33054.2 34059.7 34208.1 34262.7 34313.7 28398.0 32381.7 33816.0 33943.8 33948.0 25361.7 25386.4 25656.6 25673.2 27545.0 19:08:18 INFO - 2015-11-19 19:08:18,282 DEBUG : BROWSER_OUTPUT: 19:08:18 INFO - 2015-11-19 19:08:18,282 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29584.56 Median:4185.63 stddev:92141.67 (2201.4%) stddev-sans-first:92374.02 19:08:18 INFO - 2015-11-19 19:08:18,282 DEBUG : BROWSER_OUTPUT: Values: 356.8 359.6 364.7 365.0 366.0 14100.6 14204.6 16227.4 16486.9 17088.0 15711.2 16827.9 16996.4 17022.8 17179.7 5457.5 5602.9 5610.4 5610.7 5624.0 3943.1 4004.0 5551.3 5584.0 5945.5 5983.0 5998.8 6030.7 6031.2 6033.0 5231.1 5366.8 5369.5 5376.1 5986.6 5100.7 5149.3 5158.8 5166.6 5339.7 18780.8 18783.5 19093.5 19095.4 19122.3 2020.1 2031.1 2056.0 2065.3 2077.6 9418.2 9513.7 9628.1 9866.7 9900.0 1801.6 1805.6 1843.2 1887.6 1890.0 59397.7 60281.1 60348.7 62165.7 64069.0 10013.7 10248.8 10276.8 10295.7 10348.0 13457.1 13460.6 13464.3 13483.5 13485.0 7461.2 7508.6 7515.7 7540.4 7599.3 7064.2 7107.6 7162.0 7221.6 7235.3 4367.2 4413.3 4413.5 4442.3 4453.9 1714.2 1786.7 1803.3 2963.2 2995.3 863.3 872.8 889.8 915.0 964.0 14131.7 15485.7 15875.4 16105.6 16279.5 884.2 886.8 957.7 981.2 1003.2 9112.7 9327.2 9334.8 9373.6 9414.1 552512.0 552917.0 554523.0 577418.0 579777.0 81935.0 84390.0 84492.0 85610.1 85612.0 1159.0 1160.1 1171.8 1175.3 1178.9 14280.1 14392.7 14539.9 14762.4 14838.9 33933.5 35062.0 35809.5 36497.0 37305.0 47926.0 48003.5 48059.0 48138.0 48667.0 9399.4 9511.6 9680.0 9693.8 9740.1 16676.2 17076.1 17114.6 17126.6 17415.8 14588.6 14644.2 14959.7 15250.8 15251.3 14646.6 14657.4 14968.1 15041.7 15343.1 11426.4 14258.9 14460.8 14903.5 15604.4 9305.6 9605.0 9605.0 9618.1 9619.8 9876.0 9896.8 10152.0 14507.3 14534.3 19:08:18 INFO - 2015-11-19 19:08:18,282 DEBUG : BROWSER_OUTPUT: 19:08:18 INFO - 2015-11-19 19:08:18,282 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5188.44 Median:5883.59 stddev:2664.11 (45.3%) stddev-sans-first:2655.60 19:08:18 INFO - 2015-11-19 19:08:18,282 DEBUG : BROWSER_OUTPUT: Values: 1305.8 1376.3 1425.4 1569.3 1769.2 6451.9 7249.2 7379.4 7406.7 7415.3 7244.1 7412.4 7448.4 7462.3 7468.3 1944.1 1978.2 2164.8 2205.1 2224.7 2239.0 2244.8 2255.5 2255.6 2257.3 2168.8 2247.3 2253.8 2258.5 2259.9 1955.4 1997.2 2166.0 2200.7 2210.1 2233.0 2236.8 2257.8 2266.4 2323.2 7399.4 7448.8 7461.7 7466.0 7483.2 4561.0 4617.3 4645.4 4666.9 4676.6 3067.1 3119.9 3135.1 3136.2 3185.9 1037.5 1042.1 1079.4 1085.9 1093.3 10304.6 10348.0 10450.2 10450.3 10487.9 4115.0 4140.6 4180.0 4329.0 4337.1 8100.5 8104.8 8114.4 8166.3 8177.5 4555.3 4561.3 4581.6 4593.4 4602.6 4194.2 4214.5 4228.6 4233.1 4236.2 3373.1 3445.7 3450.9 3453.9 3461.4 1056.7 1080.8 1089.4 1093.7 1095.9 1271.3 1282.4 1285.2 1306.3 1337.5 6983.9 6994.5 7060.4 7067.9 7083.2 479.9 484.5 488.1 493.9 504.0 5615.6 5638.1 5653.2 5656.8 5660.6 8504.2 8628.9 8630.1 8731.1 8796.6 8711.9 8809.4 8838.6 8947.8 8967.3 6626.1 6686.7 6692.2 6731.6 6755.4 6790.2 6843.7 6874.0 6878.6 6883.5 7741.8 7926.8 7993.8 8000.6 8005.5 8068.4 8111.4 8138.6 8236.1 8273.1 6036.4 6115.8 6145.7 6701.1 6768.6 7140.7 7181.4 7185.2 7186.2 7191.6 5786.4 6546.6 6803.1 6898.4 6978.7 6418.7 6548.4 6566.0 6747.6 6939.5 6582.7 6591.2 6605.1 6686.2 6785.0 5986.9 6051.8 6086.9 6093.8 6100.5 5980.8 6315.6 6323.8 6328.3 6338.4 19:08:18 INFO - 2015-11-19 19:08:18,282 DEBUG : BROWSER_OUTPUT: 19:08:18 INFO - 2015-11-19 19:08:18,283 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5337.70 Median:6059.68 stddev:1604.10 (26.5%) stddev-sans-first:1564.15 19:08:18 INFO - 2015-11-19 19:08:18,284 DEBUG : BROWSER_OUTPUT: Values: 341.4 345.0 351.8 353.2 353.7 5985.7 6000.1 6021.4 6023.4 6041.9 5961.8 5963.5 5967.2 5968.7 5970.0 4539.7 4636.1 4642.3 4644.4 4648.9 4607.9 4637.7 4638.3 4638.6 4644.1 4590.5 4591.1 4592.5 4604.9 4608.6 4377.5 4446.5 4460.1 4589.7 4597.6 4043.8 4086.0 4094.8 4096.4 4102.6 5675.3 5683.5 5684.9 5696.5 5705.1 5102.2 5243.8 5282.2 5374.1 5381.9 6291.1 6302.4 6306.3 6311.4 6320.1 4515.7 4516.3 4516.5 4521.6 4524.5 6413.2 6572.4 6579.7 6583.5 6591.1 4513.4 4538.8 4539.6 4541.3 4544.5 4578.4 4580.3 4593.3 4597.7 4602.0 5284.8 5287.3 5290.6 5292.7 5306.8 6178.6 6320.4 6330.8 6365.1 6409.7 1600.8 1620.4 1620.6 1622.1 1622.1 1079.9 1081.4 1090.1 1108.4 1112.3 6133.6 6135.5 6138.2 6138.4 6140.9 6453.8 6473.7 6482.4 6485.3 6488.8 6015.2 6077.5 6102.1 6112.3 6127.4 3488.4 3552.9 3559.1 3563.7 3564.5 6787.3 6819.4 6837.7 6856.0 6862.5 6469.9 6581.3 6631.1 6714.1 6722.7 6857.6 6886.7 6916.6 6919.6 6938.6 6582.4 6600.7 6617.3 6643.1 6649.1 6427.1 6550.1 6552.9 6569.1 6573.6 6861.7 6868.4 6874.4 6914.0 6923.2 4474.5 4478.0 4479.1 4481.0 4488.7 6453.2 6600.5 6629.5 6631.9 6633.6 6382.9 6411.6 6418.8 6436.6 6437.8 6419.2 6423.4 6426.4 6427.7 6429.8 6170.6 6275.8 6291.2 6294.6 6298.6 6457.7 6468.0 6472.3 6473.5 6473.9 6280.1 6310.4 6315.4 6317.8 6320.9 19:08:18 INFO - 2015-11-19 19:08:18,284 DEBUG : BROWSER_OUTPUT: 19:08:18 INFO - 2015-11-19 19:08:18,284 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13587.91 Median:48427.88 stddev:16285.86 (33.6%) stddev-sans-first:16303.12 19:08:18 INFO - 2015-11-19 19:08:18,284 DEBUG : BROWSER_OUTPUT: Values: 774.4 781.1 786.1 842.8 864.4 12457.8 12793.8 12961.1 12993.1 12994.9 12545.5 12602.6 12615.1 12616.0 12621.5 675.0 680.9 802.6 810.4 821.0 816.8 819.2 823.7 827.1 827.7 866.5 875.9 876.1 876.5 876.9 695.5 695.8 853.8 862.5 869.1 638.4 640.7 641.6 641.8 641.9 12580.7 12633.6 12724.5 12726.8 12731.1 11804.5 11840.8 12334.1 12438.9 12475.6 10323.5 10424.1 10659.7 10703.7 10746.9 3570.0 3644.8 3686.5 3693.7 3786.5 45238.1 45657.8 45665.3 45734.5 45881.8 9353.2 9533.1 9562.2 9796.3 9819.3 17431.7 18049.6 18087.5 18110.6 18455.0 7008.7 7135.3 7213.9 7214.1 7248.3 3820.0 3936.8 3958.6 3968.4 3986.0 3239.7 3297.3 3299.1 3301.3 3305.7 546.3 558.1 566.2 578.7 581.0 553.4 608.1 651.5 651.8 704.9 7424.4 7584.2 7701.5 7884.4 7937.3 609.0 655.4 673.8 686.8 703.5 3440.5 3751.5 3763.7 3779.7 3802.7 58897.4 60212.4 60263.4 60321.7 60479.7 52242.0 53077.2 53379.7 53569.0 54498.0 47896.8 48390.8 48465.0 48493.5 49960.5 7470.4 7515.7 7624.5 7751.2 7780.0 34458.3 34606.5 35313.0 35659.0 35719.0 41753.4 42091.0 43737.3 44818.8 45107.8 9964.0 10112.6 10359.8 10390.3 10433.9 11111.7 11665.2 11810.0 11896.6 12350.3 10458.7 10466.9 10481.2 10533.6 10580.2 10023.2 10180.3 10211.6 10432.5 10539.6 10281.6 10296.5 10500.3 10578.4 10591.2 8790.8 9258.5 9267.9 9466.2 9571.0 7715.5 7812.2 8109.1 8345.5 8739.2 19:08:18 INFO - 2015-11-19 19:08:18,284 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:08:18 INFO - 2015-11-19 19:08:18,284 DEBUG : BROWSER_OUTPUT: 19:08:18 INFO - 2015-11-19 19:08:18,996 INFO : Browser exited with error code: 0 19:08:19 INFO - 2015-11-19 19:08:19,017 INFO : Completed test dromaeo_css (00:18:35) 19:08:19 INFO - 2015-11-19 19:08:19,017 INFO : Starting test kraken 19:08:19 INFO - 2015-11-19 19:08:19,017 DEBUG : operating with platform_type : linux_ 19:08:19 INFO - 2015-11-19 19:08:19,017 INFO : Initialising browser for kraken test... 19:08:19 INFO - 2015-11-19 19:08:19,023 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpj3oCVE/profile http://localhost:42556/getInfo.html 19:08:23 INFO - 2015-11-19 19:08:23,291 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:08:23 INFO - 2015-11-19 19:08:23,292 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:08:23 INFO - 2015-11-19 19:08:23,292 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:08:23 INFO - 2015-11-19 19:08:23,292 DEBUG : BROWSER_OUTPUT: __metrics 19:08:23 INFO - 2015-11-19 19:08:23,353 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:08:24 INFO - 2015-11-19 19:08:24,694 INFO : Browser initialized. 19:08:24 INFO - 2015-11-19 19:08:24,694 INFO : Running cycle 1/1 for kraken test... 19:08:24 INFO - 2015-11-19 19:08:24,695 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpj3oCVE/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:08:36 INFO - 2015-11-19 19:08:36,433 DEBUG : BROWSER_OUTPUT: RSS: Main: 141656064 19:08:36 INFO - 2015-11-19 19:08:36,433 DEBUG : BROWSER_OUTPUT: 19:08:38 INFO - 2015-11-19 19:08:38,888 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42556/tests/kraken/driver.html?testName=audio-beat-detection) 19:08:39 INFO - 2015-11-19 19:08:39,469 DEBUG : BROWSER_OUTPUT: RSS: Main: 179843072 19:08:39 INFO - 2015-11-19 19:08:39,470 DEBUG : BROWSER_OUTPUT: 19:08:42 INFO - 2015-11-19 19:08:42,029 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42556/tests/kraken/driver.html?testName=audio-dft) 19:08:42 INFO - 2015-11-19 19:08:42,611 DEBUG : BROWSER_OUTPUT: RSS: Main: 168148992 19:08:42 INFO - 2015-11-19 19:08:42,611 DEBUG : BROWSER_OUTPUT: 19:08:45 INFO - 2015-11-19 19:08:45,474 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42556/tests/kraken/driver.html?testName=audio-fft) 19:08:46 INFO - 2015-11-19 19:08:46,023 DEBUG : BROWSER_OUTPUT: RSS: Main: 151351296 19:08:46 INFO - 2015-11-19 19:08:46,023 DEBUG : BROWSER_OUTPUT: 19:08:48 INFO - 2015-11-19 19:08:48,015 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42556/tests/kraken/driver.html?testName=audio-oscillator) 19:08:48 INFO - 2015-11-19 19:08:48,582 DEBUG : BROWSER_OUTPUT: RSS: Main: 153952256 19:08:48 INFO - 2015-11-19 19:08:48,582 DEBUG : BROWSER_OUTPUT: 19:08:50 INFO - 2015-11-19 19:08:50,735 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42556/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:08:51 INFO - 2015-11-19 19:08:51,296 DEBUG : BROWSER_OUTPUT: RSS: Main: 155770880 19:08:51 INFO - 2015-11-19 19:08:51,297 DEBUG : BROWSER_OUTPUT: 19:08:54 INFO - 2015-11-19 19:08:54,183 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42556/tests/kraken/driver.html?testName=imaging-darkroom) 19:08:54 INFO - 2015-11-19 19:08:54,764 DEBUG : BROWSER_OUTPUT: RSS: Main: 153944064 19:08:54 INFO - 2015-11-19 19:08:54,764 DEBUG : BROWSER_OUTPUT: 19:08:58 INFO - 2015-11-19 19:08:58,168 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42556/tests/kraken/driver.html?testName=imaging-desaturate) 19:08:58 INFO - 2015-11-19 19:08:58,735 DEBUG : BROWSER_OUTPUT: RSS: Main: 156860416 19:08:58 INFO - 2015-11-19 19:08:58,735 DEBUG : BROWSER_OUTPUT: 19:09:01 INFO - 2015-11-19 19:09:01,524 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42556/tests/kraken/driver.html?testName=json-parse-financial) 19:09:02 INFO - 2015-11-19 19:09:02,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 156069888 19:09:02 INFO - 2015-11-19 19:09:02,094 DEBUG : BROWSER_OUTPUT: 19:09:03 INFO - 2015-11-19 19:09:03,643 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42556/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:09:04 INFO - 2015-11-19 19:09:04,205 DEBUG : BROWSER_OUTPUT: RSS: Main: 165384192 19:09:04 INFO - 2015-11-19 19:09:04,205 DEBUG : BROWSER_OUTPUT: 19:09:06 INFO - 2015-11-19 19:09:06,041 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42556/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:09:06 INFO - 2015-11-19 19:09:06,636 DEBUG : BROWSER_OUTPUT: RSS: Main: 165294080 19:09:06 INFO - 2015-11-19 19:09:06,636 DEBUG : BROWSER_OUTPUT: 19:09:08 INFO - 2015-11-19 19:09:08,708 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42556/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:09:09 INFO - 2015-11-19 19:09:09,274 DEBUG : BROWSER_OUTPUT: RSS: Main: 174878720 19:09:09 INFO - 2015-11-19 19:09:09,274 DEBUG : BROWSER_OUTPUT: 19:09:11 INFO - 2015-11-19 19:09:11,640 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42556/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:09:12 INFO - 2015-11-19 19:09:12,222 DEBUG : BROWSER_OUTPUT: RSS: Main: 179908608 19:09:12 INFO - 2015-11-19 19:09:12,223 DEBUG : BROWSER_OUTPUT: 19:09:14 INFO - 2015-11-19 19:09:14,839 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42556/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:09:15 INFO - 2015-11-19 19:09:15,421 DEBUG : BROWSER_OUTPUT: RSS: Main: 180248576 19:09:15 INFO - 2015-11-19 19:09:15,421 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,110 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42556/tests/kraken/driver.html?testName=ai-astar) 19:09:17 INFO - 2015-11-19 19:09:17,360 DEBUG : BROWSER_OUTPUT: RSS: Main: 239308800 19:09:17 INFO - 2015-11-19 19:09:17,360 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,360 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:09:17 INFO - 2015-11-19 19:09:17,361 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:09:17 INFO - 2015-11-19 19:09:17,361 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:09:17 INFO - 2015-11-19 19:09:17,361 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:09:17 INFO - 2015-11-19 19:09:17,361 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;111;106;110;112;112;99;110;110;109 19:09:17 INFO - 2015-11-19 19:09:17,361 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;151;124;150;164;126;151;149;136;165;124 19:09:17 INFO - 2015-11-19 19:09:17,361 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;161;162;162;160;162;161;162;162;160 19:09:17 INFO - 2015-11-19 19:09:17,361 DEBUG : BROWSER_OUTPUT: |3;audio-fft;72;71;81;101;73;71;73;109;88;72 19:09:17 INFO - 2015-11-19 19:09:17,362 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;90;90;133;90;90;89;131;90;91;132 19:09:17 INFO - 2015-11-19 19:09:17,362 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;110;109;109;109;110;110;109 19:09:17 INFO - 2015-11-19 19:09:17,362 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;146;145;146;145;145;145;145;145;145 19:09:17 INFO - 2015-11-19 19:09:17,362 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;96;96;97;95;95;95;95 19:09:17 INFO - 2015-11-19 19:09:17,362 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;60;60;61;60;60;59;62;68;57;58 19:09:17 INFO - 2015-11-19 19:09:17,362 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;70;69;69;69;70;70;69;70;70 19:09:17 INFO - 2015-11-19 19:09:17,362 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;85;84;91;84;86;88;84;85;84 19:09:17 INFO - 2015-11-19 19:09:17,362 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;118;122;117;115;133;116;115;121;122;116 19:09:17 INFO - 2015-11-19 19:09:17,363 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;153;148;149;148;153;150;162;153;150;145 19:09:17 INFO - 2015-11-19 19:09:17,363 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;69;70;67;70;71;67;69;71;70 19:09:17 INFO - 2015-11-19 19:09:17,363 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:09:17 INFO - 2015-11-19 19:09:17,363 DEBUG : BROWSER_OUTPUT: __start_cc_report 19:09:17 INFO - 2015-11-19 19:09:17,363 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,916 19:09:17 INFO - 2015-11-19 19:09:17,363 DEBUG : BROWSER_OUTPUT: __end_cc_report 19:09:17 INFO - 2015-11-19 19:09:17,363 DEBUG : BROWSER_OUTPUT: __startTimestamp1447988957359__endTimestamp 19:09:17 INFO - 2015-11-19 19:09:17,364 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,367 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:09:17 INFO - 2015-11-19 19:09:17,367 DEBUG : BROWSER_OUTPUT: Number of tests: 14 19:09:17 INFO - 2015-11-19 19:09:17,367 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,368 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:108.90 Median:110.50 stddev:3.87 (3.5%) stddev-sans-first:4.09 19:09:17 INFO - 2015-11-19 19:09:17,368 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 106.0 110.0 112.0 112.0 99.0 110.0 110.0 109.0 19:09:17 INFO - 2015-11-19 19:09:17,368 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,368 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.00 Median:150.50 stddev:15.59 (10.4%) stddev-sans-first:16.33 19:09:17 INFO - 2015-11-19 19:09:17,368 DEBUG : BROWSER_OUTPUT: Values: 151.0 124.0 150.0 164.0 126.0 151.0 149.0 136.0 165.0 124.0 19:09:17 INFO - 2015-11-19 19:09:17,368 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,368 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:0.84 (0.5%) stddev-sans-first:0.87 19:09:17 INFO - 2015-11-19 19:09:17,368 DEBUG : BROWSER_OUTPUT: Values: 162.0 161.0 162.0 162.0 160.0 162.0 161.0 162.0 162.0 160.0 19:09:17 INFO - 2015-11-19 19:09:17,368 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,368 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.10 Median:72.50 stddev:13.84 (19.1%) stddev-sans-first:14.28 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: Values: 72.0 71.0 81.0 101.0 73.0 71.0 73.0 109.0 88.0 72.0 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:102.60 Median:90.00 stddev:20.30 (22.6%) stddev-sans-first:21.01 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: Values: 90.0 90.0 133.0 90.0 90.0 89.0 131.0 90.0 91.0 132.0 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.40 Median:109.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 110.0 109.0 109.0 109.0 110.0 110.0 109.0 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.30 Median:145.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: Values: 146.0 146.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 145.0 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 96.0 96.0 97.0 95.0 95.0 95.0 95.0 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,369 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:60.50 Median:60.00 stddev:2.99 (5.0%) stddev-sans-first:3.17 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: Values: 60.0 60.0 61.0 60.0 60.0 59.0 62.0 68.0 57.0 58.0 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:70.00 stddev:0.70 (1.0%) stddev-sans-first:0.53 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: Values: 68.0 70.0 69.0 69.0 69.0 70.0 70.0 69.0 70.0 70.0 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:85.00 stddev:2.32 (2.7%) stddev-sans-first:2.40 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: Values: 84.0 85.0 84.0 91.0 84.0 86.0 88.0 84.0 85.0 84.0 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:119.50 Median:119.50 stddev:5.48 (4.6%) stddev-sans-first:5.79 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: Values: 118.0 122.0 117.0 115.0 133.0 116.0 115.0 121.0 122.0 116.0 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.10 Median:151.50 stddev:4.63 (3.1%) stddev-sans-first:4.86 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: Values: 153.0 148.0 149.0 148.0 153.0 150.0 162.0 153.0 150.0 145.0 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.20 Median:70.00 stddev:1.48 (2.1%) stddev-sans-first:1.50 19:09:17 INFO - 2015-11-19 19:09:17,370 DEBUG : BROWSER_OUTPUT: Values: 68.0 69.0 70.0 67.0 70.0 71.0 67.0 69.0 71.0 70.0 19:09:17 INFO - 2015-11-19 19:09:17,371 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:09:17 INFO - 2015-11-19 19:09:17,371 DEBUG : BROWSER_OUTPUT: 19:09:18 INFO - 2015-11-19 19:09:18,240 INFO : Browser exited with error code: 0 19:09:18 INFO - 2015-11-19 19:09:18,294 INFO : Completed test kraken (00:00:59) 19:09:18 INFO - 2015-11-19 19:09:18,294 INFO : Starting test v8_7 19:09:18 INFO - 2015-11-19 19:09:18,294 DEBUG : operating with platform_type : linux_ 19:09:18 INFO - 2015-11-19 19:09:18,294 INFO : Initialising browser for v8_7 test... 19:09:18 INFO - 2015-11-19 19:09:18,300 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXv7jP4/profile http://localhost:42556/getInfo.html 19:09:22 INFO - 2015-11-19 19:09:22,503 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 19:09:22 INFO - 2015-11-19 19:09:22,503 DEBUG : BROWSER_OUTPUT: colorDepth:24 19:09:22 INFO - 2015-11-19 19:09:22,503 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 19:09:22 INFO - 2015-11-19 19:09:22,503 DEBUG : BROWSER_OUTPUT: __metrics 19:09:22 INFO - 2015-11-19 19:09:22,661 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:09:24 INFO - 2015-11-19 19:09:24,021 INFO : Browser initialized. 19:09:24 INFO - 2015-11-19 19:09:24,022 INFO : Running cycle 1/1 for v8_7 test... 19:09:24 INFO - 2015-11-19 19:09:24,022 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpXv7jP4/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 19:09:35 INFO - 2015-11-19 19:09:35,803 DEBUG : BROWSER_OUTPUT: RSS: Main: 141119488 19:09:35 INFO - 2015-11-19 19:09:35,803 DEBUG : BROWSER_OUTPUT: 19:09:57 INFO - 2015-11-19 19:09:57,215 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42556/tests/v8_7/run.html (next: http://localhost:42556/tests/v8_7/run.html) 19:09:57 INFO - 2015-11-19 19:09:57,465 DEBUG : BROWSER_OUTPUT: RSS: Main: 615825408 19:09:57 INFO - 2015-11-19 19:09:57,465 DEBUG : BROWSER_OUTPUT: 19:09:57 INFO - 2015-11-19 19:09:57,467 DEBUG : BROWSER_OUTPUT: __start_tp_report 19:09:57 INFO - 2015-11-19 19:09:57,467 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 19:09:57 INFO - 2015-11-19 19:09:57,467 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 19:09:57 INFO - 2015-11-19 19:09:57,467 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 19:09:57 INFO - 2015-11-19 19:09:57,467 DEBUG : BROWSER_OUTPUT: |0;Richards;162.97262059973923 19:09:57 INFO - 2015-11-19 19:09:57,467 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;188.89308651303364 19:09:57 INFO - 2015-11-19 19:09:57,467 DEBUG : BROWSER_OUTPUT: |2;Encrypt;282.4060999717594 19:09:57 INFO - 2015-11-19 19:09:57,468 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5035.175879396985 19:09:57 INFO - 2015-11-19 19:09:57,468 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1949.317738791423 19:09:57 INFO - 2015-11-19 19:09:57,468 DEBUG : BROWSER_OUTPUT: |5;Earley;793.1854199683042 19:09:57 INFO - 2015-11-19 19:09:57,468 DEBUG : BROWSER_OUTPUT: |6;Boyer;12144.578313253012 19:09:57 INFO - 2015-11-19 19:09:57,468 DEBUG : BROWSER_OUTPUT: |7;RegExp;26526.315789473683 19:09:57 INFO - 2015-11-19 19:09:57,468 DEBUG : BROWSER_OUTPUT: |8;Splay;645.0577663671373 19:09:57 INFO - 2015-11-19 19:09:57,469 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4830.917874396136 19:09:57 INFO - 2015-11-19 19:09:57,469 DEBUG : BROWSER_OUTPUT: __end_tp_report 19:09:57 INFO - 2015-11-19 19:09:57,469 DEBUG : BROWSER_OUTPUT: __startTimestamp1447988997465__endTimestamp 19:09:57 INFO - 2015-11-19 19:09:57,469 DEBUG : BROWSER_OUTPUT: 19:09:57 INFO - 2015-11-19 19:09:57,469 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 19:09:57 INFO - 2015-11-19 19:09:57,469 DEBUG : BROWSER_OUTPUT: Number of tests: 10 19:09:57 INFO - 2015-11-19 19:09:57,470 DEBUG : BROWSER_OUTPUT: 19:09:57 INFO - 2015-11-19 19:09:57,470 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:162.97 Median:162.97 stddev:0.00 (0.0%) 19:09:57 INFO - 2015-11-19 19:09:57,470 DEBUG : BROWSER_OUTPUT: Values: 163.0 19:09:57 INFO - 2015-11-19 19:09:57,470 DEBUG : BROWSER_OUTPUT: 19:09:57 INFO - 2015-11-19 19:09:57,470 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:188.89 Median:188.89 stddev:0.00 (0.0%) 19:09:57 INFO - 2015-11-19 19:09:57,471 DEBUG : BROWSER_OUTPUT: Values: 188.9 19:09:57 INFO - 2015-11-19 19:09:57,471 DEBUG : BROWSER_OUTPUT: 19:09:57 INFO - 2015-11-19 19:09:57,471 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:282.41 Median:282.41 stddev:0.00 (0.0%) 19:09:57 INFO - 2015-11-19 19:09:57,471 DEBUG : BROWSER_OUTPUT: Values: 282.4 19:09:57 INFO - 2015-11-19 19:09:57,471 DEBUG : BROWSER_OUTPUT: 19:09:57 INFO - 2015-11-19 19:09:57,471 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5035.18 Median:5035.18 stddev:0.00 (0.0%) 19:09:57 INFO - 2015-11-19 19:09:57,473 DEBUG : BROWSER_OUTPUT: Values: 5035.2 19:09:57 INFO - 2015-11-19 19:09:57,473 DEBUG : BROWSER_OUTPUT: 19:09:57 INFO - 2015-11-19 19:09:57,473 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1949.32 Median:1949.32 stddev:0.00 (0.0%) 19:09:57 INFO - 2015-11-19 19:09:57,473 DEBUG : BROWSER_OUTPUT: Values: 1949.3 19:09:57 INFO - 2015-11-19 19:09:57,473 DEBUG : BROWSER_OUTPUT: 19:09:57 INFO - 2015-11-19 19:09:57,473 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:793.19 Median:793.19 stddev:0.00 (0.0%) 19:09:57 INFO - 2015-11-19 19:09:57,473 DEBUG : BROWSER_OUTPUT: Values: 793.2 19:09:57 INFO - 2015-11-19 19:09:57,473 DEBUG : BROWSER_OUTPUT: 19:09:57 INFO - 2015-11-19 19:09:57,474 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12144.58 Median:12144.58 stddev:0.00 (0.0%) 19:09:57 INFO - 2015-11-19 19:09:57,474 DEBUG : BROWSER_OUTPUT: Values: 12144.6 19:09:57 INFO - 2015-11-19 19:09:57,474 DEBUG : BROWSER_OUTPUT: 19:09:57 INFO - 2015-11-19 19:09:57,474 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26526.32 Median:26526.32 stddev:0.00 (0.0%) 19:09:57 INFO - 2015-11-19 19:09:57,474 DEBUG : BROWSER_OUTPUT: Values: 26526.3 19:09:57 INFO - 2015-11-19 19:09:57,474 DEBUG : BROWSER_OUTPUT: 19:09:57 INFO - 2015-11-19 19:09:57,474 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:645.06 Median:645.06 stddev:0.00 (0.0%) 19:09:57 INFO - 2015-11-19 19:09:57,474 DEBUG : BROWSER_OUTPUT: Values: 645.1 19:09:57 INFO - 2015-11-19 19:09:57,474 DEBUG : BROWSER_OUTPUT: 19:09:57 INFO - 2015-11-19 19:09:57,475 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4830.92 Median:4830.92 stddev:0.00 (0.0%) 19:09:57 INFO - 2015-11-19 19:09:57,475 DEBUG : BROWSER_OUTPUT: Values: 4830.9 19:09:57 INFO - 2015-11-19 19:09:57,475 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 19:09:57 INFO - 2015-11-19 19:09:57,475 DEBUG : BROWSER_OUTPUT: 19:09:58 INFO - 2015-11-19 19:09:58,312 INFO : Browser exited with error code: 0 19:09:58 INFO - 2015-11-19 19:09:58,327 INFO : Completed test v8_7 (00:00:40) 19:09:58 INFO - 2015-11-19 19:09:58,454 INFO : Completed test suite (00:20:14) 19:09:58 INFO - 2015-11-19 19:09:58,454 DEBUG : Working with test: dromaeo_css 19:09:58 INFO - 2015-11-19 19:09:58,454 DEBUG : Generating results file: dromaeo_css 19:09:58 INFO - 2015-11-19 19:09:58,455 DEBUG : Working with test: kraken 19:09:58 INFO - 2015-11-19 19:09:58,455 DEBUG : Generating results file: kraken 19:09:58 INFO - 2015-11-19 19:09:58,456 INFO : javascript benchmark 19:09:58 INFO - 2015-11-19 19:09:58,456 DEBUG : Working with test: v8_7 19:09:58 INFO - 2015-11-19 19:09:58,456 DEBUG : Generating results file: v8_7 19:09:58 INFO - 2015-11-19 19:09:58,456 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:09:58 INFO - 2015-11-19 19:09:58,498 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 19:09:58 INFO - 2015-11-19 19:09:58,498 DEBUG : process_Request line: dromaeo_css 6937.12 graph.html#tests=[[72,64,35]] 19:09:58 INFO - 2015-11-19 19:09:58,498 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:09:58 INFO - 2015-11-19 19:09:58,526 DEBUG : process_Request line: kraken 1503.30 graph.html#tests=[[232,64,35]] 19:09:58 INFO - 2015-11-19 19:09:58,526 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 19:09:58 INFO - 2015-11-19 19:09:58,562 DEBUG : process_Request line: v8_7 19778.25 graph.html#tests=[[230,64,35]] 19:09:58 INFO - 2015-11-19 19:09:58,564 INFO : javascript benchmark 19:09:58 INFO - 2015-11-19 19:09:58,567 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3156.842157892105, 3225.564548785916, 3272.1328847855307, 3276.787008844425, 3304.636489986102, 5249.317588713471, 5373.355197376311, 5380.650257733254, 5477.424870388611, 5518.310211223592, 5337.0, 5446.673199608026, 5446.700431476267, 5526.640768350055, 5544.334679838415, 4988.127077761395, 4991.425986011619, 4998.250262460642, 5013.623978201631, 5015.924761128586, 4824.565789078983, 5026.0, 5026.52248036436, 5091.6690415123085, 5128.692278463285, 4843.128236917353, 4898.902021959577, 4950.455449900508, 4960.479149689267, 4971.179755340365, 4920.975395123037, 4921.311016457699, 4929.644347804347, 4938.061768264054, 4967.329410529568, 4885.902281954376, 4903.0, 4919.581835543947, 4923.655344125913, 4951.925721114186, 5122.436531981479, 5198.818041368534, 5239.449857764918, 5335.599830012726, 5373.0, 5919.023361145406, 5925.140854576105, 5937.881242375129, 5943.791967281168, 5946.672932988684, 5827.592068555203, 5938.168656388109, 5950.315713692938, 5965.582409231356, 5974.462298393166, 5397.892042159214, 5457.317835270591, 5462.3445186577865, 5483.095289277305, 5530.5852061095575, 5958.255218097738, 5961.254843144607, 5974.790882319185, 5986.191864098378, 6048.788292409745, 5548.278723765965, 5814.215080964016, 5815.156802263648, 5820.679862607597, 5846.649201047866, 5620.185073164368, 5712.80005199816, 5716.371199168088, 5838.883222335529, 5843.620164689281, 5808.88382232353, 5890.087036509348, 5893.263342082239, 5904.025835737141, 5912.290525137011, 5878.11828225761, 5885.293764748258, 5886.058230683156, 5888.941110588925, 5894.440028197314, 4350.303951367766, 4429.069895321954, 4435.844745433894, 4531.977340113344, 4563.133004729091, 4024.5371782245415, 4026.355783074694, 4030.1939612077704, 4032.2137183248983, 4067.7559346439307, 5532.170174473861, 5537.335519737577, 5543.445655434424, 5543.972280138573, 5598.412166722517, 5678.0915053591225, 5715.057015592381, 5765.962126817213, 5891.646501209942, 5893.44012318829, 5555.527780138734, 5610.102383618601, 5630.071038278638, 5640.097584386561, 5644.32268127819, 5123.154679477844, 5132.255822905732, 5144.845654630367, 5150.510701483353, 5198.740062996835, 5860.677662728591, 5993.041113421918, 6000.519958403317, 6005.489533389711, 6129.540284478593, 5775.826725198251, 5779.971100144472, 5783.219265399117, 5783.421657834183, 5786.652800831964, 6002.909956350571, 6003.759849605967, 6008.7296071676, 6011.639301641915, 6015.0676645120075, 5664.886702265849, 5684.8010319638615, 5748.367679555162, 5790.449907258803, 5815.738291776965, 5784.479396854304, 5793.391693872083, 5823.097419899922, 5825.41745825431, 5846.912296315473, 5658.688772117573, 5664.688442135557, 5680.886382272249, 5681.147827825859, 5682.403347648437, 5439.129739241861, 5533.280673512418, 5555.361133469541, 5558.527525160407, 5609.691466969356, 5511.889762204813, 5577.888442231051, 5578.246936663499, 5583.525400341016, 5584.218209450762, 5713.400092990342, 5714.657120572779, 5715.39988301239, 5716.256886604715, 5716.88520738452, 5268.0, 5421.996930567858, 5423.430539793265, 5570.58220633446, 5685.829425117253], "unit": "ms", "name": "dojo.html", "value": 5375.6687599978795}, {"lowerIsBetter": false, "replicates": [543.6792292547397, 586.9647821130733, 593.4569868570259, 601.2874743429037, 613.2487702564359, 29737.256568585795, 41239.587604123946, 41257.174856502854, 41484.0, 41548.0, 43860.0, 44442.555574444246, 44475.0, 44523.33215001778, 44590.7770461148, 7682.654280557388, 7803.492772969767, 7811.88282175765, 8829.072947340517, 8990.0, 10645.627403040895, 10767.784644307108, 10776.46117694116, 10893.07408870247, 10904.61833835816, 10534.315269507457, 10614.681559553188, 10671.893281067167, 10688.144948404148, 10691.358518488876, 7916.327112195441, 8074.152214017502, 8083.191680831928, 10422.062014418701, 10705.83941240882, 10405.79188416235, 10544.841827372595, 10555.155587552978, 10559.524821383056, 10559.894401055968, 39951.60048399537, 40475.59524404777, 40514.18971620551, 40564.79717601393, 40571.59428405737, 3398.3713013092656, 3431.5367425397503, 3538.0800991742076, 3555.3600351936643, 3797.39241721326, 30498.144012629637, 30957.53563696524, 31162.0, 31340.0, 31388.843055784804, 3907.5506316773367, 3911.8435262589464, 3973.662238709713, 3989.2221016901717, 4128.071183983609, 287794.0, 288746.0, 289052.0, 289193.0, 290604.0, 57766.422335776944, 59191.70404147952, 60608.696956515814, 61165.69417152975, 61645.38354616486, 56818.43181568214, 56845.0, 57165.42834571601, 57169.42830571724, 57205.14192287119, 14657.92671036638, 14819.333130009176, 14832.629184270263, 14833.62915927089, 14843.480478183212, 11429.942850285861, 11550.133739969537, 11616.12879034059, 11624.244424112574, 11625.360605166628, 20326.796732032784, 20594.0, 20642.380728578166, 20642.896785515975, 20719.067641955848, 7617.847643047108, 7658.477100533705, 7732.110807257125, 7761.301482866569, 7766.262205090507, 1008.7175590834578, 1013.9290249682599, 1065.5844220753913, 1089.809283375406, 1131.5247596009697, 26379.340516487242, 26425.207243782716, 26937.73062269352, 27440.588391174148, 27504.44991100167, 1001.7495626093477, 1003.35449862226, 1007.2043085962155, 1049.2707568240153, 1152.233764546567, 14504.274786260856, 14599.416023358946, 14679.0, 14987.775183372258, 14988.47540336083, 330708.0, 331354.0, 331702.0, 335294.0, 336280.0, 54672.17991730127, 54977.72511137499, 55003.174952375746, 55069.173962390596, 55073.17390239069, 1292.4700872642359, 1319.70306680996, 1324.9337533123305, 1325.132038514755, 1339.8191244182105, 27588.0, 28137.296567585156, 28303.2924176889, 28578.142655720363, 29756.404871902872, 43328.78335608302, 44509.77745111254, 44946.0, 49347.25979110388, 50932.49067509278, 218826.0, 219232.0, 220628.0, 221774.0, 222325.0, 27479.450410992067, 30094.0, 30357.39285214239, 30385.848070759504, 30409.543856842607, 37007.44488832616, 40526.39210411787, 41182.38226426546, 41604.3759343604, 41809.79095104505, 36091.278174435836, 36506.452403214505, 37302.253954920205, 37465.0, 38161.42757858579, 35004.0, 35224.64775352316, 35653.64346356503, 37402.81298593598, 37601.62398375981, 33054.17364565905, 34059.65940340664, 34208.14479638029, 34262.657373425944, 34313.656863431046, 28398.0, 32381.676183237865, 33816.0, 33943.830280849426, 33948.0, 25361.746382535937, 25386.365340866625, 25656.615150773127, 25673.229803105936, 27545.0], "unit": "ms", "name": "ext.html", "value": 18716.276766799176}, {"lowerIsBetter": false, "replicates": [356.76275276940834, 359.62060026671867, 364.7100555058728, 364.998175009125, 365.9871904483344, 14100.57698269051, 14204.573862784133, 16227.350905963762, 16486.917565412172, 17088.0, 15711.214439278048, 16827.915860420682, 16996.4051258206, 17022.82977170228, 17179.74230386542, 5457.481539253775, 5602.8599285018, 5610.438956104398, 5610.747516361753, 5624.0, 3943.093088589612, 4004.0190153412454, 5551.306086739158, 5583.972080139593, 5945.524358051345, 5983.0, 5998.820035398945, 6030.66831324277, 6031.155638210654, 6032.9698351508405, 5231.11071117909, 5366.812161574346, 5369.516743493065, 5376.086065368858, 5986.580939334227, 5100.719460429675, 5149.2791009258735, 5158.819441319573, 5166.560842328407, 5339.733013349317, 18780.81219187804, 18783.530411739815, 19093.522661933424, 19095.427137185907, 19122.330718424928, 2020.1414398880418, 2031.086011294923, 2056.033664177834, 2065.2771529964393, 2077.5637116205617, 9418.246540276761, 9513.66702165421, 9628.085331893459, 9866.65466708669, 9900.009999000114, 1801.639672065579, 1805.6208196278797, 1843.2258451450289, 1887.5564242403161, 1889.9716504252447, 59397.703011484664, 60281.09578356328, 60348.69825650888, 62165.6891715543, 64069.0, 10013.699589012196, 10248.846267305997, 10276.845847312296, 10295.691129266133, 10348.0, 13457.125286856322, 13460.596182114552, 13464.326783660777, 13483.460661573426, 13485.0, 7461.216572259943, 7508.62456877154, 7515.661795219228, 7540.359069479046, 7599.278068583476, 7064.222935477093, 7107.6446177690905, 7162.033125528092, 7221.566705997657, 7235.312645298688, 4367.235733746582, 4413.271810151353, 4413.492448368414, 4442.333649952533, 4453.933191002137, 1714.1857617631524, 1786.7230579260236, 1803.2877013579562, 2963.2147480917574, 2995.3110784519245, 863.313665635821, 872.8341615093112, 889.7820034091689, 915.0, 963.9807203855884, 14131.71736565263, 15485.690286194213, 15875.444359447363, 16105.597360065858, 16279.51161465158, 884.1688812516214, 886.7605746448423, 957.7270477913901, 981.2297346582898, 1003.2225025605069, 9112.681056163003, 9327.207187389147, 9334.813303733887, 9373.578188981512, 9414.058594140668, 552512.0, 552917.0, 554523.0, 577418.0, 579777.0, 81935.0, 84390.0, 84492.0, 85610.14389856021, 85612.0, 1159.0495793449459, 1160.1125139268465, 1171.7949358862165, 1175.2830773228325, 1178.8938995490275, 14280.075347633296, 14392.712145757236, 14539.854601454274, 14762.409503620165, 14838.851611483748, 33933.49099763555, 35062.0, 35809.46285805663, 36497.0, 37305.0, 47926.0, 48003.5199647999, 48059.0, 48138.03723925572, 48667.02665946732, 9399.389039712396, 9511.57197926081, 9679.951600242235, 9693.757656058655, 9740.123388895034, 16676.249568769676, 17076.146192690067, 17114.57213569621, 17126.571835703708, 17415.825841741927, 14588.562343129723, 14644.194569298365, 14959.700805983603, 15250.77123843121, 15251.313690884159, 14646.63383415423, 14657.413703451743, 14968.101913885203, 15041.6991660164, 15343.07941523512, 11426.428678565871, 14258.928705356408, 14460.783088253895, 14903.478378257143, 15604.375824967328, 9305.581248843953, 9605.0, 9605.0, 9618.086281803218, 9619.807603848023, 9876.0, 9896.802063958536, 10152.0, 14507.347169377614, 14534.345954431865], "unit": "ms", "name": "jquery.html", "value": 8886.360342317248}, {"lowerIsBetter": false, "replicates": [1305.8367704036996, 1376.2980879751326, 1425.422703804959, 1569.2938177819976, 1769.1950162675976, 6451.9032214516765, 7249.202587715347, 7379.3727533159745, 7406.703731850733, 7415.295546923047, 7244.0582724245905, 7412.369948554379, 7448.441366897478, 7462.253774622535, 7468.327850493454, 1944.057132290838, 1978.1592823050219, 2164.8159906407973, 2205.0518277140864, 2224.6774217738416, 2238.9664155037685, 2244.7979681828715, 2255.503789166389, 2255.5827171973237, 2257.2663884237604, 2168.8373371997086, 2247.2584047264445, 2253.8422310438277, 2258.5369999150216, 2259.9096036158535, 1955.4329244519072, 1997.2111016148613, 2165.9783402165936, 2200.702905107822, 2210.0938615167706, 2233.039792889057, 2236.809871160953, 2257.808086312665, 2266.4220623741007, 2323.1752727781563, 7399.408047356253, 7448.776536703907, 7461.664225109829, 7466.029416175917, 7483.214262502468, 4560.9771951140365, 4617.330487079388, 4645.372874661911, 4666.929996050062, 4676.625869930397, 3067.1412004638737, 3119.9220019499467, 3135.1221657935816, 3136.2159460134967, 3185.8725650973934, 1037.4968140451874, 1042.0829669890525, 1079.3739631013993, 1085.93484390936, 1093.33853018924, 10304.587816487332, 10348.0, 10450.16398688103, 10450.268481206318, 10487.94756026215, 4114.958850411517, 4140.60664236897, 4180.0, 4329.047609525899, 4337.110892267077, 8100.473469224599, 8104.8379032419025, 8114.350851931831, 8166.346692264605, 8177.4684645497855, 4555.339475775994, 4561.315802629566, 4581.633469322446, 4593.402857628487, 4602.585767280961, 4194.203101410722, 4214.452121224222, 4228.61942425177, 4233.068724880522, 4236.173946080547, 3373.089265898194, 3445.672661097192, 3450.9482357764655, 3453.8963831085107, 3461.359648465042, 1056.7463808686014, 1080.7568297133082, 1089.4008295437477, 1093.6992327110108, 1095.917806164541, 1271.3325504110417, 1282.4165004922738, 1285.1967520299813, 1306.288073000217, 1337.4650139943906, 6983.860322793617, 6994.510384273052, 7060.364567188978, 7067.92932070683, 7083.220845706968, 479.85364463838863, 484.4525685974826, 488.1067646207432, 493.85431297767246, 503.9874003149877, 5615.550755939515, 5638.097904335286, 5653.23681303027, 5656.773729050874, 5660.575456840754, 8504.192101750325, 8628.913710862937, 8630.093840146694, 8731.083236260229, 8796.604152813139, 8711.912880871238, 8809.383343165917, 8838.602262898185, 8947.821043579092, 8967.282617390592, 6626.105475760711, 6686.699098540577, 6692.19310786085, 6731.562448440738, 6755.4257888080065, 6790.151231096113, 6843.692033858588, 6874.0, 6878.55289406187, 6883.518153729391, 7741.7677469676, 7926.762197134095, 7993.760187194393, 8000.5599692017495, 8005.5196688196565, 8068.394870384862, 8111.391645626483, 8138.633761480866, 8236.052853921876, 8273.089960104264, 6036.396360363929, 6115.816525504242, 6145.66198859067, 6701.0953521274005, 6768.593884366954, 7140.714371425085, 7181.35367816899, 7185.173705023989, 7186.209516953236, 7191.640417978975, 5786.363500015079, 6546.574472659262, 6803.149606299213, 6898.379145876896, 6978.685959131953, 6418.743250269937, 6548.410643041959, 6566.0, 6747.595144291358, 6939.548929319578, 6582.736690532325, 6591.242007169045, 6605.141331626858, 6686.231083425274, 6785.0, 5986.910196347146, 6051.788187413419, 6086.908696369469, 6093.75624974996, 6100.54245931548, 5980.820575382745, 6315.5894866833505, 6323.841903952254, 6328.335524769833, 6338.429541341301], "unit": "ms", "name": "mootools.html", "value": 4227.413843731014}, {"lowerIsBetter": false, "replicates": [341.44174275060277, 344.95688038995127, 351.825846206128, 353.20000199548014, 353.6975885617797, 5985.670788106652, 6000.069989151678, 6021.427964343391, 6023.397660233974, 6041.9093713594275, 5961.761529538824, 5963.463288304052, 5967.224260846084, 5968.67172305523, 5970.014947533663, 4539.704919180259, 4636.142313671965, 4642.32686260492, 4644.442666879962, 4648.860534183971, 4607.9308810367875, 4637.652176086807, 4638.281066434708, 4638.582527572517, 4644.094401591691, 4590.540945905416, 4591.104734576759, 4592.540745925414, 4604.861854144381, 4608.5621865922685, 4377.4528183977, 4446.466424029105, 4460.063386688799, 4589.65577581681, 4597.586217240448, 4043.7573745575064, 4086.0, 4094.754314741125, 4096.446979657738, 4102.610252026053, 5675.318961724578, 5683.5169010634145, 5684.914726279109, 5696.487316141567, 5705.087186050254, 5102.209157580543, 5243.8164664236565, 5282.2076688496645, 5374.140137578008, 5381.919271210935, 6291.1192433059405, 6302.369763023707, 6306.306306306303, 6311.368863113697, 6320.146780184708, 4515.683902126852, 4516.261239422813, 4516.458025036984, 4521.570450807168, 4524.547545245482, 6413.1662883825275, 6572.375624315682, 6579.736810527573, 6583.506237032242, 6591.11020012297, 4513.390692256569, 4538.773061346919, 4539.614132798683, 4541.318802179633, 4544.454665440168, 4578.404807375086, 4580.290055041474, 4593.28804035375, 4597.747123908217, 4602.0, 5284.788608455696, 5287.312649355559, 5290.550303224269, 5292.708901010405, 5306.8142615009065, 6178.567500274938, 6320.4311611955145, 6330.778422755181, 6365.140706004723, 6409.711562979677, 1600.7918970533756, 1620.3842539834786, 1620.5705488045678, 1622.059205660714, 1622.107840687617, 1079.9028087472166, 1081.3944191252924, 1090.1006669497697, 1108.362691452402, 1112.2547892911768, 6133.570650054453, 6135.478484328793, 6138.171346868205, 6138.386161383826, 6140.8771824564155, 6453.774117905851, 6473.741050358027, 6482.416582507597, 6485.254195767453, 6488.805335839932, 6015.248093988252, 6077.453029227391, 6102.0541816018585, 6112.327643959161, 6127.356627554132, 3488.424409972326, 3552.857885684544, 3559.056849934769, 3563.7149028077692, 3564.483149943244, 6787.2534021257625, 6819.352161544645, 6837.726490940307, 6856.040154378391, 6862.519623626398, 6469.935300647027, 6581.308962558863, 6631.10480085192, 6714.1271634688155, 6722.731090756315, 6857.55425897315, 6886.655667216619, 6916.585004899722, 6919.619420931796, 6938.618375989268, 6582.3746744059235, 6600.669966501751, 6617.338266173344, 6643.136392268974, 6649.135612370555, 6427.132337134614, 6550.050242714684, 6552.934470655233, 6569.113169722026, 6573.57271777333, 6861.656917154022, 6868.416184624363, 6874.415674667709, 6914.0, 6923.169219693669, 4474.530174331779, 4478.037221997286, 4479.148961697267, 4481.036577135931, 4488.68579199466, 6453.1610890583925, 6600.53796234259, 6629.46964242869, 6631.867362652816, 6633.6351500666, 6382.872342553216, 6411.551191416556, 6418.807435776934, 6436.645984470899, 6437.806865794034, 6419.165508483868, 6423.42189202974, 6426.3573642635365, 6427.742890284337, 6429.8392540185, 6170.629762214281, 6275.780347687809, 6291.182146320949, 6294.590851594632, 6298.559100862895, 6457.6771161442675, 6468.0, 6472.320406357453, 6473.482121430368, 6473.902891456726, 6280.0893870389455, 6310.368963103837, 6315.431611155018, 6317.842053948688, 6320.873582528416], "unit": "ms", "name": "prototype.html", "value": 4820.344693965991}, {"lowerIsBetter": false, "replicates": [774.4424014709407, 781.1485480825899, 786.1195461083587, 842.8019415437371, 864.425157270415, 12457.813132803016, 12793.808092878615, 12961.092723509346, 12993.090483666147, 12994.93502532486, 12545.49818007281, 12602.558910438136, 12615.05387095967, 12616.0, 12621.495140194404, 675.0380707491829, 680.928502507237, 802.6147449224361, 810.3557671651022, 820.9630566624486, 816.8121332093622, 819.1971867569786, 823.6911158315631, 827.0943317067802, 827.6937533112757, 866.4931015356025, 875.9386842920998, 876.071364353787, 876.5047748022347, 876.8903887014123, 695.4610177112728, 695.7843068648709, 853.7737499562621, 862.4868203418956, 869.0657543141149, 638.3935261501592, 640.7244884699577, 641.6278558436096, 641.8491654461197, 641.8716256748669, 12580.748385032248, 12633.557825476064, 12724.491020359266, 12726.80909786354, 12731.108822382437, 11804.46879890407, 11840.822387664191, 12334.136610437274, 12438.875611243953, 12475.563355282613, 10323.483825808755, 10424.113950314233, 10659.733506662318, 10703.732406689816, 10746.892531074745, 3570.036090255642, 3644.7813131211947, 3686.5391826021746, 3693.7229707772026, 3786.5266841644793, 45238.09523809538, 45657.771711141235, 45665.31502027472, 45734.542654573364, 45881.77059114683, 9353.158215760614, 9533.094356036165, 9562.190146535217, 9796.26528010402, 9819.312648114706, 17431.73852392215, 18049.639007219783, 18087.547811304823, 18110.63778724418, 18455.0, 7008.719651213894, 7135.286471352823, 7213.8557228855125, 7214.098237720285, 7248.311410416002, 3819.9618003819605, 3936.842526298916, 3958.5645578986287, 3968.3849003404516, 3985.980070099631, 3239.72462340699, 3297.274599588087, 3299.109240505098, 3301.290222602152, 3305.719013883847, 546.3471151973354, 558.0568838662647, 566.1762136091976, 578.6817250512202, 580.962237454564, 553.4106176921566, 608.1091201389985, 651.465798045598, 651.8012006338113, 704.8555046215514, 7424.3504435995055, 7584.2036586158765, 7701.537907725555, 7884.448088633741, 7937.285644291926, 608.954328425361, 655.4199533412964, 673.7608149107094, 686.8282929267683, 703.4864548879244, 3440.501127336522, 3751.5498140222803, 3763.6800871926193, 3779.7354185206773, 3802.733808633369, 58897.41102589005, 60212.397876021554, 60263.397366025776, 60321.69839150776, 60479.69760151171, 52242.0, 53077.2038419424, 53379.73310133425, 53569.0, 54498.0, 47896.76051619789, 48390.75804620954, 48465.0, 48493.5150648489, 49960.500394996314, 7470.402367810453, 7515.736949206752, 7624.5425274483705, 7751.186125456971, 7779.961100194463, 34458.31083378368, 34606.480902786985, 35313.0, 35659.0, 35719.0, 41753.37369939514, 42091.0, 43737.34393984029, 44818.77590612026, 45107.77446112748, 9963.950180249052, 10112.59549618007, 10359.84460233082, 10390.324628899098, 10433.947830261104, 11111.72220694489, 11665.183437159318, 11810.0, 11896.583619573274, 12350.320732359807, 10458.738531536772, 10466.895331046897, 10481.213908957012, 10533.631322903968, 10580.15358771312, 10023.248256380946, 10180.338278011906, 10211.591536338463, 10432.530536126045, 10539.578416863545, 10281.640142594973, 10296.536655850356, 10500.264981451224, 10578.365298082139, 10591.152707783513, 8790.815031577082, 9258.49078300673, 9267.907320926704, 9466.158483421657, 9571.0, 7715.498492598188, 7812.218778122142, 8109.107998120084, 8345.54099524532, 8739.21347078766], "unit": "ms", "name": "yui.html", "value": 6116.530508493052}], "name": "dromaeo_css", "value": 6937.11920834871}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 111.0, 106.0, 110.0, 112.0, 112.0, 99.0, 110.0, 110.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 108.9}, {"lowerIsBetter": true, "replicates": [151.0, 124.0, 150.0, 164.0, 126.0, 151.0, 149.0, 136.0, 165.0, 124.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.0}, {"lowerIsBetter": true, "replicates": [162.0, 161.0, 162.0, 162.0, 160.0, 162.0, 161.0, 162.0, 162.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 161.4}, {"lowerIsBetter": true, "replicates": [72.0, 71.0, 81.0, 101.0, 73.0, 71.0, 73.0, 109.0, 88.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 81.1}, {"lowerIsBetter": true, "replicates": [90.0, 90.0, 133.0, 90.0, 90.0, 89.0, 131.0, 90.0, 91.0, 132.0], "unit": "ms", "name": "audio-oscillator", "value": 102.6}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 110.0, 109.0, 109.0, 109.0, 110.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.4}, {"lowerIsBetter": true, "replicates": [146.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.3}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 97.0, 95.0, 95.0, 95.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [60.0, 60.0, 61.0, 60.0, 60.0, 59.0, 62.0, 68.0, 57.0, 58.0], "unit": "ms", "name": "json-parse-financial", "value": 60.5}, {"lowerIsBetter": true, "replicates": [68.0, 70.0, 69.0, 69.0, 69.0, 70.0, 70.0, 69.0, 70.0, 70.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.4}, {"lowerIsBetter": true, "replicates": [84.0, 85.0, 84.0, 91.0, 84.0, 86.0, 88.0, 84.0, 85.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.5}, {"lowerIsBetter": true, "replicates": [118.0, 122.0, 117.0, 115.0, 133.0, 116.0, 115.0, 121.0, 122.0, 116.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 119.5}, {"lowerIsBetter": true, "replicates": [153.0, 148.0, 149.0, 148.0, 153.0, 150.0, 162.0, 153.0, 150.0, 145.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 151.1}, {"lowerIsBetter": true, "replicates": [68.0, 69.0, 70.0, 67.0, 70.0, 71.0, 67.0, 69.0, 71.0, 70.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 69.2}], "name": "kraken", "value": 1503.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [162.97262059973923], "unit": "score", "name": "Richards", "value": 216.61307199999993}, {"lowerIsBetter": false, "replicates": [188.89308651303364], "unit": "score", "name": "DeltaBlue", "value": 350.02869200000004}, {"lowerIsBetter": false, "replicates": [282.4060999717594], "unit": "score", "name": "Encrypt", "value": 942.5469209999999}, {"lowerIsBetter": false, "replicates": [5035.175879396985], "unit": "score", "name": "Decrypt", "value": 52.864290419161705}, {"lowerIsBetter": false, "replicates": [1949.317738791423], "unit": "score", "name": "RayTrace", "value": 379.61435700000015}, {"lowerIsBetter": false, "replicates": [793.1854199683042], "unit": "score", "name": "Earley", "value": 840.23606993007}, {"lowerIsBetter": false, "replicates": [12144.578313253012], "unit": "score", "name": "Boyer", "value": 54.87740972222223}, {"lowerIsBetter": false, "replicates": [26526.315789473683], "unit": "score", "name": "RegExp", "value": 34.34268849206351}, {"lowerIsBetter": false, "replicates": [645.0577663671373], "unit": "score", "name": "Splay", "value": 126.33132139303481}, {"lowerIsBetter": false, "replicates": [4830.917874396136], "unit": "score", "name": "NavierStokes", "value": 307.1879999999999}], "name": "v8_7", "value": 19778.251963620893}]} 19:09:58 INFO - RETURN: dromaeo_css: 6937.12 19:09:58 INFO - RETURN: kraken: 1503.30 19:09:58 INFO - RETURN: v8_7: 19778.25 19:09:58 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "19778.25"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1503.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "6937.12"}}} 19:09:58 INFO - Return code: 0 19:09:58 INFO - # TBPL SUCCESS # 19:09:58 INFO - Running post-action listener: _resource_record_post_action 19:09:58 INFO - Running post-run listener: _resource_record_post_run 19:09:59 INFO - Total resource usage - Wall time: 1229s; CPU: 13.0%; Read bytes: 8192; Write bytes: 524226560; Read time: 276; Write time: 468928 19:09:59 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 177336320; Read time: 0; Write time: 170864 19:09:59 INFO - run-tests - Wall time: 1215s; CPU: 13.0%; Read bytes: 8192; Write bytes: 342945792; Read time: 276; Write time: 297188 19:09:59 INFO - Running post-run listener: _upload_blobber_files 19:09:59 INFO - Blob upload gear active. 19:09:59 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 19:09:59 INFO - Copying logs to upload dir... 19:09:59 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1264.224235 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2015-11-19 19:09:59.259028) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 19:09:59.262176) ========= 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=d16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009979 build_url: 'https://queue.taskcluster.net/v1/task/mpyaTDQhR7eCavf7kaOvlw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.16 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-19 19:09:59.430610) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2015-11-19 19:09:59.430951) ========= 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=d16fe3a243cfe9e60ec7df77000001e0-1447978639.122015-715799472 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004636 ========= master_lag: 2.48 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2015-11-19 19:10:01.920170) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 19:10:01.920514) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-19 19:10:01.921556) ========= ========= Total master_lag: 2.89 =========