builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1448497344.32 results: success (0) buildid: 20151125143004 builduid: 0551c0ba97c042e384658b854b79a551 revision: 3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.316453) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.316892) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.317194) ========= 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=cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.009108 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.05 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.378080) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.378472) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.424654) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.425095) ========= 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=cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004583 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.474806) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.475110) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.475475) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.475785) ========= 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=cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-25 16:22:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.1M=0s 2015-11-25 16:22:25 (43.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.169432 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.657139) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.657474) ========= 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=cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015056 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.706678) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:24.707013) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98 --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=cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-25 16:22:26,015 truncating revision to first 12 chars 2015-11-25 16:22:26,015 Setting DEBUG logging. 2015-11-25 16:22:26,015 attempt 1/10 2015-11-25 16:22:26,015 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/3e7edd0d28a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-25 16:22:26,273 unpacking tar archive at: fx-team-3e7edd0d28a6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.538317 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:25.257404) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:25.257735) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:25.266392) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:25.266807) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-25 16:22:25.267425) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2015-11-25 16:22:25.267916) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:22:26 INFO - MultiFileLogger online at 20151125 16:22:26 in /builds/slave/test-pgo 16:22:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 16:22:26 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 16:22:26 INFO - {'append_to_log': False, 16:22:26 INFO - 'base_work_dir': '/builds/slave/test-pgo', 16:22:26 INFO - 'blob_upload_branch': 'Fx-Team', 16:22:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 16:22:26 INFO - 'branch': 'Fx-Team', 16:22:26 INFO - 'buildbot_json_path': 'buildprops.json', 16:22:26 INFO - 'config_files': ('talos/linux_config.py',), 16:22:26 INFO - 'default_actions': ('clobber', 16:22:26 INFO - 'read-buildbot-config', 16:22:26 INFO - 'download-and-extract', 16:22:26 INFO - 'populate-webroot', 16:22:26 INFO - 'create-virtualenv', 16:22:26 INFO - 'install', 16:22:26 INFO - 'run-tests'), 16:22:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:22:26 INFO - 'download_minidump_stackwalk': True, 16:22:26 INFO - 'download_symbols': 'ondemand', 16:22:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:22:26 INFO - 'tooltool.py': '/tools/tooltool.py', 16:22:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:22:26 INFO - '/tools/misc-python/virtualenv.py')}, 16:22:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:22:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:22:26 INFO - 'installer_path': 'installer.exe', 16:22:26 INFO - 'log_level': 'info', 16:22:26 INFO - 'log_name': 'talos', 16:22:26 INFO - 'log_to_console': True, 16:22:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:22:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:22:26 INFO - 'opt_config_files': (), 16:22:26 INFO - 'pip_index': False, 16:22:26 INFO - 'sps_profile': False, 16:22:26 INFO - 'sps_profile_interval': 0, 16:22:26 INFO - 'suite': 'dromaeojs', 16:22:26 INFO - 'system_bits': '64', 16:22:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:22:26 INFO - 'title': 'talos-linux64-ix-005', 16:22:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:22:26 INFO - 'use_talos_json': True, 16:22:26 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 16:22:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:22:26 INFO - 'work_dir': 'build'} 16:22:26 INFO - ##### 16:22:26 INFO - ##### Running clobber step. 16:22:26 INFO - ##### 16:22:26 INFO - Running pre-action listener: _resource_record_pre_action 16:22:26 INFO - Running main action method: clobber 16:22:26 INFO - rmtree: /builds/slave/test-pgo/build 16:22:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 16:22:27 INFO - Running post-action listener: _resource_record_post_action 16:22:27 INFO - ##### 16:22:27 INFO - ##### Running read-buildbot-config step. 16:22:27 INFO - ##### 16:22:27 INFO - Running pre-action listener: _resource_record_pre_action 16:22:27 INFO - Running main action method: read_buildbot_config 16:22:27 INFO - Using buildbot properties: 16:22:27 INFO - { 16:22:27 INFO - "properties": { 16:22:27 INFO - "buildnumber": 517, 16:22:27 INFO - "product": "firefox", 16:22:27 INFO - "script_repo_revision": "production", 16:22:27 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 16:22:27 INFO - "repository": "", 16:22:27 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 16:22:27 INFO - "buildid": "20151125143004", 16:22:27 INFO - "slavename": "talos-linux64-ix-005", 16:22:27 INFO - "pgo_build": "True", 16:22:27 INFO - "basedir": "/builds/slave/test-pgo", 16:22:27 INFO - "project": "", 16:22:27 INFO - "platform": "ubuntu64_hw", 16:22:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:22:27 INFO - "slavebuilddir": "test", 16:22:27 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 16:22:27 INFO - "branch": "fx-team", 16:22:27 INFO - "repo_path": "integration/fx-team", 16:22:27 INFO - "stage_platform": "linux64-pgo", 16:22:27 INFO - "builduid": "0551c0ba97c042e384658b854b79a551", 16:22:27 INFO - "revision": "3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98" 16:22:27 INFO - }, 16:22:27 INFO - "sourcestamp": { 16:22:27 INFO - "repository": "", 16:22:27 INFO - "hasPatch": false, 16:22:27 INFO - "project": "", 16:22:27 INFO - "branch": "fx-team-linux64-pgo-talos", 16:22:27 INFO - "changes": [ 16:22:27 INFO - { 16:22:27 INFO - "category": null, 16:22:27 INFO - "files": [ 16:22:27 INFO - { 16:22:27 INFO - "url": null, 16:22:27 INFO - "name": "https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 16:22:27 INFO - } 16:22:27 INFO - ], 16:22:27 INFO - "repository": "", 16:22:27 INFO - "rev": "3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98", 16:22:27 INFO - "who": "sendchange", 16:22:27 INFO - "when": 1448497276, 16:22:27 INFO - "number": 6708453, 16:22:27 INFO - "comments": "", 16:22:27 INFO - "project": "", 16:22:27 INFO - "at": "Wed 25 Nov 2015 16:21:16", 16:22:27 INFO - "branch": "fx-team-linux64-pgo-talos", 16:22:27 INFO - "revlink": "", 16:22:27 INFO - "properties": [ 16:22:27 INFO - [ 16:22:27 INFO - "buildid", 16:22:27 INFO - "20151125143004", 16:22:27 INFO - "Change" 16:22:27 INFO - ], 16:22:27 INFO - [ 16:22:27 INFO - "builduid", 16:22:27 INFO - "0551c0ba97c042e384658b854b79a551", 16:22:27 INFO - "Change" 16:22:27 INFO - ], 16:22:27 INFO - [ 16:22:27 INFO - "pgo_build", 16:22:27 INFO - "True", 16:22:27 INFO - "Change" 16:22:27 INFO - ] 16:22:27 INFO - ], 16:22:27 INFO - "revision": "3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98" 16:22:27 INFO - } 16:22:27 INFO - ], 16:22:27 INFO - "revision": "3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98" 16:22:27 INFO - } 16:22:27 INFO - } 16:22:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 16:22:27 INFO - Running post-action listener: _resource_record_post_action 16:22:27 INFO - ##### 16:22:27 INFO - ##### Running download-and-extract step. 16:22:27 INFO - ##### 16:22:27 INFO - Running pre-action listener: _resource_record_pre_action 16:22:27 INFO - Running main action method: download_and_extract 16:22:27 INFO - mkdir: /builds/slave/test-pgo/build/tests 16:22: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:22:27 INFO - https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 16:22:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/test_packages.json 16:22:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/test_packages.json 16:22:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 16:22:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 16:22:35 INFO - Downloaded 1302 bytes. 16:22:35 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 16:22:35 INFO - Using the following test package requirements: 16:22:35 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 16:22:35 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:35 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:22:35 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:35 INFO - u'jsshell-linux-x86_64.zip'], 16:22:35 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:35 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:22:35 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 16:22:35 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:35 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:22:35 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:35 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:22:35 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:35 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:22:35 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 16:22:35 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 16:22:35 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:22:35 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:22:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:35 INFO - https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:22:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:22:42 INFO - Downloaded 22755164 bytes. 16:22:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 16:22:42 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:42 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 16:22:44 INFO - Return code: 0 16:22:44 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:22:44 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:22:44 INFO - https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:22:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:22:45 INFO - Downloaded 22755164 bytes. 16:22:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 16:22:45 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 16:22:45 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 16:22:46 INFO - Return code: 0 16:22: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:22:46 INFO - https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:22:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 16:22:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 16:22:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 16:22:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:22:47 INFO - Downloaded 11131717 bytes. 16:22:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 16:22:47 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 16:22:47 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 16:22:47 INFO - Return code: 0 16:22:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:47 INFO - https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:22:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:22:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:22:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:22:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:22:50 INFO - Downloaded 58386647 bytes. 16:22:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:22:50 INFO - mkdir: /builds/slave/test-pgo/properties 16:22:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:22:50 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:22:50 INFO - Contents: 16:22:50 INFO - build_url:https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 16:22:50 INFO - Running post-action listener: _resource_record_post_action 16:22:50 INFO - Running post-action listener: set_extra_try_arguments 16:22:50 INFO - ##### 16:22:50 INFO - ##### Running populate-webroot step. 16:22:50 INFO - ##### 16:22:50 INFO - Running pre-action listener: _resource_record_pre_action 16:22:50 INFO - Running main action method: populate_webroot 16:22:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:22:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:22:50 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 16:22:50 INFO - '../fennec_ids.txt'], 16:22:50 INFO - 'tests': ['tcheck2']}, 16:22:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:22:50 INFO - '--tppagecycles', 16:22:50 INFO - '7'], 16:22:50 INFO - 'tests': ['tsvgm']}}, 16:22:50 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:22:50 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:22:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:22:50 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:22:50 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:22:50 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:22:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:22:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:50 INFO - 'talos_options': ['--e10s'], 16:22:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:22:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:50 INFO - 'tests': ['damp', 'tps']}, 16:22:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:50 INFO - 'talos_options': ['--e10s'], 16:22:50 INFO - 'tests': ['damp', 'tps']}, 16:22:50 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:22:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:22:50 INFO - 'other': {'tests': ['a11yr', 16:22:50 INFO - 'ts_paint', 16:22:50 INFO - 'tpaint', 16:22:50 INFO - 'sessionrestore', 16:22:50 INFO - 'sessionrestore_no_auto_restore']}, 16:22:50 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:22:50 INFO - 'tests': ['a11yr', 16:22:50 INFO - 'ts_paint', 16:22:50 INFO - 'tpaint', 16:22:50 INFO - 'sessionrestore', 16:22:50 INFO - 'sessionrestore_no_auto_restore']}, 16:22:50 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:22:50 INFO - 'tests': ['a11yr', 16:22:50 INFO - 'ts_paint', 16:22:50 INFO - 'tpaint', 16:22:50 INFO - 'sessionrestore', 16:22:50 INFO - 'sessionrestore_no_auto_restore']}, 16:22:50 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:22:50 INFO - 'tests': ['a11yr', 16:22:50 INFO - 'ts_paint', 16:22:50 INFO - 'tpaint', 16:22:50 INFO - 'sessionrestore', 16:22:50 INFO - 'sessionrestore_no_auto_restore']}, 16:22:50 INFO - 'other_l64': {'tests': ['a11yr', 16:22:50 INFO - 'ts_paint', 16:22:50 INFO - 'tpaint', 16:22:50 INFO - 'sessionrestore', 16:22:50 INFO - 'sessionrestore_no_auto_restore']}, 16:22:50 INFO - 'other_nol64': {'tests': ['a11yr', 16:22:50 INFO - 'ts_paint', 16:22:50 INFO - 'tpaint', 16:22:50 INFO - 'sessionrestore', 16:22:50 INFO - 'sessionrestore_no_auto_restore']}, 16:22:50 INFO - 'svgr': {'tests': ['tsvgx', 16:22:50 INFO - 'tsvgr_opacity', 16:22:50 INFO - 'tart', 16:22:50 INFO - 'tscrollx', 16:22:50 INFO - 'cart']}, 16:22:50 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:22:50 INFO - 'tests': ['tsvgx', 16:22:50 INFO - 'tsvgr_opacity', 16:22:50 INFO - 'tart', 16:22:50 INFO - 'tscrollx', 16:22:50 INFO - 'cart']}, 16:22:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:50 INFO - 'tests': ['tp5o']}, 16:22:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:50 INFO - 'talos_options': ['--e10s'], 16:22:50 INFO - 'tests': ['tp5o']}, 16:22:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:50 INFO - 'talos_options': ['--xperf_path', 16:22:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:22:50 INFO - 'tests': ['tp5n']}, 16:22:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:22:50 INFO - 'talos_options': ['--e10s', 16:22:50 INFO - '--xperf_path', 16:22:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:22:50 INFO - 'tests': ['tp5n']}}, 16:22:50 INFO - 'talos.zip': {'path': '', 16:22:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:22:50 INFO - Running post-action listener: _resource_record_post_action 16:22:50 INFO - ##### 16:22:50 INFO - ##### Running create-virtualenv step. 16:22:50 INFO - ##### 16:22:50 INFO - Running pre-action listener: _resource_record_pre_action 16:22:50 INFO - Running main action method: create_virtualenv 16:22:50 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:22:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test-pgo/build/venv'] in /builds/slave/test-pgo/build 16:22:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:22:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:22:50 INFO - Using real prefix '/usr' 16:22:50 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:22:51 INFO - Installing distribute.............................................................................................................................................................................................done. 16:22:53 INFO - Installing pip.................done. 16:22:53 INFO - Return code: 0 16:22:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:22: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:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16167d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a5640>, '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 0x16a6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a69d0>, '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': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', '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:22:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 16:22:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:22:53 INFO - Using env: {'DISPLAY': ':0', 16:22:53 INFO - 'HOME': '/home/cltbld', 16:22:53 INFO - 'LANG': 'en_US.UTF-8', 16:22:53 INFO - 'LANGUAGE': 'en_US:en', 16:22:53 INFO - 'LOGNAME': 'cltbld', 16:22:53 INFO - 'MAIL': '/var/mail/cltbld', 16:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:53 INFO - 'MOZ_NO_REMOTE': '1', 16:22:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:53 INFO - 'NO_EM_RESTART': '1', 16:22:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:22:53 INFO - 'PWD': '/builds/slave/test-pgo', 16:22:53 INFO - 'SHELL': '/bin/bash', 16:22:53 INFO - 'SHLVL': '1', 16:22:53 INFO - 'TERM': 'linux', 16:22:53 INFO - 'TMOUT': '86400', 16:22:53 INFO - 'USER': 'cltbld', 16:22:53 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', 16:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:53 INFO - '_': '/tools/buildbot/bin/python'} 16:22:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:53 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:22:53 INFO - Cleaning up... 16:22:53 INFO - Return code: 0 16:22:53 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:22: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:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22: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:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:53 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13e9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16167d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a5640>, '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 0x16a6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a69d0>, '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': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', '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:22:53 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 16:22:53 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:22:53 INFO - Using env: {'DISPLAY': ':0', 16:22:53 INFO - 'HOME': '/home/cltbld', 16:22:53 INFO - 'LANG': 'en_US.UTF-8', 16:22:53 INFO - 'LANGUAGE': 'en_US:en', 16:22:53 INFO - 'LOGNAME': 'cltbld', 16:22:53 INFO - 'MAIL': '/var/mail/cltbld', 16:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:53 INFO - 'MOZ_NO_REMOTE': '1', 16:22:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:53 INFO - 'NO_EM_RESTART': '1', 16:22:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:22:53 INFO - 'PWD': '/builds/slave/test-pgo', 16:22:53 INFO - 'SHELL': '/bin/bash', 16:22:53 INFO - 'SHLVL': '1', 16:22:53 INFO - 'TERM': 'linux', 16:22:53 INFO - 'TMOUT': '86400', 16:22:53 INFO - 'USER': 'cltbld', 16:22:53 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', 16:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:53 INFO - '_': '/tools/buildbot/bin/python'} 16:22:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:53 INFO - Downloading/unpacking psutil>=0.7.1 16:22:53 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:22:53 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:22:53 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:22:53 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:22:53 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:22:53 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:22:55 INFO - Creating supposed download cache at /builds/slave/test-pgo/build/venv/cache 16:22:55 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 16:22:55 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:22:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:22:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:22:55 INFO - Installing collected packages: psutil 16:22:55 INFO - Running setup.py install for psutil 16:22:55 INFO - building 'psutil._psutil_linux' extension 16:22:55 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:22:55 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:22:55 INFO - building 'psutil._psutil_posix' extension 16:22:55 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:22:55 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:22:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:22:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:22:55 INFO - Successfully installed psutil 16:22:55 INFO - Cleaning up... 16:22:55 INFO - Return code: 0 16:22:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:22:55 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:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:55 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:22:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:55 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16167d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a5640>, '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 0x16a6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a69d0>, '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': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', '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:22:55 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 16:22:55 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:22:55 INFO - Using env: {'DISPLAY': ':0', 16:22:55 INFO - 'HOME': '/home/cltbld', 16:22:55 INFO - 'LANG': 'en_US.UTF-8', 16:22:55 INFO - 'LANGUAGE': 'en_US:en', 16:22:55 INFO - 'LOGNAME': 'cltbld', 16:22:55 INFO - 'MAIL': '/var/mail/cltbld', 16:22:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:55 INFO - 'MOZ_NO_REMOTE': '1', 16:22:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:55 INFO - 'NO_EM_RESTART': '1', 16:22:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:22:55 INFO - 'PWD': '/builds/slave/test-pgo', 16:22:55 INFO - 'SHELL': '/bin/bash', 16:22:55 INFO - 'SHLVL': '1', 16:22:55 INFO - 'TERM': 'linux', 16:22:55 INFO - 'TMOUT': '86400', 16:22:55 INFO - 'USER': 'cltbld', 16:22:55 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', 16:22:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:55 INFO - '_': '/tools/buildbot/bin/python'} 16:22:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:22: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:22: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:22: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:22: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:22: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:22: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:22:57 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:22:57 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 16:22:57 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:22:57 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:22:57 INFO - Installing collected packages: mozsystemmonitor 16:22:57 INFO - Running setup.py install for mozsystemmonitor 16:22:57 INFO - Successfully installed mozsystemmonitor 16:22:57 INFO - Cleaning up... 16:22:58 INFO - Return code: 0 16:22:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 16:22:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:22:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:22:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:22:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:22:58 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16167d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a5640>, '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 0x16a6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a69d0>, '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': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', '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:22:58 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 16:22:58 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:22:58 INFO - Using env: {'DISPLAY': ':0', 16:22:58 INFO - 'HOME': '/home/cltbld', 16:22:58 INFO - 'LANG': 'en_US.UTF-8', 16:22:58 INFO - 'LANGUAGE': 'en_US:en', 16:22:58 INFO - 'LOGNAME': 'cltbld', 16:22:58 INFO - 'MAIL': '/var/mail/cltbld', 16:22:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:22:58 INFO - 'MOZ_NO_REMOTE': '1', 16:22:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:22:58 INFO - 'NO_EM_RESTART': '1', 16:22:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:22:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:22:58 INFO - 'PWD': '/builds/slave/test-pgo', 16:22:58 INFO - 'SHELL': '/bin/bash', 16:22:58 INFO - 'SHLVL': '1', 16:22:58 INFO - 'TERM': 'linux', 16:22:58 INFO - 'TMOUT': '86400', 16:22:58 INFO - 'USER': 'cltbld', 16:22:58 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', 16:22:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:22:58 INFO - '_': '/tools/buildbot/bin/python'} 16:22:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:22:58 INFO - Downloading/unpacking blobuploader==1.2.4 16:22: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:22: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:22: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:22: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:22: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:22: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:23:00 INFO - Downloading blobuploader-1.2.4.tar.gz 16:23:00 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 16:23:00 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:23:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 16:23:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:23:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:23:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:23:01 INFO - Downloading docopt-0.6.1.tar.gz 16:23:01 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 16:23:01 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:23:01 INFO - Installing collected packages: blobuploader, requests, docopt 16:23:01 INFO - Running setup.py install for blobuploader 16:23:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:23:01 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:23:01 INFO - Running setup.py install for requests 16:23:02 INFO - Running setup.py install for docopt 16:23:02 INFO - Successfully installed blobuploader requests docopt 16:23:02 INFO - Cleaning up... 16:23:02 INFO - Return code: 0 16:23:02 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:23:02 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:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:02 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:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:02 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16167d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a5640>, '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 0x16a6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a69d0>, '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': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', '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:23:02 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 16:23:02 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:23:02 INFO - Using env: {'DISPLAY': ':0', 16:23:02 INFO - 'HOME': '/home/cltbld', 16:23:02 INFO - 'LANG': 'en_US.UTF-8', 16:23:02 INFO - 'LANGUAGE': 'en_US:en', 16:23:02 INFO - 'LOGNAME': 'cltbld', 16:23:02 INFO - 'MAIL': '/var/mail/cltbld', 16:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:02 INFO - 'MOZ_NO_REMOTE': '1', 16:23:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:02 INFO - 'NO_EM_RESTART': '1', 16:23:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:23:02 INFO - 'PWD': '/builds/slave/test-pgo', 16:23:02 INFO - 'SHELL': '/bin/bash', 16:23:02 INFO - 'SHLVL': '1', 16:23:02 INFO - 'TERM': 'linux', 16:23:02 INFO - 'TMOUT': '86400', 16:23:02 INFO - 'USER': 'cltbld', 16:23:02 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', 16:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:02 INFO - '_': '/tools/buildbot/bin/python'} 16:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:23:02 INFO - Running setup.py (path:/tmp/pip-HJtXMA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:23:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:23:02 INFO - Running setup.py (path:/tmp/pip-HxyFTX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:23:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:23:02 INFO - Running setup.py (path:/tmp/pip-KTpEA9-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:23:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:23:02 INFO - Running setup.py (path:/tmp/pip-jxeKSp-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:23:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:23:02 INFO - Running setup.py (path:/tmp/pip-92sClA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:23:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:23:02 INFO - Running setup.py (path:/tmp/pip-BTeYpL-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:23:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:23:02 INFO - Running setup.py (path:/tmp/pip-GBEMTY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:23:02 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:23:02 INFO - Running setup.py (path:/tmp/pip-zVfxcX-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:23:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:23:03 INFO - Running setup.py (path:/tmp/pip-D1bRg8-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:23:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:23:03 INFO - Running setup.py (path:/tmp/pip-7cwdJs-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:23:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:23:03 INFO - Running setup.py (path:/tmp/pip-jKrssi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:23:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:23:03 INFO - Running setup.py (path:/tmp/pip-bDgTgY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:23:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:23:03 INFO - Running setup.py (path:/tmp/pip-ZF6aGc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:23:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:23:03 INFO - Running setup.py (path:/tmp/pip-47tWwz-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:23:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:23:03 INFO - Running setup.py (path:/tmp/pip-YCdKBc-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:23:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:23:03 INFO - Running setup.py (path:/tmp/pip-VbZ_W1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:23:03 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:23:03 INFO - Running setup.py (path:/tmp/pip-0lsumG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:23:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:23:03 INFO - Running setup.py install for manifestparser 16:23:03 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:23:03 INFO - Running setup.py install for mozcrash 16:23:03 INFO - Running setup.py install for mozdebug 16:23:03 INFO - Running setup.py install for mozdevice 16:23:04 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:23:04 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:23:04 INFO - Running setup.py install for mozfile 16:23:04 INFO - Running setup.py install for mozhttpd 16:23:04 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:23:04 INFO - Running setup.py install for mozinfo 16:23:04 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:23:04 INFO - Running setup.py install for mozInstall 16:23:04 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:23:04 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:23:04 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:23:04 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:23:04 INFO - Running setup.py install for mozleak 16:23:04 INFO - Running setup.py install for mozlog 16:23:04 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:23:04 INFO - Running setup.py install for moznetwork 16:23:04 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:23:04 INFO - Running setup.py install for mozprocess 16:23:04 INFO - Running setup.py install for mozprofile 16:23:05 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:23:05 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:23:05 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:23:05 INFO - Running setup.py install for mozrunner 16:23:05 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:23:05 INFO - Running setup.py install for mozscreenshot 16:23:05 INFO - Running setup.py install for moztest 16:23:05 INFO - Running setup.py install for mozversion 16:23:05 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:23:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:23:05 INFO - Cleaning up... 16:23:05 INFO - Return code: 0 16:23:05 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:23:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:23:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:05 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16167d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a5640>, '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 0x16a6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a69d0>, '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': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', '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:23:05 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 16:23:05 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:23:05 INFO - Using env: {'DISPLAY': ':0', 16:23:05 INFO - 'HOME': '/home/cltbld', 16:23:05 INFO - 'LANG': 'en_US.UTF-8', 16:23:05 INFO - 'LANGUAGE': 'en_US:en', 16:23:05 INFO - 'LOGNAME': 'cltbld', 16:23:05 INFO - 'MAIL': '/var/mail/cltbld', 16:23:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:05 INFO - 'MOZ_NO_REMOTE': '1', 16:23:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:05 INFO - 'NO_EM_RESTART': '1', 16:23:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:23:05 INFO - 'PWD': '/builds/slave/test-pgo', 16:23:05 INFO - 'SHELL': '/bin/bash', 16:23:05 INFO - 'SHLVL': '1', 16:23:05 INFO - 'TERM': 'linux', 16:23:05 INFO - 'TMOUT': '86400', 16:23:05 INFO - 'USER': 'cltbld', 16:23:05 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', 16:23:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:05 INFO - '_': '/tools/buildbot/bin/python'} 16:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:23:05 INFO - Running setup.py (path:/tmp/pip-bRdYBC-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:23:05 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:23:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:23:05 INFO - Running setup.py (path:/tmp/pip-FOtTOP-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:23:05 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:23:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:23:05 INFO - Running setup.py (path:/tmp/pip-Ijnu5Q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:23:05 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:23:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:23:05 INFO - Running setup.py (path:/tmp/pip-onykTU-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:23:05 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:23:05 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:23:05 INFO - Running setup.py (path:/tmp/pip-ZgQ7e1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:23:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 5)) 16:23:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:23:06 INFO - Running setup.py (path:/tmp/pip-JPWfO2-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:23:06 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:23:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:23:06 INFO - Running setup.py (path:/tmp/pip-IL_Irh-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:23:06 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:23:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:23:06 INFO - Running setup.py (path:/tmp/pip-W2wxjZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:23:06 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:23:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:23:06 INFO - Running setup.py (path:/tmp/pip-YumJuu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:23:06 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:23:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:23:06 INFO - Running setup.py (path:/tmp/pip-OEuWzH-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:23:06 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:23:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:23:06 INFO - Running setup.py (path:/tmp/pip-ScubjN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:23:06 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:23:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:23:06 INFO - Running setup.py (path:/tmp/pip-7BQDWi-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:23:06 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:23:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:23:06 INFO - Running setup.py (path:/tmp/pip-3S9NDd-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:23:06 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:23:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:23:06 INFO - Running setup.py (path:/tmp/pip-3JSv0o-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:23:06 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:23:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:23:06 INFO - Running setup.py (path:/tmp/pip-gWiikZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:23:06 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:23:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:23:06 INFO - Running setup.py (path:/tmp/pip-t2EuOD-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:23:06 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:23:06 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:23:06 INFO - Running setup.py (path:/tmp/pip-iDQJSg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:23:06 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:23:06 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:23:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 2)) 16:23:06 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:23:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test-pgo/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 4)) 16:23:06 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:23:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:23:06 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:23:06 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:23:06 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:23:06 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:23:06 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:23:06 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:23:08 INFO - Downloading blessings-1.5.1.tar.gz 16:23:08 INFO - Storing download in cache at /builds/slave/test-pgo/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 16:23:08 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:23:08 INFO - Installing collected packages: blessings 16:23:08 INFO - Running setup.py install for blessings 16:23:08 INFO - Successfully installed blessings 16:23:08 INFO - Cleaning up... 16:23:08 INFO - Return code: 0 16:23:08 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:23:08 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:23:08 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:23:09 INFO - Reading from file tmpfile_stdout 16:23:09 INFO - Current package versions: 16:23:09 INFO - argparse == 1.2.1 16:23:09 INFO - blessings == 1.5.1 16:23:09 INFO - blobuploader == 1.2.4 16:23:09 INFO - docopt == 0.6.1 16:23:09 INFO - manifestparser == 1.1 16:23:09 INFO - mozInstall == 1.12 16:23:09 INFO - mozcrash == 0.16 16:23:09 INFO - mozdebug == 0.1 16:23:09 INFO - mozdevice == 0.47 16:23:09 INFO - mozfile == 1.2 16:23:09 INFO - mozhttpd == 0.7 16:23:09 INFO - mozinfo == 0.9 16:23:09 INFO - mozleak == 0.1 16:23:09 INFO - mozlog == 3.1 16:23:09 INFO - moznetwork == 0.27 16:23:09 INFO - mozprocess == 0.22 16:23:09 INFO - mozprofile == 0.27 16:23:09 INFO - mozrunner == 6.11 16:23:09 INFO - mozscreenshot == 0.1 16:23:09 INFO - mozsystemmonitor == 0.0 16:23:09 INFO - moztest == 0.7 16:23:09 INFO - mozversion == 1.4 16:23:09 INFO - psutil == 3.1.1 16:23:09 INFO - requests == 1.2.3 16:23:09 INFO - wsgiref == 0.1.2 16:23:09 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16167d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a5640>, '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 0x16a6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a69d0>, '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': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', '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:23:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 16:23:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:23:09 INFO - Using env: {'DISPLAY': ':0', 16:23:09 INFO - 'HOME': '/home/cltbld', 16:23:09 INFO - 'LANG': 'en_US.UTF-8', 16:23:09 INFO - 'LANGUAGE': 'en_US:en', 16:23:09 INFO - 'LOGNAME': 'cltbld', 16:23:09 INFO - 'MAIL': '/var/mail/cltbld', 16:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:09 INFO - 'MOZ_NO_REMOTE': '1', 16:23:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:09 INFO - 'NO_EM_RESTART': '1', 16:23:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:23:09 INFO - 'PWD': '/builds/slave/test-pgo', 16:23:09 INFO - 'SHELL': '/bin/bash', 16:23:09 INFO - 'SHLVL': '1', 16:23:09 INFO - 'TERM': 'linux', 16:23:09 INFO - 'TMOUT': '86400', 16:23:09 INFO - 'USER': 'cltbld', 16:23:09 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', 16:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:09 INFO - '_': '/tools/buildbot/bin/python'} 16:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:09 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:23:09 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:23:09 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:23:09 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:23:09 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:23:09 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:23:09 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:23:09 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:23:09 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:23:09 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:23:09 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:23:09 INFO - Cleaning up... 16:23:09 INFO - Return code: 0 16:23:09 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 16:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:23:09 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--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 0x13e9030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15498a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16167d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a5640>, '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 0x16a6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a69d0>, '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': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', '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:23:09 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test-pgo/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 16:23:09 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --download-cache /builds/slave/test-pgo/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:23:09 INFO - Using env: {'DISPLAY': ':0', 16:23:09 INFO - 'HOME': '/home/cltbld', 16:23:09 INFO - 'LANG': 'en_US.UTF-8', 16:23:09 INFO - 'LANGUAGE': 'en_US:en', 16:23:09 INFO - 'LOGNAME': 'cltbld', 16:23:09 INFO - 'MAIL': '/var/mail/cltbld', 16:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:09 INFO - 'MOZ_NO_REMOTE': '1', 16:23:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:09 INFO - 'NO_EM_RESTART': '1', 16:23:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:23:09 INFO - 'PWD': '/builds/slave/test-pgo', 16:23:09 INFO - 'SHELL': '/bin/bash', 16:23:09 INFO - 'SHLVL': '1', 16:23:09 INFO - 'TERM': 'linux', 16:23:09 INFO - 'TMOUT': '86400', 16:23:09 INFO - 'USER': 'cltbld', 16:23:09 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', 16:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:09 INFO - '_': '/tools/buildbot/bin/python'} 16:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:23:09 INFO - Downloading/unpacking jsonschema 16:23:09 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:23:09 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:23:09 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:23:09 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:23:09 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:23:09 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:23:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:23:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 16:23:11 INFO - Downloading/unpacking functools32 (from jsonschema) 16:23:11 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:23:11 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:23:11 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:23:11 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:23:11 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:23:11 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:23:11 INFO - Downloading functools32-3.2.3-2.tar.gz 16:23:11 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 16:23:11 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 16:23:11 INFO - warning: no files found matching '*.txt' 16:23:11 INFO - no previously-included directories found matching 'build' 16:23:11 INFO - no previously-included directories found matching 'dist' 16:23:11 INFO - no previously-included directories found matching '.git*' 16:23:11 INFO - Installing collected packages: jsonschema, functools32 16:23:11 INFO - Running setup.py install for functools32 16:23:11 INFO - warning: no files found matching '*.txt' 16:23:11 INFO - no previously-included directories found matching 'build' 16:23:11 INFO - no previously-included directories found matching 'dist' 16:23:11 INFO - no previously-included directories found matching '.git*' 16:23:11 INFO - Successfully installed jsonschema functools32 16:23:11 INFO - Cleaning up... 16:23:11 INFO - Return code: 0 16:23:11 INFO - Running post-action listener: _resource_record_post_action 16:23:11 INFO - Running post-action listener: _start_resource_monitoring 16:23:11 INFO - Starting resource monitoring. 16:23:11 INFO - ##### 16:23:11 INFO - ##### Running install step. 16:23:11 INFO - ##### 16:23:11 INFO - Running pre-action listener: _resource_record_pre_action 16:23:11 INFO - Running main action method: install 16:23:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:23:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:23:11 INFO - Reading from file tmpfile_stdout 16:23:11 INFO - Detecting whether we're running mozinstall >=1.0... 16:23:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:23:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:23:11 INFO - Reading from file tmpfile_stdout 16:23:11 INFO - Output received: 16:23:11 INFO - Usage: mozinstall [options] installer 16:23:11 INFO - Options: 16:23:11 INFO - -h, --help show this help message and exit 16:23:11 INFO - -d DEST, --destination=DEST 16:23:11 INFO - Directory to install application into. [default: 16:23:11 INFO - "/builds/slave/test-pgo"] 16:23:11 INFO - --app=APP Application being installed. [default: firefox] 16:23:11 INFO - mkdir: /builds/slave/test-pgo/build/application 16:23:11 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 16:23:11 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 16:23:26 INFO - Reading from file tmpfile_stdout 16:23:26 INFO - Output received: 16:23:26 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:23:26 INFO - Running post-action listener: _resource_record_post_action 16:23:26 INFO - ##### 16:23:26 INFO - ##### Running run-tests step. 16:23:26 INFO - ##### 16:23:26 INFO - Running pre-action listener: _resource_record_pre_action 16:23:26 INFO - Running main action method: run_tests 16:23:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:23:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:23:26 INFO - Python 2.7.3 16:23:26 INFO - Return code: 0 16:23:26 INFO - grabbing minidump binary from tooltool 16:23:26 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:23:26 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 0x16a5640>, '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 0x16a6540>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16a69d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:23:26 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:23:26 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:23:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:23:26 INFO - Return code: 0 16:23:26 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:23:26 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:23:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:23:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:23:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:23:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 16:23:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost 16:23:26 INFO - Using env: {'DISPLAY': ':0', 16:23:26 INFO - 'HOME': '/home/cltbld', 16:23:26 INFO - 'LANG': 'en_US.UTF-8', 16:23:26 INFO - 'LANGUAGE': 'en_US:en', 16:23:26 INFO - 'LOGNAME': 'cltbld', 16:23:26 INFO - 'MAIL': '/var/mail/cltbld', 16:23:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:23:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:23:26 INFO - 'MOZ_NO_REMOTE': '1', 16:23:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:23:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:23:26 INFO - 'NO_EM_RESTART': '1', 16:23:26 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:23:26 INFO - 'PWD': '/builds/slave/test-pgo', 16:23:26 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:23:26 INFO - 'SHELL': '/bin/bash', 16:23:26 INFO - 'SHLVL': '1', 16:23:26 INFO - 'TERM': 'linux', 16:23:26 INFO - 'TMOUT': '86400', 16:23:26 INFO - 'USER': 'cltbld', 16:23:26 INFO - 'XDG_SESSION_COOKIE': 'cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763', 16:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:23:26 INFO - '_': '/tools/buildbot/bin/python'} 16:23:26 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost'] with output_timeout 3600 16:23:26 INFO - mozversion INFO | application_buildid: 20151125143004 16:23:26 INFO - mozversion INFO | application_changeset: 3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98 16:23:26 INFO - mozversion INFO | application_display_name: Nightly 16:23:26 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:23:26 INFO - mozversion INFO | application_name: Firefox 16:23:26 INFO - mozversion INFO | application_remotingname: firefox 16:23:26 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 16:23:26 INFO - mozversion INFO | application_vendor: Mozilla 16:23:26 INFO - mozversion INFO | application_version: 45.0a1 16:23:26 INFO - mozversion INFO | platform_buildid: 20151125143004 16:23:26 INFO - mozversion INFO | platform_changeset: 3e7edd0d28a6ab21191fac9946ea0a3ac7eecc98 16:23:26 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 16:23:26 INFO - mozversion INFO | platform_version: 45.0a1 16:23:26 INFO - 2015-11-25 16:23:26,834 DEBUG : using testdate: 1448497406 16:23:26 INFO - 2015-11-25 16:23:26,834 DEBUG : actual date: 1448497406 16:23:26 INFO - 2015-11-25 16:23:26,844 INFO : starting webserver on 'localhost:57651' 16:23:26 INFO - 2015-11-25 16:23:26,845 INFO : Starting test suite talos-linux64-ix-005 16:23:26 INFO - 2015-11-25 16:23:26,845 INFO : Starting test dromaeo_css 16:23:26 INFO - 2015-11-25 16:23:26,845 DEBUG : operating with platform_type : linux_ 16:23:26 INFO - 2015-11-25 16:23:26,846 INFO : Initialising browser for dromaeo_css test... 16:23:26 INFO - 2015-11-25 16:23:26,862 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpJomKT6/profile http://localhost:57651/getInfo.html 16:23:30 INFO - 2015-11-25 16:23:30,234 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:23:30 INFO - 2015-11-25 16:23:30,235 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:23:30 INFO - 2015-11-25 16:23:30,235 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:23:30 INFO - 2015-11-25 16:23:30,235 DEBUG : BROWSER_OUTPUT: __metrics 16:23:30 INFO - 2015-11-25 16:23:30,352 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:23:31 INFO - 2015-11-25 16:23:31,331 INFO : Browser initialized. 16:23:31 INFO - 2015-11-25 16:23:31,332 INFO : Running cycle 1/1 for dromaeo_css test... 16:23:31 INFO - 2015-11-25 16:23:31,332 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpJomKT6/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:23:43 INFO - 2015-11-25 16:23:43,020 DEBUG : BROWSER_OUTPUT: RSS: Main: 139182080 16:23:43 INFO - 2015-11-25 16:23:43,020 DEBUG : BROWSER_OUTPUT: 16:24:34 INFO - 2015-11-25 16:24:34,620 DEBUG : BROWSER_OUTPUT: 1448497474619 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:26:43 INFO - 2015-11-25 16:26:43,482 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57651/tests/dromaeo/cssquery-ext.html) 16:26:44 INFO - 2015-11-25 16:26:44,652 DEBUG : BROWSER_OUTPUT: RSS: Main: 258965504 16:26:44 INFO - 2015-11-25 16:26:44,652 DEBUG : BROWSER_OUTPUT: 16:29:46 INFO - 2015-11-25 16:29:46,924 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/dromaeo/cssquery-ext.html (next: http://localhost:57651/tests/dromaeo/cssquery-jquery.html) 16:29:47 INFO - 2015-11-25 16:29:47,491 DEBUG : BROWSER_OUTPUT: RSS: Main: 219586560 16:29:47 INFO - 2015-11-25 16:29:47,492 DEBUG : BROWSER_OUTPUT: 16:32:49 INFO - 2015-11-25 16:32:49,320 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57651/tests/dromaeo/cssquery-mootools.html) 16:32:49 INFO - 2015-11-25 16:32:49,890 DEBUG : BROWSER_OUTPUT: RSS: Main: 209776640 16:32:49 INFO - 2015-11-25 16:32:49,890 DEBUG : BROWSER_OUTPUT: 16:35:51 INFO - 2015-11-25 16:35:51,806 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57651/tests/dromaeo/cssquery-prototype.html) 16:35:52 INFO - 2015-11-25 16:35:52,367 DEBUG : BROWSER_OUTPUT: RSS: Main: 199827456 16:35:52 INFO - 2015-11-25 16:35:52,367 DEBUG : BROWSER_OUTPUT: 16:38:54 INFO - 2015-11-25 16:38:54,846 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57651/tests/dromaeo/cssquery-yui.html) 16:38:55 INFO - 2015-11-25 16:38:55,428 DEBUG : BROWSER_OUTPUT: RSS: Main: 204587008 16:38:55 INFO - 2015-11-25 16:38:55,428 DEBUG : BROWSER_OUTPUT: 16:41:58 INFO - 2015-11-25 16:41:58,184 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/dromaeo/cssquery-yui.html (next: http://localhost:57651/tests/dromaeo/cssquery-dojo.html) 16:41:58 INFO - 2015-11-25 16:41:58,434 DEBUG : BROWSER_OUTPUT: RSS: Main: 283725824 16:41:58 INFO - 2015-11-25 16:41:58,434 DEBUG : BROWSER_OUTPUT: 16:41:58 INFO - 2015-11-25 16:41:58,435 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:41:58 INFO - 2015-11-25 16:41:58,435 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:41:58 INFO - 2015-11-25 16:41:58,435 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:41:58 INFO - 2015-11-25 16:41:58,436 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:41:58 INFO - 2015-11-25 16:41:58,437 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3215.598050243719;3284.4252255855217;3326.8669253229873;3327.351166522529;3344.5652065231507;5342.118550439173;5472.50747432731;5491.972540137299;5508.20131080993;5519.061759500884;5502.064649009668;5555.527780138693;5592.664440133595;5594.356648985368;5623.606347555673;4847.495067543978;5069.873253168673;5079.669821461612;5099.796008159669;5112.130937740593;4934.161192597249;5107.438181800017;5124.154514505102;5165.66423182492;5207.739613019334;4974.751262436882;4992.450830408634;5046.2178362353525;5058.519440653132;5089.160288552384;5070.08738427083;5070.61970352225;5077.923831142536;5109.540141387256;5122.897542049175;4864.562189402971;4994.450610432849;5035.62232832539;5048.091343558158;5065.062963351792;5233.921491177597;5331.760070796823;5343.946560534384;5348;5358.4641535846495;5883;5883.6469811811;5890.587658863881;5939.079442686348;5947.762089516415;5819.88360232793;5864.618799778001;5913.349531551526;5935.10973353996;5954.851128721816;5622.409646987089;5624.3250809903075;5678.034734095172;5690.715464226772;5700.287464066992;6125.938740612626;6132.6933653317155;6140.140380346755;6143.815685529441;6148.231471066117;5693.487586117187;5750.7987220447085;5904.143899134688;5906.438888305604;5963.4334738199805;5827.24245848037;5851.180834683148;5906;5937.70311484424;5978.760849565969;5853.765849365977;5865.266841644794;5871.412858714094;5877.471027607536;5987.760489580455;5798.188253644493;5866.530677545786;5887.735051922674;5900.734466948998;5921.3190483094995;4499.235130027903;4515.887102822456;4566.885827854264;4600.42494688164;4603.125406172817;4090.7136500445276;4101.036256479725;4107.342825147962;4132.504099508018;4143.3784932260405;5548.944510554924;5759;5763.250777398911;5765.682887441147;5769.826905192851;5852.268466441695;5960.642361458326;5975.641461512323;6006.669633170218;6022.156898034279;5723.627964182315;5724.284464441945;5725.456081672234;5727.914081288784;5741.425857414309;5163.612729045337;5221.6083793715625;5227.320448341691;5233.110371236899;5264.368275806928;5880.0591905295805;6016.458518733335;6082.391760823971;6165.815025549241;6179.69101544912;5961.314448838439;5962.582619216613;5964.194833697569;5966.463018328372;6070.2108725865355;6152.384761523812;6179.073139029181;6183.690815459299;6191.597546159485;6197.287311959182;5731.742071606871;5794.304683438015;5797.362290147996;5831.591788574759;5854.94145058561;5903.203067585992;5972.970135149297;5993.041113421831;6026.939730602638;6034.09488576717;5839.5036421904615;5853.678047707417;5858.94141058584;5880.705964701662;5880.911786323123;5628.015097357864;5699.544036477155;5823.417658234142;5834.795782147774;5847.26909136358;5625.493705566519;5664.801731939362;5687.260656114679;5721.828345149652;5722.227499287543;5847.7368518417625;5867.20792692981;5873.853153671193;5880.970595147168;5935.732892019956;5461.918071228855;5647.774089036392;5762.423757624204;5762.596618236683;5762.654240745569 16:41:58 INFO - 2015-11-25 16:41:58,440 DEBUG : BROWSER_OUTPUT: |1;ext.html;556.9168015971953;572.3560993881883;586.5981802465315;591.7899145803237;592.4371846745591;30408.087757367288;31307.373852522935;31405.371892562136;31446.213844653867;36473.452898206546;40694.7965260174;41317;41515.79242103793;41608.79195604018;41615;8672.43629164104;8824.338174636896;8849.60176792046;8852.380333376666;8860.20258176764;7701.114371847253;7849.6075196240245;7858.332041776427;7863.174366691501;8456.746297611082;7652.31129198372;10628.468576571178;10818.134549236041;10844.457777111153;10851.294665846734;10348.5343159558;10359.22305827066;10431.269811113225;10442.2168337375;10475.004874536944;7743.728969486041;7943.324817390529;10189;10329.276950613461;10345.068943795019;38415.231695366216;39481.60518394808;39632.20735585301;39637.40543891844;39643.60356396428;3429.9657003430148;3496.895093147184;3726.366517691999;3998.5801490843623;4025.75845449274;28091.57862632062;28898.566521502195;29522.704772952424;29818.10545683633;29985;3809.29528037314;3820.9808950955066;3850.152966347399;3888.0668639526593;3985.1830374773103;253582;254698.99999999814;256075;256500.71749641318;257713;55004.72497637486;55306.17040744392;55414.16878746822;55548.72225638846;55842.72078639581;58180.709096454244;58289;58391.70804145952;58398.4160158393;58485.122723159184;15918.124503152438;16262.105584192746;16291;16300.184990750415;16306.836931630532;12234.510619575296;12410.627681169579;12411.875881241253;12422.130450868352;12425.813612795815;17988.460346189637;18561;18791;18801.05994700287;18832.90583547101;6908.930910690829;7032.8945065824055;7066.081409416743;7110.466714996396;7121.216666166718;880.6521424037597;1005.3364779245662;1135.0465608888574;1140.6463996161217;1148.8793676663997;27643.170704878885;27832.86083569569;27876.44247115046;27884.442311154067;28120.29699257494;947.516766449116;1001.7095042437758;1015.786684796201;1055.7518983038979;1064.41989115932;13098.083134180706;13129.934350328187;13173.736525269442;13288.800667989795;13368;260003;262464;263590;264371;265356;45775;45792.54207457883;45803.541964580596;45837;45851.31223031657;1344.6571124363224;1353.262471952788;1366.8838148757268;1370.6641872741243;1383.4535358533324;25945.22164335073;26578.335541611614;26818;26942.59586106249;26993.595096073183;42907;43138.56861431345;43254.567454326316;43367.78316108525;43456.34815477707;303345;305430;305881;306161;307214;26921.596176057767;27489.450210995266;30311.090667280016;30653;31144.844275779382;36945.81527092455;37187;38101.809490952364;38292.808535958255;40553;33877.66122338844;34565;34652;35116;35345.29309413746;31540.36919261648;34338.82830585831;34836;36091.27817443689;36489.27021459503;33370.666293336755;33648.32703345968;35313.64686353104;36051;36102.8194859024;28732.569011465264;29108;30186.094417167522;30699;30700.84649576738;22953.655695164918;23275.650865237374;24515.182221600833;24519.141830035864;24801.50396992086 16:41:58 INFO - 2015-11-25 16:41:58,440 DEBUG : BROWSER_OUTPUT: |2;jquery.html;354.26490033181153;355.40292308921016;355.70476504501266;356.5240404060579;361.3189138473976;14748.705025899475;15010.220677101199;16524;16612.501624951256;17230.138493075327;16346.10096444695;17375.652486950286;17610.735838962424;17614.295428182857;17718.91140544299;5317.867053323659;5438.3473983121885;5448.591355648323;5452.454754524536;5456.91814622781;4031.3549832026883;4128.587141285862;5352;5384.434634363394;5891.88216235675;5973.044312909935;5992.940070599304;6012.909806352908;6026.246719160105;6035.637861728288;5888.558358123137;5974;6028.367021462727;6029.728662210211;6034.577579569432;5299.417064122943;5324.866878328034;5325;5325.668582854287;5328.80101977056;20081.899590501955;20117.680582703837;20478;20496.077676504592;20551.588968220552;2112.6197284488785;2258.1870526610405;2468.6543883856275;2531.9112781503945;2703.6890757562937;13009.219446833125;13152.6711832204;13259.801102983463;13260.867391326059;13665.795013074812;1795.0367234596351;1854.6105317883262;1881.5014021284371;1897.8328328078271;1904.2192700992664;57550.42449575492;59757.40242597562;60173.6991315045;60537.39462605362;61316.38683613151;9676.467794271382;9710.223182145412;9968.302218844756;9991.400515969066;10007.349522281174;12998;13002.479900804052;13054.412551435209;13082.476700931857;13098.148620339647;7777.3778097753175;7803.531788092732;7810.375169986388;7859.371250300077;7924.811278308184;7694.769156925301;7701.1143718473095;7740.187280335484;7769.572673503011;7776.183500732454;5937.465628093492;6002;6023.337432882379;6027.427394397525;6040.788572399945;1681.1090122235235;1711.1957380031365;1723.8189990051114;1724.6981778188767;1789.1322708486373;932.7248461703814;963.2342287881012;967.1778987860263;1015.2487159501916;1049.8372752223418;17179;17212.053337066332;17247.137643117792;17277.13614319279;17407;945.933784635069;967.2213867836318;973.1290495006929;987.0721521769514;1006.7382480554962;8025.879611805827;10031.097201251776;10045.547950342252;10136.2904596679;10175.287729858986;534947;540032;543757;546935;548324;70086.9486957696;70115.64942175256;70156.64921675359;70563;71208.64395677988;1085.0885256384518;1091.879893211746;1097.3251450358132;1111.6387174168267;1125.3585456289836;14987.925060374628;15203.239838007834;15384.69230615359;15390.923045384701;15418.383264669288;33458.832705836314;33531.32937341288;33706.325873482885;33768.324633507684;33952;43569.56430435782;43641.3453798187;44157.46740260836;44302.33546496741;45004.32493512666;9607.807843843224;9633.759156021155;9825.4104753715;9846.261530385393;9846.852297215692;17313.56716082108;17613.471595852145;17835.108244587977;18149.818501814814;18228.17973191183;11254.83117753218;14206.289685515889;14212.573622791333;14414.198225221848;14467.782983255049;14509.274536273355;14627.780583291473;14810.62973425673;15085.24573771329;15225.619359515658;12262.632121036384;14518.564443066725;14744.336504857092;15144.091354518765;15546.455874044354;9053.275737940818;9451.149396554343;9452.480113593543;9455.952720236355;9502.524873756147;13607.591772246848;14264.072835265675;14688.412463501767;14879.553613391616;15046.473373431878 16:41:58 INFO - 2015-11-25 16:41:58,443 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1535.401193534521;1703.344212478196;1734.340950438833;1775.3608700867694;1783.6789377911975;6899.689513971871;7261.38278246349;7429.554226746399;7434.814129646761;7447.88828167578;7584.924150758491;7808.7266945656775;7811.804704882381;7822.726204582841;7833.060032796073;2026.0072564443465;2064.370367038053;2066.5866826634733;2280.703508543891;2317.918872839451;2305.14709557464;2360.7403185649564;2361.8346715729904;2365.562370961369;2366.7041619797524;2275.374272075176;2276.1009401286483;2330.8135349172026;2332.568474832153;2333.0201315447553;2013.2953466286756;2053.6816793396897;2054.517188460718;2059.2895451069357;2251.549690061986;2276.203328834911;2336.626139817621;2341.157183413987;2341.4497593065616;2345.4019225097663;7625.428092893055;7751.186125456802;7769.494982826098;7771.417143714188;7777.144514103444;4559.703619264737;4602.585767280927;4606.493285738566;4607.308903664443;4625.306204069383;3093.876244950199;3188.3145123798495;3264.5756051713124;3266.673332666738;3289.605247370307;1007.7984403119406;1015.7155996321042;1024.8923862994352;1034.8189066913335;1054.5518154784263;10631.94684026575;10635.468226588717;10642.946785266102;10643.893561064368;10645.20160987929;4211.705180637326;4247.851325203603;4258.957410425918;4259.2759230930815;4436.245838207513;9109.316801239933;9131.178193962574;9137.5888085035;9146.359754817233;9148.496832674135;4678.1813182692895;4704.929426058612;4708.552687494683;4709.175894218498;4710;4241.1941731071;4242.61816436516;4244.214820258262;4252.87241382759;4255.3829694694095;3763.802687006135;3791.6777073949024;3799.772013679188;3804.9048773780323;3824.9235015299537;1177.8292147638545;1182.8048372018693;1188.5602327139013;1230.2618428942562;1231.6366671831831;1303.6545315491403;1315.625046861659;1335.9599212023654;1336.6190635668981;1338.2706424998205;6880.139982502187;6938.444924406035;6952.339527744856;6997.510174287852;7022.894656580155;485.36902027364283;494.6117297921115;495.2719502331567;497.8630876508989;500.6395395315367;5467.043267428184;5545.72271386429;5701.743421546041;5788.392218816964;5929.199558059607;8733.214010739064;8916.197542221102;9056.864147037799;9058.818823623491;9100;9011.774705632411;9017.278617710568;9060.81878362429;9065.864012039825;9080.500572468445;7011.053507776589;7048.647567621599;7106.111736032996;7117.074780278531;7171.103612048494;7157.856842863218;7219.314165154302;7242.529235599669;7250.564966102051;7251.419886408969;8131.390145738975;8231.09457959612;8335.541545215072;8338.499690018376;8363.20549547792;8343.249107580346;8575.185357391038;8618.956905215684;8695.391322607356;8723.65105395777;6897.48268879826;6923.273056329214;6969.7909062728195;6984.3364880336285;6988.231294557696;7517.398608111229;7580;7587.810304742426;7604.771856844082;7612.1246056704185;7034.964825175842;7054.647267636495;7123.679434425359;7156.964215178891;7245.529040612343;7016.859662806613;7025.156981162293;7050.858982820418;7067.25793791665;7177.031100801325;7023;7092.6099064551945;7095.077639906779;7117.644117793986;7130.750423735144;6458.418742313214;6516.869662606627;6524.347565243438;6525.510586706111;6526.347365263436;6484.351564843478;6660.234073081658;6696.7656132035145;6698.296678848846;6701.564398314094 16:41:58 INFO - 2015-11-25 16:41:58,444 DEBUG : BROWSER_OUTPUT: |4;prototype.html;319.4664909600966;320.52242159182816;322.9095853161115;329.11960505647386;334.29463831315917;5819.272590926134;5865.85335366615;5943.702814859256;5982.222311099562;5991.670458124801;5748.827535173941;5885.764569417229;5910.083936989763;5910.852228694284;5921.940780592204;4613.999999999992;4621.168189725848;4622.514635963226;4626.259798432252;4628.791704373294;4411.536788637179;4499.75251361175;4500.032493014001;4500.369948207253;4626.8380606678775;4374.256376416017;4436.268015777393;4454.109178164364;4461.24158892989;4467.061916997434;4416.823327066945;4433.933490997638;4438.733675979419;4443.488998765151;4451.109778044388;3852.7863722927186;3866.7873266970305;3867.9613203867884;3874.302625527404;3882.49527561418;5443.401225865191;5575.637583557056;5575.721213939287;5603.439656034364;5620.5503559715535;5208.5572726318305;5223.399309079467;5226.81706140287;5236.266922630834;5237.790488380499;6173.722182501798;6176.7838125665385;6178.876422471525;6181.938180618181;6182.783602573933;4357.36818161368;4371.16947779924;4373.737575745466;4377.846775362847;4387.912241755147;6215.34738852423;6295.2373335466145;6436.098946147543;6549.901751473732;6554.14796076507;4400.185965596311;4400.9559904401185;4407.625351845129;4477.350784136347;4563.361129441881;4583.220852455091;4601.148787474328;4610.608098311681;4610.884727881829;4617.7691115444095;5299.894002120013;5319.6808191508635;5322.787088516416;5329.09405401075;5334.653247538975;6182.876342473215;6332.4934005279465;6333.556651034475;6339.049142628551;6350.396712312323;1650.8596769274507;1666.4000959654516;1666.8666506679438;1667.8749093818012;1668.799744030724;1097.7255686078481;1129.615930583589;1136.7442325476702;1137.4028634966708;1153.2734377342388;6170.352113028157;6194.907076393858;6197.163382943335;6197.504199664107;6208.255009398901;6553.901691474632;6567.2447668518735;6567.408933196035;6567.638779867058;6568.244651865097;6070.666113363719;6155.969220153871;6182.350853160443;6196.845078872974;6203.875922481525;3534.6111927687934;3559.6974257187912;3564.8930532084078;3565.2156525564333;3565.6969157621375;6730.293319201512;6736.292689267654;6738.730450782012;6740.460763138937;6744.190697116279;6551.508636852255;6607.504437167232;6670.966645166744;6674.365935236145;6679;6721.1262535871;6733.663316834237;6735.057092007123;6744.224414192333;6750.932490675031;6519;6532.771353002622;6535.379138981789;6538.1827271591055;6541.149650545399;6335.461485773761;6447.516436267392;6459.354064593503;6459.870802584016;6460.967695161494;6519;6639.800805975829;6712.92593185088;6716.429103526059;6735.090762746966;4518.231900576844;4538.773061346853;4547.295169248771;4550.931736024028;4551.36280920678;6520.902186467112;6529.347065293433;6529.542931994714;6534.222955243908;6538.248101468392;6267.780627678009;6400.167978162809;6410.326915673787;6413.903791443226;6429.807105786834;6379.266384365672;6393.520485963479;6399.264084630141;6400.167978162809;6401.135846660642;6282.560220784501;6287.339829318039;6288.654124023223;6293.622382657055;6295.3389894060465;6254;6377.617342959437;6411.551191416743;6435.646039467687;6451.903221451588;6233.781817636361;6265.530085243534;6266.467350275278;6268.028455589391;6268.153799237045 16:41:58 INFO - 2015-11-25 16:41:58,444 DEBUG : BROWSER_OUTPUT: |5;yui.html;840.9117042710513;842.9367797415191;847.1147650705013;858.652245840434;860.6170254236863;13740.793888091686;13928.51250206243;14069.155850648955;14152.858471415282;14169.220692861902;13297.13568618039;13297.401616927238;13349.799753003688;13397.732045359087;13472.191668499896;679.0730652659109;688.0573614148624;824.3199360527559;837.9371547133959;839.3369238301734;826.8222332198607;839.2866063845713;839.412411312081;846.4709556527171;847.0851480401152;870.6822009966348;881.0220655072864;881.0616693221731;885.8848349714528;887.8845750052517;709.39701253934;720.1789959446236;866.9956650216772;870.5212133326677;891.8350105230553;628.1112226199922;633.3255083336232;633.6704913445029;638.581728967527;639.2967735490969;13808.447662093504;13925.930370348186;13968.371423285977;13969.930150349284;14011.579652610337;12353.752924941451;12818.230906145662;12897.613071607773;13247.536336228282;13528.169189095175;10278.897211027868;10337.431441270726;10464.476776161162;10596.735081622945;10622.203334749925;3572.231970126409;3646.3721913379895;3711.9443208351895;3716.6655001049767;3766.3408903441787;46322.30516542255;46672;46936.530634693896;46937.53062469366;47135.29297060547;10385.63650272237;10390.168786497063;10414.427206503715;10505.107065899332;10573.101286390744;18837.90581047086;18851.24595016184;18867.433976980712;18919.905400473184;19559.31542396009;7667.233276672288;7841.019872515935;7881.14793023639;7900.683972641145;7922.960385198037;4479.372887795711;4505.211587972158;4508.571685689854;4548.226801443762;4571.748553829571;3174.1588479052607;3209.4864821628425;3215.887443939451;3223;3225.3388055448577;583.7023118209744;584.6988800767608;586.0623003194939;586.3140126052529;592.7628948420667;657.4641667041346;659.6194503171247;676.3980057748607;681.3118750062473;717.4511498703496;8223.917760822435;8269.8346033079;8370.288525475282;8426.199511046563;8444.915550844536;538.222268821549;637.4963778614842;642.9292777794439;678.135377393817;693.4556373246977;4090.897727556835;4196.139791342767;4255.085266592394;4257.978710106429;4265.53079161292;56675;56889.14666280009;57505.42494575084;57695.42304576984;57699.13451298234;52302.738486307324;53873.461265387625;53964;54038.45961540413;54223.72888135534;46947;46958.76520617375;47192.528074718815;47726.37504937192;47908.28137577939;8043.718469853527;8060.234277743605;8176.46852954556;8231.711890083818;8248.711295104642;35111.82444087763;35301.64698353086;35317;35423.64576354204;36047.639523604426;42284.57715422929;44995.55004450044;47017.52982470268;47228.29157562708;47840.7607961958;9852.950735246279;10161.949190254001;10309.74225644335;10355.534000970125;10756.677299681021;11113.388763618079;11383.487743051748;11567.826482602935;11601.12991525622;11816.113791465847;10729;10810.29733067348;11297.096232301561;11387.715307117389;11451.942740286246;10836;10848.294860834021;10942.945285273841;10955.83566246523;11361.622036109278;9515.666951656658;9775.266854986046;10534.104601108991;10841;10848.945755271174;8623.982809733183;8724.43291186071;8740.78148046304;9021.729348119567;9316.627334906529;8483.321334293365;9143;9185.862212066691;9202.49386283734;9445.669401571187 16:41:58 INFO - 2015-11-25 16:41:58,444 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:41:58 INFO - 2015-11-25 16:41:58,444 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:41:58 INFO - 2015-11-25 16:41:58,444 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,930 16:41:58 INFO - 2015-11-25 16:41:58,444 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:41:58 INFO - 2015-11-25 16:41:58,444 DEBUG : BROWSER_OUTPUT: __startTimestamp1448498518434__endTimestamp 16:41:58 INFO - 2015-11-25 16:41:58,444 DEBUG : BROWSER_OUTPUT: 16:41:58 INFO - 2015-11-25 16:41:58,444 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:41:58 INFO - 2015-11-25 16:41:58,444 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:41:58 INFO - 2015-11-25 16:41:58,444 DEBUG : BROWSER_OUTPUT: 16:41:58 INFO - 2015-11-25 16:41:58,445 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5522.88 Median:5750.80 stddev:611.52 (10.6%) stddev-sans-first:586.00 16:41:58 INFO - 2015-11-25 16:41:58,445 DEBUG : BROWSER_OUTPUT: Values: 3215.6 3284.4 3326.9 3327.4 3344.6 5342.1 5472.5 5492.0 5508.2 5519.1 5502.1 5555.5 5592.7 5594.4 5623.6 4847.5 5069.9 5079.7 5099.8 5112.1 4934.2 5107.4 5124.2 5165.7 5207.7 4974.8 4992.5 5046.2 5058.5 5089.2 5070.1 5070.6 5077.9 5109.5 5122.9 4864.6 4994.5 5035.6 5048.1 5065.1 5233.9 5331.8 5343.9 5348.0 5358.5 5883.0 5883.6 5890.6 5939.1 5947.8 5819.9 5864.6 5913.3 5935.1 5954.9 5622.4 5624.3 5678.0 5690.7 5700.3 6125.9 6132.7 6140.1 6143.8 6148.2 5693.5 5750.8 5904.1 5906.4 5963.4 5827.2 5851.2 5906.0 5937.7 5978.8 5853.8 5865.3 5871.4 5877.5 5987.8 5798.2 5866.5 5887.7 5900.7 5921.3 4499.2 4515.9 4566.9 4600.4 4603.1 4090.7 4101.0 4107.3 4132.5 4143.4 5548.9 5759.0 5763.3 5765.7 5769.8 5852.3 5960.6 5975.6 6006.7 6022.2 5723.6 5724.3 5725.5 5727.9 5741.4 5163.6 5221.6 5227.3 5233.1 5264.4 5880.1 6016.5 6082.4 6165.8 6179.7 5961.3 5962.6 5964.2 5966.5 6070.2 6152.4 6179.1 6183.7 6191.6 6197.3 5731.7 5794.3 5797.4 5831.6 5854.9 5903.2 5973.0 5993.0 6026.9 6034.1 5839.5 5853.7 5858.9 5880.7 5880.9 5628.0 5699.5 5823.4 5834.8 5847.3 5625.5 5664.8 5687.3 5721.8 5722.2 5847.7 5867.2 5873.9 5881.0 5935.7 5461.9 5647.8 5762.4 5762.6 5762.7 16:41:58 INFO - 2015-11-25 16:41:58,445 DEBUG : BROWSER_OUTPUT: 16:41:58 INFO - 2015-11-25 16:41:58,445 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:43761.13 Median:31226.11 stddev:71900.95 (230.3%) stddev-sans-first:72029.47 16:41:58 INFO - 2015-11-25 16:41:58,446 DEBUG : BROWSER_OUTPUT: Values: 556.9 572.4 586.6 591.8 592.4 30408.1 31307.4 31405.4 31446.2 36473.5 40694.8 41317.0 41515.8 41608.8 41615.0 8672.4 8824.3 8849.6 8852.4 8860.2 7701.1 7849.6 7858.3 7863.2 8456.7 7652.3 10628.5 10818.1 10844.5 10851.3 10348.5 10359.2 10431.3 10442.2 10475.0 7743.7 7943.3 10189.0 10329.3 10345.1 38415.2 39481.6 39632.2 39637.4 39643.6 3430.0 3496.9 3726.4 3998.6 4025.8 28091.6 28898.6 29522.7 29818.1 29985.0 3809.3 3821.0 3850.2 3888.1 3985.2 253582.0 254699.0 256075.0 256500.7 257713.0 55004.7 55306.2 55414.2 55548.7 55842.7 58180.7 58289.0 58391.7 58398.4 58485.1 15918.1 16262.1 16291.0 16300.2 16306.8 12234.5 12410.6 12411.9 12422.1 12425.8 17988.5 18561.0 18791.0 18801.1 18832.9 6908.9 7032.9 7066.1 7110.5 7121.2 880.7 1005.3 1135.0 1140.6 1148.9 27643.2 27832.9 27876.4 27884.4 28120.3 947.5 1001.7 1015.8 1055.8 1064.4 13098.1 13129.9 13173.7 13288.8 13368.0 260003.0 262464.0 263590.0 264371.0 265356.0 45775.0 45792.5 45803.5 45837.0 45851.3 1344.7 1353.3 1366.9 1370.7 1383.5 25945.2 26578.3 26818.0 26942.6 26993.6 42907.0 43138.6 43254.6 43367.8 43456.3 303345.0 305430.0 305881.0 306161.0 307214.0 26921.6 27489.5 30311.1 30653.0 31144.8 36945.8 37187.0 38101.8 38292.8 40553.0 33877.7 34565.0 34652.0 35116.0 35345.3 31540.4 34338.8 34836.0 36091.3 36489.3 33370.7 33648.3 35313.6 36051.0 36102.8 28732.6 29108.0 30186.1 30699.0 30700.8 22953.7 23275.7 24515.2 24519.1 24801.5 16:41:58 INFO - 2015-11-25 16:41:58,446 DEBUG : BROWSER_OUTPUT: 16:41:58 INFO - 2015-11-25 16:41:58,446 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28783.29 Median:33737.33 stddev:88462.84 (262.2%) stddev-sans-first:88685.25 16:41:58 INFO - 2015-11-25 16:41:58,446 DEBUG : BROWSER_OUTPUT: Values: 354.3 355.4 355.7 356.5 361.3 14748.7 15010.2 16524.0 16612.5 17230.1 16346.1 17375.7 17610.7 17614.3 17718.9 5317.9 5438.3 5448.6 5452.5 5456.9 4031.4 4128.6 5352.0 5384.4 5891.9 5973.0 5992.9 6012.9 6026.2 6035.6 5888.6 5974.0 6028.4 6029.7 6034.6 5299.4 5324.9 5325.0 5325.7 5328.8 20081.9 20117.7 20478.0 20496.1 20551.6 2112.6 2258.2 2468.7 2531.9 2703.7 13009.2 13152.7 13259.8 13260.9 13665.8 1795.0 1854.6 1881.5 1897.8 1904.2 57550.4 59757.4 60173.7 60537.4 61316.4 9676.5 9710.2 9968.3 9991.4 10007.3 12998.0 13002.5 13054.4 13082.5 13098.1 7777.4 7803.5 7810.4 7859.4 7924.8 7694.8 7701.1 7740.2 7769.6 7776.2 5937.5 6002.0 6023.3 6027.4 6040.8 1681.1 1711.2 1723.8 1724.7 1789.1 932.7 963.2 967.2 1015.2 1049.8 17179.0 17212.1 17247.1 17277.1 17407.0 945.9 967.2 973.1 987.1 1006.7 8025.9 10031.1 10045.5 10136.3 10175.3 534947.0 540032.0 543757.0 546935.0 548324.0 70086.9 70115.6 70156.6 70563.0 71208.6 1085.1 1091.9 1097.3 1111.6 1125.4 14987.9 15203.2 15384.7 15390.9 15418.4 33458.8 33531.3 33706.3 33768.3 33952.0 43569.6 43641.3 44157.5 44302.3 45004.3 9607.8 9633.8 9825.4 9846.3 9846.9 17313.6 17613.5 17835.1 18149.8 18228.2 11254.8 14206.3 14212.6 14414.2 14467.8 14509.3 14627.8 14810.6 15085.2 15225.6 12262.6 14518.6 14744.3 15144.1 15546.5 9053.3 9451.1 9452.5 9456.0 9502.5 13607.6 14264.1 14688.4 14879.6 15046.5 16:41:58 INFO - 2015-11-25 16:41:58,446 DEBUG : BROWSER_OUTPUT: 16:41:58 INFO - 2015-11-25 16:41:58,446 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5423.34 Median:6471.39 stddev:2796.45 (43.2%) stddev-sans-first:2789.03 16:41:58 INFO - 2015-11-25 16:41:58,448 DEBUG : BROWSER_OUTPUT: Values: 1535.4 1703.3 1734.3 1775.4 1783.7 6899.7 7261.4 7429.6 7434.8 7447.9 7584.9 7808.7 7811.8 7822.7 7833.1 2026.0 2064.4 2066.6 2280.7 2317.9 2305.1 2360.7 2361.8 2365.6 2366.7 2275.4 2276.1 2330.8 2332.6 2333.0 2013.3 2053.7 2054.5 2059.3 2251.5 2276.2 2336.6 2341.2 2341.4 2345.4 7625.4 7751.2 7769.5 7771.4 7777.1 4559.7 4602.6 4606.5 4607.3 4625.3 3093.9 3188.3 3264.6 3266.7 3289.6 1007.8 1015.7 1024.9 1034.8 1054.6 10631.9 10635.5 10642.9 10643.9 10645.2 4211.7 4247.9 4259.0 4259.3 4436.2 9109.3 9131.2 9137.6 9146.4 9148.5 4678.2 4704.9 4708.6 4709.2 4710.0 4241.2 4242.6 4244.2 4252.9 4255.4 3763.8 3791.7 3799.8 3804.9 3824.9 1177.8 1182.8 1188.6 1230.3 1231.6 1303.7 1315.6 1336.0 1336.6 1338.3 6880.1 6938.4 6952.3 6997.5 7022.9 485.4 494.6 495.3 497.9 500.6 5467.0 5545.7 5701.7 5788.4 5929.2 8733.2 8916.2 9056.9 9058.8 9100.0 9011.8 9017.3 9060.8 9065.9 9080.5 7011.1 7048.6 7106.1 7117.1 7171.1 7157.9 7219.3 7242.5 7250.6 7251.4 8131.4 8231.1 8335.5 8338.5 8363.2 8343.2 8575.2 8619.0 8695.4 8723.7 6897.5 6923.3 6969.8 6984.3 6988.2 7517.4 7580.0 7587.8 7604.8 7612.1 7035.0 7054.6 7123.7 7157.0 7245.5 7016.9 7025.2 7050.9 7067.3 7177.0 7023.0 7092.6 7095.1 7117.6 7130.8 6458.4 6516.9 6524.3 6525.5 6526.3 6484.4 6660.2 6696.8 6698.3 6701.6 16:41:58 INFO - 2015-11-25 16:41:58,448 DEBUG : BROWSER_OUTPUT: 16:41:58 INFO - 2015-11-25 16:41:58,448 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5284.30 Median:6113.32 stddev:1587.43 (26.0%) stddev-sans-first:1547.52 16:41:58 INFO - 2015-11-25 16:41:58,448 DEBUG : BROWSER_OUTPUT: Values: 319.5 320.5 322.9 329.1 334.3 5819.3 5865.9 5943.7 5982.2 5991.7 5748.8 5885.8 5910.1 5910.9 5921.9 4614.0 4621.2 4622.5 4626.3 4628.8 4411.5 4499.8 4500.0 4500.4 4626.8 4374.3 4436.3 4454.1 4461.2 4467.1 4416.8 4433.9 4438.7 4443.5 4451.1 3852.8 3866.8 3868.0 3874.3 3882.5 5443.4 5575.6 5575.7 5603.4 5620.6 5208.6 5223.4 5226.8 5236.3 5237.8 6173.7 6176.8 6178.9 6181.9 6182.8 4357.4 4371.2 4373.7 4377.8 4387.9 6215.3 6295.2 6436.1 6549.9 6554.1 4400.2 4401.0 4407.6 4477.4 4563.4 4583.2 4601.1 4610.6 4610.9 4617.8 5299.9 5319.7 5322.8 5329.1 5334.7 6182.9 6332.5 6333.6 6339.0 6350.4 1650.9 1666.4 1666.9 1667.9 1668.8 1097.7 1129.6 1136.7 1137.4 1153.3 6170.4 6194.9 6197.2 6197.5 6208.3 6553.9 6567.2 6567.4 6567.6 6568.2 6070.7 6156.0 6182.4 6196.8 6203.9 3534.6 3559.7 3564.9 3565.2 3565.7 6730.3 6736.3 6738.7 6740.5 6744.2 6551.5 6607.5 6671.0 6674.4 6679.0 6721.1 6733.7 6735.1 6744.2 6750.9 6519.0 6532.8 6535.4 6538.2 6541.1 6335.5 6447.5 6459.4 6459.9 6461.0 6519.0 6639.8 6712.9 6716.4 6735.1 4518.2 4538.8 4547.3 4550.9 4551.4 6520.9 6529.3 6529.5 6534.2 6538.2 6267.8 6400.2 6410.3 6413.9 6429.8 6379.3 6393.5 6399.3 6400.2 6401.1 6282.6 6287.3 6288.7 6293.6 6295.3 6254.0 6377.6 6411.6 6435.6 6451.9 6233.8 6265.5 6266.5 6268.0 6268.2 16:41:58 INFO - 2015-11-25 16:41:58,448 DEBUG : BROWSER_OUTPUT: 16:41:58 INFO - 2015-11-25 16:41:58,448 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:13874.69 Median:47076.41 stddev:16173.84 (34.4%) stddev-sans-first:16189.60 16:41:58 INFO - 2015-11-25 16:41:58,448 DEBUG : BROWSER_OUTPUT: Values: 840.9 842.9 847.1 858.7 860.6 13740.8 13928.5 14069.2 14152.9 14169.2 13297.1 13297.4 13349.8 13397.7 13472.2 679.1 688.1 824.3 837.9 839.3 826.8 839.3 839.4 846.5 847.1 870.7 881.0 881.1 885.9 887.9 709.4 720.2 867.0 870.5 891.8 628.1 633.3 633.7 638.6 639.3 13808.4 13925.9 13968.4 13969.9 14011.6 12353.8 12818.2 12897.6 13247.5 13528.2 10278.9 10337.4 10464.5 10596.7 10622.2 3572.2 3646.4 3711.9 3716.7 3766.3 46322.3 46672.0 46936.5 46937.5 47135.3 10385.6 10390.2 10414.4 10505.1 10573.1 18837.9 18851.2 18867.4 18919.9 19559.3 7667.2 7841.0 7881.1 7900.7 7923.0 4479.4 4505.2 4508.6 4548.2 4571.7 3174.2 3209.5 3215.9 3223.0 3225.3 583.7 584.7 586.1 586.3 592.8 657.5 659.6 676.4 681.3 717.5 8223.9 8269.8 8370.3 8426.2 8444.9 538.2 637.5 642.9 678.1 693.5 4090.9 4196.1 4255.1 4258.0 4265.5 56675.0 56889.1 57505.4 57695.4 57699.1 52302.7 53873.5 53964.0 54038.5 54223.7 46947.0 46958.8 47192.5 47726.4 47908.3 8043.7 8060.2 8176.5 8231.7 8248.7 35111.8 35301.6 35317.0 35423.6 36047.6 42284.6 44995.6 47017.5 47228.3 47840.8 9853.0 10161.9 10309.7 10355.5 10756.7 11113.4 11383.5 11567.8 11601.1 11816.1 10729.0 10810.3 11297.1 11387.7 11451.9 10836.0 10848.3 10942.9 10955.8 11361.6 9515.7 9775.3 10534.1 10841.0 10848.9 8624.0 8724.4 8740.8 9021.7 9316.6 8483.3 9143.0 9185.9 9202.5 9445.7 16:41:58 INFO - 2015-11-25 16:41:58,449 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:41:58 INFO - 2015-11-25 16:41:58,449 DEBUG : BROWSER_OUTPUT: 16:41:59 INFO - 2015-11-25 16:41:59,130 INFO : Browser exited with error code: 0 16:41:59 INFO - 2015-11-25 16:41:59,146 INFO : Completed test dromaeo_css (00:18:32) 16:41:59 INFO - 2015-11-25 16:41:59,147 INFO : Starting test kraken 16:41:59 INFO - 2015-11-25 16:41:59,147 DEBUG : operating with platform_type : linux_ 16:41:59 INFO - 2015-11-25 16:41:59,147 INFO : Initialising browser for kraken test... 16:41:59 INFO - 2015-11-25 16:41:59,153 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpc6EwPS/profile http://localhost:57651/getInfo.html 16:42:02 INFO - 2015-11-25 16:42:02,506 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:42:02 INFO - 2015-11-25 16:42:02,506 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:42:02 INFO - 2015-11-25 16:42:02,506 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:42:02 INFO - 2015-11-25 16:42:02,506 DEBUG : BROWSER_OUTPUT: __metrics 16:42:02 INFO - 2015-11-25 16:42:02,607 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:42:03 INFO - 2015-11-25 16:42:03,622 INFO : Browser initialized. 16:42:03 INFO - 2015-11-25 16:42:03,623 INFO : Running cycle 1/1 for kraken test... 16:42:03 INFO - 2015-11-25 16:42:03,623 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpc6EwPS/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:42:15 INFO - 2015-11-25 16:42:15,357 DEBUG : BROWSER_OUTPUT: RSS: Main: 139268096 16:42:15 INFO - 2015-11-25 16:42:15,357 DEBUG : BROWSER_OUTPUT: 16:42:17 INFO - 2015-11-25 16:42:17,795 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57651/tests/kraken/driver.html?testName=audio-beat-detection) 16:42:18 INFO - 2015-11-25 16:42:18,382 DEBUG : BROWSER_OUTPUT: RSS: Main: 177459200 16:42:18 INFO - 2015-11-25 16:42:18,382 DEBUG : BROWSER_OUTPUT: 16:42:20 INFO - 2015-11-25 16:42:20,963 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57651/tests/kraken/driver.html?testName=audio-dft) 16:42:21 INFO - 2015-11-25 16:42:21,532 DEBUG : BROWSER_OUTPUT: RSS: Main: 165654528 16:42:21 INFO - 2015-11-25 16:42:21,532 DEBUG : BROWSER_OUTPUT: 16:42:24 INFO - 2015-11-25 16:42:24,390 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57651/tests/kraken/driver.html?testName=audio-fft) 16:42:24 INFO - 2015-11-25 16:42:24,939 DEBUG : BROWSER_OUTPUT: RSS: Main: 149901312 16:42:24 INFO - 2015-11-25 16:42:24,939 DEBUG : BROWSER_OUTPUT: 16:42:26 INFO - 2015-11-25 16:42:26,902 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57651/tests/kraken/driver.html?testName=audio-oscillator) 16:42:27 INFO - 2015-11-25 16:42:27,467 DEBUG : BROWSER_OUTPUT: RSS: Main: 152657920 16:42:27 INFO - 2015-11-25 16:42:27,467 DEBUG : BROWSER_OUTPUT: 16:42:29 INFO - 2015-11-25 16:42:29,641 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57651/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:42:30 INFO - 2015-11-25 16:42:30,194 DEBUG : BROWSER_OUTPUT: RSS: Main: 154537984 16:42:30 INFO - 2015-11-25 16:42:30,194 DEBUG : BROWSER_OUTPUT: 16:42:33 INFO - 2015-11-25 16:42:33,092 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57651/tests/kraken/driver.html?testName=imaging-darkroom) 16:42:33 INFO - 2015-11-25 16:42:33,658 DEBUG : BROWSER_OUTPUT: RSS: Main: 155525120 16:42:33 INFO - 2015-11-25 16:42:33,659 DEBUG : BROWSER_OUTPUT: 16:42:37 INFO - 2015-11-25 16:42:37,047 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57651/tests/kraken/driver.html?testName=imaging-desaturate) 16:42:37 INFO - 2015-11-25 16:42:37,620 DEBUG : BROWSER_OUTPUT: RSS: Main: 156700672 16:42:37 INFO - 2015-11-25 16:42:37,620 DEBUG : BROWSER_OUTPUT: 16:42:40 INFO - 2015-11-25 16:42:40,357 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57651/tests/kraken/driver.html?testName=json-parse-financial) 16:42:40 INFO - 2015-11-25 16:42:40,938 DEBUG : BROWSER_OUTPUT: RSS: Main: 156708864 16:42:40 INFO - 2015-11-25 16:42:40,939 DEBUG : BROWSER_OUTPUT: 16:42:42 INFO - 2015-11-25 16:42:42,490 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57651/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:42:43 INFO - 2015-11-25 16:42:43,052 DEBUG : BROWSER_OUTPUT: RSS: Main: 169529344 16:42:43 INFO - 2015-11-25 16:42:43,052 DEBUG : BROWSER_OUTPUT: 16:42:44 INFO - 2015-11-25 16:42:44,862 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57651/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:42:45 INFO - 2015-11-25 16:42:45,455 DEBUG : BROWSER_OUTPUT: RSS: Main: 169254912 16:42:45 INFO - 2015-11-25 16:42:45,455 DEBUG : BROWSER_OUTPUT: 16:42:47 INFO - 2015-11-25 16:42:47,519 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57651/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:42:48 INFO - 2015-11-25 16:42:48,092 DEBUG : BROWSER_OUTPUT: RSS: Main: 177102848 16:42:48 INFO - 2015-11-25 16:42:48,092 DEBUG : BROWSER_OUTPUT: 16:42:50 INFO - 2015-11-25 16:42:50,498 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57651/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:42:51 INFO - 2015-11-25 16:42:51,083 DEBUG : BROWSER_OUTPUT: RSS: Main: 185036800 16:42:51 INFO - 2015-11-25 16:42:51,084 DEBUG : BROWSER_OUTPUT: 16:42:53 INFO - 2015-11-25 16:42:53,618 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57651/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:42:54 INFO - 2015-11-25 16:42:54,183 DEBUG : BROWSER_OUTPUT: RSS: Main: 181579776 16:42:54 INFO - 2015-11-25 16:42:54,184 DEBUG : BROWSER_OUTPUT: 16:42:55 INFO - 2015-11-25 16:42:55,832 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57651/tests/kraken/driver.html?testName=ai-astar) 16:42:56 INFO - 2015-11-25 16:42:56,082 DEBUG : BROWSER_OUTPUT: RSS: Main: 240660480 16:42:56 INFO - 2015-11-25 16:42:56,083 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,084 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:42:56 INFO - 2015-11-25 16:42:56,084 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:42:56 INFO - 2015-11-25 16:42:56,084 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:42:56 INFO - 2015-11-25 16:42:56,084 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:42:56 INFO - 2015-11-25 16:42:56,084 DEBUG : BROWSER_OUTPUT: |0;ai-astar;108;112;104;109;109;109;98;109;107;99 16:42:56 INFO - 2015-11-25 16:42:56,084 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;154;123;155;165;128;146;151;125;163;123 16:42:56 INFO - 2015-11-25 16:42:56,085 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;158;159;161;159;160;162;159;160 16:42:56 INFO - 2015-11-25 16:42:56,085 DEBUG : BROWSER_OUTPUT: |3;audio-fft;71;71;82;93;71;70;72;128;91;70 16:42:56 INFO - 2015-11-25 16:42:56,085 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;93;93;137;93;93;93;140;93;94;141 16:42:56 INFO - 2015-11-25 16:42:56,085 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;109;109;109;108;109;109;110;109;110;109 16:42:56 INFO - 2015-11-25 16:42:56,085 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;145;145;144;144;146;145;145;145;145;147 16:42:56 INFO - 2015-11-25 16:42:56,085 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;95;95;96;96;96;96;96;96;95;96 16:42:56 INFO - 2015-11-25 16:42:56,085 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;59;57;57;59;58;57;57;57;60;56 16:42:56 INFO - 2015-11-25 16:42:56,086 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;69;70;70;70;70;69;69;71;71;71 16:42:56 INFO - 2015-11-25 16:42:56,086 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;83;85;87;84;90;85;83;85;86;85 16:42:56 INFO - 2015-11-25 16:42:56,086 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;128;119;119;115;119;116;122;118;117;132 16:42:56 INFO - 2015-11-25 16:42:56,086 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;141;143;152;153;150;139;148;151;146;138 16:42:56 INFO - 2015-11-25 16:42:56,086 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;66;66;64;65;65;66;64;65;65;65 16:42:56 INFO - 2015-11-25 16:42:56,086 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:42:56 INFO - 2015-11-25 16:42:56,086 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:42:56 INFO - 2015-11-25 16:42:56,087 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,917 16:42:56 INFO - 2015-11-25 16:42:56,087 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:42:56 INFO - 2015-11-25 16:42:56,087 DEBUG : BROWSER_OUTPUT: __startTimestamp1448498576082__endTimestamp 16:42:56 INFO - 2015-11-25 16:42:56,087 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,090 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:42:56 INFO - 2015-11-25 16:42:56,090 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:42:56 INFO - 2015-11-25 16:42:56,090 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:106.40 Median:109.00 stddev:4.62 (4.2%) stddev-sans-first:4.87 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: Values: 108.0 112.0 104.0 109.0 109.0 109.0 98.0 109.0 107.0 99.0 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:143.30 Median:152.50 stddev:16.90 (11.1%) stddev-sans-first:17.47 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: Values: 154.0 123.0 155.0 165.0 128.0 146.0 151.0 125.0 163.0 123.0 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:159.90 Median:160.00 stddev:1.20 (0.7%) stddev-sans-first:1.20 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 158.0 159.0 161.0 159.0 160.0 162.0 159.0 160.0 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:81.90 Median:71.50 stddev:18.47 (25.8%) stddev-sans-first:19.16 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: Values: 71.0 71.0 82.0 93.0 71.0 70.0 72.0 128.0 91.0 70.0 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:107.00 Median:93.00 stddev:22.34 (24.0%) stddev-sans-first:23.11 16:42:56 INFO - 2015-11-25 16:42:56,091 DEBUG : BROWSER_OUTPUT: Values: 93.0 93.0 137.0 93.0 93.0 93.0 140.0 93.0 94.0 141.0 16:42:56 INFO - 2015-11-25 16:42:56,092 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,092 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.10 Median:109.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 16:42:56 INFO - 2015-11-25 16:42:56,092 DEBUG : BROWSER_OUTPUT: Values: 109.0 109.0 109.0 108.0 109.0 109.0 110.0 109.0 110.0 109.0 16:42:56 INFO - 2015-11-25 16:42:56,092 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,092 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.10 Median:145.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 16:42:56 INFO - 2015-11-25 16:42:56,093 DEBUG : BROWSER_OUTPUT: Values: 145.0 145.0 144.0 144.0 146.0 145.0 145.0 145.0 145.0 147.0 16:42:56 INFO - 2015-11-25 16:42:56,093 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,093 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 16:42:56 INFO - 2015-11-25 16:42:56,093 DEBUG : BROWSER_OUTPUT: Values: 95.0 95.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 96.0 16:42:56 INFO - 2015-11-25 16:42:56,093 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,093 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:57.70 Median:57.50 stddev:1.25 (2.2%) stddev-sans-first:1.24 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: Values: 59.0 57.0 57.0 59.0 58.0 57.0 57.0 57.0 60.0 56.0 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.00 stddev:0.82 (1.2%) stddev-sans-first:0.78 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: Values: 69.0 70.0 70.0 70.0 70.0 69.0 69.0 71.0 71.0 71.0 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:85.00 stddev:2.06 (2.4%) stddev-sans-first:2.01 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: Values: 83.0 85.0 87.0 84.0 90.0 85.0 83.0 85.0 86.0 85.0 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:120.50 Median:119.00 stddev:5.44 (4.6%) stddev-sans-first:5.05 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: Values: 128.0 119.0 119.0 115.0 119.0 116.0 122.0 118.0 117.0 132.0 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:146.10 Median:149.00 stddev:5.55 (3.7%) stddev-sans-first:5.57 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: Values: 141.0 143.0 152.0 153.0 150.0 139.0 148.0 151.0 146.0 138.0 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.00 stddev:0.74 (1.1%) stddev-sans-first:0.71 16:42:56 INFO - 2015-11-25 16:42:56,094 DEBUG : BROWSER_OUTPUT: Values: 66.0 66.0 64.0 65.0 65.0 66.0 64.0 65.0 65.0 65.0 16:42:56 INFO - 2015-11-25 16:42:56,095 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:42:56 INFO - 2015-11-25 16:42:56,095 DEBUG : BROWSER_OUTPUT: 16:42:56 INFO - 2015-11-25 16:42:56,878 INFO : Browser exited with error code: 0 16:42:56 INFO - 2015-11-25 16:42:56,931 INFO : Completed test kraken (00:00:57) 16:42:56 INFO - 2015-11-25 16:42:56,931 INFO : Starting test v8_7 16:42:56 INFO - 2015-11-25 16:42:56,931 DEBUG : operating with platform_type : linux_ 16:42:56 INFO - 2015-11-25 16:42:56,932 INFO : Initialising browser for v8_7 test... 16:42:56 INFO - 2015-11-25 16:42:56,938 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaZ4KJG/profile http://localhost:57651/getInfo.html 16:43:00 INFO - 2015-11-25 16:43:00,251 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:43:00 INFO - 2015-11-25 16:43:00,252 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:43:00 INFO - 2015-11-25 16:43:00,252 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:43:00 INFO - 2015-11-25 16:43:00,252 DEBUG : BROWSER_OUTPUT: __metrics 16:43:00 INFO - 2015-11-25 16:43:00,389 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:43:01 INFO - 2015-11-25 16:43:01,357 INFO : Browser initialized. 16:43:01 INFO - 2015-11-25 16:43:01,358 INFO : Running cycle 1/1 for v8_7 test... 16:43:01 INFO - 2015-11-25 16:43:01,358 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpaZ4KJG/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:43:13 INFO - 2015-11-25 16:43:13,035 DEBUG : BROWSER_OUTPUT: RSS: Main: 138891264 16:43:13 INFO - 2015-11-25 16:43:13,036 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,472 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:57651/tests/v8_7/run.html (next: http://localhost:57651/tests/v8_7/run.html) 16:43:34 INFO - 2015-11-25 16:43:34,723 DEBUG : BROWSER_OUTPUT: RSS: Main: 622694400 16:43:34 INFO - 2015-11-25 16:43:34,723 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,723 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:43:34 INFO - 2015-11-25 16:43:34,723 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:43:34 INFO - 2015-11-25 16:43:34,723 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:43:34 INFO - 2015-11-25 16:43:34,724 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:43:34 INFO - 2015-11-25 16:43:34,724 DEBUG : BROWSER_OUTPUT: |0;Richards;164.58196181698486 16:43:34 INFO - 2015-11-25 16:43:34,724 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;194.43904335990666 16:43:34 INFO - 2015-11-25 16:43:34,724 DEBUG : BROWSER_OUTPUT: |2;Encrypt;282.6455624646693 16:43:34 INFO - 2015-11-25 16:43:34,725 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5223.958333333333 16:43:34 INFO - 2015-11-25 16:43:34,725 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1092.896174863388 16:43:34 INFO - 2015-11-25 16:43:34,725 DEBUG : BROWSER_OUTPUT: |5;Earley;776.3975155279503 16:43:34 INFO - 2015-11-25 16:43:34,725 DEBUG : BROWSER_OUTPUT: |6;Boyer;11929.411764705883 16:43:34 INFO - 2015-11-25 16:43:34,726 DEBUG : BROWSER_OUTPUT: |7;RegExp;26076.923076923078 16:43:34 INFO - 2015-11-25 16:43:34,726 DEBUG : BROWSER_OUTPUT: |8;Splay;665.1926721415035 16:43:34 INFO - 2015-11-25 16:43:34,726 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4845.410628019324 16:43:34 INFO - 2015-11-25 16:43:34,727 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:43:34 INFO - 2015-11-25 16:43:34,727 DEBUG : BROWSER_OUTPUT: __startTimestamp1448498614722__endTimestamp 16:43:34 INFO - 2015-11-25 16:43:34,727 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,727 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:43:34 INFO - 2015-11-25 16:43:34,728 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:43:34 INFO - 2015-11-25 16:43:34,728 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,728 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:164.58 Median:164.58 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,728 DEBUG : BROWSER_OUTPUT: Values: 164.6 16:43:34 INFO - 2015-11-25 16:43:34,729 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,729 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:194.44 Median:194.44 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,729 DEBUG : BROWSER_OUTPUT: Values: 194.4 16:43:34 INFO - 2015-11-25 16:43:34,730 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,730 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:282.65 Median:282.65 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,730 DEBUG : BROWSER_OUTPUT: Values: 282.6 16:43:34 INFO - 2015-11-25 16:43:34,730 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,731 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5223.96 Median:5223.96 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,732 DEBUG : BROWSER_OUTPUT: Values: 5224.0 16:43:34 INFO - 2015-11-25 16:43:34,732 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,732 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1092.90 Median:1092.90 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,732 DEBUG : BROWSER_OUTPUT: Values: 1092.9 16:43:34 INFO - 2015-11-25 16:43:34,732 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,732 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:776.40 Median:776.40 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,732 DEBUG : BROWSER_OUTPUT: Values: 776.4 16:43:34 INFO - 2015-11-25 16:43:34,732 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,732 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:11929.41 Median:11929.41 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,732 DEBUG : BROWSER_OUTPUT: Values: 11929.4 16:43:34 INFO - 2015-11-25 16:43:34,732 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,732 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26076.92 Median:26076.92 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,732 DEBUG : BROWSER_OUTPUT: Values: 26076.9 16:43:34 INFO - 2015-11-25 16:43:34,733 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,733 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:665.19 Median:665.19 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,733 DEBUG : BROWSER_OUTPUT: Values: 665.2 16:43:34 INFO - 2015-11-25 16:43:34,733 DEBUG : BROWSER_OUTPUT: 16:43:34 INFO - 2015-11-25 16:43:34,733 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4845.41 Median:4845.41 stddev:0.00 (0.0%) 16:43:34 INFO - 2015-11-25 16:43:34,733 DEBUG : BROWSER_OUTPUT: Values: 4845.4 16:43:34 INFO - 2015-11-25 16:43:34,733 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:43:34 INFO - 2015-11-25 16:43:34,733 DEBUG : BROWSER_OUTPUT: 16:43:35 INFO - 2015-11-25 16:43:35,466 INFO : Browser exited with error code: 0 16:43:35 INFO - 2015-11-25 16:43:35,485 INFO : Completed test v8_7 (00:00:38) 16:43:35 INFO - 2015-11-25 16:43:35,689 INFO : Completed test suite (00:20:08) 16:43:35 INFO - 2015-11-25 16:43:35,689 DEBUG : Working with test: dromaeo_css 16:43:35 INFO - 2015-11-25 16:43:35,689 DEBUG : Generating results file: dromaeo_css 16:43:35 INFO - 2015-11-25 16:43:35,690 DEBUG : Working with test: kraken 16:43:35 INFO - 2015-11-25 16:43:35,691 DEBUG : Generating results file: kraken 16:43:35 INFO - 2015-11-25 16:43:35,691 INFO : javascript benchmark 16:43:35 INFO - 2015-11-25 16:43:35,691 DEBUG : Working with test: v8_7 16:43:35 INFO - 2015-11-25 16:43:35,691 DEBUG : Generating results file: v8_7 16:43:35 INFO - 2015-11-25 16:43:35,692 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:43:35 INFO - 2015-11-25 16:43:35,741 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,64,35]] 16:43:35 INFO - 2015-11-25 16:43:35,741 DEBUG : process_Request line: dromaeo_css 7017.31 graph.html#tests=[[72,64,35]] 16:43:35 INFO - 2015-11-25 16:43:35,742 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:43:35 INFO - 2015-11-25 16:43:35,771 DEBUG : process_Request line: kraken 1493.10 graph.html#tests=[[232,64,35]] 16:43:35 INFO - 2015-11-25 16:43:35,771 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:43:35 INFO - 2015-11-25 16:43:35,802 DEBUG : process_Request line: v8_7 20847.55 graph.html#tests=[[230,64,35]] 16:43:35 INFO - 2015-11-25 16:43:35,803 INFO : javascript benchmark 16:43:35 INFO - 2015-11-25 16:43:35,807 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3215.598050243719, 3284.4252255855217, 3326.8669253229873, 3327.351166522529, 3344.5652065231507, 5342.118550439173, 5472.50747432731, 5491.972540137299, 5508.20131080993, 5519.061759500884, 5502.064649009668, 5555.527780138693, 5592.664440133595, 5594.356648985368, 5623.606347555673, 4847.495067543978, 5069.873253168673, 5079.669821461612, 5099.796008159669, 5112.130937740593, 4934.161192597249, 5107.438181800017, 5124.154514505102, 5165.66423182492, 5207.739613019334, 4974.751262436882, 4992.450830408634, 5046.2178362353525, 5058.519440653132, 5089.160288552384, 5070.08738427083, 5070.61970352225, 5077.923831142536, 5109.540141387256, 5122.897542049175, 4864.562189402971, 4994.450610432849, 5035.62232832539, 5048.091343558158, 5065.062963351792, 5233.921491177597, 5331.760070796823, 5343.946560534384, 5348.0, 5358.4641535846495, 5883.0, 5883.6469811811, 5890.587658863881, 5939.079442686348, 5947.762089516415, 5819.88360232793, 5864.618799778001, 5913.349531551526, 5935.10973353996, 5954.851128721816, 5622.409646987089, 5624.3250809903075, 5678.034734095172, 5690.715464226772, 5700.287464066992, 6125.938740612626, 6132.6933653317155, 6140.140380346755, 6143.815685529441, 6148.231471066117, 5693.487586117187, 5750.7987220447085, 5904.143899134688, 5906.438888305604, 5963.4334738199805, 5827.24245848037, 5851.180834683148, 5906.0, 5937.70311484424, 5978.760849565969, 5853.765849365977, 5865.266841644794, 5871.412858714094, 5877.471027607536, 5987.760489580455, 5798.188253644493, 5866.530677545786, 5887.735051922674, 5900.734466948998, 5921.3190483094995, 4499.235130027903, 4515.887102822456, 4566.885827854264, 4600.42494688164, 4603.125406172817, 4090.7136500445276, 4101.036256479725, 4107.342825147962, 4132.504099508018, 4143.3784932260405, 5548.944510554924, 5759.0, 5763.250777398911, 5765.682887441147, 5769.826905192851, 5852.268466441695, 5960.642361458326, 5975.641461512323, 6006.669633170218, 6022.156898034279, 5723.627964182315, 5724.284464441945, 5725.456081672234, 5727.914081288784, 5741.425857414309, 5163.612729045337, 5221.6083793715625, 5227.320448341691, 5233.110371236899, 5264.368275806928, 5880.0591905295805, 6016.458518733335, 6082.391760823971, 6165.815025549241, 6179.69101544912, 5961.314448838439, 5962.582619216613, 5964.194833697569, 5966.463018328372, 6070.2108725865355, 6152.384761523812, 6179.073139029181, 6183.690815459299, 6191.597546159485, 6197.287311959182, 5731.742071606871, 5794.304683438015, 5797.362290147996, 5831.591788574759, 5854.94145058561, 5903.203067585992, 5972.970135149297, 5993.041113421831, 6026.939730602638, 6034.09488576717, 5839.5036421904615, 5853.678047707417, 5858.94141058584, 5880.705964701662, 5880.911786323123, 5628.015097357864, 5699.544036477155, 5823.417658234142, 5834.795782147774, 5847.26909136358, 5625.493705566519, 5664.801731939362, 5687.260656114679, 5721.828345149652, 5722.227499287543, 5847.7368518417625, 5867.20792692981, 5873.853153671193, 5880.970595147168, 5935.732892019956, 5461.918071228855, 5647.774089036392, 5762.423757624204, 5762.596618236683, 5762.654240745569], "unit": "ms", "name": "dojo.html", "value": 5483.168385598564}, {"lowerIsBetter": false, "replicates": [556.9168015971953, 572.3560993881883, 586.5981802465315, 591.7899145803237, 592.4371846745591, 30408.087757367288, 31307.373852522935, 31405.371892562136, 31446.213844653867, 36473.452898206546, 40694.7965260174, 41317.0, 41515.79242103793, 41608.79195604018, 41615.0, 8672.43629164104, 8824.338174636896, 8849.60176792046, 8852.380333376666, 8860.20258176764, 7701.114371847253, 7849.6075196240245, 7858.332041776427, 7863.174366691501, 8456.746297611082, 7652.31129198372, 10628.468576571178, 10818.134549236041, 10844.457777111153, 10851.294665846734, 10348.5343159558, 10359.22305827066, 10431.269811113225, 10442.2168337375, 10475.004874536944, 7743.728969486041, 7943.324817390529, 10189.0, 10329.276950613461, 10345.068943795019, 38415.231695366216, 39481.60518394808, 39632.20735585301, 39637.40543891844, 39643.60356396428, 3429.9657003430148, 3496.895093147184, 3726.366517691999, 3998.5801490843623, 4025.75845449274, 28091.57862632062, 28898.566521502195, 29522.704772952424, 29818.10545683633, 29985.0, 3809.29528037314, 3820.9808950955066, 3850.152966347399, 3888.0668639526593, 3985.1830374773103, 253582.0, 254698.99999999814, 256075.0, 256500.71749641318, 257713.0, 55004.72497637486, 55306.17040744392, 55414.16878746822, 55548.72225638846, 55842.72078639581, 58180.709096454244, 58289.0, 58391.70804145952, 58398.4160158393, 58485.122723159184, 15918.124503152438, 16262.105584192746, 16291.0, 16300.184990750415, 16306.836931630532, 12234.510619575296, 12410.627681169579, 12411.875881241253, 12422.130450868352, 12425.813612795815, 17988.460346189637, 18561.0, 18791.0, 18801.05994700287, 18832.90583547101, 6908.930910690829, 7032.8945065824055, 7066.081409416743, 7110.466714996396, 7121.216666166718, 880.6521424037597, 1005.3364779245662, 1135.0465608888574, 1140.6463996161217, 1148.8793676663997, 27643.170704878885, 27832.86083569569, 27876.44247115046, 27884.442311154067, 28120.29699257494, 947.516766449116, 1001.7095042437758, 1015.786684796201, 1055.7518983038979, 1064.41989115932, 13098.083134180706, 13129.934350328187, 13173.736525269442, 13288.800667989795, 13368.0, 260003.0, 262464.0, 263590.0, 264371.0, 265356.0, 45775.0, 45792.54207457883, 45803.541964580596, 45837.0, 45851.31223031657, 1344.6571124363224, 1353.262471952788, 1366.8838148757268, 1370.6641872741243, 1383.4535358533324, 25945.22164335073, 26578.335541611614, 26818.0, 26942.59586106249, 26993.595096073183, 42907.0, 43138.56861431345, 43254.567454326316, 43367.78316108525, 43456.34815477707, 303345.0, 305430.0, 305881.0, 306161.0, 307214.0, 26921.596176057767, 27489.450210995266, 30311.090667280016, 30653.0, 31144.844275779382, 36945.81527092455, 37187.0, 38101.809490952364, 38292.808535958255, 40553.0, 33877.66122338844, 34565.0, 34652.0, 35116.0, 35345.29309413746, 31540.36919261648, 34338.82830585831, 34836.0, 36091.27817443689, 36489.27021459503, 33370.666293336755, 33648.32703345968, 35313.64686353104, 36051.0, 36102.8194859024, 28732.569011465264, 29108.0, 30186.094417167522, 30699.0, 30700.84649576738, 22953.655695164918, 23275.650865237374, 24515.182221600833, 24519.141830035864, 24801.50396992086], "unit": "ms", "name": "ext.html", "value": 17986.243467875287}, {"lowerIsBetter": false, "replicates": [354.26490033181153, 355.40292308921016, 355.70476504501266, 356.5240404060579, 361.3189138473976, 14748.705025899475, 15010.220677101199, 16524.0, 16612.501624951256, 17230.138493075327, 16346.10096444695, 17375.652486950286, 17610.735838962424, 17614.295428182857, 17718.91140544299, 5317.867053323659, 5438.3473983121885, 5448.591355648323, 5452.454754524536, 5456.91814622781, 4031.3549832026883, 4128.587141285862, 5352.0, 5384.434634363394, 5891.88216235675, 5973.044312909935, 5992.940070599304, 6012.909806352908, 6026.246719160105, 6035.637861728288, 5888.558358123137, 5974.0, 6028.367021462727, 6029.728662210211, 6034.577579569432, 5299.417064122943, 5324.866878328034, 5325.0, 5325.668582854287, 5328.80101977056, 20081.899590501955, 20117.680582703837, 20478.0, 20496.077676504592, 20551.588968220552, 2112.6197284488785, 2258.1870526610405, 2468.6543883856275, 2531.9112781503945, 2703.6890757562937, 13009.219446833125, 13152.6711832204, 13259.801102983463, 13260.867391326059, 13665.795013074812, 1795.0367234596351, 1854.6105317883262, 1881.5014021284371, 1897.8328328078271, 1904.2192700992664, 57550.42449575492, 59757.40242597562, 60173.6991315045, 60537.39462605362, 61316.38683613151, 9676.467794271382, 9710.223182145412, 9968.302218844756, 9991.400515969066, 10007.349522281174, 12998.0, 13002.479900804052, 13054.412551435209, 13082.476700931857, 13098.148620339647, 7777.3778097753175, 7803.531788092732, 7810.375169986388, 7859.371250300077, 7924.811278308184, 7694.769156925301, 7701.1143718473095, 7740.187280335484, 7769.572673503011, 7776.183500732454, 5937.465628093492, 6002.0, 6023.337432882379, 6027.427394397525, 6040.788572399945, 1681.1090122235235, 1711.1957380031365, 1723.8189990051114, 1724.6981778188767, 1789.1322708486373, 932.7248461703814, 963.2342287881012, 967.1778987860263, 1015.2487159501916, 1049.8372752223418, 17179.0, 17212.053337066332, 17247.137643117792, 17277.13614319279, 17407.0, 945.933784635069, 967.2213867836318, 973.1290495006929, 987.0721521769514, 1006.7382480554962, 8025.879611805827, 10031.097201251776, 10045.547950342252, 10136.2904596679, 10175.287729858986, 534947.0, 540032.0, 543757.0, 546935.0, 548324.0, 70086.9486957696, 70115.64942175256, 70156.64921675359, 70563.0, 71208.64395677988, 1085.0885256384518, 1091.879893211746, 1097.3251450358132, 1111.6387174168267, 1125.3585456289836, 14987.925060374628, 15203.239838007834, 15384.69230615359, 15390.923045384701, 15418.383264669288, 33458.832705836314, 33531.32937341288, 33706.325873482885, 33768.324633507684, 33952.0, 43569.56430435782, 43641.3453798187, 44157.46740260836, 44302.33546496741, 45004.32493512666, 9607.807843843224, 9633.759156021155, 9825.4104753715, 9846.261530385393, 9846.852297215692, 17313.56716082108, 17613.471595852145, 17835.108244587977, 18149.818501814814, 18228.17973191183, 11254.83117753218, 14206.289685515889, 14212.573622791333, 14414.198225221848, 14467.782983255049, 14509.274536273355, 14627.780583291473, 14810.62973425673, 15085.24573771329, 15225.619359515658, 12262.632121036384, 14518.564443066725, 14744.336504857092, 15144.091354518765, 15546.455874044354, 9053.275737940818, 9451.149396554343, 9452.480113593543, 9455.952720236355, 9502.524873756147, 13607.591772246848, 14264.072835265675, 14688.412463501767, 14879.553613391616, 15046.473373431878], "unit": "ms", "name": "jquery.html", "value": 9091.188387126636}, {"lowerIsBetter": false, "replicates": [1535.401193534521, 1703.344212478196, 1734.340950438833, 1775.3608700867694, 1783.6789377911975, 6899.689513971871, 7261.38278246349, 7429.554226746399, 7434.814129646761, 7447.88828167578, 7584.924150758491, 7808.7266945656775, 7811.804704882381, 7822.726204582841, 7833.060032796073, 2026.0072564443465, 2064.370367038053, 2066.5866826634733, 2280.703508543891, 2317.918872839451, 2305.14709557464, 2360.7403185649564, 2361.8346715729904, 2365.562370961369, 2366.7041619797524, 2275.374272075176, 2276.1009401286483, 2330.8135349172026, 2332.568474832153, 2333.0201315447553, 2013.2953466286756, 2053.6816793396897, 2054.517188460718, 2059.2895451069357, 2251.549690061986, 2276.203328834911, 2336.626139817621, 2341.157183413987, 2341.4497593065616, 2345.4019225097663, 7625.428092893055, 7751.186125456802, 7769.494982826098, 7771.417143714188, 7777.144514103444, 4559.703619264737, 4602.585767280927, 4606.493285738566, 4607.308903664443, 4625.306204069383, 3093.876244950199, 3188.3145123798495, 3264.5756051713124, 3266.673332666738, 3289.605247370307, 1007.7984403119406, 1015.7155996321042, 1024.8923862994352, 1034.8189066913335, 1054.5518154784263, 10631.94684026575, 10635.468226588717, 10642.946785266102, 10643.893561064368, 10645.20160987929, 4211.705180637326, 4247.851325203603, 4258.957410425918, 4259.2759230930815, 4436.245838207513, 9109.316801239933, 9131.178193962574, 9137.5888085035, 9146.359754817233, 9148.496832674135, 4678.1813182692895, 4704.929426058612, 4708.552687494683, 4709.175894218498, 4710.0, 4241.1941731071, 4242.61816436516, 4244.214820258262, 4252.87241382759, 4255.3829694694095, 3763.802687006135, 3791.6777073949024, 3799.772013679188, 3804.9048773780323, 3824.9235015299537, 1177.8292147638545, 1182.8048372018693, 1188.5602327139013, 1230.2618428942562, 1231.6366671831831, 1303.6545315491403, 1315.625046861659, 1335.9599212023654, 1336.6190635668981, 1338.2706424998205, 6880.139982502187, 6938.444924406035, 6952.339527744856, 6997.510174287852, 7022.894656580155, 485.36902027364283, 494.6117297921115, 495.2719502331567, 497.8630876508989, 500.6395395315367, 5467.043267428184, 5545.72271386429, 5701.743421546041, 5788.392218816964, 5929.199558059607, 8733.214010739064, 8916.197542221102, 9056.864147037799, 9058.818823623491, 9100.0, 9011.774705632411, 9017.278617710568, 9060.81878362429, 9065.864012039825, 9080.500572468445, 7011.053507776589, 7048.647567621599, 7106.111736032996, 7117.074780278531, 7171.103612048494, 7157.856842863218, 7219.314165154302, 7242.529235599669, 7250.564966102051, 7251.419886408969, 8131.390145738975, 8231.09457959612, 8335.541545215072, 8338.499690018376, 8363.20549547792, 8343.249107580346, 8575.185357391038, 8618.956905215684, 8695.391322607356, 8723.65105395777, 6897.48268879826, 6923.273056329214, 6969.7909062728195, 6984.3364880336285, 6988.231294557696, 7517.398608111229, 7580.0, 7587.810304742426, 7604.771856844082, 7612.1246056704185, 7034.964825175842, 7054.647267636495, 7123.679434425359, 7156.964215178891, 7245.529040612343, 7016.859662806613, 7025.156981162293, 7050.858982820418, 7067.25793791665, 7177.031100801325, 7023.0, 7092.6099064551945, 7095.077639906779, 7117.644117793986, 7130.750423735144, 6458.418742313214, 6516.869662606627, 6524.347565243438, 6525.510586706111, 6526.347365263436, 6484.351564843478, 6660.234073081658, 6696.7656132035145, 6698.296678848846, 6701.564398314094], "unit": "ms", "name": "mootools.html", "value": 4406.59309926679}, {"lowerIsBetter": false, "replicates": [319.4664909600966, 320.52242159182816, 322.9095853161115, 329.11960505647386, 334.29463831315917, 5819.272590926134, 5865.85335366615, 5943.702814859256, 5982.222311099562, 5991.670458124801, 5748.827535173941, 5885.764569417229, 5910.083936989763, 5910.852228694284, 5921.940780592204, 4613.999999999992, 4621.168189725848, 4622.514635963226, 4626.259798432252, 4628.791704373294, 4411.536788637179, 4499.75251361175, 4500.032493014001, 4500.369948207253, 4626.8380606678775, 4374.256376416017, 4436.268015777393, 4454.109178164364, 4461.24158892989, 4467.061916997434, 4416.823327066945, 4433.933490997638, 4438.733675979419, 4443.488998765151, 4451.109778044388, 3852.7863722927186, 3866.7873266970305, 3867.9613203867884, 3874.302625527404, 3882.49527561418, 5443.401225865191, 5575.637583557056, 5575.721213939287, 5603.439656034364, 5620.5503559715535, 5208.5572726318305, 5223.399309079467, 5226.81706140287, 5236.266922630834, 5237.790488380499, 6173.722182501798, 6176.7838125665385, 6178.876422471525, 6181.938180618181, 6182.783602573933, 4357.36818161368, 4371.16947779924, 4373.737575745466, 4377.846775362847, 4387.912241755147, 6215.34738852423, 6295.2373335466145, 6436.098946147543, 6549.901751473732, 6554.14796076507, 4400.185965596311, 4400.9559904401185, 4407.625351845129, 4477.350784136347, 4563.361129441881, 4583.220852455091, 4601.148787474328, 4610.608098311681, 4610.884727881829, 4617.7691115444095, 5299.894002120013, 5319.6808191508635, 5322.787088516416, 5329.09405401075, 5334.653247538975, 6182.876342473215, 6332.4934005279465, 6333.556651034475, 6339.049142628551, 6350.396712312323, 1650.8596769274507, 1666.4000959654516, 1666.8666506679438, 1667.8749093818012, 1668.799744030724, 1097.7255686078481, 1129.615930583589, 1136.7442325476702, 1137.4028634966708, 1153.2734377342388, 6170.352113028157, 6194.907076393858, 6197.163382943335, 6197.504199664107, 6208.255009398901, 6553.901691474632, 6567.2447668518735, 6567.408933196035, 6567.638779867058, 6568.244651865097, 6070.666113363719, 6155.969220153871, 6182.350853160443, 6196.845078872974, 6203.875922481525, 3534.6111927687934, 3559.6974257187912, 3564.8930532084078, 3565.2156525564333, 3565.6969157621375, 6730.293319201512, 6736.292689267654, 6738.730450782012, 6740.460763138937, 6744.190697116279, 6551.508636852255, 6607.504437167232, 6670.966645166744, 6674.365935236145, 6679.0, 6721.1262535871, 6733.663316834237, 6735.057092007123, 6744.224414192333, 6750.932490675031, 6519.0, 6532.771353002622, 6535.379138981789, 6538.1827271591055, 6541.149650545399, 6335.461485773761, 6447.516436267392, 6459.354064593503, 6459.870802584016, 6460.967695161494, 6519.0, 6639.800805975829, 6712.92593185088, 6716.429103526059, 6735.090762746966, 4518.231900576844, 4538.773061346853, 4547.295169248771, 4550.931736024028, 4551.36280920678, 6520.902186467112, 6529.347065293433, 6529.542931994714, 6534.222955243908, 6538.248101468392, 6267.780627678009, 6400.167978162809, 6410.326915673787, 6413.903791443226, 6429.807105786834, 6379.266384365672, 6393.520485963479, 6399.264084630141, 6400.167978162809, 6401.135846660642, 6282.560220784501, 6287.339829318039, 6288.654124023223, 6293.622382657055, 6295.3389894060465, 6254.0, 6377.617342959437, 6411.551191416743, 6435.646039467687, 6451.903221451588, 6233.781817636361, 6265.530085243534, 6266.467350275278, 6268.028455589391, 6268.153799237045], "unit": "ms", "name": "prototype.html", "value": 4770.966003482936}, {"lowerIsBetter": false, "replicates": [840.9117042710513, 842.9367797415191, 847.1147650705013, 858.652245840434, 860.6170254236863, 13740.793888091686, 13928.51250206243, 14069.155850648955, 14152.858471415282, 14169.220692861902, 13297.13568618039, 13297.401616927238, 13349.799753003688, 13397.732045359087, 13472.191668499896, 679.0730652659109, 688.0573614148624, 824.3199360527559, 837.9371547133959, 839.3369238301734, 826.8222332198607, 839.2866063845713, 839.412411312081, 846.4709556527171, 847.0851480401152, 870.6822009966348, 881.0220655072864, 881.0616693221731, 885.8848349714528, 887.8845750052517, 709.39701253934, 720.1789959446236, 866.9956650216772, 870.5212133326677, 891.8350105230553, 628.1112226199922, 633.3255083336232, 633.6704913445029, 638.581728967527, 639.2967735490969, 13808.447662093504, 13925.930370348186, 13968.371423285977, 13969.930150349284, 14011.579652610337, 12353.752924941451, 12818.230906145662, 12897.613071607773, 13247.536336228282, 13528.169189095175, 10278.897211027868, 10337.431441270726, 10464.476776161162, 10596.735081622945, 10622.203334749925, 3572.231970126409, 3646.3721913379895, 3711.9443208351895, 3716.6655001049767, 3766.3408903441787, 46322.30516542255, 46672.0, 46936.530634693896, 46937.53062469366, 47135.29297060547, 10385.63650272237, 10390.168786497063, 10414.427206503715, 10505.107065899332, 10573.101286390744, 18837.90581047086, 18851.24595016184, 18867.433976980712, 18919.905400473184, 19559.31542396009, 7667.233276672288, 7841.019872515935, 7881.14793023639, 7900.683972641145, 7922.960385198037, 4479.372887795711, 4505.211587972158, 4508.571685689854, 4548.226801443762, 4571.748553829571, 3174.1588479052607, 3209.4864821628425, 3215.887443939451, 3223.0, 3225.3388055448577, 583.7023118209744, 584.6988800767608, 586.0623003194939, 586.3140126052529, 592.7628948420667, 657.4641667041346, 659.6194503171247, 676.3980057748607, 681.3118750062473, 717.4511498703496, 8223.917760822435, 8269.8346033079, 8370.288525475282, 8426.199511046563, 8444.915550844536, 538.222268821549, 637.4963778614842, 642.9292777794439, 678.135377393817, 693.4556373246977, 4090.897727556835, 4196.139791342767, 4255.085266592394, 4257.978710106429, 4265.53079161292, 56675.0, 56889.14666280009, 57505.42494575084, 57695.42304576984, 57699.13451298234, 52302.738486307324, 53873.461265387625, 53964.0, 54038.45961540413, 54223.72888135534, 46947.0, 46958.76520617375, 47192.528074718815, 47726.37504937192, 47908.28137577939, 8043.718469853527, 8060.234277743605, 8176.46852954556, 8231.711890083818, 8248.711295104642, 35111.82444087763, 35301.64698353086, 35317.0, 35423.64576354204, 36047.639523604426, 42284.57715422929, 44995.55004450044, 47017.52982470268, 47228.29157562708, 47840.7607961958, 9852.950735246279, 10161.949190254001, 10309.74225644335, 10355.534000970125, 10756.677299681021, 11113.388763618079, 11383.487743051748, 11567.826482602935, 11601.12991525622, 11816.113791465847, 10729.0, 10810.29733067348, 11297.096232301561, 11387.715307117389, 11451.942740286246, 10836.0, 10848.294860834021, 10942.945285273841, 10955.83566246523, 11361.622036109278, 9515.666951656658, 9775.266854986046, 10534.104601108991, 10841.0, 10848.945755271174, 8623.982809733183, 8724.43291186071, 8740.78148046304, 9021.729348119567, 9316.627334906529, 8483.321334293365, 9143.0, 9185.862212066691, 9202.49386283734, 9445.669401571187], "unit": "ms", "name": "yui.html", "value": 6334.071898325238}], "name": "dromaeo_css", "value": 7017.307091666977}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [108.0, 112.0, 104.0, 109.0, 109.0, 109.0, 98.0, 109.0, 107.0, 99.0], "unit": "ms", "name": "ai-astar", "value": 106.4}, {"lowerIsBetter": true, "replicates": [154.0, 123.0, 155.0, 165.0, 128.0, 146.0, 151.0, 125.0, 163.0, 123.0], "unit": "ms", "name": "audio-beat-detection", "value": 143.3}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 158.0, 159.0, 161.0, 159.0, 160.0, 162.0, 159.0, 160.0], "unit": "ms", "name": "audio-dft", "value": 159.9}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 82.0, 93.0, 71.0, 70.0, 72.0, 128.0, 91.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 81.9}, {"lowerIsBetter": true, "replicates": [93.0, 93.0, 137.0, 93.0, 93.0, 93.0, 140.0, 93.0, 94.0, 141.0], "unit": "ms", "name": "audio-oscillator", "value": 107.0}, {"lowerIsBetter": true, "replicates": [109.0, 109.0, 109.0, 108.0, 109.0, 109.0, 110.0, 109.0, 110.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.1}, {"lowerIsBetter": true, "replicates": [145.0, 145.0, 144.0, 144.0, 146.0, 145.0, 145.0, 145.0, 145.0, 147.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.1}, {"lowerIsBetter": true, "replicates": [95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.7}, {"lowerIsBetter": true, "replicates": [59.0, 57.0, 57.0, 59.0, 58.0, 57.0, 57.0, 57.0, 60.0, 56.0], "unit": "ms", "name": "json-parse-financial", "value": 57.7}, {"lowerIsBetter": true, "replicates": [69.0, 70.0, 70.0, 70.0, 70.0, 69.0, 69.0, 71.0, 71.0, 71.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 70.0}, {"lowerIsBetter": true, "replicates": [83.0, 85.0, 87.0, 84.0, 90.0, 85.0, 83.0, 85.0, 86.0, 85.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 85.3}, {"lowerIsBetter": true, "replicates": [128.0, 119.0, 119.0, 115.0, 119.0, 116.0, 122.0, 118.0, 117.0, 132.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 120.5}, {"lowerIsBetter": true, "replicates": [141.0, 143.0, 152.0, 153.0, 150.0, 139.0, 148.0, 151.0, 146.0, 138.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 146.1}, {"lowerIsBetter": true, "replicates": [66.0, 66.0, 64.0, 65.0, 65.0, 66.0, 64.0, 65.0, 65.0, 65.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 65.1}], "name": "kraken", "value": 1493.1}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [164.58196181698486], "unit": "score", "name": "Richards", "value": 214.4949520000001}, {"lowerIsBetter": false, "replicates": [194.43904335990666], "unit": "score", "name": "DeltaBlue", "value": 340.0448739999999}, {"lowerIsBetter": false, "replicates": [282.6455624646693], "unit": "score", "name": "Encrypt", "value": 941.7483780000005}, {"lowerIsBetter": false, "replicates": [5223.958333333333], "unit": "score", "name": "Decrypt", "value": 50.95389032901295}, {"lowerIsBetter": false, "replicates": [1092.896174863388], "unit": "score", "name": "RayTrace", "value": 677.089935}, {"lowerIsBetter": false, "replicates": [776.3975155279503], "unit": "score", "name": "Earley", "value": 858.4043439999999}, {"lowerIsBetter": false, "replicates": [11929.411764705883], "unit": "score", "name": "Boyer", "value": 55.867214003944795}, {"lowerIsBetter": false, "replicates": [26076.923076923078], "unit": "score", "name": "RegExp", "value": 34.934528023598794}, {"lowerIsBetter": false, "replicates": [665.1926721415035], "unit": "score", "name": "Splay", "value": 122.50736277302941}, {"lowerIsBetter": false, "replicates": [4845.410628019324], "unit": "score", "name": "NavierStokes", "value": 306.26919242273203}], "name": "v8_7", "value": 20847.553592473992}]} 16:43:35 INFO - RETURN: dromaeo_css: 7017.31 16:43:35 INFO - RETURN: kraken: 1493.10 16:43:35 INFO - RETURN: v8_7: 20847.55 16:43:35 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,64,35]]", "result": "20847.55"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,64,35]]", "result": "1493.10"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,64,35]]", "result": "7017.31"}}} 16:43:35 INFO - Return code: 0 16:43:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:43:35 INFO - # TBPL SUCCESS # 16:43:35 INFO - Running post-action listener: _resource_record_post_action 16:43:35 INFO - Running post-run listener: _resource_record_post_run 16:43:36 INFO - Total resource usage - Wall time: 1224s; CPU: 13.0%; Read bytes: 0; Write bytes: 503287808; Read time: 0; Write time: 367592 16:43:36 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 232501248; Read time: 0; Write time: 223732 16:43:37 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 0; Write bytes: 270786560; Read time: 0; Write time: 143860 16:43:37 INFO - Running post-run listener: _upload_blobber_files 16:43:37 INFO - Blob upload gear active. 16:43:37 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:43:37 INFO - Copying logs to upload dir... 16:43:37 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1270.501430 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2015-11-25 16:43:35.785967) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 16:43:35.789509) ========= 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=cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009940 build_url: 'https://queue.taskcluster.net/v1/task/diAwANq5Qa6593bGDNvMjg/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-25 16:43:35.844344) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:43:35.844952) ========= 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=cd88c086d1ff67493cfd1714000001ba-1448491699.658521-1565405763 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004558 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-25 16:43:35.898107) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 16:43:35.898733) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-25 16:43:35.899045) ========= ========= Total master_lag: 0.27 =========