builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1454544012.51 results: success (0) buildid: 20160203133327 builduid: 3879188f05c442aa90bf5e6613361d7a revision: e29130ca7a64 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.509213) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.509814) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.510119) ========= 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=652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test-pgo program finished with exit code 0 elapsedTime=0.006312 basedir: '/builds/slave/test-pgo' ========= master_lag: 0.03 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.541962) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.542235) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.585461) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.585727) ========= 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=652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.026716 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.634219) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.634491) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.634845) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.635126) ========= 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=652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-03 16:00:12-- 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% 35.3M=0s 2016-02-03 16:00:12 (35.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118811 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.766174) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.766497) ========= 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=652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.131397 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.912540) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:12.912866) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e29130ca7a64 --destination scripts --debug' in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e29130ca7a64 --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=652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-03 16:00:12,959 Setting DEBUG logging. 2016-02-03 16:00:12,959 attempt 1/10 2016-02-03 16:00:12,959 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e29130ca7a64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-03 16:00:13,245 unpacking tar archive at: mozilla-beta-e29130ca7a64/testing/mozharness/ program finished with exit code 0 elapsedTime=0.563834 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:13.489398) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:13.489739) ========= echo e29130ca7a64 in dir /builds/slave/test-pgo/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'e29130ca7a64'] 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=652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False e29130ca7a64 program finished with exit code 0 elapsedTime=0.003750 script_repo_revision: 'e29130ca7a64' ========= master_lag: 0.04 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:13.537522) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:13.537864) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-03 16:00:13.547688) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-02-03 16:00:13.547984) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test-pgo/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test-pgo/buildprops.json PWD=/builds/slave/test-pgo SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:00:13 INFO - MultiFileLogger online at 20160203 16:00:13 in /builds/slave/test-pgo 16:00:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:00:13 INFO - Dumping config to /builds/slave/test-pgo/logs/localconfig.json. 16:00:13 INFO - {'append_to_log': False, 16:00:13 INFO - 'base_work_dir': '/builds/slave/test-pgo', 16:00:13 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:00:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test-pgo/oauth.txt', 16:00:13 INFO - 'branch': 'Mozilla-Beta', 16:00:13 INFO - 'buildbot_json_path': 'buildprops.json', 16:00:13 INFO - 'config_files': ('talos/linux_config.py',), 16:00:13 INFO - 'default_actions': ('clobber', 16:00:13 INFO - 'read-buildbot-config', 16:00:13 INFO - 'download-and-extract', 16:00:13 INFO - 'populate-webroot', 16:00:13 INFO - 'create-virtualenv', 16:00:13 INFO - 'install', 16:00:13 INFO - 'run-tests'), 16:00:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:00:13 INFO - 'download_minidump_stackwalk': True, 16:00:13 INFO - 'download_symbols': 'ondemand', 16:00:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:00:13 INFO - 'tooltool.py': '/tools/tooltool.py', 16:00:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:00:13 INFO - '/tools/misc-python/virtualenv.py')}, 16:00:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:00:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:00:13 INFO - 'installer_path': 'installer.exe', 16:00:13 INFO - 'log_level': 'info', 16:00:13 INFO - 'log_name': 'talos', 16:00:13 INFO - 'log_to_console': True, 16:00:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:00:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:00:13 INFO - 'opt_config_files': (), 16:00:13 INFO - 'pip_index': False, 16:00:13 INFO - 'sps_profile': False, 16:00:13 INFO - 'sps_profile_interval': 0, 16:00:13 INFO - 'suite': 'dromaeojs', 16:00:13 INFO - 'system_bits': '32', 16:00:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:00:13 INFO - 'title': 'talos-linux64-ix-046', 16:00:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:00:13 INFO - 'use_talos_json': True, 16:00:13 INFO - 'virtualenv_path': '/builds/slave/test-pgo/build/venv', 16:00:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:00:13 INFO - 'work_dir': 'build'} 16:00:13 INFO - ##### 16:00:13 INFO - ##### Running clobber step. 16:00:13 INFO - ##### 16:00:13 INFO - Running pre-action listener: _resource_record_pre_action 16:00:13 INFO - Running main action method: clobber 16:00:13 INFO - rmtree: /builds/slave/test-pgo/build 16:00:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test-pgo/build',), kwargs: {}, attempt #1 16:00:15 INFO - Running post-action listener: _resource_record_post_action 16:00:15 INFO - ##### 16:00:15 INFO - ##### Running read-buildbot-config step. 16:00:15 INFO - ##### 16:00:15 INFO - Running pre-action listener: _resource_record_pre_action 16:00:15 INFO - Running main action method: read_buildbot_config 16:00:15 INFO - Using buildbot properties: 16:00:15 INFO - { 16:00:15 INFO - "properties": { 16:00:15 INFO - "buildnumber": 100, 16:00:15 INFO - "product": "firefox", 16:00:15 INFO - "script_repo_revision": "production", 16:00:15 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:00:15 INFO - "repository": "", 16:00:15 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:00:15 INFO - "buildid": "20160203133327", 16:00:15 INFO - "slavename": "talos-linux64-ix-046", 16:00:15 INFO - "pgo_build": "True", 16:00:15 INFO - "basedir": "/builds/slave/test-pgo", 16:00:15 INFO - "project": "", 16:00:15 INFO - "platform": "ubuntu64_hw", 16:00:15 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:00:15 INFO - "slavebuilddir": "test", 16:00:15 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:00:15 INFO - "branch": "mozilla-beta", 16:00:15 INFO - "repo_path": "releases/mozilla-beta", 16:00:15 INFO - "stage_platform": "linux64-pgo", 16:00:15 INFO - "builduid": "3879188f05c442aa90bf5e6613361d7a", 16:00:15 INFO - "revision": "e29130ca7a64" 16:00:15 INFO - }, 16:00:15 INFO - "sourcestamp": { 16:00:15 INFO - "repository": "", 16:00:15 INFO - "hasPatch": false, 16:00:15 INFO - "project": "", 16:00:15 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:00:15 INFO - "changes": [ 16:00:15 INFO - { 16:00:15 INFO - "category": null, 16:00:15 INFO - "files": [ 16:00:15 INFO - { 16:00:15 INFO - "url": null, 16:00:15 INFO - "name": "http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.tar.bz2" 16:00:15 INFO - } 16:00:15 INFO - ], 16:00:15 INFO - "repository": "", 16:00:15 INFO - "rev": "e29130ca7a64", 16:00:15 INFO - "who": "sendchange", 16:00:15 INFO - "when": 1454543976, 16:00:15 INFO - "number": 7559359, 16:00:15 INFO - "comments": "Bug 1059469 - Part 2 - When rescheduling the interval timer, cancel it first, and refactor things so that actually does something. r=bent, a=sylvestre", 16:00:15 INFO - "project": "", 16:00:15 INFO - "at": "Wed 03 Feb 2016 15:59:36", 16:00:15 INFO - "branch": "mozilla-beta-linux64-pgo-talos", 16:00:15 INFO - "revlink": "", 16:00:15 INFO - "properties": [ 16:00:15 INFO - [ 16:00:15 INFO - "buildid", 16:00:15 INFO - "20160203133327", 16:00:15 INFO - "Change" 16:00:15 INFO - ], 16:00:15 INFO - [ 16:00:15 INFO - "builduid", 16:00:15 INFO - "3879188f05c442aa90bf5e6613361d7a", 16:00:15 INFO - "Change" 16:00:15 INFO - ], 16:00:15 INFO - [ 16:00:15 INFO - "pgo_build", 16:00:15 INFO - "True", 16:00:15 INFO - "Change" 16:00:15 INFO - ] 16:00:15 INFO - ], 16:00:15 INFO - "revision": "e29130ca7a64" 16:00:15 INFO - } 16:00:15 INFO - ], 16:00:15 INFO - "revision": "e29130ca7a64" 16:00:15 INFO - } 16:00:15 INFO - } 16:00:15 INFO - Found installer url http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.tar.bz2. 16:00:15 INFO - Running post-action listener: _resource_record_post_action 16:00:15 INFO - ##### 16:00:15 INFO - ##### Running download-and-extract step. 16:00:15 INFO - ##### 16:00:15 INFO - Running pre-action listener: _resource_record_pre_action 16:00:15 INFO - Running main action method: download_and_extract 16:00:15 INFO - mkdir: /builds/slave/test-pgo/build/tests 16:00:15 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:00:15 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/test_packages.json 16:00:15 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/test_packages.json to /builds/slave/test-pgo/build/test_packages.json 16:00:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/test_packages.json', 'file_name': '/builds/slave/test-pgo/build/test_packages.json'}, attempt #1 16:00:15 INFO - Downloaded 1270 bytes. 16:00:15 INFO - Reading from file /builds/slave/test-pgo/build/test_packages.json 16:00:15 INFO - Using the following test package requirements: 16:00:15 INFO - {u'common': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:00:15 INFO - u'cppunittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:00:15 INFO - u'firefox-45.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:00:15 INFO - u'jittest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:00:15 INFO - u'jsshell-linux-x86_64.zip'], 16:00:15 INFO - u'mochitest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:00:15 INFO - u'firefox-45.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:00:15 INFO - u'mozbase': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:00:15 INFO - u'reftest': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:00:15 INFO - u'firefox-45.0.en-US.linux-x86_64.reftest.tests.zip'], 16:00:15 INFO - u'talos': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:00:15 INFO - u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'], 16:00:15 INFO - u'web-platform': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:00:15 INFO - u'firefox-45.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:00:15 INFO - u'webapprt': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'], 16:00:15 INFO - u'xpcshell': [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', 16:00:15 INFO - u'firefox-45.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:00:15 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:00:15 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:00:15 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:00:15 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:00:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:00:15 INFO - Downloaded 21580637 bytes. 16:00:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 16:00:15 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:00:15 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 16:00:16 INFO - Return code: 0 16:00:16 INFO - Downloading packages: [u'firefox-45.0.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:00:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:16 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:00:16 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:00:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:00:18 INFO - Downloaded 21580637 bytes. 16:00:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test-pgo/build/tests 16:00:18 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip 16:00:18 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 16:00:19 INFO - Return code: 0 16:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:19 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 16:00:19 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 16:00:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:00:19 INFO - Downloaded 11132038 bytes. 16:00:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test-pgo/build/tests 16:00:19 INFO - Copy/paste: unzip -q -o /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip 16:00:19 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 16:00:19 INFO - Return code: 0 16:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:19 INFO - trying http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:00:19 INFO - Downloading http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:00:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:00:20 INFO - Downloaded 57935604 bytes. 16:00:20 INFO - Setting buildbot property build_url to http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:00:20 INFO - mkdir: /builds/slave/test-pgo/properties 16:00:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test-pgo/properties/build_url 16:00:20 INFO - Writing to file /builds/slave/test-pgo/properties/build_url 16:00:20 INFO - Contents: 16:00:20 INFO - build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.tar.bz2 16:00:20 INFO - Running post-action listener: _resource_record_post_action 16:00:20 INFO - Running post-action listener: set_extra_try_arguments 16:00:20 INFO - ##### 16:00:20 INFO - ##### Running populate-webroot step. 16:00:20 INFO - ##### 16:00:20 INFO - Running pre-action listener: _resource_record_pre_action 16:00:20 INFO - Running main action method: populate_webroot 16:00:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:00:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:00:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:00:20 INFO - '--tppagecycles', 16:00:20 INFO - '7'], 16:00:20 INFO - 'tests': ['tsvgm']}}, 16:00:20 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:00:20 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:00:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:00:20 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:00:20 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:00:20 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 16:00:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:20 INFO - 'talos_options': ['--e10s'], 16:00:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:20 INFO - 'tests': ['damp', 'tps']}, 16:00:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:20 INFO - 'talos_options': ['--e10s'], 16:00:20 INFO - 'tests': ['damp', 'tps']}, 16:00:20 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:00:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:00:20 INFO - 'other': {'tests': ['a11yr', 16:00:20 INFO - 'ts_paint', 16:00:20 INFO - 'tpaint', 16:00:20 INFO - 'sessionrestore', 16:00:20 INFO - 'sessionrestore_no_auto_restore']}, 16:00:20 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:00:20 INFO - 'tests': ['a11yr', 16:00:20 INFO - 'ts_paint', 16:00:20 INFO - 'tpaint', 16:00:20 INFO - 'sessionrestore', 16:00:20 INFO - 'sessionrestore_no_auto_restore']}, 16:00:20 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:00:20 INFO - 'tests': ['a11yr', 16:00:20 INFO - 'ts_paint', 16:00:20 INFO - 'tpaint', 16:00:20 INFO - 'sessionrestore', 16:00:20 INFO - 'sessionrestore_no_auto_restore']}, 16:00:20 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:00:20 INFO - 'tests': ['a11yr', 16:00:20 INFO - 'ts_paint', 16:00:20 INFO - 'tpaint', 16:00:20 INFO - 'sessionrestore', 16:00:20 INFO - 'sessionrestore_no_auto_restore']}, 16:00:20 INFO - 'other_l64': {'tests': ['a11yr', 16:00:20 INFO - 'ts_paint', 16:00:20 INFO - 'tpaint', 16:00:20 INFO - 'sessionrestore', 16:00:20 INFO - 'sessionrestore_no_auto_restore']}, 16:00:20 INFO - 'other_nol64': {'tests': ['a11yr', 16:00:20 INFO - 'ts_paint', 16:00:20 INFO - 'tpaint', 16:00:20 INFO - 'sessionrestore', 16:00:20 INFO - 'sessionrestore_no_auto_restore']}, 16:00:20 INFO - 'svgr': {'tests': ['tsvgx', 16:00:20 INFO - 'tsvgr_opacity', 16:00:20 INFO - 'tart', 16:00:20 INFO - 'tscrollx', 16:00:20 INFO - 'cart']}, 16:00:20 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:00:20 INFO - 'tests': ['tsvgx', 16:00:20 INFO - 'tsvgr_opacity', 16:00:20 INFO - 'tart', 16:00:20 INFO - 'tscrollx', 16:00:20 INFO - 'cart']}, 16:00:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:20 INFO - 'tests': ['tp5o']}, 16:00:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:20 INFO - 'talos_options': ['--e10s'], 16:00:20 INFO - 'tests': ['tp5o']}, 16:00:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:20 INFO - 'talos_options': ['--xperf_path', 16:00:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:20 INFO - 'tests': ['tp5n']}, 16:00:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:00:20 INFO - 'talos_options': ['--e10s', 16:00:20 INFO - '--xperf_path', 16:00:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:20 INFO - 'tests': ['tp5n']}}, 16:00:20 INFO - 'talos.zip': {'path': '', 16:00:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:00:20 INFO - Running post-action listener: _resource_record_post_action 16:00:20 INFO - ##### 16:00:20 INFO - ##### Running create-virtualenv step. 16:00:20 INFO - ##### 16:00:20 INFO - Running pre-action listener: _resource_record_pre_action 16:00:20 INFO - Running main action method: create_virtualenv 16:00:20 INFO - Creating virtualenv /builds/slave/test-pgo/build/venv 16:00:20 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:00:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test-pgo/build/venv 16:00:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:00:20 INFO - Using real prefix '/usr' 16:00:20 INFO - New python executable in /builds/slave/test-pgo/build/venv/bin/python 16:00:21 INFO - Installing distribute.............................................................................................................................................................................................done. 16:00:23 INFO - Installing pip.................done. 16:00:23 INFO - Return code: 0 16:00:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test-pgo/build/venv 16:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:23 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced87ddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2425350>, '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 0x2426250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24266e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', '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:00:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test-pgo/build 16:00:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:00:23 INFO - Using env: {'DISPLAY': ':0', 16:00:23 INFO - 'HOME': '/home/cltbld', 16:00:23 INFO - 'LANG': 'en_US.UTF-8', 16:00:23 INFO - 'LANGUAGE': 'en_US:en', 16:00:23 INFO - 'LOGNAME': 'cltbld', 16:00:23 INFO - 'MAIL': '/var/mail/cltbld', 16:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:23 INFO - 'MOZ_NO_REMOTE': '1', 16:00:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:23 INFO - 'NO_EM_RESTART': '1', 16:00:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:23 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:23 INFO - 'SHELL': '/bin/bash', 16:00:23 INFO - 'SHLVL': '1', 16:00:23 INFO - 'TERM': 'linux', 16:00:23 INFO - 'TMOUT': '86400', 16:00:23 INFO - 'USER': 'cltbld', 16:00:23 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', 16:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:23 INFO - '_': '/tools/buildbot/bin/python'} 16:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:23 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:00:23 INFO - Cleaning up... 16:00:23 INFO - Return code: 0 16:00:23 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test-pgo/build/venv 16:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:23 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fced87ddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2425350>, '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 0x2426250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24266e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', '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:00:23 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test-pgo/build 16:00:23 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:00:23 INFO - Using env: {'DISPLAY': ':0', 16:00:23 INFO - 'HOME': '/home/cltbld', 16:00:23 INFO - 'LANG': 'en_US.UTF-8', 16:00:23 INFO - 'LANGUAGE': 'en_US:en', 16:00:23 INFO - 'LOGNAME': 'cltbld', 16:00:23 INFO - 'MAIL': '/var/mail/cltbld', 16:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:23 INFO - 'MOZ_NO_REMOTE': '1', 16:00:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:23 INFO - 'NO_EM_RESTART': '1', 16:00:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:23 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:23 INFO - 'SHELL': '/bin/bash', 16:00:23 INFO - 'SHLVL': '1', 16:00:23 INFO - 'TERM': 'linux', 16:00:23 INFO - 'TMOUT': '86400', 16:00:23 INFO - 'USER': 'cltbld', 16:00:23 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', 16:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:23 INFO - '_': '/tools/buildbot/bin/python'} 16:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:23 INFO - Downloading/unpacking psutil>=0.7.1 16:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:25 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/psutil/setup.py) egg_info for package psutil 16:00:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:25 INFO - Installing collected packages: psutil 16:00:25 INFO - Running setup.py install for psutil 16:00:25 INFO - building 'psutil._psutil_linux' extension 16:00:25 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:00:25 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:00:25 INFO - building 'psutil._psutil_posix' extension 16:00:25 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:00:26 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:00:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:26 INFO - Successfully installed psutil 16:00:26 INFO - Cleaning up... 16:00:26 INFO - Return code: 0 16:00:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test-pgo/build/venv 16:00: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:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00: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:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:26 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced87ddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2425350>, '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 0x2426250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24266e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', '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:00:26 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test-pgo/build 16:00:26 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:00:26 INFO - Using env: {'DISPLAY': ':0', 16:00:26 INFO - 'HOME': '/home/cltbld', 16:00:26 INFO - 'LANG': 'en_US.UTF-8', 16:00:26 INFO - 'LANGUAGE': 'en_US:en', 16:00:26 INFO - 'LOGNAME': 'cltbld', 16:00:26 INFO - 'MAIL': '/var/mail/cltbld', 16:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:26 INFO - 'MOZ_NO_REMOTE': '1', 16:00:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:26 INFO - 'NO_EM_RESTART': '1', 16:00:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:26 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:26 INFO - 'SHELL': '/bin/bash', 16:00:26 INFO - 'SHLVL': '1', 16:00:26 INFO - 'TERM': 'linux', 16:00:26 INFO - 'TMOUT': '86400', 16:00:26 INFO - 'USER': 'cltbld', 16:00:26 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', 16:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:26 INFO - '_': '/tools/buildbot/bin/python'} 16:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:28 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:00:28 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:00:28 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:00:28 INFO - Installing collected packages: mozsystemmonitor 16:00:28 INFO - Running setup.py install for mozsystemmonitor 16:00:28 INFO - Successfully installed mozsystemmonitor 16:00:28 INFO - Cleaning up... 16:00:28 INFO - Return code: 0 16:00:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test-pgo/build/venv 16:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:28 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced87ddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2425350>, '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 0x2426250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24266e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', '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:00:28 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test-pgo/build 16:00:28 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:00:28 INFO - Using env: {'DISPLAY': ':0', 16:00:28 INFO - 'HOME': '/home/cltbld', 16:00:28 INFO - 'LANG': 'en_US.UTF-8', 16:00:28 INFO - 'LANGUAGE': 'en_US:en', 16:00:28 INFO - 'LOGNAME': 'cltbld', 16:00:28 INFO - 'MAIL': '/var/mail/cltbld', 16:00:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:28 INFO - 'MOZ_NO_REMOTE': '1', 16:00:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:28 INFO - 'NO_EM_RESTART': '1', 16:00:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:28 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:28 INFO - 'SHELL': '/bin/bash', 16:00:28 INFO - 'SHLVL': '1', 16:00:28 INFO - 'TERM': 'linux', 16:00:28 INFO - 'TMOUT': '86400', 16:00:28 INFO - 'USER': 'cltbld', 16:00:28 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', 16:00:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:28 INFO - '_': '/tools/buildbot/bin/python'} 16:00:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:28 INFO - Downloading/unpacking blobuploader==1.2.4 16:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:30 INFO - Downloading blobuploader-1.2.4.tar.gz 16:00:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:00:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:00:30 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:00:30 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:00:30 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:00:30 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:00:30 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:00:30 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:00:30 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/requests/setup.py) egg_info for package requests 16:00:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:00:30 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:00:30 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:00:30 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:00:30 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:00:30 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:00:30 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:00:31 INFO - Downloading docopt-0.6.1.tar.gz 16:00:31 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/docopt/setup.py) egg_info for package docopt 16:00:31 INFO - Installing collected packages: blobuploader, requests, docopt 16:00:31 INFO - Running setup.py install for blobuploader 16:00:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:00:31 INFO - changing mode of /builds/slave/test-pgo/build/venv/bin/blobberc.py to 775 16:00:31 INFO - Running setup.py install for requests 16:00:31 INFO - Running setup.py install for docopt 16:00:31 INFO - Successfully installed blobuploader requests docopt 16:00:31 INFO - Cleaning up... 16:00:31 INFO - Return code: 0 16:00:31 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:31 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced87ddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2425350>, '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 0x2426250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24266e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', '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:00:31 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 16:00:31 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:00:31 INFO - Using env: {'DISPLAY': ':0', 16:00:31 INFO - 'HOME': '/home/cltbld', 16:00:31 INFO - 'LANG': 'en_US.UTF-8', 16:00:31 INFO - 'LANGUAGE': 'en_US:en', 16:00:31 INFO - 'LOGNAME': 'cltbld', 16:00:31 INFO - 'MAIL': '/var/mail/cltbld', 16:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:31 INFO - 'MOZ_NO_REMOTE': '1', 16:00:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:31 INFO - 'NO_EM_RESTART': '1', 16:00:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:31 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:31 INFO - 'SHELL': '/bin/bash', 16:00:31 INFO - 'SHLVL': '1', 16:00:31 INFO - 'TERM': 'linux', 16:00:31 INFO - 'TMOUT': '86400', 16:00:31 INFO - 'USER': 'cltbld', 16:00:31 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', 16:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:31 INFO - '_': '/tools/buildbot/bin/python'} 16:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:00:31 INFO - Running setup.py (path:/tmp/pip-w0zXTM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:00:31 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:00:31 INFO - Running setup.py (path:/tmp/pip-gFc6FI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:00:32 INFO - Running setup.py (path:/tmp/pip-bTpIPA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:00:32 INFO - Running setup.py (path:/tmp/pip-iUhW3B-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:00:32 INFO - Running setup.py (path:/tmp/pip-XczK45-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:00:32 INFO - Running setup.py (path:/tmp/pip-rFKCBm-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:00:32 INFO - Running setup.py (path:/tmp/pip-jn2zUn-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:00:32 INFO - Running setup.py (path:/tmp/pip-NABeRx-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:00:32 INFO - Running setup.py (path:/tmp/pip-h_usMI-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:00:32 INFO - Running setup.py (path:/tmp/pip-SzsY8J-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:00:32 INFO - Running setup.py (path:/tmp/pip-peuuEN-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:00:32 INFO - Running setup.py (path:/tmp/pip-bces38-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:00:32 INFO - Running setup.py (path:/tmp/pip-FuvJ88-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:00:32 INFO - Running setup.py (path:/tmp/pip-cpWTuZ-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:00:32 INFO - Running setup.py (path:/tmp/pip-7oDOHq-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:00:32 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:00:32 INFO - Running setup.py (path:/tmp/pip-NoMLyA-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:00:33 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:00:33 INFO - Running setup.py (path:/tmp/pip-z4D81b-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:00:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:00:33 INFO - Running setup.py install for manifestparser 16:00:33 INFO - Installing manifestparser script to /builds/slave/test-pgo/build/venv/bin 16:00:33 INFO - Running setup.py install for mozcrash 16:00:33 INFO - Running setup.py install for mozdebug 16:00:33 INFO - Running setup.py install for mozdevice 16:00:33 INFO - Installing sutini script to /builds/slave/test-pgo/build/venv/bin 16:00:33 INFO - Installing dm script to /builds/slave/test-pgo/build/venv/bin 16:00:33 INFO - Running setup.py install for mozfile 16:00:33 INFO - Running setup.py install for mozhttpd 16:00:33 INFO - Installing mozhttpd script to /builds/slave/test-pgo/build/venv/bin 16:00:33 INFO - Running setup.py install for mozinfo 16:00:33 INFO - Installing mozinfo script to /builds/slave/test-pgo/build/venv/bin 16:00:33 INFO - Running setup.py install for mozInstall 16:00:33 INFO - Installing moz_remove_from_system script to /builds/slave/test-pgo/build/venv/bin 16:00:33 INFO - Installing mozuninstall script to /builds/slave/test-pgo/build/venv/bin 16:00:33 INFO - Installing mozinstall script to /builds/slave/test-pgo/build/venv/bin 16:00:33 INFO - Installing moz_add_to_system script to /builds/slave/test-pgo/build/venv/bin 16:00:33 INFO - Running setup.py install for mozleak 16:00:34 INFO - Running setup.py install for mozlog 16:00:34 INFO - Installing structlog script to /builds/slave/test-pgo/build/venv/bin 16:00:34 INFO - Running setup.py install for moznetwork 16:00:34 INFO - Installing moznetwork script to /builds/slave/test-pgo/build/venv/bin 16:00:34 INFO - Running setup.py install for mozprocess 16:00:34 INFO - Running setup.py install for mozprofile 16:00:34 INFO - Installing mozprofile script to /builds/slave/test-pgo/build/venv/bin 16:00:34 INFO - Installing diff-profiles script to /builds/slave/test-pgo/build/venv/bin 16:00:34 INFO - Installing view-profile script to /builds/slave/test-pgo/build/venv/bin 16:00:34 INFO - Running setup.py install for mozrunner 16:00:34 INFO - Installing mozrunner script to /builds/slave/test-pgo/build/venv/bin 16:00:34 INFO - Running setup.py install for mozscreenshot 16:00:34 INFO - Running setup.py install for moztest 16:00:34 INFO - Running setup.py install for mozversion 16:00:34 INFO - Installing mozversion script to /builds/slave/test-pgo/build/venv/bin 16:00:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:00:34 INFO - Cleaning up... 16:00:34 INFO - Return code: 0 16:00:34 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:00:34 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:00:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:34 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:00:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:34 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced87ddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2425350>, '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 0x2426250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24266e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', '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:00:34 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/config 16:00:34 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:00:34 INFO - Using env: {'DISPLAY': ':0', 16:00:34 INFO - 'HOME': '/home/cltbld', 16:00:34 INFO - 'LANG': 'en_US.UTF-8', 16:00:34 INFO - 'LANGUAGE': 'en_US:en', 16:00:34 INFO - 'LOGNAME': 'cltbld', 16:00:34 INFO - 'MAIL': '/var/mail/cltbld', 16:00:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:34 INFO - 'MOZ_NO_REMOTE': '1', 16:00:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:34 INFO - 'NO_EM_RESTART': '1', 16:00:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:34 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:34 INFO - 'SHELL': '/bin/bash', 16:00:34 INFO - 'SHLVL': '1', 16:00:34 INFO - 'TERM': 'linux', 16:00:34 INFO - 'TMOUT': '86400', 16:00:34 INFO - 'USER': 'cltbld', 16:00:34 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', 16:00:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:34 INFO - '_': '/tools/buildbot/bin/python'} 16:00:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:00:35 INFO - Running setup.py (path:/tmp/pip-ZiowSa-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/manifestparser 16:00:35 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:00:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:00:35 INFO - Running setup.py (path:/tmp/pip-DtGi5h-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozcrash 16:00:35 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:00:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:00:35 INFO - Running setup.py (path:/tmp/pip-CZTDo7-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdebug 16:00:35 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:00:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:00:35 INFO - Running setup.py (path:/tmp/pip-3PPmSu-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozdevice 16:00:35 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:00:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozfile 16:00:35 INFO - Running setup.py (path:/tmp/pip-Eplze4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozfile 16:00:35 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:00:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:00:35 INFO - Running setup.py (path:/tmp/pip-bRQFL4-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozhttpd 16:00:35 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:00:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:00:35 INFO - Running setup.py (path:/tmp/pip-kdPPdY-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinfo 16:00:35 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:00:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:00:35 INFO - Running setup.py (path:/tmp/pip-cUjaLg-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozinstall 16:00:35 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:00:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozleak 16:00:35 INFO - Running setup.py (path:/tmp/pip-_jBb4q-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozleak 16:00:35 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:00:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozlog 16:00:35 INFO - Running setup.py (path:/tmp/pip-QQW86D-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozlog 16:00:35 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:00:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:00:35 INFO - Running setup.py (path:/tmp/pip-QXrax1-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moznetwork 16:00:35 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:00:35 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:00:35 INFO - Running setup.py (path:/tmp/pip-z5SA_g-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprocess 16:00:36 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:00:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:00:36 INFO - Running setup.py (path:/tmp/pip-xmqZCl-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozprofile 16:00:36 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:00:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:00:36 INFO - Running setup.py (path:/tmp/pip-S5vDiG-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozrunner 16:00:36 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:00:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:00:36 INFO - Running setup.py (path:/tmp/pip-25VOYV-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozscreenshot 16:00:36 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:00:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/moztest 16:00:36 INFO - Running setup.py (path:/tmp/pip-uKpyqe-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/moztest 16:00:36 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:00:36 INFO - Unpacking /builds/slave/test-pgo/build/tests/mozbase/mozversion 16:00:36 INFO - Running setup.py (path:/tmp/pip-onraKM-build/setup.py) egg_info for package from file:///builds/slave/test-pgo/build/tests/mozbase/mozversion 16:00:36 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:00:36 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:00:36 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:00:36 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:00:36 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:00:36 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:00:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test-pgo/build/tests/config/mozbase_requirements.txt (line 10)) 16:00:36 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:00:36 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:00:36 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:00:36 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:00:36 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:00:36 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:00:38 INFO - Downloading blessings-1.6.tar.gz 16:00:38 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/blessings/setup.py) egg_info for package blessings 16:00:38 INFO - Installing collected packages: blessings 16:00:38 INFO - Running setup.py install for blessings 16:00:38 INFO - Successfully installed blessings 16:00:38 INFO - Cleaning up... 16:00:38 INFO - Return code: 0 16:00:38 INFO - Done creating virtualenv /builds/slave/test-pgo/build/venv. 16:00:38 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:00:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:00:38 INFO - Reading from file tmpfile_stdout 16:00:38 INFO - Current package versions: 16:00:38 INFO - argparse == 1.2.1 16:00:38 INFO - blessings == 1.6 16:00:38 INFO - blobuploader == 1.2.4 16:00:38 INFO - docopt == 0.6.1 16:00:38 INFO - manifestparser == 1.1 16:00:38 INFO - mozInstall == 1.12 16:00:38 INFO - mozcrash == 0.16 16:00:38 INFO - mozdebug == 0.1 16:00:38 INFO - mozdevice == 0.47 16:00:38 INFO - mozfile == 1.2 16:00:38 INFO - mozhttpd == 0.7 16:00:38 INFO - mozinfo == 0.9 16:00:38 INFO - mozleak == 0.1 16:00:38 INFO - mozlog == 3.1 16:00:38 INFO - moznetwork == 0.27 16:00:38 INFO - mozprocess == 0.22 16:00:38 INFO - mozprofile == 0.27 16:00:38 INFO - mozrunner == 6.11 16:00:38 INFO - mozscreenshot == 0.1 16:00:38 INFO - mozsystemmonitor == 0.0 16:00:38 INFO - moztest == 0.7 16:00:38 INFO - mozversion == 1.4 16:00:38 INFO - psutil == 3.1.1 16:00:38 INFO - requests == 1.2.3 16:00:38 INFO - wsgiref == 0.1.2 16:00:38 INFO - Installing None into virtualenv /builds/slave/test-pgo/build/venv 16:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:38 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced87ddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2425350>, '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 0x2426250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24266e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', '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:00:38 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test-pgo/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test-pgo/build/tests/talos 16:00:38 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 -r /builds/slave/test-pgo/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:00:38 INFO - Using env: {'DISPLAY': ':0', 16:00:38 INFO - 'HOME': '/home/cltbld', 16:00:38 INFO - 'LANG': 'en_US.UTF-8', 16:00:38 INFO - 'LANGUAGE': 'en_US:en', 16:00:38 INFO - 'LOGNAME': 'cltbld', 16:00:38 INFO - 'MAIL': '/var/mail/cltbld', 16:00:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:38 INFO - 'MOZ_NO_REMOTE': '1', 16:00:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:38 INFO - 'NO_EM_RESTART': '1', 16:00:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:38 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:38 INFO - 'SHELL': '/bin/bash', 16:00:38 INFO - 'SHLVL': '1', 16:00:38 INFO - 'TERM': 'linux', 16:00:38 INFO - 'TMOUT': '86400', 16:00:38 INFO - 'USER': 'cltbld', 16:00:38 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', 16:00:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:38 INFO - '_': '/tools/buildbot/bin/python'} 16:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:39 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:00:39 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:00:39 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:00:39 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:00:39 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:00:39 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:00:39 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:00:39 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:00:39 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:00:39 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:00:39 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:00:39 INFO - Cleaning up... 16:00:39 INFO - Return code: 0 16:00:39 INFO - Installing jsonschema into virtualenv /builds/slave/test-pgo/build/venv 16:00:39 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:00:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:39 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:00:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:39 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced87ddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2425350>, '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 0x2426250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24266e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', '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:00:39 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test-pgo/build 16:00:39 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:00:39 INFO - Using env: {'DISPLAY': ':0', 16:00:39 INFO - 'HOME': '/home/cltbld', 16:00:39 INFO - 'LANG': 'en_US.UTF-8', 16:00:39 INFO - 'LANGUAGE': 'en_US:en', 16:00:39 INFO - 'LOGNAME': 'cltbld', 16:00:39 INFO - 'MAIL': '/var/mail/cltbld', 16:00:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:39 INFO - 'MOZ_NO_REMOTE': '1', 16:00:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:39 INFO - 'NO_EM_RESTART': '1', 16:00:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:39 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:39 INFO - 'SHELL': '/bin/bash', 16:00:39 INFO - 'SHLVL': '1', 16:00:39 INFO - 'TERM': 'linux', 16:00:39 INFO - 'TMOUT': '86400', 16:00:39 INFO - 'USER': 'cltbld', 16:00:39 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', 16:00:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:39 INFO - '_': '/tools/buildbot/bin/python'} 16:00:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:39 INFO - Downloading/unpacking jsonschema 16:00:39 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:00:39 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:00:39 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:00:39 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:00:39 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:00:39 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:00:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:00:41 INFO - Downloading/unpacking functools32 (from jsonschema) 16:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:41 INFO - Downloading functools32-3.2.3-2.tar.gz 16:00:41 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/functools32/setup.py) egg_info for package functools32 16:00:41 INFO - warning: no files found matching '*.txt' 16:00:41 INFO - no previously-included directories found matching 'build' 16:00:41 INFO - no previously-included directories found matching 'dist' 16:00:41 INFO - no previously-included directories found matching '.git*' 16:00:41 INFO - Installing collected packages: jsonschema, functools32 16:00:41 INFO - Running setup.py install for functools32 16:00:41 INFO - warning: no files found matching '*.txt' 16:00:41 INFO - no previously-included directories found matching 'build' 16:00:41 INFO - no previously-included directories found matching 'dist' 16:00:41 INFO - no previously-included directories found matching '.git*' 16:00:41 INFO - Successfully installed jsonschema functools32 16:00:41 INFO - Cleaning up... 16:00:41 INFO - Return code: 0 16:00:41 INFO - Installing flake8 into virtualenv /builds/slave/test-pgo/build/venv 16:00:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:00:41 INFO - retry: Calling run_command with args: [['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fced87ddf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22c98a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2396600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2425350>, '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 0x2426250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24266e0>, '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': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', '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:00:41 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test-pgo/build 16:00:41 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:00:41 INFO - Using env: {'DISPLAY': ':0', 16:00:41 INFO - 'HOME': '/home/cltbld', 16:00:41 INFO - 'LANG': 'en_US.UTF-8', 16:00:41 INFO - 'LANGUAGE': 'en_US:en', 16:00:41 INFO - 'LOGNAME': 'cltbld', 16:00:41 INFO - 'MAIL': '/var/mail/cltbld', 16:00:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:41 INFO - 'MOZ_NO_REMOTE': '1', 16:00:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:00:41 INFO - 'NO_EM_RESTART': '1', 16:00:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:00:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:00:41 INFO - 'PWD': '/builds/slave/test-pgo', 16:00:41 INFO - 'SHELL': '/bin/bash', 16:00:41 INFO - 'SHLVL': '1', 16:00:41 INFO - 'TERM': 'linux', 16:00:41 INFO - 'TMOUT': '86400', 16:00:41 INFO - 'USER': 'cltbld', 16:00:41 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', 16:00:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:41 INFO - '_': '/tools/buildbot/bin/python'} 16:00:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:41 INFO - Downloading/unpacking flake8 16:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:43 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:00:43 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:43 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:00:43 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:43 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:00:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:44 INFO - Running setup.py (path:/builds/slave/test-pgo/build/venv/build/pep8/setup.py) egg_info for package pep8 16:00:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:00:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:00:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:00:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:00:44 INFO - no previously-included directories found matching 'docs/_build' 16:00:44 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:00:44 INFO - Running setup.py install for pep8 16:00:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:00:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:00:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:00:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:00:44 INFO - no previously-included directories found matching 'docs/_build' 16:00:44 INFO - Installing pep8 script to /builds/slave/test-pgo/build/venv/bin 16:00:44 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:00:44 INFO - Cleaning up... 16:00:44 INFO - Return code: 0 16:00:44 INFO - Running post-action listener: _resource_record_post_action 16:00:44 INFO - Running post-action listener: _start_resource_monitoring 16:00:44 INFO - Starting resource monitoring. 16:00:44 INFO - ##### 16:00:44 INFO - ##### Running install step. 16:00:44 INFO - ##### 16:00:44 INFO - Running pre-action listener: _resource_record_pre_action 16:00:44 INFO - Running main action method: install 16:00:44 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/pip', 'freeze'] 16:00:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/pip freeze 16:00:44 INFO - Reading from file tmpfile_stdout 16:00:44 INFO - Detecting whether we're running mozinstall >=1.0... 16:00:44 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '-h'] 16:00:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall -h 16:00:44 INFO - Reading from file tmpfile_stdout 16:00:44 INFO - Output received: 16:00:44 INFO - Usage: mozinstall [options] installer 16:00:44 INFO - Options: 16:00:44 INFO - -h, --help show this help message and exit 16:00:44 INFO - -d DEST, --destination=DEST 16:00:44 INFO - Directory to install application into. [default: 16:00:44 INFO - "/builds/slave/test-pgo"] 16:00:44 INFO - --app=APP Application being installed. [default: firefox] 16:00:44 INFO - mkdir: /builds/slave/test-pgo/build/application 16:00:44 INFO - Getting output from command: ['/builds/slave/test-pgo/build/venv/bin/mozinstall', '/builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test-pgo/build/application'] 16:00:44 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/mozinstall /builds/slave/test-pgo/build/firefox-45.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test-pgo/build/application 16:00:59 INFO - Reading from file tmpfile_stdout 16:00:59 INFO - Output received: 16:00:59 INFO - /builds/slave/test-pgo/build/application/firefox/firefox 16:00:59 INFO - Running post-action listener: _resource_record_post_action 16:00:59 INFO - ##### 16:00:59 INFO - ##### Running run-tests step. 16:00:59 INFO - ##### 16:00:59 INFO - Running pre-action listener: _resource_record_pre_action 16:00:59 INFO - Running main action method: run_tests 16:00:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '--version'] 16:00:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python --version 16:00:59 INFO - Python 2.7.3 16:00:59 INFO - Return code: 0 16:00:59 INFO - grabbing minidump binary from tooltool 16:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:00:59 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 0x2425350>, '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 0x2426250>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24266e0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test-pgo/build', 'privileged': False}, attempt #1 16:00:59 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:00:59 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:00:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:00:59 INFO - Return code: 0 16:00:59 INFO - Chmoding /builds/slave/test-pgo/build/linux64-minidump_stackwalk to 0755 16:00:59 INFO - mkdir: /builds/slave/test-pgo/build/blobber_upload_dir 16:00:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test-pgo/build/blobber_upload_dir 16:00:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test-pgo/build/linux64-minidump_stackwalk 16:00:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test-pgo/build/blobber_upload_dir 16:00:59 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/flake8', '/builds/slave/test-pgo/build/tests/talos/talos'] 16:00:59 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/flake8 /builds/slave/test-pgo/build/tests/talos/talos 16:01:00 INFO - Return code: 0 16:01:00 INFO - Running command: ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] in /builds/slave/test-pgo/build 16:01:00 INFO - Copy/paste: /builds/slave/test-pgo/build/venv/bin/python /builds/slave/test-pgo/build/tests/talos/talos/run_tests.py --debug --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test-pgo/build/application/firefox/firefox --symbolsPath http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --webServer localhost 16:01:00 INFO - Using env: {'DISPLAY': ':0', 16:01:00 INFO - 'HOME': '/home/cltbld', 16:01:00 INFO - 'LANG': 'en_US.UTF-8', 16:01:00 INFO - 'LANGUAGE': 'en_US:en', 16:01:00 INFO - 'LOGNAME': 'cltbld', 16:01:00 INFO - 'MAIL': '/var/mail/cltbld', 16:01:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:01:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test-pgo/build/linux64-minidump_stackwalk', 16:01:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:01:00 INFO - 'MOZ_NO_REMOTE': '1', 16:01:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test-pgo/build/blobber_upload_dir', 16:01:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:01:00 INFO - 'NO_EM_RESTART': '1', 16:01:00 INFO - 'PATH': '/builds/slave/test-pgo/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:01:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test-pgo/buildprops.json', 16:01:00 INFO - 'PWD': '/builds/slave/test-pgo', 16:01:00 INFO - 'PYTHONPATH': '/builds/slave/test-pgo/build/tests/talos', 16:01:00 INFO - 'SHELL': '/bin/bash', 16:01:00 INFO - 'SHLVL': '1', 16:01:00 INFO - 'TERM': 'linux', 16:01:00 INFO - 'TMOUT': '86400', 16:01:00 INFO - 'USER': 'cltbld', 16:01:00 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499', 16:01:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:01:00 INFO - '_': '/tools/buildbot/bin/python'} 16:01:00 INFO - Calling ['/builds/slave/test-pgo/build/venv/bin/python', '/builds/slave/test-pgo/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test-pgo/build/application/firefox/firefox', '--symbolsPath', 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--webServer', 'localhost'] with output_timeout 3600 16:01:00 INFO - mozversion INFO | application_buildid: 20160203133327 16:01:00 INFO - mozversion INFO | application_changeset: e29130ca7a645075902cc9d666995851f273870b 16:01:00 INFO - mozversion INFO | application_display_name: Nightly 16:01:00 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:01:00 INFO - mozversion INFO | application_name: Firefox 16:01:00 INFO - mozversion INFO | application_remotingname: firefox 16:01:00 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:01:00 INFO - mozversion INFO | application_vendor: Mozilla 16:01:00 INFO - mozversion INFO | application_version: 45.0 16:01:00 INFO - mozversion INFO | platform_buildid: 20160203133327 16:01:00 INFO - mozversion INFO | platform_changeset: e29130ca7a645075902cc9d666995851f273870b 16:01:00 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:01:00 INFO - mozversion INFO | platform_version: 45.0 16:01:00 INFO - 2016-02-03 16:01:00,613 DEBUG : using testdate: 1454544060 16:01:00 INFO - 2016-02-03 16:01:00,613 DEBUG : actual date: 1454544060 16:01:00 INFO - 2016-02-03 16:01:00,624 INFO : starting webserver on 'localhost:35508' 16:01:00 INFO - 2016-02-03 16:01:00,626 INFO : Starting test suite talos-linux64-ix-046 16:01:00 INFO - 2016-02-03 16:01:00,626 INFO : Starting test dromaeo_css 16:01:00 INFO - 2016-02-03 16:01:00,626 DEBUG : operating with platform_type : linux_ 16:01:00 INFO - 2016-02-03 16:01:00,626 INFO : Initialising browser for dromaeo_css test... 16:01:00 INFO - 2016-02-03 16:01:00,639 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpM6Q5vs/profile http://localhost:35508/getInfo.html 16:01:04 INFO - 2016-02-03 16:01:04,839 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:01:04 INFO - 2016-02-03 16:01:04,840 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:01:04 INFO - 2016-02-03 16:01:04,840 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:01:04 INFO - 2016-02-03 16:01:04,840 DEBUG : BROWSER_OUTPUT: __metrics 16:01:04 INFO - 2016-02-03 16:01:04,997 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:01:05 INFO - 2016-02-03 16:01:05,708 INFO : Browser initialized. 16:01:05 INFO - 2016-02-03 16:01:05,709 INFO : Running cycle 1/1 for dromaeo_css test... 16:01:05 INFO - 2016-02-03 16:01:05,709 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpM6Q5vs/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:01:17 INFO - 2016-02-03 16:01:17,523 DEBUG : BROWSER_OUTPUT: RSS: Main: 134680576 16:01:17 INFO - 2016-02-03 16:01:17,523 DEBUG : BROWSER_OUTPUT: 16:02:08 INFO - 2016-02-03 16:02:08,273 DEBUG : BROWSER_OUTPUT: 1454544128272 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:04:16 INFO - 2016-02-03 16:04:16,555 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35508/tests/dromaeo/cssquery-ext.html) 16:04:17 INFO - 2016-02-03 16:04:17,560 DEBUG : BROWSER_OUTPUT: RSS: Main: 235913216 16:04:17 INFO - 2016-02-03 16:04:17,560 DEBUG : BROWSER_OUTPUT: 16:07:19 INFO - 2016-02-03 16:07:19,616 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/dromaeo/cssquery-ext.html (next: http://localhost:35508/tests/dromaeo/cssquery-jquery.html) 16:07:20 INFO - 2016-02-03 16:07:20,189 DEBUG : BROWSER_OUTPUT: RSS: Main: 215597056 16:07:20 INFO - 2016-02-03 16:07:20,190 DEBUG : BROWSER_OUTPUT: 16:10:21 INFO - 2016-02-03 16:10:21,987 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35508/tests/dromaeo/cssquery-mootools.html) 16:10:22 INFO - 2016-02-03 16:10:22,548 DEBUG : BROWSER_OUTPUT: RSS: Main: 214749184 16:10:22 INFO - 2016-02-03 16:10:22,549 DEBUG : BROWSER_OUTPUT: 16:13:24 INFO - 2016-02-03 16:13:24,205 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35508/tests/dromaeo/cssquery-prototype.html) 16:13:24 INFO - 2016-02-03 16:13:24,772 DEBUG : BROWSER_OUTPUT: RSS: Main: 197742592 16:13:24 INFO - 2016-02-03 16:13:24,773 DEBUG : BROWSER_OUTPUT: 16:16:27 INFO - 2016-02-03 16:16:27,101 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35508/tests/dromaeo/cssquery-yui.html) 16:16:27 INFO - 2016-02-03 16:16:27,668 DEBUG : BROWSER_OUTPUT: RSS: Main: 198356992 16:16:27 INFO - 2016-02-03 16:16:27,668 DEBUG : BROWSER_OUTPUT: 16:19:29 INFO - 2016-02-03 16:19:29,642 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/dromaeo/cssquery-yui.html (next: http://localhost:35508/tests/dromaeo/cssquery-dojo.html) 16:19:29 INFO - 2016-02-03 16:19:29,892 DEBUG : BROWSER_OUTPUT: RSS: Main: 215171072 16:19:29 INFO - 2016-02-03 16:19:29,893 DEBUG : BROWSER_OUTPUT: 16:19:29 INFO - 2016-02-03 16:19:29,893 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:19:29 INFO - 2016-02-03 16:19:29,893 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:19:29 INFO - 2016-02-03 16:19:29,893 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:19:29 INFO - 2016-02-03 16:19:29,893 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:19:29 INFO - 2016-02-03 16:19:29,893 DEBUG : BROWSER_OUTPUT: |0;dojo.html;3322.4351860183765;3384.4923261510785;3413.880514182004;3414.5731783527044;3429.2112814052775;5517.724113794309;5577.665340079598;5595.972020139899;5596.160575913615;5607.523360514356;5591.161325801122;5594.104943209088;5612.102063669803;5626.774929002835;5635.436456354362;4909.116359055369;4997.475265097167;5056.292119103328;5090.923636145442;5144.6655967362;5026.497350264981;5060.6963582185;5083.110455670261;5087.414947281073;5089.46560611136;4987.276844857493;5071.797128114871;5073.695578265298;5100.030994111125;5114.23286507025;5002.924956125625;5014.222795466709;5021.849344519688;5022.623303252252;5054.696718196883;4848.224284114524;4885.828995985159;4993.989455359865;4998.57512111471;5056.671316364462;5214.009338225763;5269.973650131725;5284.894302113975;5286.9471305287225;5304.761285742113;5839.941600583982;5864.824055278348;5885.117232415129;5888.234529511136;5897.8230653080045;5738.6556806591325;5836.124581312794;5852.853678658025;5906.5570082244;5938.7624495020145;5638.097904335327;5658.207850900877;5658.7170641467765;5659.490645841853;5659.971700141473;6076.696165191723;6082.482988945901;6098.451139397475;6100.0849872519475;6103.298120716175;5552.52803511706;5673.063944449203;5845.152452894392;5915.349311575723;5949.048152295699;5913.674747888907;5923.911141332797;5933.199018132583;5942.37605051472;5950.137230101611;5845.386234445322;5852.122181672783;5861.472467477947;5885.293764748258;5892.469677729025;5723.856903577361;5799.33307669615;5839.795607153729;5930.792422265286;5980.13288073227;4542.091581683677;4568.680192386568;4577.908441831211;4578.336141259499;4582.88542786433;3970.146418520032;4085.9591404086173;4086;4086.1010577672864;4099.938500922489;5629.774809007676;5691.03252447085;5718.971405142947;5721.771129154788;5760.711964401763;5999.520038397005;6013.6993150342305;6017.939820601826;6022.36765139654;6023.969880150571;5529.060059789846;5626.493615574618;5754.971225143931;5769.971150144306;5770.2498675171955;4940.555350018516;5020.523050310215;5025.296458495814;5028.874278143076;5050.696958182521;6213.1922850029205;6228.719707613168;6257.061440783918;6265.028920517327;6274.058891166362;5666.660000399989;5761.279840019997;5927.170196172626;6028.939710602838;6041.909371359346;6103.919453433995;6104.324103667003;6109.247962423028;6114.236541168763;6212;5747.48272655463;5792.4497172768515;5793.536517078539;5840.94159058421;5849.268841394825;5686.891056244158;5788.536917046542;5804.042333015005;5938.732757025858;5947.04847224442;5742.856428589319;5884.852878678067;5890.14592884038;5899.764009439575;5901.114832775118;5838.737256823538;5869.706514674163;5891.204687367322;5894.882102357843;5896.764129434946;5496.450354964471;5546.362168350692;5610.382857885547;5610.382857885711;5623.8594035149445;5868.970655146697;5882.676452794966;5887.5289976802605;5895.02732049207;5903.49820265265;5556.333240011225;5707.315122185364;5740.913886291793;5765.94234057654;5768.50967667753 16:19:29 INFO - 2016-02-03 16:19:29,894 DEBUG : BROWSER_OUTPUT: |1;ext.html;615.9322474527801;656.7274581048864;659.1990731261523;660.1187414801242;711.814928118689;32154.67845321549;33242;38186.23627527451;38536.61463385366;38614;44619;45323.320150197775;45452.3182152268;45497.77251113748;45572.3164152538;8205.95897020514;9064.501452420114;9228.307876909224;9229.538523073854;9237.676681316156;8392.790180245482;8496;10730.409827459525;10955.56177752889;10974.067204287645;10734.624288149917;10830.783384332308;10841.945790271038;10846;10846.078083362923;10512.316699414514;10556.736081597945;10578.735531611694;10592.099671527929;10601.893981060168;10615.310004849738;10734.141268698486;10754.569817207303;10763.138948884149;10776.245662803607;41999;42350.36474452886;42455.36316955248;42525.14949700989;42546.1490770186;3624.800635965021;3789.7157713171623;4235.57644235577;4483;4550;32376.83811580927;33005;33006.669933300596;33080.669193308;33157.3368532628;4302.161078589676;4309.59058889405;4337.371081193241;4415.403920470728;4419.558044195587;293885.5305723479;294392;297395;297574;299609.99999999785;63255.683721581096;63308;63527;63681.36318636754;64601.676991615685;77096;78744.60627696825;79021.60489197633;79087.20912790914;79102.20897790948;18275.817241827677;18331.908340458478;18430.078496075144;18485.7227141593;18508;14195.21926294043;14283.642908927151;14291.714165716627;14292.64219899108;14300.928495357457;21616.13535458564;21865.344039678515;21877;21880.343589692333;21905.904704765017;7859.882101768478;8211;8279.668813247403;8289.626966786509;8312.625931832961;954.2842867849113;978.1050338939932;985.1822986920838;1143.891330323618;1234.2471092633486;25871.870640646677;26052;26114.738852611612;26236.344091397867;26557.203283901516;1112.3492756737237;1127.5489804078434;1135.3641960502146;1150.511032811052;1163.5811108001112;13370.799438008438;14974.907796700982;15898.364065437485;15954.122523261332;16122.004357298429;333942;337271;338650;338687;341211;59365.40634593685;59386.40613593809;59629.10556341658;59758.70120649456;59821.70089149613;1409.0629731228823;1499.317810396267;1515.8787297016213;1536.6004838742008;1624.5370069530363;27175.86412067927;28627;28642.570361444177;29491;29531.261718457212;45480.090398192515;45918.31122533231;45943.31085033794;45952.31071533997;45965.77017114893;455150;461287;464245;464504;464719;35463;35778.821105894305;35808.10479737923;35929.10177245485;36271;47351.28973065476;47634;48035.75982120067;48343.51656483531;48427.75786121047;39914.60085399109;40135;40840;41211;41886.581134188265;37787.62212377841;38295.6170438292;39038.80480597693;40588.39117413182;41001.97495062647;34549;34630.82684586561;35039;35112.64887351094;35217.64782352144;31209.53185702171;31355.21611959628;34422.82788586041;34431.65568344285;34897.82551087228;26207;26713.866430667724;26814.86592567025;26825.195244142706;27053.72946270512 16:19:29 INFO - 2016-02-03 16:19:29,896 DEBUG : BROWSER_OUTPUT: |2;jquery.html;383.77165586476036;385.19494257002856;386.5245747730291;388.35145307336745;388.63662475585323;15902.84097159028;16342.509724708249;16972.74540881886;18501.35245266416;18621.348252811153;17694.911525442356;18020.18909149088;18038.81961180388;18374.816251837477;18762.812371876276;5996.850078748033;6155.19982402287;6167.013277875541;6180.042093475518;6181.134641150243;4534.79593418297;4717.764111794414;6084.665343406111;6563.638999855006;6820;6626.5030122740745;6628.734850605994;6683.966580167093;6787.76242831501;6805.081314022593;6731.798046058601;6736.5958042517605;6752.426043786235;6774.288699686511;6776.457883369319;6434.163558737381;6504.5121615879;6518.152640156749;6578.3421657834315;6584.9012264816065;22388.21641242565;22908.19821306263;23067.192648257398;23088.884555577282;23631;2608.4652646207483;2632.7498887605648;2704.013035242142;2708.7155848635807;2890.985545072282;9705.320627556073;9709.223262139012;9994.750131246705;10111;14961.251937403087;1940.2433051110079;1940.8156225158586;1973.220577871747;2016.4253187841396;2044.509317763741;65461.67269163624;65842.3415765841;66937.3306266936;67161.66419167921;68227.65886170587;11235.157363197794;11271.54913803455;11312.660620181408;11346.602868899714;11384.203105782515;14985.475508357154;15065.849341506664;15091.773623395658;15105.169215693022;15109.697806043818;8433.19884610961;8494.660213591511;8575.614097365633;8659.6103175356;8661.177188167114;8548.529830859363;8566.657333706706;8579.227869491775;8607.440516366416;8659.177378149066;4551.840685575989;4734.668573199843;4753.976230118897;4851.611871050308;4874.610031197567;1747.2672285478732;1838.779346478431;1883.9246430142641;1897.848172146225;1913.4929243750416;906.4334790755778;939.2861425316809;1012.6050840172338;1015.137133436573;1039.3348257115451;14737;16777.580560486087;17332.3067077318;17712.911435443;17757.911210443865;990.2474119669104;992.9772334495481;993.965211217604;1025.9538320775434;1031.3347890610664;6246.156768836149;6509.121268628769;9569.61721531145;10054.798904021878;10254.23093267993;437066;442540;474391;539369;539431.51421214;88475;89213;90898;90971.5451422752;91095.54452227829;531.0573731626394;538.3082738680855;539.6573176033247;540.3623724005711;541.0882662713341;11064.39145846954;11427.142964277878;11477.311361318347;11642.068634509389;15946.36214551405;37822.054448639006;37981.810090949366;38950;39293;39865.601343986185;49078;50390.748046261004;50545.74727126341;52325.215121772446;52628.47371526236;10192.286539942133;10346.37921724699;10366.067053964878;10373.948130259603;10643.627473038407;17807.376741813976;18097.095145242947;18138.546536336697;18363.54091147732;18387.816121838612;14499.130052196902;14757.778633320295;14938.178400188093;14943.701125977203;15004.099754014795;12554.121211515107;12703.80944285855;15299.847001529843;15727.213639318217;15829.208539572744;14878.404863805326;15320.770188446959;15345.23273836326;15370.692586148438;15823.208839557747;10546.419946902994;10581.418022008864;10718.89281107179;10762.085222756154;10861.131109511378;11174.720631984266;14493.347799348843;14742.48401305992;15115.395384184509;15158.924205378902 16:19:29 INFO - 2016-02-03 16:19:29,897 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1532.555558887922;1534.0258935575905;1729.4984454508194;1815.4099917526803;1842.548575598978;7185.317394847488;7568.5458872467625;7576.583287919162;7583.203763604824;7586.203448637896;7650.8852367214395;7731;7738.1488036316105;7745.806354841132;7747.845043099149;2252.7409347925036;2272.1025195047987;2312.271634435156;2314.837961342707;2316.2124877541632;2344.3553022918663;2372.632242002484;2372.810175185981;2372.881355932205;2373.8338316317777;1989.094961792381;2023.5447024419534;2204.801567858892;2253.9436514087115;2293.86236825791;2240.1599400224914;2253.042456955787;2256.3907744908947;2269.9886500567472;2272.6477396003647;2219.1567204462362;2259.638457846737;2279.726432828055;2284.2690339091496;2284.371797755614;6652.667366631649;6831.931680683179;7220.74727384539;7431.814204644873;7437.293457121565;4745.9525404745855;4816.975915120402;4823.5176482351835;4826.34844296019;4828.903421931542;3070.47801873682;3076.077176846937;3103.2707313781243;3114.252579380955;3167.7307428868576;977.951102444882;997.7555498902743;1007.450939238117;1031.6750223679517;1062.8352605346183;10180.541875615541;10203.89796102037;10324.07083362501;10330.948345258226;10358.22313326496;4018;4107.938380924288;4108.281050816096;4118.794060296973;4143.91712165755;8727.65089396417;8752.8687069694;8772.868406973901;8815.118488151262;8823.647054117764;4693.55411235932;4697;4700.224462963641;4706.223473126965;4713.033828065239;4331.393604895317;4348.130373925228;4350.216960946997;4357.477102747691;4360.171567402247;3475.721942244665;3509.3332266869215;3521.4189658706537;3522.6125126236093;3551.183227857607;1084.159776173456;1100.6422912553453;1102.8841971592867;1114.3815182573587;1120.551779288275;1123.1407972900738;1149.5516748468103;1151.8963293303475;1318.4660212613908;1426.657602175491;7363.48455608113;7415.851682966419;7438.218987006395;7478.140013898363;7497.062867141607;524.8976449592293;536.1796451429319;536.7826030457671;539.8623351045459;539.870431096534;5362.785488580491;5907.822765317047;5987.5808693391045;5996.4603185713495;5997.640141591519;8744.300455963636;9356.391834530734;9485;9514.952425237969;9521.095495927875;9019.594118264562;9209.907900921038;9233.353665243503;9249.398789078688;9406.623735050658;7318.414526837841;7326.267373262738;7338.522996005136;7350.191478937312;7444.8883266751045;7181.856362872609;7199;7226;7297.963510182628;7298.4891057625455;8365.623546940524;8543.914560854311;8607.741767746978;8654.697085602225;8663.870041949249;8587.355948303777;8609.82780344402;8783.989841168092;8795.692150774692;8801.559922004002;6377;6448.387403196689;6448.484121270193;6454.806355809334;6458.160439142881;7239.167495738057;7244.094488188976;7286.599237041802;7337.04618399626;7354.411647068328;6844.828879278058;6868.278830722902;6882.070920425878;6938.965305173442;6960.617166055916;6615.206175259;6657.866842663023;6774.695138718868;6777.695003724745;6858.245592984668;6807.591544507147;6824.692888820115;6838.452923766186;6841.897371539331;6844.760433384808;6460.3862633049785;6505.284418714033;6506.3493650634555;6506.479481641362;6512.3162067982175;6261.906071408841;6391.10524526576;6407.199100112486;6408.455281301141;6411.198600174978 16:19:29 INFO - 2016-02-03 16:19:29,898 DEBUG : BROWSER_OUTPUT: |4;prototype.html;346.7676656640051;348.1574589493426;351.2588438394987;352.24092081564237;356.18611472784676;5958.970205148974;6004.669743164119;6008.24896887889;6055.0311950088;6068.120122582234;5697.088465845465;5778.768849246025;5905;5952.2262105926275;5981.880362392761;4630.21286381316;4632.745199014053;4642.860714178563;4652.534746525354;4660.091282199973;4499.280115181572;4604.355390245369;4615.307703844416;4649.44206695197;4660.930086048712;4491.101779644085;4533.5013148553635;4569.771511424432;4604.171249175147;4623.9768801156115;4565.406497155382;4573.062522182944;4576.816927322903;4600.148972440093;4613.63090952723;4048.696347773899;4064.8983775405554;4071.124708187724;4071.5114186297537;4072.2466343726505;5604.915926261109;5669.83768327496;5679.858003549903;5680.744366503517;5687.857803554903;5358.062339090644;5358.249845021661;5366.409694933554;5367.677939323614;5376.166694162411;6394.904076438857;6396.424321811013;6401.807945761635;6404.263509696398;6405.647689377082;4439.489458712257;4446.021875187454;4451.666125040635;4457.531959144308;4457.598816106533;6493.285738568754;6536.3463653634735;6598.637074960875;6609.272979972199;6615.735370585171;4557.931631025537;4561.749103799257;4561.749103799323;4562.087582483517;4562.338460923214;4598.149342371672;4612.331211974245;4616.146012987608;4618.584327410482;4623.329617205553;5317.787288508416;5324.440933701983;5325.973370133124;5329.520343169133;5330.573554115739;6211.627302361872;6342.841428964312;6349.746010159634;6365.96817015912;6403.231612206565;1615.6203292226317;1635.4602981016237;1635.8037035555571;1636.9345226191058;1637.7379619260862;1093.8195197792434;1096.8628921384827;1102.5204036244259;1118.4631376938953;1121.0135081128556;6313.147725057151;6334.588251763621;6336.20797400325;6340.714667839959;6345.714442850082;6400.423961843457;6533.673316334164;6576.506761992774;6584.539082264196;6595.0766892635065;6205.720742566596;6282.277538083179;6328.430441260217;6328.746850125944;6394.488440924715;3530.5763308402643;3553.8578456861437;3575.4994300797907;3589.4974703541525;3591.44332628443;6820.147481564804;6956.130483689539;6959.338862808025;6967.512274140761;6972.895406568906;6805.421539169127;6810.114685091005;6821.556598821061;6836.213835408892;6838.316168383122;6932.861342773116;6957.199922008933;6957.373836354753;6969.7909062728195;6971.6862741176765;6548.6655735761005;6585.703643336157;6632.767853125118;6638.236602790741;6645.269020407654;6547.377999090078;6547.9672601636685;6559.901601476077;6562.146920900253;6564.310747371648;6942.687579059056;6943.340382663639;6944.965275173592;6953.895691564731;6954.130733658293;4604.470485894165;4657.347971284091;4666.696664716783;4668.53314668544;4671.579557839675;6578.04618330349;6612.404883560503;6634.203895532567;6641.767538136142;6643.069970204272;6455.322191169859;6466.418022378009;6466.80364132637;6468.644224567505;6481.995290729945;6302.369763023844;6455.096286519797;6459.806205813833;6461.321561236003;6463.967680161569;6343.9682801585695;6375.362463753588;6379.075034120119;6384.553081284265;6386.61680299165;6471.352864713679;6499.837504062436;6506.512011599244;6512.544121911421;6517.804465866032;6258.092576576462;6349.07938348946;6364.681765911593;6371;6373.904391434225 16:19:29 INFO - 2016-02-03 16:19:29,899 DEBUG : BROWSER_OUTPUT: |5;yui.html;883.3860466975455;896.3143195455482;904.4076130134762;916.5279880861358;917.8026724254285;14310.642233944143;14911.701765964674;14924.701505969873;14951.775723364144;14959.700805983874;13992.79010814836;15603.531894043197;15745.84254157458;15752.212389380542;15758.84241157588;752.1388010727713;757.7764559454946;913.2511340700628;929.3355250995537;930.2325581395343;915.2860768600502;920.3695468604028;927.3462209142572;927.3925578745932;927.6938610258599;964.1611797735944;970.034815358719;970.6942313171364;972.3388096094653;972.6158167523823;960.538941308169;963.1379914976092;964.8600952861796;965.025324422335;970.815545046439;704.3555147040452;708.943284537241;712.5546533416615;715.4491041897761;717.7057406463377;13639;13759.656008599764;13787.86212137886;13805.79291310621;13826.654333641638;11590.710232244177;12091.153619246656;12274.38628068593;12662.176958497761;13214.141080829715;10066.899331006669;11027.062699670538;11112.944435277852;11315.094792416587;11373.829392559119;3788.4696142540065;3796.2027974125467;3839.9424008639894;3873.767573945572;3882.281777871103;53185.73407132978;53360.46639533594;53897;54116.45883541153;54304;10729.517171727291;10837.133029357632;10864.619738309122;10881.673549793519;11062.115030797517;19182.232710691696;19375.51561210981;19383.709244361347;19400.902995484932;19419.4174174775;7541.5475071495885;7666.310032097138;7792.493487923267;7827.882581761278;8236.382271329674;4555.567221113989;4573.199690054227;4582.266837306015;4666.4633567139535;4699.224627936421;3104.5343198520404;3113.9688603113677;3131.7338026267566;3137.1529686984386;3140.8272545009786;535.6518263128919;543.6520626798851;555.328053055807;558.2407925221695;558.4499268220799;564.2692712936757;577.3809523809524;610.036142894226;610.2189197826789;641.0608458608161;7918.6436610352675;7972.880406793903;8094.27151556351;8145.633446494803;8189.426740128134;519.1900635009397;531.404826594217;553.808935917108;617.3949529461153;670.1522573943964;3870.59358767331;3878.534575850916;3908.472356231929;3927.8428862845167;3985.7904788292126;60957.08564371538;61541;61964.38035619586;62016.06975895365;62276;53385.73307133517;53926.73036634792;54047.18929216065;54138;57579.136312955336;50488.49511504911;50685.74657126691;50705;50795.492045079074;51182.48817511852;7804.336631386395;7818.609069546386;7905.32804711606;7980.600969951595;8030.397720171128;36562;36688.26623467569;37175.070623234635;37388.439173411876;37689.81155094207;45222.77388613036;45467.54532454633;48580.5141948576;48988;49146.77133071702;9477.763055923657;9526.52367381642;9682;9874.851877221703;9876.753081173028;10509.9474502627;10619.734506637087;10639.787204256027;11825.526978920747;11953.163278570417;10003.899961000589;10213;10263;10589.152867770406;10648.148148147975;9888.950555247178;9916.851247231152;10037.34757240777;10047.145992590711;10220;10147.18822494184;10492.79014419692;10700.785984280115;10767.461626918779;10800.837987430037;9059.320550958573;9280.814383712423;9305.302102342215;9517.904820951702;9537.904620953701;8943.597538110931;8999.730008099768;9044.231240344645;9069.319801015083;9258 16:19:29 INFO - 2016-02-03 16:19:29,900 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:19:29 INFO - 2016-02-03 16:19:29,900 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:19:29 INFO - 2016-02-03 16:19:29,900 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,758 16:19:29 INFO - 2016-02-03 16:19:29,900 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:19:29 INFO - 2016-02-03 16:19:29,900 DEBUG : BROWSER_OUTPUT: __startTimestamp1454545169891__endTimestamp 16:19:29 INFO - 2016-02-03 16:19:29,900 DEBUG : BROWSER_OUTPUT: 16:19:29 INFO - 2016-02-03 16:19:29,900 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:19:29 INFO - 2016-02-03 16:19:29,900 DEBUG : BROWSER_OUTPUT: Number of tests: 6 16:19:29 INFO - 2016-02-03 16:19:29,900 DEBUG : BROWSER_OUTPUT: 16:19:29 INFO - 2016-02-03 16:19:29,901 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:5509.28 Median:5723.86 stddev:609.59 (10.6%) stddev-sans-first:586.82 16:19:29 INFO - 2016-02-03 16:19:29,902 DEBUG : BROWSER_OUTPUT: Values: 3322.4 3384.5 3413.9 3414.6 3429.2 5517.7 5577.7 5596.0 5596.2 5607.5 5591.2 5594.1 5612.1 5626.8 5635.4 4909.1 4997.5 5056.3 5090.9 5144.7 5026.5 5060.7 5083.1 5087.4 5089.5 4987.3 5071.8 5073.7 5100.0 5114.2 5002.9 5014.2 5021.8 5022.6 5054.7 4848.2 4885.8 4994.0 4998.6 5056.7 5214.0 5270.0 5284.9 5286.9 5304.8 5839.9 5864.8 5885.1 5888.2 5897.8 5738.7 5836.1 5852.9 5906.6 5938.8 5638.1 5658.2 5658.7 5659.5 5660.0 6076.7 6082.5 6098.5 6100.1 6103.3 5552.5 5673.1 5845.2 5915.3 5949.0 5913.7 5923.9 5933.2 5942.4 5950.1 5845.4 5852.1 5861.5 5885.3 5892.5 5723.9 5799.3 5839.8 5930.8 5980.1 4542.1 4568.7 4577.9 4578.3 4582.9 3970.1 4086.0 4086.0 4086.1 4099.9 5629.8 5691.0 5719.0 5721.8 5760.7 5999.5 6013.7 6017.9 6022.4 6024.0 5529.1 5626.5 5755.0 5770.0 5770.2 4940.6 5020.5 5025.3 5028.9 5050.7 6213.2 6228.7 6257.1 6265.0 6274.1 5666.7 5761.3 5927.2 6028.9 6041.9 6103.9 6104.3 6109.2 6114.2 6212.0 5747.5 5792.4 5793.5 5840.9 5849.3 5686.9 5788.5 5804.0 5938.7 5947.0 5742.9 5884.9 5890.1 5899.8 5901.1 5838.7 5869.7 5891.2 5894.9 5896.8 5496.5 5546.4 5610.4 5610.4 5623.9 5869.0 5882.7 5887.5 5895.0 5903.5 5556.3 5707.3 5740.9 5765.9 5768.5 16:19:29 INFO - 2016-02-03 16:19:29,902 DEBUG : BROWSER_OUTPUT: 16:19:29 INFO - 2016-02-03 16:19:29,902 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:54348.04 Median:35165.15 stddev:98118.34 (279.0%) stddev-sans-first:98310.65 16:19:29 INFO - 2016-02-03 16:19:29,903 DEBUG : BROWSER_OUTPUT: Values: 615.9 656.7 659.2 660.1 711.8 32154.7 33242.0 38186.2 38536.6 38614.0 44619.0 45323.3 45452.3 45497.8 45572.3 8206.0 9064.5 9228.3 9229.5 9237.7 8392.8 8496.0 10730.4 10955.6 10974.1 10734.6 10830.8 10841.9 10846.0 10846.1 10512.3 10556.7 10578.7 10592.1 10601.9 10615.3 10734.1 10754.6 10763.1 10776.2 41999.0 42350.4 42455.4 42525.1 42546.1 3624.8 3789.7 4235.6 4483.0 4550.0 32376.8 33005.0 33006.7 33080.7 33157.3 4302.2 4309.6 4337.4 4415.4 4419.6 293885.5 294392.0 297395.0 297574.0 299610.0 63255.7 63308.0 63527.0 63681.4 64601.7 77096.0 78744.6 79021.6 79087.2 79102.2 18275.8 18331.9 18430.1 18485.7 18508.0 14195.2 14283.6 14291.7 14292.6 14300.9 21616.1 21865.3 21877.0 21880.3 21905.9 7859.9 8211.0 8279.7 8289.6 8312.6 954.3 978.1 985.2 1143.9 1234.2 25871.9 26052.0 26114.7 26236.3 26557.2 1112.3 1127.5 1135.4 1150.5 1163.6 13370.8 14974.9 15898.4 15954.1 16122.0 333942.0 337271.0 338650.0 338687.0 341211.0 59365.4 59386.4 59629.1 59758.7 59821.7 1409.1 1499.3 1515.9 1536.6 1624.5 27175.9 28627.0 28642.6 29491.0 29531.3 45480.1 45918.3 45943.3 45952.3 45965.8 455150.0 461287.0 464245.0 464504.0 464719.0 35463.0 35778.8 35808.1 35929.1 36271.0 47351.3 47634.0 48035.8 48343.5 48427.8 39914.6 40135.0 40840.0 41211.0 41886.6 37787.6 38295.6 39038.8 40588.4 41002.0 34549.0 34630.8 35039.0 35112.6 35217.6 31209.5 31355.2 34422.8 34431.7 34897.8 26207.0 26713.9 26814.9 26825.2 27053.7 16:19:29 INFO - 2016-02-03 16:19:29,903 DEBUG : BROWSER_OUTPUT: 16:19:29 INFO - 2016-02-03 16:19:29,903 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:28551.29 Median:2668.38 stddev:80165.40 (3004.3%) stddev-sans-first:80362.39 16:19:29 INFO - 2016-02-03 16:19:29,904 DEBUG : BROWSER_OUTPUT: Values: 383.8 385.2 386.5 388.4 388.6 15902.8 16342.5 16972.7 18501.4 18621.3 17694.9 18020.2 18038.8 18374.8 18762.8 5996.9 6155.2 6167.0 6180.0 6181.1 4534.8 4717.8 6084.7 6563.6 6820.0 6626.5 6628.7 6684.0 6787.8 6805.1 6731.8 6736.6 6752.4 6774.3 6776.5 6434.2 6504.5 6518.2 6578.3 6584.9 22388.2 22908.2 23067.2 23088.9 23631.0 2608.5 2632.7 2704.0 2708.7 2891.0 9705.3 9709.2 9994.8 10111.0 14961.3 1940.2 1940.8 1973.2 2016.4 2044.5 65461.7 65842.3 66937.3 67161.7 68227.7 11235.2 11271.5 11312.7 11346.6 11384.2 14985.5 15065.8 15091.8 15105.2 15109.7 8433.2 8494.7 8575.6 8659.6 8661.2 8548.5 8566.7 8579.2 8607.4 8659.2 4551.8 4734.7 4754.0 4851.6 4874.6 1747.3 1838.8 1883.9 1897.8 1913.5 906.4 939.3 1012.6 1015.1 1039.3 14737.0 16777.6 17332.3 17712.9 17757.9 990.2 993.0 994.0 1026.0 1031.3 6246.2 6509.1 9569.6 10054.8 10254.2 437066.0 442540.0 474391.0 539369.0 539431.5 88475.0 89213.0 90898.0 90971.5 91095.5 531.1 538.3 539.7 540.4 541.1 11064.4 11427.1 11477.3 11642.1 15946.4 37822.1 37981.8 38950.0 39293.0 39865.6 49078.0 50390.7 50545.7 52325.2 52628.5 10192.3 10346.4 10366.1 10373.9 10643.6 17807.4 18097.1 18138.5 18363.5 18387.8 14499.1 14757.8 14938.2 14943.7 15004.1 12554.1 12703.8 15299.8 15727.2 15829.2 14878.4 15320.8 15345.2 15370.7 15823.2 10546.4 10581.4 10718.9 10762.1 10861.1 11174.7 14493.3 14742.5 15115.4 15158.9 16:19:29 INFO - 2016-02-03 16:19:29,904 DEBUG : BROWSER_OUTPUT: 16:19:29 INFO - 2016-02-03 16:19:29,904 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:5380.07 Median:6399.15 stddev:2785.87 (43.5%) stddev-sans-first:2778.68 16:19:29 INFO - 2016-02-03 16:19:29,905 DEBUG : BROWSER_OUTPUT: Values: 1532.6 1534.0 1729.5 1815.4 1842.5 7185.3 7568.5 7576.6 7583.2 7586.2 7650.9 7731.0 7738.1 7745.8 7747.8 2252.7 2272.1 2312.3 2314.8 2316.2 2344.4 2372.6 2372.8 2372.9 2373.8 1989.1 2023.5 2204.8 2253.9 2293.9 2240.2 2253.0 2256.4 2270.0 2272.6 2219.2 2259.6 2279.7 2284.3 2284.4 6652.7 6831.9 7220.7 7431.8 7437.3 4746.0 4817.0 4823.5 4826.3 4828.9 3070.5 3076.1 3103.3 3114.3 3167.7 978.0 997.8 1007.5 1031.7 1062.8 10180.5 10203.9 10324.1 10330.9 10358.2 4018.0 4107.9 4108.3 4118.8 4143.9 8727.7 8752.9 8772.9 8815.1 8823.6 4693.6 4697.0 4700.2 4706.2 4713.0 4331.4 4348.1 4350.2 4357.5 4360.2 3475.7 3509.3 3521.4 3522.6 3551.2 1084.2 1100.6 1102.9 1114.4 1120.6 1123.1 1149.6 1151.9 1318.5 1426.7 7363.5 7415.9 7438.2 7478.1 7497.1 524.9 536.2 536.8 539.9 539.9 5362.8 5907.8 5987.6 5996.5 5997.6 8744.3 9356.4 9485.0 9515.0 9521.1 9019.6 9209.9 9233.4 9249.4 9406.6 7318.4 7326.3 7338.5 7350.2 7444.9 7181.9 7199.0 7226.0 7298.0 7298.5 8365.6 8543.9 8607.7 8654.7 8663.9 8587.4 8609.8 8784.0 8795.7 8801.6 6377.0 6448.4 6448.5 6454.8 6458.2 7239.2 7244.1 7286.6 7337.0 7354.4 6844.8 6868.3 6882.1 6939.0 6960.6 6615.2 6657.9 6774.7 6777.7 6858.2 6807.6 6824.7 6838.5 6841.9 6844.8 6460.4 6505.3 6506.3 6506.5 6512.3 6261.9 6391.1 6407.2 6408.5 6411.2 16:19:29 INFO - 2016-02-03 16:19:29,905 DEBUG : BROWSER_OUTPUT: 16:19:29 INFO - 2016-02-03 16:19:29,905 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:5380.36 Median:6208.67 stddev:1622.96 (26.1%) stddev-sans-first:1582.92 16:19:29 INFO - 2016-02-03 16:19:29,906 DEBUG : BROWSER_OUTPUT: Values: 346.8 348.2 351.3 352.2 356.2 5959.0 6004.7 6008.2 6055.0 6068.1 5697.1 5778.8 5905.0 5952.2 5981.9 4630.2 4632.7 4642.9 4652.5 4660.1 4499.3 4604.4 4615.3 4649.4 4660.9 4491.1 4533.5 4569.8 4604.2 4624.0 4565.4 4573.1 4576.8 4600.1 4613.6 4048.7 4064.9 4071.1 4071.5 4072.2 5604.9 5669.8 5679.9 5680.7 5687.9 5358.1 5358.2 5366.4 5367.7 5376.2 6394.9 6396.4 6401.8 6404.3 6405.6 4439.5 4446.0 4451.7 4457.5 4457.6 6493.3 6536.3 6598.6 6609.3 6615.7 4557.9 4561.7 4561.7 4562.1 4562.3 4598.1 4612.3 4616.1 4618.6 4623.3 5317.8 5324.4 5326.0 5329.5 5330.6 6211.6 6342.8 6349.7 6366.0 6403.2 1615.6 1635.5 1635.8 1636.9 1637.7 1093.8 1096.9 1102.5 1118.5 1121.0 6313.1 6334.6 6336.2 6340.7 6345.7 6400.4 6533.7 6576.5 6584.5 6595.1 6205.7 6282.3 6328.4 6328.7 6394.5 3530.6 3553.9 3575.5 3589.5 3591.4 6820.1 6956.1 6959.3 6967.5 6972.9 6805.4 6810.1 6821.6 6836.2 6838.3 6932.9 6957.2 6957.4 6969.8 6971.7 6548.7 6585.7 6632.8 6638.2 6645.3 6547.4 6548.0 6559.9 6562.1 6564.3 6942.7 6943.3 6945.0 6953.9 6954.1 4604.5 4657.3 4666.7 4668.5 4671.6 6578.0 6612.4 6634.2 6641.8 6643.1 6455.3 6466.4 6466.8 6468.6 6482.0 6302.4 6455.1 6459.8 6461.3 6464.0 6344.0 6375.4 6379.1 6384.6 6386.6 6471.4 6499.8 6506.5 6512.5 6517.8 6258.1 6349.1 6364.7 6371.0 6373.9 16:19:29 INFO - 2016-02-03 16:19:29,906 DEBUG : BROWSER_OUTPUT: 16:19:29 INFO - 2016-02-03 16:19:29,907 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:14430.42 Median:53273.10 stddev:17352.97 (32.6%) stddev-sans-first:17371.83 16:19:29 INFO - 2016-02-03 16:19:29,907 DEBUG : BROWSER_OUTPUT: Values: 883.4 896.3 904.4 916.5 917.8 14310.6 14911.7 14924.7 14951.8 14959.7 13992.8 15603.5 15745.8 15752.2 15758.8 752.1 757.8 913.3 929.3 930.2 915.3 920.4 927.3 927.4 927.7 964.2 970.0 970.7 972.3 972.6 960.5 963.1 964.9 965.0 970.8 704.4 708.9 712.6 715.4 717.7 13639.0 13759.7 13787.9 13805.8 13826.7 11590.7 12091.2 12274.4 12662.2 13214.1 10066.9 11027.1 11112.9 11315.1 11373.8 3788.5 3796.2 3839.9 3873.8 3882.3 53185.7 53360.5 53897.0 54116.5 54304.0 10729.5 10837.1 10864.6 10881.7 11062.1 19182.2 19375.5 19383.7 19400.9 19419.4 7541.5 7666.3 7792.5 7827.9 8236.4 4555.6 4573.2 4582.3 4666.5 4699.2 3104.5 3114.0 3131.7 3137.2 3140.8 535.7 543.7 555.3 558.2 558.4 564.3 577.4 610.0 610.2 641.1 7918.6 7972.9 8094.3 8145.6 8189.4 519.2 531.4 553.8 617.4 670.2 3870.6 3878.5 3908.5 3927.8 3985.8 60957.1 61541.0 61964.4 62016.1 62276.0 53385.7 53926.7 54047.2 54138.0 57579.1 50488.5 50685.7 50705.0 50795.5 51182.5 7804.3 7818.6 7905.3 7980.6 8030.4 36562.0 36688.3 37175.1 37388.4 37689.8 45222.8 45467.5 48580.5 48988.0 49146.8 9477.8 9526.5 9682.0 9874.9 9876.8 10509.9 10619.7 10639.8 11825.5 11953.2 10003.9 10213.0 10263.0 10589.2 10648.1 9889.0 9916.9 10037.3 10047.1 10220.0 10147.2 10492.8 10700.8 10767.5 10800.8 9059.3 9280.8 9305.3 9517.9 9537.9 8943.6 8999.7 9044.2 9069.3 9258.0 16:19:29 INFO - 2016-02-03 16:19:29,907 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:19:29 INFO - 2016-02-03 16:19:29,907 DEBUG : BROWSER_OUTPUT: 16:19:30 INFO - 2016-02-03 16:19:30,517 INFO : Browser exited with error code: 0 16:19:30 INFO - 2016-02-03 16:19:30,536 INFO : Completed test dromaeo_css (00:18:29) 16:19:30 INFO - 2016-02-03 16:19:30,536 INFO : Starting test kraken 16:19:30 INFO - 2016-02-03 16:19:30,536 DEBUG : operating with platform_type : linux_ 16:19:30 INFO - 2016-02-03 16:19:30,536 INFO : Initialising browser for kraken test... 16:19:30 INFO - 2016-02-03 16:19:30,542 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEDZVlt/profile http://localhost:35508/getInfo.html 16:19:34 INFO - 2016-02-03 16:19:34,609 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:19:34 INFO - 2016-02-03 16:19:34,609 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:19:34 INFO - 2016-02-03 16:19:34,609 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:19:34 INFO - 2016-02-03 16:19:34,609 DEBUG : BROWSER_OUTPUT: __metrics 16:19:34 INFO - 2016-02-03 16:19:34,758 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:19:35 INFO - 2016-02-03 16:19:35,463 INFO : Browser initialized. 16:19:35 INFO - 2016-02-03 16:19:35,464 INFO : Running cycle 1/1 for kraken test... 16:19:35 INFO - 2016-02-03 16:19:35,464 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmpEDZVlt/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:19:47 INFO - 2016-02-03 16:19:47,247 DEBUG : BROWSER_OUTPUT: RSS: Main: 134074368 16:19:47 INFO - 2016-02-03 16:19:47,247 DEBUG : BROWSER_OUTPUT: 16:19:49 INFO - 2016-02-03 16:19:49,683 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35508/tests/kraken/driver.html?testName=audio-beat-detection) 16:19:50 INFO - 2016-02-03 16:19:50,256 DEBUG : BROWSER_OUTPUT: RSS: Main: 167108608 16:19:50 INFO - 2016-02-03 16:19:50,256 DEBUG : BROWSER_OUTPUT: 16:19:52 INFO - 2016-02-03 16:19:52,812 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35508/tests/kraken/driver.html?testName=audio-dft) 16:19:53 INFO - 2016-02-03 16:19:53,378 DEBUG : BROWSER_OUTPUT: RSS: Main: 163573760 16:19:53 INFO - 2016-02-03 16:19:53,378 DEBUG : BROWSER_OUTPUT: 16:19:56 INFO - 2016-02-03 16:19:56,197 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35508/tests/kraken/driver.html?testName=audio-fft) 16:19:56 INFO - 2016-02-03 16:19:56,766 DEBUG : BROWSER_OUTPUT: RSS: Main: 160366592 16:19:56 INFO - 2016-02-03 16:19:56,766 DEBUG : BROWSER_OUTPUT: 16:19:58 INFO - 2016-02-03 16:19:58,761 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35508/tests/kraken/driver.html?testName=audio-oscillator) 16:19:59 INFO - 2016-02-03 16:19:59,335 DEBUG : BROWSER_OUTPUT: RSS: Main: 161939456 16:19:59 INFO - 2016-02-03 16:19:59,335 DEBUG : BROWSER_OUTPUT: 16:20:01 INFO - 2016-02-03 16:20:01,501 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35508/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:20:02 INFO - 2016-02-03 16:20:02,063 DEBUG : BROWSER_OUTPUT: RSS: Main: 159817728 16:20:02 INFO - 2016-02-03 16:20:02,063 DEBUG : BROWSER_OUTPUT: 16:20:04 INFO - 2016-02-03 16:20:04,921 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35508/tests/kraken/driver.html?testName=imaging-darkroom) 16:20:05 INFO - 2016-02-03 16:20:05,483 DEBUG : BROWSER_OUTPUT: RSS: Main: 159584256 16:20:05 INFO - 2016-02-03 16:20:05,483 DEBUG : BROWSER_OUTPUT: 16:20:08 INFO - 2016-02-03 16:20:08,829 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35508/tests/kraken/driver.html?testName=imaging-desaturate) 16:20:09 INFO - 2016-02-03 16:20:09,394 DEBUG : BROWSER_OUTPUT: RSS: Main: 161075200 16:20:09 INFO - 2016-02-03 16:20:09,395 DEBUG : BROWSER_OUTPUT: 16:20:12 INFO - 2016-02-03 16:20:12,072 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35508/tests/kraken/driver.html?testName=json-parse-financial) 16:20:12 INFO - 2016-02-03 16:20:12,640 DEBUG : BROWSER_OUTPUT: RSS: Main: 159297536 16:20:12 INFO - 2016-02-03 16:20:12,641 DEBUG : BROWSER_OUTPUT: 16:20:14 INFO - 2016-02-03 16:20:14,191 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35508/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:20:14 INFO - 2016-02-03 16:20:14,753 DEBUG : BROWSER_OUTPUT: RSS: Main: 166711296 16:20:14 INFO - 2016-02-03 16:20:14,753 DEBUG : BROWSER_OUTPUT: 16:20:16 INFO - 2016-02-03 16:20:16,523 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35508/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:20:17 INFO - 2016-02-03 16:20:17,109 DEBUG : BROWSER_OUTPUT: RSS: Main: 164597760 16:20:17 INFO - 2016-02-03 16:20:17,109 DEBUG : BROWSER_OUTPUT: 16:20:19 INFO - 2016-02-03 16:20:19,062 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35508/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:20:19 INFO - 2016-02-03 16:20:19,630 DEBUG : BROWSER_OUTPUT: RSS: Main: 169066496 16:20:19 INFO - 2016-02-03 16:20:19,630 DEBUG : BROWSER_OUTPUT: 16:20:21 INFO - 2016-02-03 16:20:21,964 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35508/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:20:22 INFO - 2016-02-03 16:20:22,545 DEBUG : BROWSER_OUTPUT: RSS: Main: 175673344 16:20:22 INFO - 2016-02-03 16:20:22,545 DEBUG : BROWSER_OUTPUT: 16:20:24 INFO - 2016-02-03 16:20:24,984 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35508/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:20:25 INFO - 2016-02-03 16:20:25,545 DEBUG : BROWSER_OUTPUT: RSS: Main: 173555712 16:20:25 INFO - 2016-02-03 16:20:25,546 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,167 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35508/tests/kraken/driver.html?testName=ai-astar) 16:20:27 INFO - 2016-02-03 16:20:27,418 DEBUG : BROWSER_OUTPUT: RSS: Main: 231575552 16:20:27 INFO - 2016-02-03 16:20:27,418 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,418 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:20:27 INFO - 2016-02-03 16:20:27,418 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:20:27 INFO - 2016-02-03 16:20:27,418 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:20:27 INFO - 2016-02-03 16:20:27,418 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:20:27 INFO - 2016-02-03 16:20:27,419 DEBUG : BROWSER_OUTPUT: |0;ai-astar;111;113;106;110;110;112;100;110;109;109 16:20:27 INFO - 2016-02-03 16:20:27,419 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;145;120;141;160;126;157;145;121;167;122 16:20:27 INFO - 2016-02-03 16:20:27,419 DEBUG : BROWSER_OUTPUT: |2;audio-dft;161;160;159;159;161;165;161;162;160;159 16:20:27 INFO - 2016-02-03 16:20:27,419 DEBUG : BROWSER_OUTPUT: |3;audio-fft;74;71;70;152;71;73;72;71;157;70 16:20:27 INFO - 2016-02-03 16:20:27,419 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;92;92;150;91;92;91;134;92;92;138 16:20:27 INFO - 2016-02-03 16:20:27,420 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;110;108;109;110;108;109;109;109;109;109 16:20:27 INFO - 2016-02-03 16:20:27,420 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;147;147;146;145;145;145;146;145;146;145 16:20:27 INFO - 2016-02-03 16:20:27,420 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;95;95;95;95;96;95;95;96 16:20:27 INFO - 2016-02-03 16:20:27,420 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;56;56;57;56;56;55;57;58;54;54 16:20:27 INFO - 2016-02-03 16:20:27,420 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;64;65;64;64;64;65;66;65;65;65 16:20:27 INFO - 2016-02-03 16:20:27,420 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;79;78;79;78;79;80;78;79;79;79 16:20:27 INFO - 2016-02-03 16:20:27,421 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;120;114;117;119;113;112;121;112;111;113 16:20:27 INFO - 2016-02-03 16:20:27,421 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;146;131;144;143;142;136;141;143;142;131 16:20:27 INFO - 2016-02-03 16:20:27,421 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;65;67;65;64;64;63;62;63;66;63 16:20:27 INFO - 2016-02-03 16:20:27,421 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:20:27 INFO - 2016-02-03 16:20:27,421 DEBUG : BROWSER_OUTPUT: __start_cc_report 16:20:27 INFO - 2016-02-03 16:20:27,421 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,889 16:20:27 INFO - 2016-02-03 16:20:27,421 DEBUG : BROWSER_OUTPUT: __end_cc_report 16:20:27 INFO - 2016-02-03 16:20:27,421 DEBUG : BROWSER_OUTPUT: __startTimestamp1454545227417__endTimestamp 16:20:27 INFO - 2016-02-03 16:20:27,421 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,423 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:20:27 INFO - 2016-02-03 16:20:27,423 DEBUG : BROWSER_OUTPUT: Number of tests: 14 16:20:27 INFO - 2016-02-03 16:20:27,423 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,423 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:109.00 Median:110.00 stddev:3.68 (3.3%) stddev-sans-first:3.83 16:20:27 INFO - 2016-02-03 16:20:27,423 DEBUG : BROWSER_OUTPUT: Values: 111.0 113.0 106.0 110.0 110.0 112.0 100.0 110.0 109.0 109.0 16:20:27 INFO - 2016-02-03 16:20:27,423 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,423 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:140.40 Median:145.00 stddev:17.48 (12.1%) stddev-sans-first:18.46 16:20:27 INFO - 2016-02-03 16:20:27,423 DEBUG : BROWSER_OUTPUT: Values: 145.0 120.0 141.0 160.0 126.0 157.0 145.0 121.0 167.0 122.0 16:20:27 INFO - 2016-02-03 16:20:27,423 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,423 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.83 (1.1%) stddev-sans-first:1.94 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: Values: 161.0 160.0 159.0 159.0 161.0 165.0 161.0 162.0 160.0 159.0 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:88.10 Median:71.00 stddev:35.04 (49.3%) stddev-sans-first:36.79 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: Values: 74.0 71.0 70.0 152.0 71.0 73.0 72.0 71.0 157.0 70.0 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:106.40 Median:92.00 stddev:23.97 (26.1%) stddev-sans-first:24.85 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: Values: 92.0 92.0 150.0 91.0 92.0 91.0 134.0 92.0 92.0 138.0 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:109.00 Median:109.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: Values: 110.0 108.0 109.0 110.0 108.0 109.0 109.0 109.0 109.0 109.0 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.73 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: Values: 147.0 147.0 146.0 145.0 145.0 145.0 146.0 145.0 146.0 145.0 16:20:27 INFO - 2016-02-03 16:20:27,425 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:1.29 (2.3%) stddev-sans-first:1.36 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: Values: 56.0 56.0 57.0 56.0 56.0 55.0 57.0 58.0 54.0 54.0 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:64.70 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: Values: 64.0 65.0 64.0 64.0 64.0 65.0 66.0 65.0 65.0 65.0 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:78.80 Median:79.00 stddev:0.63 (0.8%) stddev-sans-first:0.67 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: Values: 79.0 78.0 79.0 78.0 79.0 80.0 78.0 79.0 79.0 79.0 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:115.20 Median:115.50 stddev:3.71 (3.2%) stddev-sans-first:3.50 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: Values: 120.0 114.0 117.0 119.0 113.0 112.0 121.0 112.0 111.0 113.0 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,426 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:139.90 Median:142.50 stddev:5.34 (3.7%) stddev-sans-first:5.19 16:20:27 INFO - 2016-02-03 16:20:27,427 DEBUG : BROWSER_OUTPUT: Values: 146.0 131.0 144.0 143.0 142.0 136.0 141.0 143.0 142.0 131.0 16:20:27 INFO - 2016-02-03 16:20:27,427 DEBUG : BROWSER_OUTPUT: 16:20:27 INFO - 2016-02-03 16:20:27,427 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.50 stddev:1.55 (2.4%) stddev-sans-first:1.62 16:20:27 INFO - 2016-02-03 16:20:27,427 DEBUG : BROWSER_OUTPUT: Values: 65.0 67.0 65.0 64.0 64.0 63.0 62.0 63.0 66.0 63.0 16:20:27 INFO - 2016-02-03 16:20:27,427 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:20:27 INFO - 2016-02-03 16:20:27,427 DEBUG : BROWSER_OUTPUT: 16:20:28 INFO - 2016-02-03 16:20:28,177 INFO : Browser exited with error code: 0 16:20:28 INFO - 2016-02-03 16:20:28,228 INFO : Completed test kraken (00:00:57) 16:20:28 INFO - 2016-02-03 16:20:28,228 INFO : Starting test v8_7 16:20:28 INFO - 2016-02-03 16:20:28,228 DEBUG : operating with platform_type : linux_ 16:20:28 INFO - 2016-02-03 16:20:28,228 INFO : Initialising browser for v8_7 test... 16:20:28 INFO - 2016-02-03 16:20:28,235 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppJraEC/profile http://localhost:35508/getInfo.html 16:20:32 INFO - 2016-02-03 16:20:32,050 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 16:20:32 INFO - 2016-02-03 16:20:32,050 DEBUG : BROWSER_OUTPUT: colorDepth:24 16:20:32 INFO - 2016-02-03 16:20:32,050 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 16:20:32 INFO - 2016-02-03 16:20:32,050 DEBUG : BROWSER_OUTPUT: __metrics 16:20:32 INFO - 2016-02-03 16:20:32,333 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:20:33 INFO - 2016-02-03 16:20:33,233 INFO : Browser initialized. 16:20:33 INFO - 2016-02-03 16:20:33,234 INFO : Running cycle 1/1 for v8_7 test... 16:20:33 INFO - 2016-02-03 16:20:33,234 DEBUG : command line: /builds/slave/test-pgo/build/application/firefox/firefox -profile /tmp/tmppJraEC/profile -tp file:/builds/slave/test-pgo/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 16:20:45 INFO - 2016-02-03 16:20:45,026 DEBUG : BROWSER_OUTPUT: RSS: Main: 134635520 16:20:45 INFO - 2016-02-03 16:20:45,026 DEBUG : BROWSER_OUTPUT: 16:21:06 INFO - 2016-02-03 16:21:06,348 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35508/tests/v8_7/run.html (next: http://localhost:35508/tests/v8_7/run.html) 16:21:06 INFO - 2016-02-03 16:21:06,598 DEBUG : BROWSER_OUTPUT: RSS: Main: 424730624 16:21:06 INFO - 2016-02-03 16:21:06,598 DEBUG : BROWSER_OUTPUT: 16:21:06 INFO - 2016-02-03 16:21:06,598 DEBUG : BROWSER_OUTPUT: __start_tp_report 16:21:06 INFO - 2016-02-03 16:21:06,598 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 16:21:06 INFO - 2016-02-03 16:21:06,599 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 16:21:06 INFO - 2016-02-03 16:21:06,599 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 16:21:06 INFO - 2016-02-03 16:21:06,599 DEBUG : BROWSER_OUTPUT: |0;Richards;167.7289500167729 16:21:06 INFO - 2016-02-03 16:21:06,599 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;165.0437365901964 16:21:06 INFO - 2016-02-03 16:21:06,599 DEBUG : BROWSER_OUTPUT: |2;Encrypt;278.1641168289291 16:21:06 INFO - 2016-02-03 16:21:06,600 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5191.709844559586 16:21:06 INFO - 2016-02-03 16:21:06,600 DEBUG : BROWSER_OUTPUT: |4;RayTrace;927.643784786642 16:21:06 INFO - 2016-02-03 16:21:06,600 DEBUG : BROWSER_OUTPUT: |5;Earley;538.5029617662897 16:21:06 INFO - 2016-02-03 16:21:06,600 DEBUG : BROWSER_OUTPUT: |6;Boyer;10526.315789473685 16:21:06 INFO - 2016-02-03 16:21:06,600 DEBUG : BROWSER_OUTPUT: |7;RegExp;24023.809523809523 16:21:06 INFO - 2016-02-03 16:21:06,601 DEBUG : BROWSER_OUTPUT: |8;Splay;520.5622071837585 16:21:06 INFO - 2016-02-03 16:21:06,601 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4835.748792270531 16:21:06 INFO - 2016-02-03 16:21:06,601 DEBUG : BROWSER_OUTPUT: __end_tp_report 16:21:06 INFO - 2016-02-03 16:21:06,601 DEBUG : BROWSER_OUTPUT: __startTimestamp1454545266596__endTimestamp 16:21:06 INFO - 2016-02-03 16:21:06,601 DEBUG : BROWSER_OUTPUT: 16:21:06 INFO - 2016-02-03 16:21:06,601 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 16:21:06 INFO - 2016-02-03 16:21:06,601 DEBUG : BROWSER_OUTPUT: Number of tests: 10 16:21:06 INFO - 2016-02-03 16:21:06,601 DEBUG : BROWSER_OUTPUT: 16:21:06 INFO - 2016-02-03 16:21:06,602 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.73 Median:167.73 stddev:0.00 (0.0%) 16:21:06 INFO - 2016-02-03 16:21:06,602 DEBUG : BROWSER_OUTPUT: Values: 167.7 16:21:06 INFO - 2016-02-03 16:21:06,602 DEBUG : BROWSER_OUTPUT: 16:21:06 INFO - 2016-02-03 16:21:06,602 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:165.04 Median:165.04 stddev:0.00 (0.0%) 16:21:06 INFO - 2016-02-03 16:21:06,602 DEBUG : BROWSER_OUTPUT: Values: 165.0 16:21:06 INFO - 2016-02-03 16:21:06,602 DEBUG : BROWSER_OUTPUT: 16:21:06 INFO - 2016-02-03 16:21:06,602 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:278.16 Median:278.16 stddev:0.00 (0.0%) 16:21:06 INFO - 2016-02-03 16:21:06,603 DEBUG : BROWSER_OUTPUT: Values: 278.2 16:21:06 INFO - 2016-02-03 16:21:06,603 DEBUG : BROWSER_OUTPUT: 16:21:06 INFO - 2016-02-03 16:21:06,603 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5191.71 Median:5191.71 stddev:0.00 (0.0%) 16:21:06 INFO - 2016-02-03 16:21:06,603 DEBUG : BROWSER_OUTPUT: Values: 5191.7 16:21:06 INFO - 2016-02-03 16:21:06,603 DEBUG : BROWSER_OUTPUT: 16:21:06 INFO - 2016-02-03 16:21:06,603 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:927.64 Median:927.64 stddev:0.00 (0.0%) 16:21:06 INFO - 2016-02-03 16:21:06,603 DEBUG : BROWSER_OUTPUT: Values: 927.6 16:21:06 INFO - 2016-02-03 16:21:06,603 DEBUG : BROWSER_OUTPUT: 16:21:06 INFO - 2016-02-03 16:21:06,603 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:538.50 Median:538.50 stddev:0.00 (0.0%) 16:21:06 INFO - 2016-02-03 16:21:06,603 DEBUG : BROWSER_OUTPUT: Values: 538.5 16:21:06 INFO - 2016-02-03 16:21:06,603 DEBUG : BROWSER_OUTPUT: 16:21:06 INFO - 2016-02-03 16:21:06,603 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:10526.32 Median:10526.32 stddev:0.00 (0.0%) 16:21:06 INFO - 2016-02-03 16:21:06,603 DEBUG : BROWSER_OUTPUT: Values: 10526.3 16:21:06 INFO - 2016-02-03 16:21:06,604 DEBUG : BROWSER_OUTPUT: 16:21:06 INFO - 2016-02-03 16:21:06,605 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:24023.81 Median:24023.81 stddev:0.00 (0.0%) 16:21:06 INFO - 2016-02-03 16:21:06,605 DEBUG : BROWSER_OUTPUT: Values: 24023.8 16:21:06 INFO - 2016-02-03 16:21:06,605 DEBUG : BROWSER_OUTPUT: 16:21:06 INFO - 2016-02-03 16:21:06,605 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:520.56 Median:520.56 stddev:0.00 (0.0%) 16:21:06 INFO - 2016-02-03 16:21:06,605 DEBUG : BROWSER_OUTPUT: Values: 520.6 16:21:06 INFO - 2016-02-03 16:21:06,605 DEBUG : BROWSER_OUTPUT: 16:21:06 INFO - 2016-02-03 16:21:06,605 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4835.75 Median:4835.75 stddev:0.00 (0.0%) 16:21:06 INFO - 2016-02-03 16:21:06,605 DEBUG : BROWSER_OUTPUT: Values: 4835.7 16:21:06 INFO - 2016-02-03 16:21:06,605 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 16:21:06 INFO - 2016-02-03 16:21:06,605 DEBUG : BROWSER_OUTPUT: 16:21:07 INFO - 2016-02-03 16:21:07,309 INFO : Browser exited with error code: 0 16:21:07 INFO - 2016-02-03 16:21:07,326 INFO : Completed test v8_7 (00:00:39) 16:21:07 INFO - 2016-02-03 16:21:07,656 INFO : Completed test suite (00:20:07) 16:21:07 INFO - 2016-02-03 16:21:07,656 DEBUG : Working with test: dromaeo_css 16:21:07 INFO - 2016-02-03 16:21:07,656 DEBUG : Generating results file: dromaeo_css 16:21:07 INFO - 2016-02-03 16:21:07,657 DEBUG : Working with test: kraken 16:21:07 INFO - 2016-02-03 16:21:07,657 DEBUG : Generating results file: kraken 16:21:07 INFO - 2016-02-03 16:21:07,658 INFO : javascript benchmark 16:21:07 INFO - 2016-02-03 16:21:07,658 DEBUG : Working with test: v8_7 16:21:07 INFO - 2016-02-03 16:21:07,658 DEBUG : Generating results file: v8_7 16:21:07 INFO - 2016-02-03 16:21:07,658 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:21:07 INFO - 2016-02-03 16:21:07,723 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,53,35]] 16:21:07 INFO - 2016-02-03 16:21:07,723 DEBUG : process_Request line: dromaeo_css 7238.24 graph.html#tests=[[72,53,35]] 16:21:07 INFO - 2016-02-03 16:21:07,723 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:21:07 INFO - 2016-02-03 16:21:07,754 DEBUG : process_Request line: kraken 1473.40 graph.html#tests=[[232,53,35]] 16:21:07 INFO - 2016-02-03 16:21:07,754 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 16:21:07 INFO - 2016-02-03 16:21:07,790 DEBUG : process_Request line: v8_7 23386.75 graph.html#tests=[[230,53,35]] 16:21:07 INFO - 2016-02-03 16:21:07,792 INFO : javascript benchmark 16:21:07 INFO - 2016-02-03 16:21:07,795 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3322.4351860183765, 3384.4923261510785, 3413.880514182004, 3414.5731783527044, 3429.2112814052775, 5517.724113794309, 5577.665340079598, 5595.972020139899, 5596.160575913615, 5607.523360514356, 5591.161325801122, 5594.104943209088, 5612.102063669803, 5626.774929002835, 5635.436456354362, 4909.116359055369, 4997.475265097167, 5056.292119103328, 5090.923636145442, 5144.6655967362, 5026.497350264981, 5060.6963582185, 5083.110455670261, 5087.414947281073, 5089.46560611136, 4987.276844857493, 5071.797128114871, 5073.695578265298, 5100.030994111125, 5114.23286507025, 5002.924956125625, 5014.222795466709, 5021.849344519688, 5022.623303252252, 5054.696718196883, 4848.224284114524, 4885.828995985159, 4993.989455359865, 4998.57512111471, 5056.671316364462, 5214.009338225763, 5269.973650131725, 5284.894302113975, 5286.9471305287225, 5304.761285742113, 5839.941600583982, 5864.824055278348, 5885.117232415129, 5888.234529511136, 5897.8230653080045, 5738.6556806591325, 5836.124581312794, 5852.853678658025, 5906.5570082244, 5938.7624495020145, 5638.097904335327, 5658.207850900877, 5658.7170641467765, 5659.490645841853, 5659.971700141473, 6076.696165191723, 6082.482988945901, 6098.451139397475, 6100.0849872519475, 6103.298120716175, 5552.52803511706, 5673.063944449203, 5845.152452894392, 5915.349311575723, 5949.048152295699, 5913.674747888907, 5923.911141332797, 5933.199018132583, 5942.37605051472, 5950.137230101611, 5845.386234445322, 5852.122181672783, 5861.472467477947, 5885.293764748258, 5892.469677729025, 5723.856903577361, 5799.33307669615, 5839.795607153729, 5930.792422265286, 5980.13288073227, 4542.091581683677, 4568.680192386568, 4577.908441831211, 4578.336141259499, 4582.88542786433, 3970.146418520032, 4085.9591404086173, 4086.0, 4086.1010577672864, 4099.938500922489, 5629.774809007676, 5691.03252447085, 5718.971405142947, 5721.771129154788, 5760.711964401763, 5999.520038397005, 6013.6993150342305, 6017.939820601826, 6022.36765139654, 6023.969880150571, 5529.060059789846, 5626.493615574618, 5754.971225143931, 5769.971150144306, 5770.2498675171955, 4940.555350018516, 5020.523050310215, 5025.296458495814, 5028.874278143076, 5050.696958182521, 6213.1922850029205, 6228.719707613168, 6257.061440783918, 6265.028920517327, 6274.058891166362, 5666.660000399989, 5761.279840019997, 5927.170196172626, 6028.939710602838, 6041.909371359346, 6103.919453433995, 6104.324103667003, 6109.247962423028, 6114.236541168763, 6212.0, 5747.48272655463, 5792.4497172768515, 5793.536517078539, 5840.94159058421, 5849.268841394825, 5686.891056244158, 5788.536917046542, 5804.042333015005, 5938.732757025858, 5947.04847224442, 5742.856428589319, 5884.852878678067, 5890.14592884038, 5899.764009439575, 5901.114832775118, 5838.737256823538, 5869.706514674163, 5891.204687367322, 5894.882102357843, 5896.764129434946, 5496.450354964471, 5546.362168350692, 5610.382857885547, 5610.382857885711, 5623.8594035149445, 5868.970655146697, 5882.676452794966, 5887.5289976802605, 5895.02732049207, 5903.49820265265, 5556.333240011225, 5707.315122185364, 5740.913886291793, 5765.94234057654, 5768.50967667753], "unit": "ms", "name": "dojo.html", "value": 5470.41737377379}, {"lowerIsBetter": false, "replicates": [615.9322474527801, 656.7274581048864, 659.1990731261523, 660.1187414801242, 711.814928118689, 32154.67845321549, 33242.0, 38186.23627527451, 38536.61463385366, 38614.0, 44619.0, 45323.320150197775, 45452.3182152268, 45497.77251113748, 45572.3164152538, 8205.95897020514, 9064.501452420114, 9228.307876909224, 9229.538523073854, 9237.676681316156, 8392.790180245482, 8496.0, 10730.409827459525, 10955.56177752889, 10974.067204287645, 10734.624288149917, 10830.783384332308, 10841.945790271038, 10846.0, 10846.078083362923, 10512.316699414514, 10556.736081597945, 10578.735531611694, 10592.099671527929, 10601.893981060168, 10615.310004849738, 10734.141268698486, 10754.569817207303, 10763.138948884149, 10776.245662803607, 41999.0, 42350.36474452886, 42455.36316955248, 42525.14949700989, 42546.1490770186, 3624.800635965021, 3789.7157713171623, 4235.57644235577, 4483.0, 4550.0, 32376.83811580927, 33005.0, 33006.669933300596, 33080.669193308, 33157.3368532628, 4302.161078589676, 4309.59058889405, 4337.371081193241, 4415.403920470728, 4419.558044195587, 293885.5305723479, 294392.0, 297395.0, 297574.0, 299609.99999999785, 63255.683721581096, 63308.0, 63527.0, 63681.36318636754, 64601.676991615685, 77096.0, 78744.60627696825, 79021.60489197633, 79087.20912790914, 79102.20897790948, 18275.817241827677, 18331.908340458478, 18430.078496075144, 18485.7227141593, 18508.0, 14195.21926294043, 14283.642908927151, 14291.714165716627, 14292.64219899108, 14300.928495357457, 21616.13535458564, 21865.344039678515, 21877.0, 21880.343589692333, 21905.904704765017, 7859.882101768478, 8211.0, 8279.668813247403, 8289.626966786509, 8312.625931832961, 954.2842867849113, 978.1050338939932, 985.1822986920838, 1143.891330323618, 1234.2471092633486, 25871.870640646677, 26052.0, 26114.738852611612, 26236.344091397867, 26557.203283901516, 1112.3492756737237, 1127.5489804078434, 1135.3641960502146, 1150.511032811052, 1163.5811108001112, 13370.799438008438, 14974.907796700982, 15898.364065437485, 15954.122523261332, 16122.004357298429, 333942.0, 337271.0, 338650.0, 338687.0, 341211.0, 59365.40634593685, 59386.40613593809, 59629.10556341658, 59758.70120649456, 59821.70089149613, 1409.0629731228823, 1499.317810396267, 1515.8787297016213, 1536.6004838742008, 1624.5370069530363, 27175.86412067927, 28627.0, 28642.570361444177, 29491.0, 29531.261718457212, 45480.090398192515, 45918.31122533231, 45943.31085033794, 45952.31071533997, 45965.77017114893, 455150.0, 461287.0, 464245.0, 464504.0, 464719.0, 35463.0, 35778.821105894305, 35808.10479737923, 35929.10177245485, 36271.0, 47351.28973065476, 47634.0, 48035.75982120067, 48343.51656483531, 48427.75786121047, 39914.60085399109, 40135.0, 40840.0, 41211.0, 41886.581134188265, 37787.62212377841, 38295.6170438292, 39038.80480597693, 40588.39117413182, 41001.97495062647, 34549.0, 34630.82684586561, 35039.0, 35112.64887351094, 35217.64782352144, 31209.53185702171, 31355.21611959628, 34422.82788586041, 34431.65568344285, 34897.82551087228, 26207.0, 26713.866430667724, 26814.86592567025, 26825.195244142706, 27053.72946270512], "unit": "ms", "name": "ext.html", "value": 20472.84945890156}, {"lowerIsBetter": false, "replicates": [383.77165586476036, 385.19494257002856, 386.5245747730291, 388.35145307336745, 388.63662475585323, 15902.84097159028, 16342.509724708249, 16972.74540881886, 18501.35245266416, 18621.348252811153, 17694.911525442356, 18020.18909149088, 18038.81961180388, 18374.816251837477, 18762.812371876276, 5996.850078748033, 6155.19982402287, 6167.013277875541, 6180.042093475518, 6181.134641150243, 4534.79593418297, 4717.764111794414, 6084.665343406111, 6563.638999855006, 6820.0, 6626.5030122740745, 6628.734850605994, 6683.966580167093, 6787.76242831501, 6805.081314022593, 6731.798046058601, 6736.5958042517605, 6752.426043786235, 6774.288699686511, 6776.457883369319, 6434.163558737381, 6504.5121615879, 6518.152640156749, 6578.3421657834315, 6584.9012264816065, 22388.21641242565, 22908.19821306263, 23067.192648257398, 23088.884555577282, 23631.0, 2608.4652646207483, 2632.7498887605648, 2704.013035242142, 2708.7155848635807, 2890.985545072282, 9705.320627556073, 9709.223262139012, 9994.750131246705, 10111.0, 14961.251937403087, 1940.2433051110079, 1940.8156225158586, 1973.220577871747, 2016.4253187841396, 2044.509317763741, 65461.67269163624, 65842.3415765841, 66937.3306266936, 67161.66419167921, 68227.65886170587, 11235.157363197794, 11271.54913803455, 11312.660620181408, 11346.602868899714, 11384.203105782515, 14985.475508357154, 15065.849341506664, 15091.773623395658, 15105.169215693022, 15109.697806043818, 8433.19884610961, 8494.660213591511, 8575.614097365633, 8659.6103175356, 8661.177188167114, 8548.529830859363, 8566.657333706706, 8579.227869491775, 8607.440516366416, 8659.177378149066, 4551.840685575989, 4734.668573199843, 4753.976230118897, 4851.611871050308, 4874.610031197567, 1747.2672285478732, 1838.779346478431, 1883.9246430142641, 1897.848172146225, 1913.4929243750416, 906.4334790755778, 939.2861425316809, 1012.6050840172338, 1015.137133436573, 1039.3348257115451, 14737.0, 16777.580560486087, 17332.3067077318, 17712.911435443, 17757.911210443865, 990.2474119669104, 992.9772334495481, 993.965211217604, 1025.9538320775434, 1031.3347890610664, 6246.156768836149, 6509.121268628769, 9569.61721531145, 10054.798904021878, 10254.23093267993, 437066.0, 442540.0, 474391.0, 539369.0, 539431.51421214, 88475.0, 89213.0, 90898.0, 90971.5451422752, 91095.54452227829, 531.0573731626394, 538.3082738680855, 539.6573176033247, 540.3623724005711, 541.0882662713341, 11064.39145846954, 11427.142964277878, 11477.311361318347, 11642.068634509389, 15946.36214551405, 37822.054448639006, 37981.810090949366, 38950.0, 39293.0, 39865.601343986185, 49078.0, 50390.748046261004, 50545.74727126341, 52325.215121772446, 52628.47371526236, 10192.286539942133, 10346.37921724699, 10366.067053964878, 10373.948130259603, 10643.627473038407, 17807.376741813976, 18097.095145242947, 18138.546536336697, 18363.54091147732, 18387.816121838612, 14499.130052196902, 14757.778633320295, 14938.178400188093, 14943.701125977203, 15004.099754014795, 12554.121211515107, 12703.80944285855, 15299.847001529843, 15727.213639318217, 15829.208539572744, 14878.404863805326, 15320.770188446959, 15345.23273836326, 15370.692586148438, 15823.208839557747, 10546.419946902994, 10581.418022008864, 10718.89281107179, 10762.085222756154, 10861.131109511378, 11174.720631984266, 14493.347799348843, 14742.48401305992, 15115.395384184509, 15158.924205378902], "unit": "ms", "name": "jquery.html", "value": 9371.951971942046}, {"lowerIsBetter": false, "replicates": [1532.555558887922, 1534.0258935575905, 1729.4984454508194, 1815.4099917526803, 1842.548575598978, 7185.317394847488, 7568.5458872467625, 7576.583287919162, 7583.203763604824, 7586.203448637896, 7650.8852367214395, 7731.0, 7738.1488036316105, 7745.806354841132, 7747.845043099149, 2252.7409347925036, 2272.1025195047987, 2312.271634435156, 2314.837961342707, 2316.2124877541632, 2344.3553022918663, 2372.632242002484, 2372.810175185981, 2372.881355932205, 2373.8338316317777, 1989.094961792381, 2023.5447024419534, 2204.801567858892, 2253.9436514087115, 2293.86236825791, 2240.1599400224914, 2253.042456955787, 2256.3907744908947, 2269.9886500567472, 2272.6477396003647, 2219.1567204462362, 2259.638457846737, 2279.726432828055, 2284.2690339091496, 2284.371797755614, 6652.667366631649, 6831.931680683179, 7220.74727384539, 7431.814204644873, 7437.293457121565, 4745.9525404745855, 4816.975915120402, 4823.5176482351835, 4826.34844296019, 4828.903421931542, 3070.47801873682, 3076.077176846937, 3103.2707313781243, 3114.252579380955, 3167.7307428868576, 977.951102444882, 997.7555498902743, 1007.450939238117, 1031.6750223679517, 1062.8352605346183, 10180.541875615541, 10203.89796102037, 10324.07083362501, 10330.948345258226, 10358.22313326496, 4018.0, 4107.938380924288, 4108.281050816096, 4118.794060296973, 4143.91712165755, 8727.65089396417, 8752.8687069694, 8772.868406973901, 8815.118488151262, 8823.647054117764, 4693.55411235932, 4697.0, 4700.224462963641, 4706.223473126965, 4713.033828065239, 4331.393604895317, 4348.130373925228, 4350.216960946997, 4357.477102747691, 4360.171567402247, 3475.721942244665, 3509.3332266869215, 3521.4189658706537, 3522.6125126236093, 3551.183227857607, 1084.159776173456, 1100.6422912553453, 1102.8841971592867, 1114.3815182573587, 1120.551779288275, 1123.1407972900738, 1149.5516748468103, 1151.8963293303475, 1318.4660212613908, 1426.657602175491, 7363.48455608113, 7415.851682966419, 7438.218987006395, 7478.140013898363, 7497.062867141607, 524.8976449592293, 536.1796451429319, 536.7826030457671, 539.8623351045459, 539.870431096534, 5362.785488580491, 5907.822765317047, 5987.5808693391045, 5996.4603185713495, 5997.640141591519, 8744.300455963636, 9356.391834530734, 9485.0, 9514.952425237969, 9521.095495927875, 9019.594118264562, 9209.907900921038, 9233.353665243503, 9249.398789078688, 9406.623735050658, 7318.414526837841, 7326.267373262738, 7338.522996005136, 7350.191478937312, 7444.8883266751045, 7181.856362872609, 7199.0, 7226.0, 7297.963510182628, 7298.4891057625455, 8365.623546940524, 8543.914560854311, 8607.741767746978, 8654.697085602225, 8663.870041949249, 8587.355948303777, 8609.82780344402, 8783.989841168092, 8795.692150774692, 8801.559922004002, 6377.0, 6448.387403196689, 6448.484121270193, 6454.806355809334, 6458.160439142881, 7239.167495738057, 7244.094488188976, 7286.599237041802, 7337.04618399626, 7354.411647068328, 6844.828879278058, 6868.278830722902, 6882.070920425878, 6938.965305173442, 6960.617166055916, 6615.206175259, 6657.866842663023, 6774.695138718868, 6777.695003724745, 6858.245592984668, 6807.591544507147, 6824.692888820115, 6838.452923766186, 6841.897371539331, 6844.760433384808, 6460.3862633049785, 6505.284418714033, 6506.3493650634555, 6506.479481641362, 6512.3162067982175, 6261.906071408841, 6391.10524526576, 6407.199100112486, 6408.455281301141, 6411.198600174978], "unit": "ms", "name": "mootools.html", "value": 4369.011120532926}, {"lowerIsBetter": false, "replicates": [346.7676656640051, 348.1574589493426, 351.2588438394987, 352.24092081564237, 356.18611472784676, 5958.970205148974, 6004.669743164119, 6008.24896887889, 6055.0311950088, 6068.120122582234, 5697.088465845465, 5778.768849246025, 5905.0, 5952.2262105926275, 5981.880362392761, 4630.21286381316, 4632.745199014053, 4642.860714178563, 4652.534746525354, 4660.091282199973, 4499.280115181572, 4604.355390245369, 4615.307703844416, 4649.44206695197, 4660.930086048712, 4491.101779644085, 4533.5013148553635, 4569.771511424432, 4604.171249175147, 4623.9768801156115, 4565.406497155382, 4573.062522182944, 4576.816927322903, 4600.148972440093, 4613.63090952723, 4048.696347773899, 4064.8983775405554, 4071.124708187724, 4071.5114186297537, 4072.2466343726505, 5604.915926261109, 5669.83768327496, 5679.858003549903, 5680.744366503517, 5687.857803554903, 5358.062339090644, 5358.249845021661, 5366.409694933554, 5367.677939323614, 5376.166694162411, 6394.904076438857, 6396.424321811013, 6401.807945761635, 6404.263509696398, 6405.647689377082, 4439.489458712257, 4446.021875187454, 4451.666125040635, 4457.531959144308, 4457.598816106533, 6493.285738568754, 6536.3463653634735, 6598.637074960875, 6609.272979972199, 6615.735370585171, 4557.931631025537, 4561.749103799257, 4561.749103799323, 4562.087582483517, 4562.338460923214, 4598.149342371672, 4612.331211974245, 4616.146012987608, 4618.584327410482, 4623.329617205553, 5317.787288508416, 5324.440933701983, 5325.973370133124, 5329.520343169133, 5330.573554115739, 6211.627302361872, 6342.841428964312, 6349.746010159634, 6365.96817015912, 6403.231612206565, 1615.6203292226317, 1635.4602981016237, 1635.8037035555571, 1636.9345226191058, 1637.7379619260862, 1093.8195197792434, 1096.8628921384827, 1102.5204036244259, 1118.4631376938953, 1121.0135081128556, 6313.147725057151, 6334.588251763621, 6336.20797400325, 6340.714667839959, 6345.714442850082, 6400.423961843457, 6533.673316334164, 6576.506761992774, 6584.539082264196, 6595.0766892635065, 6205.720742566596, 6282.277538083179, 6328.430441260217, 6328.746850125944, 6394.488440924715, 3530.5763308402643, 3553.8578456861437, 3575.4994300797907, 3589.4974703541525, 3591.44332628443, 6820.147481564804, 6956.130483689539, 6959.338862808025, 6967.512274140761, 6972.895406568906, 6805.421539169127, 6810.114685091005, 6821.556598821061, 6836.213835408892, 6838.316168383122, 6932.861342773116, 6957.199922008933, 6957.373836354753, 6969.7909062728195, 6971.6862741176765, 6548.6655735761005, 6585.703643336157, 6632.767853125118, 6638.236602790741, 6645.269020407654, 6547.377999090078, 6547.9672601636685, 6559.901601476077, 6562.146920900253, 6564.310747371648, 6942.687579059056, 6943.340382663639, 6944.965275173592, 6953.895691564731, 6954.130733658293, 4604.470485894165, 4657.347971284091, 4666.696664716783, 4668.53314668544, 4671.579557839675, 6578.04618330349, 6612.404883560503, 6634.203895532567, 6641.767538136142, 6643.069970204272, 6455.322191169859, 6466.418022378009, 6466.80364132637, 6468.644224567505, 6481.995290729945, 6302.369763023844, 6455.096286519797, 6459.806205813833, 6461.321561236003, 6463.967680161569, 6343.9682801585695, 6375.362463753588, 6379.075034120119, 6384.553081284265, 6386.61680299165, 6471.352864713679, 6499.837504062436, 6506.512011599244, 6512.544121911421, 6517.804465866032, 6258.092576576462, 6349.07938348946, 6364.681765911593, 6371.0, 6373.904391434225], "unit": "ms", "name": "prototype.html", "value": 4857.311689559027}, {"lowerIsBetter": false, "replicates": [883.3860466975455, 896.3143195455482, 904.4076130134762, 916.5279880861358, 917.8026724254285, 14310.642233944143, 14911.701765964674, 14924.701505969873, 14951.775723364144, 14959.700805983874, 13992.79010814836, 15603.531894043197, 15745.84254157458, 15752.212389380542, 15758.84241157588, 752.1388010727713, 757.7764559454946, 913.2511340700628, 929.3355250995537, 930.2325581395343, 915.2860768600502, 920.3695468604028, 927.3462209142572, 927.3925578745932, 927.6938610258599, 964.1611797735944, 970.034815358719, 970.6942313171364, 972.3388096094653, 972.6158167523823, 960.538941308169, 963.1379914976092, 964.8600952861796, 965.025324422335, 970.815545046439, 704.3555147040452, 708.943284537241, 712.5546533416615, 715.4491041897761, 717.7057406463377, 13639.0, 13759.656008599764, 13787.86212137886, 13805.79291310621, 13826.654333641638, 11590.710232244177, 12091.153619246656, 12274.38628068593, 12662.176958497761, 13214.141080829715, 10066.899331006669, 11027.062699670538, 11112.944435277852, 11315.094792416587, 11373.829392559119, 3788.4696142540065, 3796.2027974125467, 3839.9424008639894, 3873.767573945572, 3882.281777871103, 53185.73407132978, 53360.46639533594, 53897.0, 54116.45883541153, 54304.0, 10729.517171727291, 10837.133029357632, 10864.619738309122, 10881.673549793519, 11062.115030797517, 19182.232710691696, 19375.51561210981, 19383.709244361347, 19400.902995484932, 19419.4174174775, 7541.5475071495885, 7666.310032097138, 7792.493487923267, 7827.882581761278, 8236.382271329674, 4555.567221113989, 4573.199690054227, 4582.266837306015, 4666.4633567139535, 4699.224627936421, 3104.5343198520404, 3113.9688603113677, 3131.7338026267566, 3137.1529686984386, 3140.8272545009786, 535.6518263128919, 543.6520626798851, 555.328053055807, 558.2407925221695, 558.4499268220799, 564.2692712936757, 577.3809523809524, 610.036142894226, 610.2189197826789, 641.0608458608161, 7918.6436610352675, 7972.880406793903, 8094.27151556351, 8145.633446494803, 8189.426740128134, 519.1900635009397, 531.404826594217, 553.808935917108, 617.3949529461153, 670.1522573943964, 3870.59358767331, 3878.534575850916, 3908.472356231929, 3927.8428862845167, 3985.7904788292126, 60957.08564371538, 61541.0, 61964.38035619586, 62016.06975895365, 62276.0, 53385.73307133517, 53926.73036634792, 54047.18929216065, 54138.0, 57579.136312955336, 50488.49511504911, 50685.74657126691, 50705.0, 50795.492045079074, 51182.48817511852, 7804.336631386395, 7818.609069546386, 7905.32804711606, 7980.600969951595, 8030.397720171128, 36562.0, 36688.26623467569, 37175.070623234635, 37388.439173411876, 37689.81155094207, 45222.77388613036, 45467.54532454633, 48580.5141948576, 48988.0, 49146.77133071702, 9477.763055923657, 9526.52367381642, 9682.0, 9874.851877221703, 9876.753081173028, 10509.9474502627, 10619.734506637087, 10639.787204256027, 11825.526978920747, 11953.163278570417, 10003.899961000589, 10213.0, 10263.0, 10589.152867770406, 10648.148148147975, 9888.950555247178, 9916.851247231152, 10037.34757240777, 10047.145992590711, 10220.0, 10147.18822494184, 10492.79014419692, 10700.785984280115, 10767.461626918779, 10800.837987430037, 9059.320550958573, 9280.814383712423, 9305.302102342215, 9517.904820951702, 9537.904620953701, 8943.597538110931, 8999.730008099768, 9044.231240344645, 9069.319801015083, 9258.0], "unit": "ms", "name": "yui.html", "value": 6455.771480310454}], "name": "dromaeo_css", "value": 7238.2434039835725}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 113.0, 106.0, 110.0, 110.0, 112.0, 100.0, 110.0, 109.0, 109.0], "unit": "ms", "name": "ai-astar", "value": 109.0}, {"lowerIsBetter": true, "replicates": [145.0, 120.0, 141.0, 160.0, 126.0, 157.0, 145.0, 121.0, 167.0, 122.0], "unit": "ms", "name": "audio-beat-detection", "value": 140.4}, {"lowerIsBetter": true, "replicates": [161.0, 160.0, 159.0, 159.0, 161.0, 165.0, 161.0, 162.0, 160.0, 159.0], "unit": "ms", "name": "audio-dft", "value": 160.7}, {"lowerIsBetter": true, "replicates": [74.0, 71.0, 70.0, 152.0, 71.0, 73.0, 72.0, 71.0, 157.0, 70.0], "unit": "ms", "name": "audio-fft", "value": 88.1}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 150.0, 91.0, 92.0, 91.0, 134.0, 92.0, 92.0, 138.0], "unit": "ms", "name": "audio-oscillator", "value": 106.4}, {"lowerIsBetter": true, "replicates": [110.0, 108.0, 109.0, 110.0, 108.0, 109.0, 109.0, 109.0, 109.0, 109.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 109.0}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 146.0, 145.0, 145.0, 145.0, 146.0, 145.0, 146.0, 145.0], "unit": "ms", "name": "imaging-darkroom", "value": 145.7}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 95.4}, {"lowerIsBetter": true, "replicates": [56.0, 56.0, 57.0, 56.0, 56.0, 55.0, 57.0, 58.0, 54.0, 54.0], "unit": "ms", "name": "json-parse-financial", "value": 55.9}, {"lowerIsBetter": true, "replicates": [64.0, 65.0, 64.0, 64.0, 64.0, 65.0, 66.0, 65.0, 65.0, 65.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 64.7}, {"lowerIsBetter": true, "replicates": [79.0, 78.0, 79.0, 78.0, 79.0, 80.0, 78.0, 79.0, 79.0, 79.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 78.8}, {"lowerIsBetter": true, "replicates": [120.0, 114.0, 117.0, 119.0, 113.0, 112.0, 121.0, 112.0, 111.0, 113.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 115.2}, {"lowerIsBetter": true, "replicates": [146.0, 131.0, 144.0, 143.0, 142.0, 136.0, 141.0, 143.0, 142.0, 131.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 139.9}, {"lowerIsBetter": true, "replicates": [65.0, 67.0, 65.0, 64.0, 64.0, 63.0, 62.0, 63.0, 66.0, 63.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 64.2}], "name": "kraken", "value": 1473.4}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.7289500167729], "unit": "score", "name": "Richards", "value": 210.47052400000004}, {"lowerIsBetter": false, "replicates": [165.0437365901964], "unit": "score", "name": "DeltaBlue", "value": 400.608962}, {"lowerIsBetter": false, "replicates": [278.1641168289291], "unit": "score", "name": "Encrypt", "value": 956.9206949999998}, {"lowerIsBetter": false, "replicates": [5191.709844559586], "unit": "score", "name": "Decrypt", "value": 51.270392215568904}, {"lowerIsBetter": false, "replicates": [927.643784786642], "unit": "score", "name": "RayTrace", "value": 797.7081420000002}, {"lowerIsBetter": false, "replicates": [538.5029617662897], "unit": "score", "name": "Earley", "value": 1237.621791}, {"lowerIsBetter": false, "replicates": [10526.315789473685], "unit": "score", "name": "Boyer", "value": 63.313984999999995}, {"lowerIsBetter": false, "replicates": [24023.809523809523], "unit": "score", "name": "RegExp", "value": 37.920089197225}, {"lowerIsBetter": false, "replicates": [520.5622071837585], "unit": "score", "name": "Splay", "value": 156.54421100000005}, {"lowerIsBetter": false, "replicates": [4835.748792270531], "unit": "score", "name": "NavierStokes", "value": 306.881118881119}], "name": "v8_7", "value": 23386.75476926445}]} 16:21:07 INFO - RETURN: dromaeo_css: 7238.24 16:21:07 INFO - RETURN: kraken: 1473.40 16:21:07 INFO - RETURN: v8_7: 23386.75 16:21:07 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,53,35]]", "result": "23386.75"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,53,35]]", "result": "1473.40"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,53,35]]", "result": "7238.24"}}} 16:21:07 INFO - Return code: 0 16:21:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test-pgo/build/tests/talos/treeherder-schemas/performance-artifact.json 16:21:07 INFO - # TBPL SUCCESS # 16:21:07 INFO - Running post-action listener: _resource_record_post_action 16:21:07 INFO - Running post-run listener: _resource_record_post_run 16:21:08 INFO - Total resource usage - Wall time: 1223s; CPU: 13.0%; Read bytes: 16384; Write bytes: 515743744; Read time: 32; Write time: 417248 16:21:08 INFO - install - Wall time: 15s; CPU: 14.0%; Read bytes: 0; Write bytes: 250757120; Read time: 0; Write time: 277228 16:21:09 INFO - run-tests - Wall time: 1209s; CPU: 13.0%; Read bytes: 16384; Write bytes: 264388608; Read time: 32; Write time: 139592 16:21:09 INFO - Running post-run listener: _upload_blobber_files 16:21:09 INFO - Blob upload gear active. 16:21:09 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 16:21:09 INFO - Copying logs to upload dir... 16:21:09 INFO - mkdir: /builds/slave/test-pgo/build/upload/logs program finished with exit code 0 elapsedTime=1255.476263 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 55 secs) (at 2016-02-03 16:21:09.044385) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-03 16:21:09.048228) ========= 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=652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010070 build_url: 'http://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-beta-linux64/1454535207/firefox-45.0.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2016-02-03 16:21:09.099869) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 16:21:09.100194) ========= 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=652eb03db935cfc1c15ebda0000001ad-1454542094.810338-288165499 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004540 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-03 16:21:09.150000) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-03 16:21:09.150378) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-03 16:21:09.150705) ========= ========= Total master_lag: 0.24 =========