builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-049 starttime: 1455840864.82 results: success (0) buildid: 20160218134825 builduid: be9334a6f66b4e22a3c9762b5a61bcef revision: 32608d640a8c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:24.819713) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:24.820179) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:24.820479) ========= 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=8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006256 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:24.843342) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:24.843651) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:24.857010) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:24.857393) ========= 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=8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004589 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:24.906615) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:24.906925) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:24.907270) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:24.907546) ========= 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=8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-18 16:14:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 31.1M=0s 2016-02-18 16:14:25 (31.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.101923 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:25.021593) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:25.021931) ========= 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=8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018229 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:25.071879) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:25.072244) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 32608d640a8c --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 32608d640a8c --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=8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-18 16:14:25,464 Setting DEBUG logging. 2016-02-18 16:14:25,464 attempt 1/10 2016-02-18 16:14:25,464 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/32608d640a8c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-18 16:14:25,712 unpacking tar archive at: mozilla-beta-32608d640a8c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.542563 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:25.627586) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:25.627993) ========= echo 32608d640a8c in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'32608d640a8c'] 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=8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 32608d640a8c program finished with exit code 0 elapsedTime=0.004890 script_repo_revision: '32608d640a8c' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:25.678581) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:25.678900) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-18 16:14:25.689028) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-02-18 16:14:25.689423) ========= /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=8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:14:26 INFO - MultiFileLogger online at 20160218 16:14:26 in /builds/slave/test-pgo 16:14:26 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 16:14:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 16:14:26 INFO - {'append_to_log': False, 16:14:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 16:14:26 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:14:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 16:14:26 INFO - 'branch': 'Mozilla-Beta', 16:14:26 INFO - 'buildbot_json_path': 'buildprops.json', 16:14:26 INFO - 'config_files': ('talos/linux_config.py',), 16:14:26 INFO - 'default_actions': ('clobber', 16:14:26 INFO - 'read-buildbot-config', 16:14:26 INFO - 'download-and-extract', 16:14:26 INFO - 'populate-webroot', 16:14:26 INFO - 'create-virtualenv', 16:14:26 INFO - 'install', 16:14:26 INFO - 'run-tests'), 16:14:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:14:26 INFO - 'download_minidump_stackwalk': True, 16:14:26 INFO - 'download_symbols': 'ondemand', 16:14:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:14:26 INFO - 'tooltool.py': '/tools/tooltool.py', 16:14:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:14:26 INFO - '/tools/misc-python/virtualenv.py')}, 16:14:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:14:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:14:26 INFO - 'installer_path': 'installer.exe', 16:14:26 INFO - 'log_level': 'info', 16:14:26 INFO - 'log_name': 'talos', 16:14:26 INFO - 'log_to_console': True, 16:14:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:14:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:14:26 INFO - 'opt_config_files': (), 16:14:26 INFO - 'pip_index': False, 16:14:26 INFO - 'sps_profile': False, 16:14:26 INFO - 'sps_profile_interval': 0, 16:14:26 INFO - 'suite': 'dromaeojs', 16:14:26 INFO - 'system_bits': '32', 16:14:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:14:26 INFO - 'title': 'talos-linux64-ix-049', 16:14:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:14:26 INFO - 'use_talos_json': True, 16:14:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 16:14:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:14:26 INFO - 'work_dir': 'build'} 16:14:26 INFO - ##### 16:14:26 INFO - ##### Running clobber step. 16:14:26 INFO - ##### 16:14:26 INFO - Running pre-action listener: _resource_record_pre_action 16:14:26 INFO - Running main action method: clobber 16:14:26 INFO - rmtree: /builds/slave/test-pgo/build 16:14:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 16:14:27 INFO - Running post-action listener: _resource_record_post_action 16:14:27 INFO - ##### 16:14:27 INFO - ##### Running read-buildbot-config step. 16:14:27 INFO - ##### 16:14:27 INFO - Running pre-action listener: _resource_record_pre_action 16:14:27 INFO - Running main action method: read_buildbot_config 16:14:27 INFO - Using buildbot properties: 16:14:27 INFO - { 16:14:27 INFO - "properties": { 16:14:27 INFO - "buildnumber": 114, 16:14:27 INFO - "product": "firefox", 16:14:27 INFO - "script_repo_revision": "production", 16:14:27 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:14:27 INFO - "repository": "", 16:14:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:14:27 INFO - "buildid": "20160218134825", 16:14:27 INFO - "slavename": "talos-linux64-ix-049", 16:14:27 INFO - "pgo_build": "True", 16:14:27 INFO - "basedir": "/builds/slave/test-pgo", 16:14:27 INFO - "project": "", 16:14:27 INFO - "platform": "ubuntu64_hw", 16:14:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:14:27 INFO - "slavebuilddir": "test", 16:14:27 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:14:27 INFO - "branch": "mozilla-beta", 16:14:27 INFO - "repo_path": "releases/mozilla-beta", 16:14:27 INFO - "stage_platform": "linux64-pgo", 16:14:27 INFO - "builduid": "be9334a6f66b4e22a3c9762b5a61bcef", 16:14:27 INFO - "revision": "32608d640a8c" 16:14:27 INFO - }, 16:14:27 INFO - "sourcestamp": { 16:14:27 INFO - "repository": "", 16:14:27 INFO - "hasPatch": false, 16:14:27 INFO - "project": "", 16:14:27 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:14:27 INFO - "changes": [ 16:14:27 INFO - { 16:14:27 INFO - "category": null, 16:14:27 INFO - "files": [ 16:14:27 INFO - { 16:14:27 INFO - "url": null, 16:14:27 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/firefox-45.0.en-US.linux-x86_64.tar.bz2" 16:14:27 INFO - } 16:14:27 INFO - ], 16:14:27 INFO - "repository": "", 16:14:27 INFO - "rev": "32608d640a8c", 16:14:27 INFO - "who": "sendchange", 16:14:27 INFO - "when": 1455840830, 16:14:27 INFO - "number": 7624605, 16:14:27 INFO - "comments": "Bug 1244650 - Failure to clear Forms and Search Data on exit. r=yoric a=sylvestre", 16:14:27 INFO - "project": "", 16:14:27 INFO - "at": "Thu 18 Feb 2016 16:13:50", 16:14:27 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:14:27 INFO - "revlink": "", 16:14:27 INFO - "properties": [ 16:14:27 INFO - [ 16:14:27 INFO - "buildid", 16:14:27 INFO - "20160218134825", 16:14:27 INFO - "Change" 16:14:27 INFO - ], 16:14:27 INFO - [ 16:14:27 INFO - "builduid", 16:14:27 INFO - "be9334a6f66b4e22a3c9762b5a61bcef", 16:14:27 INFO - "Change" 16:14:27 INFO - ], 16:14:27 INFO - [ 16:14:27 INFO - "pgo_build", 16:14:27 INFO - "True", 16:14:27 INFO - "Change" 16:14:27 INFO - ] 16:14:27 INFO - ], 16:14:27 INFO - "revision": "32608d640a8c" 16:14:27 INFO - } 16:14:27 INFO - ], 16:14:27 INFO - "revision": "32608d640a8c" 16:14:27 INFO - } 16:14:27 INFO - } 16:14:27 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/firefox-45.0.en-US.linux-x86_64.tar.bz2. 16:14:27 INFO - Running post-action listener: _resource_record_post_action 16:14:27 INFO - ##### 16:14:27 INFO - ##### Running download-and-extract step. 16:14:27 INFO - ##### 16:14:27 INFO - Running pre-action listener: _resource_record_pre_action 16:14:27 INFO - Running main action method: download_and_extract 16:14:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 16:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:27 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/test_packages.json 16:14:27 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 16:14:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 16:14:27 INFO - Downloaded 1270 bytes. 16:14:27 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 16:14:27 INFO - Using the following test package requirements: 16:14:27 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:14:27 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:14:27 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:14:27 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:14:27 INFO - u'jsshell-linux-x86_64.zip'], 16:14:27 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:14:27 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:14:27 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:14:27 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:14:27 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 16:14:27 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:14:27 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 16:14:27 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:14:27 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:14:27 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:14:27 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:14:27 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:14:27 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:14:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:27 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:14:27 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/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 16:14:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/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 16:14:27 INFO - Downloaded 22255750 bytes. 16:14:27 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 16:14:27 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:14:27 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 16:14:28 INFO - Return code: 0 16:14:28 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 16:14:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:28 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:14:28 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/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 16:14:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/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 16:14:29 INFO - Downloaded 22255750 bytes. 16:14:29 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 16:14:29 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:14:29 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 16:14:30 INFO - Return code: 0 16:14:30 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')]} 16:14:30 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 16:14:30 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/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 16:14:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/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 16:14:30 INFO - Downloaded 11132038 bytes. 16:14:30 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 16:14:30 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 16:14:30 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 16:14:31 INFO - Return code: 0 16:14:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:31 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:14:31 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/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 16:14:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/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 16:14:35 INFO - Downloaded 58333377 bytes. 16:14:35 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:14:35 INFO - mkdir: /builds/slave/test-pgo/properties 16:14:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:14:35 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:14:35 INFO - Contents: 16:14:35 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:14:35 INFO - Running post-action listener: _resource_record_post_action 16:14:35 INFO - Running post-action listener: set_extra_try_arguments 16:14:35 INFO - ##### 16:14:35 INFO - ##### Running populate-webroot step. 16:14:35 INFO - ##### 16:14:35 INFO - Running pre-action listener: _resource_record_pre_action 16:14:35 INFO - Running main action method: populate_webroot 16:14:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:14:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:14:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:14:35 INFO - '--tppagecycles', 16:14:35 INFO - '7'], 16:14:35 INFO - 'tests': ['tsvgm']}}, 16:14:35 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:14:35 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:14:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:14:35 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:14:35 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:14:35 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:14:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:14:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:35 INFO - 'talos_options': ['--e10s'], 16:14:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:14:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:35 INFO - 'tests': ['damp', 'tps']}, 16:14:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:35 INFO - 'talos_options': ['--e10s'], 16:14:35 INFO - 'tests': ['damp', 'tps']}, 16:14:35 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:14:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:14:35 INFO - 'other': {'tests': ['a11yr', 16:14:35 INFO - 'ts_paint', 16:14:35 INFO - 'tpaint', 16:14:35 INFO - 'sessionrestore', 16:14:35 INFO - 'sessionrestore_no_auto_restore']}, 16:14:35 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:14:35 INFO - 'tests': ['a11yr', 16:14:35 INFO - 'ts_paint', 16:14:35 INFO - 'tpaint', 16:14:35 INFO - 'sessionrestore', 16:14:35 INFO - 'sessionrestore_no_auto_restore']}, 16:14:35 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:14:35 INFO - 'tests': ['a11yr', 16:14:35 INFO - 'ts_paint', 16:14:35 INFO - 'tpaint', 16:14:35 INFO - 'sessionrestore', 16:14:35 INFO - 'sessionrestore_no_auto_restore']}, 16:14:35 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:14:35 INFO - 'tests': ['a11yr', 16:14:35 INFO - 'ts_paint', 16:14:35 INFO - 'tpaint', 16:14:35 INFO - 'sessionrestore', 16:14:35 INFO - 'sessionrestore_no_auto_restore']}, 16:14:35 INFO - 'other_l64': {'tests': ['a11yr', 16:14:35 INFO - 'ts_paint', 16:14:35 INFO - 'tpaint', 16:14:35 INFO - 'sessionrestore', 16:14:35 INFO - 'sessionrestore_no_auto_restore']}, 16:14:35 INFO - 'other_nol64': {'tests': ['a11yr', 16:14:35 INFO - 'ts_paint', 16:14:35 INFO - 'tpaint', 16:14:35 INFO - 'sessionrestore', 16:14:35 INFO - 'sessionrestore_no_auto_restore']}, 16:14:35 INFO - 'svgr': {'tests': ['tsvgx', 16:14:35 INFO - 'tsvgr_opacity', 16:14:35 INFO - 'tart', 16:14:35 INFO - 'tscrollx', 16:14:35 INFO - 'cart']}, 16:14:35 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:14:35 INFO - 'tests': ['tsvgx', 16:14:35 INFO - 'tsvgr_opacity', 16:14:35 INFO - 'tart', 16:14:35 INFO - 'tscrollx', 16:14:35 INFO - 'cart']}, 16:14:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:35 INFO - 'tests': ['tp5o']}, 16:14:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:35 INFO - 'talos_options': ['--e10s'], 16:14:35 INFO - 'tests': ['tp5o']}, 16:14:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:35 INFO - 'talos_options': ['--xperf_path', 16:14:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:14:35 INFO - 'tests': ['tp5n']}, 16:14:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:14:35 INFO - 'talos_options': ['--e10s', 16:14:35 INFO - '--xperf_path', 16:14:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:14:35 INFO - 'tests': ['tp5n']}}, 16:14:35 INFO - 'talos.zip': {'path': '', 16:14:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:14:35 INFO - Running post-action listener: _resource_record_post_action 16:14:35 INFO - ##### 16:14:35 INFO - ##### Running create-virtualenv step. 16:14:35 INFO - ##### 16:14:35 INFO - Running pre-action listener: _resource_record_pre_action 16:14:35 INFO - Running main action method: create_virtualenv 16:14:35 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:14:35 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 16:14:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:14:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:14:36 INFO - Using real prefix '/usr' 16:14:36 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:14:36 INFO - Installing distribute.............................................................................................................................................................................................done. 16:14:38 INFO - Installing pip.................done. 16:14:38 INFO - Return code: 0 16:14:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:38 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 0x7fc44aa82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d5a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de9350>, '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 0x1dea250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dea6e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', '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 16:14:38 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 16:14:38 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 16:14:38 INFO - Using env: {'DISPLAY': ':0', 16:14:38 INFO - 'HOME': '/home/cltbld', 16:14:38 INFO - 'LANG': 'en_US.UTF-8', 16:14:38 INFO - 'LANGUAGE': 'en_US:en', 16:14:38 INFO - 'LOGNAME': 'cltbld', 16:14:38 INFO - 'MAIL': '/var/mail/cltbld', 16:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:38 INFO - 'MOZ_NO_REMOTE': '1', 16:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:38 INFO - 'NO_EM_RESTART': '1', 16:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:38 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:38 INFO - 'SHELL': '/bin/bash', 16:14:38 INFO - 'SHLVL': '1', 16:14:38 INFO - 'TERM': 'linux', 16:14:38 INFO - 'TMOUT': '86400', 16:14:38 INFO - 'USER': 'cltbld', 16:14:38 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', 16:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:38 INFO - '_': '/tools/buildbot/bin/python'} 16:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:38 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 16:14:38 INFO - Cleaning up... 16:14:38 INFO - Return code: 0 16:14:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:38 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 0x7fc44aa82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d5a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de9350>, '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 0x1dea250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dea6e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', '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 16:14:38 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 16:14:38 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 16:14:38 INFO - Using env: {'DISPLAY': ':0', 16:14:38 INFO - 'HOME': '/home/cltbld', 16:14:38 INFO - 'LANG': 'en_US.UTF-8', 16:14:38 INFO - 'LANGUAGE': 'en_US:en', 16:14:38 INFO - 'LOGNAME': 'cltbld', 16:14:38 INFO - 'MAIL': '/var/mail/cltbld', 16:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:38 INFO - 'MOZ_NO_REMOTE': '1', 16:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:38 INFO - 'NO_EM_RESTART': '1', 16:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:38 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:38 INFO - 'SHELL': '/bin/bash', 16:14:38 INFO - 'SHLVL': '1', 16:14:38 INFO - 'TERM': 'linux', 16:14:38 INFO - 'TMOUT': '86400', 16:14:38 INFO - 'USER': 'cltbld', 16:14:38 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', 16:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:38 INFO - '_': '/tools/buildbot/bin/python'} 16:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:38 INFO - Downloading/unpacking psutil>=0.7.1 16:14:38 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 16:14:38 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 16:14:38 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 16:14:38 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 16:14:38 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 16:14:38 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 16:14:40 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:14:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:14:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:14:40 INFO - Installing collected packages: psutil 16:14:40 INFO - Running setup.py install for psutil 16:14:40 INFO - building 'psutil._psutil_linux' extension 16:14:40 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 16:14:41 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 16:14:41 INFO - building 'psutil._psutil_posix' extension 16:14:41 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 16:14:41 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 16:14:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:14:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:14:41 INFO - Successfully installed psutil 16:14:41 INFO - Cleaning up... 16:14:41 INFO - Return code: 0 16:14:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:41 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 0x7fc44aa82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d5a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de9350>, '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 0x1dea250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dea6e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', '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 16:14:41 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 16:14:41 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 16:14:41 INFO - Using env: {'DISPLAY': ':0', 16:14:41 INFO - 'HOME': '/home/cltbld', 16:14:41 INFO - 'LANG': 'en_US.UTF-8', 16:14:41 INFO - 'LANGUAGE': 'en_US:en', 16:14:41 INFO - 'LOGNAME': 'cltbld', 16:14:41 INFO - 'MAIL': '/var/mail/cltbld', 16:14:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:41 INFO - 'MOZ_NO_REMOTE': '1', 16:14:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:41 INFO - 'NO_EM_RESTART': '1', 16:14:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:41 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:41 INFO - 'SHELL': '/bin/bash', 16:14:41 INFO - 'SHLVL': '1', 16:14:41 INFO - 'TERM': 'linux', 16:14:41 INFO - 'TMOUT': '86400', 16:14:41 INFO - 'USER': 'cltbld', 16:14:41 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', 16:14:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:41 INFO - '_': '/tools/buildbot/bin/python'} 16:14:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:14:43 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:14:43 INFO - Installing collected packages: mozsystemmonitor 16:14:43 INFO - Running setup.py install for mozsystemmonitor 16:14:43 INFO - Successfully installed mozsystemmonitor 16:14:43 INFO - Cleaning up... 16:14:43 INFO - Return code: 0 16:14:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 16:14: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')]} 16:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14: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')]} 16:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:43 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 0x7fc44aa82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d5a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de9350>, '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 0x1dea250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dea6e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', '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 16:14:43 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 16:14:43 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 16:14:43 INFO - Using env: {'DISPLAY': ':0', 16:14:43 INFO - 'HOME': '/home/cltbld', 16:14:43 INFO - 'LANG': 'en_US.UTF-8', 16:14:43 INFO - 'LANGUAGE': 'en_US:en', 16:14:43 INFO - 'LOGNAME': 'cltbld', 16:14:43 INFO - 'MAIL': '/var/mail/cltbld', 16:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:43 INFO - 'MOZ_NO_REMOTE': '1', 16:14:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:43 INFO - 'NO_EM_RESTART': '1', 16:14:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:43 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:43 INFO - 'SHELL': '/bin/bash', 16:14:43 INFO - 'SHLVL': '1', 16:14:43 INFO - 'TERM': 'linux', 16:14:43 INFO - 'TMOUT': '86400', 16:14:43 INFO - 'USER': 'cltbld', 16:14:43 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', 16:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:43 INFO - '_': '/tools/buildbot/bin/python'} 16:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:43 INFO - Downloading/unpacking blobuploader==1.2.4 16:14:43 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 16:14:43 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 16:14:43 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 16:14:43 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 16:14:43 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 16:14:43 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 16:14:45 INFO - Downloading blobuploader-1.2.4.tar.gz 16:14:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:14:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:14:45 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 16:14:45 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 16:14:45 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 16:14:45 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 16:14:45 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 16:14:45 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 16:14:45 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:14:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:46 INFO - Downloading docopt-0.6.1.tar.gz 16:14:46 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:14:46 INFO - Installing collected packages: blobuploader, requests, docopt 16:14:46 INFO - Running setup.py install for blobuploader 16:14:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:14:46 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:14:46 INFO - Running setup.py install for requests 16:14:46 INFO - Running setup.py install for docopt 16:14:46 INFO - Successfully installed blobuploader requests docopt 16:14:46 INFO - Cleaning up... 16:14:46 INFO - Return code: 0 16:14:46 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:46 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 0x7fc44aa82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d5a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de9350>, '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 0x1dea250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dea6e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', '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 16:14:46 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 16:14:46 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 16:14:46 INFO - Using env: {'DISPLAY': ':0', 16:14:46 INFO - 'HOME': '/home/cltbld', 16:14:46 INFO - 'LANG': 'en_US.UTF-8', 16:14:46 INFO - 'LANGUAGE': 'en_US:en', 16:14:46 INFO - 'LOGNAME': 'cltbld', 16:14:46 INFO - 'MAIL': '/var/mail/cltbld', 16:14:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:46 INFO - 'MOZ_NO_REMOTE': '1', 16:14:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:46 INFO - 'NO_EM_RESTART': '1', 16:14:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:46 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:46 INFO - 'SHELL': '/bin/bash', 16:14:46 INFO - 'SHLVL': '1', 16:14:46 INFO - 'TERM': 'linux', 16:14:46 INFO - 'TMOUT': '86400', 16:14:46 INFO - 'USER': 'cltbld', 16:14:46 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', 16:14:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:46 INFO - '_': '/tools/buildbot/bin/python'} 16:14:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:14:47 INFO - Running setup.py (path:/tmp/pip-VUKnD_-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:14:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:14:47 INFO - Running setup.py (path:/tmp/pip-LBy0s6-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:14:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:14:47 INFO - Running setup.py (path:/tmp/pip-nDIdul-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:14:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:14:47 INFO - Running setup.py (path:/tmp/pip-VffgyX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:14:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:14:47 INFO - Running setup.py (path:/tmp/pip-0cmzU1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:14:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:14:47 INFO - Running setup.py (path:/tmp/pip-E5yVvJ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:14:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:14:47 INFO - Running setup.py (path:/tmp/pip-XVO5dn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:14:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:14:47 INFO - Running setup.py (path:/tmp/pip-fdpPJA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:14:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:14:47 INFO - Running setup.py (path:/tmp/pip-OOL5rO-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:14:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:14:47 INFO - Running setup.py (path:/tmp/pip-LIiJLh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:14:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:14:47 INFO - Running setup.py (path:/tmp/pip-vyOeM5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:14:47 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:14:47 INFO - Running setup.py (path:/tmp/pip-XU_7lA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:14:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:14:48 INFO - Running setup.py (path:/tmp/pip-a7nd5R-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:14:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:14:48 INFO - Running setup.py (path:/tmp/pip-2R5PoB-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:14:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:14:48 INFO - Running setup.py (path:/tmp/pip-wUt1gV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:14:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:14:48 INFO - Running setup.py (path:/tmp/pip-tufynG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:14:48 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:14:48 INFO - Running setup.py (path:/tmp/pip-oHkjpl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:14:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:14:48 INFO - Running setup.py install for manifestparser 16:14:48 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:14:48 INFO - Running setup.py install for mozcrash 16:14:48 INFO - Running setup.py install for mozdebug 16:14:48 INFO - Running setup.py install for mozdevice 16:14:48 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:14:48 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:14:48 INFO - Running setup.py install for mozfile 16:14:48 INFO - Running setup.py install for mozhttpd 16:14:49 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:14:49 INFO - Running setup.py install for mozinfo 16:14:49 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:14:49 INFO - Running setup.py install for mozInstall 16:14:49 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:14:49 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:14:49 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:14:49 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:14:49 INFO - Running setup.py install for mozleak 16:14:49 INFO - Running setup.py install for mozlog 16:14:49 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:14:49 INFO - Running setup.py install for moznetwork 16:14:49 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:14:49 INFO - Running setup.py install for mozprocess 16:14:49 INFO - Running setup.py install for mozprofile 16:14:49 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:14:49 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:14:49 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:14:49 INFO - Running setup.py install for mozrunner 16:14:49 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:14:49 INFO - Running setup.py install for mozscreenshot 16:14:49 INFO - Running setup.py install for moztest 16:14:50 INFO - Running setup.py install for mozversion 16:14:50 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:14:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:14:50 INFO - Cleaning up... 16:14:50 INFO - Return code: 0 16:14:50 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:14:50 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')]} 16:14:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:50 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')]} 16:14:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:50 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 0x7fc44aa82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d5a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de9350>, '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 0x1dea250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dea6e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', '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 16:14:50 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 16:14:50 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 16:14:50 INFO - Using env: {'DISPLAY': ':0', 16:14:50 INFO - 'HOME': '/home/cltbld', 16:14:50 INFO - 'LANG': 'en_US.UTF-8', 16:14:50 INFO - 'LANGUAGE': 'en_US:en', 16:14:50 INFO - 'LOGNAME': 'cltbld', 16:14:50 INFO - 'MAIL': '/var/mail/cltbld', 16:14:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:50 INFO - 'MOZ_NO_REMOTE': '1', 16:14:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:50 INFO - 'NO_EM_RESTART': '1', 16:14:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:50 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:50 INFO - 'SHELL': '/bin/bash', 16:14:50 INFO - 'SHLVL': '1', 16:14:50 INFO - 'TERM': 'linux', 16:14:50 INFO - 'TMOUT': '86400', 16:14:50 INFO - 'USER': 'cltbld', 16:14:50 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', 16:14:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:50 INFO - '_': '/tools/buildbot/bin/python'} 16:14:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:14:50 INFO - Running setup.py (path:/tmp/pip-lMY3Cw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:14:50 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)) 16:14:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:14:50 INFO - Running setup.py (path:/tmp/pip-4Wkz4c-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:14:50 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)) 16:14:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:14:50 INFO - Running setup.py (path:/tmp/pip-UCyshC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:14:50 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)) 16:14:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:14:50 INFO - Running setup.py (path:/tmp/pip-8KETO0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:14:50 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)) 16:14:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:14:50 INFO - Running setup.py (path:/tmp/pip-23SlD0-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:14:50 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)) 16:14:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:14:50 INFO - Running setup.py (path:/tmp/pip-PNFLci-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:14:50 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)) 16:14:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:14:50 INFO - Running setup.py (path:/tmp/pip-raUyyw-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:14:50 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)) 16:14:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:14:50 INFO - Running setup.py (path:/tmp/pip-qC_2XQ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:14:50 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)) 16:14:50 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:14:50 INFO - Running setup.py (path:/tmp/pip-WanZv2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:14:51 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)) 16:14:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:14:51 INFO - Running setup.py (path:/tmp/pip-LOgztM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:14:51 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)) 16:14:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:14:51 INFO - Running setup.py (path:/tmp/pip-w7uhBK-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:14:51 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)) 16:14:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:14:51 INFO - Running setup.py (path:/tmp/pip-hrb7w5-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:14:51 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)) 16:14:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:14:51 INFO - Running setup.py (path:/tmp/pip-jBy8iV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:14:51 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)) 16:14:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:14:51 INFO - Running setup.py (path:/tmp/pip-gIdwzU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:14:51 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)) 16:14:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:14:51 INFO - Running setup.py (path:/tmp/pip-gUetbj-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:14:51 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)) 16:14:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:14:51 INFO - Running setup.py (path:/tmp/pip-QvzMSe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:14:51 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)) 16:14:51 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:14:51 INFO - Running setup.py (path:/tmp/pip-441VcI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:14:51 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)) 16:14:51 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)) 16:14:51 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)) 16:14:51 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)) 16:14:51 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)) 16:14:51 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)) 16:14:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:14:51 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 16:14:51 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 16:14:51 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 16:14:51 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 16:14:51 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 16:14:51 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 16:14:53 INFO - Downloading blessings-1.6.tar.gz 16:14:53 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:14:53 INFO - Installing collected packages: blessings 16:14:53 INFO - Running setup.py install for blessings 16:14:53 INFO - Successfully installed blessings 16:14:53 INFO - Cleaning up... 16:14:53 INFO - Return code: 0 16:14:53 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:14:53 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:14:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:14:53 INFO - Reading from file tmpfile_stdout 16:14:53 INFO - Current package versions: 16:14:53 INFO - argparse == 1.2.1 16:14:53 INFO - blessings == 1.6 16:14:53 INFO - blobuploader == 1.2.4 16:14:53 INFO - docopt == 0.6.1 16:14:53 INFO - manifestparser == 1.1 16:14:53 INFO - mozInstall == 1.12 16:14:53 INFO - mozcrash == 0.16 16:14:53 INFO - mozdebug == 0.1 16:14:53 INFO - mozdevice == 0.47 16:14:53 INFO - mozfile == 1.2 16:14:53 INFO - mozhttpd == 0.7 16:14:53 INFO - mozinfo == 0.9 16:14:53 INFO - mozleak == 0.1 16:14:53 INFO - mozlog == 3.1 16:14:53 INFO - moznetwork == 0.27 16:14:53 INFO - mozprocess == 0.22 16:14:53 INFO - mozprofile == 0.27 16:14:53 INFO - mozrunner == 6.11 16:14:53 INFO - mozscreenshot == 0.1 16:14:53 INFO - mozsystemmonitor == 0.0 16:14:53 INFO - moztest == 0.7 16:14:53 INFO - mozversion == 1.4 16:14:53 INFO - psutil == 3.1.1 16:14:53 INFO - requests == 1.2.3 16:14:53 INFO - wsgiref == 0.1.2 16:14:53 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:53 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 0x7fc44aa82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d5a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de9350>, '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 0x1dea250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dea6e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', '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 16:14:53 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 16:14:53 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 16:14:53 INFO - Using env: {'DISPLAY': ':0', 16:14:53 INFO - 'HOME': '/home/cltbld', 16:14:53 INFO - 'LANG': 'en_US.UTF-8', 16:14:53 INFO - 'LANGUAGE': 'en_US:en', 16:14:53 INFO - 'LOGNAME': 'cltbld', 16:14:53 INFO - 'MAIL': '/var/mail/cltbld', 16:14:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:53 INFO - 'MOZ_NO_REMOTE': '1', 16:14:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:53 INFO - 'NO_EM_RESTART': '1', 16:14:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:53 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:53 INFO - 'SHELL': '/bin/bash', 16:14:53 INFO - 'SHLVL': '1', 16:14:53 INFO - 'TERM': 'linux', 16:14:53 INFO - 'TMOUT': '86400', 16:14:53 INFO - 'USER': 'cltbld', 16:14:53 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', 16:14:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:53 INFO - '_': '/tools/buildbot/bin/python'} 16:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:54 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)) 16:14:54 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)) 16:14:54 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)) 16:14:54 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)) 16:14:54 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)) 16:14:54 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)) 16:14:54 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)) 16:14:54 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)) 16:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 1)) 16:14:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test-pgo/build/tests/talos/requirements.txt (line 3)) 16:14:54 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)) 16:14:54 INFO - Cleaning up... 16:14:54 INFO - Return code: 0 16:14:54 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 16:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:54 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 0x7fc44aa82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d5a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de9350>, '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 0x1dea250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dea6e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', '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 16:14:54 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 16:14:54 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 16:14:54 INFO - Using env: {'DISPLAY': ':0', 16:14:54 INFO - 'HOME': '/home/cltbld', 16:14:54 INFO - 'LANG': 'en_US.UTF-8', 16:14:54 INFO - 'LANGUAGE': 'en_US:en', 16:14:54 INFO - 'LOGNAME': 'cltbld', 16:14:54 INFO - 'MAIL': '/var/mail/cltbld', 16:14:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:54 INFO - 'MOZ_NO_REMOTE': '1', 16:14:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:54 INFO - 'NO_EM_RESTART': '1', 16:14:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:54 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:54 INFO - 'SHELL': '/bin/bash', 16:14:54 INFO - 'SHLVL': '1', 16:14:54 INFO - 'TERM': 'linux', 16:14:54 INFO - 'TMOUT': '86400', 16:14:54 INFO - 'USER': 'cltbld', 16:14:54 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', 16:14:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:54 INFO - '_': '/tools/buildbot/bin/python'} 16:14:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:54 INFO - Downloading/unpacking jsonschema 16:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:14:56 INFO - Downloading/unpacking functools32 (from jsonschema) 16:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:56 INFO - Downloading functools32-3.2.3-2.tar.gz 16:14:56 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 16:14:56 INFO - warning: no files found matching '*.txt' 16:14:56 INFO - no previously-included directories found matching 'build' 16:14:56 INFO - no previously-included directories found matching 'dist' 16:14:56 INFO - no previously-included directories found matching '.git*' 16:14:56 INFO - Installing collected packages: jsonschema, functools32 16:14:56 INFO - Running setup.py install for functools32 16:14:56 INFO - warning: no files found matching '*.txt' 16:14:56 INFO - no previously-included directories found matching 'build' 16:14:56 INFO - no previously-included directories found matching 'dist' 16:14:56 INFO - no previously-included directories found matching '.git*' 16:14:56 INFO - Successfully installed jsonschema functools32 16:14:56 INFO - Cleaning up... 16:14:56 INFO - Return code: 0 16:14:56 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 16:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:14:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:14:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:14:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:14:56 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 0x7fc44aa82f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d5a600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de9350>, '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 0x1dea250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dea6e0>, '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': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', '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 16:14:56 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 16:14:56 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 16:14:56 INFO - Using env: {'DISPLAY': ':0', 16:14:56 INFO - 'HOME': '/home/cltbld', 16:14:56 INFO - 'LANG': 'en_US.UTF-8', 16:14:56 INFO - 'LANGUAGE': 'en_US:en', 16:14:56 INFO - 'LOGNAME': 'cltbld', 16:14:56 INFO - 'MAIL': '/var/mail/cltbld', 16:14:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:56 INFO - 'MOZ_NO_REMOTE': '1', 16:14:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:14:56 INFO - 'NO_EM_RESTART': '1', 16:14:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:14:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:14:56 INFO - 'PWD': '/builds/slave/test-pgo', 16:14:56 INFO - 'SHELL': '/bin/bash', 16:14:56 INFO - 'SHLVL': '1', 16:14:56 INFO - 'TERM': 'linux', 16:14:56 INFO - 'TMOUT': '86400', 16:14:56 INFO - 'USER': 'cltbld', 16:14:56 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', 16:14:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:56 INFO - '_': '/tools/buildbot/bin/python'} 16:14:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:14:56 INFO - Downloading/unpacking flake8 16:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:14:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:14:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:14:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:14:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:14:59 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 16:14:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:14:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:14:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:14:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:14:59 INFO - no previously-included directories found matching 'docs/_build' 16:14:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:14:59 INFO - Running setup.py install for pep8 16:14:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:14:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:14:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:14:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:14:59 INFO - no previously-included directories found matching 'docs/_build' 16:14:59 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 16:14:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:14:59 INFO - Cleaning up... 16:14:59 INFO - Return code: 0 16:14:59 INFO - Running post-action listener: _resource_record_post_action 16:14:59 INFO - Running post-action listener: _start_resource_monitoring 16:14:59 INFO - Starting resource monitoring. 16:14:59 INFO - ##### 16:14:59 INFO - ##### Running install step. 16:14:59 INFO - ##### 16:14:59 INFO - Running pre-action listener: _resource_record_pre_action 16:14:59 INFO - Running main action method: install 16:14:59 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:14:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:14:59 INFO - Reading from file tmpfile_stdout 16:14:59 INFO - Detecting whether we're running mozinstall >=1.0... 16:14:59 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:14:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:14:59 INFO - Reading from file tmpfile_stdout 16:14:59 INFO - Output received: 16:14:59 INFO - Usage: mozinstall [options] installer 16:14:59 INFO - Options: 16:14:59 INFO - -h, --help show this help message and exit 16:14:59 INFO - -d DEST, --destination=DEST 16:14:59 INFO - Directory to install application into. [default: 16:14:59 INFO - "/builds/slave/test-pgo"] 16:14:59 INFO - --app=APP Application being installed. [default: firefox] 16:14:59 INFO - mkdir: /builds/slave/test-pgo/build/application 16:14:59 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'] 16:14:59 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 16:15:14 INFO - Reading from file tmpfile_stdout 16:15:14 INFO - Output received: 16:15:14 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:15:14 INFO - Running post-action listener: _resource_record_post_action 16:15:14 INFO - ##### 16:15:14 INFO - ##### Running run-tests step. 16:15:14 INFO - ##### 16:15:14 INFO - Running pre-action listener: _resource_record_pre_action 16:15:14 INFO - Running main action method: run_tests 16:15:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:15:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:15:14 INFO - Python 2.7.3 16:15:14 INFO - Return code: 0 16:15:14 INFO - grabbing minidump binary from tooltool 16:15:14 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')]} 16:15:14 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 0x1de9350>, '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 0x1dea250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dea6e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:15:14 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 16:15:14 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 16:15:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:15:14 INFO - Return code: 0 16:15:14 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:15:14 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:15:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:15:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:15:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:15:14 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 16:15:14 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 16:15:15 INFO - Return code: 0 16:15:15 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/1455832105/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 16:15:15 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/1455832105/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-049 --webServer localhost 16:15:15 INFO - Using env: {'DISPLAY': ':0', 16:15:15 INFO - 'HOME': '/home/cltbld', 16:15:15 INFO - 'LANG': 'en_US.UTF-8', 16:15:15 INFO - 'LANGUAGE': 'en_US:en', 16:15:15 INFO - 'LOGNAME': 'cltbld', 16:15:15 INFO - 'MAIL': '/var/mail/cltbld', 16:15:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:15:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:15:15 INFO - 'MOZ_NO_REMOTE': '1', 16:15:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:15:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:15:15 INFO - 'NO_EM_RESTART': '1', 16:15:15 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:15:15 INFO - 'PWD': '/builds/slave/test-pgo', 16:15:15 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:15:15 INFO - 'SHELL': '/bin/bash', 16:15:15 INFO - 'SHLVL': '1', 16:15:15 INFO - 'TERM': 'linux', 16:15:15 INFO - 'TMOUT': '86400', 16:15:15 INFO - 'USER': 'cltbld', 16:15:15 INFO - 'XDG_SESSION_COOKIE': '8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054', 16:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:15:15 INFO - '_': '/tools/buildbot/bin/python'} 16:15:15 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/1455832105/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-049', '--webServer', 'localhost'] with output_timeout 3600 16:15:15 INFO - mozversion INFO | application_buildid: 20160218134825 16:15:15 INFO - mozversion INFO | application_changeset: 32608d640a8c72afc6f16c1883e08846aa80905d 16:15:15 INFO - mozversion INFO | application_display_name: Nightly 16:15:15 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:15:15 INFO - mozversion INFO | application_name: Firefox 16:15:15 INFO - mozversion INFO | application_remotingname: firefox 16:15:15 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:15:15 INFO - mozversion INFO | application_vendor: Mozilla 16:15:15 INFO - mozversion INFO | application_version: 45.0 16:15:15 INFO - mozversion INFO | platform_buildid: 20160218134825 16:15:15 INFO - mozversion INFO | platform_changeset: 32608d640a8c72afc6f16c1883e08846aa80905d 16:15:15 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:15:15 INFO - mozversion INFO | platform_version: 45.0 16:15:15 INFO - 2016-02-18 16:15:15,887 DEBUG : using testdate: 1455840915 16:15:15 INFO - 2016-02-18 16:15:15,887 DEBUG : actual date: 1455840915 16:15:15 INFO - 2016-02-18 16:15:15,895 INFO : starting webserver on 'localhost:37312' 16:15:15 INFO - 2016-02-18 16:15:15,896 INFO : Starting test suite talos-linux64-ix-049 16:15:15 INFO - 2016-02-18 16:15:15,896 INFO : Starting test dromaeo_css 16:15:15 INFO - 2016-02-18 16:15:15,896 DEBUG : operating with platform_type : linux_ 16:15:15 INFO - 2016-02-18 16:15:15,897 INFO : Initialising browser for dromaeo_css test... 16:15:15 INFO - 2016-02-18 16:15:15,913 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCDf1Nd/profile http://localhost:37312/getInfo.html 16:15:19 INFO - 2016-02-18 16:15:19,635 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:15:19 INFO - 2016-02-18 16:15:19,636 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:15:19 INFO - 2016-02-18 16:15:19,636 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:15:19 INFO - 2016-02-18 16:15:19,636 DEBUG : BROWSER_OUTPUT: __metrics 16:15:19 INFO - 2016-02-18 16:15:19,757 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] 16:15:20 INFO - 2016-02-18 16:15:20,797 INFO : Browser initialized. 16:15:20 INFO - 2016-02-18 16:15:20,798 INFO : Running cycle 1/1 for dromaeo_css test... 16:15:20 INFO - 2016-02-18 16:15:20,798 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpCDf1Nd/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:15:32 INFO - 2016-02-18 16:15:32,388 DEBUG : BROWSER_OUTPUT: RSS: Main: 132878336 16:15:32 INFO - 2016-02-18 16:15:32,388 DEBUG : BROWSER_OUTPUT: 16:16:23 INFO - 2016-02-18 16:16:23,072 DEBUG : BROWSER_OUTPUT: 1455840983071 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] 16:18:31 INFO - 2016-02-18 16:18:31,376 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37312/tests/dromaeo/cssquery-ext.html) 16:18:32 INFO - 2016-02-18 16:18:32,449 DEBUG : BROWSER_OUTPUT: RSS: Main: 230703104 16:18:32 INFO - 2016-02-18 16:18:32,449 DEBUG : BROWSER_OUTPUT: 16:21:34 INFO - 2016-02-18 16:21:34,512 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/dromaeo/cssquery-ext.html (next: http://localhost:37312/tests/dromaeo/cssquery-jquery.html) 16:21:35 INFO - 2016-02-18 16:21:35,074 DEBUG : BROWSER_OUTPUT: RSS: Main: 212422656 16:21:35 INFO - 2016-02-18 16:21:35,075 DEBUG : BROWSER_OUTPUT: 16:24:36 INFO - 2016-02-18 16:24:36,857 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37312/tests/dromaeo/cssquery-mootools.html) 16:24:37 INFO - 2016-02-18 16:24:37,418 DEBUG : BROWSER_OUTPUT: RSS: Main: 209211392 16:24:37 INFO - 2016-02-18 16:24:37,418 DEBUG : BROWSER_OUTPUT: 16:27:39 INFO - 2016-02-18 16:27:39,077 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37312/tests/dromaeo/cssquery-prototype.html) 16:27:39 INFO - 2016-02-18 16:27:39,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 197644288 16:27:39 INFO - 2016-02-18 16:27:39,641 DEBUG : BROWSER_OUTPUT: 16:30:42 INFO - 2016-02-18 16:30:42,015 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37312/tests/dromaeo/cssquery-yui.html) 16:30:42 INFO - 2016-02-18 16:30:42,577 DEBUG : BROWSER_OUTPUT: RSS: Main: 198877184 16:30:42 INFO - 2016-02-18 16:30:42,577 DEBUG : BROWSER_OUTPUT: 16:33:44 INFO - 2016-02-18 16:33:44,798 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/dromaeo/cssquery-yui.html (next: http://localhost:37312/tests/dromaeo/cssquery-dojo.html) 16:33:45 INFO - 2016-02-18 16:33:45,048 DEBUG : BROWSER_OUTPUT: RSS: Main: 241152000 16:33:45 INFO - 2016-02-18 16:33:45,048 DEBUG : BROWSER_OUTPUT: 16:33:45 INFO - 2016-02-18 16:33:45,049 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:33:45 INFO - 2016-02-18 16:33:45,049 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:33:45 INFO - 2016-02-18 16:33:45,049 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:33:45 INFO - 2016-02-18 16:33:45,050 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:33:45 INFO - 2016-02-18 16:33:45,051 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3291.325278317945;3342.181165614425;3345.4981752737103;3375.6455572164923;3403.1322012886717;5462.426445223249;5511.118221084627;5522.116461366182;5530.087535556633;5542.7782888684505;5497.340319161706;5497.532709719679;5506.229127922094;5538.418466061066;5547.0570003099465;4887.1447496688115;5020.071286811952;5049.4193167785625;5131;5135.024345374386;4938.604911607063;4951.678140920847;5030.2957585938;5067.113255180348;5085;5062.544371006608;5077.314562534048;5084.161113416282;5089.312942752718;5101.668391554537;5060.924086138711;5068.113080210986;5087.720175390352;5107.080725469414;5136.717480538568;4829.734364609945;4836.2503811908855;4926.359573255454;4932.260160975882;4934.6792458490445;5317.973410132963;5359.035373632744;5361.195820626898;5374.059539580558;5386.91919621206;5578.748956296936;5917.497012753921;5919.260092488439;5964.97017514914;5965.403459654043;5745.7127143643065;5943.138244954457;6019.69901504923;6032.728527216286;6033.5173186145;5555.3611334696625;5559.499645031967;5562.443755624446;5562.805301814417;5565.860853478655;5721.5708821838525;5948.553858460633;6004.489618382487;6009.969950150221;6015.127806468037;5732.914006289909;5738.540916726652;5839.708014599253;5852.092925596539;5865.472107510344;5931.288245410578;5988.580799344091;5994.520438364921;6004.039353703386;6022.759089636453;5639.943600563942;5766.88466230673;5956.493698035629;5962.761489540369;5966.224390829165;5798.391168927202;5860.355360910296;5861.150133230658;5886.646801191942;5903.439173278532;4447.62195213404;4454.77726113693;4458.375827384169;4467.262901621196;4482.4172857528965;3937.645611894943;4018.1361007383553;4035.6771458283265;4081.3265811141423;4107.6508496777515;5598.3282006159525;5609.719514024282;5631;5634.85912852182;5673.06394444912;5967.254093238345;5986.132010858401;5987.31145918225;5987.640741555433;5993.580449368503;5386.757595908193;5538.6399884007415;5605.271314729059;5621.494065534122;5627.887442251132;5130.461301563357;5150.27896094547;5175.844724658266;5195.636305458582;5200.5059519345605;6009.789657361971;6014.0377539593455;6015.608985416022;6016.819495415145;6020.096985452217;5779.884402311846;5904.881902362014;5908.14331921869;5908.675022873783;5910.822675319747;6046.455818976313;6084.77757367609;6129.84675383119;6144.586745048575;6152.384761523812;5872.55955803325;5882.411758824083;5897.941020589739;5900;5919.4080591940465;5706.514946229617;5853.736581853742;5941.554383421347;5987.940120598738;5989.9700501498955;5911.615744976562;5912.46787789101;5919.289685237799;5930.05119180929;5938.703064846827;5879.382664820303;5892.410758924073;5893.970530147321;5895;5908.468237858613;5474.972625136849;5601.663900166003;5644.971775141098;5698.487136157766;5809.680467574324;5923.815236952713;5942.73257703413;5955.2853657559635;5955.434233747787;5971.104334349882;5842.474177324061;5849.210356601804;5849.239598852122;5849.795257165979;5849.970750146222 16:33:45 INFO - 2016-02-18 16:33:45,052 DEBUG : BROWSER_OUTPUT: |1;ext.html;597.3578403216543;631.2677294338567;642.8649983503466;675.4832553096878;686.8900975843866;32022;42085.57914420855;42413;42442.36336454948;42466.787666061704;46753.766231168884;47430.52569474304;47475.28787068188;47526;47567;8076.152004039579;8198.713045043425;8907.198352148289;9259.768505787342;10627.84058239127;10757.30077544961;10783.137349012099;10799.892001080007;10841;10906.127509799197;10902.945485272603;10983.670489885317;11012.284201526914;11025.283356581836;11027;10735.838962415532;10904.291221070645;10911.345319280788;10920.453977301104;10920.726981825439;10753.354798712102;10816.621418250323;10866.315721063247;10894.128469722482;10894.945525272402;43382.349264761055;43684.34473482901;43761;43896;43935.56064439347;3620.981895090534;3906.453096566483;4015.297322968498;4266.3813747006825;4304;33129.6687033129;33213.33573328544;33506;33516.66483335184;33551;4341.630961368288;4392.89017774555;4447.73313601185;4448.644108471347;4544.386507821435;278695;279499.99999999796;279652;280205;280848;57702;58938;65668.67165664141;72565.27434725585;73145.6342718283;65230.34769652338;65753.34246657568;65837.34162658408;66017;66368.33631663719;15564.844351556567;15801.209939502978;15803.920980395025;15810;15832.050076995187;11123.833142502868;11259.943700281447;11261.324320540794;11272.21094523392;11296.661100167008;22210.66683999741;22554;22587;22592.774072259395;22683.432914177276;8438.704645337506;8587.39888207832;8751.431156974777;8754.693585724597;8771.868421973675;1061.9734506637396;1110.1174566219875;1151.9020883225019;1158.0272571040366;1230.501646833034;26658.333541661614;27010.5948410774;27471.31321716973;30703.385932281228;31085.22286942799;1102.2229328323513;1182.2847177457686;1189.2448295332354;1256.7360854220717;1286.1961274203622;14396;15282.388704451698;15346.53960381168;15592;16397.154913559927;333000;333188;333211;333292;333918;56728.14907776387;56969;57086.42913570894;57603.135952960736;57615.42384576184;1343.4491858338035;1390.1033833177544;1449.340550049725;1480.2376775960388;1494.6412860913304;27445.862770686017;28704.856475717486;29145.56281655819;29685.554716678835;29840;46081;46742.76628616835;46790.76604616955;46807.76596116998;46852.531474684816;219925;221480;221705;222248;223293;35639.10902227465;36002.63997359993;36092.09769755777;36470;36513.452298215016;47479.52520474751;47743.04513909772;48226.27660585024;48565.27152092792;48671.969125093965;38465.2306953865;39206;39456;39457.40813887736;39983.80008099941;40431.1913761729;40734.1119065714;40877.79561102176;41371.172576547695;41611;40342.79828600838;40348.394774079;40352.3947140799;40641;40747.59252407438;35083;35678.4648230282;36837.81581092077;38079;38128.42807357836;26679.599806003313;27428.862855685595;29397.559036614897;29910;30095.548566771955 16:33:45 INFO - 2016-02-18 16:33:45,056 DEBUG : BROWSER_OUTPUT: |2;jquery.html;391.7923500544711;391.8099721635007;393.44524220848604;395.5372214509024;397.1302846765585;15298.23508824557;16640.500784976455;16908.577285567866;18507.81492185076;18508.167132479037;17695.911520442412;18145.7278140828;18147.274109035654;18538.258469661232;18905.810941890577;5089.567386772129;5149.588032957355;5177.844664660048;5198.2982297389935;5203;3989.5810939851335;4100.85647002355;5144.17693169093;5565.471280228372;5722.513586345166;5601.327840659126;5605.803796867111;5627.521660658849;5662.801801936933;5706.457886500796;5459.235707001023;5591.7483713233005;5602.60781745278;5608.859778505529;5612.7754889804355;5393.406725260218;5393.433689462589;5403.081476149064;5404.351477822647;5405.864853378697;13684.31578421075;14164.787528187086;14175.220362880038;14180;19746.70379944302;2197.6703494475796;2212.6570381590877;2320.5358928214423;2371.632396978471;2454.4722884579724;10091;10106.994075864914;10208.642697505626;10301.484925753757;10602.734931626694;1881.369741136721;2022.393282015404;2030.7360043194437;2033.644112280345;2034.9287774927807;64431.35568644301;66347.66826165887;66623;68451;68665.65667171682;11037;11298.322100673986;11368.658940231806;11391.886081139082;11463.656090317303;14686.63283417923;14996.925015374853;15022;15062.096274223362;15064.623384415478;7440.284296231836;7640.197779233212;7666.65500052499;7704.383649308042;7718.459707820511;6952;6964.233934267227;6995.055667484824;7035.613041282779;7089.61007144612;4885.633577481632;4895.8041678332465;4956.306117143603;4979.950200498021;4982.352294201731;1737.3179093708513;1979.3666026871551;1979.475439008664;1987.1355960157377;2010.0854111379288;923.4459324405303;972.6498460554336;975.4488713876636;993.1111655068673;995.1740055753708;14979;17393.478195654152;17750.37873674441;17831.197596107948;18534.629307414045;965.4641673870929;1007.793402352516;1050.8686414198226;1051.6529545250048;1096.4243772019595;9299.53502324881;9500.23998080152;9579.13787759105;9687.70936871895;9767.755806104904;594713;595905;607981;628800;638172;87302.56348718215;88690;89470;89645.55177224202;91478.54260728786;1176.5705517486056;1180.15029178991;1202.8797120288077;1209.0206932384797;1223.8470191226097;15586.376544938075;15655.84344156544;15658.886050383053;15684.215789210723;15844.76232856485;33968.66031339655;35063.12342191466;35326.293474130885;35659;36061.81969090138;45083.77458112688;48256.517434825204;49604.25593616026;49694;51356.48643513666;9989.250806189419;10051.095401413908;10208.336458130198;10243.487825608541;10283.331583447052;11783.293002419883;11903.523859045541;11993.520259189534;14169.43322267139;14767.335469903664;14608.926955365156;14963.476278330207;15183.544493665207;15196.316165772789;15800.683986319978;14878.181700006604;14927.925360373129;15013.774793378327;15092.245387730789;15330.540083797503;15016.6996660064;15723;15913;16103.67792644164;16255.268512916711;9762.219022478326;10215.489225538544;10293.125084367912;10306.536205870603;10361.067503924683;10674.412907290174;10737.248392612442;11832.34922079294;15730.056196628238;16058.919705401866 16:33:45 INFO - 2016-02-18 16:33:45,058 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1532.5172570640254;1752;1819.6683716710043;1845.7600511933447;1863.7297591849183;7187.496875218737;7509.136449308325;7513.812154696136;7518.398528117751;7567;6387.936120638792;6440.162778838756;7112.537685050468;7306.853862922739;7352.522086064416;2195.1768086967386;2203.283932721864;2225.63277059285;2226.443389152712;2228.821694264455;1938.8158124978174;2138.1874887542713;2185.584738899612;2187.814035806958;2188.2341180586827;1899.2023350192883;2159.838012149095;2214.180753121347;2215.4572129828216;2215.8559693619945;2141.1649456711893;2163.967540486894;2164.404788683125;2167.8590891592;2174.7281589801273;2188.9671654925187;2191.2330684260382;2204.1403852497538;2215.9778402215934;2218.744844342889;7417.332440080419;7482.289182527651;7490.4756667033325;7494.1381741099385;7499.325060744504;4440.777961101932;4541.613962813165;4541.8410355637725;4544.545545445462;4546.454425468932;2945.3225758075764;3012.5782390465133;3014.4724673182104;3048.2531779714113;3053.8167709937475;996.4170959988343;1013.4426065663929;1018.9082982531608;1044.655263762964;1048.114343379843;9897.554610042565;9916.057974492413;9927.255455840841;9930.304878658497;9940.652077177265;4064.9796751016056;4122.154958233555;4127.09203975125;4141.855035073758;4174.666026717855;8605.268552173135;8614.396992210484;8616.138386161334;8630.654773808978;8630.956845215735;4598.977005114953;4624.1445332613575;4632.444106707217;4637.25803871379;4638.0260145369175;4220.788960552021;4244.3633454982;4246.363045543193;4247.49030116388;4253.595908388698;3601.3517566838223;3639.490471334015;3659.798711070917;3668.192997540537;3685.152414944524;1062.95216715248;1074.1353210665334;1181.4270079011671;1187.6852634051984;1194.4624918786408;1160.5879912631062;1165.5279611757253;1178.0634395655475;1182.018924292835;1188.7800756859835;7180.31786980236;7206.891896621555;7241.131064272321;7289.380402665833;7293;1200.6458094862037;1209.3469526455792;1209.866914639389;1210.2980271442543;1225.7609766095525;5807.738651760681;5859.091840764603;5868.2664666916635;5873.823785286363;5880.470757631748;8434.493930364199;8932.062133476022;8943.910560894437;8961.372703910794;8979.10208979097;8769.254613357809;8777.605007774666;8858.512781797062;8890.73327800167;8906.55467226636;6878.896816547755;6917.481188910851;7131.964340178266;7136.92863071373;7149.428045756327;6962.547434416747;6992.4406047517095;6994.510384273052;6994.5803251805055;6994.860102798018;7779.494332868346;7965.044194696674;7997.4401791876335;8092.190780921861;8107.635156418093;8064.55644939534;8257.21556452136;8280.171982801912;8297.04583972851;8301.211384918422;6232.940400132079;6296.212973378328;6444.8388790280615;6449.419552240321;6469.126667899774;7162.176349719849;7216.7834964951135;7220.133583969747;7220.711171553079;7223.422126229784;5841.18223448709;6763.932360676527;6853.7943861684225;6957.930420695729;7003.019577259086;6550.868982620416;6574.967125164344;6600.339966003361;6691.799246022627;6819.5908245505425;6769.323067693192;6787.89818152738;6873.690683919136;6886.414654754201;6887.276835932155;6334.904976425265;6360;6381.234251889803;6385.169927909342;6391.456726178327;6379.904301435389;6416.454601358937;6455.741770329134;6470.935290647033;6480.513961452815 16:33:45 INFO - 2016-02-18 16:33:45,061 DEBUG : BROWSER_OUTPUT: |4;prototype.html;352.1864493021121;361.31530749230194;363.88537610652645;365.5850609558151;369.39419352262297;6225.159603453538;6235.407636274558;6254.562180647351;6257;6281.309056003847;5929.614575052618;6029.125776762377;6059.0608455689435;6199.814005579839;6209.937900620993;4754.286856971447;4785.3300537924715;4792.856214313568;4801.711897286156;4801.879953001168;4564.84023059193;4781.737004464753;4786.952130478703;4790.7604619769045;4794.184988551954;4592.196365636017;4643.373144625466;4738.573528382445;4755.548222918817;4758.762061896909;4731.124741922756;4752.405949256343;4756.025014871943;4756.619470442356;4757.04859028194;4182.64447521961;4205.432266644025;4206.831726730927;4206.91586168278;4213.346931225665;5771.913421298684;5846.269216347957;5852.824415267549;5862.970685146547;5870.589058765888;5569.637973531708;5571.749271282762;5572.414896435897;5573.303337082864;5573.5819813514145;6496.317886621884;6537.182852143482;6540.869182616369;6541.443977261938;6541.967290163567;4647.60495357895;4653.092646933849;4655.627549796042;4675.485696573374;4683.95316046842;6630.966845165792;6766.729330826761;6799.218089919673;6809.3190680932;6815.352541508599;4599.08018396322;4636.142313671914;4639.698419602715;4639.9536004640195;4660.673752837269;4724.527547245248;4726.480087190406;4726.621870250372;4727.2909063640045;4728.692634978715;5337.8131765388025;5458.4814442627885;5467.6719396836315;5469.535089517435;5471.972640136773;6556.606603603799;6574.276829548746;6586.80239592813;6588.604683718992;6590.538662293689;1644.6546225292823;1659.8672106231472;1662.5012496251077;1663.2847875413688;1663.4760050584027;1095.2333366643381;1097.8298363753631;1098.4837126550508;1123.3035517978742;1128.3230061962756;6414.262359828586;6427.967860160763;6429.485641148697;6436.420722134937;6436.613803171825;6679.3320667933785;6798.354156355138;6843.418309443654;6850.554713943578;6861.451083913275;6374.69318789647;6381.1066450696935;6486.643234622046;6491;6506.739730410731;3657.835397407123;3731.794751288651;3732.197577520846;3734.589195188527;3734.8506059757847;6864.38220560152;7052.435805135577;7073.363397294268;7077.82305442368;7086.2205157432645;7006.4044556212275;7064.258252883476;7077.7168913242895;7081.610511421921;7104.183018952784;7048.964755176191;7062.258462861429;7062.435005199572;7073.045138906285;7099.858002839915;6818.965905170442;6859.588424694534;6925.168979722465;6931.341522555349;6954.130733658293;6833;6833.282505336868;6834.658267086526;6836.5214434989075;6842.144731908512;7060.6469676517;7077.5399599025895;7081.044059052168;7088.787336379917;7091.468139889632;4523.638108951342;4664.836730714409;4688.296755486705;4702.553257440538;4735.052989402065;6840.452763778786;6843.828904277433;6844.2129155147795;6844.691988860613;6848.9657551711925;6641.833954150991;6649;6658.500612454182;6666.500012498985;6673.632950187786;6513.088167656627;6653.1018312527185;6653.367930046637;6658.0345849852465;6659.134312539339;6481.935180648133;6525.608463492016;6528.086067950396;6535.771248006297;6538;6607.306232845482;6662.800115996528;6673.666316684049;6673.8998915015345;6686.966565167143;6330.208723909512;6391.776287829904;6505.186851643544;6509.381608747161;6519.380658837403 16:33:45 INFO - 2016-02-18 16:33:45,061 DEBUG : BROWSER_OUTPUT: |5;yui.html;838.0153319849176;894.1505569708776;916.2303664921465;943.8867335919691;948.0993056596235;14555.56333310001;14716.484923027696;14798.556043318704;14807.851921480782;14899.329530171155;13980.231087290194;14014.15915045098;14038.368273427695;14063.859361406383;14077.50728724495;744.4007573903006;754.9773506794805;758.8368500772333;908.2143945487139;921.9631214751402;865.1219012702112;914.1909410172012;915.8671992561108;922.9676961306355;923.0630909626701;944.9763755906123;947.5972711597578;953.1040821627682;955.4171955107379;956.3926906414442;784.9803754906144;802.8755542890862;937.4843835890233;952.4666186935339;953.8378475659155;704.4681265644406;706.6784613001123;706.883364244899;708.412018025038;708.7023450150897;13974.93012534941;14056.297185140702;14063.64840878976;14102.858971410256;14207.644808879757;11566.595169169039;12225.083118766128;13141;13187.406566704522;13388.397522111529;10652.786944261148;10742.462876856125;10836.837447438294;10964.671059868217;11041.447927603587;3890.790098169022;3895.7467764595494;3933.724639275252;3971.4439978403047;4038.8788336349958;47176.76411617954;47291.29063064022;47479.762601186776;48001.519984800056;48554.51445485535;10300.536475858604;10321.587136514605;10362.326448780805;10413.42726150069;10459.790804183875;16642.667146657;16706.749398759028;16722.66554668924;19666.410007699793;19968.50078748043;7527.360174385129;7693.538387696756;7703.383729301642;7705.15243323234;7767.572783496962;4546.772661366918;4642.2108241598335;4667.159911215946;4673.976630116827;4745.240761478147;3303.487959366302;3309.5532103166247;3312;3322.119638295805;3329;586.6773274698899;595.6961949405834;601.4616917858492;607.4988134788817;611.9204503414528;659.2385794407467;671.318611609217;675.8040168351222;686.7218776395468;689.1867596236389;8855.73432797017;8857.867131993025;9199.356045076915;9200.40197387181;9235.861462078074;527.9128943724243;565.9009673307155;566.8809549994547;650.067153634536;678.0744284052233;4190.266703326905;4211.515675697334;4405.955940440619;4407.6914615976575;4517.75152366623;58027.41972580305;61243;61543;62579;62633.373666262756;49895.50104498908;50909;53328.20007699887;53404;53604.19593706098;47714.04571908612;48909.266361004615;49172.262416063786;49971.25043124356;50269;8756.168164024662;8852.734417967471;8947.328950328621;9034.954825226096;9038.231750301034;36574;37454.250914981;37506.812465937495;37799.62200377961;37900.62099379081;45265.32102018406;47456.525434745214;47842.52157478381;48150.518494816;48154.759226205046;10479.895201047892;11326.37704926237;11336.659900203007;11376;11780.116491263361;11834.230774999598;12101.394930253628;12181.208221465577;12342;12833.165844220095;11299.152563557931;11585.88901969362;11590.304581725124;11595;11978.04175665962;10679.46602669848;11011.28426652265;11063.391513466837;11133;11438.82841757391;11728.76542469163;11738.76522469563;11926.940365298466;12157.027437804778;12214.633560992828;10133.08802207805;10196.847047294148;10257;10543.94728026355;10722.62470813549;9887.653932112342;10002.799944001225;10349.327293725884;10360.689179324632;10413.739656508647 16:33:45 INFO - 2016-02-18 16:33:45,061 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:33:45 INFO - 2016-02-18 16:33:45,061 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:33:45 INFO - 2016-02-18 16:33:45,062 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,763 16:33:45 INFO - 2016-02-18 16:33:45,062 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:33:45 INFO - 2016-02-18 16:33:45,062 DEBUG : BROWSER_OUTPUT: __startTimestamp1455842025048__endTimestamp 16:33:45 INFO - 2016-02-18 16:33:45,062 DEBUG : BROWSER_OUTPUT: 16:33:45 INFO - 2016-02-18 16:33:45,062 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:33:45 INFO - 2016-02-18 16:33:45,062 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:33:45 INFO - 2016-02-18 16:33:45,062 DEBUG : BROWSER_OUTPUT: 16:33:45 INFO - 2016-02-18 16:33:45,062 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5503.23 Median:5721.57 stddev:615.10 (10.8%) stddev-sans-first:592.01 16:33:45 INFO - 2016-02-18 16:33:45,064 DEBUG : BROWSER_OUTPUT: Values: 3291.3 3342.2 3345.5 3375.6 3403.1 5462.4 5511.1 5522.1 5530.1 5542.8 5497.3 5497.5 5506.2 5538.4 5547.1 4887.1 5020.1 5049.4 5131.0 5135.0 4938.6 4951.7 5030.3 5067.1 5085.0 5062.5 5077.3 5084.2 5089.3 5101.7 5060.9 5068.1 5087.7 5107.1 5136.7 4829.7 4836.3 4926.4 4932.3 4934.7 5318.0 5359.0 5361.2 5374.1 5386.9 5578.7 5917.5 5919.3 5965.0 5965.4 5745.7 5943.1 6019.7 6032.7 6033.5 5555.4 5559.5 5562.4 5562.8 5565.9 5721.6 5948.6 6004.5 6010.0 6015.1 5732.9 5738.5 5839.7 5852.1 5865.5 5931.3 5988.6 5994.5 6004.0 6022.8 5639.9 5766.9 5956.5 5962.8 5966.2 5798.4 5860.4 5861.2 5886.6 5903.4 4447.6 4454.8 4458.4 4467.3 4482.4 3937.6 4018.1 4035.7 4081.3 4107.7 5598.3 5609.7 5631.0 5634.9 5673.1 5967.3 5986.1 5987.3 5987.6 5993.6 5386.8 5538.6 5605.3 5621.5 5627.9 5130.5 5150.3 5175.8 5195.6 5200.5 6009.8 6014.0 6015.6 6016.8 6020.1 5779.9 5904.9 5908.1 5908.7 5910.8 6046.5 6084.8 6129.8 6144.6 6152.4 5872.6 5882.4 5897.9 5900.0 5919.4 5706.5 5853.7 5941.6 5987.9 5990.0 5911.6 5912.5 5919.3 5930.1 5938.7 5879.4 5892.4 5894.0 5895.0 5908.5 5475.0 5601.7 5645.0 5698.5 5809.7 5923.8 5942.7 5955.3 5955.4 5971.1 5842.5 5849.2 5849.2 5849.8 5850.0 16:33:45 INFO - 2016-02-18 16:33:45,064 DEBUG : BROWSER_OUTPUT: 16:33:45 INFO - 2016-02-18 16:33:45,064 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:47277.93 Median:33533.83 stddev:73469.52 (219.1%) stddev-sans-first:73592.01 16:33:45 INFO - 2016-02-18 16:33:45,065 DEBUG : BROWSER_OUTPUT: Values: 597.4 631.3 642.9 675.5 686.9 32022.0 42085.6 42413.0 42442.4 42466.8 46753.8 47430.5 47475.3 47526.0 47567.0 8076.2 8198.7 8907.2 9259.8 10627.8 10757.3 10783.1 10799.9 10841.0 10906.1 10902.9 10983.7 11012.3 11025.3 11027.0 10735.8 10904.3 10911.3 10920.5 10920.7 10753.4 10816.6 10866.3 10894.1 10894.9 43382.3 43684.3 43761.0 43896.0 43935.6 3621.0 3906.5 4015.3 4266.4 4304.0 33129.7 33213.3 33506.0 33516.7 33551.0 4341.6 4392.9 4447.7 4448.6 4544.4 278695.0 279500.0 279652.0 280205.0 280848.0 57702.0 58938.0 65668.7 72565.3 73145.6 65230.3 65753.3 65837.3 66017.0 66368.3 15564.8 15801.2 15803.9 15810.0 15832.1 11123.8 11259.9 11261.3 11272.2 11296.7 22210.7 22554.0 22587.0 22592.8 22683.4 8438.7 8587.4 8751.4 8754.7 8771.9 1062.0 1110.1 1151.9 1158.0 1230.5 26658.3 27010.6 27471.3 30703.4 31085.2 1102.2 1182.3 1189.2 1256.7 1286.2 14396.0 15282.4 15346.5 15592.0 16397.2 333000.0 333188.0 333211.0 333292.0 333918.0 56728.1 56969.0 57086.4 57603.1 57615.4 1343.4 1390.1 1449.3 1480.2 1494.6 27445.9 28704.9 29145.6 29685.6 29840.0 46081.0 46742.8 46790.8 46807.8 46852.5 219925.0 221480.0 221705.0 222248.0 223293.0 35639.1 36002.6 36092.1 36470.0 36513.5 47479.5 47743.0 48226.3 48565.3 48672.0 38465.2 39206.0 39456.0 39457.4 39983.8 40431.2 40734.1 40877.8 41371.2 41611.0 40342.8 40348.4 40352.4 40641.0 40747.6 35083.0 35678.5 36837.8 38079.0 38128.4 26679.6 27428.9 29397.6 29910.0 30095.5 16:33:45 INFO - 2016-02-18 16:33:45,066 DEBUG : BROWSER_OUTPUT: 16:33:45 INFO - 2016-02-18 16:33:45,066 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:31429.99 Median:2034.29 stddev:100313.62 (4931.1%) stddev-sans-first:100567.95 16:33:45 INFO - 2016-02-18 16:33:45,066 DEBUG : BROWSER_OUTPUT: Values: 391.8 391.8 393.4 395.5 397.1 15298.2 16640.5 16908.6 18507.8 18508.2 17695.9 18145.7 18147.3 18538.3 18905.8 5089.6 5149.6 5177.8 5198.3 5203.0 3989.6 4100.9 5144.2 5565.5 5722.5 5601.3 5605.8 5627.5 5662.8 5706.5 5459.2 5591.7 5602.6 5608.9 5612.8 5393.4 5393.4 5403.1 5404.4 5405.9 13684.3 14164.8 14175.2 14180.0 19746.7 2197.7 2212.7 2320.5 2371.6 2454.5 10091.0 10107.0 10208.6 10301.5 10602.7 1881.4 2022.4 2030.7 2033.6 2034.9 64431.4 66347.7 66623.0 68451.0 68665.7 11037.0 11298.3 11368.7 11391.9 11463.7 14686.6 14996.9 15022.0 15062.1 15064.6 7440.3 7640.2 7666.7 7704.4 7718.5 6952.0 6964.2 6995.1 7035.6 7089.6 4885.6 4895.8 4956.3 4980.0 4982.4 1737.3 1979.4 1979.5 1987.1 2010.1 923.4 972.6 975.4 993.1 995.2 14979.0 17393.5 17750.4 17831.2 18534.6 965.5 1007.8 1050.9 1051.7 1096.4 9299.5 9500.2 9579.1 9687.7 9767.8 594713.0 595905.0 607981.0 628800.0 638172.0 87302.6 88690.0 89470.0 89645.6 91478.5 1176.6 1180.2 1202.9 1209.0 1223.8 15586.4 15655.8 15658.9 15684.2 15844.8 33968.7 35063.1 35326.3 35659.0 36061.8 45083.8 48256.5 49604.3 49694.0 51356.5 9989.3 10051.1 10208.3 10243.5 10283.3 11783.3 11903.5 11993.5 14169.4 14767.3 14608.9 14963.5 15183.5 15196.3 15800.7 14878.2 14927.9 15013.8 15092.2 15330.5 15016.7 15723.0 15913.0 16103.7 16255.3 9762.2 10215.5 10293.1 10306.5 10361.1 10674.4 10737.2 11832.3 15730.1 16058.9 16:33:45 INFO - 2016-02-18 16:33:45,066 DEBUG : BROWSER_OUTPUT: 16:33:45 INFO - 2016-02-18 16:33:45,066 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5260.17 Median:6383.20 stddev:2650.74 (41.5%) stddev-sans-first:2643.37 16:33:45 INFO - 2016-02-18 16:33:45,066 DEBUG : BROWSER_OUTPUT: Values: 1532.5 1752.0 1819.7 1845.8 1863.7 7187.5 7509.1 7513.8 7518.4 7567.0 6387.9 6440.2 7112.5 7306.9 7352.5 2195.2 2203.3 2225.6 2226.4 2228.8 1938.8 2138.2 2185.6 2187.8 2188.2 1899.2 2159.8 2214.2 2215.5 2215.9 2141.2 2164.0 2164.4 2167.9 2174.7 2189.0 2191.2 2204.1 2216.0 2218.7 7417.3 7482.3 7490.5 7494.1 7499.3 4440.8 4541.6 4541.8 4544.5 4546.5 2945.3 3012.6 3014.5 3048.3 3053.8 996.4 1013.4 1018.9 1044.7 1048.1 9897.6 9916.1 9927.3 9930.3 9940.7 4065.0 4122.2 4127.1 4141.9 4174.7 8605.3 8614.4 8616.1 8630.7 8631.0 4599.0 4624.1 4632.4 4637.3 4638.0 4220.8 4244.4 4246.4 4247.5 4253.6 3601.4 3639.5 3659.8 3668.2 3685.2 1063.0 1074.1 1181.4 1187.7 1194.5 1160.6 1165.5 1178.1 1182.0 1188.8 7180.3 7206.9 7241.1 7289.4 7293.0 1200.6 1209.3 1209.9 1210.3 1225.8 5807.7 5859.1 5868.3 5873.8 5880.5 8434.5 8932.1 8943.9 8961.4 8979.1 8769.3 8777.6 8858.5 8890.7 8906.6 6878.9 6917.5 7132.0 7136.9 7149.4 6962.5 6992.4 6994.5 6994.6 6994.9 7779.5 7965.0 7997.4 8092.2 8107.6 8064.6 8257.2 8280.2 8297.0 8301.2 6232.9 6296.2 6444.8 6449.4 6469.1 7162.2 7216.8 7220.1 7220.7 7223.4 5841.2 6763.9 6853.8 6957.9 7003.0 6550.9 6575.0 6600.3 6691.8 6819.6 6769.3 6787.9 6873.7 6886.4 6887.3 6334.9 6360.0 6381.2 6385.2 6391.5 6379.9 6416.5 6455.7 6470.9 6480.5 16:33:45 INFO - 2016-02-18 16:33:45,066 DEBUG : BROWSER_OUTPUT: 16:33:45 INFO - 2016-02-18 16:33:45,066 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5533.66 Median:6352.45 stddev:1670.41 (26.3%) stddev-sans-first:1629.20 16:33:45 INFO - 2016-02-18 16:33:45,067 DEBUG : BROWSER_OUTPUT: Values: 352.2 361.3 363.9 365.6 369.4 6225.2 6235.4 6254.6 6257.0 6281.3 5929.6 6029.1 6059.1 6199.8 6209.9 4754.3 4785.3 4792.9 4801.7 4801.9 4564.8 4781.7 4787.0 4790.8 4794.2 4592.2 4643.4 4738.6 4755.5 4758.8 4731.1 4752.4 4756.0 4756.6 4757.0 4182.6 4205.4 4206.8 4206.9 4213.3 5771.9 5846.3 5852.8 5863.0 5870.6 5569.6 5571.7 5572.4 5573.3 5573.6 6496.3 6537.2 6540.9 6541.4 6542.0 4647.6 4653.1 4655.6 4675.5 4684.0 6631.0 6766.7 6799.2 6809.3 6815.4 4599.1 4636.1 4639.7 4640.0 4660.7 4724.5 4726.5 4726.6 4727.3 4728.7 5337.8 5458.5 5467.7 5469.5 5472.0 6556.6 6574.3 6586.8 6588.6 6590.5 1644.7 1659.9 1662.5 1663.3 1663.5 1095.2 1097.8 1098.5 1123.3 1128.3 6414.3 6428.0 6429.5 6436.4 6436.6 6679.3 6798.4 6843.4 6850.6 6861.5 6374.7 6381.1 6486.6 6491.0 6506.7 3657.8 3731.8 3732.2 3734.6 3734.9 6864.4 7052.4 7073.4 7077.8 7086.2 7006.4 7064.3 7077.7 7081.6 7104.2 7049.0 7062.3 7062.4 7073.0 7099.9 6819.0 6859.6 6925.2 6931.3 6954.1 6833.0 6833.3 6834.7 6836.5 6842.1 7060.6 7077.5 7081.0 7088.8 7091.5 4523.6 4664.8 4688.3 4702.6 4735.1 6840.5 6843.8 6844.2 6844.7 6849.0 6641.8 6649.0 6658.5 6666.5 6673.6 6513.1 6653.1 6653.4 6658.0 6659.1 6481.9 6525.6 6528.1 6535.8 6538.0 6607.3 6662.8 6673.7 6673.9 6687.0 6330.2 6391.8 6505.2 6509.4 6519.4 16:33:45 INFO - 2016-02-18 16:33:45,068 DEBUG : BROWSER_OUTPUT: 16:33:45 INFO - 2016-02-18 16:33:45,068 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14377.99 Median:4594.49 stddev:16621.04 (361.8%) stddev-sans-first:16636.57 16:33:45 INFO - 2016-02-18 16:33:45,068 DEBUG : BROWSER_OUTPUT: Values: 838.0 894.2 916.2 943.9 948.1 14555.6 14716.5 14798.6 14807.9 14899.3 13980.2 14014.2 14038.4 14063.9 14077.5 744.4 755.0 758.8 908.2 922.0 865.1 914.2 915.9 923.0 923.1 945.0 947.6 953.1 955.4 956.4 785.0 802.9 937.5 952.5 953.8 704.5 706.7 706.9 708.4 708.7 13974.9 14056.3 14063.6 14102.9 14207.6 11566.6 12225.1 13141.0 13187.4 13388.4 10652.8 10742.5 10836.8 10964.7 11041.4 3890.8 3895.7 3933.7 3971.4 4038.9 47176.8 47291.3 47479.8 48001.5 48554.5 10300.5 10321.6 10362.3 10413.4 10459.8 16642.7 16706.7 16722.7 19666.4 19968.5 7527.4 7693.5 7703.4 7705.2 7767.6 4546.8 4642.2 4667.2 4674.0 4745.2 3303.5 3309.6 3312.0 3322.1 3329.0 586.7 595.7 601.5 607.5 611.9 659.2 671.3 675.8 686.7 689.2 8855.7 8857.9 9199.4 9200.4 9235.9 527.9 565.9 566.9 650.1 678.1 4190.3 4211.5 4406.0 4407.7 4517.8 58027.4 61243.0 61543.0 62579.0 62633.4 49895.5 50909.0 53328.2 53404.0 53604.2 47714.0 48909.3 49172.3 49971.3 50269.0 8756.2 8852.7 8947.3 9035.0 9038.2 36574.0 37454.3 37506.8 37799.6 37900.6 45265.3 47456.5 47842.5 48150.5 48154.8 10479.9 11326.4 11336.7 11376.0 11780.1 11834.2 12101.4 12181.2 12342.0 12833.2 11299.2 11585.9 11590.3 11595.0 11978.0 10679.5 11011.3 11063.4 11133.0 11438.8 11728.8 11738.8 11926.9 12157.0 12214.6 10133.1 10196.8 10257.0 10543.9 10722.6 9887.7 10002.8 10349.3 10360.7 10413.7 16:33:45 INFO - 2016-02-18 16:33:45,068 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:33:45 INFO - 2016-02-18 16:33:45,068 DEBUG : BROWSER_OUTPUT: 16:33:45 INFO - 2016-02-18 16:33:45,601 INFO : Browser exited with error code: 0 16:33:45 INFO - 2016-02-18 16:33:45,620 INFO : Completed test dromaeo_css (00:18:29) 16:33:45 INFO - 2016-02-18 16:33:45,620 INFO : Starting test kraken 16:33:45 INFO - 2016-02-18 16:33:45,620 DEBUG : operating with platform_type : linux_ 16:33:45 INFO - 2016-02-18 16:33:45,620 INFO : Initialising browser for kraken test... 16:33:45 INFO - 2016-02-18 16:33:45,627 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpJ2npxC/profile http://localhost:37312/getInfo.html 16:33:49 INFO - 2016-02-18 16:33:49,284 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:33:49 INFO - 2016-02-18 16:33:49,284 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:33:49 INFO - 2016-02-18 16:33:49,284 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:33:49 INFO - 2016-02-18 16:33:49,284 DEBUG : BROWSER_OUTPUT: __metrics 16:33:49 INFO - 2016-02-18 16:33:49,405 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] 16:33:50 INFO - 2016-02-18 16:33:50,129 INFO : Browser initialized. 16:33:50 INFO - 2016-02-18 16:33:50,130 INFO : Running cycle 1/1 for kraken test... 16:33:50 INFO - 2016-02-18 16:33:50,130 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpJ2npxC/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:34:01 INFO - 2016-02-18 16:34:01,819 DEBUG : BROWSER_OUTPUT: RSS: Main: 132481024 16:34:01 INFO - 2016-02-18 16:34:01,819 DEBUG : BROWSER_OUTPUT: 16:34:04 INFO - 2016-02-18 16:34:04,269 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37312/tests/kraken/driver.html?testName=audio-beat-detection) 16:34:04 INFO - 2016-02-18 16:34:04,836 DEBUG : BROWSER_OUTPUT: RSS: Main: 165748736 16:34:04 INFO - 2016-02-18 16:34:04,836 DEBUG : BROWSER_OUTPUT: 16:34:07 INFO - 2016-02-18 16:34:07,423 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37312/tests/kraken/driver.html?testName=audio-dft) 16:34:07 INFO - 2016-02-18 16:34:07,989 DEBUG : BROWSER_OUTPUT: RSS: Main: 162963456 16:34:07 INFO - 2016-02-18 16:34:07,989 DEBUG : BROWSER_OUTPUT: 16:34:10 INFO - 2016-02-18 16:34:10,787 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37312/tests/kraken/driver.html?testName=audio-fft) 16:34:11 INFO - 2016-02-18 16:34:11,357 DEBUG : BROWSER_OUTPUT: RSS: Main: 161730560 16:34:11 INFO - 2016-02-18 16:34:11,357 DEBUG : BROWSER_OUTPUT: 16:34:13 INFO - 2016-02-18 16:34:13,342 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37312/tests/kraken/driver.html?testName=audio-oscillator) 16:34:13 INFO - 2016-02-18 16:34:13,907 DEBUG : BROWSER_OUTPUT: RSS: Main: 159539200 16:34:13 INFO - 2016-02-18 16:34:13,908 DEBUG : BROWSER_OUTPUT: 16:34:16 INFO - 2016-02-18 16:34:16,095 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37312/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:34:16 INFO - 2016-02-18 16:34:16,656 DEBUG : BROWSER_OUTPUT: RSS: Main: 160161792 16:34:16 INFO - 2016-02-18 16:34:16,656 DEBUG : BROWSER_OUTPUT: 16:34:19 INFO - 2016-02-18 16:34:19,522 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37312/tests/kraken/driver.html?testName=imaging-darkroom) 16:34:20 INFO - 2016-02-18 16:34:20,087 DEBUG : BROWSER_OUTPUT: RSS: Main: 160620544 16:34:20 INFO - 2016-02-18 16:34:20,088 DEBUG : BROWSER_OUTPUT: 16:34:23 INFO - 2016-02-18 16:34:23,422 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37312/tests/kraken/driver.html?testName=imaging-desaturate) 16:34:23 INFO - 2016-02-18 16:34:23,988 DEBUG : BROWSER_OUTPUT: RSS: Main: 160509952 16:34:23 INFO - 2016-02-18 16:34:23,988 DEBUG : BROWSER_OUTPUT: 16:34:26 INFO - 2016-02-18 16:34:26,689 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37312/tests/kraken/driver.html?testName=json-parse-financial) 16:34:27 INFO - 2016-02-18 16:34:27,271 DEBUG : BROWSER_OUTPUT: RSS: Main: 161525760 16:34:27 INFO - 2016-02-18 16:34:27,271 DEBUG : BROWSER_OUTPUT: 16:34:28 INFO - 2016-02-18 16:34:28,792 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37312/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:34:29 INFO - 2016-02-18 16:34:29,354 DEBUG : BROWSER_OUTPUT: RSS: Main: 169443328 16:34:29 INFO - 2016-02-18 16:34:29,354 DEBUG : BROWSER_OUTPUT: 16:34:31 INFO - 2016-02-18 16:34:31,098 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37312/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:34:31 INFO - 2016-02-18 16:34:31,692 DEBUG : BROWSER_OUTPUT: RSS: Main: 167124992 16:34:31 INFO - 2016-02-18 16:34:31,693 DEBUG : BROWSER_OUTPUT: 16:34:33 INFO - 2016-02-18 16:34:33,690 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37312/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:34:34 INFO - 2016-02-18 16:34:34,259 DEBUG : BROWSER_OUTPUT: RSS: Main: 173031424 16:34:34 INFO - 2016-02-18 16:34:34,259 DEBUG : BROWSER_OUTPUT: 16:34:36 INFO - 2016-02-18 16:34:36,648 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37312/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:34:37 INFO - 2016-02-18 16:34:37,219 DEBUG : BROWSER_OUTPUT: RSS: Main: 179884032 16:34:37 INFO - 2016-02-18 16:34:37,219 DEBUG : BROWSER_OUTPUT: 16:34:39 INFO - 2016-02-18 16:34:39,737 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37312/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:34:40 INFO - 2016-02-18 16:34:40,305 DEBUG : BROWSER_OUTPUT: RSS: Main: 177881088 16:34:40 INFO - 2016-02-18 16:34:40,305 DEBUG : BROWSER_OUTPUT: 16:34:41 INFO - 2016-02-18 16:34:41,959 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37312/tests/kraken/driver.html?testName=ai-astar) 16:34:42 INFO - 2016-02-18 16:34:42,209 DEBUG : BROWSER_OUTPUT: RSS: Main: 235106304 16:34:42 INFO - 2016-02-18 16:34:42,210 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,211 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:34:42 INFO - 2016-02-18 16:34:42,211 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:34:42 INFO - 2016-02-18 16:34:42,211 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:34:42 INFO - 2016-02-18 16:34:42,211 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:34:42 INFO - 2016-02-18 16:34:42,212 DEBUG : BROWSER_OUTPUT: |0;ai-astar;109;112;106;110;112;112;100;109;111;111 16:34:42 INFO - 2016-02-18 16:34:42,212 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;149;123;158;161;131;155;151;130;155;122 16:34:42 INFO - 2016-02-18 16:34:42,212 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;161;161;159;160;159;158;163;160;163 16:34:42 INFO - 2016-02-18 16:34:42,212 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;70;71;149;74;75;71;71;155;72 16:34:42 INFO - 2016-02-18 16:34:42,212 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;141;92;93;93;141;92;93;133 16:34:42 INFO - 2016-02-18 16:34:42,212 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;108;109;109;109;109;109;109;109;109 16:34:42 INFO - 2016-02-18 16:34:42,212 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;146;145;145;145;145;146;145;145 16:34:42 INFO - 2016-02-18 16:34:42,213 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;95;95;96;95;95;96;96;95 16:34:42 INFO - 2016-02-18 16:34:42,213 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;56;56;55;56;57;56;56;53;54 16:34:42 INFO - 2016-02-18 16:34:42,213 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;63;63;65;63;64;64;64;64;65;64 16:34:42 INFO - 2016-02-18 16:34:42,213 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;85;78;77;79;79;80;78;77;79;80 16:34:42 INFO - 2016-02-18 16:34:42,213 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;124;118;114;113;117;112;119;118;116;126 16:34:42 INFO - 2016-02-18 16:34:42,214 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;136;137;146;148;151;156;144;144;156;135 16:34:42 INFO - 2016-02-18 16:34:42,214 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;63;67;63;63;66;63;64;63;64;66 16:34:42 INFO - 2016-02-18 16:34:42,214 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:34:42 INFO - 2016-02-18 16:34:42,214 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:34:42 INFO - 2016-02-18 16:34:42,214 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,889 16:34:42 INFO - 2016-02-18 16:34:42,214 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:34:42 INFO - 2016-02-18 16:34:42,214 DEBUG : BROWSER_OUTPUT: __startTimestamp1455842082210__endTimestamp 16:34:42 INFO - 2016-02-18 16:34:42,215 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,218 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:34:42 INFO - 2016-02-18 16:34:42,218 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:34:42 INFO - 2016-02-18 16:34:42,218 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,218 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.20 Median:111.00 stddev:3.74 (3.4%) stddev-sans-first:3.96 16:34:42 INFO - 2016-02-18 16:34:42,218 DEBUG : BROWSER_OUTPUT: Values: 109.0 112.0 106.0 110.0 112.0 112.0 100.0 109.0 111.0 111.0 16:34:42 INFO - 2016-02-18 16:34:42,218 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,219 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.50 Median:153.00 stddev:15.23 (10.0%) stddev-sans-first:16.03 16:34:42 INFO - 2016-02-18 16:34:42,219 DEBUG : BROWSER_OUTPUT: Values: 149.0 123.0 158.0 161.0 131.0 155.0 151.0 130.0 155.0 122.0 16:34:42 INFO - 2016-02-18 16:34:42,219 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,219 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.50 Median:161.00 stddev:1.65 (1.0%) stddev-sans-first:1.74 16:34:42 INFO - 2016-02-18 16:34:42,219 DEBUG : BROWSER_OUTPUT: Values: 161.0 161.0 161.0 159.0 160.0 159.0 158.0 163.0 160.0 163.0 16:34:42 INFO - 2016-02-18 16:34:42,219 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,219 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.10 Median:71.50 stddev:33.74 (47.2%) stddev-sans-first:35.34 16:34:42 INFO - 2016-02-18 16:34:42,219 DEBUG : BROWSER_OUTPUT: Values: 73.0 70.0 71.0 149.0 74.0 75.0 71.0 71.0 155.0 72.0 16:34:42 INFO - 2016-02-18 16:34:42,219 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,219 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.40 Median:93.00 stddev:22.15 (23.8%) stddev-sans-first:22.95 16:34:42 INFO - 2016-02-18 16:34:42,219 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 141.0 92.0 93.0 93.0 141.0 92.0 93.0 133.0 16:34:42 INFO - 2016-02-18 16:34:42,219 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,220 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:108.90 Median:109.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:34:42 INFO - 2016-02-18 16:34:42,220 DEBUG : BROWSER_OUTPUT: Values: 109.0 108.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 109.0 16:34:42 INFO - 2016-02-18 16:34:42,220 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,220 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 16:34:42 INFO - 2016-02-18 16:34:42,220 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 146.0 145.0 145.0 145.0 145.0 146.0 145.0 145.0 16:34:42 INFO - 2016-02-18 16:34:42,220 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,220 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 16:34:42 INFO - 2016-02-18 16:34:42,220 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 96.0 95.0 16:34:42 INFO - 2016-02-18 16:34:42,220 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,220 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.50 Median:56.00 stddev:1.18 (2.1%) stddev-sans-first:1.24 16:34:42 INFO - 2016-02-18 16:34:42,220 DEBUG : BROWSER_OUTPUT: Values: 56.0 56.0 56.0 55.0 56.0 57.0 56.0 56.0 53.0 54.0 16:34:42 INFO - 2016-02-18 16:34:42,221 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,221 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:64.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 16:34:42 INFO - 2016-02-18 16:34:42,221 DEBUG : BROWSER_OUTPUT: Values: 63.0 63.0 65.0 63.0 64.0 64.0 64.0 64.0 65.0 64.0 16:34:42 INFO - 2016-02-18 16:34:42,221 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,221 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.00 stddev:2.30 (2.9%) stddev-sans-first:1.13 16:34:42 INFO - 2016-02-18 16:34:42,221 DEBUG : BROWSER_OUTPUT: Values: 85.0 78.0 77.0 79.0 79.0 80.0 78.0 77.0 79.0 80.0 16:34:42 INFO - 2016-02-18 16:34:42,221 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,221 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:117.70 Median:118.00 stddev:4.50 (3.8%) stddev-sans-first:4.15 16:34:42 INFO - 2016-02-18 16:34:42,221 DEBUG : BROWSER_OUTPUT: Values: 124.0 118.0 114.0 113.0 117.0 112.0 119.0 118.0 116.0 126.0 16:34:42 INFO - 2016-02-18 16:34:42,221 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,221 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.30 Median:147.00 stddev:7.70 (5.2%) stddev-sans-first:7.40 16:34:42 INFO - 2016-02-18 16:34:42,221 DEBUG : BROWSER_OUTPUT: Values: 136.0 137.0 146.0 148.0 151.0 156.0 144.0 144.0 156.0 135.0 16:34:42 INFO - 2016-02-18 16:34:42,221 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,222 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.00 stddev:1.55 (2.4%) stddev-sans-first:1.58 16:34:42 INFO - 2016-02-18 16:34:42,222 DEBUG : BROWSER_OUTPUT: Values: 63.0 67.0 63.0 63.0 66.0 63.0 64.0 63.0 64.0 66.0 16:34:42 INFO - 2016-02-18 16:34:42,222 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:34:42 INFO - 2016-02-18 16:34:42,222 DEBUG : BROWSER_OUTPUT: 16:34:42 INFO - 2016-02-18 16:34:42,825 INFO : Browser exited with error code: 0 16:34:42 INFO - 2016-02-18 16:34:42,879 INFO : Completed test kraken (00:00:57) 16:34:42 INFO - 2016-02-18 16:34:42,879 INFO : Starting test v8_7 16:34:42 INFO - 2016-02-18 16:34:42,879 DEBUG : operating with platform_type : linux_ 16:34:42 INFO - 2016-02-18 16:34:42,879 INFO : Initialising browser for v8_7 test... 16:34:42 INFO - 2016-02-18 16:34:42,885 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpPBajTB/profile http://localhost:37312/getInfo.html 16:34:46 INFO - 2016-02-18 16:34:46,546 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:34:46 INFO - 2016-02-18 16:34:46,546 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:34:46 INFO - 2016-02-18 16:34:46,546 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:34:46 INFO - 2016-02-18 16:34:46,546 DEBUG : BROWSER_OUTPUT: __metrics 16:34:46 INFO - 2016-02-18 16:34:46,683 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] 16:34:47 INFO - 2016-02-18 16:34:47,457 INFO : Browser initialized. 16:34:47 INFO - 2016-02-18 16:34:47,458 INFO : Running cycle 1/1 for v8_7 test... 16:34:47 INFO - 2016-02-18 16:34:47,458 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpPBajTB/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:34:59 INFO - 2016-02-18 16:34:59,133 DEBUG : BROWSER_OUTPUT: RSS: Main: 133406720 16:34:59 INFO - 2016-02-18 16:34:59,133 DEBUG : BROWSER_OUTPUT: 16:35:20 INFO - 2016-02-18 16:35:20,471 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:37312/tests/v8_7/run.html (next: http://localhost:37312/tests/v8_7/run.html) 16:35:20 INFO - 2016-02-18 16:35:20,729 DEBUG : BROWSER_OUTPUT: RSS: Main: 419766272 16:35:20 INFO - 2016-02-18 16:35:20,729 DEBUG : BROWSER_OUTPUT: 16:35:20 INFO - 2016-02-18 16:35:20,729 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:35:20 INFO - 2016-02-18 16:35:20,729 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:35:20 INFO - 2016-02-18 16:35:20,729 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: |0;Richards;164.33853738701725 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;171.40898183064792 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.55153203342616 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5117.34693877551 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: |4;RayTrace;971.8172983479105 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: |5;Earley;544.9591280653951 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: |6;Boyer;10589.473684210527 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: |7;RegExp;24463.414634146342 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: |8;Splay;514.6078933880061 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4826.923076923077 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: __startTimestamp1455842120722__endTimestamp 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:35:20 INFO - 2016-02-18 16:35:20,730 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.34 Median:164.34 stddev:0.00 (0.0%) 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: Values: 164.3 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:171.41 Median:171.41 stddev:0.00 (0.0%) 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: Values: 171.4 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.55 Median:278.55 stddev:0.00 (0.0%) 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: Values: 278.6 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5117.35 Median:5117.35 stddev:0.00 (0.0%) 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: Values: 5117.3 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:971.82 Median:971.82 stddev:0.00 (0.0%) 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: Values: 971.8 16:35:20 INFO - 2016-02-18 16:35:20,731 DEBUG : BROWSER_OUTPUT: 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:544.96 Median:544.96 stddev:0.00 (0.0%) 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: Values: 545.0 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10589.47 Median:10589.47 stddev:0.00 (0.0%) 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: Values: 10589.5 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24463.41 Median:24463.41 stddev:0.00 (0.0%) 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: Values: 24463.4 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:514.61 Median:514.61 stddev:0.00 (0.0%) 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: Values: 514.6 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4826.92 Median:4826.92 stddev:0.00 (0.0%) 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: Values: 4826.9 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:35:20 INFO - 2016-02-18 16:35:20,732 DEBUG : BROWSER_OUTPUT: 16:35:21 INFO - 2016-02-18 16:35:21,405 INFO : Browser exited with error code: 0 16:35:21 INFO - 2016-02-18 16:35:21,422 INFO : Completed test v8_7 (00:00:38) 16:35:21 INFO - 2016-02-18 16:35:21,800 INFO : Completed test suite (00:20:05) 16:35:21 INFO - 2016-02-18 16:35:21,800 DEBUG : Working with test: dromaeo_css 16:35:21 INFO - 2016-02-18 16:35:21,800 DEBUG : Generating results file: dromaeo_css 16:35:21 INFO - 2016-02-18 16:35:21,801 DEBUG : Working with test: kraken 16:35:21 INFO - 2016-02-18 16:35:21,801 DEBUG : Generating results file: kraken 16:35:21 INFO - 2016-02-18 16:35:21,802 INFO : javascript benchmark 16:35:21 INFO - 2016-02-18 16:35:21,802 DEBUG : Working with test: v8_7 16:35:21 INFO - 2016-02-18 16:35:21,802 DEBUG : Generating results file: v8_7 16:35:21 INFO - 2016-02-18 16:35:21,803 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:35:21 INFO - 2016-02-18 16:35:21,852 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 16:35:21 INFO - 2016-02-18 16:35:21,852 DEBUG : process_Request line: dromaeo_css 7257.79 graph.html#tests=[[72,53,35]] 16:35:21 INFO - 2016-02-18 16:35:21,852 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:35:21 INFO - 2016-02-18 16:35:21,881 DEBUG : process_Request line: kraken 1482.90 graph.html#tests=[[232,53,35]] 16:35:21 INFO - 2016-02-18 16:35:21,881 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:35:21 INFO - 2016-02-18 16:35:21,914 DEBUG : process_Request line: v8_7 23215.16 graph.html#tests=[[230,53,35]] 16:35:21 INFO - 2016-02-18 16:35:21,915 INFO : javascript benchmark 16:35:21 INFO - 2016-02-18 16:35:21,919 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3291.325278317945, 3342.181165614425, 3345.4981752737103, 3375.6455572164923, 3403.1322012886717, 5462.426445223249, 5511.118221084627, 5522.116461366182, 5530.087535556633, 5542.7782888684505, 5497.340319161706, 5497.532709719679, 5506.229127922094, 5538.418466061066, 5547.0570003099465, 4887.1447496688115, 5020.071286811952, 5049.4193167785625, 5131.0, 5135.024345374386, 4938.604911607063, 4951.678140920847, 5030.2957585938, 5067.113255180348, 5085.0, 5062.544371006608, 5077.314562534048, 5084.161113416282, 5089.312942752718, 5101.668391554537, 5060.924086138711, 5068.113080210986, 5087.720175390352, 5107.080725469414, 5136.717480538568, 4829.734364609945, 4836.2503811908855, 4926.359573255454, 4932.260160975882, 4934.6792458490445, 5317.973410132963, 5359.035373632744, 5361.195820626898, 5374.059539580558, 5386.91919621206, 5578.748956296936, 5917.497012753921, 5919.260092488439, 5964.97017514914, 5965.403459654043, 5745.7127143643065, 5943.138244954457, 6019.69901504923, 6032.728527216286, 6033.5173186145, 5555.3611334696625, 5559.499645031967, 5562.443755624446, 5562.805301814417, 5565.860853478655, 5721.5708821838525, 5948.553858460633, 6004.489618382487, 6009.969950150221, 6015.127806468037, 5732.914006289909, 5738.540916726652, 5839.708014599253, 5852.092925596539, 5865.472107510344, 5931.288245410578, 5988.580799344091, 5994.520438364921, 6004.039353703386, 6022.759089636453, 5639.943600563942, 5766.88466230673, 5956.493698035629, 5962.761489540369, 5966.224390829165, 5798.391168927202, 5860.355360910296, 5861.150133230658, 5886.646801191942, 5903.439173278532, 4447.62195213404, 4454.77726113693, 4458.375827384169, 4467.262901621196, 4482.4172857528965, 3937.645611894943, 4018.1361007383553, 4035.6771458283265, 4081.3265811141423, 4107.6508496777515, 5598.3282006159525, 5609.719514024282, 5631.0, 5634.85912852182, 5673.06394444912, 5967.254093238345, 5986.132010858401, 5987.31145918225, 5987.640741555433, 5993.580449368503, 5386.757595908193, 5538.6399884007415, 5605.271314729059, 5621.494065534122, 5627.887442251132, 5130.461301563357, 5150.27896094547, 5175.844724658266, 5195.636305458582, 5200.5059519345605, 6009.789657361971, 6014.0377539593455, 6015.608985416022, 6016.819495415145, 6020.096985452217, 5779.884402311846, 5904.881902362014, 5908.14331921869, 5908.675022873783, 5910.822675319747, 6046.455818976313, 6084.77757367609, 6129.84675383119, 6144.586745048575, 6152.384761523812, 5872.55955803325, 5882.411758824083, 5897.941020589739, 5900.0, 5919.4080591940465, 5706.514946229617, 5853.736581853742, 5941.554383421347, 5987.940120598738, 5989.9700501498955, 5911.615744976562, 5912.46787789101, 5919.289685237799, 5930.05119180929, 5938.703064846827, 5879.382664820303, 5892.410758924073, 5893.970530147321, 5895.0, 5908.468237858613, 5474.972625136849, 5601.663900166003, 5644.971775141098, 5698.487136157766, 5809.680467574324, 5923.815236952713, 5942.73257703413, 5955.2853657559635, 5955.434233747787, 5971.104334349882, 5842.474177324061, 5849.210356601804, 5849.239598852122, 5849.795257165979, 5849.970750146222], "unit": "ms", "name": "dojo.html", "value": 5463.264561392408}, {"lowerIsBetter": false, "replicates": [597.3578403216543, 631.2677294338567, 642.8649983503466, 675.4832553096878, 686.8900975843866, 32022.0, 42085.57914420855, 42413.0, 42442.36336454948, 42466.787666061704, 46753.766231168884, 47430.52569474304, 47475.28787068188, 47526.0, 47567.0, 8076.152004039579, 8198.713045043425, 8907.198352148289, 9259.768505787342, 10627.84058239127, 10757.30077544961, 10783.137349012099, 10799.892001080007, 10841.0, 10906.127509799197, 10902.945485272603, 10983.670489885317, 11012.284201526914, 11025.283356581836, 11027.0, 10735.838962415532, 10904.291221070645, 10911.345319280788, 10920.453977301104, 10920.726981825439, 10753.354798712102, 10816.621418250323, 10866.315721063247, 10894.128469722482, 10894.945525272402, 43382.349264761055, 43684.34473482901, 43761.0, 43896.0, 43935.56064439347, 3620.981895090534, 3906.453096566483, 4015.297322968498, 4266.3813747006825, 4304.0, 33129.6687033129, 33213.33573328544, 33506.0, 33516.66483335184, 33551.0, 4341.630961368288, 4392.89017774555, 4447.73313601185, 4448.644108471347, 4544.386507821435, 278695.0, 279499.99999999796, 279652.0, 280205.0, 280848.0, 57702.0, 58938.0, 65668.67165664141, 72565.27434725585, 73145.6342718283, 65230.34769652338, 65753.34246657568, 65837.34162658408, 66017.0, 66368.33631663719, 15564.844351556567, 15801.209939502978, 15803.920980395025, 15810.0, 15832.050076995187, 11123.833142502868, 11259.943700281447, 11261.324320540794, 11272.21094523392, 11296.661100167008, 22210.66683999741, 22554.0, 22587.0, 22592.774072259395, 22683.432914177276, 8438.704645337506, 8587.39888207832, 8751.431156974777, 8754.693585724597, 8771.868421973675, 1061.9734506637396, 1110.1174566219875, 1151.9020883225019, 1158.0272571040366, 1230.501646833034, 26658.333541661614, 27010.5948410774, 27471.31321716973, 30703.385932281228, 31085.22286942799, 1102.2229328323513, 1182.2847177457686, 1189.2448295332354, 1256.7360854220717, 1286.1961274203622, 14396.0, 15282.388704451698, 15346.53960381168, 15592.0, 16397.154913559927, 333000.0, 333188.0, 333211.0, 333292.0, 333918.0, 56728.14907776387, 56969.0, 57086.42913570894, 57603.135952960736, 57615.42384576184, 1343.4491858338035, 1390.1033833177544, 1449.340550049725, 1480.2376775960388, 1494.6412860913304, 27445.862770686017, 28704.856475717486, 29145.56281655819, 29685.554716678835, 29840.0, 46081.0, 46742.76628616835, 46790.76604616955, 46807.76596116998, 46852.531474684816, 219925.0, 221480.0, 221705.0, 222248.0, 223293.0, 35639.10902227465, 36002.63997359993, 36092.09769755777, 36470.0, 36513.452298215016, 47479.52520474751, 47743.04513909772, 48226.27660585024, 48565.27152092792, 48671.969125093965, 38465.2306953865, 39206.0, 39456.0, 39457.40813887736, 39983.80008099941, 40431.1913761729, 40734.1119065714, 40877.79561102176, 41371.172576547695, 41611.0, 40342.79828600838, 40348.394774079, 40352.3947140799, 40641.0, 40747.59252407438, 35083.0, 35678.4648230282, 36837.81581092077, 38079.0, 38128.42807357836, 26679.599806003313, 27428.862855685595, 29397.559036614897, 29910.0, 30095.548566771955], "unit": "ms", "name": "ext.html", "value": 20215.52773516067}, {"lowerIsBetter": false, "replicates": [391.7923500544711, 391.8099721635007, 393.44524220848604, 395.5372214509024, 397.1302846765585, 15298.23508824557, 16640.500784976455, 16908.577285567866, 18507.81492185076, 18508.167132479037, 17695.911520442412, 18145.7278140828, 18147.274109035654, 18538.258469661232, 18905.810941890577, 5089.567386772129, 5149.588032957355, 5177.844664660048, 5198.2982297389935, 5203.0, 3989.5810939851335, 4100.85647002355, 5144.17693169093, 5565.471280228372, 5722.513586345166, 5601.327840659126, 5605.803796867111, 5627.521660658849, 5662.801801936933, 5706.457886500796, 5459.235707001023, 5591.7483713233005, 5602.60781745278, 5608.859778505529, 5612.7754889804355, 5393.406725260218, 5393.433689462589, 5403.081476149064, 5404.351477822647, 5405.864853378697, 13684.31578421075, 14164.787528187086, 14175.220362880038, 14180.0, 19746.70379944302, 2197.6703494475796, 2212.6570381590877, 2320.5358928214423, 2371.632396978471, 2454.4722884579724, 10091.0, 10106.994075864914, 10208.642697505626, 10301.484925753757, 10602.734931626694, 1881.369741136721, 2022.393282015404, 2030.7360043194437, 2033.644112280345, 2034.9287774927807, 64431.35568644301, 66347.66826165887, 66623.0, 68451.0, 68665.65667171682, 11037.0, 11298.322100673986, 11368.658940231806, 11391.886081139082, 11463.656090317303, 14686.63283417923, 14996.925015374853, 15022.0, 15062.096274223362, 15064.623384415478, 7440.284296231836, 7640.197779233212, 7666.65500052499, 7704.383649308042, 7718.459707820511, 6952.0, 6964.233934267227, 6995.055667484824, 7035.613041282779, 7089.61007144612, 4885.633577481632, 4895.8041678332465, 4956.306117143603, 4979.950200498021, 4982.352294201731, 1737.3179093708513, 1979.3666026871551, 1979.475439008664, 1987.1355960157377, 2010.0854111379288, 923.4459324405303, 972.6498460554336, 975.4488713876636, 993.1111655068673, 995.1740055753708, 14979.0, 17393.478195654152, 17750.37873674441, 17831.197596107948, 18534.629307414045, 965.4641673870929, 1007.793402352516, 1050.8686414198226, 1051.6529545250048, 1096.4243772019595, 9299.53502324881, 9500.23998080152, 9579.13787759105, 9687.70936871895, 9767.755806104904, 594713.0, 595905.0, 607981.0, 628800.0, 638172.0, 87302.56348718215, 88690.0, 89470.0, 89645.55177224202, 91478.54260728786, 1176.5705517486056, 1180.15029178991, 1202.8797120288077, 1209.0206932384797, 1223.8470191226097, 15586.376544938075, 15655.84344156544, 15658.886050383053, 15684.215789210723, 15844.76232856485, 33968.66031339655, 35063.12342191466, 35326.293474130885, 35659.0, 36061.81969090138, 45083.77458112688, 48256.517434825204, 49604.25593616026, 49694.0, 51356.48643513666, 9989.250806189419, 10051.095401413908, 10208.336458130198, 10243.487825608541, 10283.331583447052, 11783.293002419883, 11903.523859045541, 11993.520259189534, 14169.43322267139, 14767.335469903664, 14608.926955365156, 14963.476278330207, 15183.544493665207, 15196.316165772789, 15800.683986319978, 14878.181700006604, 14927.925360373129, 15013.774793378327, 15092.245387730789, 15330.540083797503, 15016.6996660064, 15723.0, 15913.0, 16103.67792644164, 16255.268512916711, 9762.219022478326, 10215.489225538544, 10293.125084367912, 10306.536205870603, 10361.067503924683, 10674.412907290174, 10737.248392612442, 11832.34922079294, 15730.056196628238, 16058.919705401866], "unit": "ms", "name": "jquery.html", "value": 9152.645826105521}, {"lowerIsBetter": false, "replicates": [1532.5172570640254, 1752.0, 1819.6683716710043, 1845.7600511933447, 1863.7297591849183, 7187.496875218737, 7509.136449308325, 7513.812154696136, 7518.398528117751, 7567.0, 6387.936120638792, 6440.162778838756, 7112.537685050468, 7306.853862922739, 7352.522086064416, 2195.1768086967386, 2203.283932721864, 2225.63277059285, 2226.443389152712, 2228.821694264455, 1938.8158124978174, 2138.1874887542713, 2185.584738899612, 2187.814035806958, 2188.2341180586827, 1899.2023350192883, 2159.838012149095, 2214.180753121347, 2215.4572129828216, 2215.8559693619945, 2141.1649456711893, 2163.967540486894, 2164.404788683125, 2167.8590891592, 2174.7281589801273, 2188.9671654925187, 2191.2330684260382, 2204.1403852497538, 2215.9778402215934, 2218.744844342889, 7417.332440080419, 7482.289182527651, 7490.4756667033325, 7494.1381741099385, 7499.325060744504, 4440.777961101932, 4541.613962813165, 4541.8410355637725, 4544.545545445462, 4546.454425468932, 2945.3225758075764, 3012.5782390465133, 3014.4724673182104, 3048.2531779714113, 3053.8167709937475, 996.4170959988343, 1013.4426065663929, 1018.9082982531608, 1044.655263762964, 1048.114343379843, 9897.554610042565, 9916.057974492413, 9927.255455840841, 9930.304878658497, 9940.652077177265, 4064.9796751016056, 4122.154958233555, 4127.09203975125, 4141.855035073758, 4174.666026717855, 8605.268552173135, 8614.396992210484, 8616.138386161334, 8630.654773808978, 8630.956845215735, 4598.977005114953, 4624.1445332613575, 4632.444106707217, 4637.25803871379, 4638.0260145369175, 4220.788960552021, 4244.3633454982, 4246.363045543193, 4247.49030116388, 4253.595908388698, 3601.3517566838223, 3639.490471334015, 3659.798711070917, 3668.192997540537, 3685.152414944524, 1062.95216715248, 1074.1353210665334, 1181.4270079011671, 1187.6852634051984, 1194.4624918786408, 1160.5879912631062, 1165.5279611757253, 1178.0634395655475, 1182.018924292835, 1188.7800756859835, 7180.31786980236, 7206.891896621555, 7241.131064272321, 7289.380402665833, 7293.0, 1200.6458094862037, 1209.3469526455792, 1209.866914639389, 1210.2980271442543, 1225.7609766095525, 5807.738651760681, 5859.091840764603, 5868.2664666916635, 5873.823785286363, 5880.470757631748, 8434.493930364199, 8932.062133476022, 8943.910560894437, 8961.372703910794, 8979.10208979097, 8769.254613357809, 8777.605007774666, 8858.512781797062, 8890.73327800167, 8906.55467226636, 6878.896816547755, 6917.481188910851, 7131.964340178266, 7136.92863071373, 7149.428045756327, 6962.547434416747, 6992.4406047517095, 6994.510384273052, 6994.5803251805055, 6994.860102798018, 7779.494332868346, 7965.044194696674, 7997.4401791876335, 8092.190780921861, 8107.635156418093, 8064.55644939534, 8257.21556452136, 8280.171982801912, 8297.04583972851, 8301.211384918422, 6232.940400132079, 6296.212973378328, 6444.8388790280615, 6449.419552240321, 6469.126667899774, 7162.176349719849, 7216.7834964951135, 7220.133583969747, 7220.711171553079, 7223.422126229784, 5841.18223448709, 6763.932360676527, 6853.7943861684225, 6957.930420695729, 7003.019577259086, 6550.868982620416, 6574.967125164344, 6600.339966003361, 6691.799246022627, 6819.5908245505425, 6769.323067693192, 6787.89818152738, 6873.690683919136, 6886.414654754201, 6887.276835932155, 6334.904976425265, 6360.0, 6381.234251889803, 6385.169927909342, 6391.456726178327, 6379.904301435389, 6416.454601358937, 6455.741770329134, 6470.935290647033, 6480.513961452815], "unit": "ms", "name": "mootools.html", "value": 4371.905225592374}, {"lowerIsBetter": false, "replicates": [352.1864493021121, 361.31530749230194, 363.88537610652645, 365.5850609558151, 369.39419352262297, 6225.159603453538, 6235.407636274558, 6254.562180647351, 6257.0, 6281.309056003847, 5929.614575052618, 6029.125776762377, 6059.0608455689435, 6199.814005579839, 6209.937900620993, 4754.286856971447, 4785.3300537924715, 4792.856214313568, 4801.711897286156, 4801.879953001168, 4564.84023059193, 4781.737004464753, 4786.952130478703, 4790.7604619769045, 4794.184988551954, 4592.196365636017, 4643.373144625466, 4738.573528382445, 4755.548222918817, 4758.762061896909, 4731.124741922756, 4752.405949256343, 4756.025014871943, 4756.619470442356, 4757.04859028194, 4182.64447521961, 4205.432266644025, 4206.831726730927, 4206.91586168278, 4213.346931225665, 5771.913421298684, 5846.269216347957, 5852.824415267549, 5862.970685146547, 5870.589058765888, 5569.637973531708, 5571.749271282762, 5572.414896435897, 5573.303337082864, 5573.5819813514145, 6496.317886621884, 6537.182852143482, 6540.869182616369, 6541.443977261938, 6541.967290163567, 4647.60495357895, 4653.092646933849, 4655.627549796042, 4675.485696573374, 4683.95316046842, 6630.966845165792, 6766.729330826761, 6799.218089919673, 6809.3190680932, 6815.352541508599, 4599.08018396322, 4636.142313671914, 4639.698419602715, 4639.9536004640195, 4660.673752837269, 4724.527547245248, 4726.480087190406, 4726.621870250372, 4727.2909063640045, 4728.692634978715, 5337.8131765388025, 5458.4814442627885, 5467.6719396836315, 5469.535089517435, 5471.972640136773, 6556.606603603799, 6574.276829548746, 6586.80239592813, 6588.604683718992, 6590.538662293689, 1644.6546225292823, 1659.8672106231472, 1662.5012496251077, 1663.2847875413688, 1663.4760050584027, 1095.2333366643381, 1097.8298363753631, 1098.4837126550508, 1123.3035517978742, 1128.3230061962756, 6414.262359828586, 6427.967860160763, 6429.485641148697, 6436.420722134937, 6436.613803171825, 6679.3320667933785, 6798.354156355138, 6843.418309443654, 6850.554713943578, 6861.451083913275, 6374.69318789647, 6381.1066450696935, 6486.643234622046, 6491.0, 6506.739730410731, 3657.835397407123, 3731.794751288651, 3732.197577520846, 3734.589195188527, 3734.8506059757847, 6864.38220560152, 7052.435805135577, 7073.363397294268, 7077.82305442368, 7086.2205157432645, 7006.4044556212275, 7064.258252883476, 7077.7168913242895, 7081.610511421921, 7104.183018952784, 7048.964755176191, 7062.258462861429, 7062.435005199572, 7073.045138906285, 7099.858002839915, 6818.965905170442, 6859.588424694534, 6925.168979722465, 6931.341522555349, 6954.130733658293, 6833.0, 6833.282505336868, 6834.658267086526, 6836.5214434989075, 6842.144731908512, 7060.6469676517, 7077.5399599025895, 7081.044059052168, 7088.787336379917, 7091.468139889632, 4523.638108951342, 4664.836730714409, 4688.296755486705, 4702.553257440538, 4735.052989402065, 6840.452763778786, 6843.828904277433, 6844.2129155147795, 6844.691988860613, 6848.9657551711925, 6641.833954150991, 6649.0, 6658.500612454182, 6666.500012498985, 6673.632950187786, 6513.088167656627, 6653.1018312527185, 6653.367930046637, 6658.0345849852465, 6659.134312539339, 6481.935180648133, 6525.608463492016, 6528.086067950396, 6535.771248006297, 6538.0, 6607.306232845482, 6662.800115996528, 6673.666316684049, 6673.8998915015345, 6686.966565167143, 6330.208723909512, 6391.776287829904, 6505.186851643544, 6509.381608747161, 6519.380658837403], "unit": "ms", "name": "prototype.html", "value": 4992.929364693493}, {"lowerIsBetter": false, "replicates": [838.0153319849176, 894.1505569708776, 916.2303664921465, 943.8867335919691, 948.0993056596235, 14555.56333310001, 14716.484923027696, 14798.556043318704, 14807.851921480782, 14899.329530171155, 13980.231087290194, 14014.15915045098, 14038.368273427695, 14063.859361406383, 14077.50728724495, 744.4007573903006, 754.9773506794805, 758.8368500772333, 908.2143945487139, 921.9631214751402, 865.1219012702112, 914.1909410172012, 915.8671992561108, 922.9676961306355, 923.0630909626701, 944.9763755906123, 947.5972711597578, 953.1040821627682, 955.4171955107379, 956.3926906414442, 784.9803754906144, 802.8755542890862, 937.4843835890233, 952.4666186935339, 953.8378475659155, 704.4681265644406, 706.6784613001123, 706.883364244899, 708.412018025038, 708.7023450150897, 13974.93012534941, 14056.297185140702, 14063.64840878976, 14102.858971410256, 14207.644808879757, 11566.595169169039, 12225.083118766128, 13141.0, 13187.406566704522, 13388.397522111529, 10652.786944261148, 10742.462876856125, 10836.837447438294, 10964.671059868217, 11041.447927603587, 3890.790098169022, 3895.7467764595494, 3933.724639275252, 3971.4439978403047, 4038.8788336349958, 47176.76411617954, 47291.29063064022, 47479.762601186776, 48001.519984800056, 48554.51445485535, 10300.536475858604, 10321.587136514605, 10362.326448780805, 10413.42726150069, 10459.790804183875, 16642.667146657, 16706.749398759028, 16722.66554668924, 19666.410007699793, 19968.50078748043, 7527.360174385129, 7693.538387696756, 7703.383729301642, 7705.15243323234, 7767.572783496962, 4546.772661366918, 4642.2108241598335, 4667.159911215946, 4673.976630116827, 4745.240761478147, 3303.487959366302, 3309.5532103166247, 3312.0, 3322.119638295805, 3329.0, 586.6773274698899, 595.6961949405834, 601.4616917858492, 607.4988134788817, 611.9204503414528, 659.2385794407467, 671.318611609217, 675.8040168351222, 686.7218776395468, 689.1867596236389, 8855.73432797017, 8857.867131993025, 9199.356045076915, 9200.40197387181, 9235.861462078074, 527.9128943724243, 565.9009673307155, 566.8809549994547, 650.067153634536, 678.0744284052233, 4190.266703326905, 4211.515675697334, 4405.955940440619, 4407.6914615976575, 4517.75152366623, 58027.41972580305, 61243.0, 61543.0, 62579.0, 62633.373666262756, 49895.50104498908, 50909.0, 53328.20007699887, 53404.0, 53604.19593706098, 47714.04571908612, 48909.266361004615, 49172.262416063786, 49971.25043124356, 50269.0, 8756.168164024662, 8852.734417967471, 8947.328950328621, 9034.954825226096, 9038.231750301034, 36574.0, 37454.250914981, 37506.812465937495, 37799.62200377961, 37900.62099379081, 45265.32102018406, 47456.525434745214, 47842.52157478381, 48150.518494816, 48154.759226205046, 10479.895201047892, 11326.37704926237, 11336.659900203007, 11376.0, 11780.116491263361, 11834.230774999598, 12101.394930253628, 12181.208221465577, 12342.0, 12833.165844220095, 11299.152563557931, 11585.88901969362, 11590.304581725124, 11595.0, 11978.04175665962, 10679.46602669848, 11011.28426652265, 11063.391513466837, 11133.0, 11438.82841757391, 11728.76542469163, 11738.76522469563, 11926.940365298466, 12157.027437804778, 12214.633560992828, 10133.08802207805, 10196.847047294148, 10257.0, 10543.94728026355, 10722.62470813549, 9887.653932112342, 10002.799944001225, 10349.327293725884, 10360.689179324632, 10413.739656508647], "unit": "ms", "name": "yui.html", "value": 6623.291072350196}], "name": "dromaeo_css", "value": 7257.786831953073}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [109.0, 112.0, 106.0, 110.0, 112.0, 112.0, 100.0, 109.0, 111.0, 111.0], "unit": "ms", "name": "ai-astar", "value": 109.2}, {"lowerIsBetter": true, "replicates": [149.0, 123.0, 158.0, 161.0, 131.0, 155.0, 151.0, 130.0, 155.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.5}, {"lowerIsBetter": true, "replicates": [161.0, 161.0, 161.0, 159.0, 160.0, 159.0, 158.0, 163.0, 160.0, 163.0], "unit": "ms", "name": "audio-dft", "value": 160.5}, {"lowerIsBetter": true, "replicates": [73.0, 70.0, 71.0, 149.0, 74.0, 75.0, 71.0, 71.0, 155.0, 72.0], "unit": "ms", "name": "audio-fft", "value": 88.1}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 141.0, 92.0, 93.0, 93.0, 141.0, 92.0, 93.0, 133.0], "unit": "ms", "name": "audio-oscillator", "value": 106.4}, {"lowerIsBetter": true, "replicates": [109.0, 108.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 108.9}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.2}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.3}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 56.0, 55.0, 56.0, 57.0, 56.0, 56.0, 53.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 55.5}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 65.0, 63.0, 64.0, 64.0, 64.0, 64.0, 65.0, 64.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 63.9}, {"lowerIsBetter": true, "replicates": [85.0, 78.0, 77.0, 79.0, 79.0, 80.0, 78.0, 77.0, 79.0, 80.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 79.2}, {"lowerIsBetter": true, "replicates": [124.0, 118.0, 114.0, 113.0, 117.0, 112.0, 119.0, 118.0, 116.0, 126.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 117.7}, {"lowerIsBetter": true, "replicates": [136.0, 137.0, 146.0, 148.0, 151.0, 156.0, 144.0, 144.0, 156.0, 135.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 145.3}, {"lowerIsBetter": true, "replicates": [63.0, 67.0, 63.0, 63.0, 66.0, 63.0, 64.0, 63.0, 64.0, 66.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.2}], "name": "kraken", "value": 1482.9}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.33853738701725], "unit": "score", "name": "Richards", "value": 214.81267000000008}, {"lowerIsBetter": false, "replicates": [171.40898183064792], "unit": "score", "name": "DeltaBlue", "value": 385.73241199999995}, {"lowerIsBetter": false, "replicates": [278.55153203342616], "unit": "score", "name": "Encrypt", "value": 955.5897900000004}, {"lowerIsBetter": false, "replicates": [5117.34693877551], "unit": "score", "name": "Decrypt", "value": 52.01542971086741}, {"lowerIsBetter": false, "replicates": [971.8172983479105], "unit": "score", "name": "RayTrace", "value": 761.4486809999997}, {"lowerIsBetter": false, "replicates": [544.9591280653951], "unit": "score", "name": "Earley", "value": 1222.959605}, {"lowerIsBetter": false, "replicates": [10589.473684210527], "unit": "score", "name": "Boyer", "value": 62.93636679920471}, {"lowerIsBetter": false, "replicates": [24463.414634146342], "unit": "score", "name": "RegExp", "value": 37.23866899302096}, {"lowerIsBetter": false, "replicates": [514.6078933880061], "unit": "score", "name": "Splay", "value": 158.35551892430277}, {"lowerIsBetter": false, "replicates": [4826.923076923077], "unit": "score", "name": "NavierStokes", "value": 307.4422310756973}], "name": "v8_7", "value": 23215.164847644694}]} 16:35:21 INFO - RETURN: dromaeo_css: 7257.79 16:35:21 INFO - RETURN: kraken: 1482.90 16:35:21 INFO - RETURN: v8_7: 23215.16 16:35:21 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23215.16"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1482.90"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7257.79"}}} 16:35:22 INFO - Return code: 0 16:35:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:35:22 INFO - # TBPL SUCCESS # 16:35:22 INFO - Running post-action listener: _resource_record_post_action 16:35:22 INFO - Running post-run listener: _resource_record_post_run 16:35:23 INFO - Total resource usage - Wall time: 1222s; CPU: 13.0%; Read bytes: 315392; Write bytes: 519876608; Read time: 44; Write time: 619016 16:35:23 INFO - install - Wall time: 15s; CPU: 17.0%; Read bytes: 0; Write bytes: 258232320; Read time: 0; Write time: 460932 16:35:23 INFO - run-tests - Wall time: 1208s; CPU: 13.0%; Read bytes: 315392; Write bytes: 261046272; Read time: 44; Write time: 158084 16:35:23 INFO - Running post-run listener: _upload_blobber_files 16:35:23 INFO - Blob upload gear active. 16:35:23 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:35:23 INFO - Copying logs to upload dir... 16:35:23 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1257.123543 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2016-02-18 16:35:22.829198) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-18 16:35:22.833284) ========= 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=8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009982 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1455832105/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-18 16:35:22.883700) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 16:35:22.884052) ========= 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=8c719f637fb0c43bc4e5671e000001d0-1455840864.457222-963444054 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004671 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-18 16:35:22.934551) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-18 16:35:22.934894) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-18 16:35:22.935211) ========= ========= Total master_lag: 0.27 =========