builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-019 starttime: 1448342734.89 results: success (0) buildid: 20151123203315 builduid: 7147fa46b55745859de0d2acd03204a2 revision: befb8e2645ef77775bbd5ef7eb866fd51b376458 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:34.891585) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:34.892289) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:34.892595) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006613 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:34.913347) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:34.913656) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:34.926832) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:34.927205) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004642 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:34.976452) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:34.976757) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:34.977166) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:34.977465) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-23 21:25:35-- 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% 44.0M=0s 2015-11-23 21:25:35 (44.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.192536 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:35.186048) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:35.186641) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018209 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:35.244027) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 21:25:35.244543) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev befb8e2645ef77775bbd5ef7eb866fd51b376458 --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 befb8e2645ef77775bbd5ef7eb866fd51b376458 --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=d60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-23 21:25:35,448 truncating revision to first 12 chars 2015-11-23 21:25:35,448 Setting DEBUG logging. 2015-11-23 21:25:35,448 attempt 1/10 2015-11-23 21:25:35,448 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/befb8e2645ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-23 21:25:36,563 unpacking tar archive at: fx-team-befb8e2645ef/testing/mozharness/ program finished with exit code 0 elapsedTime=1.423361 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2015-11-23 21:25:36.684320) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:36.684641) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:36.693038) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:36.693373) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-23 21:25:36.693783) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2015-11-23 21:25:36.694072) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=d60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:25:36 INFO - MultiFileLogger online at 20151123 21:25:36 in /builds/slave/test-pgo 21:25:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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 21:25:36 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 21:25:36 INFO - {'append_to_log': False, 21:25:36 INFO - 'base_work_dir': '/builds/slave/test-pgo', 21:25:36 INFO - 'blob_upload_branch': 'Fx-Team', 21:25:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 21:25:36 INFO - 'branch': 'Fx-Team', 21:25:36 INFO - 'buildbot_json_path': 'buildprops.json', 21:25:36 INFO - 'config_files': ('talos/linux_config.py',), 21:25:36 INFO - 'default_actions': ('clobber', 21:25:36 INFO - 'read-buildbot-config', 21:25:36 INFO - 'download-and-extract', 21:25:36 INFO - 'populate-webroot', 21:25:36 INFO - 'create-virtualenv', 21:25:36 INFO - 'install', 21:25:36 INFO - 'run-tests'), 21:25:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:25:36 INFO - 'download_minidump_stackwalk': True, 21:25:36 INFO - 'download_symbols': 'ondemand', 21:25:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:25:36 INFO - 'tooltool.py': '/tools/tooltool.py', 21:25:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:25:36 INFO - '/tools/misc-python/virtualenv.py')}, 21:25:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:25:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:25:36 INFO - 'installer_path': 'installer.exe', 21:25:36 INFO - 'log_level': 'info', 21:25:36 INFO - 'log_name': 'talos', 21:25:36 INFO - 'log_to_console': True, 21:25:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:25:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:25:36 INFO - 'opt_config_files': (), 21:25:36 INFO - 'pip_index': False, 21:25:36 INFO - 'sps_profile': False, 21:25:36 INFO - 'sps_profile_interval': 0, 21:25:36 INFO - 'suite': 'dromaeojs-e10s', 21:25:36 INFO - 'system_bits': '64', 21:25:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:25:36 INFO - 'title': 'talos-linux64-ix-019', 21:25:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:25:36 INFO - 'use_talos_json': True, 21:25:36 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 21:25:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:25:36 INFO - 'work_dir': 'build'} 21:25:36 INFO - ##### 21:25:36 INFO - ##### Running clobber step. 21:25:36 INFO - ##### 21:25:36 INFO - Running pre-action listener: _resource_record_pre_action 21:25:36 INFO - Running main action method: clobber 21:25:36 INFO - rmtree: /builds/slave/test-pgo/build 21:25:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 21:25:37 INFO - Running post-action listener: _resource_record_post_action 21:25:37 INFO - ##### 21:25:37 INFO - ##### Running read-buildbot-config step. 21:25:37 INFO - ##### 21:25:37 INFO - Running pre-action listener: _resource_record_pre_action 21:25:37 INFO - Running main action method: read_buildbot_config 21:25:37 INFO - Using buildbot properties: 21:25:37 INFO - { 21:25:37 INFO - "properties": { 21:25:37 INFO - "buildnumber": 102, 21:25:37 INFO - "product": "firefox", 21:25:37 INFO - "script_repo_revision": "production", 21:25:37 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 21:25:37 INFO - "repository": "", 21:25:37 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 21:25:37 INFO - "buildid": "20151123203315", 21:25:37 INFO - "slavename": "talos-linux64-ix-019", 21:25:37 INFO - "pgo_build": "True", 21:25:37 INFO - "basedir": "/builds/slave/test-pgo", 21:25:37 INFO - "project": "", 21:25:37 INFO - "platform": "ubuntu64_hw", 21:25:37 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:25:37 INFO - "slavebuilddir": "test", 21:25:37 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 21:25:37 INFO - "branch": "fx-team", 21:25:37 INFO - "repo_path": "integration/fx-team", 21:25:37 INFO - "stage_platform": "linux64-pgo", 21:25:37 INFO - "builduid": "7147fa46b55745859de0d2acd03204a2", 21:25:37 INFO - "revision": "befb8e2645ef77775bbd5ef7eb866fd51b376458" 21:25:37 INFO - }, 21:25:37 INFO - "sourcestamp": { 21:25:37 INFO - "repository": "", 21:25:37 INFO - "hasPatch": false, 21:25:37 INFO - "project": "", 21:25:37 INFO - "branch": "fx-team-linux64-pgo-talos", 21:25:37 INFO - "changes": [ 21:25:37 INFO - { 21:25:37 INFO - "category": null, 21:25:37 INFO - "files": [ 21:25:37 INFO - { 21:25:37 INFO - "url": null, 21:25:37 INFO - "name": "https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 21:25:37 INFO - } 21:25:37 INFO - ], 21:25:37 INFO - "repository": "", 21:25:37 INFO - "rev": "befb8e2645ef77775bbd5ef7eb866fd51b376458", 21:25:37 INFO - "who": "sendchange", 21:25:37 INFO - "when": 1448342674, 21:25:37 INFO - "number": 6698948, 21:25:37 INFO - "comments": "", 21:25:37 INFO - "project": "", 21:25:37 INFO - "at": "Mon 23 Nov 2015 21:24:34", 21:25:37 INFO - "branch": "fx-team-linux64-pgo-talos", 21:25:37 INFO - "revlink": "", 21:25:37 INFO - "properties": [ 21:25:37 INFO - [ 21:25:37 INFO - "buildid", 21:25:37 INFO - "20151123203315", 21:25:37 INFO - "Change" 21:25:37 INFO - ], 21:25:37 INFO - [ 21:25:37 INFO - "builduid", 21:25:37 INFO - "7147fa46b55745859de0d2acd03204a2", 21:25:37 INFO - "Change" 21:25:37 INFO - ], 21:25:37 INFO - [ 21:25:37 INFO - "pgo_build", 21:25:37 INFO - "True", 21:25:37 INFO - "Change" 21:25:37 INFO - ] 21:25:37 INFO - ], 21:25:37 INFO - "revision": "befb8e2645ef77775bbd5ef7eb866fd51b376458" 21:25:37 INFO - } 21:25:37 INFO - ], 21:25:37 INFO - "revision": "befb8e2645ef77775bbd5ef7eb866fd51b376458" 21:25:37 INFO - } 21:25:37 INFO - } 21:25:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 21:25:37 INFO - Running post-action listener: _resource_record_post_action 21:25:37 INFO - ##### 21:25:37 INFO - ##### Running download-and-extract step. 21:25:37 INFO - ##### 21:25:37 INFO - Running pre-action listener: _resource_record_pre_action 21:25:37 INFO - Running main action method: download_and_extract 21:25:37 INFO - mkdir: /builds/slave/test-pgo/build/tests 21:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:25:37 INFO - https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 21:25:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/test_packages.json 21:25:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/test_packages.json 21:25:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 21:25:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 21:25:38 INFO - Downloaded 1302 bytes. 21:25:38 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 21:25:38 INFO - Using the following test package requirements: 21:25:38 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:25:38 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:25:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:25:38 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:25:38 INFO - u'jsshell-linux-x86_64.zip'], 21:25:38 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:25:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:25:38 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:25:38 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:25:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:25:38 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:25:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:25:38 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:25:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:25:38 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 21:25:38 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 21:25:38 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:25:38 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:25:38 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')]} 21:25:38 INFO - https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:25:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:25:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:25:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/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 21:25:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/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 21:25:40 INFO - Downloaded 22525927 bytes. 21:25:40 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 21:25:40 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:25:40 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 21:25:41 INFO - Return code: 0 21:25:41 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 21:25:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:25:41 INFO - https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:25:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:25:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:25:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/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 21:25:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/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 21:25:45 INFO - Downloaded 22525927 bytes. 21:25:45 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 21:25:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 21:25:45 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 21:25:46 INFO - Return code: 0 21:25:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:25:46 INFO - https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:25:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:25:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:25:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/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 21:25:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/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 21:25:47 INFO - Downloaded 11130997 bytes. 21:25:47 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 21:25:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 21:25:47 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 21:25:47 INFO - Return code: 0 21:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:25:47 INFO - https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:25:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:25:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:25:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/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 21:25:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/uE6ZnjnNThu-eRznmLvRqw/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 21:25:49 INFO - Downloaded 57580176 bytes. 21:25:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:25:49 INFO - mkdir: /builds/slave/test-pgo/properties 21:25:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 21:25:49 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 21:25:49 INFO - Contents: 21:25:49 INFO - build_url:https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 21:25:49 INFO - Running post-action listener: _resource_record_post_action 21:25:49 INFO - Running post-action listener: set_extra_try_arguments 21:25:49 INFO - ##### 21:25:49 INFO - ##### Running populate-webroot step. 21:25:49 INFO - ##### 21:25:49 INFO - Running pre-action listener: _resource_record_pre_action 21:25:49 INFO - Running main action method: populate_webroot 21:25:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:25:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:25:49 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 21:25:49 INFO - '../fennec_ids.txt'], 21:25:49 INFO - 'tests': ['tcheck2']}, 21:25:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:25:49 INFO - '--tppagecycles', 21:25:49 INFO - '7'], 21:25:49 INFO - 'tests': ['tsvgm']}}, 21:25:49 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 21:25:49 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 21:25:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:25:49 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:25:49 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 21:25:49 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 21:25:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:25:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:49 INFO - 'talos_options': ['--e10s'], 21:25:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:25:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:49 INFO - 'tests': ['damp', 'tps']}, 21:25:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:49 INFO - 'talos_options': ['--e10s'], 21:25:49 INFO - 'tests': ['damp', 'tps']}, 21:25:49 INFO - 'g3': {'tests': ['dromaeo_dom']}, 21:25:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:25:49 INFO - 'other': {'tests': ['a11yr', 21:25:49 INFO - 'ts_paint', 21:25:49 INFO - 'tpaint', 21:25:49 INFO - 'sessionrestore', 21:25:49 INFO - 'sessionrestore_no_auto_restore']}, 21:25:49 INFO - 'other-e10s': {'talos_options': ['--e10s'], 21:25:49 INFO - 'tests': ['a11yr', 21:25:49 INFO - 'ts_paint', 21:25:49 INFO - 'tpaint', 21:25:49 INFO - 'sessionrestore', 21:25:49 INFO - 'sessionrestore_no_auto_restore']}, 21:25:49 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 21:25:49 INFO - 'tests': ['a11yr', 21:25:49 INFO - 'ts_paint', 21:25:49 INFO - 'tpaint', 21:25:49 INFO - 'sessionrestore', 21:25:49 INFO - 'sessionrestore_no_auto_restore']}, 21:25:49 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 21:25:49 INFO - 'tests': ['a11yr', 21:25:49 INFO - 'ts_paint', 21:25:49 INFO - 'tpaint', 21:25:49 INFO - 'sessionrestore', 21:25:49 INFO - 'sessionrestore_no_auto_restore']}, 21:25:49 INFO - 'other_l64': {'tests': ['a11yr', 21:25:49 INFO - 'ts_paint', 21:25:49 INFO - 'tpaint', 21:25:49 INFO - 'sessionrestore', 21:25:49 INFO - 'sessionrestore_no_auto_restore']}, 21:25:49 INFO - 'other_nol64': {'tests': ['a11yr', 21:25:49 INFO - 'ts_paint', 21:25:49 INFO - 'tpaint', 21:25:49 INFO - 'sessionrestore', 21:25:49 INFO - 'sessionrestore_no_auto_restore']}, 21:25:49 INFO - 'svgr': {'tests': ['tsvgx', 21:25:49 INFO - 'tsvgr_opacity', 21:25:49 INFO - 'tart', 21:25:49 INFO - 'tscrollx', 21:25:49 INFO - 'cart']}, 21:25:49 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 21:25:49 INFO - 'tests': ['tsvgx', 21:25:49 INFO - 'tsvgr_opacity', 21:25:49 INFO - 'tart', 21:25:49 INFO - 'tscrollx', 21:25:49 INFO - 'cart']}, 21:25:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:49 INFO - 'tests': ['tp5o']}, 21:25:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:49 INFO - 'talos_options': ['--e10s'], 21:25:49 INFO - 'tests': ['tp5o']}, 21:25:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:49 INFO - 'talos_options': ['--xperf_path', 21:25:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:25:49 INFO - 'tests': ['tp5n']}, 21:25:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:25:49 INFO - 'talos_options': ['--e10s', 21:25:49 INFO - '--xperf_path', 21:25:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:25:49 INFO - 'tests': ['tp5n']}}, 21:25:49 INFO - 'talos.zip': {'path': '', 21:25:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:25:49 INFO - Running post-action listener: _resource_record_post_action 21:25:49 INFO - ##### 21:25:49 INFO - ##### Running create-virtualenv step. 21:25:49 INFO - ##### 21:25:49 INFO - Running pre-action listener: _resource_record_pre_action 21:25:49 INFO - Running main action method: create_virtualenv 21:25:49 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 21:25:49 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 21:25:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 21:25:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:25:49 INFO - Using real prefix '/usr' 21:25:49 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 21:25:49 INFO - Installing distribute.............................................................................................................................................................................................done. 21:25:51 INFO - Installing pip.................done. 21:25:51 INFO - Return code: 0 21:25:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 21:25:51 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')]} 21:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:51 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')]} 21:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:51 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 0x7effa8cfbc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24aa7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eed70>, '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 0x24efb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24effb0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', '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 21:25:51 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 21:25:51 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 21:25:51 INFO - Using env: {'DISPLAY': ':0', 21:25:51 INFO - 'HOME': '/home/cltbld', 21:25:51 INFO - 'LANG': 'en_US.UTF-8', 21:25:51 INFO - 'LANGUAGE': 'en_US:en', 21:25:51 INFO - 'LOGNAME': 'cltbld', 21:25:51 INFO - 'MAIL': '/var/mail/cltbld', 21:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:25:51 INFO - 'MOZ_NO_REMOTE': '1', 21:25:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:25:51 INFO - 'NO_EM_RESTART': '1', 21:25:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:25:51 INFO - 'PWD': '/builds/slave/test-pgo', 21:25:51 INFO - 'SHELL': '/bin/bash', 21:25:51 INFO - 'SHLVL': '1', 21:25:51 INFO - 'TERM': 'linux', 21:25:51 INFO - 'TMOUT': '86400', 21:25:51 INFO - 'USER': 'cltbld', 21:25:51 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', 21:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:25:51 INFO - '_': '/tools/buildbot/bin/python'} 21:25:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:25:52 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 21:25:52 INFO - Cleaning up... 21:25:52 INFO - Return code: 0 21:25:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 21:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:25:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:25:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:25:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:52 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x7effa8cfbc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24aa7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eed70>, '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 0x24efb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24effb0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', '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 21:25:52 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 21:25:52 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 21:25:52 INFO - Using env: {'DISPLAY': ':0', 21:25:52 INFO - 'HOME': '/home/cltbld', 21:25:52 INFO - 'LANG': 'en_US.UTF-8', 21:25:52 INFO - 'LANGUAGE': 'en_US:en', 21:25:52 INFO - 'LOGNAME': 'cltbld', 21:25:52 INFO - 'MAIL': '/var/mail/cltbld', 21:25:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:25:52 INFO - 'MOZ_NO_REMOTE': '1', 21:25:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:25:52 INFO - 'NO_EM_RESTART': '1', 21:25:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:25:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:25:52 INFO - 'PWD': '/builds/slave/test-pgo', 21:25:52 INFO - 'SHELL': '/bin/bash', 21:25:52 INFO - 'SHLVL': '1', 21:25:52 INFO - 'TERM': 'linux', 21:25:52 INFO - 'TMOUT': '86400', 21:25:52 INFO - 'USER': 'cltbld', 21:25:52 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', 21:25:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:25:52 INFO - '_': '/tools/buildbot/bin/python'} 21:25:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:25:52 INFO - Downloading/unpacking psutil>=0.7.1 21:25:52 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 21:25:52 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 21:25:52 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 21:25:52 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 21:25:52 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 21:25:52 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 21:25:53 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 21:25:53 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 21:25:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 21:25:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:25:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:25:54 INFO - Installing collected packages: psutil 21:25:54 INFO - Running setup.py install for psutil 21:25:54 INFO - building 'psutil._psutil_linux' extension 21:25:54 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 21:25:54 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 21:25:54 INFO - building 'psutil._psutil_posix' extension 21:25:54 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 21:25:54 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 21:25:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:25:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:25:54 INFO - Successfully installed psutil 21:25:54 INFO - Cleaning up... 21:25:54 INFO - Return code: 0 21:25:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 21:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:25:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:25:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:54 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7effa8cfbc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24aa7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eed70>, '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 0x24efb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24effb0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', '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 21:25:54 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 21:25:54 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 21:25:54 INFO - Using env: {'DISPLAY': ':0', 21:25:54 INFO - 'HOME': '/home/cltbld', 21:25:54 INFO - 'LANG': 'en_US.UTF-8', 21:25:54 INFO - 'LANGUAGE': 'en_US:en', 21:25:54 INFO - 'LOGNAME': 'cltbld', 21:25:54 INFO - 'MAIL': '/var/mail/cltbld', 21:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:25:54 INFO - 'MOZ_NO_REMOTE': '1', 21:25:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:25:54 INFO - 'NO_EM_RESTART': '1', 21:25:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:25:54 INFO - 'PWD': '/builds/slave/test-pgo', 21:25:54 INFO - 'SHELL': '/bin/bash', 21:25:54 INFO - 'SHLVL': '1', 21:25:54 INFO - 'TERM': 'linux', 21:25:54 INFO - 'TMOUT': '86400', 21:25:54 INFO - 'USER': 'cltbld', 21:25:54 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', 21:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:25:54 INFO - '_': '/tools/buildbot/bin/python'} 21:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:25:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:25:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:25:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:25:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:25:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:25:56 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 21:25:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:25:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:25:56 INFO - Installing collected packages: mozsystemmonitor 21:25:56 INFO - Running setup.py install for mozsystemmonitor 21:25:56 INFO - Successfully installed mozsystemmonitor 21:25:56 INFO - Cleaning up... 21:25:56 INFO - Return code: 0 21:25:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 21:25: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')]} 21:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25: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')]} 21:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:56 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7effa8cfbc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24aa7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eed70>, '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 0x24efb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24effb0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', '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 21:25:56 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 21:25:56 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:25:56 INFO - Using env: {'DISPLAY': ':0', 21:25:56 INFO - 'HOME': '/home/cltbld', 21:25:56 INFO - 'LANG': 'en_US.UTF-8', 21:25:56 INFO - 'LANGUAGE': 'en_US:en', 21:25:56 INFO - 'LOGNAME': 'cltbld', 21:25:56 INFO - 'MAIL': '/var/mail/cltbld', 21:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:25:56 INFO - 'MOZ_NO_REMOTE': '1', 21:25:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:25:56 INFO - 'NO_EM_RESTART': '1', 21:25:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:25:56 INFO - 'PWD': '/builds/slave/test-pgo', 21:25:56 INFO - 'SHELL': '/bin/bash', 21:25:56 INFO - 'SHLVL': '1', 21:25:56 INFO - 'TERM': 'linux', 21:25:56 INFO - 'TMOUT': '86400', 21:25:56 INFO - 'USER': 'cltbld', 21:25:56 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', 21:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:25:56 INFO - '_': '/tools/buildbot/bin/python'} 21:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:25:56 INFO - Downloading/unpacking blobuploader==1.2.4 21:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:25:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:25:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:25:58 INFO - Downloading blobuploader-1.2.4.tar.gz 21:25:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 21:25:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:25:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:25:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:25:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:25:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:25:58 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 21:25:58 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 21:25:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:25:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:25:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:25:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:25:59 INFO - Downloading docopt-0.6.1.tar.gz 21:25:59 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 21:25:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 21:25:59 INFO - Installing collected packages: blobuploader, requests, docopt 21:25:59 INFO - Running setup.py install for blobuploader 21:25:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:25:59 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 21:25:59 INFO - Running setup.py install for requests 21:25:59 INFO - Running setup.py install for docopt 21:25:59 INFO - Successfully installed blobuploader requests docopt 21:25:59 INFO - Cleaning up... 21:25:59 INFO - Return code: 0 21:25:59 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:25:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:25:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:25:59 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 0x7effa8cfbc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24aa7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eed70>, '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 0x24efb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24effb0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', '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 21:25:59 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 21:25:59 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 21:25:59 INFO - Using env: {'DISPLAY': ':0', 21:25:59 INFO - 'HOME': '/home/cltbld', 21:25:59 INFO - 'LANG': 'en_US.UTF-8', 21:25:59 INFO - 'LANGUAGE': 'en_US:en', 21:25:59 INFO - 'LOGNAME': 'cltbld', 21:25:59 INFO - 'MAIL': '/var/mail/cltbld', 21:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:25:59 INFO - 'MOZ_NO_REMOTE': '1', 21:25:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:25:59 INFO - 'NO_EM_RESTART': '1', 21:25:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:25:59 INFO - 'PWD': '/builds/slave/test-pgo', 21:25:59 INFO - 'SHELL': '/bin/bash', 21:25:59 INFO - 'SHLVL': '1', 21:25:59 INFO - 'TERM': 'linux', 21:25:59 INFO - 'TMOUT': '86400', 21:25:59 INFO - 'USER': 'cltbld', 21:25:59 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', 21:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:25:59 INFO - '_': '/tools/buildbot/bin/python'} 21:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:26:00 INFO - Running setup.py (path:/tmp/pip-ALTIk9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:26:00 INFO - Running setup.py (path:/tmp/pip-FW8qVv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:26:00 INFO - Running setup.py (path:/tmp/pip-ZnDLbz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:26:00 INFO - Running setup.py (path:/tmp/pip-bmYhYp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:26:00 INFO - Running setup.py (path:/tmp/pip-8f6u09-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:26:00 INFO - Running setup.py (path:/tmp/pip-rWyphh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:26:00 INFO - Running setup.py (path:/tmp/pip-UBPl2q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:26:00 INFO - Running setup.py (path:/tmp/pip-oNYay_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:26:00 INFO - Running setup.py (path:/tmp/pip-6JoCvQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:26:00 INFO - Running setup.py (path:/tmp/pip-yVOvi0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:26:00 INFO - Running setup.py (path:/tmp/pip-tAM46N-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:26:00 INFO - Running setup.py (path:/tmp/pip-RTvA3x-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:26:00 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:26:00 INFO - Running setup.py (path:/tmp/pip-U6PbGV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:26:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:26:01 INFO - Running setup.py (path:/tmp/pip-BDU1tL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:26:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:26:01 INFO - Running setup.py (path:/tmp/pip-VCqUEe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:26:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:26:01 INFO - Running setup.py (path:/tmp/pip-yhCuYX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:26:01 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:26:01 INFO - Running setup.py (path:/tmp/pip-YqubxJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:26:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:26:01 INFO - Running setup.py install for manifestparser 21:26:01 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 21:26:01 INFO - Running setup.py install for mozcrash 21:26:01 INFO - Running setup.py install for mozdebug 21:26:01 INFO - Running setup.py install for mozdevice 21:26:01 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 21:26:01 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 21:26:01 INFO - Running setup.py install for mozfile 21:26:01 INFO - Running setup.py install for mozhttpd 21:26:01 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 21:26:01 INFO - Running setup.py install for mozinfo 21:26:01 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 21:26:02 INFO - Running setup.py install for mozInstall 21:26:02 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 21:26:02 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 21:26:02 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 21:26:02 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 21:26:02 INFO - Running setup.py install for mozleak 21:26:02 INFO - Running setup.py install for mozlog 21:26:02 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 21:26:02 INFO - Running setup.py install for moznetwork 21:26:02 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 21:26:02 INFO - Running setup.py install for mozprocess 21:26:02 INFO - Running setup.py install for mozprofile 21:26:02 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 21:26:02 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 21:26:02 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 21:26:02 INFO - Running setup.py install for mozrunner 21:26:02 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 21:26:02 INFO - Running setup.py install for mozscreenshot 21:26:02 INFO - Running setup.py install for moztest 21:26:02 INFO - Running setup.py install for mozversion 21:26:03 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 21:26:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:26:03 INFO - Cleaning up... 21:26:03 INFO - Return code: 0 21:26:03 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:03 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 0x7effa8cfbc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24aa7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eed70>, '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 0x24efb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24effb0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', '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 21:26:03 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 21:26:03 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 21:26:03 INFO - Using env: {'DISPLAY': ':0', 21:26:03 INFO - 'HOME': '/home/cltbld', 21:26:03 INFO - 'LANG': 'en_US.UTF-8', 21:26:03 INFO - 'LANGUAGE': 'en_US:en', 21:26:03 INFO - 'LOGNAME': 'cltbld', 21:26:03 INFO - 'MAIL': '/var/mail/cltbld', 21:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:03 INFO - 'MOZ_NO_REMOTE': '1', 21:26:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:26:03 INFO - 'NO_EM_RESTART': '1', 21:26:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:26:03 INFO - 'PWD': '/builds/slave/test-pgo', 21:26:03 INFO - 'SHELL': '/bin/bash', 21:26:03 INFO - 'SHLVL': '1', 21:26:03 INFO - 'TERM': 'linux', 21:26:03 INFO - 'TMOUT': '86400', 21:26:03 INFO - 'USER': 'cltbld', 21:26:03 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', 21:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:03 INFO - '_': '/tools/buildbot/bin/python'} 21:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:26:03 INFO - Running setup.py (path:/tmp/pip-LTksxn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 21:26:03 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)) 21:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:26:03 INFO - Running setup.py (path:/tmp/pip-eAVez5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 21:26:03 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)) 21:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:26:03 INFO - Running setup.py (path:/tmp/pip-5qh6F4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 21:26:03 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)) 21:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:26:03 INFO - Running setup.py (path:/tmp/pip-sRPpyT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 21:26:03 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)) 21:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 21:26:03 INFO - Running setup.py (path:/tmp/pip-rirp_4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 21:26:03 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)) 21:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:26:03 INFO - Running setup.py (path:/tmp/pip-iqqNrf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 21:26:03 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)) 21:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:26:03 INFO - Running setup.py (path:/tmp/pip-0Fh3Iz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 21:26:03 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)) 21:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:26:03 INFO - Running setup.py (path:/tmp/pip-mt4nvY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 21:26:03 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)) 21:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 21:26:03 INFO - Running setup.py (path:/tmp/pip-L0bMb7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 21:26:03 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)) 21:26:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 21:26:03 INFO - Running setup.py (path:/tmp/pip-W7w0VN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 21:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:26:04 INFO - Running setup.py (path:/tmp/pip-_zSrpU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 21:26:04 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)) 21:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:26:04 INFO - Running setup.py (path:/tmp/pip-jubcw2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 21:26:04 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)) 21:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:26:04 INFO - Running setup.py (path:/tmp/pip-W2lmrH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 21:26:04 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)) 21:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:26:04 INFO - Running setup.py (path:/tmp/pip-ftFrJT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 21:26:04 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)) 21:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:26:04 INFO - Running setup.py (path:/tmp/pip-h0v192-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 21:26:04 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)) 21:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 21:26:04 INFO - Running setup.py (path:/tmp/pip-t4dNYg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 21:26:04 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)) 21:26:04 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 21:26:04 INFO - Running setup.py (path:/tmp/pip-3WgrGa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 21:26:04 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)) 21:26:04 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)) 21:26:04 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)) 21:26:04 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)) 21:26:04 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)) 21:26:04 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)) 21:26:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 21:26:04 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 21:26:04 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 21:26:04 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 21:26:04 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 21:26:04 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 21:26:04 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 21:26:06 INFO - Downloading blessings-1.5.1.tar.gz 21:26:06 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 21:26:06 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 21:26:06 INFO - Installing collected packages: blessings 21:26:06 INFO - Running setup.py install for blessings 21:26:06 INFO - Successfully installed blessings 21:26:06 INFO - Cleaning up... 21:26:06 INFO - Return code: 0 21:26:06 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 21:26:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:26:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:26:06 INFO - Reading from file tmpfile_stdout 21:26:06 INFO - Current package versions: 21:26:06 INFO - argparse == 1.2.1 21:26:06 INFO - blessings == 1.5.1 21:26:06 INFO - blobuploader == 1.2.4 21:26:06 INFO - docopt == 0.6.1 21:26:06 INFO - manifestparser == 1.1 21:26:06 INFO - mozInstall == 1.12 21:26:06 INFO - mozcrash == 0.16 21:26:06 INFO - mozdebug == 0.1 21:26:06 INFO - mozdevice == 0.47 21:26:06 INFO - mozfile == 1.2 21:26:06 INFO - mozhttpd == 0.7 21:26:06 INFO - mozinfo == 0.9 21:26:06 INFO - mozleak == 0.1 21:26:06 INFO - mozlog == 3.1 21:26:06 INFO - moznetwork == 0.27 21:26:06 INFO - mozprocess == 0.22 21:26:06 INFO - mozprofile == 0.27 21:26:06 INFO - mozrunner == 6.11 21:26:06 INFO - mozscreenshot == 0.1 21:26:06 INFO - mozsystemmonitor == 0.0 21:26:06 INFO - moztest == 0.7 21:26:06 INFO - mozversion == 1.4 21:26:06 INFO - psutil == 3.1.1 21:26:06 INFO - requests == 1.2.3 21:26:06 INFO - wsgiref == 0.1.2 21:26:06 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 21:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:26:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:26:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:26:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:26:06 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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 0x7effa8cfbc70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23f86c0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24aa7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24eed70>, '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 0x24efb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24effb0>, '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': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', '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 21:26:06 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/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 21:26:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/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 21:26:06 INFO - Using env: {'DISPLAY': ':0', 21:26:06 INFO - 'HOME': '/home/cltbld', 21:26:06 INFO - 'LANG': 'en_US.UTF-8', 21:26:06 INFO - 'LANGUAGE': 'en_US:en', 21:26:06 INFO - 'LOGNAME': 'cltbld', 21:26:06 INFO - 'MAIL': '/var/mail/cltbld', 21:26:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:06 INFO - 'MOZ_NO_REMOTE': '1', 21:26:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:26:06 INFO - 'NO_EM_RESTART': '1', 21:26:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:26:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:26:06 INFO - 'PWD': '/builds/slave/test-pgo', 21:26:06 INFO - 'SHELL': '/bin/bash', 21:26:06 INFO - 'SHLVL': '1', 21:26:06 INFO - 'TERM': 'linux', 21:26:06 INFO - 'TMOUT': '86400', 21:26:06 INFO - 'USER': 'cltbld', 21:26:06 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', 21:26:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:06 INFO - '_': '/tools/buildbot/bin/python'} 21:26:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:26:06 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)) 21:26:06 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)) 21:26:06 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)) 21:26:06 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)) 21:26:06 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)) 21:26:06 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)) 21:26:06 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)) 21:26:06 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)) 21:26:06 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)) 21:26:06 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)) 21:26:06 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)) 21:26:06 INFO - Cleaning up... 21:26:06 INFO - Return code: 0 21:26:06 INFO - Running post-action listener: _resource_record_post_action 21:26:06 INFO - Running post-action listener: _start_resource_monitoring 21:26:06 INFO - Starting resource monitoring. 21:26:06 INFO - ##### 21:26:06 INFO - ##### Running install step. 21:26:06 INFO - ##### 21:26:06 INFO - Running pre-action listener: _resource_record_pre_action 21:26:06 INFO - Running main action method: install 21:26:06 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 21:26:06 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 21:26:07 INFO - Reading from file tmpfile_stdout 21:26:07 INFO - Detecting whether we're running mozinstall >=1.0... 21:26:07 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 21:26:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 21:26:07 INFO - Reading from file tmpfile_stdout 21:26:07 INFO - Output received: 21:26:07 INFO - Usage: mozinstall [options] installer 21:26:07 INFO - Options: 21:26:07 INFO - -h, --help show this help message and exit 21:26:07 INFO - -d DEST, --destination=DEST 21:26:07 INFO - Directory to install application into. [default: 21:26:07 INFO - "/builds/slave/test-pgo"] 21:26:07 INFO - --app=APP Application being installed. [default: firefox] 21:26:07 INFO - mkdir: /builds/slave/test-pgo/build/application 21:26:07 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'] 21:26:07 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 21:26:21 INFO - Reading from file tmpfile_stdout 21:26:21 INFO - Output received: 21:26:21 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 21:26:21 INFO - Running post-action listener: _resource_record_post_action 21:26:21 INFO - ##### 21:26:21 INFO - ##### Running run-tests step. 21:26:21 INFO - ##### 21:26:21 INFO - Running pre-action listener: _resource_record_pre_action 21:26:21 INFO - Running main action method: run_tests 21:26:21 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 21:26:21 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 21:26:21 INFO - Python 2.7.3 21:26:21 INFO - Return code: 0 21:26:21 INFO - grabbing minidump binary from tooltool 21:26: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')]} 21:26:21 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 0x24eed70>, '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 0x24efb20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24effb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 21:26:21 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 21:26:21 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 21:26:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:26:21 INFO - Return code: 0 21:26:21 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 21:26:21 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 21:26:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 21:26:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 21:26:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 21:26:21 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 21:26:21 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-e10s --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --webServer localhost 21:26:21 INFO - Using env: {'DISPLAY': ':0', 21:26:21 INFO - 'HOME': '/home/cltbld', 21:26:21 INFO - 'LANG': 'en_US.UTF-8', 21:26:21 INFO - 'LANGUAGE': 'en_US:en', 21:26:21 INFO - 'LOGNAME': 'cltbld', 21:26:21 INFO - 'MAIL': '/var/mail/cltbld', 21:26:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:26:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 21:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:26:21 INFO - 'MOZ_NO_REMOTE': '1', 21:26:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 21:26:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:26:21 INFO - 'NO_EM_RESTART': '1', 21:26:21 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 21:26:21 INFO - 'PWD': '/builds/slave/test-pgo', 21:26:21 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 21:26:21 INFO - 'SHELL': '/bin/bash', 21:26:21 INFO - 'SHLVL': '1', 21:26:21 INFO - 'TERM': 'linux', 21:26:21 INFO - 'TMOUT': '86400', 21:26:21 INFO - 'USER': 'cltbld', 21:26:21 INFO - 'XDG_SESSION_COOKIE': 'd60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226', 21:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:26:21 INFO - '_': '/tools/buildbot/bin/python'} 21:26:21 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-e10s', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--webServer', 'localhost'] with output_timeout 3600 21:26:21 INFO - mozversion INFO | application_buildid: 20151123203315 21:26:21 INFO - mozversion INFO | application_changeset: befb8e2645ef77775bbd5ef7eb866fd51b376458 21:26:21 INFO - mozversion INFO | application_display_name: Nightly 21:26:21 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:26:21 INFO - mozversion INFO | application_name: Firefox 21:26:21 INFO - mozversion INFO | application_remotingname: firefox 21:26:21 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 21:26:21 INFO - mozversion INFO | application_vendor: Mozilla 21:26:21 INFO - mozversion INFO | application_version: 45.0a1 21:26:21 INFO - mozversion INFO | platform_buildid: 20151123203315 21:26:21 INFO - mozversion INFO | platform_changeset: befb8e2645ef77775bbd5ef7eb866fd51b376458 21:26:21 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 21:26:21 INFO - mozversion INFO | platform_version: 45.0a1 21:26:21 INFO - 2015-11-23 21:26:21,900 DEBUG : using testdate: 1448342781 21:26:21 INFO - 2015-11-23 21:26:21,900 DEBUG : actual date: 1448342781 21:26:21 INFO - 2015-11-23 21:26:21,908 INFO : starting webserver on 'localhost:42112' 21:26:21 INFO - 2015-11-23 21:26:21,909 INFO : Starting test suite talos-linux64-ix-019.e 21:26:21 INFO - 2015-11-23 21:26:21,909 INFO : Starting test dromaeo_css 21:26:21 INFO - 2015-11-23 21:26:21,909 DEBUG : operating with platform_type : linux_ 21:26:22 INFO - 2015-11-23 21:26:22,086 INFO : Initialising browser for dromaeo_css test... 21:26:22 INFO - 2015-11-23 21:26:22,097 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoX2SuM/profile http://localhost:42112/getInfo.html 21:26:27 INFO - 2015-11-23 21:26:27,890 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 21:26:27 INFO - 2015-11-23 21:26:27,890 DEBUG : BROWSER_OUTPUT: colorDepth:24 21:26:27 INFO - 2015-11-23 21:26:27,890 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 21:26:27 INFO - 2015-11-23 21:26:27,890 DEBUG : BROWSER_OUTPUT: __metrics 21:26:28 INFO - 2015-11-23 21:26:28,945 INFO : Browser initialized. 21:26:28 INFO - 2015-11-23 21:26:28,946 INFO : Running cycle 1/1 for dromaeo_css test... 21:26:28 INFO - 2015-11-23 21:26:28,946 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpoX2SuM/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 21:26:40 INFO - 2015-11-23 21:26:40,793 DEBUG : BROWSER_OUTPUT: RSS: Main: 139735040 21:26:40 INFO - 2015-11-23 21:26:40,794 DEBUG : BROWSER_OUTPUT: 21:27:30 INFO - 2015-11-23 21:27:30,416 DEBUG : BROWSER_OUTPUT: 1448342850414 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] 21:29:39 INFO - 2015-11-23 21:29:39,954 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42112/tests/dromaeo/cssquery-ext.html) 21:29:41 INFO - 2015-11-23 21:29:41,121 DEBUG : BROWSER_OUTPUT: RSS: Main: 145526784 21:29:41 INFO - 2015-11-23 21:29:41,121 DEBUG : BROWSER_OUTPUT: 21:32:43 INFO - 2015-11-23 21:32:43,158 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/dromaeo/cssquery-ext.html (next: http://localhost:42112/tests/dromaeo/cssquery-jquery.html) 21:32:43 INFO - 2015-11-23 21:32:43,744 DEBUG : BROWSER_OUTPUT: RSS: Main: 142245888 21:32:43 INFO - 2015-11-23 21:32:43,744 DEBUG : BROWSER_OUTPUT: 21:35:45 INFO - 2015-11-23 21:35:45,388 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42112/tests/dromaeo/cssquery-mootools.html) 21:35:45 INFO - 2015-11-23 21:35:45,974 DEBUG : BROWSER_OUTPUT: RSS: Main: 143245312 21:35:45 INFO - 2015-11-23 21:35:45,975 DEBUG : BROWSER_OUTPUT: 21:38:47 INFO - 2015-11-23 21:38:47,625 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42112/tests/dromaeo/cssquery-prototype.html) 21:38:48 INFO - 2015-11-23 21:38:48,212 DEBUG : BROWSER_OUTPUT: RSS: Main: 141733888 21:38:48 INFO - 2015-11-23 21:38:48,212 DEBUG : BROWSER_OUTPUT: 21:41:50 INFO - 2015-11-23 21:41:50,573 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42112/tests/dromaeo/cssquery-yui.html) 21:41:51 INFO - 2015-11-23 21:41:51,193 DEBUG : BROWSER_OUTPUT: RSS: Main: 141606912 21:41:51 INFO - 2015-11-23 21:41:51,194 DEBUG : BROWSER_OUTPUT: 21:44:53 INFO - 2015-11-23 21:44:53,745 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/dromaeo/cssquery-yui.html (next: http://localhost:42112/tests/dromaeo/cssquery-dojo.html) 21:44:53 INFO - 2015-11-23 21:44:53,996 DEBUG : BROWSER_OUTPUT: RSS: Main: 144842752 21:44:53 INFO - 2015-11-23 21:44:53,996 DEBUG : BROWSER_OUTPUT: 21:44:53 INFO - 2015-11-23 21:44:53,997 DEBUG : BROWSER_OUTPUT: __start_tp_report 21:44:53 INFO - 2015-11-23 21:44:53,997 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 21:44:53 INFO - 2015-11-23 21:44:53,998 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 21:44:53 INFO - 2015-11-23 21:44:53,998 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 21:44:54 INFO - 2015-11-23 21:44:53,999 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3249.756268279879;3328.66713328667;3341.2649217172216;3355.4631258998556;3379.509971054197;5345.224942383352;5565.805196818113;5573.498385145336;5577.219189313494;5613.38252792193;5523.751431185597;5539.058360078786;5586.972065139668;5588.413216612258;5681.0910254359205;5009;5100.311457953175;5131.871703207413;5153.69077855328;5181.274621552986;4951.77717002734;5118.411382691001;5227.895442091156;5251.842444726664;5293.100172970585;4853.587445067156;4950.133726597867;4967.801287948478;4970.577500912426;4974.1295273327205;5031;5126.461721519261;5136.332276803992;5160.819371321985;5191.896162076775;4849.733264670442;4907.460179380265;5011.548960593527;5098.311727916721;5141.2545180948555;5203.973980130113;5273.630845840793;5286.735663216824;5345.839624811262;5431.619786614939;5938.435848594377;5943.673097979609;5960.165576819249;5964.105384192362;5974.014287642577;5909.438603332676;5985.850353741148;6015.849603759897;6023.397660233942;6028.638281703112;5521.282233309644;5566.4990150886615;5574;5574.9721251393885;5578.023845827005;6032.426919442646;6041.5468839837185;6051.304100028465;6055.333913269536;6060.818175454743;5988.431099045583;5995.310539287951;5995.820125396245;5999.640021598718;6009.308929473079;6028.849278768066;6042.728077236535;6047.0929360596265;6069.484093851984;6076.5442591805795;5765.769369225184;5890.587658863924;5897.616654917417;5960.821175364746;6069.0592958091565;5908.084246941731;5962.791302304398;5982.222311099529;6000.0999850022845;6025.8192254232445;4561.361409402686;4563.201439748097;4565.474970378449;4591.586757191869;4602.7008244464005;4021.6380525752825;4022.4368588397647;4059.512858457004;4075.673946084306;4098.8360465581045;5605.355384130877;5606.1625906813415;5630.183623374588;5636.239107720406;5711.457411545896;5820.330661973842;5926.733297001645;5931.614445061057;5944.464998150187;5958.225430693982;5547.1679248113105;5574.358948720949;5614.129809879475;5626.493615574618;5681.57388195887;5186.740662966912;5208.869778255574;5243.082460569385;5243.606729495227;5245.318108645852;6029;6104.913929256043;6196.380361963767;6223.813285601439;6231.688415579203;5947.31605865331;5965.313988891159;5994.940050599613;6027.60820546663;6071.544634152545;6018.909716354171;6172.629642221481;6181.53638477125;6236.875262494634;6270.498360131087;5644.52021578154;5873.970630146822;5937.970310148422;5956.13636022783;5992.790252341321;6021.307549631849;6034.758609655564;6106.339326076981;6112.26032301701;6131.540134489842;5793.623414478046;5794.362620111869;5804.158397032491;5812.476877081083;5842.912356314574;5782.10377391505;5790.536757059509;5794.304683438015;5829.329627092939;5898.262717160355;5728.455796699307;5745;5764.769409223584;5856.59003869725;5857.765689372377;5905.261842269716;5907.2911250650195;5965.254343207099;5972.5520585955355;5973.342932277533;5672.688002159921;5795.188673585617;5796.333421656564;5810.796622118206;5825.3883342248455 21:44:54 INFO - 2015-11-23 21:44:54,003 DEBUG : BROWSER_OUTPUT: |1;ext.html;548.2145220136487;585.9238299021127;593.8360812806897;600.9218801555797;607.708300015992;39173.41239881404;40563;40765;40853;40932.795336023315;43412.56587434125;43954;43954.34068488975;44099.77950110253;44314.33528497075;8856.911430885673;8940.240079593243;8958.193762561368;8976.820463590724;9035.683751068715;8778.2099611035;8885.02264750877;8912.955435222848;8934.73195804127;8946.373753837233;8766.079561646031;8783.47299162049;8860.8670869937;8889.95555022224;8910.777230569256;8889.511076890769;8904.198622124008;8935.419197752126;8957.910420895838;8970.86543701845;8743.737687869374;8879.28965682744;8899.822003559893;8912.108789121101;8922.821543569156;40262.798686006674;40668.79665601653;40761;40769.38845917313;40830.38754418686;3267.754918381107;3333.6999670029813;3351.1287065362935;3590.8204589770667;3776.206996530732;30764.846175768977;30793.538096928565;30905;30922.690773092207;32082.839585802154;3874.3219936511277;3949.98025009876;4013.8795836124964;4044.312466880637;4107.342825147962;252939;253200;253433;253771.99999999817;254450;55635.72182139063;56402;58792.412075878696;59300.40699593035;60467.39532604706;56606;56763.148552771745;56837.43162568404;57051.14423283654;57095.14357284644;15082.170480623452;15185.62035949088;15188;15219.847801521842;15256.847431525764;11131;11392.25950313227;11396;11426.542938282375;11444.14168937333;20220.89889550543;20533.281335153497;20694.586108277752;20756;20771.376858694264;7496.512726672858;7636.579988100708;7683.769486915401;7703.229677032252;7712.768616941501;919.8252332056887;945.5110641732124;959.8656188133667;973.6981535723806;1043.9873322876797;28340.574891376647;28556.013488197827;28830.279243019093;29019.564706529418;29292.853535732185;1009;1024.897510248984;1040.7658276887792;1116.0680831505783;1153.5212886651946;14047.859521404655;14161.29193540319;15167.620809479851;15662.9216853915;15674.764878526603;311981;318489;320108;321030;321252;52667;52679;52771.47228527742;52802.471975279754;52989.205161922604;1304.804279358104;1308.4308325878274;1308.738252349534;1322.0679421008167;1340.162398500937;28348;29136.125916222547;29311.413771724874;29515.85242073776;29652.25869353197;46537.301940471596;46635.53364466448;46960.29559556541;47101.528984709716;47150.29274560953;288603;291001;291046;291163;291212;31390.52914206243;32012.839935801105;32492.288618149763;32840.50739238961;32853.67146328506;39369;39852.60147398489;40133.1973360537;40454;40611;34363;34398.65601343955;34408.82795586006;34627.826860865534;35225.471617925235;34237;34321;34703.13242168865;35052;35257.64742352646;34847.12882177864;36124;36362.63637363593;36468.81765591155;36631.633683662825;31125.533117003717;31669.841650791597;33056.83471582723;33083;33452.665473344954;24664;25892.35269118287;25923.22230333093;25965.22104336873;26061.348466288495 21:44:54 INFO - 2015-11-23 21:44:54,003 DEBUG : BROWSER_OUTPUT: |2;jquery.html;351.82584620612795;352.31474781549895;353.2370080625847;354.1376747619548;359.13627725320606;16404.671906561878;17180.65638687227;17558.561035974108;18161.545961350974;18498.352557660484;11043;11150.553977840877;11204.607838725646;11774.705632359195;16983.915080424613;4837.951620483803;4916.213405855064;5059.569936555397;5338.332708411449;5374.677519348832;5348.064088784467;5386.218998245252;5395.514403703666;5399.19012148177;5403.243545903577;5112.642115051948;5221.2951251580935;5274.05067087924;5364.597655175858;5374.865628359284;5176.275321454999;5217.426083130871;5228.163493840986;5253.60597955153;5380.811671591476;5206.271122042917;5249.895002099936;5256.106461901448;5260.132078207092;5264.52619264268;11604.245724027996;11773.823392649118;18524.722129168073;19136.904315478332;19139.043047847554;2000.5898790747863;2112.6936594193908;2189.2665956904457;2191.4630915425664;2196.736391632998;8903.643854245822;8906.109389061106;9005.774855628662;9227.169554740105;9368.110029547182;1715.2367196597395;1724.560237139534;1745.467632372126;1835.3667984545311;1889;60814.39185608132;64190;64904;65046;65147.67426162839;10039.849402258971;10141.94929025365;10150.695479135638;10201.795964080677;10207.591696332212;13426.731465370638;13430.529931452353;13468.461261549428;13474.528391506446;13500.392482338319;7630.8855367169535;7667.271609197117;7683.385329173652;7705.345045671181;7711.344535714527;7417.554946703107;7423.62881855905;7434.479586429006;7474.289942455458;7514.699412023567;4232.280512312914;4251.086016506466;4261.87214383569;4267.829286828554;4284.721493102938;1692.6530061337396;1709.1625103699098;1729.8097209306966;1739.443378119015;1807.8101799311146;927.2303987690201;932.1936524906043;944.4710961861242;1013.4527355228241;1051.1118105201092;16283.348666053225;16702.4154154604;17190.914045429694;17406.39077632277;17496.737548936777;925.4401087342061;940.2618944128826;966.6616684160629;976.1556253840376;987.9407235565889;9732.513374331396;10090.243231757648;10095.798084038424;10097.848532272023;10127.848082278771;542326;564555;565730;569726;577810;77306.22693773103;79336.60331698305;79357;80125;81935;1166.7666466706523;1171.8945294923499;1221.2916508425092;1223.5228260978226;1243.098753403775;15238;15298.694026119638;15505.22473876279;15556.377744890078;15594.766078508605;34226.31547368989;35005.299894002485;35053.47419788756;35845.28309433849;37317.626823731414;42568.78715606402;43850.561494384645;47529.52470475251;47874.28188577244;52365.47634523606;9469.47917864524;9778.07108324708;9784.706458806248;9902.356346837432;9903.752406189902;12944.935275323563;17269;17409.651806964044;17711.911440442716;18146.09269536544;12813.359332033175;13436.865631343562;14054.437822487394;14384.712305753617;14973;14487.20320382347;14551.708965820835;14923.925380373463;14929.925350373178;15270.16014119234;15141.864360173251;15181.468648597245;15236.619084522976;15390.692186156439;15571.377144914077;9326.393784403992;9650.903490965;9667.419954802454;9690.176335011603;9937.956514566156;11946.402679866145;13223.272720000492;13850.584482465541;14968.40126394932;14990.250487475801 21:44:54 INFO - 2015-11-23 21:44:54,004 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1493.9701205975887;1689.7380905959576;1748.0647853398427;1752.719564869621;1763.1360633289685;6909.2054413742435;6993.44052475802;7349.265073492648;7751.147373788879;7779.805504862381;7138.464615153858;7189.317014883591;7292.96353518233;7340.596267205302;7348.669309881055;2190.561887622478;2222.544378402432;2241.5628952354296;2248.0895237428786;2249.201533455624;2257.3115199864037;2276.8178545716305;2318.3508617587104;2318.7449380568123;2327.94180145496;2227.0535022615404;2245.7529671736097;2250.1786847800518;2261.728592568894;2285.6342985122383;2185.300703774785;2196.077647388101;2218.500837311608;2229.7658745831695;2232.3414592695276;2266.614675505168;2282.805961493275;2289.347535952246;2293.0942277800177;2298.0348253733473;7580;7719.459637825355;7738.613069346567;7744.419168562324;7754;4437.955620443819;4499.572540608637;4513.052259025609;4519.028408892104;4544.250198717207;3175.5712978747806;3189.4418476766473;3302.3890580242733;3323.135984643986;3331.0839519132433;1110.5058249079163;1129.9661010169707;1133.625903451867;1146.7821113988316;1151.401271338899;10259.230557708202;10284.845727314096;10293.897061029369;10303.742406439824;10332.94833525835;4087.2847251730827;4102.979485102615;4160.771157586361;4168.3955826405;4177.352510360899;8863.423877447945;8864.20222179994;8873.245774109273;8874.822503550022;8875.778105547413;4626.676132670681;4638.257878739386;4668.50980647034;4691.601213896789;4697.342372067913;4129.876103716893;4191.203671302443;4204.894877628022;4216.620504154641;4257.723247988871;3520.806355650464;3523.823808809549;3530.505729197914;3546.6098729139776;3574;1072;1073.0664322039875;1073.1522097543009;1073.2058276875057;1086.8587083679072;1312.1667740984544;1333.2134040915905;1333.686583652841;1367.3710093356967;1373.1074801379184;7259.997602972277;7337.523061001018;7338.046054012944;7338.339549440576;7364.153122390993;441.72833707269797;452.8279253883503;458.85775409623125;458.97475638840183;461.197516321598;5689.800856969986;5708.0581704019205;5717.685527295956;5735.741891614887;5737.3688894221605;8869.866951995597;8889.599968001456;9073.81852362949;9140.862887056699;9183.081691830763;8926.062763409878;8945.686900958566;8997.235235004968;9022.639094436148;9067.727968160965;6615.17310336208;6995.370416662525;6999.965000174967;7003;7070.611116388648;7084.822879428056;7134.678939447634;7140.571565706075;7154.642267886689;7163.64181790898;7792.1428642848105;7984.441089123706;7986.600669966595;8011.839763204586;8114.51312921203;8153.510789352657;8296.958515207385;8315.293200078062;8345.290650294794;8419.98960124789;6866.588004719533;6871.9656401717675;7014.894776578253;7041.894371584328;7056.1179852518435;7509.849803004018;7555.546667199986;7563.9621801890635;7573.545587264782;7576.924230757621;6879.965600171967;6901.1718593769065;6905.75829845953;6929.3070692930305;7153;6868.519203655896;6875.828104297432;6910.585364878123;6948;7137.072180616694;6475.09348691193;6661.666916654245;6674.16572928384;6957.79126626202;6988.825279368057;6529.347065293433;6530.9020364693615;6548.246951600437;6568.343165683394;6589.209294884453;6432.614043157426;6465.482761378984;6475.708593113227;6486.318936511599;6648.966755166193 21:44:54 INFO - 2015-11-23 21:44:54,004 DEBUG : BROWSER_OUTPUT: |4;prototype.html;311.52647975077883;314.2959770114942;321.57873186126176;324.2251020062052;325.07678193929235;6272.341404152561;6308.873822523547;6352.237731472218;6393.712282947267;6399.200099987502;6189.13352130701;6190.81427557174;6220.906686399708;6237.313895471494;6261.561690681654;4644.930326045112;4755.42934847817;4764.047190561884;4813.205821039524;4819.903601927959;4730.97634511827;4741.525847415248;4747.7388743619085;4748.5251474852585;4751.881202969919;4684.484706682262;4707.223308154151;4729.929051064237;4736.573708366246;4737.597304229144;4664.416947881532;4685.953140468586;4725.881852953669;4732.929006064912;4738.265568836836;3949.4075888616935;4086.162336720965;4086.468759061303;4089.0595163112353;4101.507819061731;5947.613405128653;5977.312609049972;5982.790602328898;5996.550258730613;6035.336113027563;5485.862853428656;5501.394846566875;5505.449455054502;5532.944670553283;5538.363088244822;6581.078648989145;6656.068150458939;6685.866282674319;6707.094542236784;6709.664516774142;4513.097380523908;4517.796699148514;4518.390017347681;4520.344550040259;4521.773911304422;6890.104286442781;6965.059716938199;6973.581585104859;6974.163100427981;6976.127984002;4609.53904609543;4614.307853821953;4615.192341340252;4618.51505591908;4626.097910907408;4578.130155270488;4673.7429441381055;4675.7895894684825;4685.9765701171955;4686.297055441711;5424.457554244544;5471.37079235885;5486.835394938158;5502.889942201134;5510.889782204414;6749.6962636681465;6772.0857684212;6782.016607591871;6785.1518560179975;6788.2193547742645;1618.0938674342406;1657.8839481236198;1661.96676066478;1662.2852173565484;1665.9171538500073;1036.1348274190984;1068.8450174724621;1069.262209075751;1069.8020866139789;1098.5386137822134;6213.875722485525;6298.370162983757;6439.581427207217;6503.512236582181;6526.445252153525;6844.315568443116;6911.930880691129;7041.8239544010785;7050.823729406703;7063.611501367474;6534.836629084216;6535.346465353522;6540.247871494839;6542.182227221598;6543.8364040898405;3729.8694545691314;3771.17034252464;3815.04623844039;3823.5602905665646;3837.827297771607;7235.3488186063505;7264.745733899288;7270;7282.162551306562;7286.23494533077;7147.64261786919;7164.677589508485;7175.425965922714;7193.784186474414;7207.783766487014;7027.121609798775;7221;7224.20533741288;7226.92773072273;7238.348548630754;6987.7204911802955;6998.825029374307;7026.8945965809535;7029.297070293134;7030.472714546328;6890.621015844176;6911.3779759821855;6917.4781548059345;6919.861602767815;6941.965290173719;7055.2944705529035;7229.457790665826;7239.819004524929;7241.5293005956555;7269.127704675473;4707.976460117678;4708.764561771829;4723.976380118215;4725.267583524559;4737.455192652889;6938.479614028867;6979.720811167496;6981.720731170696;6982.301769822977;7017.3684368407085;6619.669016549057;6745.5952642841585;6792.830179245559;6800.183977922681;6828.931710682829;6742.93257067423;6796.184457865088;6815.488838337244;6816.659167041727;6823.1812182538415;6567.146270984741;6666.966665166837;6678.198616166092;6684.8997265042035;6686.866262674622;6672.799816005528;6757.831054223684;6842.657867106725;6844.110265665432;6853.040574319699;6582.670866456562;6587.63767992765;6643.302453242533;6667.466602671678;6705.362990515893 21:44:54 INFO - 2015-11-23 21:44:54,005 DEBUG : BROWSER_OUTPUT: |5;yui.html;853.6116067189428;860.7073594977707;864.2135656552539;865.238590040764;882.2236431939892;13505;13698.589042328733;13702.246376449291;13714.65713357164;13793.103448275868;13315.733685326288;13371.732565348688;13482.595522134327;13487.865121348783;13502.527411540597;803.9155888631711;826.5536610230469;828.4408024583382;828.9171082891693;834.0658462521984;828.159418190537;829.6308142876422;830.3979614779297;830.9418340716152;839.4291881520563;872.5768002518773;878.3895192840978;878.5431575580694;878.9208971192623;881.0528681667233;861.956902154896;868.1492137705085;868.700298397052;871.6382701179003;872.6422166911567;635.6059243269157;637.1684951138762;639.7441023590554;641.2945759664378;643.8261669349249;13907.860921390758;13936.233507157101;13995.020348575605;14005.509807156803;14011.579652610337;12237.755244895052;12286.815697764541;12364.690882727913;12452.564160254347;12553.184043037174;10315.43265120418;10432.426216558086;10575.524101415454;10723.678289651323;10732.034116929435;3613.834801805917;3692.1877187018813;3703.9074023149637;3722.683571896365;3726.776393416377;44370.33444498335;44606.553934460564;44720.552794471965;44738.55261447409;44815.32777008347;9808.117269445784;10058.245631577662;10074.748131296776;10262.743431414125;10297.74255643615;18022.189001494964;18022.90988545075;18117.36589219371;18141.274349026156;18146.274149034154;7610.505317154367;7672.61636918163;7681.807954801063;7682.769516914502;7696.384289256846;4224.5353011168745;4255.5957184067465;4420.911581768347;4435.6673249505775;4438.755868427267;3085.506318988951;3107.0523490335213;3116.6415862175686;3116.9402403183026;3124.1564777509943;570.1903297317818;582.3361368040437;583.2126629050748;583.5040215816607;590.4774274766862;566.4760096910342;577.5206578539802;585.3414908228243;670.5004771445305;703.1070540413646;8041.396895232764;8153.959230203811;8639.95680021596;8712.346574006975;9021.594028268744;547.17923115327;564.9548036157098;587.6092398554998;603.285107148031;649.7238673563717;4154.771487568152;4247.447831781848;4371.366151907955;4404.207242696345;4471.334539741923;56135.4484259058;56995;57861;58783.706081470176;58956.11565826516;52504.47495525072;52653.210201847;53487.46512534903;53868.730656346466;54000.45999539954;44095.55904440978;44526.77736611296;45015.77492112519;45484.31773523334;48150;8538.829223415622;8666.263367613823;9235.399699019543;9351;9389;35733.46399804057;36005;36206.275874481835;36412.635873640924;37085.814570926974;42314.36528452014;44399;46155;46199;46318;11010.50452729613;11109.611163609236;11199.440027998404;11237.213395062266;11255.324680519196;12344.382780860742;12466.563670271496;12662.556810511587;12669.873301266869;13157.60527184186;10918.597908460126;10937.070349020423;10977.83533246986;11729.941350293195;11901.821472677744;11119.555217791198;11355.034822039888;11599.304041757521;11662.53349865996;11664.708382290171;10995.780084398428;11020.724481888017;11118.49966751482;11270.436478176221;11278.718032048937;9899.554520046471;9972.152367048882;10081.495925203857;10099.444530550596;10320.48397580133;9176.35765496409;9416.670416535388;9506.239500840054;9798.706038818847;10039 21:44:54 INFO - 2015-11-23 21:44:54,005 DEBUG : BROWSER_OUTPUT: __end_tp_report 21:44:54 INFO - 2015-11-23 21:44:54,005 DEBUG : BROWSER_OUTPUT: __start_cc_report 21:44:54 INFO - 2015-11-23 21:44:54,006 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,314 21:44:54 INFO - 2015-11-23 21:44:54,006 DEBUG : BROWSER_OUTPUT: __end_cc_report 21:44:54 INFO - 2015-11-23 21:44:54,006 DEBUG : BROWSER_OUTPUT: __startTimestamp1448343893996__endTimestamp 21:44:54 INFO - 2015-11-23 21:44:54,006 DEBUG : BROWSER_OUTPUT: 21:44:54 INFO - 2015-11-23 21:44:54,006 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 21:44:54 INFO - 2015-11-23 21:44:54,006 DEBUG : BROWSER_OUTPUT: Number of tests: 6 21:44:54 INFO - 2015-11-23 21:44:54,006 DEBUG : BROWSER_OUTPUT: 21:44:54 INFO - 2015-11-23 21:44:54,006 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5547.91 Median:5790.54 stddev:626.07 (10.8%) stddev-sans-first:601.47 21:44:54 INFO - 2015-11-23 21:44:54,006 DEBUG : BROWSER_OUTPUT: Values: 3249.8 3328.7 3341.3 3355.5 3379.5 5345.2 5565.8 5573.5 5577.2 5613.4 5523.8 5539.1 5587.0 5588.4 5681.1 5009.0 5100.3 5131.9 5153.7 5181.3 4951.8 5118.4 5227.9 5251.8 5293.1 4853.6 4950.1 4967.8 4970.6 4974.1 5031.0 5126.5 5136.3 5160.8 5191.9 4849.7 4907.5 5011.5 5098.3 5141.3 5204.0 5273.6 5286.7 5345.8 5431.6 5938.4 5943.7 5960.2 5964.1 5974.0 5909.4 5985.9 6015.8 6023.4 6028.6 5521.3 5566.5 5574.0 5575.0 5578.0 6032.4 6041.5 6051.3 6055.3 6060.8 5988.4 5995.3 5995.8 5999.6 6009.3 6028.8 6042.7 6047.1 6069.5 6076.5 5765.8 5890.6 5897.6 5960.8 6069.1 5908.1 5962.8 5982.2 6000.1 6025.8 4561.4 4563.2 4565.5 4591.6 4602.7 4021.6 4022.4 4059.5 4075.7 4098.8 5605.4 5606.2 5630.2 5636.2 5711.5 5820.3 5926.7 5931.6 5944.5 5958.2 5547.2 5574.4 5614.1 5626.5 5681.6 5186.7 5208.9 5243.1 5243.6 5245.3 6029.0 6104.9 6196.4 6223.8 6231.7 5947.3 5965.3 5994.9 6027.6 6071.5 6018.9 6172.6 6181.5 6236.9 6270.5 5644.5 5874.0 5938.0 5956.1 5992.8 6021.3 6034.8 6106.3 6112.3 6131.5 5793.6 5794.4 5804.2 5812.5 5842.9 5782.1 5790.5 5794.3 5829.3 5898.3 5728.5 5745.0 5764.8 5856.6 5857.8 5905.3 5907.3 5965.3 5972.6 5973.3 5672.7 5795.2 5796.3 5810.8 5825.4 21:44:54 INFO - 2015-11-23 21:44:54,006 DEBUG : BROWSER_OUTPUT: 21:44:54 INFO - 2015-11-23 21:44:54,006 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:45920.88 Median:19857.41 stddev:75370.30 (379.6%) stddev-sans-first:75504.75 21:44:54 INFO - 2015-11-23 21:44:54,008 DEBUG : BROWSER_OUTPUT: Values: 548.2 585.9 593.8 600.9 607.7 39173.4 40563.0 40765.0 40853.0 40932.8 43412.6 43954.0 43954.3 44099.8 44314.3 8856.9 8940.2 8958.2 8976.8 9035.7 8778.2 8885.0 8913.0 8934.7 8946.4 8766.1 8783.5 8860.9 8890.0 8910.8 8889.5 8904.2 8935.4 8957.9 8970.9 8743.7 8879.3 8899.8 8912.1 8922.8 40262.8 40668.8 40761.0 40769.4 40830.4 3267.8 3333.7 3351.1 3590.8 3776.2 30764.8 30793.5 30905.0 30922.7 32082.8 3874.3 3950.0 4013.9 4044.3 4107.3 252939.0 253200.0 253433.0 253772.0 254450.0 55635.7 56402.0 58792.4 59300.4 60467.4 56606.0 56763.1 56837.4 57051.1 57095.1 15082.2 15185.6 15188.0 15219.8 15256.8 11131.0 11392.3 11396.0 11426.5 11444.1 20220.9 20533.3 20694.6 20756.0 20771.4 7496.5 7636.6 7683.8 7703.2 7712.8 919.8 945.5 959.9 973.7 1044.0 28340.6 28556.0 28830.3 29019.6 29292.9 1009.0 1024.9 1040.8 1116.1 1153.5 14047.9 14161.3 15167.6 15662.9 15674.8 311981.0 318489.0 320108.0 321030.0 321252.0 52667.0 52679.0 52771.5 52802.5 52989.2 1304.8 1308.4 1308.7 1322.1 1340.2 28348.0 29136.1 29311.4 29515.9 29652.3 46537.3 46635.5 46960.3 47101.5 47150.3 288603.0 291001.0 291046.0 291163.0 291212.0 31390.5 32012.8 32492.3 32840.5 32853.7 39369.0 39852.6 40133.2 40454.0 40611.0 34363.0 34398.7 34408.8 34627.8 35225.5 34237.0 34321.0 34703.1 35052.0 35257.6 34847.1 36124.0 36362.6 36468.8 36631.6 31125.5 31669.8 33056.8 33083.0 33452.7 24664.0 25892.4 25923.2 25965.2 26061.3 21:44:54 INFO - 2015-11-23 21:44:54,008 DEBUG : BROWSER_OUTPUT: 21:44:54 INFO - 2015-11-23 21:44:54,008 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:29443.76 Median:352.78 stddev:92195.02 (26134.2%) stddev-sans-first:92427.77 21:44:54 INFO - 2015-11-23 21:44:54,009 DEBUG : BROWSER_OUTPUT: Values: 351.8 352.3 353.2 354.1 359.1 16404.7 17180.7 17558.6 18161.5 18498.4 11043.0 11150.6 11204.6 11774.7 16983.9 4838.0 4916.2 5059.6 5338.3 5374.7 5348.1 5386.2 5395.5 5399.2 5403.2 5112.6 5221.3 5274.1 5364.6 5374.9 5176.3 5217.4 5228.2 5253.6 5380.8 5206.3 5249.9 5256.1 5260.1 5264.5 11604.2 11773.8 18524.7 19136.9 19139.0 2000.6 2112.7 2189.3 2191.5 2196.7 8903.6 8906.1 9005.8 9227.2 9368.1 1715.2 1724.6 1745.5 1835.4 1889.0 60814.4 64190.0 64904.0 65046.0 65147.7 10039.8 10141.9 10150.7 10201.8 10207.6 13426.7 13430.5 13468.5 13474.5 13500.4 7630.9 7667.3 7683.4 7705.3 7711.3 7417.6 7423.6 7434.5 7474.3 7514.7 4232.3 4251.1 4261.9 4267.8 4284.7 1692.7 1709.2 1729.8 1739.4 1807.8 927.2 932.2 944.5 1013.5 1051.1 16283.3 16702.4 17190.9 17406.4 17496.7 925.4 940.3 966.7 976.2 987.9 9732.5 10090.2 10095.8 10097.8 10127.8 542326.0 564555.0 565730.0 569726.0 577810.0 77306.2 79336.6 79357.0 80125.0 81935.0 1166.8 1171.9 1221.3 1223.5 1243.1 15238.0 15298.7 15505.2 15556.4 15594.8 34226.3 35005.3 35053.5 35845.3 37317.6 42568.8 43850.6 47529.5 47874.3 52365.5 9469.5 9778.1 9784.7 9902.4 9903.8 12944.9 17269.0 17409.7 17711.9 18146.1 12813.4 13436.9 14054.4 14384.7 14973.0 14487.2 14551.7 14923.9 14929.9 15270.2 15141.9 15181.5 15236.6 15390.7 15571.4 9326.4 9650.9 9667.4 9690.2 9938.0 11946.4 13223.3 13850.6 14968.4 14990.3 21:44:54 INFO - 2015-11-23 21:44:54,009 DEBUG : BROWSER_OUTPUT: 21:44:54 INFO - 2015-11-23 21:44:54,009 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5345.61 Median:6449.05 stddev:2740.50 (42.5%) stddev-sans-first:2732.90 21:44:54 INFO - 2015-11-23 21:44:54,010 DEBUG : BROWSER_OUTPUT: Values: 1494.0 1689.7 1748.1 1752.7 1763.1 6909.2 6993.4 7349.3 7751.1 7779.8 7138.5 7189.3 7293.0 7340.6 7348.7 2190.6 2222.5 2241.6 2248.1 2249.2 2257.3 2276.8 2318.4 2318.7 2327.9 2227.1 2245.8 2250.2 2261.7 2285.6 2185.3 2196.1 2218.5 2229.8 2232.3 2266.6 2282.8 2289.3 2293.1 2298.0 7580.0 7719.5 7738.6 7744.4 7754.0 4438.0 4499.6 4513.1 4519.0 4544.3 3175.6 3189.4 3302.4 3323.1 3331.1 1110.5 1130.0 1133.6 1146.8 1151.4 10259.2 10284.8 10293.9 10303.7 10332.9 4087.3 4103.0 4160.8 4168.4 4177.4 8863.4 8864.2 8873.2 8874.8 8875.8 4626.7 4638.3 4668.5 4691.6 4697.3 4129.9 4191.2 4204.9 4216.6 4257.7 3520.8 3523.8 3530.5 3546.6 3574.0 1072.0 1073.1 1073.2 1073.2 1086.9 1312.2 1333.2 1333.7 1367.4 1373.1 7260.0 7337.5 7338.0 7338.3 7364.2 441.7 452.8 458.9 459.0 461.2 5689.8 5708.1 5717.7 5735.7 5737.4 8869.9 8889.6 9073.8 9140.9 9183.1 8926.1 8945.7 8997.2 9022.6 9067.7 6615.2 6995.4 7000.0 7003.0 7070.6 7084.8 7134.7 7140.6 7154.6 7163.6 7792.1 7984.4 7986.6 8011.8 8114.5 8153.5 8297.0 8315.3 8345.3 8420.0 6866.6 6872.0 7014.9 7041.9 7056.1 7509.8 7555.5 7564.0 7573.5 7576.9 6880.0 6901.2 6905.8 6929.3 7153.0 6868.5 6875.8 6910.6 6948.0 7137.1 6475.1 6661.7 6674.2 6957.8 6988.8 6529.3 6530.9 6548.2 6568.3 6589.2 6432.6 6465.5 6475.7 6486.3 6649.0 21:44:54 INFO - 2015-11-23 21:44:54,010 DEBUG : BROWSER_OUTPUT: 21:44:54 INFO - 2015-11-23 21:44:54,010 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5600.14 Median:6396.46 stddev:1735.27 (27.1%) stddev-sans-first:1694.13 21:44:54 INFO - 2015-11-23 21:44:54,011 DEBUG : BROWSER_OUTPUT: Values: 311.5 314.3 321.6 324.2 325.1 6272.3 6308.9 6352.2 6393.7 6399.2 6189.1 6190.8 6220.9 6237.3 6261.6 4644.9 4755.4 4764.0 4813.2 4819.9 4731.0 4741.5 4747.7 4748.5 4751.9 4684.5 4707.2 4729.9 4736.6 4737.6 4664.4 4686.0 4725.9 4732.9 4738.3 3949.4 4086.2 4086.5 4089.1 4101.5 5947.6 5977.3 5982.8 5996.6 6035.3 5485.9 5501.4 5505.4 5532.9 5538.4 6581.1 6656.1 6685.9 6707.1 6709.7 4513.1 4517.8 4518.4 4520.3 4521.8 6890.1 6965.1 6973.6 6974.2 6976.1 4609.5 4614.3 4615.2 4618.5 4626.1 4578.1 4673.7 4675.8 4686.0 4686.3 5424.5 5471.4 5486.8 5502.9 5510.9 6749.7 6772.1 6782.0 6785.2 6788.2 1618.1 1657.9 1662.0 1662.3 1665.9 1036.1 1068.8 1069.3 1069.8 1098.5 6213.9 6298.4 6439.6 6503.5 6526.4 6844.3 6911.9 7041.8 7050.8 7063.6 6534.8 6535.3 6540.2 6542.2 6543.8 3729.9 3771.2 3815.0 3823.6 3837.8 7235.3 7264.7 7270.0 7282.2 7286.2 7147.6 7164.7 7175.4 7193.8 7207.8 7027.1 7221.0 7224.2 7226.9 7238.3 6987.7 6998.8 7026.9 7029.3 7030.5 6890.6 6911.4 6917.5 6919.9 6942.0 7055.3 7229.5 7239.8 7241.5 7269.1 4708.0 4708.8 4724.0 4725.3 4737.5 6938.5 6979.7 6981.7 6982.3 7017.4 6619.7 6745.6 6792.8 6800.2 6828.9 6742.9 6796.2 6815.5 6816.7 6823.2 6567.1 6667.0 6678.2 6684.9 6686.9 6672.8 6757.8 6842.7 6844.1 6853.0 6582.7 6587.6 6643.3 6667.5 6705.4 21:44:54 INFO - 2015-11-23 21:44:54,011 DEBUG : BROWSER_OUTPUT: 21:44:54 INFO - 2015-11-23 21:44:54,011 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13875.88 Median:44462.89 stddev:15906.05 (35.8%) stddev-sans-first:15920.60 21:44:54 INFO - 2015-11-23 21:44:54,012 DEBUG : BROWSER_OUTPUT: Values: 853.6 860.7 864.2 865.2 882.2 13505.0 13698.6 13702.2 13714.7 13793.1 13315.7 13371.7 13482.6 13487.9 13502.5 803.9 826.6 828.4 828.9 834.1 828.2 829.6 830.4 830.9 839.4 872.6 878.4 878.5 878.9 881.1 862.0 868.1 868.7 871.6 872.6 635.6 637.2 639.7 641.3 643.8 13907.9 13936.2 13995.0 14005.5 14011.6 12237.8 12286.8 12364.7 12452.6 12553.2 10315.4 10432.4 10575.5 10723.7 10732.0 3613.8 3692.2 3703.9 3722.7 3726.8 44370.3 44606.6 44720.6 44738.6 44815.3 9808.1 10058.2 10074.7 10262.7 10297.7 18022.2 18022.9 18117.4 18141.3 18146.3 7610.5 7672.6 7681.8 7682.8 7696.4 4224.5 4255.6 4420.9 4435.7 4438.8 3085.5 3107.1 3116.6 3116.9 3124.2 570.2 582.3 583.2 583.5 590.5 566.5 577.5 585.3 670.5 703.1 8041.4 8154.0 8640.0 8712.3 9021.6 547.2 565.0 587.6 603.3 649.7 4154.8 4247.4 4371.4 4404.2 4471.3 56135.4 56995.0 57861.0 58783.7 58956.1 52504.5 52653.2 53487.5 53868.7 54000.5 44095.6 44526.8 45015.8 45484.3 48150.0 8538.8 8666.3 9235.4 9351.0 9389.0 35733.5 36005.0 36206.3 36412.6 37085.8 42314.4 44399.0 46155.0 46199.0 46318.0 11010.5 11109.6 11199.4 11237.2 11255.3 12344.4 12466.6 12662.6 12669.9 13157.6 10918.6 10937.1 10977.8 11729.9 11901.8 11119.6 11355.0 11599.3 11662.5 11664.7 10995.8 11020.7 11118.5 11270.4 11278.7 9899.6 9972.2 10081.5 10099.4 10320.5 9176.4 9416.7 9506.2 9798.7 10039.0 21:44:54 INFO - 2015-11-23 21:44:54,012 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 21:44:54 INFO - 2015-11-23 21:44:54,012 DEBUG : BROWSER_OUTPUT: 21:44:54 INFO - 2015-11-23 21:44:54,694 INFO : Browser exited with error code: 0 21:44:54 INFO - 2015-11-23 21:44:54,717 INFO : Completed test dromaeo_css (00:18:32) 21:44:54 INFO - 2015-11-23 21:44:54,718 INFO : Starting test kraken 21:44:54 INFO - 2015-11-23 21:44:54,718 DEBUG : operating with platform_type : linux_ 21:44:54 INFO - 2015-11-23 21:44:54,718 INFO : Initialising browser for kraken test... 21:44:54 INFO - 2015-11-23 21:44:54,724 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzQHaDO/profile http://localhost:42112/getInfo.html 21:44:59 INFO - 2015-11-23 21:44:59,550 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 21:44:59 INFO - 2015-11-23 21:44:59,550 DEBUG : BROWSER_OUTPUT: colorDepth:24 21:44:59 INFO - 2015-11-23 21:44:59,550 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 21:44:59 INFO - 2015-11-23 21:44:59,550 DEBUG : BROWSER_OUTPUT: __metrics 21:45:00 INFO - 2015-11-23 21:45:00,598 INFO : Browser initialized. 21:45:00 INFO - 2015-11-23 21:45:00,598 INFO : Running cycle 1/1 for kraken test... 21:45:00 INFO - 2015-11-23 21:45:00,598 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpzQHaDO/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 21:45:12 INFO - 2015-11-23 21:45:12,359 DEBUG : BROWSER_OUTPUT: RSS: Main: 139960320 21:45:12 INFO - 2015-11-23 21:45:12,359 DEBUG : BROWSER_OUTPUT: 21:45:14 INFO - 2015-11-23 21:45:14,835 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42112/tests/kraken/driver.html?testName=audio-beat-detection) 21:45:15 INFO - 2015-11-23 21:45:15,437 DEBUG : BROWSER_OUTPUT: RSS: Main: 142520320 21:45:15 INFO - 2015-11-23 21:45:15,437 DEBUG : BROWSER_OUTPUT: 21:45:18 INFO - 2015-11-23 21:45:18,087 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42112/tests/kraken/driver.html?testName=audio-dft) 21:45:18 INFO - 2015-11-23 21:45:18,676 DEBUG : BROWSER_OUTPUT: RSS: Main: 142114816 21:45:18 INFO - 2015-11-23 21:45:18,677 DEBUG : BROWSER_OUTPUT: 21:45:21 INFO - 2015-11-23 21:45:21,556 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42112/tests/kraken/driver.html?testName=audio-fft) 21:45:22 INFO - 2015-11-23 21:45:22,137 DEBUG : BROWSER_OUTPUT: RSS: Main: 137404416 21:45:22 INFO - 2015-11-23 21:45:22,137 DEBUG : BROWSER_OUTPUT: 21:45:24 INFO - 2015-11-23 21:45:24,122 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42112/tests/kraken/driver.html?testName=audio-oscillator) 21:45:24 INFO - 2015-11-23 21:45:24,744 DEBUG : BROWSER_OUTPUT: RSS: Main: 137609216 21:45:24 INFO - 2015-11-23 21:45:24,744 DEBUG : BROWSER_OUTPUT: 21:45:26 INFO - 2015-11-23 21:45:26,963 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42112/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:45:27 INFO - 2015-11-23 21:45:27,544 DEBUG : BROWSER_OUTPUT: RSS: Main: 139325440 21:45:27 INFO - 2015-11-23 21:45:27,545 DEBUG : BROWSER_OUTPUT: 21:45:30 INFO - 2015-11-23 21:45:30,610 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42112/tests/kraken/driver.html?testName=imaging-darkroom) 21:45:31 INFO - 2015-11-23 21:45:31,196 DEBUG : BROWSER_OUTPUT: RSS: Main: 140152832 21:45:31 INFO - 2015-11-23 21:45:31,196 DEBUG : BROWSER_OUTPUT: 21:45:34 INFO - 2015-11-23 21:45:34,619 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42112/tests/kraken/driver.html?testName=imaging-desaturate) 21:45:35 INFO - 2015-11-23 21:45:35,205 DEBUG : BROWSER_OUTPUT: RSS: Main: 140140544 21:45:35 INFO - 2015-11-23 21:45:35,205 DEBUG : BROWSER_OUTPUT: 21:45:38 INFO - 2015-11-23 21:45:38,064 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42112/tests/kraken/driver.html?testName=json-parse-financial) 21:45:38 INFO - 2015-11-23 21:45:38,657 DEBUG : BROWSER_OUTPUT: RSS: Main: 140255232 21:45:38 INFO - 2015-11-23 21:45:38,657 DEBUG : BROWSER_OUTPUT: 21:45:40 INFO - 2015-11-23 21:45:40,257 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42112/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:45:40 INFO - 2015-11-23 21:45:40,826 DEBUG : BROWSER_OUTPUT: RSS: Main: 139390976 21:45:40 INFO - 2015-11-23 21:45:40,826 DEBUG : BROWSER_OUTPUT: 21:45:42 INFO - 2015-11-23 21:45:42,657 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42112/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:45:43 INFO - 2015-11-23 21:45:43,265 DEBUG : BROWSER_OUTPUT: RSS: Main: 139350016 21:45:43 INFO - 2015-11-23 21:45:43,265 DEBUG : BROWSER_OUTPUT: 21:45:45 INFO - 2015-11-23 21:45:45,377 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42112/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:45:45 INFO - 2015-11-23 21:45:45,959 DEBUG : BROWSER_OUTPUT: RSS: Main: 140726272 21:45:45 INFO - 2015-11-23 21:45:45,959 DEBUG : BROWSER_OUTPUT: 21:45:48 INFO - 2015-11-23 21:45:48,426 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42112/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:45:49 INFO - 2015-11-23 21:45:49,026 DEBUG : BROWSER_OUTPUT: RSS: Main: 139800576 21:45:49 INFO - 2015-11-23 21:45:49,026 DEBUG : BROWSER_OUTPUT: 21:45:51 INFO - 2015-11-23 21:45:51,602 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42112/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:45:52 INFO - 2015-11-23 21:45:52,183 DEBUG : BROWSER_OUTPUT: RSS: Main: 140816384 21:45:52 INFO - 2015-11-23 21:45:52,183 DEBUG : BROWSER_OUTPUT: 21:45:53 INFO - 2015-11-23 21:45:53,865 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42112/tests/kraken/driver.html?testName=ai-astar) 21:45:54 INFO - 2015-11-23 21:45:54,123 DEBUG : BROWSER_OUTPUT: RSS: Main: 140832768 21:45:54 INFO - 2015-11-23 21:45:54,123 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,123 DEBUG : BROWSER_OUTPUT: __start_tp_report 21:45:54 INFO - 2015-11-23 21:45:54,123 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 21:45:54 INFO - 2015-11-23 21:45:54,123 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 21:45:54 INFO - 2015-11-23 21:45:54,123 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 21:45:54 INFO - 2015-11-23 21:45:54,123 DEBUG : BROWSER_OUTPUT: |0;ai-astar;110;105;110;110;112;122;110;110;109;110 21:45:54 INFO - 2015-11-23 21:45:54,123 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;156;126;151;169;126;149;146;127;171;127 21:45:54 INFO - 2015-11-23 21:45:54,124 DEBUG : BROWSER_OUTPUT: |2;audio-dft;162;164;162;163;163;164;164;163;172;163 21:45:54 INFO - 2015-11-23 21:45:54,124 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;73;72;72;71;71;155;73;72;71 21:45:54 INFO - 2015-11-23 21:45:54,124 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;94;139;94;94;93;146;94;95;131 21:45:54 INFO - 2015-11-23 21:45:54,124 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;111;110;110;110;110;110;110;110;110 21:45:54 INFO - 2015-11-23 21:45:54,124 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;146;146;147;147;147;146;147;146;147 21:45:54 INFO - 2015-11-23 21:45:54,124 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;97;96;96;97;97;96;96;97;96 21:45:54 INFO - 2015-11-23 21:45:54,124 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;62;62;65;61;61;62;61;65;63;61 21:45:54 INFO - 2015-11-23 21:45:54,125 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;69;68;69;69;70;70;69;69;69 21:45:54 INFO - 2015-11-23 21:45:54,125 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;84;96;88;86;86;84;88;87;87;84 21:45:54 INFO - 2015-11-23 21:45:54,125 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;118;126;121;122;118;127;129;136;121;127 21:45:54 INFO - 2015-11-23 21:45:54,125 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;143;149;139;153;151;147;151;142;154;158 21:45:54 INFO - 2015-11-23 21:45:54,125 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;68;66;68;67;68;66;66;66;70;70 21:45:54 INFO - 2015-11-23 21:45:54,125 DEBUG : BROWSER_OUTPUT: __end_tp_report 21:45:54 INFO - 2015-11-23 21:45:54,125 DEBUG : BROWSER_OUTPUT: __start_cc_report 21:45:54 INFO - 2015-11-23 21:45:54,125 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,707 21:45:54 INFO - 2015-11-23 21:45:54,125 DEBUG : BROWSER_OUTPUT: __end_cc_report 21:45:54 INFO - 2015-11-23 21:45:54,126 DEBUG : BROWSER_OUTPUT: __startTimestamp1448343954118__endTimestamp 21:45:54 INFO - 2015-11-23 21:45:54,126 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,127 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 21:45:54 INFO - 2015-11-23 21:45:54,127 DEBUG : BROWSER_OUTPUT: Number of tests: 14 21:45:54 INFO - 2015-11-23 21:45:54,128 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,128 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:110.80 Median:110.00 stddev:4.32 (3.9%) stddev-sans-first:4.57 21:45:54 INFO - 2015-11-23 21:45:54,128 DEBUG : BROWSER_OUTPUT: Values: 110.0 105.0 110.0 110.0 112.0 122.0 110.0 110.0 109.0 110.0 21:45:54 INFO - 2015-11-23 21:45:54,128 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,129 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.80 Median:150.00 stddev:17.62 (11.7%) stddev-sans-first:18.22 21:45:54 INFO - 2015-11-23 21:45:54,129 DEBUG : BROWSER_OUTPUT: Values: 156.0 126.0 151.0 169.0 126.0 149.0 146.0 127.0 171.0 127.0 21:45:54 INFO - 2015-11-23 21:45:54,129 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,130 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.00 Median:163.50 stddev:2.91 (1.8%) stddev-sans-first:2.99 21:45:54 INFO - 2015-11-23 21:45:54,130 DEBUG : BROWSER_OUTPUT: Values: 162.0 164.0 162.0 163.0 163.0 164.0 164.0 163.0 172.0 163.0 21:45:54 INFO - 2015-11-23 21:45:54,130 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,130 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:80.10 Median:72.00 stddev:26.33 (36.6%) stddev-sans-first:27.72 21:45:54 INFO - 2015-11-23 21:45:54,130 DEBUG : BROWSER_OUTPUT: Values: 71.0 73.0 72.0 72.0 71.0 71.0 155.0 73.0 72.0 71.0 21:45:54 INFO - 2015-11-23 21:45:54,131 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,131 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.30 Median:94.00 stddev:21.94 (23.3%) stddev-sans-first:22.65 21:45:54 INFO - 2015-11-23 21:45:54,131 DEBUG : BROWSER_OUTPUT: Values: 93.0 94.0 139.0 94.0 94.0 93.0 146.0 94.0 95.0 131.0 21:45:54 INFO - 2015-11-23 21:45:54,131 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,131 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.10 Median:110.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 21:45:54 INFO - 2015-11-23 21:45:54,131 DEBUG : BROWSER_OUTPUT: Values: 110.0 111.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 110.0 21:45:54 INFO - 2015-11-23 21:45:54,132 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,132 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 21:45:54 INFO - 2015-11-23 21:45:54,132 DEBUG : BROWSER_OUTPUT: Values: 147.0 146.0 146.0 147.0 147.0 147.0 146.0 147.0 146.0 147.0 21:45:54 INFO - 2015-11-23 21:45:54,132 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,132 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 21:45:54 INFO - 2015-11-23 21:45:54,132 DEBUG : BROWSER_OUTPUT: Values: 96.0 97.0 96.0 96.0 97.0 97.0 96.0 96.0 97.0 96.0 21:45:54 INFO - 2015-11-23 21:45:54,132 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,133 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:62.30 Median:62.00 stddev:1.57 (2.5%) stddev-sans-first:1.66 21:45:54 INFO - 2015-11-23 21:45:54,133 DEBUG : BROWSER_OUTPUT: Values: 62.0 62.0 65.0 61.0 61.0 62.0 61.0 65.0 63.0 61.0 21:45:54 INFO - 2015-11-23 21:45:54,133 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,133 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:69.00 stddev:0.57 (0.8%) stddev-sans-first:0.60 21:45:54 INFO - 2015-11-23 21:45:54,133 DEBUG : BROWSER_OUTPUT: Values: 69.0 69.0 68.0 69.0 69.0 70.0 70.0 69.0 69.0 69.0 21:45:54 INFO - 2015-11-23 21:45:54,133 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,133 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:87.00 stddev:3.53 (4.1%) stddev-sans-first:3.57 21:45:54 INFO - 2015-11-23 21:45:54,133 DEBUG : BROWSER_OUTPUT: Values: 84.0 96.0 88.0 86.0 86.0 84.0 88.0 87.0 87.0 84.0 21:45:54 INFO - 2015-11-23 21:45:54,133 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,133 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:124.50 Median:126.50 stddev:5.60 (4.4%) stddev-sans-first:5.43 21:45:54 INFO - 2015-11-23 21:45:54,134 DEBUG : BROWSER_OUTPUT: Values: 118.0 126.0 121.0 122.0 118.0 127.0 129.0 136.0 121.0 127.0 21:45:54 INFO - 2015-11-23 21:45:54,134 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,134 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:148.70 Median:151.00 stddev:5.95 (3.9%) stddev-sans-first:5.94 21:45:54 INFO - 2015-11-23 21:45:54,134 DEBUG : BROWSER_OUTPUT: Values: 143.0 149.0 139.0 153.0 151.0 147.0 151.0 142.0 154.0 158.0 21:45:54 INFO - 2015-11-23 21:45:54,134 DEBUG : BROWSER_OUTPUT: 21:45:54 INFO - 2015-11-23 21:45:54,134 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.58 (2.3%) stddev-sans-first:1.67 21:45:54 INFO - 2015-11-23 21:45:54,134 DEBUG : BROWSER_OUTPUT: Values: 68.0 66.0 68.0 67.0 68.0 66.0 66.0 66.0 70.0 70.0 21:45:54 INFO - 2015-11-23 21:45:54,134 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 21:45:54 INFO - 2015-11-23 21:45:54,134 DEBUG : BROWSER_OUTPUT: 21:45:55 INFO - 2015-11-23 21:45:55,034 INFO : Browser exited with error code: 0 21:45:55 INFO - 2015-11-23 21:45:55,084 INFO : Completed test kraken (00:01:00) 21:45:55 INFO - 2015-11-23 21:45:55,085 INFO : Starting test v8_7 21:45:55 INFO - 2015-11-23 21:45:55,085 DEBUG : operating with platform_type : linux_ 21:45:55 INFO - 2015-11-23 21:45:55,085 INFO : Initialising browser for v8_7 test... 21:45:55 INFO - 2015-11-23 21:45:55,091 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpk_8ZMi/profile http://localhost:42112/getInfo.html 21:45:59 INFO - 2015-11-23 21:45:59,697 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 21:45:59 INFO - 2015-11-23 21:45:59,697 DEBUG : BROWSER_OUTPUT: colorDepth:24 21:45:59 INFO - 2015-11-23 21:45:59,697 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 21:45:59 INFO - 2015-11-23 21:45:59,697 DEBUG : BROWSER_OUTPUT: __metrics 21:46:00 INFO - 2015-11-23 21:46:00,810 INFO : Browser initialized. 21:46:00 INFO - 2015-11-23 21:46:00,810 INFO : Running cycle 1/1 for v8_7 test... 21:46:00 INFO - 2015-11-23 21:46:00,810 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpk_8ZMi/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 21:46:12 INFO - 2015-11-23 21:46:12,632 DEBUG : BROWSER_OUTPUT: RSS: Main: 142114816 21:46:12 INFO - 2015-11-23 21:46:12,632 DEBUG : BROWSER_OUTPUT: 21:46:34 INFO - 2015-11-23 21:46:34,039 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:42112/tests/v8_7/run.html (next: http://localhost:42112/tests/v8_7/run.html) 21:46:34 INFO - 2015-11-23 21:46:34,296 DEBUG : BROWSER_OUTPUT: RSS: Main: 139116544 21:46:34 INFO - 2015-11-23 21:46:34,297 DEBUG : BROWSER_OUTPUT: 21:46:34 INFO - 2015-11-23 21:46:34,297 DEBUG : BROWSER_OUTPUT: __start_tp_report 21:46:34 INFO - 2015-11-23 21:46:34,297 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 21:46:34 INFO - 2015-11-23 21:46:34,297 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 21:46:34 INFO - 2015-11-23 21:46:34,297 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 21:46:34 INFO - 2015-11-23 21:46:34,297 DEBUG : BROWSER_OUTPUT: |0;Richards;186.42803877703207 21:46:34 INFO - 2015-11-23 21:46:34,298 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;193.4984520123839 21:46:34 INFO - 2015-11-23 21:46:34,298 DEBUG : BROWSER_OUTPUT: |2;Encrypt;280.50490883590464 21:46:34 INFO - 2015-11-23 21:46:34,298 DEBUG : BROWSER_OUTPUT: |3;Decrypt;4995.024875621891 21:46:34 INFO - 2015-11-23 21:46:34,298 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1044.932079414838 21:46:34 INFO - 2015-11-23 21:46:34,298 DEBUG : BROWSER_OUTPUT: |5;Earley;740.7407407407408 21:46:34 INFO - 2015-11-23 21:46:34,298 DEBUG : BROWSER_OUTPUT: |6;Boyer;11952.941176470587 21:46:34 INFO - 2015-11-23 21:46:34,299 DEBUG : BROWSER_OUTPUT: |7;RegExp;28166.666666666668 21:46:34 INFO - 2015-11-23 21:46:34,299 DEBUG : BROWSER_OUTPUT: |8;Splay;608.272506082725 21:46:34 INFO - 2015-11-23 21:46:34,299 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 21:46:34 INFO - 2015-11-23 21:46:34,299 DEBUG : BROWSER_OUTPUT: __end_tp_report 21:46:34 INFO - 2015-11-23 21:46:34,299 DEBUG : BROWSER_OUTPUT: __startTimestamp1448343994290__endTimestamp 21:46:34 INFO - 2015-11-23 21:46:34,299 DEBUG : BROWSER_OUTPUT: 21:46:34 INFO - 2015-11-23 21:46:34,299 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 21:46:34 INFO - 2015-11-23 21:46:34,299 DEBUG : BROWSER_OUTPUT: Number of tests: 10 21:46:34 INFO - 2015-11-23 21:46:34,300 DEBUG : BROWSER_OUTPUT: 21:46:34 INFO - 2015-11-23 21:46:34,300 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:186.43 Median:186.43 stddev:0.00 (0.0%) 21:46:34 INFO - 2015-11-23 21:46:34,300 DEBUG : BROWSER_OUTPUT: Values: 186.4 21:46:34 INFO - 2015-11-23 21:46:34,300 DEBUG : BROWSER_OUTPUT: 21:46:34 INFO - 2015-11-23 21:46:34,300 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:193.50 Median:193.50 stddev:0.00 (0.0%) 21:46:34 INFO - 2015-11-23 21:46:34,300 DEBUG : BROWSER_OUTPUT: Values: 193.5 21:46:34 INFO - 2015-11-23 21:46:34,300 DEBUG : BROWSER_OUTPUT: 21:46:34 INFO - 2015-11-23 21:46:34,301 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:280.50 Median:280.50 stddev:0.00 (0.0%) 21:46:34 INFO - 2015-11-23 21:46:34,301 DEBUG : BROWSER_OUTPUT: Values: 280.5 21:46:34 INFO - 2015-11-23 21:46:34,301 DEBUG : BROWSER_OUTPUT: 21:46:34 INFO - 2015-11-23 21:46:34,301 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:4995.02 Median:4995.02 stddev:0.00 (0.0%) 21:46:34 INFO - 2015-11-23 21:46:34,301 DEBUG : BROWSER_OUTPUT: Values: 4995.0 21:46:34 INFO - 2015-11-23 21:46:34,301 DEBUG : BROWSER_OUTPUT: 21:46:34 INFO - 2015-11-23 21:46:34,301 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1044.93 Median:1044.93 stddev:0.00 (0.0%) 21:46:34 INFO - 2015-11-23 21:46:34,302 DEBUG : BROWSER_OUTPUT: Values: 1044.9 21:46:34 INFO - 2015-11-23 21:46:34,302 DEBUG : BROWSER_OUTPUT: 21:46:34 INFO - 2015-11-23 21:46:34,302 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:740.74 Median:740.74 stddev:0.00 (0.0%) 21:46:34 INFO - 2015-11-23 21:46:34,302 DEBUG : BROWSER_OUTPUT: Values: 740.7 21:46:34 INFO - 2015-11-23 21:46:34,302 DEBUG : BROWSER_OUTPUT: 21:46:34 INFO - 2015-11-23 21:46:34,302 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11952.94 Median:11952.94 stddev:0.00 (0.0%) 21:46:34 INFO - 2015-11-23 21:46:34,302 DEBUG : BROWSER_OUTPUT: Values: 11952.9 21:46:34 INFO - 2015-11-23 21:46:34,303 DEBUG : BROWSER_OUTPUT: 21:46:34 INFO - 2015-11-23 21:46:34,303 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:28166.67 Median:28166.67 stddev:0.00 (0.0%) 21:46:34 INFO - 2015-11-23 21:46:34,303 DEBUG : BROWSER_OUTPUT: Values: 28166.7 21:46:34 INFO - 2015-11-23 21:46:34,303 DEBUG : BROWSER_OUTPUT: 21:46:34 INFO - 2015-11-23 21:46:34,303 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:608.27 Median:608.27 stddev:0.00 (0.0%) 21:46:34 INFO - 2015-11-23 21:46:34,303 DEBUG : BROWSER_OUTPUT: Values: 608.3 21:46:34 INFO - 2015-11-23 21:46:34,303 DEBUG : BROWSER_OUTPUT: 21:46:34 INFO - 2015-11-23 21:46:34,304 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 21:46:34 INFO - 2015-11-23 21:46:34,304 DEBUG : BROWSER_OUTPUT: Values: 4840.6 21:46:34 INFO - 2015-11-23 21:46:34,304 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 21:46:34 INFO - 2015-11-23 21:46:34,304 DEBUG : BROWSER_OUTPUT: 21:46:35 INFO - 2015-11-23 21:46:35,232 INFO : Browser exited with error code: 0 21:46:35 INFO - 2015-11-23 21:46:35,250 INFO : Completed test v8_7 (00:00:40) 21:46:35 INFO - 2015-11-23 21:46:35,316 INFO : Completed test suite (00:20:13) 21:46:35 INFO - 2015-11-23 21:46:35,317 DEBUG : Working with test: dromaeo_css 21:46:35 INFO - 2015-11-23 21:46:35,317 DEBUG : Generating results file: dromaeo_css 21:46:35 INFO - 2015-11-23 21:46:35,318 DEBUG : Working with test: kraken 21:46:35 INFO - 2015-11-23 21:46:35,318 DEBUG : Generating results file: kraken 21:46:35 INFO - 2015-11-23 21:46:35,318 INFO : javascript benchmark 21:46:35 INFO - 2015-11-23 21:46:35,318 DEBUG : Working with test: v8_7 21:46:35 INFO - 2015-11-23 21:46:35,319 DEBUG : Generating results file: v8_7 21:46:35 INFO - 2015-11-23 21:46:35,319 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 21:46:35 INFO - 2015-11-23 21:46:35,375 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,43]] 21:46:35 INFO - 2015-11-23 21:46:35,375 DEBUG : process_Request line: dromaeo_css 7070.91 graph.html#tests=[[72,64,43]] 21:46:35 INFO - 2015-11-23 21:46:35,375 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 21:46:35 INFO - 2015-11-23 21:46:35,492 DEBUG : process_Request line: kraken 1519.20 graph.html#tests=[[232,64,43]] 21:46:35 INFO - 2015-11-23 21:46:35,492 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 21:46:35 INFO - 2015-11-23 21:46:35,611 DEBUG : process_Request line: v8_7 20925.44 graph.html#tests=[[230,64,43]] 21:46:35 INFO - 2015-11-23 21:46:35,613 INFO : javascript benchmark 21:46:35 INFO - 2015-11-23 21:46:35,616 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3249.756268279879, 3328.66713328667, 3341.2649217172216, 3355.4631258998556, 3379.509971054197, 5345.224942383352, 5565.805196818113, 5573.498385145336, 5577.219189313494, 5613.38252792193, 5523.751431185597, 5539.058360078786, 5586.972065139668, 5588.413216612258, 5681.0910254359205, 5009.0, 5100.311457953175, 5131.871703207413, 5153.69077855328, 5181.274621552986, 4951.77717002734, 5118.411382691001, 5227.895442091156, 5251.842444726664, 5293.100172970585, 4853.587445067156, 4950.133726597867, 4967.801287948478, 4970.577500912426, 4974.1295273327205, 5031.0, 5126.461721519261, 5136.332276803992, 5160.819371321985, 5191.896162076775, 4849.733264670442, 4907.460179380265, 5011.548960593527, 5098.311727916721, 5141.2545180948555, 5203.973980130113, 5273.630845840793, 5286.735663216824, 5345.839624811262, 5431.619786614939, 5938.435848594377, 5943.673097979609, 5960.165576819249, 5964.105384192362, 5974.014287642577, 5909.438603332676, 5985.850353741148, 6015.849603759897, 6023.397660233942, 6028.638281703112, 5521.282233309644, 5566.4990150886615, 5574.0, 5574.9721251393885, 5578.023845827005, 6032.426919442646, 6041.5468839837185, 6051.304100028465, 6055.333913269536, 6060.818175454743, 5988.431099045583, 5995.310539287951, 5995.820125396245, 5999.640021598718, 6009.308929473079, 6028.849278768066, 6042.728077236535, 6047.0929360596265, 6069.484093851984, 6076.5442591805795, 5765.769369225184, 5890.587658863924, 5897.616654917417, 5960.821175364746, 6069.0592958091565, 5908.084246941731, 5962.791302304398, 5982.222311099529, 6000.0999850022845, 6025.8192254232445, 4561.361409402686, 4563.201439748097, 4565.474970378449, 4591.586757191869, 4602.7008244464005, 4021.6380525752825, 4022.4368588397647, 4059.512858457004, 4075.673946084306, 4098.8360465581045, 5605.355384130877, 5606.1625906813415, 5630.183623374588, 5636.239107720406, 5711.457411545896, 5820.330661973842, 5926.733297001645, 5931.614445061057, 5944.464998150187, 5958.225430693982, 5547.1679248113105, 5574.358948720949, 5614.129809879475, 5626.493615574618, 5681.57388195887, 5186.740662966912, 5208.869778255574, 5243.082460569385, 5243.606729495227, 5245.318108645852, 6029.0, 6104.913929256043, 6196.380361963767, 6223.813285601439, 6231.688415579203, 5947.31605865331, 5965.313988891159, 5994.940050599613, 6027.60820546663, 6071.544634152545, 6018.909716354171, 6172.629642221481, 6181.53638477125, 6236.875262494634, 6270.498360131087, 5644.52021578154, 5873.970630146822, 5937.970310148422, 5956.13636022783, 5992.790252341321, 6021.307549631849, 6034.758609655564, 6106.339326076981, 6112.26032301701, 6131.540134489842, 5793.623414478046, 5794.362620111869, 5804.158397032491, 5812.476877081083, 5842.912356314574, 5782.10377391505, 5790.536757059509, 5794.304683438015, 5829.329627092939, 5898.262717160355, 5728.455796699307, 5745.0, 5764.769409223584, 5856.59003869725, 5857.765689372377, 5905.261842269716, 5907.2911250650195, 5965.254343207099, 5972.5520585955355, 5973.342932277533, 5672.688002159921, 5795.188673585617, 5796.333421656564, 5810.796622118206, 5825.3883342248455], "unit": "ms", "name": "dojo.html", "value": 5506.661565977172}, {"lowerIsBetter": false, "replicates": [548.2145220136487, 585.9238299021127, 593.8360812806897, 600.9218801555797, 607.708300015992, 39173.41239881404, 40563.0, 40765.0, 40853.0, 40932.795336023315, 43412.56587434125, 43954.0, 43954.34068488975, 44099.77950110253, 44314.33528497075, 8856.911430885673, 8940.240079593243, 8958.193762561368, 8976.820463590724, 9035.683751068715, 8778.2099611035, 8885.02264750877, 8912.955435222848, 8934.73195804127, 8946.373753837233, 8766.079561646031, 8783.47299162049, 8860.8670869937, 8889.95555022224, 8910.777230569256, 8889.511076890769, 8904.198622124008, 8935.419197752126, 8957.910420895838, 8970.86543701845, 8743.737687869374, 8879.28965682744, 8899.822003559893, 8912.108789121101, 8922.821543569156, 40262.798686006674, 40668.79665601653, 40761.0, 40769.38845917313, 40830.38754418686, 3267.754918381107, 3333.6999670029813, 3351.1287065362935, 3590.8204589770667, 3776.206996530732, 30764.846175768977, 30793.538096928565, 30905.0, 30922.690773092207, 32082.839585802154, 3874.3219936511277, 3949.98025009876, 4013.8795836124964, 4044.312466880637, 4107.342825147962, 252939.0, 253200.0, 253433.0, 253771.99999999817, 254450.0, 55635.72182139063, 56402.0, 58792.412075878696, 59300.40699593035, 60467.39532604706, 56606.0, 56763.148552771745, 56837.43162568404, 57051.14423283654, 57095.14357284644, 15082.170480623452, 15185.62035949088, 15188.0, 15219.847801521842, 15256.847431525764, 11131.0, 11392.25950313227, 11396.0, 11426.542938282375, 11444.14168937333, 20220.89889550543, 20533.281335153497, 20694.586108277752, 20756.0, 20771.376858694264, 7496.512726672858, 7636.579988100708, 7683.769486915401, 7703.229677032252, 7712.768616941501, 919.8252332056887, 945.5110641732124, 959.8656188133667, 973.6981535723806, 1043.9873322876797, 28340.574891376647, 28556.013488197827, 28830.279243019093, 29019.564706529418, 29292.853535732185, 1009.0, 1024.897510248984, 1040.7658276887792, 1116.0680831505783, 1153.5212886651946, 14047.859521404655, 14161.29193540319, 15167.620809479851, 15662.9216853915, 15674.764878526603, 311981.0, 318489.0, 320108.0, 321030.0, 321252.0, 52667.0, 52679.0, 52771.47228527742, 52802.471975279754, 52989.205161922604, 1304.804279358104, 1308.4308325878274, 1308.738252349534, 1322.0679421008167, 1340.162398500937, 28348.0, 29136.125916222547, 29311.413771724874, 29515.85242073776, 29652.25869353197, 46537.301940471596, 46635.53364466448, 46960.29559556541, 47101.528984709716, 47150.29274560953, 288603.0, 291001.0, 291046.0, 291163.0, 291212.0, 31390.52914206243, 32012.839935801105, 32492.288618149763, 32840.50739238961, 32853.67146328506, 39369.0, 39852.60147398489, 40133.1973360537, 40454.0, 40611.0, 34363.0, 34398.65601343955, 34408.82795586006, 34627.826860865534, 35225.471617925235, 34237.0, 34321.0, 34703.13242168865, 35052.0, 35257.64742352646, 34847.12882177864, 36124.0, 36362.63637363593, 36468.81765591155, 36631.633683662825, 31125.533117003717, 31669.841650791597, 33056.83471582723, 33083.0, 33452.665473344954, 24664.0, 25892.35269118287, 25923.22230333093, 25965.22104336873, 26061.348466288495], "unit": "ms", "name": "ext.html", "value": 18516.846727847667}, {"lowerIsBetter": false, "replicates": [351.82584620612795, 352.31474781549895, 353.2370080625847, 354.1376747619548, 359.13627725320606, 16404.671906561878, 17180.65638687227, 17558.561035974108, 18161.545961350974, 18498.352557660484, 11043.0, 11150.553977840877, 11204.607838725646, 11774.705632359195, 16983.915080424613, 4837.951620483803, 4916.213405855064, 5059.569936555397, 5338.332708411449, 5374.677519348832, 5348.064088784467, 5386.218998245252, 5395.514403703666, 5399.19012148177, 5403.243545903577, 5112.642115051948, 5221.2951251580935, 5274.05067087924, 5364.597655175858, 5374.865628359284, 5176.275321454999, 5217.426083130871, 5228.163493840986, 5253.60597955153, 5380.811671591476, 5206.271122042917, 5249.895002099936, 5256.106461901448, 5260.132078207092, 5264.52619264268, 11604.245724027996, 11773.823392649118, 18524.722129168073, 19136.904315478332, 19139.043047847554, 2000.5898790747863, 2112.6936594193908, 2189.2665956904457, 2191.4630915425664, 2196.736391632998, 8903.643854245822, 8906.109389061106, 9005.774855628662, 9227.169554740105, 9368.110029547182, 1715.2367196597395, 1724.560237139534, 1745.467632372126, 1835.3667984545311, 1889.0, 60814.39185608132, 64190.0, 64904.0, 65046.0, 65147.67426162839, 10039.849402258971, 10141.94929025365, 10150.695479135638, 10201.795964080677, 10207.591696332212, 13426.731465370638, 13430.529931452353, 13468.461261549428, 13474.528391506446, 13500.392482338319, 7630.8855367169535, 7667.271609197117, 7683.385329173652, 7705.345045671181, 7711.344535714527, 7417.554946703107, 7423.62881855905, 7434.479586429006, 7474.289942455458, 7514.699412023567, 4232.280512312914, 4251.086016506466, 4261.87214383569, 4267.829286828554, 4284.721493102938, 1692.6530061337396, 1709.1625103699098, 1729.8097209306966, 1739.443378119015, 1807.8101799311146, 927.2303987690201, 932.1936524906043, 944.4710961861242, 1013.4527355228241, 1051.1118105201092, 16283.348666053225, 16702.4154154604, 17190.914045429694, 17406.39077632277, 17496.737548936777, 925.4401087342061, 940.2618944128826, 966.6616684160629, 976.1556253840376, 987.9407235565889, 9732.513374331396, 10090.243231757648, 10095.798084038424, 10097.848532272023, 10127.848082278771, 542326.0, 564555.0, 565730.0, 569726.0, 577810.0, 77306.22693773103, 79336.60331698305, 79357.0, 80125.0, 81935.0, 1166.7666466706523, 1171.8945294923499, 1221.2916508425092, 1223.5228260978226, 1243.098753403775, 15238.0, 15298.694026119638, 15505.22473876279, 15556.377744890078, 15594.766078508605, 34226.31547368989, 35005.299894002485, 35053.47419788756, 35845.28309433849, 37317.626823731414, 42568.78715606402, 43850.561494384645, 47529.52470475251, 47874.28188577244, 52365.47634523606, 9469.47917864524, 9778.07108324708, 9784.706458806248, 9902.356346837432, 9903.752406189902, 12944.935275323563, 17269.0, 17409.651806964044, 17711.911440442716, 18146.09269536544, 12813.359332033175, 13436.865631343562, 14054.437822487394, 14384.712305753617, 14973.0, 14487.20320382347, 14551.708965820835, 14923.925380373463, 14929.925350373178, 15270.16014119234, 15141.864360173251, 15181.468648597245, 15236.619084522976, 15390.692186156439, 15571.377144914077, 9326.393784403992, 9650.903490965, 9667.419954802454, 9690.176335011603, 9937.956514566156, 11946.402679866145, 13223.272720000492, 13850.584482465541, 14968.40126394932, 14990.250487475801], "unit": "ms", "name": "jquery.html", "value": 8791.772473033267}, {"lowerIsBetter": false, "replicates": [1493.9701205975887, 1689.7380905959576, 1748.0647853398427, 1752.719564869621, 1763.1360633289685, 6909.2054413742435, 6993.44052475802, 7349.265073492648, 7751.147373788879, 7779.805504862381, 7138.464615153858, 7189.317014883591, 7292.96353518233, 7340.596267205302, 7348.669309881055, 2190.561887622478, 2222.544378402432, 2241.5628952354296, 2248.0895237428786, 2249.201533455624, 2257.3115199864037, 2276.8178545716305, 2318.3508617587104, 2318.7449380568123, 2327.94180145496, 2227.0535022615404, 2245.7529671736097, 2250.1786847800518, 2261.728592568894, 2285.6342985122383, 2185.300703774785, 2196.077647388101, 2218.500837311608, 2229.7658745831695, 2232.3414592695276, 2266.614675505168, 2282.805961493275, 2289.347535952246, 2293.0942277800177, 2298.0348253733473, 7580.0, 7719.459637825355, 7738.613069346567, 7744.419168562324, 7754.0, 4437.955620443819, 4499.572540608637, 4513.052259025609, 4519.028408892104, 4544.250198717207, 3175.5712978747806, 3189.4418476766473, 3302.3890580242733, 3323.135984643986, 3331.0839519132433, 1110.5058249079163, 1129.9661010169707, 1133.625903451867, 1146.7821113988316, 1151.401271338899, 10259.230557708202, 10284.845727314096, 10293.897061029369, 10303.742406439824, 10332.94833525835, 4087.2847251730827, 4102.979485102615, 4160.771157586361, 4168.3955826405, 4177.352510360899, 8863.423877447945, 8864.20222179994, 8873.245774109273, 8874.822503550022, 8875.778105547413, 4626.676132670681, 4638.257878739386, 4668.50980647034, 4691.601213896789, 4697.342372067913, 4129.876103716893, 4191.203671302443, 4204.894877628022, 4216.620504154641, 4257.723247988871, 3520.806355650464, 3523.823808809549, 3530.505729197914, 3546.6098729139776, 3574.0, 1072.0, 1073.0664322039875, 1073.1522097543009, 1073.2058276875057, 1086.8587083679072, 1312.1667740984544, 1333.2134040915905, 1333.686583652841, 1367.3710093356967, 1373.1074801379184, 7259.997602972277, 7337.523061001018, 7338.046054012944, 7338.339549440576, 7364.153122390993, 441.72833707269797, 452.8279253883503, 458.85775409623125, 458.97475638840183, 461.197516321598, 5689.800856969986, 5708.0581704019205, 5717.685527295956, 5735.741891614887, 5737.3688894221605, 8869.866951995597, 8889.599968001456, 9073.81852362949, 9140.862887056699, 9183.081691830763, 8926.062763409878, 8945.686900958566, 8997.235235004968, 9022.639094436148, 9067.727968160965, 6615.17310336208, 6995.370416662525, 6999.965000174967, 7003.0, 7070.611116388648, 7084.822879428056, 7134.678939447634, 7140.571565706075, 7154.642267886689, 7163.64181790898, 7792.1428642848105, 7984.441089123706, 7986.600669966595, 8011.839763204586, 8114.51312921203, 8153.510789352657, 8296.958515207385, 8315.293200078062, 8345.290650294794, 8419.98960124789, 6866.588004719533, 6871.9656401717675, 7014.894776578253, 7041.894371584328, 7056.1179852518435, 7509.849803004018, 7555.546667199986, 7563.9621801890635, 7573.545587264782, 7576.924230757621, 6879.965600171967, 6901.1718593769065, 6905.75829845953, 6929.3070692930305, 7153.0, 6868.519203655896, 6875.828104297432, 6910.585364878123, 6948.0, 7137.072180616694, 6475.09348691193, 6661.666916654245, 6674.16572928384, 6957.79126626202, 6988.825279368057, 6529.347065293433, 6530.9020364693615, 6548.246951600437, 6568.343165683394, 6589.209294884453, 6432.614043157426, 6465.482761378984, 6475.708593113227, 6486.318936511599, 6648.966755166193], "unit": "ms", "name": "mootools.html", "value": 4348.82375691894}, {"lowerIsBetter": false, "replicates": [311.52647975077883, 314.2959770114942, 321.57873186126176, 324.2251020062052, 325.07678193929235, 6272.341404152561, 6308.873822523547, 6352.237731472218, 6393.712282947267, 6399.200099987502, 6189.13352130701, 6190.81427557174, 6220.906686399708, 6237.313895471494, 6261.561690681654, 4644.930326045112, 4755.42934847817, 4764.047190561884, 4813.205821039524, 4819.903601927959, 4730.97634511827, 4741.525847415248, 4747.7388743619085, 4748.5251474852585, 4751.881202969919, 4684.484706682262, 4707.223308154151, 4729.929051064237, 4736.573708366246, 4737.597304229144, 4664.416947881532, 4685.953140468586, 4725.881852953669, 4732.929006064912, 4738.265568836836, 3949.4075888616935, 4086.162336720965, 4086.468759061303, 4089.0595163112353, 4101.507819061731, 5947.613405128653, 5977.312609049972, 5982.790602328898, 5996.550258730613, 6035.336113027563, 5485.862853428656, 5501.394846566875, 5505.449455054502, 5532.944670553283, 5538.363088244822, 6581.078648989145, 6656.068150458939, 6685.866282674319, 6707.094542236784, 6709.664516774142, 4513.097380523908, 4517.796699148514, 4518.390017347681, 4520.344550040259, 4521.773911304422, 6890.104286442781, 6965.059716938199, 6973.581585104859, 6974.163100427981, 6976.127984002, 4609.53904609543, 4614.307853821953, 4615.192341340252, 4618.51505591908, 4626.097910907408, 4578.130155270488, 4673.7429441381055, 4675.7895894684825, 4685.9765701171955, 4686.297055441711, 5424.457554244544, 5471.37079235885, 5486.835394938158, 5502.889942201134, 5510.889782204414, 6749.6962636681465, 6772.0857684212, 6782.016607591871, 6785.1518560179975, 6788.2193547742645, 1618.0938674342406, 1657.8839481236198, 1661.96676066478, 1662.2852173565484, 1665.9171538500073, 1036.1348274190984, 1068.8450174724621, 1069.262209075751, 1069.8020866139789, 1098.5386137822134, 6213.875722485525, 6298.370162983757, 6439.581427207217, 6503.512236582181, 6526.445252153525, 6844.315568443116, 6911.930880691129, 7041.8239544010785, 7050.823729406703, 7063.611501367474, 6534.836629084216, 6535.346465353522, 6540.247871494839, 6542.182227221598, 6543.8364040898405, 3729.8694545691314, 3771.17034252464, 3815.04623844039, 3823.5602905665646, 3837.827297771607, 7235.3488186063505, 7264.745733899288, 7270.0, 7282.162551306562, 7286.23494533077, 7147.64261786919, 7164.677589508485, 7175.425965922714, 7193.784186474414, 7207.783766487014, 7027.121609798775, 7221.0, 7224.20533741288, 7226.92773072273, 7238.348548630754, 6987.7204911802955, 6998.825029374307, 7026.8945965809535, 7029.297070293134, 7030.472714546328, 6890.621015844176, 6911.3779759821855, 6917.4781548059345, 6919.861602767815, 6941.965290173719, 7055.2944705529035, 7229.457790665826, 7239.819004524929, 7241.5293005956555, 7269.127704675473, 4707.976460117678, 4708.764561771829, 4723.976380118215, 4725.267583524559, 4737.455192652889, 6938.479614028867, 6979.720811167496, 6981.720731170696, 6982.301769822977, 7017.3684368407085, 6619.669016549057, 6745.5952642841585, 6792.830179245559, 6800.183977922681, 6828.931710682829, 6742.93257067423, 6796.184457865088, 6815.488838337244, 6816.659167041727, 6823.1812182538415, 6567.146270984741, 6666.966665166837, 6678.198616166092, 6684.8997265042035, 6686.866262674622, 6672.799816005528, 6757.831054223684, 6842.657867106725, 6844.110265665432, 6853.040574319699, 6582.670866456562, 6587.63767992765, 6643.302453242533, 6667.466602671678, 6705.362990515893], "unit": "ms", "name": "prototype.html", "value": 5018.446698032865}, {"lowerIsBetter": false, "replicates": [853.6116067189428, 860.7073594977707, 864.2135656552539, 865.238590040764, 882.2236431939892, 13505.0, 13698.589042328733, 13702.246376449291, 13714.65713357164, 13793.103448275868, 13315.733685326288, 13371.732565348688, 13482.595522134327, 13487.865121348783, 13502.527411540597, 803.9155888631711, 826.5536610230469, 828.4408024583382, 828.9171082891693, 834.0658462521984, 828.159418190537, 829.6308142876422, 830.3979614779297, 830.9418340716152, 839.4291881520563, 872.5768002518773, 878.3895192840978, 878.5431575580694, 878.9208971192623, 881.0528681667233, 861.956902154896, 868.1492137705085, 868.700298397052, 871.6382701179003, 872.6422166911567, 635.6059243269157, 637.1684951138762, 639.7441023590554, 641.2945759664378, 643.8261669349249, 13907.860921390758, 13936.233507157101, 13995.020348575605, 14005.509807156803, 14011.579652610337, 12237.755244895052, 12286.815697764541, 12364.690882727913, 12452.564160254347, 12553.184043037174, 10315.43265120418, 10432.426216558086, 10575.524101415454, 10723.678289651323, 10732.034116929435, 3613.834801805917, 3692.1877187018813, 3703.9074023149637, 3722.683571896365, 3726.776393416377, 44370.33444498335, 44606.553934460564, 44720.552794471965, 44738.55261447409, 44815.32777008347, 9808.117269445784, 10058.245631577662, 10074.748131296776, 10262.743431414125, 10297.74255643615, 18022.189001494964, 18022.90988545075, 18117.36589219371, 18141.274349026156, 18146.274149034154, 7610.505317154367, 7672.61636918163, 7681.807954801063, 7682.769516914502, 7696.384289256846, 4224.5353011168745, 4255.5957184067465, 4420.911581768347, 4435.6673249505775, 4438.755868427267, 3085.506318988951, 3107.0523490335213, 3116.6415862175686, 3116.9402403183026, 3124.1564777509943, 570.1903297317818, 582.3361368040437, 583.2126629050748, 583.5040215816607, 590.4774274766862, 566.4760096910342, 577.5206578539802, 585.3414908228243, 670.5004771445305, 703.1070540413646, 8041.396895232764, 8153.959230203811, 8639.95680021596, 8712.346574006975, 9021.594028268744, 547.17923115327, 564.9548036157098, 587.6092398554998, 603.285107148031, 649.7238673563717, 4154.771487568152, 4247.447831781848, 4371.366151907955, 4404.207242696345, 4471.334539741923, 56135.4484259058, 56995.0, 57861.0, 58783.706081470176, 58956.11565826516, 52504.47495525072, 52653.210201847, 53487.46512534903, 53868.730656346466, 54000.45999539954, 44095.55904440978, 44526.77736611296, 45015.77492112519, 45484.31773523334, 48150.0, 8538.829223415622, 8666.263367613823, 9235.399699019543, 9351.0, 9389.0, 35733.46399804057, 36005.0, 36206.275874481835, 36412.635873640924, 37085.814570926974, 42314.36528452014, 44399.0, 46155.0, 46199.0, 46318.0, 11010.50452729613, 11109.611163609236, 11199.440027998404, 11237.213395062266, 11255.324680519196, 12344.382780860742, 12466.563670271496, 12662.556810511587, 12669.873301266869, 13157.60527184186, 10918.597908460126, 10937.070349020423, 10977.83533246986, 11729.941350293195, 11901.821472677744, 11119.555217791198, 11355.034822039888, 11599.304041757521, 11662.53349865996, 11664.708382290171, 10995.780084398428, 11020.724481888017, 11118.49966751482, 11270.436478176221, 11278.718032048937, 9899.554520046471, 9972.152367048882, 10081.495925203857, 10099.444530550596, 10320.48397580133, 9176.35765496409, 9416.670416535388, 9506.239500840054, 9798.706038818847, 10039.0], "unit": "ms", "name": "yui.html", "value": 6387.659793353301}], "name": "dromaeo_css", "value": 7070.911317937352}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [110.0, 105.0, 110.0, 110.0, 112.0, 122.0, 110.0, 110.0, 109.0, 110.0], "unit": "ms", "name": "ai-astar", "value": 110.8}, {"lowerIsBetter": true, "replicates": [156.0, 126.0, 151.0, 169.0, 126.0, 149.0, 146.0, 127.0, 171.0, 127.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.8}, {"lowerIsBetter": true, "replicates": [162.0, 164.0, 162.0, 163.0, 163.0, 164.0, 164.0, 163.0, 172.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 164.0}, {"lowerIsBetter": true, "replicates": [71.0, 73.0, 72.0, 72.0, 71.0, 71.0, 155.0, 73.0, 72.0, 71.0], "unit": "ms", "name": "audio-fft", "value": 80.1}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 139.0, 94.0, 94.0, 93.0, 146.0, 94.0, 95.0, 131.0], "unit": "ms", "name": "audio-oscillator", "value": 107.3}, {"lowerIsBetter": true, "replicates": [110.0, 111.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.1}, {"lowerIsBetter": true, "replicates": [147.0, 146.0, 146.0, 147.0, 147.0, 147.0, 146.0, 147.0, 146.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 146.6}, {"lowerIsBetter": true, "replicates": [96.0, 97.0, 96.0, 96.0, 97.0, 97.0, 96.0, 96.0, 97.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.4}, {"lowerIsBetter": true, "replicates": [62.0, 62.0, 65.0, 61.0, 61.0, 62.0, 61.0, 65.0, 63.0, 61.0], "unit": "ms", "name": "json-parse-financial", "value": 62.3}, {"lowerIsBetter": true, "replicates": [69.0, 69.0, 68.0, 69.0, 69.0, 70.0, 70.0, 69.0, 69.0, 69.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 69.1}, {"lowerIsBetter": true, "replicates": [84.0, 96.0, 88.0, 86.0, 86.0, 84.0, 88.0, 87.0, 87.0, 84.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 87.0}, {"lowerIsBetter": true, "replicates": [118.0, 126.0, 121.0, 122.0, 118.0, 127.0, 129.0, 136.0, 121.0, 127.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 124.5}, {"lowerIsBetter": true, "replicates": [143.0, 149.0, 139.0, 153.0, 151.0, 147.0, 151.0, 142.0, 154.0, 158.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 148.7}, {"lowerIsBetter": true, "replicates": [68.0, 66.0, 68.0, 67.0, 68.0, 66.0, 66.0, 66.0, 70.0, 70.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 67.5}], "name": "kraken", "value": 1519.2}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [186.42803877703207], "unit": "score", "name": "Richards", "value": 189.3599279999999}, {"lowerIsBetter": false, "replicates": [193.4984520123839], "unit": "score", "name": "DeltaBlue", "value": 341.6978240000001}, {"lowerIsBetter": false, "replicates": [280.50490883590464], "unit": "score", "name": "Encrypt", "value": 948.9352650000003}, {"lowerIsBetter": false, "replicates": [4995.024875621891], "unit": "score", "name": "Decrypt", "value": 53.289224103585696}, {"lowerIsBetter": false, "replicates": [1044.932079414838], "unit": "score", "name": "RayTrace", "value": 708.1694730000004}, {"lowerIsBetter": false, "replicates": [740.7407407407408], "unit": "score", "name": "Earley", "value": 899.7250500000001}, {"lowerIsBetter": false, "replicates": [11952.941176470587], "unit": "score", "name": "Boyer", "value": 55.75723917322837}, {"lowerIsBetter": false, "replicates": [28166.666666666668], "unit": "score", "name": "RegExp", "value": 32.34266272189347}, {"lowerIsBetter": false, "replicates": [608.272506082725], "unit": "score", "name": "Splay", "value": 133.97120400000006}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 20925.43686187044}]} 21:46:35 INFO - RETURN: dromaeo_css: 7070.91 21:46:35 INFO - RETURN: kraken: 1519.20 21:46:35 INFO - RETURN: v8_7: 20925.44 21:46:35 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,43]]", "result": "20925.44"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,43]]", "result": "1519.20"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,43]]", "result": "7070.91"}}} 21:46:35 INFO - Return code: 0 21:46:35 INFO - # TBPL SUCCESS # 21:46:35 INFO - Running post-action listener: _resource_record_post_action 21:46:35 INFO - Running post-run listener: _resource_record_post_run 21:46:36 INFO - Total resource usage - Wall time: 1228s; CPU: 13.0%; Read bytes: 4096; Write bytes: 524169216; Read time: 176; Write time: 579036 21:46:36 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 88154112; Read time: 0; Write time: 79820 21:46:36 INFO - run-tests - Wall time: 1214s; CPU: 13.0%; Read bytes: 4096; Write bytes: 366166016; Read time: 176; Write time: 358120 21:46:36 INFO - Running post-run listener: _upload_blobber_files 21:46:36 INFO - Blob upload gear active. 21:46:36 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 21:46:36 INFO - Copying logs to upload dir... 21:46:36 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1259.964998 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2015-11-23 21:46:36.676296) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 21:46:36.679809) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009996 build_url: 'https://queue.taskcluster.net/v1/task/uE6ZnjnNThu-eRznmLvRqw/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-23 21:46:36.730514) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 21:46:36.730840) ========= 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=d60a7c61e513c1f504d0d12f000001b4-1448342388.680512-200713226 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004518 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-23 21:46:36.780608) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 21:46:36.780912) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-23 21:46:36.781248) ========= ========= Total master_lag: 0.23 =========