builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-010 starttime: 1456580575.89 results: success (0) buildid: 20160227031625 builduid: 009d9ca84a164431aa29a68fab0726ef revision: f1a50b394664 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:55.890541) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:55.891083) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:55.891408) ========= 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=eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006383 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.01 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:55.910850) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:55.911213) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:55.919504) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:55.919879) ========= 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=eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004220 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:55.968689) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:55.969072) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:55.969480) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:55.969804) ========= 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=eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-27 05:42:56-- 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% 84.4M=0s 2016-02-27 05:42:56 (84.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.167959 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:56.150141) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:56.150514) ========= 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=eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013440 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:56.200986) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-27 05:42:56.201397) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f1a50b394664 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f1a50b394664 --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=eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-27 05:42:57,053 Setting DEBUG logging. 2016-02-27 05:42:57,053 attempt 1/10 2016-02-27 05:42:57,053 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f1a50b394664?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-27 05:42:58,395 unpacking tar archive at: mozilla-beta-f1a50b394664/testing/mozharness/ program finished with exit code 0 elapsedTime=1.671849 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-02-27 05:42:57.886815) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:57.887268) ========= echo f1a50b394664 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'f1a50b394664'] 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=eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False f1a50b394664 program finished with exit code 0 elapsedTime=0.004694 script_repo_revision: 'f1a50b394664' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:57.939210) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:57.939625) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-27 05:42:57.949397) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-02-27 05:42:57.949840) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:42:58 INFO - MultiFileLogger online at 20160227 05:42:58 in /builds/slave/test-pgo 05:42:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:42:58 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 05:42:58 INFO - {'append_to_log': False, 05:42:58 INFO - 'base_work_dir': '/builds/slave/test-pgo', 05:42:58 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:42:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 05:42:58 INFO - 'branch': 'Mozilla-Beta', 05:42:58 INFO - 'buildbot_json_path': 'buildprops.json', 05:42:58 INFO - 'config_files': ('talos/linux_config.py',), 05:42:58 INFO - 'default_actions': ('clobber', 05:42:58 INFO - 'read-buildbot-config', 05:42:58 INFO - 'download-and-extract', 05:42:58 INFO - 'populate-webroot', 05:42:58 INFO - 'create-virtualenv', 05:42:58 INFO - 'install', 05:42:58 INFO - 'run-tests'), 05:42:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:42:58 INFO - 'download_minidump_stackwalk': True, 05:42:58 INFO - 'download_symbols': 'ondemand', 05:42:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:42:58 INFO - 'tooltool.py': '/tools/tooltool.py', 05:42:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:42:58 INFO - '/tools/misc-python/virtualenv.py')}, 05:42:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:42:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:42:58 INFO - 'installer_path': 'installer.exe', 05:42:58 INFO - 'log_level': 'info', 05:42:58 INFO - 'log_name': 'talos', 05:42:58 INFO - 'log_to_console': True, 05:42:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:42:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:42:58 INFO - 'opt_config_files': (), 05:42:58 INFO - 'pip_index': False, 05:42:58 INFO - 'sps_profile': False, 05:42:58 INFO - 'sps_profile_interval': 0, 05:42:58 INFO - 'suite': 'dromaeojs', 05:42:58 INFO - 'system_bits': '32', 05:42:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:42:58 INFO - 'title': 'talos-linux64-ix-010', 05:42:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:42:58 INFO - 'use_talos_json': True, 05:42:58 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 05:42:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:42:58 INFO - 'work_dir': 'build'} 05:42:58 INFO - ##### 05:42:58 INFO - ##### Running clobber step. 05:42:58 INFO - ##### 05:42:58 INFO - Running pre-action listener: _resource_record_pre_action 05:42:58 INFO - Running main action method: clobber 05:42:58 INFO - rmtree: /builds/slave/test-pgo/build 05:42:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 05:42:59 INFO - Running post-action listener: _resource_record_post_action 05:42:59 INFO - ##### 05:42:59 INFO - ##### Running read-buildbot-config step. 05:42:59 INFO - ##### 05:42:59 INFO - Running pre-action listener: _resource_record_pre_action 05:42:59 INFO - Running main action method: read_buildbot_config 05:42:59 INFO - Using buildbot properties: 05:42:59 INFO - { 05:42:59 INFO - "properties": { 05:42:59 INFO - "buildnumber": 125, 05:42:59 INFO - "product": "firefox", 05:42:59 INFO - "script_repo_revision": "production", 05:42:59 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 05:42:59 INFO - "repository": "", 05:42:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 05:42:59 INFO - "buildid": "20160227031625", 05:42:59 INFO - "slavename": "talos-linux64-ix-010", 05:42:59 INFO - "pgo_build": "True", 05:42:59 INFO - "basedir": "/builds/slave/test-pgo", 05:42:59 INFO - "project": "", 05:42:59 INFO - "platform": "ubuntu64_hw", 05:42:59 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:42:59 INFO - "slavebuilddir": "test", 05:42:59 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 05:42:59 INFO - "branch": "mozilla-beta", 05:42:59 INFO - "repo_path": "releases/mozilla-beta", 05:42:59 INFO - "stage_platform": "linux64-pgo", 05:42:59 INFO - "builduid": "009d9ca84a164431aa29a68fab0726ef", 05:42:59 INFO - "revision": "f1a50b394664" 05:42:59 INFO - }, 05:42:59 INFO - "sourcestamp": { 05:42:59 INFO - "repository": "", 05:42:59 INFO - "hasPatch": false, 05:42:59 INFO - "project": "", 05:42:59 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 05:42:59 INFO - "changes": [ 05:42:59 INFO - { 05:42:59 INFO - "category": null, 05:42:59 INFO - "files": [ 05:42:59 INFO - { 05:42:59 INFO - "url": null, 05:42:59 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.tar.bz2" 05:42:59 INFO - } 05:42:59 INFO - ], 05:42:59 INFO - "repository": "", 05:42:59 INFO - "rev": "f1a50b394664", 05:42:59 INFO - "who": "sendchange", 05:42:59 INFO - "when": 1456580477, 05:42:59 INFO - "number": 7664242, 05:42:59 INFO - "comments": "No bug, Automated blocklist update from host bld-linux64-spot-459 - a=blocklist-update", 05:42:59 INFO - "project": "", 05:42:59 INFO - "at": "Sat 27 Feb 2016 05:41:17", 05:42:59 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 05:42:59 INFO - "revlink": "", 05:42:59 INFO - "properties": [ 05:42:59 INFO - [ 05:42:59 INFO - "buildid", 05:42:59 INFO - "20160227031625", 05:42:59 INFO - "Change" 05:42:59 INFO - ], 05:42:59 INFO - [ 05:42:59 INFO - "builduid", 05:42:59 INFO - "009d9ca84a164431aa29a68fab0726ef", 05:42:59 INFO - "Change" 05:42:59 INFO - ], 05:42:59 INFO - [ 05:42:59 INFO - "pgo_build", 05:42:59 INFO - "True", 05:42:59 INFO - "Change" 05:42:59 INFO - ] 05:42:59 INFO - ], 05:42:59 INFO - "revision": "f1a50b394664" 05:42:59 INFO - } 05:42:59 INFO - ], 05:42:59 INFO - "revision": "f1a50b394664" 05:42:59 INFO - } 05:42:59 INFO - } 05:42:59 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.tar.bz2. 05:42:59 INFO - Running post-action listener: _resource_record_post_action 05:42:59 INFO - ##### 05:42:59 INFO - ##### Running download-and-extract step. 05:42:59 INFO - ##### 05:42:59 INFO - Running pre-action listener: _resource_record_pre_action 05:42:59 INFO - Running main action method: download_and_extract 05:42:59 INFO - mkdir: /builds/slave/test-pgo/build/tests 05:42: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')]} 05:42:59 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/test_packages.json 05:42:59 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 05:42:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 05:42:59 INFO - Downloaded 1270 bytes. 05:42:59 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 05:42:59 INFO - Using the following test package requirements: 05:42:59 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 05:42:59 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:42:59 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 05:42:59 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:42:59 INFO - u'jsshell-linux-x86_64.zip'], 05:42:59 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:42:59 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 05:42:59 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 05:42:59 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:42:59 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 05:42:59 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:42:59 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 05:42:59 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:42:59 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 05:42:59 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 05:42:59 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 05:42:59 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 05:42:59 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:42: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')]} 05:42:59 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.common.tests.zip 05:42:59 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 05:42:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:43:00 INFO - Downloaded 21584732 bytes. 05:43:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:43:00 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 05:43:00 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:43:01 INFO - Return code: 0 05:43:01 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:43:01 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')]} 05:43:01 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.common.tests.zip 05:43:01 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 05:43:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:43:02 INFO - Downloaded 21584732 bytes. 05:43:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 05:43:02 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 05:43:02 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 05:43:03 INFO - Return code: 0 05:43: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')]} 05:43:03 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 05:43:03 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 05:43:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:43:03 INFO - Downloaded 11132038 bytes. 05:43:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 05:43:03 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 05:43:03 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 05:43:03 INFO - Return code: 0 05:43: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')]} 05:43:03 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.tar.bz2 05:43:03 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 05:43:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:43:05 INFO - Downloaded 57939118 bytes. 05:43:05 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.tar.bz2 05:43:05 INFO - mkdir: /builds/slave/test-pgo/properties 05:43:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 05:43:05 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 05:43:05 INFO - Contents: 05:43:05 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.tar.bz2 05:43:05 INFO - Running post-action listener: _resource_record_post_action 05:43:05 INFO - Running post-action listener: set_extra_try_arguments 05:43:05 INFO - ##### 05:43:05 INFO - ##### Running populate-webroot step. 05:43:05 INFO - ##### 05:43:05 INFO - Running pre-action listener: _resource_record_pre_action 05:43:05 INFO - Running main action method: populate_webroot 05:43:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:43:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:43:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:43:05 INFO - '--tppagecycles', 05:43:05 INFO - '7'], 05:43:05 INFO - 'tests': ['tsvgm']}}, 05:43:05 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 05:43:05 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 05:43:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:43:05 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:43:05 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 05:43:05 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 05:43:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:43:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:05 INFO - 'talos_options': ['--e10s'], 05:43:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:43:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:05 INFO - 'tests': ['damp', 'tps']}, 05:43:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:05 INFO - 'talos_options': ['--e10s'], 05:43:05 INFO - 'tests': ['damp', 'tps']}, 05:43:05 INFO - 'g3': {'tests': ['dromaeo_dom']}, 05:43:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:43:05 INFO - 'other': {'tests': ['a11yr', 05:43:05 INFO - 'ts_paint', 05:43:05 INFO - 'tpaint', 05:43:05 INFO - 'sessionrestore', 05:43:05 INFO - 'sessionrestore_no_auto_restore']}, 05:43:05 INFO - 'other-e10s': {'talos_options': ['--e10s'], 05:43:05 INFO - 'tests': ['a11yr', 05:43:05 INFO - 'ts_paint', 05:43:05 INFO - 'tpaint', 05:43:05 INFO - 'sessionrestore', 05:43:05 INFO - 'sessionrestore_no_auto_restore']}, 05:43:05 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 05:43:05 INFO - 'tests': ['a11yr', 05:43:05 INFO - 'ts_paint', 05:43:05 INFO - 'tpaint', 05:43:05 INFO - 'sessionrestore', 05:43:05 INFO - 'sessionrestore_no_auto_restore']}, 05:43:05 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 05:43:05 INFO - 'tests': ['a11yr', 05:43:05 INFO - 'ts_paint', 05:43:05 INFO - 'tpaint', 05:43:05 INFO - 'sessionrestore', 05:43:05 INFO - 'sessionrestore_no_auto_restore']}, 05:43:05 INFO - 'other_l64': {'tests': ['a11yr', 05:43:05 INFO - 'ts_paint', 05:43:05 INFO - 'tpaint', 05:43:05 INFO - 'sessionrestore', 05:43:05 INFO - 'sessionrestore_no_auto_restore']}, 05:43:05 INFO - 'other_nol64': {'tests': ['a11yr', 05:43:05 INFO - 'ts_paint', 05:43:05 INFO - 'tpaint', 05:43:05 INFO - 'sessionrestore', 05:43:05 INFO - 'sessionrestore_no_auto_restore']}, 05:43:05 INFO - 'svgr': {'tests': ['tsvgx', 05:43:05 INFO - 'tsvgr_opacity', 05:43:05 INFO - 'tart', 05:43:05 INFO - 'tscrollx', 05:43:05 INFO - 'cart']}, 05:43:05 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 05:43:05 INFO - 'tests': ['tsvgx', 05:43:05 INFO - 'tsvgr_opacity', 05:43:05 INFO - 'tart', 05:43:05 INFO - 'tscrollx', 05:43:05 INFO - 'cart']}, 05:43:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:05 INFO - 'tests': ['tp5o']}, 05:43:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:05 INFO - 'talos_options': ['--e10s'], 05:43:05 INFO - 'tests': ['tp5o']}, 05:43:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:05 INFO - 'talos_options': ['--xperf_path', 05:43:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:43:05 INFO - 'tests': ['tp5n']}, 05:43:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:43:05 INFO - 'talos_options': ['--e10s', 05:43:05 INFO - '--xperf_path', 05:43:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:43:05 INFO - 'tests': ['tp5n']}}, 05:43:05 INFO - 'talos.zip': {'path': '', 05:43:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:43:05 INFO - Running post-action listener: _resource_record_post_action 05:43:05 INFO - ##### 05:43:05 INFO - ##### Running create-virtualenv step. 05:43:05 INFO - ##### 05:43:05 INFO - Running pre-action listener: _resource_record_pre_action 05:43:05 INFO - Running main action method: create_virtualenv 05:43:05 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 05:43:05 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 05:43:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 05:43:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:43:05 INFO - Using real prefix '/usr' 05:43:05 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 05:43:05 INFO - Installing distribute.............................................................................................................................................................................................done. 05:43:07 INFO - Installing pip.................done. 05:43:07 INFO - Return code: 0 05:43:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 05:43:07 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')]} 05:43:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:07 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')]} 05:43:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fd1d8364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b60e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1744e30>, '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 0x1745d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17461c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', '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 05:43:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 05:43:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 05:43:07 INFO - Using env: {'DISPLAY': ':0', 05:43:07 INFO - 'HOME': '/home/cltbld', 05:43:07 INFO - 'LANG': 'en_US.UTF-8', 05:43:07 INFO - 'LANGUAGE': 'en_US:en', 05:43:07 INFO - 'LOGNAME': 'cltbld', 05:43:07 INFO - 'MAIL': '/var/mail/cltbld', 05:43:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:07 INFO - 'MOZ_NO_REMOTE': '1', 05:43:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:07 INFO - 'NO_EM_RESTART': '1', 05:43:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:07 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:07 INFO - 'SHELL': '/bin/bash', 05:43:07 INFO - 'SHLVL': '1', 05:43:07 INFO - 'TERM': 'linux', 05:43:07 INFO - 'TMOUT': '86400', 05:43:07 INFO - 'USER': 'cltbld', 05:43:07 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', 05:43:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:07 INFO - '_': '/tools/buildbot/bin/python'} 05:43:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:07 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 05:43:07 INFO - Cleaning up... 05:43:07 INFO - Return code: 0 05:43:07 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 05:43:07 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')]} 05:43:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:07 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')]} 05:43:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:07 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fd1d8364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b60e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1744e30>, '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 0x1745d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17461c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', '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 05:43:07 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 05:43:07 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 05:43:07 INFO - Using env: {'DISPLAY': ':0', 05:43:07 INFO - 'HOME': '/home/cltbld', 05:43:07 INFO - 'LANG': 'en_US.UTF-8', 05:43:07 INFO - 'LANGUAGE': 'en_US:en', 05:43:07 INFO - 'LOGNAME': 'cltbld', 05:43:07 INFO - 'MAIL': '/var/mail/cltbld', 05:43:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:07 INFO - 'MOZ_NO_REMOTE': '1', 05:43:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:07 INFO - 'NO_EM_RESTART': '1', 05:43:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:07 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:07 INFO - 'SHELL': '/bin/bash', 05:43:07 INFO - 'SHLVL': '1', 05:43:07 INFO - 'TERM': 'linux', 05:43:07 INFO - 'TMOUT': '86400', 05:43:07 INFO - 'USER': 'cltbld', 05:43:07 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', 05:43:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:07 INFO - '_': '/tools/buildbot/bin/python'} 05:43:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:08 INFO - Downloading/unpacking psutil>=0.7.1 05:43:08 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 05:43:08 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 05:43:08 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 05:43:08 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 05:43:08 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 05:43:08 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 05:43:10 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 05:43:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:43:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:43:10 INFO - Installing collected packages: psutil 05:43:10 INFO - Running setup.py install for psutil 05:43:10 INFO - building 'psutil._psutil_linux' extension 05:43:10 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 05:43:10 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 05:43:10 INFO - building 'psutil._psutil_posix' extension 05:43:10 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 05:43:10 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 05:43:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:43:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:43:10 INFO - Successfully installed psutil 05:43:10 INFO - Cleaning up... 05:43:10 INFO - Return code: 0 05:43:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 05:43:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:10 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fd1d8364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b60e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1744e30>, '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 0x1745d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17461c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', '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 05:43:10 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 05:43:10 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 05:43:10 INFO - Using env: {'DISPLAY': ':0', 05:43:10 INFO - 'HOME': '/home/cltbld', 05:43:10 INFO - 'LANG': 'en_US.UTF-8', 05:43:10 INFO - 'LANGUAGE': 'en_US:en', 05:43:10 INFO - 'LOGNAME': 'cltbld', 05:43:10 INFO - 'MAIL': '/var/mail/cltbld', 05:43:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:10 INFO - 'MOZ_NO_REMOTE': '1', 05:43:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:10 INFO - 'NO_EM_RESTART': '1', 05:43:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:10 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:10 INFO - 'SHELL': '/bin/bash', 05:43:10 INFO - 'SHLVL': '1', 05:43:10 INFO - 'TERM': 'linux', 05:43:10 INFO - 'TMOUT': '86400', 05:43:10 INFO - 'USER': 'cltbld', 05:43:10 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', 05:43:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:10 INFO - '_': '/tools/buildbot/bin/python'} 05:43:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:43:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:43:12 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:43:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:43:12 INFO - Installing collected packages: mozsystemmonitor 05:43:12 INFO - Running setup.py install for mozsystemmonitor 05:43:12 INFO - Successfully installed mozsystemmonitor 05:43:12 INFO - Cleaning up... 05:43:12 INFO - Return code: 0 05:43:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 05:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:12 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fd1d8364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b60e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1744e30>, '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 0x1745d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17461c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', '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 05:43:12 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 05:43:12 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 05:43:12 INFO - Using env: {'DISPLAY': ':0', 05:43:12 INFO - 'HOME': '/home/cltbld', 05:43:12 INFO - 'LANG': 'en_US.UTF-8', 05:43:12 INFO - 'LANGUAGE': 'en_US:en', 05:43:12 INFO - 'LOGNAME': 'cltbld', 05:43:12 INFO - 'MAIL': '/var/mail/cltbld', 05:43:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:12 INFO - 'MOZ_NO_REMOTE': '1', 05:43:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:12 INFO - 'NO_EM_RESTART': '1', 05:43:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:12 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:12 INFO - 'SHELL': '/bin/bash', 05:43:12 INFO - 'SHLVL': '1', 05:43:12 INFO - 'TERM': 'linux', 05:43:12 INFO - 'TMOUT': '86400', 05:43:12 INFO - 'USER': 'cltbld', 05:43:12 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', 05:43:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:12 INFO - '_': '/tools/buildbot/bin/python'} 05:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:13 INFO - Downloading/unpacking blobuploader==1.2.4 05:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:14 INFO - Downloading blobuploader-1.2.4.tar.gz 05:43:14 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:43:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:43:15 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 05:43:15 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 05:43:15 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 05:43:15 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 05:43:15 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 05:43:15 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 05:43:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 05:43:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:43:15 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 05:43:15 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 05:43:15 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 05:43:15 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 05:43:15 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 05:43:15 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 05:43:15 INFO - Downloading docopt-0.6.1.tar.gz 05:43:15 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 05:43:15 INFO - Installing collected packages: blobuploader, requests, docopt 05:43:15 INFO - Running setup.py install for blobuploader 05:43:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:43:15 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 05:43:15 INFO - Running setup.py install for requests 05:43:16 INFO - Running setup.py install for docopt 05:43:16 INFO - Successfully installed blobuploader requests docopt 05:43:16 INFO - Cleaning up... 05:43:16 INFO - Return code: 0 05:43:16 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:43:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:16 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 0x7fd1d8364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b60e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1744e30>, '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 0x1745d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17461c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', '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 05:43:16 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--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 05:43:16 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --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 05:43:16 INFO - Using env: {'DISPLAY': ':0', 05:43:16 INFO - 'HOME': '/home/cltbld', 05:43:16 INFO - 'LANG': 'en_US.UTF-8', 05:43:16 INFO - 'LANGUAGE': 'en_US:en', 05:43:16 INFO - 'LOGNAME': 'cltbld', 05:43:16 INFO - 'MAIL': '/var/mail/cltbld', 05:43:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:16 INFO - 'MOZ_NO_REMOTE': '1', 05:43:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:16 INFO - 'NO_EM_RESTART': '1', 05:43:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:16 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:16 INFO - 'SHELL': '/bin/bash', 05:43:16 INFO - 'SHLVL': '1', 05:43:16 INFO - 'TERM': 'linux', 05:43:16 INFO - 'TMOUT': '86400', 05:43:16 INFO - 'USER': 'cltbld', 05:43:16 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', 05:43:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:16 INFO - '_': '/tools/buildbot/bin/python'} 05:43:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:43:16 INFO - Running setup.py (path:/tmp/pip-bT2hvY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:43:16 INFO - Running setup.py (path:/tmp/pip-bOYP_r-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:43:16 INFO - Running setup.py (path:/tmp/pip-ie6Ky6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:43:16 INFO - Running setup.py (path:/tmp/pip-Vwk89c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:43:16 INFO - Running setup.py (path:/tmp/pip-_fxMyB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:43:16 INFO - Running setup.py (path:/tmp/pip-BSLk2J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:43:16 INFO - Running setup.py (path:/tmp/pip-3RnDJF-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:43:16 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:43:16 INFO - Running setup.py (path:/tmp/pip-H6HiJs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:43:17 INFO - Running setup.py (path:/tmp/pip-UpFGlp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:43:17 INFO - Running setup.py (path:/tmp/pip-ahwqhf-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:43:17 INFO - Running setup.py (path:/tmp/pip-m_cJgv-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:43:17 INFO - Running setup.py (path:/tmp/pip-o9bjGe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:43:17 INFO - Running setup.py (path:/tmp/pip-C55mb1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:43:17 INFO - Running setup.py (path:/tmp/pip-nYoqVi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:43:17 INFO - Running setup.py (path:/tmp/pip-1vNAUi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:43:17 INFO - Running setup.py (path:/tmp/pip-hMuFPL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:43:17 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:43:17 INFO - Running setup.py (path:/tmp/pip-dFzf_a-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:43:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:43:17 INFO - Running setup.py install for manifestparser 05:43:17 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 05:43:17 INFO - Running setup.py install for mozcrash 05:43:17 INFO - Running setup.py install for mozdebug 05:43:18 INFO - Running setup.py install for mozdevice 05:43:18 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 05:43:18 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 05:43:18 INFO - Running setup.py install for mozfile 05:43:18 INFO - Running setup.py install for mozhttpd 05:43:18 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 05:43:18 INFO - Running setup.py install for mozinfo 05:43:18 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 05:43:18 INFO - Running setup.py install for mozInstall 05:43:18 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 05:43:18 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 05:43:18 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 05:43:18 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 05:43:18 INFO - Running setup.py install for mozleak 05:43:18 INFO - Running setup.py install for mozlog 05:43:18 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 05:43:18 INFO - Running setup.py install for moznetwork 05:43:18 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 05:43:18 INFO - Running setup.py install for mozprocess 05:43:18 INFO - Running setup.py install for mozprofile 05:43:19 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 05:43:19 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 05:43:19 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 05:43:19 INFO - Running setup.py install for mozrunner 05:43:19 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 05:43:19 INFO - Running setup.py install for mozscreenshot 05:43:19 INFO - Running setup.py install for moztest 05:43:19 INFO - Running setup.py install for mozversion 05:43:19 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 05:43:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:43:19 INFO - Cleaning up... 05:43:19 INFO - Return code: 0 05:43:19 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:43:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:19 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fd1d8364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b60e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1744e30>, '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 0x1745d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17461c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', '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 05:43:19 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 05:43:19 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 05:43:19 INFO - Using env: {'DISPLAY': ':0', 05:43:19 INFO - 'HOME': '/home/cltbld', 05:43:19 INFO - 'LANG': 'en_US.UTF-8', 05:43:19 INFO - 'LANGUAGE': 'en_US:en', 05:43:19 INFO - 'LOGNAME': 'cltbld', 05:43:19 INFO - 'MAIL': '/var/mail/cltbld', 05:43:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:19 INFO - 'MOZ_NO_REMOTE': '1', 05:43:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:19 INFO - 'NO_EM_RESTART': '1', 05:43:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:19 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:19 INFO - 'SHELL': '/bin/bash', 05:43:19 INFO - 'SHLVL': '1', 05:43:19 INFO - 'TERM': 'linux', 05:43:19 INFO - 'TMOUT': '86400', 05:43:19 INFO - 'USER': 'cltbld', 05:43:19 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', 05:43:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:19 INFO - '_': '/tools/buildbot/bin/python'} 05:43:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:43:19 INFO - Running setup.py (path:/tmp/pip-CpM45W-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 05:43:19 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)) 05:43:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:43:19 INFO - Running setup.py (path:/tmp/pip-1_Dc88-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 05:43:19 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)) 05:43:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:43:19 INFO - Running setup.py (path:/tmp/pip-b8nkHX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 05:43:19 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)) 05:43:19 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:43:19 INFO - Running setup.py (path:/tmp/pip-kiB1Mx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 05:43:20 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)) 05:43:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 05:43:20 INFO - Running setup.py (path:/tmp/pip-EtyZto-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 05:43:20 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)) 05:43:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:43:20 INFO - Running setup.py (path:/tmp/pip-1QcCW2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 05:43:20 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)) 05:43:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:43:20 INFO - Running setup.py (path:/tmp/pip-viqk6j-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 05:43:20 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)) 05:43:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:43:20 INFO - Running setup.py (path:/tmp/pip-vXWa9n-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 05:43:20 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)) 05:43:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 05:43:20 INFO - Running setup.py (path:/tmp/pip-lIJNtT-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 05:43:20 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)) 05:43:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 05:43:20 INFO - Running setup.py (path:/tmp/pip-YU9v_L-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 05:43:20 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)) 05:43:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:43:20 INFO - Running setup.py (path:/tmp/pip-gVZHQX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 05:43:20 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)) 05:43:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:43:20 INFO - Running setup.py (path:/tmp/pip-L4vhpM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 05:43:20 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)) 05:43:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:43:20 INFO - Running setup.py (path:/tmp/pip-CNBDhO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 05:43:20 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)) 05:43:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:43:20 INFO - Running setup.py (path:/tmp/pip-3cbhLg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 05:43:20 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)) 05:43:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:43:20 INFO - Running setup.py (path:/tmp/pip-GkLNpx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 05:43:20 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)) 05:43:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 05:43:20 INFO - Running setup.py (path:/tmp/pip-5YKPQc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 05:43:20 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)) 05:43:20 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 05:43:20 INFO - Running setup.py (path:/tmp/pip-tkU3jX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 05:43:20 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)) 05:43:20 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)) 05:43:20 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)) 05:43:20 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)) 05:43:20 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)) 05:43:20 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)) 05:43:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 05:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:22 INFO - Downloading blessings-1.6.tar.gz 05:43:22 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 05:43:22 INFO - Installing collected packages: blessings 05:43:22 INFO - Running setup.py install for blessings 05:43:22 INFO - Successfully installed blessings 05:43:22 INFO - Cleaning up... 05:43:23 INFO - Return code: 0 05:43:23 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 05:43:23 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:43:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:43:23 INFO - Reading from file tmpfile_stdout 05:43:23 INFO - Current package versions: 05:43:23 INFO - argparse == 1.2.1 05:43:23 INFO - blessings == 1.6 05:43:23 INFO - blobuploader == 1.2.4 05:43:23 INFO - docopt == 0.6.1 05:43:23 INFO - manifestparser == 1.1 05:43:23 INFO - mozInstall == 1.12 05:43:23 INFO - mozcrash == 0.16 05:43:23 INFO - mozdebug == 0.1 05:43:23 INFO - mozdevice == 0.47 05:43:23 INFO - mozfile == 1.2 05:43:23 INFO - mozhttpd == 0.7 05:43:23 INFO - mozinfo == 0.9 05:43:23 INFO - mozleak == 0.1 05:43:23 INFO - mozlog == 3.1 05:43:23 INFO - moznetwork == 0.27 05:43:23 INFO - mozprocess == 0.22 05:43:23 INFO - mozprofile == 0.27 05:43:23 INFO - mozrunner == 6.11 05:43:23 INFO - mozscreenshot == 0.1 05:43:23 INFO - mozsystemmonitor == 0.0 05:43:23 INFO - moztest == 0.7 05:43:23 INFO - mozversion == 1.4 05:43:23 INFO - psutil == 3.1.1 05:43:23 INFO - requests == 1.2.3 05:43:23 INFO - wsgiref == 0.1.2 05:43:23 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 05:43:23 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')]} 05:43:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:23 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')]} 05:43:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:23 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 0x7fd1d8364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b60e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1744e30>, '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 0x1745d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17461c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', '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 05:43:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--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 05:43:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --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 05:43:23 INFO - Using env: {'DISPLAY': ':0', 05:43:23 INFO - 'HOME': '/home/cltbld', 05:43:23 INFO - 'LANG': 'en_US.UTF-8', 05:43:23 INFO - 'LANGUAGE': 'en_US:en', 05:43:23 INFO - 'LOGNAME': 'cltbld', 05:43:23 INFO - 'MAIL': '/var/mail/cltbld', 05:43:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:23 INFO - 'MOZ_NO_REMOTE': '1', 05:43:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:23 INFO - 'NO_EM_RESTART': '1', 05:43:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:23 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:23 INFO - 'SHELL': '/bin/bash', 05:43:23 INFO - 'SHLVL': '1', 05:43:23 INFO - 'TERM': 'linux', 05:43:23 INFO - 'TMOUT': '86400', 05:43:23 INFO - 'USER': 'cltbld', 05:43:23 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', 05:43:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:23 INFO - '_': '/tools/buildbot/bin/python'} 05:43:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:23 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)) 05:43:23 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)) 05:43:23 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)) 05:43:23 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)) 05:43:23 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)) 05:43:23 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)) 05:43:23 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)) 05:43:23 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)) 05:43:23 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)) 05:43:23 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)) 05:43:23 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)) 05:43:23 INFO - Cleaning up... 05:43:23 INFO - Return code: 0 05:43:23 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 05:43:23 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')]} 05:43:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:23 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')]} 05:43:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:23 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1d8364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b60e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1744e30>, '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 0x1745d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17461c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', '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 05:43:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 05:43:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:43:23 INFO - Using env: {'DISPLAY': ':0', 05:43:23 INFO - 'HOME': '/home/cltbld', 05:43:23 INFO - 'LANG': 'en_US.UTF-8', 05:43:23 INFO - 'LANGUAGE': 'en_US:en', 05:43:23 INFO - 'LOGNAME': 'cltbld', 05:43:23 INFO - 'MAIL': '/var/mail/cltbld', 05:43:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:23 INFO - 'MOZ_NO_REMOTE': '1', 05:43:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:23 INFO - 'NO_EM_RESTART': '1', 05:43:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:23 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:23 INFO - 'SHELL': '/bin/bash', 05:43:23 INFO - 'SHLVL': '1', 05:43:23 INFO - 'TERM': 'linux', 05:43:23 INFO - 'TMOUT': '86400', 05:43:23 INFO - 'USER': 'cltbld', 05:43:23 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', 05:43:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:23 INFO - '_': '/tools/buildbot/bin/python'} 05:43:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:23 INFO - Downloading/unpacking jsonschema 05:43:23 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 05:43:23 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 05:43:23 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 05:43:23 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 05:43:23 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 05:43:23 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 05:43:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:43:25 INFO - Downloading/unpacking functools32 (from jsonschema) 05:43:25 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 05:43:25 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 05:43:25 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 05:43:25 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 05:43:25 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 05:43:25 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 05:43:25 INFO - Downloading functools32-3.2.3-2.tar.gz 05:43:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 05:43:25 INFO - warning: no files found matching '*.txt' 05:43:25 INFO - no previously-included directories found matching 'build' 05:43:25 INFO - no previously-included directories found matching 'dist' 05:43:25 INFO - no previously-included directories found matching '.git*' 05:43:25 INFO - Installing collected packages: jsonschema, functools32 05:43:25 INFO - Running setup.py install for functools32 05:43:25 INFO - warning: no files found matching '*.txt' 05:43:25 INFO - no previously-included directories found matching 'build' 05:43:25 INFO - no previously-included directories found matching 'dist' 05:43:25 INFO - no previously-included directories found matching '.git*' 05:43:25 INFO - Successfully installed jsonschema functools32 05:43:25 INFO - Cleaning up... 05:43:25 INFO - Return code: 0 05:43:25 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 05:43:25 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')]} 05:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:43:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:25 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')]} 05:43:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:43:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:43:25 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd1d8364f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e88a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16b60e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1744e30>, '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 0x1745d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17461c0>, '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': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', '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 05:43:25 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 05:43:25 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:43:25 INFO - Using env: {'DISPLAY': ':0', 05:43:25 INFO - 'HOME': '/home/cltbld', 05:43:25 INFO - 'LANG': 'en_US.UTF-8', 05:43:25 INFO - 'LANGUAGE': 'en_US:en', 05:43:25 INFO - 'LOGNAME': 'cltbld', 05:43:25 INFO - 'MAIL': '/var/mail/cltbld', 05:43:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:25 INFO - 'MOZ_NO_REMOTE': '1', 05:43:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:25 INFO - 'NO_EM_RESTART': '1', 05:43:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:25 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:25 INFO - 'SHELL': '/bin/bash', 05:43:25 INFO - 'SHLVL': '1', 05:43:25 INFO - 'TERM': 'linux', 05:43:25 INFO - 'TMOUT': '86400', 05:43:25 INFO - 'USER': 'cltbld', 05:43:25 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', 05:43:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:25 INFO - '_': '/tools/buildbot/bin/python'} 05:43:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:43:26 INFO - Downloading/unpacking flake8 05:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:27 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:43:27 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:43:27 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 05:43:27 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 05:43:27 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 05:43:27 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 05:43:27 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 05:43:27 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 05:43:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:43:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:28 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:43:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:43:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 05:43:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:43:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:43:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:43:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:43:28 INFO - no previously-included directories found matching 'docs/_build' 05:43:28 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:43:28 INFO - Running setup.py install for pep8 05:43:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:43:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:43:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:43:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:43:28 INFO - no previously-included directories found matching 'docs/_build' 05:43:28 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 05:43:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:43:28 INFO - Cleaning up... 05:43:28 INFO - Return code: 0 05:43:28 INFO - Running post-action listener: _resource_record_post_action 05:43:28 INFO - Running post-action listener: _start_resource_monitoring 05:43:28 INFO - Starting resource monitoring. 05:43:28 INFO - ##### 05:43:28 INFO - ##### Running install step. 05:43:28 INFO - ##### 05:43:28 INFO - Running pre-action listener: _resource_record_pre_action 05:43:28 INFO - Running main action method: install 05:43:28 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 05:43:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 05:43:29 INFO - Reading from file tmpfile_stdout 05:43:29 INFO - Detecting whether we're running mozinstall >=1.0... 05:43:29 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 05:43:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 05:43:29 INFO - Reading from file tmpfile_stdout 05:43:29 INFO - Output received: 05:43:29 INFO - Usage: mozinstall [options] installer 05:43:29 INFO - Options: 05:43:29 INFO - -h, --help show this help message and exit 05:43:29 INFO - -d DEST, --destination=DEST 05:43:29 INFO - Directory to install application into. [default: 05:43:29 INFO - "/builds/slave/test-pgo"] 05:43:29 INFO - --app=APP Application being installed. [default: firefox] 05:43:29 INFO - mkdir: /builds/slave/test-pgo/build/application 05:43:29 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 05:43:29 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 05:43:43 INFO - Reading from file tmpfile_stdout 05:43:43 INFO - Output received: 05:43:43 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 05:43:43 INFO - Running post-action listener: _resource_record_post_action 05:43:43 INFO - ##### 05:43:43 INFO - ##### Running run-tests step. 05:43:43 INFO - ##### 05:43:43 INFO - Running pre-action listener: _resource_record_pre_action 05:43:43 INFO - Running main action method: run_tests 05:43:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 05:43:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 05:43:43 INFO - Python 2.7.3 05:43:43 INFO - Return code: 0 05:43:43 INFO - grabbing minidump binary from tooltool 05:43:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:43:43 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1744e30>, '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 0x1745d30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17461c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 05:43:43 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test-pgo/build 05:43:43 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test-pgo/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:43:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:43:43 INFO - Return code: 0 05:43:43 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 05:43:43 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 05:43:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 05:43:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 05:43:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 05:43:43 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 05:43:43 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 05:43:44 INFO - Return code: 0 05:43:44 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 05:43:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-010 --webServer localhost 05:43:44 INFO - Using env: {'DISPLAY': ':0', 05:43:44 INFO - 'HOME': '/home/cltbld', 05:43:44 INFO - 'LANG': 'en_US.UTF-8', 05:43:44 INFO - 'LANGUAGE': 'en_US:en', 05:43:44 INFO - 'LOGNAME': 'cltbld', 05:43:44 INFO - 'MAIL': '/var/mail/cltbld', 05:43:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:43:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 05:43:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:43:44 INFO - 'MOZ_NO_REMOTE': '1', 05:43:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 05:43:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:43:44 INFO - 'NO_EM_RESTART': '1', 05:43:44 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:43:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 05:43:44 INFO - 'PWD': '/builds/slave/test-pgo', 05:43:44 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 05:43:44 INFO - 'SHELL': '/bin/bash', 05:43:44 INFO - 'SHLVL': '1', 05:43:44 INFO - 'TERM': 'linux', 05:43:44 INFO - 'TMOUT': '86400', 05:43:44 INFO - 'USER': 'cltbld', 05:43:44 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157', 05:43:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:43:44 INFO - '_': '/tools/buildbot/bin/python'} 05:43:44 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--webServer', 'localhost'] with output_timeout 3600 05:43:45 INFO - mozversion INFO | application_buildid: 20160227031625 05:43:45 INFO - mozversion INFO | application_changeset: f1a50b394664670e1b978b805ad8da102f6a4b3b 05:43:45 INFO - mozversion INFO | application_display_name: Nightly 05:43:45 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:43:45 INFO - mozversion INFO | application_name: Firefox 05:43:45 INFO - mozversion INFO | application_remotingname: firefox 05:43:45 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:43:45 INFO - mozversion INFO | application_vendor: Mozilla 05:43:45 INFO - mozversion INFO | application_version: 45.0 05:43:45 INFO - mozversion INFO | platform_buildid: 20160227031625 05:43:45 INFO - mozversion INFO | platform_changeset: f1a50b394664670e1b978b805ad8da102f6a4b3b 05:43:45 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:43:45 INFO - mozversion INFO | platform_version: 45.0 05:43:45 INFO - 2016-02-27 05:43:45,127 DEBUG : using testdate: 1456580625 05:43:45 INFO - 2016-02-27 05:43:45,127 DEBUG : actual date: 1456580625 05:43:45 INFO - 2016-02-27 05:43:45,174 INFO : starting webserver on 'localhost:45620' 05:43:45 INFO - 2016-02-27 05:43:45,175 INFO : Starting test suite talos-linux64-ix-010 05:43:45 INFO - 2016-02-27 05:43:45,175 INFO : Starting test dromaeo_css 05:43:45 INFO - 2016-02-27 05:43:45,175 DEBUG : operating with platform_type : linux_ 05:43:45 INFO - 2016-02-27 05:43:45,176 INFO : Initialising browser for dromaeo_css test... 05:43:45 INFO - 2016-02-27 05:43:45,189 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuBXA9N/profile http://localhost:45620/getInfo.html 05:43:49 INFO - 2016-02-27 05:43:49,637 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 05:43:49 INFO - 2016-02-27 05:43:49,637 DEBUG : BROWSER_OUTPUT: colorDepth:24 05:43:49 INFO - 2016-02-27 05:43:49,638 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 05:43:49 INFO - 2016-02-27 05:43:49,638 DEBUG : BROWSER_OUTPUT: __metrics 05:43:49 INFO - 2016-02-27 05:43:49,835 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:43:51 INFO - 2016-02-27 05:43:51,063 INFO : Browser initialized. 05:43:51 INFO - 2016-02-27 05:43:51,063 INFO : Running cycle 1/1 for dromaeo_css test... 05:43:51 INFO - 2016-02-27 05:43:51,064 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpuBXA9N/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 05:44:02 INFO - 2016-02-27 05:44:02,741 DEBUG : BROWSER_OUTPUT: RSS: Main: 133697536 05:44:02 INFO - 2016-02-27 05:44:02,741 DEBUG : BROWSER_OUTPUT: 05:44:53 INFO - 2016-02-27 05:44:53,467 DEBUG : BROWSER_OUTPUT: 1456580693467 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] 05:47:01 INFO - 2016-02-27 05:47:01,442 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45620/tests/dromaeo/cssquery-ext.html) 05:47:02 INFO - 2016-02-27 05:47:02,709 DEBUG : BROWSER_OUTPUT: RSS: Main: 262914048 05:47:02 INFO - 2016-02-27 05:47:02,709 DEBUG : BROWSER_OUTPUT: 05:50:04 INFO - 2016-02-27 05:50:04,866 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/dromaeo/cssquery-ext.html (next: http://localhost:45620/tests/dromaeo/cssquery-jquery.html) 05:50:05 INFO - 2016-02-27 05:50:05,428 DEBUG : BROWSER_OUTPUT: RSS: Main: 214740992 05:50:05 INFO - 2016-02-27 05:50:05,428 DEBUG : BROWSER_OUTPUT: 05:53:07 INFO - 2016-02-27 05:53:07,242 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45620/tests/dromaeo/cssquery-mootools.html) 05:53:07 INFO - 2016-02-27 05:53:07,817 DEBUG : BROWSER_OUTPUT: RSS: Main: 211742720 05:53:07 INFO - 2016-02-27 05:53:07,817 DEBUG : BROWSER_OUTPUT: 05:56:09 INFO - 2016-02-27 05:56:09,513 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45620/tests/dromaeo/cssquery-prototype.html) 05:56:10 INFO - 2016-02-27 05:56:10,087 DEBUG : BROWSER_OUTPUT: RSS: Main: 198725632 05:56:10 INFO - 2016-02-27 05:56:10,087 DEBUG : BROWSER_OUTPUT: 05:59:12 INFO - 2016-02-27 05:59:12,487 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45620/tests/dromaeo/cssquery-yui.html) 05:59:13 INFO - 2016-02-27 05:59:13,055 DEBUG : BROWSER_OUTPUT: RSS: Main: 200658944 05:59:13 INFO - 2016-02-27 05:59:13,055 DEBUG : BROWSER_OUTPUT: 06:02:15 INFO - 2016-02-27 06:02:15,264 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/dromaeo/cssquery-yui.html (next: http://localhost:45620/tests/dromaeo/cssquery-dojo.html) 06:02:15 INFO - 2016-02-27 06:02:15,511 DEBUG : BROWSER_OUTPUT: RSS: Main: 243429376 06:02:15 INFO - 2016-02-27 06:02:15,512 DEBUG : BROWSER_OUTPUT: 06:02:15 INFO - 2016-02-27 06:02:15,513 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:02:15 INFO - 2016-02-27 06:02:15,514 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:02:15 INFO - 2016-02-27 06:02:15,514 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:02:15 INFO - 2016-02-27 06:02:15,514 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:02:15 INFO - 2016-02-27 06:02:15,518 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3279.9672003279966;3331.6335203127637;3362.6973572378497;3363.5963684357866;3380.391529524685;5476;5501.037318469267;5504.0918248489;5504.22116616884;5515.365732940713;5517.5585953123755;5530.308711411074;5531.308586426687;5542.140968149944;5553.666779993203;4946.826861059863;5038.370203724535;5042.319286896277;5049.570786483153;5134.922976155361;5054.721990290533;5066.949330506703;5108.055009823176;5110.744462776865;5115.616328775357;4992.700437973715;5064.493550644943;5075.467075957026;5120.871978200537;5127.897442051175;5144.331236939192;5168.2764412982215;5169.689818610857;5173.353330833627;5202.479752024805;4901.583365413944;5079.593632509391;5080.644354895159;5144.948550514484;5156.716380599066;5213.191955246905;5228.607854410934;5267.736613169326;5315.0432922074015;5332.653377530487;5892.351841297454;5966.224390829208;5991.490723288525;6001.3098493672915;6005.009173486414;5912.526997840162;6006.669633170174;6016.157737916695;6034.456898879078;6037.486813620848;5458.563314934796;5459.208414779874;5536.363318218376;5553.055980483327;5565.41563135873;6002.279726432856;6017.187679663277;6021.909671354933;6022.247219097613;6091.14723938649;5724.055530837449;5895.115732640138;5905.645661260252;5909.527237820964;5915.40845915405;5903.085021821625;5930.673812940329;5965.224520812267;5970.194023806817;5994.490468310155;5827.912581311284;5925.851853703605;5961.463468287875;5981.192539007266;5981.730822113015;5703.57183817772;5841.970790146022;5939;5958.493528050078;5961.254843144607;4480.708753930984;4547.545245475492;4550.817967281272;4558.772061396983;4564.475085365226;4097.795110244476;4113.753174809521;4118.402831589463;4118.670506359484;4135.648469880034;5529.474699903502;5638.464345887134;5673.574481913873;5674.687892165888;5680.375158732536;5903.35063143054;5935.554833387513;5960.880782384328;5972.790952316648;5995.2206213192;5614.157876318503;5706.400827913009;5714.914276285859;5728.713564321767;5733.369329373849;5131.409887862869;5132.0762262792305;5206.97396513015;5209.270702101709;5228.137357335997;6047.667378294148;6102.631080447795;6102.8701222366735;6110.168645843782;6158.969205153946;5911.556633252438;5912.379200183918;5916.615419997686;5919.348871624204;5941.762329506858;5900.586958912835;5931.7627294907725;6027.6986150693165;6087.66517841523;6094.573379863367;5920.585559010828;5949.940500594938;5954.46409823118;5962.731677074629;5963.761449541969;5973.49225315853;5975.40245975399;5975.731092100779;5976.701164941823;6037.78867739627;5685.573581981451;5784.334801497882;5829.6793676346515;5896.410358964069;5949.851253718519;5842.211301474246;5847.766089356378;5858.384869588632;5861.443162899518;5862.648241105548;5741;5745.195672605755;5745.626534275259;5751.482366587027;5756.021476348947;5762.1068734346245;5786.681732504752;5789.8552536186935;5866.6186697864505;5900.380460051632;5787.131930210502;5796.478316951495;5798.681072540882;5804.100364443518;5805.216295800013 06:02:15 INFO - 2016-02-27 06:02:15,518 DEBUG : BROWSER_OUTPUT: |1;ext.html;588.393954227146;634.771482266384;642.178012144455;642.4828013449178;643.0868167202575;32925.83537082317;41263.79368103159;41843.79078104605;42050;42108.368374474405;46108.76945615267;46653.30020049702;46711.766441167834;46738.76630616851;46797.29804052942;8000.519968801861;8099.838003239932;8963.282937364995;9146.908530914705;9148.542572871364;8104.027516697975;8203.26170644642;10557.78884422311;10726.463676816167;10768.407737574431;10390.324628899136;10434.582616695323;10450.58197672096;10452.477376131163;10457.633982810603;10533.947330263376;10564.947175264113;10620.150387968944;10645.308054976402;10646.946765266202;10293.382397056126;10404.843927341097;10406.791864162675;10412.32319899204;10414;43026.56973430257;43475;43499.56500435018;43554;43605.78197109026;3241.5299781531544;3305.074579117851;3348.430766769655;3390.932181356384;3678.889633311005;31751.52372714411;32063.6793632063;32118;32136;32758.508622370682;3799.3920972644246;3908.3160446921675;3920.666743326821;3959.742616729903;3975.20495900821;277382;278992;279608;279622.6018869913;280206;57106.7144664274;58272.417275827545;61670.69164654238;61833;62984.37015629877;60753.69623151856;60933.69533152306;61269;61279.38720612826;61361.38638613646;17596.912015440095;17608.559786005197;17714.645707085787;17791.822081779275;17798.91100544489;12217.816732748837;12219.633410997685;12226.816597751042;12277.570285039981;12418.81371779424;20213.595728085354;20507.28224512135;20524.281650142173;20547.38357849267;20547.97260136987;7711.419159072377;7846.136924938252;7929.563873986986;7989.161138080534;8012.27889489937;1129.7909886670993;1145.8911403416662;1149.0290704354807;1154.4862536171322;1205.3718307893305;29460.410791784045;29859.552106718416;30088.548671769942;30240.84879575588;30291.545626815616;1202.22456515547;1235.6601934468092;1253.354522420954;1282.6793301674581;1292.8060790881254;13422.395992180376;15033.51276783966;15535.586195903259;15643.608909777347;16106.919465402832;336458;338848;338883;340564;341758;54942.17586736202;55071;55413.72293138589;55428.44571554314;55607.443925561034;1298.6753311672082;1377.0842389810864;1451.6443471349587;1485.8514148585054;1486.829014663306;27591.86204068967;29196.124116276547;29236.26909327284;29545.11364659064;30101.247468813453;41491.58508414877;41762.58237417587;41771.16457670891;43022;43105.353419698105;220233;221384;221637.13451461762;222137;222439;33111.50332745059;33344.333113338085;33509.32981340408;33760.3247935035;33856.15359615931;43262;44103.33844992264;44565.33152002658;44809.775951120035;44915;35629.28741425209;36247.818760906026;36660.81669591635;36911.63088369082;37067.814660926524;38235.80882095572;38818.41772373473;39750.801245993585;41480.58519414888;42587.26797059923;37521.81239093787;37718.62281377151;37843.243135136596;37894;38517.80741096276;32776;32795.34409311753;33950;36529.26941461209;37467.43798842965;26115.216543503724;26453.470930581665;26587.734122659298;28076.719232808227;28369.29076773097 06:02:15 INFO - 2016-02-27 06:02:15,521 DEBUG : BROWSER_OUTPUT: |2;jquery.html;369.3739112204814;370.46282889809765;378.7935575111564;386.55932237249533;389.97075219358544;14637.341319640615;17050.31798728051;17351;19541.21835126595;19617.11722972466;11952.462139203735;12238.938805305983;12245.081618878576;18864.717029244573;19095.236190552398;4083.1017176221185;5118.257852611378;5237.135872581039;5237.47625237477;5240.082985477527;4161.583841615845;5591.720413979305;5762.02045652238;5765.682887441189;5766.308043034821;5593.188987596796;5689.374168841424;5706.65760054396;5721.027425337703;5728.42715728428;5584.91622625661;5623.437656234385;5651.40660230676;5651.576131790132;5656.066748986413;5384.730763461811;5429.728513574306;5439.755211015514;5536.944630553683;5618;18300.90849545757;21383.25158619441;21461.570768584697;21640.458988525257;21994.34016979493;2234.72065991751;2266.0709109265285;2267.251806903718;2281.3726225288015;2377.869217193054;10313;10369.222308326905;10435.79128417435;10523.526441310083;10698.411587362692;1966.2921348314737;1975.9407217783346;1991.1836147179731;2067.3074520035807;2072.046858445117;64721;65319.3468065318;65472.3452765471;67077;67592;10952.56189752401;11209.495572699248;11254.324740515594;11274.887251127548;11329;14784.704305914036;14827.629309267355;14912.552623421314;14942.70114597702;14943.77584336214;8250.216229458192;8333.624986875606;8572.442791218551;8628.913710862937;8659.610317535726;8368.790780230422;8401.579921003926;8402.537860417735;8477.74566762998;8484.830303393897;4470.240059189946;4624.352590637313;4703.435587729495;4746.098241334135;4774.474807771142;1911.4647898588416;1972.9112189951486;1979.3270288102115;1986.3941497843007;1996.8003199679915;963.9228861691048;1023.5240613114937;1060.3584831177182;1104.8839871813504;1117.620009196877;17548.64902701939;17738;17799.46601601954;17854;18354.632907342046;1013.2350075692952;1024.626011505795;1054.4622242656146;1099.834577726115;1115.4924509348227;10214.38713677167;10309.690709278733;10333.431661258552;10338.12125969286;10358.430286334324;580839;585226;586492;588102;597173;74945.25054749491;82890;83892;84320.15679843123;84542.57728711318;1235.1415766042694;1235.4131787400913;1266.4301064520819;1273;1295.1581472043247;15336.463223787114;15825.446109386115;16291.511254662379;16397.42609008727;16418.097004664858;33708.157296067795;34197.48703769396;34214;34228.48657270093;35161.12097197488;41518.1696366077;43595.56404436042;44708.32937506005;44895.32657010082;45119.548804511534;9474.905250947402;9631.470269135265;9694.5152742364;9751.171150452292;9760.072793084646;13577.77799998005;13854.861451385761;14342.641433964234;14514.927425362806;14749;14042.508512202025;15229.466968656045;15803.209839507748;15845.286962086504;15976.20118994069;14934.551963441114;15439.536813895153;15504.224788760292;15900.284487197872;16030.358785648443;16159;16176.595585110466;16392.83607163961;16612.833871661613;16631.33474661;7914.525128492309;8946.910530894607;10011;10084.344517606332;10257.794844103226;10488.528016239441;12458.501659933501;15648.608784780472;15909.28408221656;15926.362945482051 06:02:15 INFO - 2016-02-27 06:02:15,523 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1523.8323784383722;1529.8393668664783;1715.5368050626323;1788.3383148235155;1835.220031486618;7275.054242948416;7537.773866783991;7543.811404714871;7545.1700312965595;7550.962245188766;7673.040869891263;7740.612969351524;7761.922380776191;7763.922360776391;7770.61146942652;2264.615015447378;2270.738865030522;2312.6531020346915;2313.0169677886915;2315.1549684365255;2327.3832434404894;2330.5222429402015;2337.824663150262;2352.45893444507;2355.9057637694473;2031.5530583271657;2074.3258441006733;2234.910603575855;2284.6116160252795;2348.201611452106;2289.1987804268456;2306.1467257114805;2317.2468947592015;2328.557574060923;2329.0800133947164;2227.3206671965045;2277.39648993017;2280.737715162761;2282.2468585366787;2284.0407029047838;6786.185657721105;6966.860662786716;7203.243659415791;7348.669309881068;7364;4840.249761287007;4903.828366007208;4904.0437114762635;4907.803687852482;4908.680935739201;3047.832369219692;3047.8780848766023;3067.8619462124257;3071.2475443516482;3154.952675709866;1035.140833108518;1061.9150467962545;1082.7401423658343;1092.4919912240766;1100.5817789240118;10294.485275736184;10416.166706663449;10417.218708596809;10423.166146708245;10433.42616156111;4152.709310348247;4199.76901270427;4268.957310426856;4300.397944287803;4300.7634580097765;8530.445521041112;8546.145385461405;8554.35842311829;8565.229129378262;8569.742907712654;4632.096741135513;4676.392069031004;4677.7193368398;4687.296905464208;4690.9765451172525;4289.227938970953;4306.784660767012;4310.073334233155;4311.051568654891;4474.37358769766;3489.94765078524;3496.5279687242405;3511.0871173495075;3522.43641017436;3545.982270088685;1108.1799468393492;1114.0530549033258;1118.2507719827736;1121.041509509374;1121.2095472691733;1233.0443905972943;1258.4651523102646;1273.8726127387372;1287.7875150600235;1288.1176394170025;7434.1079070510805;7438.293362130589;7472.103347598323;7602.163761986177;7633.732819351296;1165.4638866121509;1169.6900321414832;1173.7065733566608;1182.1193211057646;1202.9639110826515;5790.507806836382;5833.649981001068;5899.587028908021;5906.734196961147;5920.348761636216;8331.916680833236;9105;9199.908000920039;9233.307501937381;9236.168744812865;8782.736517904474;8922.73231803047;8938.821223575622;8971.910280897107;9090.545472726337;7301;7364;7425.7029718810645;7443.2184620615135;7475.775726728207;7326.816829579303;7376.446766492427;7411.666475008746;7449.590272534846;7497.62511874415;8135.83728325442;8344.7913802153;8365.539895305817;8415.853957240864;8417.83164336722;8478.576071196292;8522.36082293818;8680;8689.956550217208;8761.649534018568;6930.06444130036;7038.577685338896;7051.224365319708;7054.4003759681;7072.186698529529;7310.853782924205;7350.154732205865;7357.963210183915;7366.337029667356;7604.391648668204;7029.156501219886;7059;7062.788116356517;7178.60517671533;7217.458690598121;7028.753993610199;7029.61337126463;7029.824254393681;7117.572945623279;7199.892001620085;6943.618101004493;6968;6973.3375329343635;6975.720971161097;6979.336962988508;6375.840603984938;6439.517036222208;6460.806175814733;6464.0627109069865;6468.6442245676935;6748.527603067738;6794.694238759169;6796.830079248059;6800.965995169992;6812.863742725019 06:02:15 INFO - 2016-02-27 06:02:15,524 DEBUG : BROWSER_OUTPUT: |4;prototype.html;365.1583100952305;369.6488336080724;370.2779579819353;372.5864290637393;381.2793819680802;5872.911906321408;5939.198208241886;6006.249218847644;6045.123457098718;6066.059760737087;5813.244278243833;5965.761369545224;5989.460948514633;6008;6014.669193194372;4718.150732868083;4720.197566413718;4723.78742956566;4724.834630787915;4728.314394412808;4528.43394575678;4592.931106033396;4626.051659409813;4726.338312636233;4738.099761045391;4660.091282199973;4660.4174478190225;4672.3925889634465;4684.578387945085;4689.765511724418;4660.86017419478;4673.626109911216;4676.929846052312;4694.483606803249;4694.624430045588;4172.812223449952;4173.791310434466;4179.03882107114;4180.686448516343;4181.937270940938;5600.439956004408;5689.175069614924;5689.886202275973;5702.714864256812;5707.5148612368;5460.945390546083;5466.562674985991;5468.91796623051;5471.7264136793;5475.917861232085;6079.817605471843;6333.809985700436;6341.080543321239;6347.1114044033875;6351.11084448173;4464.464264288274;4531.139083574143;4581.152486789954;4584.335271385664;4588.265877459624;6536.934630653727;6559.737610495575;6561.80314590563;6562.310957349458;6563.048357988113;4699.036697477009;4710.36410084641;4716.57550820421;4717.150912835654;4721.032188401369;4688.062387522509;4768.642351823627;4776.283557466408;4783.4499032611;4793.640476964241;5411.945880541223;5422.48486393792;5425.511703946664;5433.592480563973;5437.4562543745305;6410.1987251593555;6411.51913606472;6418.518611104186;6426.646534440557;6426.839329016812;1683.7306031034975;1711.1273250642262;1711.2299465240692;1711.3924556782413;1712.5547357686842;1114.1532435349193;1126.0709914320719;1136.4431428599926;1141.149843366697;1202.903767698582;6133.815985520441;6155.169052177898;6160.168377269101;6165.198524191826;6165.660888651078;6489.545731798823;6507.154069970874;6508.934910650928;6512.479001679949;6525.347465253437;6146.5390095743;6152.507799376129;6156.199694039836;6157.415045570664;6167.629942203393;3415.863365465403;3524.101354154674;3528.7353448491467;3529.982350088233;3530.1351168963256;6718.428933540704;6727.66361681914;6734.932650673529;6735.360140786715;6738.460923126138;6664.400203981665;6678.332166783282;6681.09805176295;6683.365080317362;6686.063951046857;6592;6738.292479289702;6740.595564266159;6742.190937087581;6746.494012949049;6381.744730210739;6489.837754056186;6508.446782023581;6523.18460192476;6524.380183882524;6558.672066396756;6573.112629794917;6576.539642224999;6587.934120658732;6597.076409302797;6768.289329620516;6838.350356716104;6843.931560684528;6849.794506164823;6855.485838562028;4493.820247190076;4653.53464653532;4653.674242802971;4663.696859704109;4691.460482044608;6596.340365963557;6602.966985165235;6607.405333520006;6607.636579987954;6609.239937407259;6424.4860411166055;6443.516736244706;6445.548811583331;6446.355364463516;6450.903236451363;6310.800947819899;6414.518911081781;6432.678366081771;6443.582411869381;6480.611163330215;6340.524460665376;6342.460890824332;6348.873022539431;6352.047192921099;6354.364563543609;6460.25707043696;6484.254310754323;6498.927676933253;6500.837479063061;6510.576812507172;6222.471089957405;6331.113644089739;6351.237851457855;6357.55497115216;6360.300366959538 06:02:15 INFO - 2016-02-27 06:02:15,524 DEBUG : BROWSER_OUTPUT: |5;yui.html;772.7874834420534;798.4291231769288;847.9915200847989;854.9401541892066;871.8430682477151;14301.78547321791;14496.347664355102;14602.269886505672;14644.194569298685;14678.706425871504;13866.791998120036;13951.93024034881;13965.860341396583;13967;13970.650733731662;741.4328039050131;760.6615056299948;903.6656437118243;917.1653795046509;917.5595714057257;910.640297082652;916.3860213656833;916.8624706294042;917.5228880981886;919.8758167647383;812.3947658994058;821.8109834737984;939.5865819039635;941.5715849288591;944.1266828183936;937.4984383354906;945.7068308824287;946.4415994563241;946.5551190940247;946.6639343033193;697.4734075772759;701.1586096684;702.3398005874461;702.4520873718508;705.0904333409895;13097;13120.934395327962;13242.801357979639;13251.403686834019;13260.270685112315;12592.874071259354;12691.809622855664;12823.166494177849;12929.288889111094;13002.414891329912;10796.946015269874;11289.266197697205;11395;11578.363190024545;11735.823962640567;3760.736748427611;3776.546814382264;3781.8487260509546;3804.1821008483034;3893.6301051400073;50138.24792628077;50526.989460210956;51186;51471;51509;10723.356598604109;10816.188785841094;10836.94581527103;10911.236213464981;10984.231103822656;17043.062631555134;17147.142642868057;17167.914160429118;17168.31326746941;17197.484075477754;7441.421294570754;7628.54228746277;7641.847163056819;7689.807754806175;7808.414368922354;4562.543745625411;4602.1025899949855;4602.884927876763;4687.695299805502;4698.600618947359;3374.4938259261307;3412.5734283214597;3416.9829150854584;3417.1627951152127;3423.1442139465134;608.3581821178699;613.4295105551879;622.7975907829883;636.6657504810012;637.4295005969724;641.4355367276767;681.9795406137824;700.1772916822815;742.6212631557836;760.1182628151317;9206.355555111079;9703.902960970441;9705.320627556004;9825.263105267133;9905.35615185025;559.3204256827893;702.005232768846;710.9466789990771;727.1165533876366;740.1968863782748;4289.721168124062;4332.848350307724;4385;4469.128519938581;4518.9548104518535;59325.703371482865;59334.70332648309;59931;60161.39838601558;63320.36679633237;50899.491005089476;51071.23393149106;52125.73937130366;52894.47105528895;53161.73419132957;49078.754606227456;49821.501784982414;49942.25086623704;49949.50050499521;49995.500044999084;9472.52637368115;9833.704988850346;9875.308728388944;9982.650607228712;10068.546915388972;36319.63680363268;36802;37059.62940370562;37102.257954840214;37179.25641487209;41560.584394155674;45378.54621453875;45485.545144549455;46020;46282.30576541422;11830.40847957616;12102.75794484123;12138.089643276613;12282.385880706108;12552.49790008426;12863.871361286267;12946.741065178832;13496.797548036984;13824.471109249132;14017.859821401655;12436.253824770543;12487.12590118683;12523.123381363217;12565.748685026065;12847.678808029874;12062.155649104478;12190.2076365036;12292;12300.138990270952;12623.621291361274;10660;10803.513841877291;11332.660020199408;11832.645020649394;11934.582289619822;10556.89443105559;10565.101966332948;10732.785344293226;10783.51474183648;10848;10587.4706264688;10700.732481688021;10851.240413171003;11070.39112848801;11098.2786118902 06:02:15 INFO - 2016-02-27 06:02:15,524 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:02:15 INFO - 2016-02-27 06:02:15,524 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:02:15 INFO - 2016-02-27 06:02:15,524 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,1041 06:02:15 INFO - 2016-02-27 06:02:15,525 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:02:15 INFO - 2016-02-27 06:02:15,525 DEBUG : BROWSER_OUTPUT: __startTimestamp1456581735512__endTimestamp 06:02:15 INFO - 2016-02-27 06:02:15,525 DEBUG : BROWSER_OUTPUT: 06:02:15 INFO - 2016-02-27 06:02:15,525 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:02:15 INFO - 2016-02-27 06:02:15,525 DEBUG : BROWSER_OUTPUT: Number of tests: 6 06:02:15 INFO - 2016-02-27 06:02:15,525 DEBUG : BROWSER_OUTPUT: 06:02:15 INFO - 2016-02-27 06:02:15,525 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5527.74 Median:5756.02 stddev:603.85 (10.5%) stddev-sans-first:579.38 06:02:15 INFO - 2016-02-27 06:02:15,525 DEBUG : BROWSER_OUTPUT: Values: 3280.0 3331.6 3362.7 3363.6 3380.4 5476.0 5501.0 5504.1 5504.2 5515.4 5517.6 5530.3 5531.3 5542.1 5553.7 4946.8 5038.4 5042.3 5049.6 5134.9 5054.7 5066.9 5108.1 5110.7 5115.6 4992.7 5064.5 5075.5 5120.9 5127.9 5144.3 5168.3 5169.7 5173.4 5202.5 4901.6 5079.6 5080.6 5144.9 5156.7 5213.2 5228.6 5267.7 5315.0 5332.7 5892.4 5966.2 5991.5 6001.3 6005.0 5912.5 6006.7 6016.2 6034.5 6037.5 5458.6 5459.2 5536.4 5553.1 5565.4 6002.3 6017.2 6021.9 6022.2 6091.1 5724.1 5895.1 5905.6 5909.5 5915.4 5903.1 5930.7 5965.2 5970.2 5994.5 5827.9 5925.9 5961.5 5981.2 5981.7 5703.6 5842.0 5939.0 5958.5 5961.3 4480.7 4547.5 4550.8 4558.8 4564.5 4097.8 4113.8 4118.4 4118.7 4135.6 5529.5 5638.5 5673.6 5674.7 5680.4 5903.4 5935.6 5960.9 5972.8 5995.2 5614.2 5706.4 5714.9 5728.7 5733.4 5131.4 5132.1 5207.0 5209.3 5228.1 6047.7 6102.6 6102.9 6110.2 6159.0 5911.6 5912.4 5916.6 5919.3 5941.8 5900.6 5931.8 6027.7 6087.7 6094.6 5920.6 5949.9 5954.5 5962.7 5963.8 5973.5 5975.4 5975.7 5976.7 6037.8 5685.6 5784.3 5829.7 5896.4 5949.9 5842.2 5847.8 5858.4 5861.4 5862.6 5741.0 5745.2 5745.6 5751.5 5756.0 5762.1 5786.7 5789.9 5866.6 5900.4 5787.1 5796.5 5798.7 5804.1 5805.2 06:02:15 INFO - 2016-02-27 06:02:15,525 DEBUG : BROWSER_OUTPUT: 06:02:15 INFO - 2016-02-27 06:02:15,525 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:46379.98 Median:18428.88 stddev:74091.44 (402.0%) stddev-sans-first:74219.51 06:02:15 INFO - 2016-02-27 06:02:15,526 DEBUG : BROWSER_OUTPUT: Values: 588.4 634.8 642.2 642.5 643.1 32925.8 41263.8 41843.8 42050.0 42108.4 46108.8 46653.3 46711.8 46738.8 46797.3 8000.5 8099.8 8963.3 9146.9 9148.5 8104.0 8203.3 10557.8 10726.5 10768.4 10390.3 10434.6 10450.6 10452.5 10457.6 10533.9 10564.9 10620.2 10645.3 10646.9 10293.4 10404.8 10406.8 10412.3 10414.0 43026.6 43475.0 43499.6 43554.0 43605.8 3241.5 3305.1 3348.4 3390.9 3678.9 31751.5 32063.7 32118.0 32136.0 32758.5 3799.4 3908.3 3920.7 3959.7 3975.2 277382.0 278992.0 279608.0 279622.6 280206.0 57106.7 58272.4 61670.7 61833.0 62984.4 60753.7 60933.7 61269.0 61279.4 61361.4 17596.9 17608.6 17714.6 17791.8 17798.9 12217.8 12219.6 12226.8 12277.6 12418.8 20213.6 20507.3 20524.3 20547.4 20548.0 7711.4 7846.1 7929.6 7989.2 8012.3 1129.8 1145.9 1149.0 1154.5 1205.4 29460.4 29859.6 30088.5 30240.8 30291.5 1202.2 1235.7 1253.4 1282.7 1292.8 13422.4 15033.5 15535.6 15643.6 16106.9 336458.0 338848.0 338883.0 340564.0 341758.0 54942.2 55071.0 55413.7 55428.4 55607.4 1298.7 1377.1 1451.6 1485.9 1486.8 27591.9 29196.1 29236.3 29545.1 30101.2 41491.6 41762.6 41771.2 43022.0 43105.4 220233.0 221384.0 221637.1 222137.0 222439.0 33111.5 33344.3 33509.3 33760.3 33856.2 43262.0 44103.3 44565.3 44809.8 44915.0 35629.3 36247.8 36660.8 36911.6 37067.8 38235.8 38818.4 39750.8 41480.6 42587.3 37521.8 37718.6 37843.2 37894.0 38517.8 32776.0 32795.3 33950.0 36529.3 37467.4 26115.2 26453.5 26587.7 28076.7 28369.3 06:02:15 INFO - 2016-02-27 06:02:15,527 DEBUG : BROWSER_OUTPUT: 06:02:15 INFO - 2016-02-27 06:02:15,527 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:30604.95 Median:2032.05 stddev:95947.11 (4721.7%) stddev-sans-first:96189.41 06:02:15 INFO - 2016-02-27 06:02:15,527 DEBUG : BROWSER_OUTPUT: Values: 369.4 370.5 378.8 386.6 390.0 14637.3 17050.3 17351.0 19541.2 19617.1 11952.5 12238.9 12245.1 18864.7 19095.2 4083.1 5118.3 5237.1 5237.5 5240.1 4161.6 5591.7 5762.0 5765.7 5766.3 5593.2 5689.4 5706.7 5721.0 5728.4 5584.9 5623.4 5651.4 5651.6 5656.1 5384.7 5429.7 5439.8 5536.9 5618.0 18300.9 21383.3 21461.6 21640.5 21994.3 2234.7 2266.1 2267.3 2281.4 2377.9 10313.0 10369.2 10435.8 10523.5 10698.4 1966.3 1975.9 1991.2 2067.3 2072.0 64721.0 65319.3 65472.3 67077.0 67592.0 10952.6 11209.5 11254.3 11274.9 11329.0 14784.7 14827.6 14912.6 14942.7 14943.8 8250.2 8333.6 8572.4 8628.9 8659.6 8368.8 8401.6 8402.5 8477.7 8484.8 4470.2 4624.4 4703.4 4746.1 4774.5 1911.5 1972.9 1979.3 1986.4 1996.8 963.9 1023.5 1060.4 1104.9 1117.6 17548.6 17738.0 17799.5 17854.0 18354.6 1013.2 1024.6 1054.5 1099.8 1115.5 10214.4 10309.7 10333.4 10338.1 10358.4 580839.0 585226.0 586492.0 588102.0 597173.0 74945.3 82890.0 83892.0 84320.2 84542.6 1235.1 1235.4 1266.4 1273.0 1295.2 15336.5 15825.4 16291.5 16397.4 16418.1 33708.2 34197.5 34214.0 34228.5 35161.1 41518.2 43595.6 44708.3 44895.3 45119.5 9474.9 9631.5 9694.5 9751.2 9760.1 13577.8 13854.9 14342.6 14514.9 14749.0 14042.5 15229.5 15803.2 15845.3 15976.2 14934.6 15439.5 15504.2 15900.3 16030.4 16159.0 16176.6 16392.8 16612.8 16631.3 7914.5 8946.9 10011.0 10084.3 10257.8 10488.5 12458.5 15648.6 15909.3 15926.4 06:02:15 INFO - 2016-02-27 06:02:15,527 DEBUG : BROWSER_OUTPUT: 06:02:15 INFO - 2016-02-27 06:02:15,527 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5437.00 Median:6407.68 stddev:2745.91 (42.9%) stddev-sans-first:2737.85 06:02:15 INFO - 2016-02-27 06:02:15,527 DEBUG : BROWSER_OUTPUT: Values: 1523.8 1529.8 1715.5 1788.3 1835.2 7275.1 7537.8 7543.8 7545.2 7551.0 7673.0 7740.6 7761.9 7763.9 7770.6 2264.6 2270.7 2312.7 2313.0 2315.2 2327.4 2330.5 2337.8 2352.5 2355.9 2031.6 2074.3 2234.9 2284.6 2348.2 2289.2 2306.1 2317.2 2328.6 2329.1 2227.3 2277.4 2280.7 2282.2 2284.0 6786.2 6966.9 7203.2 7348.7 7364.0 4840.2 4903.8 4904.0 4907.8 4908.7 3047.8 3047.9 3067.9 3071.2 3155.0 1035.1 1061.9 1082.7 1092.5 1100.6 10294.5 10416.2 10417.2 10423.2 10433.4 4152.7 4199.8 4269.0 4300.4 4300.8 8530.4 8546.1 8554.4 8565.2 8569.7 4632.1 4676.4 4677.7 4687.3 4691.0 4289.2 4306.8 4310.1 4311.1 4474.4 3489.9 3496.5 3511.1 3522.4 3546.0 1108.2 1114.1 1118.3 1121.0 1121.2 1233.0 1258.5 1273.9 1287.8 1288.1 7434.1 7438.3 7472.1 7602.2 7633.7 1165.5 1169.7 1173.7 1182.1 1203.0 5790.5 5833.6 5899.6 5906.7 5920.3 8331.9 9105.0 9199.9 9233.3 9236.2 8782.7 8922.7 8938.8 8971.9 9090.5 7301.0 7364.0 7425.7 7443.2 7475.8 7326.8 7376.4 7411.7 7449.6 7497.6 8135.8 8344.8 8365.5 8415.9 8417.8 8478.6 8522.4 8680.0 8690.0 8761.6 6930.1 7038.6 7051.2 7054.4 7072.2 7310.9 7350.2 7358.0 7366.3 7604.4 7029.2 7059.0 7062.8 7178.6 7217.5 7028.8 7029.6 7029.8 7117.6 7199.9 6943.6 6968.0 6973.3 6975.7 6979.3 6375.8 6439.5 6460.8 6464.1 6468.6 6748.5 6794.7 6796.8 6801.0 6812.9 06:02:15 INFO - 2016-02-27 06:02:15,527 DEBUG : BROWSER_OUTPUT: 06:02:15 INFO - 2016-02-27 06:02:15,528 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5375.77 Median:6106.82 stddev:1576.41 (25.8%) stddev-sans-first:1535.32 06:02:15 INFO - 2016-02-27 06:02:15,528 DEBUG : BROWSER_OUTPUT: Values: 365.2 369.6 370.3 372.6 381.3 5872.9 5939.2 6006.2 6045.1 6066.1 5813.2 5965.8 5989.5 6008.0 6014.7 4718.2 4720.2 4723.8 4724.8 4728.3 4528.4 4592.9 4626.1 4726.3 4738.1 4660.1 4660.4 4672.4 4684.6 4689.8 4660.9 4673.6 4676.9 4694.5 4694.6 4172.8 4173.8 4179.0 4180.7 4181.9 5600.4 5689.2 5689.9 5702.7 5707.5 5460.9 5466.6 5468.9 5471.7 5475.9 6079.8 6333.8 6341.1 6347.1 6351.1 4464.5 4531.1 4581.2 4584.3 4588.3 6536.9 6559.7 6561.8 6562.3 6563.0 4699.0 4710.4 4716.6 4717.2 4721.0 4688.1 4768.6 4776.3 4783.4 4793.6 5411.9 5422.5 5425.5 5433.6 5437.5 6410.2 6411.5 6418.5 6426.6 6426.8 1683.7 1711.1 1711.2 1711.4 1712.6 1114.2 1126.1 1136.4 1141.1 1202.9 6133.8 6155.2 6160.2 6165.2 6165.7 6489.5 6507.2 6508.9 6512.5 6525.3 6146.5 6152.5 6156.2 6157.4 6167.6 3415.9 3524.1 3528.7 3530.0 3530.1 6718.4 6727.7 6734.9 6735.4 6738.5 6664.4 6678.3 6681.1 6683.4 6686.1 6592.0 6738.3 6740.6 6742.2 6746.5 6381.7 6489.8 6508.4 6523.2 6524.4 6558.7 6573.1 6576.5 6587.9 6597.1 6768.3 6838.4 6843.9 6849.8 6855.5 4493.8 4653.5 4653.7 4663.7 4691.5 6596.3 6603.0 6607.4 6607.6 6609.2 6424.5 6443.5 6445.5 6446.4 6450.9 6310.8 6414.5 6432.7 6443.6 6480.6 6340.5 6342.5 6348.9 6352.0 6354.4 6460.3 6484.3 6498.9 6500.8 6510.6 6222.5 6331.1 6351.2 6357.6 6360.3 06:02:15 INFO - 2016-02-27 06:02:15,529 DEBUG : BROWSER_OUTPUT: 06:02:15 INFO - 2016-02-27 06:02:15,529 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14520.93 Median:4494.04 stddev:16573.03 (368.8%) stddev-sans-first:16587.36 06:02:15 INFO - 2016-02-27 06:02:15,530 DEBUG : BROWSER_OUTPUT: Values: 772.8 798.4 848.0 854.9 871.8 14301.8 14496.3 14602.3 14644.2 14678.7 13866.8 13951.9 13965.9 13967.0 13970.7 741.4 760.7 903.7 917.2 917.6 910.6 916.4 916.9 917.5 919.9 812.4 821.8 939.6 941.6 944.1 937.5 945.7 946.4 946.6 946.7 697.5 701.2 702.3 702.5 705.1 13097.0 13120.9 13242.8 13251.4 13260.3 12592.9 12691.8 12823.2 12929.3 13002.4 10796.9 11289.3 11395.0 11578.4 11735.8 3760.7 3776.5 3781.8 3804.2 3893.6 50138.2 50527.0 51186.0 51471.0 51509.0 10723.4 10816.2 10836.9 10911.2 10984.2 17043.1 17147.1 17167.9 17168.3 17197.5 7441.4 7628.5 7641.8 7689.8 7808.4 4562.5 4602.1 4602.9 4687.7 4698.6 3374.5 3412.6 3417.0 3417.2 3423.1 608.4 613.4 622.8 636.7 637.4 641.4 682.0 700.2 742.6 760.1 9206.4 9703.9 9705.3 9825.3 9905.4 559.3 702.0 710.9 727.1 740.2 4289.7 4332.8 4385.0 4469.1 4519.0 59325.7 59334.7 59931.0 60161.4 63320.4 50899.5 51071.2 52125.7 52894.5 53161.7 49078.8 49821.5 49942.3 49949.5 49995.5 9472.5 9833.7 9875.3 9982.7 10068.5 36319.6 36802.0 37059.6 37102.3 37179.3 41560.6 45378.5 45485.5 46020.0 46282.3 11830.4 12102.8 12138.1 12282.4 12552.5 12863.9 12946.7 13496.8 13824.5 14017.9 12436.3 12487.1 12523.1 12565.7 12847.7 12062.2 12190.2 12292.0 12300.1 12623.6 10660.0 10803.5 11332.7 11832.6 11934.6 10556.9 10565.1 10732.8 10783.5 10848.0 10587.5 10700.7 10851.2 11070.4 11098.3 06:02:15 INFO - 2016-02-27 06:02:15,530 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:02:15 INFO - 2016-02-27 06:02:15,530 DEBUG : BROWSER_OUTPUT: 06:02:16 INFO - 2016-02-27 06:02:16,151 INFO : Browser exited with error code: 0 06:02:16 INFO - 2016-02-27 06:02:16,167 INFO : Completed test dromaeo_css (00:18:30) 06:02:16 INFO - 2016-02-27 06:02:16,167 INFO : Starting test kraken 06:02:16 INFO - 2016-02-27 06:02:16,167 DEBUG : operating with platform_type : linux_ 06:02:16 INFO - 2016-02-27 06:02:16,167 INFO : Initialising browser for kraken test... 06:02:16 INFO - 2016-02-27 06:02:16,174 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmprdNgbo/profile http://localhost:45620/getInfo.html 06:02:20 INFO - 2016-02-27 06:02:20,223 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:02:20 INFO - 2016-02-27 06:02:20,223 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:02:20 INFO - 2016-02-27 06:02:20,223 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:02:20 INFO - 2016-02-27 06:02:20,223 DEBUG : BROWSER_OUTPUT: __metrics 06:02:20 INFO - 2016-02-27 06:02:20,360 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:02:21 INFO - 2016-02-27 06:02:21,619 INFO : Browser initialized. 06:02:21 INFO - 2016-02-27 06:02:21,619 INFO : Running cycle 1/1 for kraken test... 06:02:21 INFO - 2016-02-27 06:02:21,620 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmprdNgbo/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:02:33 INFO - 2016-02-27 06:02:33,245 DEBUG : BROWSER_OUTPUT: RSS: Main: 134086656 06:02:33 INFO - 2016-02-27 06:02:33,245 DEBUG : BROWSER_OUTPUT: 06:02:35 INFO - 2016-02-27 06:02:35,686 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45620/tests/kraken/driver.html?testName=audio-beat-detection) 06:02:36 INFO - 2016-02-27 06:02:36,253 DEBUG : BROWSER_OUTPUT: RSS: Main: 167284736 06:02:36 INFO - 2016-02-27 06:02:36,253 DEBUG : BROWSER_OUTPUT: 06:02:38 INFO - 2016-02-27 06:02:38,856 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45620/tests/kraken/driver.html?testName=audio-dft) 06:02:39 INFO - 2016-02-27 06:02:39,430 DEBUG : BROWSER_OUTPUT: RSS: Main: 164818944 06:02:39 INFO - 2016-02-27 06:02:39,430 DEBUG : BROWSER_OUTPUT: 06:02:42 INFO - 2016-02-27 06:02:42,251 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45620/tests/kraken/driver.html?testName=audio-fft) 06:02:42 INFO - 2016-02-27 06:02:42,815 DEBUG : BROWSER_OUTPUT: RSS: Main: 160530432 06:02:42 INFO - 2016-02-27 06:02:42,815 DEBUG : BROWSER_OUTPUT: 06:02:44 INFO - 2016-02-27 06:02:44,793 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45620/tests/kraken/driver.html?testName=audio-oscillator) 06:02:45 INFO - 2016-02-27 06:02:45,358 DEBUG : BROWSER_OUTPUT: RSS: Main: 161398784 06:02:45 INFO - 2016-02-27 06:02:45,358 DEBUG : BROWSER_OUTPUT: 06:02:47 INFO - 2016-02-27 06:02:47,532 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45620/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:02:48 INFO - 2016-02-27 06:02:48,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 159891456 06:02:48 INFO - 2016-02-27 06:02:48,094 DEBUG : BROWSER_OUTPUT: 06:02:50 INFO - 2016-02-27 06:02:50,938 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45620/tests/kraken/driver.html?testName=imaging-darkroom) 06:02:51 INFO - 2016-02-27 06:02:51,504 DEBUG : BROWSER_OUTPUT: RSS: Main: 159707136 06:02:51 INFO - 2016-02-27 06:02:51,504 DEBUG : BROWSER_OUTPUT: 06:02:54 INFO - 2016-02-27 06:02:54,840 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45620/tests/kraken/driver.html?testName=imaging-desaturate) 06:02:55 INFO - 2016-02-27 06:02:55,408 DEBUG : BROWSER_OUTPUT: RSS: Main: 161697792 06:02:55 INFO - 2016-02-27 06:02:55,409 DEBUG : BROWSER_OUTPUT: 06:02:58 INFO - 2016-02-27 06:02:58,097 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45620/tests/kraken/driver.html?testName=json-parse-financial) 06:02:58 INFO - 2016-02-27 06:02:58,671 DEBUG : BROWSER_OUTPUT: RSS: Main: 161120256 06:02:58 INFO - 2016-02-27 06:02:58,671 DEBUG : BROWSER_OUTPUT: 06:03:00 INFO - 2016-02-27 06:03:00,220 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45620/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:03:00 INFO - 2016-02-27 06:03:00,774 DEBUG : BROWSER_OUTPUT: RSS: Main: 164683776 06:03:00 INFO - 2016-02-27 06:03:00,774 DEBUG : BROWSER_OUTPUT: 06:03:02 INFO - 2016-02-27 06:03:02,529 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45620/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:03:03 INFO - 2016-02-27 06:03:03,129 DEBUG : BROWSER_OUTPUT: RSS: Main: 161484800 06:03:03 INFO - 2016-02-27 06:03:03,130 DEBUG : BROWSER_OUTPUT: 06:03:05 INFO - 2016-02-27 06:03:05,106 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45620/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:03:05 INFO - 2016-02-27 06:03:05,680 DEBUG : BROWSER_OUTPUT: RSS: Main: 168599552 06:03:05 INFO - 2016-02-27 06:03:05,681 DEBUG : BROWSER_OUTPUT: 06:03:08 INFO - 2016-02-27 06:03:08,043 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45620/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:03:08 INFO - 2016-02-27 06:03:08,624 DEBUG : BROWSER_OUTPUT: RSS: Main: 176734208 06:03:08 INFO - 2016-02-27 06:03:08,624 DEBUG : BROWSER_OUTPUT: 06:03:11 INFO - 2016-02-27 06:03:11,140 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45620/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:03:11 INFO - 2016-02-27 06:03:11,701 DEBUG : BROWSER_OUTPUT: RSS: Main: 174014464 06:03:11 INFO - 2016-02-27 06:03:11,701 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,370 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45620/tests/kraken/driver.html?testName=ai-astar) 06:03:13 INFO - 2016-02-27 06:03:13,617 DEBUG : BROWSER_OUTPUT: RSS: Main: 231497728 06:03:13 INFO - 2016-02-27 06:03:13,617 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,620 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:03:13 INFO - 2016-02-27 06:03:13,621 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:03:13 INFO - 2016-02-27 06:03:13,621 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:03:13 INFO - 2016-02-27 06:03:13,621 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:03:13 INFO - 2016-02-27 06:03:13,621 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;110;102;108;109;111;99;109;109;108 06:03:13 INFO - 2016-02-27 06:03:13,621 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;152;124;150;156;136;157;152;126;163;128 06:03:13 INFO - 2016-02-27 06:03:13,621 DEBUG : BROWSER_OUTPUT: |2;audio-dft;160;160;159;160;159;160;159;164;161;160 06:03:13 INFO - 2016-02-27 06:03:13,621 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;72;157;72;72;72;73;154;72 06:03:13 INFO - 2016-02-27 06:03:13,621 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;93;139;95;92;92;136;93;93;138 06:03:13 INFO - 2016-02-27 06:03:13,622 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;110;110;109;109;109;108;110;110 06:03:13 INFO - 2016-02-27 06:03:13,622 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;146;145;145;144;145;145;145;145;145;145 06:03:13 INFO - 2016-02-27 06:03:13,622 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;96;95;96;96;96;96 06:03:13 INFO - 2016-02-27 06:03:13,622 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;54;58;57;56;56;57;56;71;54;56 06:03:13 INFO - 2016-02-27 06:03:13,622 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;65;63;64;64;66;65;64;64;65 06:03:13 INFO - 2016-02-27 06:03:13,622 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;79;80;79;79;85;80;78;86;80;79 06:03:13 INFO - 2016-02-27 06:03:13,622 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;118;119;118;111;118;124;120;118;116;110 06:03:13 INFO - 2016-02-27 06:03:13,623 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;137;137;138;150;144;142;145;144;141;141 06:03:13 INFO - 2016-02-27 06:03:13,623 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;64;64;64;64;67;66;66;66;66;66 06:03:13 INFO - 2016-02-27 06:03:13,623 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:03:13 INFO - 2016-02-27 06:03:13,623 DEBUG : BROWSER_OUTPUT: __start_cc_report 06:03:13 INFO - 2016-02-27 06:03:13,623 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,891 06:03:13 INFO - 2016-02-27 06:03:13,623 DEBUG : BROWSER_OUTPUT: __end_cc_report 06:03:13 INFO - 2016-02-27 06:03:13,623 DEBUG : BROWSER_OUTPUT: __startTimestamp1456581793618__endTimestamp 06:03:13 INFO - 2016-02-27 06:03:13,624 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,627 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:03:13 INFO - 2016-02-27 06:03:13,627 DEBUG : BROWSER_OUTPUT: Number of tests: 14 06:03:13 INFO - 2016-02-27 06:03:13,627 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,627 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:107.30 Median:109.00 stddev:3.77 (3.5%) stddev-sans-first:3.99 06:03:13 INFO - 2016-02-27 06:03:13,627 DEBUG : BROWSER_OUTPUT: Values: 108.0 110.0 102.0 108.0 109.0 111.0 99.0 109.0 109.0 108.0 06:03:13 INFO - 2016-02-27 06:03:13,627 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,627 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:144.40 Median:152.00 stddev:14.45 (9.5%) stddev-sans-first:15.07 06:03:13 INFO - 2016-02-27 06:03:13,627 DEBUG : BROWSER_OUTPUT: Values: 152.0 124.0 150.0 156.0 136.0 157.0 152.0 126.0 163.0 128.0 06:03:13 INFO - 2016-02-27 06:03:13,627 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.20 Median:160.00 stddev:1.48 (0.9%) stddev-sans-first:1.56 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: Values: 160.0 160.0 159.0 160.0 159.0 160.0 159.0 164.0 161.0 160.0 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.60 Median:72.00 stddev:35.27 (49.0%) stddev-sans-first:36.83 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 72.0 157.0 72.0 72.0 72.0 73.0 154.0 72.0 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.30 Median:92.50 stddev:21.67 (23.4%) stddev-sans-first:22.36 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: Values: 92.0 93.0 139.0 95.0 92.0 92.0 136.0 93.0 93.0 138.0 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:109.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 110.0 110.0 109.0 109.0 109.0 108.0 110.0 110.0 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.00 Median:145.00 stddev:0.47 (0.3%) stddev-sans-first:0.33 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: Values: 146.0 145.0 145.0 144.0 145.0 145.0 145.0 145.0 145.0 145.0 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,628 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 96.0 95.0 96.0 96.0 96.0 96.0 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.50 Median:56.50 stddev:4.90 (8.7%) stddev-sans-first:5.04 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: Values: 54.0 58.0 57.0 56.0 56.0 57.0 56.0 71.0 54.0 56.0 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:64.50 stddev:0.95 (1.5%) stddev-sans-first:0.88 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: Values: 63.0 65.0 63.0 64.0 64.0 66.0 65.0 64.0 64.0 65.0 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:80.00 stddev:2.72 (3.4%) stddev-sans-first:2.83 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: Values: 79.0 80.0 79.0 79.0 85.0 80.0 78.0 86.0 80.0 79.0 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.20 Median:118.00 stddev:4.10 (3.5%) stddev-sans-first:4.34 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: Values: 118.0 119.0 118.0 111.0 118.0 124.0 120.0 118.0 116.0 110.0 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,629 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:141.90 Median:143.00 stddev:4.07 (2.8%) stddev-sans-first:3.91 06:03:13 INFO - 2016-02-27 06:03:13,630 DEBUG : BROWSER_OUTPUT: Values: 137.0 137.0 138.0 150.0 144.0 142.0 145.0 144.0 141.0 141.0 06:03:13 INFO - 2016-02-27 06:03:13,630 DEBUG : BROWSER_OUTPUT: 06:03:13 INFO - 2016-02-27 06:03:13,630 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:66.00 stddev:1.16 (1.8%) stddev-sans-first:1.13 06:03:13 INFO - 2016-02-27 06:03:13,630 DEBUG : BROWSER_OUTPUT: Values: 64.0 64.0 64.0 64.0 67.0 66.0 66.0 66.0 66.0 66.0 06:03:13 INFO - 2016-02-27 06:03:13,630 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:03:13 INFO - 2016-02-27 06:03:13,630 DEBUG : BROWSER_OUTPUT: 06:03:14 INFO - 2016-02-27 06:03:14,319 INFO : Browser exited with error code: 0 06:03:14 INFO - 2016-02-27 06:03:14,374 INFO : Completed test kraken (00:00:58) 06:03:14 INFO - 2016-02-27 06:03:14,375 INFO : Starting test v8_7 06:03:14 INFO - 2016-02-27 06:03:14,375 DEBUG : operating with platform_type : linux_ 06:03:14 INFO - 2016-02-27 06:03:14,375 INFO : Initialising browser for v8_7 test... 06:03:14 INFO - 2016-02-27 06:03:14,381 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDtrKEh/profile http://localhost:45620/getInfo.html 06:03:18 INFO - 2016-02-27 06:03:18,619 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 06:03:18 INFO - 2016-02-27 06:03:18,620 DEBUG : BROWSER_OUTPUT: colorDepth:24 06:03:18 INFO - 2016-02-27 06:03:18,620 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 06:03:18 INFO - 2016-02-27 06:03:18,620 DEBUG : BROWSER_OUTPUT: __metrics 06:03:18 INFO - 2016-02-27 06:03:18,781 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:03:19 INFO - 2016-02-27 06:03:19,703 INFO : Browser initialized. 06:03:19 INFO - 2016-02-27 06:03:19,703 INFO : Running cycle 1/1 for v8_7 test... 06:03:19 INFO - 2016-02-27 06:03:19,704 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpDtrKEh/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 06:03:31 INFO - 2016-02-27 06:03:31,376 DEBUG : BROWSER_OUTPUT: RSS: Main: 132337664 06:03:31 INFO - 2016-02-27 06:03:31,377 DEBUG : BROWSER_OUTPUT: 06:03:52 INFO - 2016-02-27 06:03:52,729 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:45620/tests/v8_7/run.html (next: http://localhost:45620/tests/v8_7/run.html) 06:03:52 INFO - 2016-02-27 06:03:52,980 DEBUG : BROWSER_OUTPUT: RSS: Main: 422146048 06:03:52 INFO - 2016-02-27 06:03:52,980 DEBUG : BROWSER_OUTPUT: 06:03:52 INFO - 2016-02-27 06:03:52,981 DEBUG : BROWSER_OUTPUT: __start_tp_report 06:03:52 INFO - 2016-02-27 06:03:52,981 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 06:03:52 INFO - 2016-02-27 06:03:52,982 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 06:03:52 INFO - 2016-02-27 06:03:52,982 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 06:03:52 INFO - 2016-02-27 06:03:52,982 DEBUG : BROWSER_OUTPUT: |0;Richards;166.27868307283006 06:03:52 INFO - 2016-02-27 06:03:52,982 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;167.9261125104954 06:03:52 INFO - 2016-02-27 06:03:52,982 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.08676307007784 06:03:52 INFO - 2016-02-27 06:03:52,982 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5246.073298429319 06:03:52 INFO - 2016-02-27 06:03:52,982 DEBUG : BROWSER_OUTPUT: |4;RayTrace;925.0693802035153 06:03:52 INFO - 2016-02-27 06:03:52,983 DEBUG : BROWSER_OUTPUT: |5;Earley;544.069640914037 06:03:52 INFO - 2016-02-27 06:03:52,983 DEBUG : BROWSER_OUTPUT: |6;Boyer;10526.315789473685 06:03:52 INFO - 2016-02-27 06:03:52,983 DEBUG : BROWSER_OUTPUT: |7;RegExp;24414.634146341465 06:03:52 INFO - 2016-02-27 06:03:52,983 DEBUG : BROWSER_OUTPUT: |8;Splay;526.8703898840885 06:03:52 INFO - 2016-02-27 06:03:52,983 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4822.115384615385 06:03:52 INFO - 2016-02-27 06:03:52,984 DEBUG : BROWSER_OUTPUT: __end_tp_report 06:03:52 INFO - 2016-02-27 06:03:52,984 DEBUG : BROWSER_OUTPUT: __startTimestamp1456581832980__endTimestamp 06:03:52 INFO - 2016-02-27 06:03:52,984 DEBUG : BROWSER_OUTPUT: 06:03:52 INFO - 2016-02-27 06:03:52,984 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 06:03:52 INFO - 2016-02-27 06:03:52,984 DEBUG : BROWSER_OUTPUT: Number of tests: 10 06:03:52 INFO - 2016-02-27 06:03:52,984 DEBUG : BROWSER_OUTPUT: 06:03:52 INFO - 2016-02-27 06:03:52,985 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:166.28 Median:166.28 stddev:0.00 (0.0%) 06:03:52 INFO - 2016-02-27 06:03:52,985 DEBUG : BROWSER_OUTPUT: Values: 166.3 06:03:52 INFO - 2016-02-27 06:03:52,985 DEBUG : BROWSER_OUTPUT: 06:03:52 INFO - 2016-02-27 06:03:52,985 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:167.93 Median:167.93 stddev:0.00 (0.0%) 06:03:52 INFO - 2016-02-27 06:03:52,985 DEBUG : BROWSER_OUTPUT: Values: 167.9 06:03:52 INFO - 2016-02-27 06:03:52,986 DEBUG : BROWSER_OUTPUT: 06:03:52 INFO - 2016-02-27 06:03:52,986 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.09 Median:278.09 stddev:0.00 (0.0%) 06:03:52 INFO - 2016-02-27 06:03:52,986 DEBUG : BROWSER_OUTPUT: Values: 278.1 06:03:52 INFO - 2016-02-27 06:03:52,986 DEBUG : BROWSER_OUTPUT: 06:03:52 INFO - 2016-02-27 06:03:52,986 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5246.07 Median:5246.07 stddev:0.00 (0.0%) 06:03:52 INFO - 2016-02-27 06:03:52,986 DEBUG : BROWSER_OUTPUT: Values: 5246.1 06:03:52 INFO - 2016-02-27 06:03:52,987 DEBUG : BROWSER_OUTPUT: 06:03:52 INFO - 2016-02-27 06:03:52,987 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:925.07 Median:925.07 stddev:0.00 (0.0%) 06:03:52 INFO - 2016-02-27 06:03:52,987 DEBUG : BROWSER_OUTPUT: Values: 925.1 06:03:52 INFO - 2016-02-27 06:03:52,987 DEBUG : BROWSER_OUTPUT: 06:03:52 INFO - 2016-02-27 06:03:52,987 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:544.07 Median:544.07 stddev:0.00 (0.0%) 06:03:52 INFO - 2016-02-27 06:03:52,987 DEBUG : BROWSER_OUTPUT: Values: 544.1 06:03:52 INFO - 2016-02-27 06:03:52,987 DEBUG : BROWSER_OUTPUT: 06:03:52 INFO - 2016-02-27 06:03:52,987 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10526.32 Median:10526.32 stddev:0.00 (0.0%) 06:03:52 INFO - 2016-02-27 06:03:52,987 DEBUG : BROWSER_OUTPUT: Values: 10526.3 06:03:52 INFO - 2016-02-27 06:03:52,987 DEBUG : BROWSER_OUTPUT: 06:03:52 INFO - 2016-02-27 06:03:52,987 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24414.63 Median:24414.63 stddev:0.00 (0.0%) 06:03:52 INFO - 2016-02-27 06:03:52,987 DEBUG : BROWSER_OUTPUT: Values: 24414.6 06:03:52 INFO - 2016-02-27 06:03:52,988 DEBUG : BROWSER_OUTPUT: 06:03:52 INFO - 2016-02-27 06:03:52,988 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:526.87 Median:526.87 stddev:0.00 (0.0%) 06:03:52 INFO - 2016-02-27 06:03:52,988 DEBUG : BROWSER_OUTPUT: Values: 526.9 06:03:52 INFO - 2016-02-27 06:03:52,988 DEBUG : BROWSER_OUTPUT: 06:03:52 INFO - 2016-02-27 06:03:52,988 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4822.12 Median:4822.12 stddev:0.00 (0.0%) 06:03:52 INFO - 2016-02-27 06:03:52,988 DEBUG : BROWSER_OUTPUT: Values: 4822.1 06:03:52 INFO - 2016-02-27 06:03:52,988 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 06:03:52 INFO - 2016-02-27 06:03:52,988 DEBUG : BROWSER_OUTPUT: 06:03:53 INFO - 2016-02-27 06:03:53,667 INFO : Browser exited with error code: 0 06:03:53 INFO - 2016-02-27 06:03:53,684 INFO : Completed test v8_7 (00:00:39) 06:03:54 INFO - 2016-02-27 06:03:54,003 INFO : Completed test suite (00:20:08) 06:03:54 INFO - 2016-02-27 06:03:54,003 DEBUG : Working with test: dromaeo_css 06:03:54 INFO - 2016-02-27 06:03:54,003 DEBUG : Generating results file: dromaeo_css 06:03:54 INFO - 2016-02-27 06:03:54,005 DEBUG : Working with test: kraken 06:03:54 INFO - 2016-02-27 06:03:54,005 DEBUG : Generating results file: kraken 06:03:54 INFO - 2016-02-27 06:03:54,005 INFO : javascript benchmark 06:03:54 INFO - 2016-02-27 06:03:54,005 DEBUG : Working with test: v8_7 06:03:54 INFO - 2016-02-27 06:03:54,005 DEBUG : Generating results file: v8_7 06:03:54 INFO - 2016-02-27 06:03:54,006 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:03:54 INFO - 2016-02-27 06:03:54,051 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 06:03:54 INFO - 2016-02-27 06:03:54,051 DEBUG : process_Request line: dromaeo_css 7272.49 graph.html#tests=[[72,53,35]] 06:03:54 INFO - 2016-02-27 06:03:54,051 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:03:54 INFO - 2016-02-27 06:03:54,078 DEBUG : process_Request line: kraken 1483.30 graph.html#tests=[[232,53,35]] 06:03:54 INFO - 2016-02-27 06:03:54,078 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 06:03:54 INFO - 2016-02-27 06:03:54,106 DEBUG : process_Request line: v8_7 23267.42 graph.html#tests=[[230,53,35]] 06:03:54 INFO - 2016-02-27 06:03:54,107 INFO : javascript benchmark 06:03:54 INFO - 2016-02-27 06:03:54,111 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3279.9672003279966, 3331.6335203127637, 3362.6973572378497, 3363.5963684357866, 3380.391529524685, 5476.0, 5501.037318469267, 5504.0918248489, 5504.22116616884, 5515.365732940713, 5517.5585953123755, 5530.308711411074, 5531.308586426687, 5542.140968149944, 5553.666779993203, 4946.826861059863, 5038.370203724535, 5042.319286896277, 5049.570786483153, 5134.922976155361, 5054.721990290533, 5066.949330506703, 5108.055009823176, 5110.744462776865, 5115.616328775357, 4992.700437973715, 5064.493550644943, 5075.467075957026, 5120.871978200537, 5127.897442051175, 5144.331236939192, 5168.2764412982215, 5169.689818610857, 5173.353330833627, 5202.479752024805, 4901.583365413944, 5079.593632509391, 5080.644354895159, 5144.948550514484, 5156.716380599066, 5213.191955246905, 5228.607854410934, 5267.736613169326, 5315.0432922074015, 5332.653377530487, 5892.351841297454, 5966.224390829208, 5991.490723288525, 6001.3098493672915, 6005.009173486414, 5912.526997840162, 6006.669633170174, 6016.157737916695, 6034.456898879078, 6037.486813620848, 5458.563314934796, 5459.208414779874, 5536.363318218376, 5553.055980483327, 5565.41563135873, 6002.279726432856, 6017.187679663277, 6021.909671354933, 6022.247219097613, 6091.14723938649, 5724.055530837449, 5895.115732640138, 5905.645661260252, 5909.527237820964, 5915.40845915405, 5903.085021821625, 5930.673812940329, 5965.224520812267, 5970.194023806817, 5994.490468310155, 5827.912581311284, 5925.851853703605, 5961.463468287875, 5981.192539007266, 5981.730822113015, 5703.57183817772, 5841.970790146022, 5939.0, 5958.493528050078, 5961.254843144607, 4480.708753930984, 4547.545245475492, 4550.817967281272, 4558.772061396983, 4564.475085365226, 4097.795110244476, 4113.753174809521, 4118.402831589463, 4118.670506359484, 4135.648469880034, 5529.474699903502, 5638.464345887134, 5673.574481913873, 5674.687892165888, 5680.375158732536, 5903.35063143054, 5935.554833387513, 5960.880782384328, 5972.790952316648, 5995.2206213192, 5614.157876318503, 5706.400827913009, 5714.914276285859, 5728.713564321767, 5733.369329373849, 5131.409887862869, 5132.0762262792305, 5206.97396513015, 5209.270702101709, 5228.137357335997, 6047.667378294148, 6102.631080447795, 6102.8701222366735, 6110.168645843782, 6158.969205153946, 5911.556633252438, 5912.379200183918, 5916.615419997686, 5919.348871624204, 5941.762329506858, 5900.586958912835, 5931.7627294907725, 6027.6986150693165, 6087.66517841523, 6094.573379863367, 5920.585559010828, 5949.940500594938, 5954.46409823118, 5962.731677074629, 5963.761449541969, 5973.49225315853, 5975.40245975399, 5975.731092100779, 5976.701164941823, 6037.78867739627, 5685.573581981451, 5784.334801497882, 5829.6793676346515, 5896.410358964069, 5949.851253718519, 5842.211301474246, 5847.766089356378, 5858.384869588632, 5861.443162899518, 5862.648241105548, 5741.0, 5745.195672605755, 5745.626534275259, 5751.482366587027, 5756.021476348947, 5762.1068734346245, 5786.681732504752, 5789.8552536186935, 5866.6186697864505, 5900.380460051632, 5787.131930210502, 5796.478316951495, 5798.681072540882, 5804.100364443518, 5805.216295800013], "unit": "ms", "name": "dojo.html", "value": 5489.069031098341}, {"lowerIsBetter": false, "replicates": [588.393954227146, 634.771482266384, 642.178012144455, 642.4828013449178, 643.0868167202575, 32925.83537082317, 41263.79368103159, 41843.79078104605, 42050.0, 42108.368374474405, 46108.76945615267, 46653.30020049702, 46711.766441167834, 46738.76630616851, 46797.29804052942, 8000.519968801861, 8099.838003239932, 8963.282937364995, 9146.908530914705, 9148.542572871364, 8104.027516697975, 8203.26170644642, 10557.78884422311, 10726.463676816167, 10768.407737574431, 10390.324628899136, 10434.582616695323, 10450.58197672096, 10452.477376131163, 10457.633982810603, 10533.947330263376, 10564.947175264113, 10620.150387968944, 10645.308054976402, 10646.946765266202, 10293.382397056126, 10404.843927341097, 10406.791864162675, 10412.32319899204, 10414.0, 43026.56973430257, 43475.0, 43499.56500435018, 43554.0, 43605.78197109026, 3241.5299781531544, 3305.074579117851, 3348.430766769655, 3390.932181356384, 3678.889633311005, 31751.52372714411, 32063.6793632063, 32118.0, 32136.0, 32758.508622370682, 3799.3920972644246, 3908.3160446921675, 3920.666743326821, 3959.742616729903, 3975.20495900821, 277382.0, 278992.0, 279608.0, 279622.6018869913, 280206.0, 57106.7144664274, 58272.417275827545, 61670.69164654238, 61833.0, 62984.37015629877, 60753.69623151856, 60933.69533152306, 61269.0, 61279.38720612826, 61361.38638613646, 17596.912015440095, 17608.559786005197, 17714.645707085787, 17791.822081779275, 17798.91100544489, 12217.816732748837, 12219.633410997685, 12226.816597751042, 12277.570285039981, 12418.81371779424, 20213.595728085354, 20507.28224512135, 20524.281650142173, 20547.38357849267, 20547.97260136987, 7711.419159072377, 7846.136924938252, 7929.563873986986, 7989.161138080534, 8012.27889489937, 1129.7909886670993, 1145.8911403416662, 1149.0290704354807, 1154.4862536171322, 1205.3718307893305, 29460.410791784045, 29859.552106718416, 30088.548671769942, 30240.84879575588, 30291.545626815616, 1202.22456515547, 1235.6601934468092, 1253.354522420954, 1282.6793301674581, 1292.8060790881254, 13422.395992180376, 15033.51276783966, 15535.586195903259, 15643.608909777347, 16106.919465402832, 336458.0, 338848.0, 338883.0, 340564.0, 341758.0, 54942.17586736202, 55071.0, 55413.72293138589, 55428.44571554314, 55607.443925561034, 1298.6753311672082, 1377.0842389810864, 1451.6443471349587, 1485.8514148585054, 1486.829014663306, 27591.86204068967, 29196.124116276547, 29236.26909327284, 29545.11364659064, 30101.247468813453, 41491.58508414877, 41762.58237417587, 41771.16457670891, 43022.0, 43105.353419698105, 220233.0, 221384.0, 221637.13451461762, 222137.0, 222439.0, 33111.50332745059, 33344.333113338085, 33509.32981340408, 33760.3247935035, 33856.15359615931, 43262.0, 44103.33844992264, 44565.33152002658, 44809.775951120035, 44915.0, 35629.28741425209, 36247.818760906026, 36660.81669591635, 36911.63088369082, 37067.814660926524, 38235.80882095572, 38818.41772373473, 39750.801245993585, 41480.58519414888, 42587.26797059923, 37521.81239093787, 37718.62281377151, 37843.243135136596, 37894.0, 38517.80741096276, 32776.0, 32795.34409311753, 33950.0, 36529.26941461209, 37467.43798842965, 26115.216543503724, 26453.470930581665, 26587.734122659298, 28076.719232808227, 28369.29076773097], "unit": "ms", "name": "ext.html", "value": 19514.823212712603}, {"lowerIsBetter": false, "replicates": [369.3739112204814, 370.46282889809765, 378.7935575111564, 386.55932237249533, 389.97075219358544, 14637.341319640615, 17050.31798728051, 17351.0, 19541.21835126595, 19617.11722972466, 11952.462139203735, 12238.938805305983, 12245.081618878576, 18864.717029244573, 19095.236190552398, 4083.1017176221185, 5118.257852611378, 5237.135872581039, 5237.47625237477, 5240.082985477527, 4161.583841615845, 5591.720413979305, 5762.02045652238, 5765.682887441189, 5766.308043034821, 5593.188987596796, 5689.374168841424, 5706.65760054396, 5721.027425337703, 5728.42715728428, 5584.91622625661, 5623.437656234385, 5651.40660230676, 5651.576131790132, 5656.066748986413, 5384.730763461811, 5429.728513574306, 5439.755211015514, 5536.944630553683, 5618.0, 18300.90849545757, 21383.25158619441, 21461.570768584697, 21640.458988525257, 21994.34016979493, 2234.72065991751, 2266.0709109265285, 2267.251806903718, 2281.3726225288015, 2377.869217193054, 10313.0, 10369.222308326905, 10435.79128417435, 10523.526441310083, 10698.411587362692, 1966.2921348314737, 1975.9407217783346, 1991.1836147179731, 2067.3074520035807, 2072.046858445117, 64721.0, 65319.3468065318, 65472.3452765471, 67077.0, 67592.0, 10952.56189752401, 11209.495572699248, 11254.324740515594, 11274.887251127548, 11329.0, 14784.704305914036, 14827.629309267355, 14912.552623421314, 14942.70114597702, 14943.77584336214, 8250.216229458192, 8333.624986875606, 8572.442791218551, 8628.913710862937, 8659.610317535726, 8368.790780230422, 8401.579921003926, 8402.537860417735, 8477.74566762998, 8484.830303393897, 4470.240059189946, 4624.352590637313, 4703.435587729495, 4746.098241334135, 4774.474807771142, 1911.4647898588416, 1972.9112189951486, 1979.3270288102115, 1986.3941497843007, 1996.8003199679915, 963.9228861691048, 1023.5240613114937, 1060.3584831177182, 1104.8839871813504, 1117.620009196877, 17548.64902701939, 17738.0, 17799.46601601954, 17854.0, 18354.632907342046, 1013.2350075692952, 1024.626011505795, 1054.4622242656146, 1099.834577726115, 1115.4924509348227, 10214.38713677167, 10309.690709278733, 10333.431661258552, 10338.12125969286, 10358.430286334324, 580839.0, 585226.0, 586492.0, 588102.0, 597173.0, 74945.25054749491, 82890.0, 83892.0, 84320.15679843123, 84542.57728711318, 1235.1415766042694, 1235.4131787400913, 1266.4301064520819, 1273.0, 1295.1581472043247, 15336.463223787114, 15825.446109386115, 16291.511254662379, 16397.42609008727, 16418.097004664858, 33708.157296067795, 34197.48703769396, 34214.0, 34228.48657270093, 35161.12097197488, 41518.1696366077, 43595.56404436042, 44708.32937506005, 44895.32657010082, 45119.548804511534, 9474.905250947402, 9631.470269135265, 9694.5152742364, 9751.171150452292, 9760.072793084646, 13577.77799998005, 13854.861451385761, 14342.641433964234, 14514.927425362806, 14749.0, 14042.508512202025, 15229.466968656045, 15803.209839507748, 15845.286962086504, 15976.20118994069, 14934.551963441114, 15439.536813895153, 15504.224788760292, 15900.284487197872, 16030.358785648443, 16159.0, 16176.595585110466, 16392.83607163961, 16612.833871661613, 16631.33474661, 7914.525128492309, 8946.910530894607, 10011.0, 10084.344517606332, 10257.794844103226, 10488.528016239441, 12458.501659933501, 15648.608784780472, 15909.28408221656, 15926.362945482051], "unit": "ms", "name": "jquery.html", "value": 9306.672121421589}, {"lowerIsBetter": false, "replicates": [1523.8323784383722, 1529.8393668664783, 1715.5368050626323, 1788.3383148235155, 1835.220031486618, 7275.054242948416, 7537.773866783991, 7543.811404714871, 7545.1700312965595, 7550.962245188766, 7673.040869891263, 7740.612969351524, 7761.922380776191, 7763.922360776391, 7770.61146942652, 2264.615015447378, 2270.738865030522, 2312.6531020346915, 2313.0169677886915, 2315.1549684365255, 2327.3832434404894, 2330.5222429402015, 2337.824663150262, 2352.45893444507, 2355.9057637694473, 2031.5530583271657, 2074.3258441006733, 2234.910603575855, 2284.6116160252795, 2348.201611452106, 2289.1987804268456, 2306.1467257114805, 2317.2468947592015, 2328.557574060923, 2329.0800133947164, 2227.3206671965045, 2277.39648993017, 2280.737715162761, 2282.2468585366787, 2284.0407029047838, 6786.185657721105, 6966.860662786716, 7203.243659415791, 7348.669309881068, 7364.0, 4840.249761287007, 4903.828366007208, 4904.0437114762635, 4907.803687852482, 4908.680935739201, 3047.832369219692, 3047.8780848766023, 3067.8619462124257, 3071.2475443516482, 3154.952675709866, 1035.140833108518, 1061.9150467962545, 1082.7401423658343, 1092.4919912240766, 1100.5817789240118, 10294.485275736184, 10416.166706663449, 10417.218708596809, 10423.166146708245, 10433.42616156111, 4152.709310348247, 4199.76901270427, 4268.957310426856, 4300.397944287803, 4300.7634580097765, 8530.445521041112, 8546.145385461405, 8554.35842311829, 8565.229129378262, 8569.742907712654, 4632.096741135513, 4676.392069031004, 4677.7193368398, 4687.296905464208, 4690.9765451172525, 4289.227938970953, 4306.784660767012, 4310.073334233155, 4311.051568654891, 4474.37358769766, 3489.94765078524, 3496.5279687242405, 3511.0871173495075, 3522.43641017436, 3545.982270088685, 1108.1799468393492, 1114.0530549033258, 1118.2507719827736, 1121.041509509374, 1121.2095472691733, 1233.0443905972943, 1258.4651523102646, 1273.8726127387372, 1287.7875150600235, 1288.1176394170025, 7434.1079070510805, 7438.293362130589, 7472.103347598323, 7602.163761986177, 7633.732819351296, 1165.4638866121509, 1169.6900321414832, 1173.7065733566608, 1182.1193211057646, 1202.9639110826515, 5790.507806836382, 5833.649981001068, 5899.587028908021, 5906.734196961147, 5920.348761636216, 8331.916680833236, 9105.0, 9199.908000920039, 9233.307501937381, 9236.168744812865, 8782.736517904474, 8922.73231803047, 8938.821223575622, 8971.910280897107, 9090.545472726337, 7301.0, 7364.0, 7425.7029718810645, 7443.2184620615135, 7475.775726728207, 7326.816829579303, 7376.446766492427, 7411.666475008746, 7449.590272534846, 7497.62511874415, 8135.83728325442, 8344.7913802153, 8365.539895305817, 8415.853957240864, 8417.83164336722, 8478.576071196292, 8522.36082293818, 8680.0, 8689.956550217208, 8761.649534018568, 6930.06444130036, 7038.577685338896, 7051.224365319708, 7054.4003759681, 7072.186698529529, 7310.853782924205, 7350.154732205865, 7357.963210183915, 7366.337029667356, 7604.391648668204, 7029.156501219886, 7059.0, 7062.788116356517, 7178.60517671533, 7217.458690598121, 7028.753993610199, 7029.61337126463, 7029.824254393681, 7117.572945623279, 7199.892001620085, 6943.618101004493, 6968.0, 6973.3375329343635, 6975.720971161097, 6979.336962988508, 6375.840603984938, 6439.517036222208, 6460.806175814733, 6464.0627109069865, 6468.6442245676935, 6748.527603067738, 6794.694238759169, 6796.830079248059, 6800.965995169992, 6812.863742725019], "unit": "ms", "name": "mootools.html", "value": 4508.503197537264}, {"lowerIsBetter": false, "replicates": [365.1583100952305, 369.6488336080724, 370.2779579819353, 372.5864290637393, 381.2793819680802, 5872.911906321408, 5939.198208241886, 6006.249218847644, 6045.123457098718, 6066.059760737087, 5813.244278243833, 5965.761369545224, 5989.460948514633, 6008.0, 6014.669193194372, 4718.150732868083, 4720.197566413718, 4723.78742956566, 4724.834630787915, 4728.314394412808, 4528.43394575678, 4592.931106033396, 4626.051659409813, 4726.338312636233, 4738.099761045391, 4660.091282199973, 4660.4174478190225, 4672.3925889634465, 4684.578387945085, 4689.765511724418, 4660.86017419478, 4673.626109911216, 4676.929846052312, 4694.483606803249, 4694.624430045588, 4172.812223449952, 4173.791310434466, 4179.03882107114, 4180.686448516343, 4181.937270940938, 5600.439956004408, 5689.175069614924, 5689.886202275973, 5702.714864256812, 5707.5148612368, 5460.945390546083, 5466.562674985991, 5468.91796623051, 5471.7264136793, 5475.917861232085, 6079.817605471843, 6333.809985700436, 6341.080543321239, 6347.1114044033875, 6351.11084448173, 4464.464264288274, 4531.139083574143, 4581.152486789954, 4584.335271385664, 4588.265877459624, 6536.934630653727, 6559.737610495575, 6561.80314590563, 6562.310957349458, 6563.048357988113, 4699.036697477009, 4710.36410084641, 4716.57550820421, 4717.150912835654, 4721.032188401369, 4688.062387522509, 4768.642351823627, 4776.283557466408, 4783.4499032611, 4793.640476964241, 5411.945880541223, 5422.48486393792, 5425.511703946664, 5433.592480563973, 5437.4562543745305, 6410.1987251593555, 6411.51913606472, 6418.518611104186, 6426.646534440557, 6426.839329016812, 1683.7306031034975, 1711.1273250642262, 1711.2299465240692, 1711.3924556782413, 1712.5547357686842, 1114.1532435349193, 1126.0709914320719, 1136.4431428599926, 1141.149843366697, 1202.903767698582, 6133.815985520441, 6155.169052177898, 6160.168377269101, 6165.198524191826, 6165.660888651078, 6489.545731798823, 6507.154069970874, 6508.934910650928, 6512.479001679949, 6525.347465253437, 6146.5390095743, 6152.507799376129, 6156.199694039836, 6157.415045570664, 6167.629942203393, 3415.863365465403, 3524.101354154674, 3528.7353448491467, 3529.982350088233, 3530.1351168963256, 6718.428933540704, 6727.66361681914, 6734.932650673529, 6735.360140786715, 6738.460923126138, 6664.400203981665, 6678.332166783282, 6681.09805176295, 6683.365080317362, 6686.063951046857, 6592.0, 6738.292479289702, 6740.595564266159, 6742.190937087581, 6746.494012949049, 6381.744730210739, 6489.837754056186, 6508.446782023581, 6523.18460192476, 6524.380183882524, 6558.672066396756, 6573.112629794917, 6576.539642224999, 6587.934120658732, 6597.076409302797, 6768.289329620516, 6838.350356716104, 6843.931560684528, 6849.794506164823, 6855.485838562028, 4493.820247190076, 4653.53464653532, 4653.674242802971, 4663.696859704109, 4691.460482044608, 6596.340365963557, 6602.966985165235, 6607.405333520006, 6607.636579987954, 6609.239937407259, 6424.4860411166055, 6443.516736244706, 6445.548811583331, 6446.355364463516, 6450.903236451363, 6310.800947819899, 6414.518911081781, 6432.678366081771, 6443.582411869381, 6480.611163330215, 6340.524460665376, 6342.460890824332, 6348.873022539431, 6352.047192921099, 6354.364563543609, 6460.25707043696, 6484.254310754323, 6498.927676933253, 6500.837479063061, 6510.576812507172, 6222.471089957405, 6331.113644089739, 6351.237851457855, 6357.55497115216, 6360.300366959538], "unit": "ms", "name": "prototype.html", "value": 4876.946960831587}, {"lowerIsBetter": false, "replicates": [772.7874834420534, 798.4291231769288, 847.9915200847989, 854.9401541892066, 871.8430682477151, 14301.78547321791, 14496.347664355102, 14602.269886505672, 14644.194569298685, 14678.706425871504, 13866.791998120036, 13951.93024034881, 13965.860341396583, 13967.0, 13970.650733731662, 741.4328039050131, 760.6615056299948, 903.6656437118243, 917.1653795046509, 917.5595714057257, 910.640297082652, 916.3860213656833, 916.8624706294042, 917.5228880981886, 919.8758167647383, 812.3947658994058, 821.8109834737984, 939.5865819039635, 941.5715849288591, 944.1266828183936, 937.4984383354906, 945.7068308824287, 946.4415994563241, 946.5551190940247, 946.6639343033193, 697.4734075772759, 701.1586096684, 702.3398005874461, 702.4520873718508, 705.0904333409895, 13097.0, 13120.934395327962, 13242.801357979639, 13251.403686834019, 13260.270685112315, 12592.874071259354, 12691.809622855664, 12823.166494177849, 12929.288889111094, 13002.414891329912, 10796.946015269874, 11289.266197697205, 11395.0, 11578.363190024545, 11735.823962640567, 3760.736748427611, 3776.546814382264, 3781.8487260509546, 3804.1821008483034, 3893.6301051400073, 50138.24792628077, 50526.989460210956, 51186.0, 51471.0, 51509.0, 10723.356598604109, 10816.188785841094, 10836.94581527103, 10911.236213464981, 10984.231103822656, 17043.062631555134, 17147.142642868057, 17167.914160429118, 17168.31326746941, 17197.484075477754, 7441.421294570754, 7628.54228746277, 7641.847163056819, 7689.807754806175, 7808.414368922354, 4562.543745625411, 4602.1025899949855, 4602.884927876763, 4687.695299805502, 4698.600618947359, 3374.4938259261307, 3412.5734283214597, 3416.9829150854584, 3417.1627951152127, 3423.1442139465134, 608.3581821178699, 613.4295105551879, 622.7975907829883, 636.6657504810012, 637.4295005969724, 641.4355367276767, 681.9795406137824, 700.1772916822815, 742.6212631557836, 760.1182628151317, 9206.355555111079, 9703.902960970441, 9705.320627556004, 9825.263105267133, 9905.35615185025, 559.3204256827893, 702.005232768846, 710.9466789990771, 727.1165533876366, 740.1968863782748, 4289.721168124062, 4332.848350307724, 4385.0, 4469.128519938581, 4518.9548104518535, 59325.703371482865, 59334.70332648309, 59931.0, 60161.39838601558, 63320.36679633237, 50899.491005089476, 51071.23393149106, 52125.73937130366, 52894.47105528895, 53161.73419132957, 49078.754606227456, 49821.501784982414, 49942.25086623704, 49949.50050499521, 49995.500044999084, 9472.52637368115, 9833.704988850346, 9875.308728388944, 9982.650607228712, 10068.546915388972, 36319.63680363268, 36802.0, 37059.62940370562, 37102.257954840214, 37179.25641487209, 41560.584394155674, 45378.54621453875, 45485.545144549455, 46020.0, 46282.30576541422, 11830.40847957616, 12102.75794484123, 12138.089643276613, 12282.385880706108, 12552.49790008426, 12863.871361286267, 12946.741065178832, 13496.797548036984, 13824.471109249132, 14017.859821401655, 12436.253824770543, 12487.12590118683, 12523.123381363217, 12565.748685026065, 12847.678808029874, 12062.155649104478, 12190.2076365036, 12292.0, 12300.138990270952, 12623.621291361274, 10660.0, 10803.513841877291, 11332.660020199408, 11832.645020649394, 11934.582289619822, 10556.89443105559, 10565.101966332948, 10732.785344293226, 10783.51474183648, 10848.0, 10587.4706264688, 10700.732481688021, 10851.240413171003, 11070.39112848801, 11098.2786118902], "unit": "ms", "name": "yui.html", "value": 6748.743683950701}], "name": "dromaeo_css", "value": 7272.494841171584}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 110.0, 102.0, 108.0, 109.0, 111.0, 99.0, 109.0, 109.0, 108.0], "unit": "ms", "name": "ai-astar", "value": 107.3}, {"lowerIsBetter": true, "replicates": [152.0, 124.0, 150.0, 156.0, 136.0, 157.0, 152.0, 126.0, 163.0, 128.0], "unit": "ms", "name": "audio-beat-detection", "value": 144.4}, {"lowerIsBetter": true, "replicates": [160.0, 160.0, 159.0, 160.0, 159.0, 160.0, 159.0, 164.0, 161.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 160.2}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 72.0, 157.0, 72.0, 72.0, 72.0, 73.0, 154.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 88.6}, {"lowerIsBetter": true, "replicates": [92.0, 93.0, 139.0, 95.0, 92.0, 92.0, 136.0, 93.0, 93.0, 138.0], "unit": "ms", "name": "audio-oscillator", "value": 106.3}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 110.0, 110.0, 109.0, 109.0, 109.0, 108.0, 110.0, 110.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.3}, {"lowerIsBetter": true, "replicates": [146.0, 145.0, 145.0, 144.0, 145.0, 145.0, 145.0, 145.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.0}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 96.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.5}, {"lowerIsBetter": true, "replicates": [54.0, 58.0, 57.0, 56.0, 56.0, 57.0, 56.0, 71.0, 54.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 57.5}, {"lowerIsBetter": true, "replicates": [63.0, 65.0, 63.0, 64.0, 64.0, 66.0, 65.0, 64.0, 64.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.3}, {"lowerIsBetter": true, "replicates": [79.0, 80.0, 79.0, 79.0, 85.0, 80.0, 78.0, 86.0, 80.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 80.5}, {"lowerIsBetter": true, "replicates": [118.0, 119.0, 118.0, 111.0, 118.0, 124.0, 120.0, 118.0, 116.0, 110.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.2}, {"lowerIsBetter": true, "replicates": [137.0, 137.0, 138.0, 150.0, 144.0, 142.0, 145.0, 144.0, 141.0, 141.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 141.9}, {"lowerIsBetter": true, "replicates": [64.0, 64.0, 64.0, 64.0, 67.0, 66.0, 66.0, 66.0, 66.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.3}], "name": "kraken", "value": 1483.3}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [166.27868307283006], "unit": "score", "name": "Richards", "value": 212.306228}, {"lowerIsBetter": false, "replicates": [167.9261125104954], "unit": "score", "name": "DeltaBlue", "value": 393.73268999999993}, {"lowerIsBetter": false, "replicates": [278.08676307007784], "unit": "score", "name": "Encrypt", "value": 957.1868759999999}, {"lowerIsBetter": false, "replicates": [5246.073298429319], "unit": "score", "name": "Decrypt", "value": 50.739092814371276}, {"lowerIsBetter": false, "replicates": [925.0693802035153], "unit": "score", "name": "RayTrace", "value": 799.928109}, {"lowerIsBetter": false, "replicates": [544.069640914037], "unit": "score", "name": "Earley", "value": 1224.9589940000005}, {"lowerIsBetter": false, "replicates": [10526.315789473685], "unit": "score", "name": "Boyer", "value": 63.313984999999995}, {"lowerIsBetter": false, "replicates": [24414.634146341465], "unit": "score", "name": "RegExp", "value": 37.31307192807194}, {"lowerIsBetter": false, "replicates": [526.8703898840885], "unit": "score", "name": "Splay", "value": 154.66991800000002}, {"lowerIsBetter": false, "replicates": [4822.115384615385], "unit": "score", "name": "NavierStokes", "value": 307.7487537387835}], "name": "v8_7", "value": 23267.41890946538}]} 06:03:54 INFO - RETURN: dromaeo_css: 7272.49 06:03:54 INFO - RETURN: kraken: 1483.30 06:03:54 INFO - RETURN: v8_7: 23267.42 06:03:54 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23267.42"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1483.30"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7272.49"}}} 06:03:54 INFO - Return code: 0 06:03:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 06:03:54 INFO - # TBPL SUCCESS # 06:03:54 INFO - Running post-action listener: _resource_record_post_action 06:03:54 INFO - Running post-run listener: _resource_record_post_run 06:03:55 INFO - Total resource usage - Wall time: 1225s; CPU: 13.0%; Read bytes: 319488; Write bytes: 502525952; Read time: 56; Write time: 472604 06:03:55 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 235626496; Read time: 0; Write time: 274560 06:03:55 INFO - run-tests - Wall time: 1210s; CPU: 13.0%; Read bytes: 319488; Write bytes: 262828032; Read time: 56; Write time: 197236 06:03:55 INFO - Running post-run listener: _upload_blobber_files 06:03:55 INFO - Blob upload gear active. 06:03:55 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 06:03:55 INFO - Copying logs to upload dir... 06:03:55 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1256.580539 ========= master_lag: 13.61 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-02-27 06:04:08.145057) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-27 06:04:08.148973) ========= 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=eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009934 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1456571785/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.03 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-27 06:04:08.184088) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 06:04:08.184395) ========= 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=eca2ab57546de7b41f256b19000001ef-1456580576.45145-96162157 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-27 06:04:08.253077) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-27 06:04:08.253908) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-27 06:04:08.254324) ========= ========= Total master_lag: 13.87 =========