builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-048 starttime: 1475771447.73 results: success (0) buildid: 20161006063500 builduid: a0670596afa74bbca0aafcda183b7780 revision: dfa484769f77ab7e87418f4862c3cd676b4a22cd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:47.726464) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:47.726971) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:47.727288) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:47.742705) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:47.743101) ========= 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/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-06 09:30:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 9.68M=0.001s 2016-10-06 09:30:48 (9.68 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.374618 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:48.132051) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:48.132441) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015709 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:48.183140) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:48.183624) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dfa484769f77ab7e87418f4862c3cd676b4a22cd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dfa484769f77ab7e87418f4862c3cd676b4a22cd --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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-06 09:30:48,531 truncating revision to first 12 chars 2016-10-06 09:30:48,531 Setting DEBUG logging. 2016-10-06 09:30:48,532 attempt 1/10 2016-10-06 09:30:48,532 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dfa484769f77?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-06 09:30:48,833 unpacking tar archive at: mozilla-aurora-dfa484769f77/testing/mozharness/ program finished with exit code 0 elapsedTime=0.615894 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:48.813332) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:48.813796) ========= script_repo_revision: dfa484769f77ab7e87418f4862c3cd676b4a22cd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:48.814228) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:48.814552) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-06 09:30:48.826106) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-10-06 09:30:48.826589) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:30:49 INFO - MultiFileLogger online at 20161006 09:30:49 in /builds/slave/test 09:30:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:30:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:30:49 INFO - {'append_to_log': False, 09:30:49 INFO - 'base_work_dir': '/builds/slave/test', 09:30:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:30:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:30:49 INFO - 'branch': 'Mozilla-Aurora', 09:30:49 INFO - 'buildbot_json_path': 'buildprops.json', 09:30:49 INFO - 'config_files': ('talos/linux_config.py',), 09:30:49 INFO - 'default_actions': ('clobber', 09:30:49 INFO - 'read-buildbot-config', 09:30:49 INFO - 'download-and-extract', 09:30:49 INFO - 'populate-webroot', 09:30:49 INFO - 'create-virtualenv', 09:30:49 INFO - 'install', 09:30:49 INFO - 'run-tests'), 09:30:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:30:49 INFO - 'download_minidump_stackwalk': True, 09:30:49 INFO - 'download_symbols': 'ondemand', 09:30:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:30:49 INFO - 'tooltool.py': '/tools/tooltool.py', 09:30:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:30:49 INFO - '/tools/misc-python/virtualenv.py')}, 09:30:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:30:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:30:49 INFO - 'installer_path': 'installer.exe', 09:30:49 INFO - 'log_level': 'info', 09:30:49 INFO - 'log_name': 'talos', 09:30:49 INFO - 'log_to_console': True, 09:30:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:30:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:30:49 INFO - 'opt_config_files': (), 09:30:49 INFO - 'pip_index': False, 09:30:49 INFO - 'sps_profile': False, 09:30:49 INFO - 'sps_profile_interval': 0, 09:30:49 INFO - 'suite': 'dromaeojs', 09:30:49 INFO - 'system_bits': '32', 09:30:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:30:49 INFO - 'title': 'talos-linux64-ix-048', 09:30:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:30:49 INFO - 'use_talos_json': True, 09:30:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:30:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:30:49 INFO - 'work_dir': 'build'} 09:30:49 INFO - [mozharness: 2016-10-06 16:30:49.268281Z] Running clobber step. 09:30:49 INFO - Running pre-action listener: _resource_record_pre_action 09:30:49 INFO - Running main action method: clobber 09:30:49 INFO - rmtree: /builds/slave/test/build 09:30:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:30:50 INFO - Running post-action listener: _resource_record_post_action 09:30:50 INFO - [mozharness: 2016-10-06 16:30:50.244552Z] Finished clobber step (success) 09:30:50 INFO - [mozharness: 2016-10-06 16:30:50.244651Z] Running read-buildbot-config step. 09:30:50 INFO - Running pre-action listener: _resource_record_pre_action 09:30:50 INFO - Running main action method: read_buildbot_config 09:30:50 INFO - Using buildbot properties: 09:30:50 INFO - { 09:30:50 INFO - "project": "", 09:30:50 INFO - "product": "firefox", 09:30:50 INFO - "script_repo_revision": "production", 09:30:50 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 09:30:50 INFO - "repository": "", 09:30:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 09:30:50 INFO - "buildid": "20161006063500", 09:30:50 INFO - "pgo_build": "True", 09:30:50 INFO - "basedir": "/builds/slave/test", 09:30:50 INFO - "buildnumber": 813, 09:30:50 INFO - "slavename": "talos-linux64-ix-048", 09:30:50 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 09:30:50 INFO - "platform": "ubuntu64_hw", 09:30:50 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:30:50 INFO - "branch": "mozilla-aurora", 09:30:50 INFO - "repo_path": "releases/mozilla-aurora", 09:30:50 INFO - "revision": "dfa484769f77ab7e87418f4862c3cd676b4a22cd", 09:30:50 INFO - "stage_platform": "linux64-pgo", 09:30:50 INFO - "builduid": "a0670596afa74bbca0aafcda183b7780", 09:30:50 INFO - "slavebuilddir": "test" 09:30:50 INFO - } 09:30:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 09:30:50 INFO - Running post-action listener: _resource_record_post_action 09:30:50 INFO - [mozharness: 2016-10-06 16:30:50.247082Z] Finished read-buildbot-config step (success) 09:30:50 INFO - [mozharness: 2016-10-06 16:30:50.247171Z] Running download-and-extract step. 09:30:50 INFO - Running pre-action listener: _resource_record_pre_action 09:30:50 INFO - Running main action method: download_and_extract 09:30:50 INFO - mkdir: /builds/slave/test/build/tests 09:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:30:50 INFO - trying https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:30:50 INFO - Downloading https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:30:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:30:50 INFO - Downloaded 1361 bytes. 09:30:50 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 09:30:50 INFO - Using the following test package requirements: 09:30:50 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:30:50 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:30:50 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:30:50 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:30:50 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:30:50 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:30:50 INFO - u'jsshell-linux-x86_64.zip'], 09:30:50 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:30:50 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:30:50 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 09:30:50 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:30:50 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:30:50 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:30:50 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:30:50 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:30:50 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:30:50 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 09:30:50 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:30:50 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:30:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:30:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:30:50 INFO - Fetch https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:30:51 INFO - Expected file size: 21906578 09:30:55 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:30:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 09:30:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:30:55 INFO - Fetch https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 09:30:56 INFO - Expected file size: 21906578 09:31:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 09:31:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:31:00 INFO - Fetch https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 09:31:01 INFO - Expected file size: 14414059 09:31:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:01 INFO - trying https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:31:01 INFO - Downloading https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:31:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:31:03 INFO - Downloaded 63944036 bytes. 09:31:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:31:03 INFO - mkdir: /builds/slave/test/properties 09:31:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:31:03 INFO - Writing to file /builds/slave/test/properties/build_url 09:31:03 INFO - Contents: 09:31:03 INFO - build_url:https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 09:31:03 INFO - Running post-action listener: _resource_record_post_action 09:31:03 INFO - Running post-action listener: set_extra_try_arguments 09:31:03 INFO - [mozharness: 2016-10-06 16:31:03.726903Z] Finished download-and-extract step (success) 09:31:03 INFO - [mozharness: 2016-10-06 16:31:03.727133Z] Running populate-webroot step. 09:31:03 INFO - Running pre-action listener: _resource_record_pre_action 09:31:03 INFO - Running main action method: populate_webroot 09:31:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:31:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:31:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:31:03 INFO - '--tppagecycles', 09:31:03 INFO - '7'], 09:31:03 INFO - 'tests': ['tsvgm']}}, 09:31:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:31:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:31:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:31:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:31:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:31:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:31:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:03 INFO - 'talos_options': ['--disable-e10s'], 09:31:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:31:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:03 INFO - 'talos_options': ['--disable-e10s'], 09:31:03 INFO - 'tests': ['damp', 'tps']}, 09:31:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:03 INFO - 'tests': ['damp', 'tps']}, 09:31:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:31:03 INFO - 'tests': ['dromaeo_dom']}, 09:31:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:31:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:31:03 INFO - 'tests': ['basic_compositor_video']}, 09:31:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:31:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:31:03 INFO - 'tests': ['a11yr', 09:31:03 INFO - 'ts_paint', 09:31:03 INFO - 'tpaint', 09:31:03 INFO - 'sessionrestore', 09:31:03 INFO - 'sessionrestore_no_auto_restore', 09:31:03 INFO - 'tabpaint']}, 09:31:03 INFO - 'other-e10s': {'tests': ['a11yr', 09:31:03 INFO - 'ts_paint', 09:31:03 INFO - 'tpaint', 09:31:03 INFO - 'sessionrestore', 09:31:03 INFO - 'sessionrestore_no_auto_restore', 09:31:03 INFO - 'tabpaint']}, 09:31:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:31:03 INFO - 'ts_paint', 09:31:03 INFO - 'tpaint', 09:31:03 INFO - 'sessionrestore', 09:31:03 INFO - 'sessionrestore_no_auto_restore', 09:31:03 INFO - 'tabpaint']}, 09:31:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:31:03 INFO - 'ts_paint', 09:31:03 INFO - 'tpaint', 09:31:03 INFO - 'sessionrestore', 09:31:03 INFO - 'sessionrestore_no_auto_restore', 09:31:03 INFO - 'tabpaint']}, 09:31:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:31:03 INFO - 'tests': ['a11yr', 09:31:03 INFO - 'ts_paint', 09:31:03 INFO - 'tpaint', 09:31:03 INFO - 'sessionrestore', 09:31:03 INFO - 'sessionrestore_no_auto_restore', 09:31:03 INFO - 'tabpaint']}, 09:31:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:31:03 INFO - 'tests': ['a11yr', 09:31:03 INFO - 'ts_paint', 09:31:03 INFO - 'tpaint', 09:31:03 INFO - 'sessionrestore', 09:31:03 INFO - 'sessionrestore_no_auto_restore', 09:31:03 INFO - 'tabpaint']}, 09:31:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:31:03 INFO - 'tests': ['tsvgx', 09:31:03 INFO - 'tsvgr_opacity', 09:31:03 INFO - 'tart', 09:31:03 INFO - 'tscrollx', 09:31:03 INFO - 'cart']}, 09:31:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:31:03 INFO - 'tsvgr_opacity', 09:31:03 INFO - 'tart', 09:31:03 INFO - 'tscrollx', 09:31:03 INFO - 'cart']}, 09:31:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:03 INFO - 'talos_options': ['--disable-e10s'], 09:31:03 INFO - 'tests': ['tp5o']}, 09:31:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:03 INFO - 'tests': ['tp5o']}, 09:31:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:03 INFO - 'talos_options': ['--disable-e10s', 09:31:03 INFO - '--xperf_path', 09:31:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:03 INFO - 'tests': ['tp5n']}, 09:31:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:31:03 INFO - 'talos_options': ['--xperf_path', 09:31:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:31:03 INFO - 'tests': ['tp5n']}}, 09:31:03 INFO - 'talos.zip': {'path': '', 09:31:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:31:03 INFO - Running post-action listener: _resource_record_post_action 09:31:03 INFO - [mozharness: 2016-10-06 16:31:03.747144Z] Finished populate-webroot step (success) 09:31:03 INFO - [mozharness: 2016-10-06 16:31:03.747232Z] Running create-virtualenv step. 09:31:03 INFO - Running pre-action listener: _resource_record_pre_action 09:31:03 INFO - Running main action method: create_virtualenv 09:31:03 INFO - Creating virtualenv /builds/slave/test/build/venv 09:31:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:31:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:31:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:31:03 INFO - Using real prefix '/usr' 09:31:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:31:04 INFO - Installing distribute.............................................................................................................................................................................................done. 09:31:06 INFO - Installing pip.................done. 09:31:06 INFO - Return code: 0 09:31:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x7ffd84817f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2442ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x243ff60>, '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 0x2440d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24411d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 09:31:06 INFO - Running command: ['/builds/slave/test/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/build 09:31:06 INFO - Copy/paste: /builds/slave/test/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 09:31:06 INFO - Using env: {'DISPLAY': ':0', 09:31:06 INFO - 'HOME': '/home/cltbld', 09:31:06 INFO - 'LANG': 'en_US.UTF-8', 09:31:06 INFO - 'LANGUAGE': 'en_US:en', 09:31:06 INFO - 'LOGNAME': 'cltbld', 09:31:06 INFO - 'MAIL': '/var/mail/cltbld', 09:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:06 INFO - 'MOZ_NO_REMOTE': '1', 09:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:06 INFO - 'NO_EM_RESTART': '1', 09:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:06 INFO - 'PWD': '/builds/slave/test', 09:31:06 INFO - 'SHELL': '/bin/bash', 09:31:06 INFO - 'SHLVL': '1', 09:31:06 INFO - 'TERM': 'linux', 09:31:06 INFO - 'TMOUT': '86400', 09:31:06 INFO - 'USER': 'cltbld', 09:31:06 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', 09:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:06 INFO - '_': '/tools/buildbot/bin/python'} 09:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:06 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 09:31:06 INFO - Cleaning up... 09:31:06 INFO - Return code: 0 09:31:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd84817f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2442ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x243ff60>, '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 0x2440d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24411d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 09:31:06 INFO - Running command: ['/builds/slave/test/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>=3.1.1'] in /builds/slave/test/build 09:31:06 INFO - Copy/paste: /builds/slave/test/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>=3.1.1 09:31:06 INFO - Using env: {'DISPLAY': ':0', 09:31:06 INFO - 'HOME': '/home/cltbld', 09:31:06 INFO - 'LANG': 'en_US.UTF-8', 09:31:06 INFO - 'LANGUAGE': 'en_US:en', 09:31:06 INFO - 'LOGNAME': 'cltbld', 09:31:06 INFO - 'MAIL': '/var/mail/cltbld', 09:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:06 INFO - 'MOZ_NO_REMOTE': '1', 09:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:06 INFO - 'NO_EM_RESTART': '1', 09:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:06 INFO - 'PWD': '/builds/slave/test', 09:31:06 INFO - 'SHELL': '/bin/bash', 09:31:06 INFO - 'SHLVL': '1', 09:31:06 INFO - 'TERM': 'linux', 09:31:06 INFO - 'TMOUT': '86400', 09:31:06 INFO - 'USER': 'cltbld', 09:31:06 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', 09:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:06 INFO - '_': '/tools/buildbot/bin/python'} 09:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:06 INFO - Downloading/unpacking psutil>=3.1.1 09:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:31:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:08 INFO - Installing collected packages: psutil 09:31:08 INFO - Running setup.py install for psutil 09:31:08 INFO - building 'psutil._psutil_linux' extension 09:31:08 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 09:31:08 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 09:31:09 INFO - building 'psutil._psutil_posix' extension 09:31:09 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 09:31:09 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 09:31:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:31:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:31:09 INFO - Successfully installed psutil 09:31:09 INFO - Cleaning up... 09:31:09 INFO - Return code: 0 09:31:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:31:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd84817f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2442ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x243ff60>, '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 0x2440d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24411d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 09:31:09 INFO - Running command: ['/builds/slave/test/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.3'] in /builds/slave/test/build 09:31:09 INFO - Copy/paste: /builds/slave/test/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.3 09:31:09 INFO - Using env: {'DISPLAY': ':0', 09:31:09 INFO - 'HOME': '/home/cltbld', 09:31:09 INFO - 'LANG': 'en_US.UTF-8', 09:31:09 INFO - 'LANGUAGE': 'en_US:en', 09:31:09 INFO - 'LOGNAME': 'cltbld', 09:31:09 INFO - 'MAIL': '/var/mail/cltbld', 09:31:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:09 INFO - 'MOZ_NO_REMOTE': '1', 09:31:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:09 INFO - 'NO_EM_RESTART': '1', 09:31:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:09 INFO - 'PWD': '/builds/slave/test', 09:31:09 INFO - 'SHELL': '/bin/bash', 09:31:09 INFO - 'SHLVL': '1', 09:31:09 INFO - 'TERM': 'linux', 09:31:09 INFO - 'TMOUT': '86400', 09:31:09 INFO - 'USER': 'cltbld', 09:31:09 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', 09:31:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:09 INFO - '_': '/tools/buildbot/bin/python'} 09:31:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:31:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:31:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:31:11 INFO - Installing collected packages: mozsystemmonitor 09:31:11 INFO - Running setup.py install for mozsystemmonitor 09:31:11 INFO - Successfully installed mozsystemmonitor 09:31:11 INFO - Cleaning up... 09:31:11 INFO - Return code: 0 09:31:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:31:11 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:11 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd84817f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2442ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x243ff60>, '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 0x2440d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24411d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 09:31:11 INFO - Running command: ['/builds/slave/test/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==2.5.1'] in /builds/slave/test/build 09:31:11 INFO - Copy/paste: /builds/slave/test/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==2.5.1 09:31:11 INFO - Using env: {'DISPLAY': ':0', 09:31:11 INFO - 'HOME': '/home/cltbld', 09:31:11 INFO - 'LANG': 'en_US.UTF-8', 09:31:11 INFO - 'LANGUAGE': 'en_US:en', 09:31:11 INFO - 'LOGNAME': 'cltbld', 09:31:11 INFO - 'MAIL': '/var/mail/cltbld', 09:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:11 INFO - 'MOZ_NO_REMOTE': '1', 09:31:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:11 INFO - 'NO_EM_RESTART': '1', 09:31:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:11 INFO - 'PWD': '/builds/slave/test', 09:31:11 INFO - 'SHELL': '/bin/bash', 09:31:11 INFO - 'SHLVL': '1', 09:31:11 INFO - 'TERM': 'linux', 09:31:11 INFO - 'TMOUT': '86400', 09:31:11 INFO - 'USER': 'cltbld', 09:31:11 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', 09:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:11 INFO - '_': '/tools/buildbot/bin/python'} 09:31:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:11 INFO - Downloading/unpacking jsonschema==2.5.1 09:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:31:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:31:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:31:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:31:13 INFO - Downloading functools32-3.2.3-2.tar.gz 09:31:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:31:14 INFO - warning: no files found matching '*.txt' 09:31:14 INFO - no previously-included directories found matching 'build' 09:31:14 INFO - no previously-included directories found matching 'dist' 09:31:14 INFO - no previously-included directories found matching '.git*' 09:31:14 INFO - Installing collected packages: jsonschema, functools32 09:31:14 INFO - Running setup.py install for functools32 09:31:14 INFO - warning: no files found matching '*.txt' 09:31:14 INFO - no previously-included directories found matching 'build' 09:31:14 INFO - no previously-included directories found matching 'dist' 09:31:14 INFO - no previously-included directories found matching '.git*' 09:31:14 INFO - Successfully installed jsonschema functools32 09:31:14 INFO - Cleaning up... 09:31:14 INFO - Return code: 0 09:31:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd84817f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2442ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x243ff60>, '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 0x2440d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24411d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 09:31:14 INFO - Running command: ['/builds/slave/test/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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:31:14 INFO - Copy/paste: /builds/slave/test/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 functools32==3.2.3-2 09:31:14 INFO - Using env: {'DISPLAY': ':0', 09:31:14 INFO - 'HOME': '/home/cltbld', 09:31:14 INFO - 'LANG': 'en_US.UTF-8', 09:31:14 INFO - 'LANGUAGE': 'en_US:en', 09:31:14 INFO - 'LOGNAME': 'cltbld', 09:31:14 INFO - 'MAIL': '/var/mail/cltbld', 09:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:14 INFO - 'MOZ_NO_REMOTE': '1', 09:31:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:14 INFO - 'NO_EM_RESTART': '1', 09:31:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:14 INFO - 'PWD': '/builds/slave/test', 09:31:14 INFO - 'SHELL': '/bin/bash', 09:31:14 INFO - 'SHLVL': '1', 09:31:14 INFO - 'TERM': 'linux', 09:31:14 INFO - 'TMOUT': '86400', 09:31:14 INFO - 'USER': 'cltbld', 09:31:14 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', 09:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:14 INFO - '_': '/tools/buildbot/bin/python'} 09:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:31:14 INFO - Cleaning up... 09:31:14 INFO - Return code: 0 09:31:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x7ffd84817f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2442ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x243ff60>, '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 0x2440d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24411d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 09:31:14 INFO - Running command: ['/builds/slave/test/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/build 09:31:14 INFO - Copy/paste: /builds/slave/test/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 09:31:14 INFO - Using env: {'DISPLAY': ':0', 09:31:14 INFO - 'HOME': '/home/cltbld', 09:31:14 INFO - 'LANG': 'en_US.UTF-8', 09:31:14 INFO - 'LANGUAGE': 'en_US:en', 09:31:14 INFO - 'LOGNAME': 'cltbld', 09:31:14 INFO - 'MAIL': '/var/mail/cltbld', 09:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:14 INFO - 'MOZ_NO_REMOTE': '1', 09:31:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:14 INFO - 'NO_EM_RESTART': '1', 09:31:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:14 INFO - 'PWD': '/builds/slave/test', 09:31:14 INFO - 'SHELL': '/bin/bash', 09:31:14 INFO - 'SHLVL': '1', 09:31:14 INFO - 'TERM': 'linux', 09:31:14 INFO - 'TMOUT': '86400', 09:31:14 INFO - 'USER': 'cltbld', 09:31:14 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', 09:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:14 INFO - '_': '/tools/buildbot/bin/python'} 09:31:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:14 INFO - Downloading/unpacking blobuploader==1.2.4 09:31:14 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 09:31:14 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 09:31:14 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 09:31:14 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 09:31:14 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 09:31:14 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 09:31:16 INFO - Downloading blobuploader-1.2.4.tar.gz 09:31:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:31:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:31:16 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 09:31:16 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 09:31:16 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 09:31:16 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 09:31:16 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 09:31:16 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 09:31:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:31:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:31:17 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 09:31:17 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 09:31:17 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 09:31:17 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 09:31:17 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 09:31:17 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 09:31:17 INFO - Downloading docopt-0.6.1.tar.gz 09:31:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:31:17 INFO - Installing collected packages: blobuploader, requests, docopt 09:31:17 INFO - Running setup.py install for blobuploader 09:31:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:31:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:31:17 INFO - Running setup.py install for requests 09:31:17 INFO - Running setup.py install for docopt 09:31:17 INFO - Successfully installed blobuploader requests docopt 09:31:17 INFO - Cleaning up... 09:31:17 INFO - Return code: 0 09:31:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:17 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:17 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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 0x7ffd84817f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2442ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x243ff60>, '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 0x2440d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24411d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 09:31:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 09:31:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/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 09:31:17 INFO - Using env: {'DISPLAY': ':0', 09:31:17 INFO - 'HOME': '/home/cltbld', 09:31:17 INFO - 'LANG': 'en_US.UTF-8', 09:31:17 INFO - 'LANGUAGE': 'en_US:en', 09:31:17 INFO - 'LOGNAME': 'cltbld', 09:31:17 INFO - 'MAIL': '/var/mail/cltbld', 09:31:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:17 INFO - 'MOZ_NO_REMOTE': '1', 09:31:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:17 INFO - 'NO_EM_RESTART': '1', 09:31:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:17 INFO - 'PWD': '/builds/slave/test', 09:31:17 INFO - 'SHELL': '/bin/bash', 09:31:17 INFO - 'SHLVL': '1', 09:31:17 INFO - 'TERM': 'linux', 09:31:17 INFO - 'TMOUT': '86400', 09:31:17 INFO - 'USER': 'cltbld', 09:31:17 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', 09:31:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:17 INFO - '_': '/tools/buildbot/bin/python'} 09:31:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:18 INFO - Running setup.py (path:/tmp/pip-eqFfXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:18 INFO - Running setup.py (path:/tmp/pip-V2aFl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:18 INFO - Running setup.py (path:/tmp/pip-YQSYI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:18 INFO - Running setup.py (path:/tmp/pip-GTej2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:18 INFO - Running setup.py (path:/tmp/pip-UOJTGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:18 INFO - Running setup.py (path:/tmp/pip-BmjOI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:18 INFO - Running setup.py (path:/tmp/pip-zj_VYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:18 INFO - Running setup.py (path:/tmp/pip-tJpPyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:18 INFO - Running setup.py (path:/tmp/pip-v1Dcww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:18 INFO - Running setup.py (path:/tmp/pip-TgfVPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:18 INFO - Running setup.py (path:/tmp/pip-XtHyAR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:18 INFO - Running setup.py (path:/tmp/pip-ZrJxy6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:18 INFO - Running setup.py (path:/tmp/pip-Hf_AB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:19 INFO - Running setup.py (path:/tmp/pip-HgdRXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:19 INFO - Running setup.py (path:/tmp/pip-EcghpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:19 INFO - Running setup.py (path:/tmp/pip-32Kjrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:19 INFO - Running setup.py (path:/tmp/pip-vXhyBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:31:19 INFO - Running setup.py install for manifestparser 09:31:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Running setup.py install for mozcrash 09:31:19 INFO - Running setup.py install for mozdebug 09:31:19 INFO - Running setup.py install for mozdevice 09:31:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Running setup.py install for mozfile 09:31:19 INFO - Running setup.py install for mozhttpd 09:31:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:31:19 INFO - Running setup.py install for mozinfo 09:31:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Running setup.py install for mozInstall 09:31:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Running setup.py install for mozleak 09:31:20 INFO - Running setup.py install for mozlog 09:31:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Running setup.py install for moznetwork 09:31:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Running setup.py install for mozprocess 09:31:20 INFO - Running setup.py install for mozprofile 09:31:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Running setup.py install for mozrunner 09:31:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:31:20 INFO - Running setup.py install for mozscreenshot 09:31:20 INFO - Running setup.py install for moztest 09:31:20 INFO - Running setup.py install for mozversion 09:31:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:31:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:31:21 INFO - Cleaning up... 09:31:21 INFO - Return code: 0 09:31:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x7ffd84817f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2442ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x243ff60>, '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 0x2440d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24411d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 09:31:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/config 09:31:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 09:31:21 INFO - Using env: {'DISPLAY': ':0', 09:31:21 INFO - 'HOME': '/home/cltbld', 09:31:21 INFO - 'LANG': 'en_US.UTF-8', 09:31:21 INFO - 'LANGUAGE': 'en_US:en', 09:31:21 INFO - 'LOGNAME': 'cltbld', 09:31:21 INFO - 'MAIL': '/var/mail/cltbld', 09:31:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:21 INFO - 'MOZ_NO_REMOTE': '1', 09:31:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:21 INFO - 'NO_EM_RESTART': '1', 09:31:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:21 INFO - 'PWD': '/builds/slave/test', 09:31:21 INFO - 'SHELL': '/bin/bash', 09:31:21 INFO - 'SHLVL': '1', 09:31:21 INFO - 'TERM': 'linux', 09:31:21 INFO - 'TMOUT': '86400', 09:31:21 INFO - 'USER': 'cltbld', 09:31:21 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', 09:31:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:21 INFO - '_': '/tools/buildbot/bin/python'} 09:31:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:31:21 INFO - Running setup.py (path:/tmp/pip-75VHKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:31:21 INFO - Running setup.py (path:/tmp/pip-Xuylfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:31:21 INFO - Running setup.py (path:/tmp/pip-yE7etH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:31:21 INFO - Running setup.py (path:/tmp/pip-3t5Yzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:31:21 INFO - Running setup.py (path:/tmp/pip-fJC1EK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:31:21 INFO - Running setup.py (path:/tmp/pip-qUvy5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:31:21 INFO - Running setup.py (path:/tmp/pip-4ZMq_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:31:21 INFO - Running setup.py (path:/tmp/pip-q4DqjA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:31:21 INFO - Running setup.py (path:/tmp/pip-urxILq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:31:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:31:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:31:21 INFO - Running setup.py (path:/tmp/pip-PkRqaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:31:22 INFO - Running setup.py (path:/tmp/pip-xDDeLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:31:22 INFO - Running setup.py (path:/tmp/pip-TABr7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:31:22 INFO - Running setup.py (path:/tmp/pip-9cbhty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:31:22 INFO - Running setup.py (path:/tmp/pip-7Bhrey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:22 INFO - Running setup.py (path:/tmp/pip-iqaUB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:31:22 INFO - Running setup.py (path:/tmp/pip-0bc5G2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:31:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:31:22 INFO - Running setup.py (path:/tmp/pip-TketpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:31:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:31:22 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 09:31:22 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 09:31:22 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 09:31:22 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 09:31:22 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 09:31:22 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 09:31:24 INFO - Downloading blessings-1.6.tar.gz 09:31:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:31:24 INFO - Installing collected packages: blessings 09:31:24 INFO - Running setup.py install for blessings 09:31:24 INFO - Successfully installed blessings 09:31:24 INFO - Cleaning up... 09:31:24 INFO - Return code: 0 09:31:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:31:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:24 INFO - Reading from file tmpfile_stdout 09:31:24 INFO - Current package versions: 09:31:24 INFO - argparse == 1.2.1 09:31:24 INFO - blessings == 1.6 09:31:24 INFO - blobuploader == 1.2.4 09:31:24 INFO - docopt == 0.6.1 09:31:24 INFO - functools32 == 3.2.3-2 09:31:24 INFO - jsonschema == 2.5.1 09:31:24 INFO - manifestparser == 1.1 09:31:24 INFO - mozInstall == 1.12 09:31:24 INFO - mozcrash == 0.17 09:31:24 INFO - mozdebug == 0.1 09:31:24 INFO - mozdevice == 0.48 09:31:24 INFO - mozfile == 1.2 09:31:24 INFO - mozhttpd == 0.7 09:31:24 INFO - mozinfo == 0.9 09:31:24 INFO - mozleak == 0.1 09:31:24 INFO - mozlog == 3.4 09:31:24 INFO - moznetwork == 0.27 09:31:24 INFO - mozprocess == 0.23 09:31:24 INFO - mozprofile == 0.28 09:31:24 INFO - mozrunner == 6.12 09:31:24 INFO - mozscreenshot == 0.1 09:31:24 INFO - mozsystemmonitor == 0.3 09:31:24 INFO - moztest == 0.7 09:31:24 INFO - mozversion == 1.4 09:31:24 INFO - psutil == 3.1.1 09:31:24 INFO - requests == 1.2.3 09:31:24 INFO - wsgiref == 0.1.2 09:31:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:31:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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 0x7ffd84817f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2442ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x243ff60>, '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 0x2440d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24411d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 09:31:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/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/build/tests/talos 09:31:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/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 09:31:24 INFO - Using env: {'DISPLAY': ':0', 09:31:24 INFO - 'HOME': '/home/cltbld', 09:31:24 INFO - 'LANG': 'en_US.UTF-8', 09:31:24 INFO - 'LANGUAGE': 'en_US:en', 09:31:24 INFO - 'LOGNAME': 'cltbld', 09:31:24 INFO - 'MAIL': '/var/mail/cltbld', 09:31:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:24 INFO - 'MOZ_NO_REMOTE': '1', 09:31:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:24 INFO - 'NO_EM_RESTART': '1', 09:31:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:24 INFO - 'PWD': '/builds/slave/test', 09:31:24 INFO - 'SHELL': '/bin/bash', 09:31:24 INFO - 'SHLVL': '1', 09:31:24 INFO - 'TERM': 'linux', 09:31:24 INFO - 'TMOUT': '86400', 09:31:24 INFO - 'USER': 'cltbld', 09:31:24 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', 09:31:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:24 INFO - '_': '/tools/buildbot/bin/python'} 09:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:31:25 INFO - Cleaning up... 09:31:25 INFO - Return code: 0 09:31:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:31:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:31:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:31:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/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 0x7ffd84817f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23758a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2442ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x243ff60>, '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 0x2440d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24411d0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', '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', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/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 09:31:25 INFO - Running command: ['/builds/slave/test/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/build 09:31:25 INFO - Copy/paste: /builds/slave/test/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 09:31:25 INFO - Using env: {'DISPLAY': ':0', 09:31:25 INFO - 'HOME': '/home/cltbld', 09:31:25 INFO - 'LANG': 'en_US.UTF-8', 09:31:25 INFO - 'LANGUAGE': 'en_US:en', 09:31:25 INFO - 'LOGNAME': 'cltbld', 09:31:25 INFO - 'MAIL': '/var/mail/cltbld', 09:31:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:25 INFO - 'MOZ_NO_REMOTE': '1', 09:31:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:25 INFO - 'NO_EM_RESTART': '1', 09:31:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:25 INFO - 'PWD': '/builds/slave/test', 09:31:25 INFO - 'SHELL': '/bin/bash', 09:31:25 INFO - 'SHLVL': '1', 09:31:25 INFO - 'TERM': 'linux', 09:31:25 INFO - 'TMOUT': '86400', 09:31:25 INFO - 'USER': 'cltbld', 09:31:25 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', 09:31:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:25 INFO - '_': '/tools/buildbot/bin/python'} 09:31:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:31:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:31:25 INFO - Cleaning up... 09:31:25 INFO - Return code: 0 09:31:25 INFO - Running post-action listener: _resource_record_post_action 09:31:25 INFO - Running post-action listener: _start_resource_monitoring 09:31:25 INFO - Starting resource monitoring. 09:31:25 INFO - [mozharness: 2016-10-06 16:31:25.390620Z] Finished create-virtualenv step (success) 09:31:25 INFO - [mozharness: 2016-10-06 16:31:25.391123Z] Running install step. 09:31:25 INFO - Running pre-action listener: _resource_record_pre_action 09:31:25 INFO - Running main action method: install 09:31:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:31:25 INFO - Reading from file tmpfile_stdout 09:31:25 INFO - Detecting whether we're running mozinstall >=1.0... 09:31:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:31:25 INFO - Reading from file tmpfile_stdout 09:31:25 INFO - Output received: 09:31:25 INFO - Usage: mozinstall [options] installer 09:31:25 INFO - Options: 09:31:25 INFO - -h, --help show this help message and exit 09:31:25 INFO - -d DEST, --destination=DEST 09:31:25 INFO - Directory to install application into. [default: 09:31:25 INFO - "/builds/slave/test"] 09:31:25 INFO - --app=APP Application being installed. [default: firefox] 09:31:25 INFO - mkdir: /builds/slave/test/build/application 09:31:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:31:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:31:41 INFO - Reading from file tmpfile_stdout 09:31:41 INFO - Output received: 09:31:41 INFO - /builds/slave/test/build/application/firefox/firefox 09:31:41 INFO - Running post-action listener: _resource_record_post_action 09:31:41 INFO - [mozharness: 2016-10-06 16:31:41.644507Z] Finished install step (success) 09:31:41 INFO - [mozharness: 2016-10-06 16:31:41.644752Z] Running run-tests step. 09:31:41 INFO - Running pre-action listener: _resource_record_pre_action 09:31:41 INFO - Running main action method: run_tests 09:31:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:31:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:31:41 INFO - Python 2.7.3 09:31:41 INFO - Return code: 0 09:31:41 INFO - grabbing minidump binary from tooltool 09:31: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:31:41 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/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x243ff60>, '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 0x2440d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24411d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:31:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:31:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:31:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:31:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:31:41 INFO - Return code: 0 09:31:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:31:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:31:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:31:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:31:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:31:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:31:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:31:41 INFO - Using env: {'DISPLAY': ':0', 09:31:41 INFO - 'HOME': '/home/cltbld', 09:31:41 INFO - 'LANG': 'en_US.UTF-8', 09:31:41 INFO - 'LANGUAGE': 'en_US:en', 09:31:41 INFO - 'LOGNAME': 'cltbld', 09:31:41 INFO - 'MAIL': '/var/mail/cltbld', 09:31:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:31:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:31:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:31:41 INFO - 'MOZ_NO_REMOTE': '1', 09:31:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:31:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:31:41 INFO - 'NO_EM_RESTART': '1', 09:31:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:31:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:31:41 INFO - 'PWD': '/builds/slave/test', 09:31:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:31:41 INFO - 'SHELL': '/bin/bash', 09:31:41 INFO - 'SHLVL': '1', 09:31:41 INFO - 'TERM': 'linux', 09:31:41 INFO - 'TMOUT': '86400', 09:31:41 INFO - 'USER': 'cltbld', 09:31:41 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250', 09:31:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:31:41 INFO - '_': '/tools/buildbot/bin/python'} 09:31:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:31:41 INFO - mozversion application_buildid: 20161006063500 09:31:41 INFO - mozversion application_changeset: dfa484769f77ab7e87418f4862c3cd676b4a22cd 09:31:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:31:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:31:41 INFO - mozversion application_name: Firefox 09:31:41 INFO - mozversion application_remotingname: firefox-dev 09:31:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:31:41 INFO - mozversion application_vendor: Mozilla 09:31:41 INFO - mozversion application_version: 51.0a2 09:31:41 INFO - mozversion platform_buildid: 20161006063500 09:31:41 INFO - mozversion platform_changeset: dfa484769f77ab7e87418f4862c3cd676b4a22cd 09:31:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:31:41 INFO - mozversion platform_version: 51.0a2 09:31:41 INFO - using testdate: 1475771501 09:31:41 INFO - actual date: 1475771501 09:31:41 INFO - starting webserver on 'localhost:53740' 09:31:41 INFO - SUITE-START | Running 2 tests 09:31:41 INFO - TEST-START | dromaeo_css 09:31:41 INFO - operating with platform_type : linux_ 09:31:41 INFO - Initialising browser for dromaeo_css test... 09:31:41 INFO - TEST-INFO | started process 11370 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWcTDnE/profile http://localhost:53740/getInfo.html) 09:31:46 INFO - PROCESS | 11370 | __metrics Screen width/height:1600/1200 09:31:46 INFO - PROCESS | 11370 | colorDepth:24 09:31:46 INFO - PROCESS | 11370 | Browser inner width/height: 1024/697 09:31:46 INFO - PROCESS | 11370 | __metrics 09:31:46 INFO - PROCESS | 11370 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:31:46 INFO - PROCESS | 11370 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:31:47 INFO - PROCESS | 11370 | console.error: PushService: 09:31:47 INFO - PROCESS | 11370 | stateChangeProcessEnqueue: Error transitioning state 09:31:47 INFO - PROCESS | 11370 | UnknownError 09:31:47 INFO - TEST-INFO | 11370: exit 0 09:31:47 INFO - Browser initialized. 09:31:47 INFO - Running cycle 1/1 for dromaeo_css test... 09:31:47 INFO - TEST-INFO | started process 11461 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWcTDnE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:31:59 INFO - PROCESS | 11461 | RSS: Main: 144842752 09:31:59 INFO - PROCESS | 11461 | 09:32:51 INFO - PROCESS | 11461 | 1475771571041 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] 09:35:00 INFO - PROCESS | 11461 | Cycle 1(1): loaded http://localhost:53740/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53740/tests/dromaeo/cssquery-ext.html) 09:35:01 INFO - PROCESS | 11461 | RSS: Main: 248684544 09:35:01 INFO - PROCESS | 11461 | 09:38:03 INFO - PROCESS | 11461 | Cycle 1(1): loaded http://localhost:53740/tests/dromaeo/cssquery-ext.html (next: http://localhost:53740/tests/dromaeo/cssquery-jquery.html) 09:38:04 INFO - PROCESS | 11461 | RSS: Main: 239370240 09:38:04 INFO - PROCESS | 11461 | 09:41:06 INFO - PROCESS | 11461 | Cycle 1(1): loaded http://localhost:53740/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53740/tests/dromaeo/cssquery-mootools.html) 09:41:07 INFO - PROCESS | 11461 | RSS: Main: 207785984 09:41:07 INFO - PROCESS | 11461 | 09:44:08 INFO - PROCESS | 11461 | Cycle 1(1): loaded http://localhost:53740/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53740/tests/dromaeo/cssquery-prototype.html) 09:44:09 INFO - PROCESS | 11461 | RSS: Main: 175931392 09:44:09 INFO - PROCESS | 11461 | 09:47:12 INFO - PROCESS | 11461 | Cycle 1(1): loaded http://localhost:53740/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53740/tests/dromaeo/cssquery-yui.html) 09:47:12 INFO - PROCESS | 11461 | RSS: Main: 176168960 09:47:12 INFO - PROCESS | 11461 | 09:50:15 INFO - PROCESS | 11461 | Cycle 1(1): loaded http://localhost:53740/tests/dromaeo/cssquery-yui.html (next: http://localhost:53740/tests/dromaeo/cssquery-dojo.html) 09:50:15 INFO - PROCESS | 11461 | RSS: Main: 265592832 09:50:15 INFO - PROCESS | 11461 | 09:50:15 INFO - PROCESS | 11461 | __start_tp_report 09:50:15 INFO - PROCESS | 11461 | _x_x_mozilla_page_load 09:50:15 INFO - PROCESS | 11461 | _x_x_mozilla_page_load_details 09:50:15 INFO - PROCESS | 11461 | |i|pagename|runs| 09:50:15 INFO - PROCESS | 11461 | |0;dojo.html;3144.5754823098882;3187.5378070179822;3192.3774863901535;3210.2295449092226;3222.7099561039504;5634.183043458696;5701.971490142554;5710.029295019846;5711.8000869969555;5738.569607279455;5709.71451427428;5747.453991870766;5765.855853603662;5770.249867517227;5773.682447465388;5225.6603320784025;5237.7381130943495;5259.342582177228;5274.894502109955;5314.548263397616;5169.767360468788;5184.818531351403;5322.946770532303;5332.840014799563;5364.758585863646;5111.897762044757;5125.154349532341;5162.535371816536;5167.509086636764;5210.869728256805;5187.533122019017;5216.739163041832;5260.473952604747;5281.207818827168;5301.549368303698;5087.465816089294;5198.948010519922;5280.128778751539;5316.893662126775;5340.54605358545;5535.03136951032;5573.41479144688;5574.60977731559;5619.775208991636;5649.971750141264;6013.278406591237;6045.6674882881425;6063.514918806485;6130.049842274455;6134.815955521297;5830.941690583082;6075.331713511554;6097.908531372033;6101.938980610182;6105.114758360058;5512.173174023888;5572.470615291581;5670.546356291528;5716.914246286309;5794.188813566104;6342.524310676626;6366.77716279928;6368.267649220306;6370.0763389308295;6373.490120790326;5899.410058994152;6108.664023478751;6110.969445152746;6115.143879856823;6120.755169793248;6015.037593984942;6034.24571928509;6077.817665469955;6107.29766076898;6143.078538219303;6103.389661033861;6118.65917226336;6131.075059010272;6132.237922446167;6138.232720909887;6125.7865398231115;6125.998614523584;6127.4974314983165;6128.506083113493;6132.141360525245;4610.170169369546;4617.792199351037;4658.790354434059;4697.177993851063;4699.882502937454;4156.25187466259;4164.479440069991;4165.604267594574;4165.91668166635;4181.916361672749;5846.503047240947;5846.883062338729;5854.063349864001;5854.619449735754;5856.0630299151935;6008.128821320972;6059.303180134253;6092.99465588173;6125.052321061979;6217.564770466114;5828.737706803204;5847.532197424196;5848.883022339614;5874.32445268791;5889.676067816225;5341.118715411992;5346.358436987586;5354.384245811703;5354.759035843396;5363.436839131835;6125.632462052291;6158.7228574714245;6198.969005155035;6370.0763389308295;6402.03969404593;6080.726367313393;6135.821783266876;6149.907751383644;6149.969250153899;6157.26112866441;6342.936570634234;6347.650879201688;6348.396902294275;6352.555321127476;6356.141920840627;5863.296404431325;5963.642181469126;6025.78909738157;6034.668093254738;6046.969765151146;5732.742026608729;6051.183090282755;6134.478569321657;6188.504919606331;6192.442680158807;6003.519718422603;6019.067044608093;6021.7591296347655;6022.454926061794;6037.849053773515;5829.970850145892;6013.428724271187;6020.578559500793;6023.427774361428;6027.969860150672;5825.3592104867585;5838.854028649148;5852.063669812809;5894.852628684145;5979.372165922689;5982.611130276518;5982.730777115127;5985.790497332572;5991.520678345809;5999.880002400015;5775;5844.649321040752;5847.356790752929;5849.795257165979;5851.678157701367 09:50:15 INFO - PROCESS | 11461 | |1;ext.html;596.8657052163261;601.3865856826037;626.8307556959621;635.9841003974897;662.1987395251745;36226.275474490496;36783.44824827626;36846.447303290406;36881.81559092201;36931.4460283096;46938.29592556114;48366.51633483664;48525.7573712131;52660;52684;9335.346525743218;9475.81048379032;9491.430514169171;9493.81012379752;9494;8617.827643447128;9280.489573073479;9363.48500832454;9388.718338449857;9391.107844754737;8687.913120868805;11165.944170279137;11415.714607134805;11416.029637480782;11792.35142067186;10962.835557466644;11012.17408694351;11035.558577656884;11038;11051.502682379272;8532.317414606816;11219.214654974156;11286.887131128748;11304.37825919574;11305.71735706614;33456.16359591005;43017.56982430167;44069.33895991563;44238.778806105765;47857;3894.1822217334393;4257.787110644455;4465.062336909221;4562.680612357136;4575.565321294472;34323.65676343229;34348.6565134348;34368.6563134368;34550.30899382023;34749.13127171841;4605.953940460619;4638.1187574360765;4684.531546845288;4727.572509930273;4762.952370476286;366848;367442;369441;369622.9999999973;369767;54831.451685482636;56334.7183264081;63834.68082659557;64249.67875160594;64293.678531607045;67869.32130678729;69872;69915.65042174756;69979;70281.64859175672;15916.522504324657;15964.68070638604;16082.276297566637;16085.230624631282;16091.678166436606;12578;12657.620271391874;12658.620241392773;12667.176633518977;12668.556600518938;16277.430289939795;16914.91542542304;18404.447866564024;18470.81529184718;18480.260789568536;6224.439800417984;6287.842803929939;6442.452391546677;6510.21877374718;6701.731930722716;1025.6051420203125;1044.6709286574705;1136.886311368873;1142.9885701142882;1175.6355529785794;33901.83049084788;34755;34772.65227347745;34823;35013.29973400518;1127.0701671121358;1160.9419529023514;1166.7841449331731;1182.8225766134976;1204.9216800907914;18085.547861303574;18112.45662630123;18124.45626631203;18148.818511814978;18294.817051829577;550819;551650;552135;554817;557282;61971.38028619746;62086.37913620896;62207.06689399663;62320.68839655773;62344;1374.8212732344732;1378.6691194113341;1409.4925826702379;1411.9294035298194;1415.2074838090703;32022.51966220462;33065.50401744024;33764;34182.487262690585;34486;54025.72987135039;59760.402395975485;61630.38369616246;61696.38303616906;67326.66336668286;459279;462508;463159;463662;464244;27628;28581.428371432037;31475.213119671276;31951.520727188647;32015.199620008756;48978.75510622424;52431.73784131055;53836;57472.42527574838;57475.71262143662;43413.34879976866;44610;45511.743094703495;45933;46422;44190.558094418644;45214.77392613016;45508.54491455043;46052.539474604826;46597;44549;44698;45039.774801125786;45629.54370456253;45768.54231457776;36958.445623315136;39442.60557394504;39519.80240098781;40486.392704110054;40643.390349145375;29398.265043374086;30132.54801178028;30384.240393990327;30460;30601 09:50:15 INFO - PROCESS | 11461 | |2;jquery.html;397.7514053716427;408.6628531461545;416.4523651398411;424.49485112715865;435.9302511598145;14905.18021508818;16641;20296.289629862957;20654.17383304666;20799.792002079976;18030.278788848464;18446.907765461187;18480.815191848076;18838.152283147258;19020.524486887836;5861.3552509223955;5868.73590688419;5975.58170928033;6030.457258846703;6087.9695601521935;4376.146651402978;5893.705314734246;5961.254843144607;5966.820995370146;5967.970160149215;4545.136424079431;6329.873402531946;6581.7367305307735;6606.603603783764;6719.462443004548;6464.903026454631;6605.07528945948;6638.435732962704;6694.698738556753;6703.631300278483;5854.76580936762;5880.8235752927485;5896.233489646362;5899.410058994066;5900.557458190653;23873.88063059691;24478.632820507708;24593.13924012651;24857.875710621513;24919.377015574573;3011.9548206776917;3255.544223808669;3292.4732042873266;3310.851011704455;3402.8298585070893;15140.697186056328;15306.693866122616;15308.846911530965;15315;15339.539813805604;1925.730397744303;1957.6574099532525;1979.6733538966198;2039.9184032638677;2091.7908209179113;61263.38736612666;73566;82018.58990705009;82306;84668.57665711633;11431.714207144722;11529.244962757428;11647.12646551512;11663.941680291713;11718.355490447937;16130.274137663833;16209.83790162107;16236.67526649484;16293.266802993894;16303.347866085223;8197;8214;8285.83428331442;8487.830243395098;8519.872201916976;7983.481073730189;7988.6405111768945;8099.352051835839;8184;8231.46495477792;6944.756933507303;7003.719851205997;7044.22513523512;7125.89311160333;7145.1425828900865;1915.7605299337583;1923.6922092465138;1931.51712071982;1948.878122770359;1978.337257018886;952.2905435450633;986.7878406142714;1052.2003277508995;1066.2962444786403;1077.21363404715;20746.79253207479;21193;21371.67942480864;21397.46506337354;21425.142994280053;1026.0867827633413;1067.6530127708527;1068.8770791358938;1099.312929419113;1123.685368096934;10986.400499064255;11784.76430471403;11834.940825295818;12292.938535307267;12294.20087694297;468325;482750;488256;489751;489908;89745;98657.50671246598;99332.50333748285;99819.50090249503;100295.49852250692;494.2758858272576;507.9796808127634;510.78802297047054;510.89526647036524;511.68275669085403;11610.187286889837;11824.763504729684;12184.7815218481;12340.568080117153;12473.750524989267;31187;31737;41853.58146418619;42167.57832421637;43427;55714;55889.44110559005;58301.125483116935;59454.405455944885;60372;9164.312676549152;9311.301652375963;9318.301127415336;9337.066293370608;9537.332386732862;10556.472176391304;13628.591142265748;13642.124096115936;13819;14092.084014539023;11533.82699259495;11830.46762895689;11850.881491184979;12012.279263244232;12247.938760306142;9576.904230957602;10883.401412922365;11867.76264474723;12061.939690301493;12211.450484728031;11369.658910232707;11576.594819181288;11615.128865335302;11648;11785.292882427082;8370.204830541088;8844.336674749291;8929.285657147282;9153.816923661623;9302.860457093013;9161;11928.761424771283;12066.638000859988;12282.201656892274;12549.686257843627 09:50:15 INFO - PROCESS | 11461 | |3;mootools.html;1395.7766757318832;1516.4616561120802;1552.2083737293979;1712.6061005968627;1840.5030641726735;8422.789430264238;9189.862152067724;9569.569369378378;9572.186364159046;9916.55375508102;9650.662226822064;9679.419234845915;9708.951455242732;9718.85421718673;9754.658586949457;2612.5950477675897;2641.1680320699015;2646.3251870772924;2646.695630002555;2647.311698958275;2582.9870850645816;2659.9202023939315;2660.640813490183;2664.3738721400455;2665.906693265736;2596.5196438658813;2637.617545455908;2673.70589235184;2675.665541807274;2679.303381120913;2549.490101979602;2561.449288402993;2607.8435293882426;2610.9347266318305;2621.9737802622017;2547.4522977559727;2553.1191738850252;2557.0283292348972;2606.2962999990086;2608.3087981684866;9463.810723785486;9616.615335386647;9674.274429417752;9675.564599593035;9795.265355098396;5562.527185189223;5785.103308987113;5786.132080187964;5787.623804452697;5791.073428251501;3533.823308834548;3549.6095429502734;3599.586047604507;3622.6920711739535;3625.2024554597947;1131;1157.1205883528578;1193.7433451807904;1195.5516681244533;1220.4263995921992;13379.331033448289;13451.192928424325;13503.459861605525;13527.391267392892;13753.449862005507;4857.489963553776;4895.926561101586;5045.318881950963;5127.154019586801;5127.435982041972;10829.67510974672;10842;10843.240973131962;10861.294015888941;10875.45622718861;5597.832065038056;5603.439656034446;5609.102543593006;5618.269624948731;5618.606697531215;4812.759362031885;4825.372701548777;5175.560077393464;5192.376914770176;5212.270282160501;4216.409702641571;4267.487901451846;4299.892502687458;4370.322599997005;4378.474583050054;1293.4502836294537;1320.2408615046386;1322.4049177869997;1336.5522549945877;1336.6324260828155;989.8416253399422;990.2177279948904;1007.5063219022773;1009.0716540782498;1017.3844823881593;6036.909446358308;6118.663473509;6161.075838624242;6438.323975982548;6454.1609590752905;496.0946273051695;502.6054547180446;507.441814004592;514.9109633125986;533.0751146760367;4229.281022226229;4292.91414171721;4338.9349159762;4417.580329868657;4435.068635586498;11482.425878706032;11942.104342174373;12015.939920300518;12030.63908082759;12045.33750643723;11529.827052594217;11829.408529573488;11981.221220620631;11984.700382490333;12028;8905.688300909436;8932.910670893338;9008.32437567172;9018.458892466473;9026.052264512264;6112.388761123852;6181.876362472815;6188.1027251049245;6214.161088253209;6257.2491301044165;10978.615748448767;11048.337099774039;11100.777984440105;11155.55377784912;11179.546658934287;10939.671809845717;10995.285306455055;11149.721256968316;11328.716782080184;11538;7368.226336234618;7647.808804779703;7811.882821757792;7960.880586791087;8076.959615201887;9584.185344245818;9655.951720241354;9659.082387173208;9663.613455461702;9665.9033409665;8410.495370277802;8560;8624.223819856243;8633.136686331316;8834.823303533765;8286.4199506034;8325;8370.790730231793;8411.3270938326;8623.95688021581;7552.39580833543;7588.696452141852;7683.270089341504;7705.845883082419;7747.612619369122;5689.8862022758485;5690.459406356389;5793.3337666167245;5846.590738648424;5901.350851406256;5744.856378590569;5770.394108618535;5850.707464626837;5866.266716660418;5950.7917222896995 09:50:15 INFO - PROCESS | 11461 | |4;prototype.html;338.78995023085685;349.1132523390588;354.10588264631804;359.1559834389187;365.7147425008494;5572.331320241572;5577.804776832807;5579.804706835262;5581.320965936353;5607.327120745516;5461.722966220266;5525.778968841251;5551.611387202887;5556.777728890849;5560.110382338827;3844.4809950656722;4115.279826030448;4294.785260736966;4327.653787696992;4351.977285337943;4344.45694288214;4346.913061738779;4358.629516491102;4364.7817609119575;4388.464037586851;4355.54266801986;4361.3457981302745;4366.868993930188;4378.343248512717;4381.167578160155;4162.687798415116;4166.791630427172;4263.722858014219;4310.80601372939;4323.459567554056;3836.5012548368813;3841.0781412461088;3848.3073046851837;3851.6340947609933;3854.768713877176;5300.284461597674;5317.325042611604;5334.67991920486;5340.25236466895;5341.973290133564;4822.541858523435;4915.517663579905;4920.810239443449;4929.162042452792;4958.950410495885;5664;5668.773249070032;5683.232763576905;5686.971565142189;5709.429057094258;4217.451731274945;4219.1139860629;4236.364545318196;4236.809343579515;4249.617534421917;5673.886522269531;5699.344575373843;5848.853778655525;5896.292444906596;5939.040116761458;4196.545021162654;4336.804843782037;4345.087531618396;4371.934420983688;4376.571003093314;4415.977920110379;4421.093675796454;4424.33634954753;4426.22541055314;4430.158269928767;4824.372831531879;4958.669425370071;4967.577755890789;4968.329275547827;4990.201567749143;5754.5971781975695;5786.392428795;5791.391903850119;5800.520867178842;5805.477507024388;1498.168516473346;1517.8178618565844;1525.557588299403;1526.6565422428414;1528.6331280492602;942.3968660057569;1000.9899901001043;1002.8495725641212;1009.7172791618359;1010.9039641234071;4855.465898751135;4880.902381952341;4886.438059623189;4911.8948236646465;4956.801727930914;5164.715940623302;5176.301199338116;5183.377994640592;5210.6613070150315;5260.421353651095;4949.975250123798;4953.628477864175;4964.716230533995;5212.530872221519;5660.660360378308;3156.4375634061284;3165.778395512338;3180.9522857157162;3183.4906414973493;3194.7603929705365;6023.427774361517;6195.141457562713;6196.783112591038;6206.782762603287;6207.379262073847;6085;6094.908576371358;6096.664683442365;6122.20411346531;6135.754569817157;6154.876902461926;6158.692065396713;6171.814845554641;6180.629162250189;6185.103160041859;5856.635982433002;5878.353381128158;5980.910286345796;5988.191594134736;6011.579189456696;5828.446297601721;5887.793927212699;5900.734466948912;5909.881802363842;5931.970340148272;5647.20939068539;5915;6043.2748070231855;6060.15165374565;6151.323354431099;4224.690345992752;4296.291111966616;4297.462817147856;4332.3068309070395;4342.196693611692;5805.477507024388;5976.133460648269;5983;6019.458248757633;6047.758089676539;5865.853353666193;5867.239828051653;5878.412158784087;5889.263842019747;5891.852703682443;5810.970945145247;5824.7381466378965;5829.358770535323;5830.737616807339;5863.73613187399;5536.169574563848;5772.94227057724;5792.94207057924;5797.79707710245;5825.70871456434;5889.440503152194;5902.6458412495385;5908.881822363443;5909.852253693692;5911.261092363455;5573.832785016456;5752.568557358131;5776.162456443876;5819.243498345188;5827.213326200908 09:50:15 INFO - PROCESS | 11461 | |5;yui.html;778.2645400096907;795.7334293011841;836.6736972580694;856.9459564735379;885.0264708820297;15832.44586439475;16494.092824894626;16519.83480165198;16553.83446165538;16717.58206044848;15224.619384515392;15546.144962027083;15548.45580404686;15550.377984880619;15563.922180389081;728.3917928529658;746.6005686957479;843.4264700003979;844.5692696724653;866.5927014303268;881.1584936385736;883.9160279773412;884.2086332732233;884.747846863644;884.8451520983834;939.2626787971444;939.2767568971888;940.5156344482563;940.6989763275755;940.9247260219166;767.17528656694;784.352908850198;786.0371045469311;790.3716545346464;936.5598168860624;659.0740010285557;675.9526833121683;677.3734295776387;681.6251061915976;681.7000519771334;15182.240887955559;15217.467388641455;15236.085834849833;15251.847481525154;15261.38954441821;13530.797038044437;13774.65563360914;13942.163470191821;14361.856381436157;14601.92699036498;12527;12568;12574.81137782934;12967;13003.284819334933;4386.298192289219;4405.515393306734;4447.488538818045;4556.544345565417;4688;65616;68978.65510672465;69679.65160174167;83261.58369208116;85613.14386856114;10980.615678451215;11101.222914396076;11204.887951120547;11701.765964680659;11795.705107372385;20859.37421877346;21348.466288342916;21390;21423.57152856934;21432.571348572943;8412.537310447862;8524.445911015764;8538.573071346407;8701.651933922572;8732.170443807829;5118.513741194581;5135.383753949475;5239.056969745407;5340.305760251143;5420.24116623673;3893.6301051400073;4068.4710987571425;4070.0842310479907;4075.5109386873764;4084.36692312692;700.8773464643768;734.6400263870665;746.358132006474;747.5402627384119;748.0761259844088;753.4010461682975;765.5751058162662;772.9690812367443;777.9183185765527;801.8997625296838;9861;10264.692059238234;10277.486125693835;10278.126359259546;10347.223958203165;663.8141320430287;687.5462194951308;690.823839920817;741.5847125609675;773.4972268025829;4654.464736555272;4768.403949506312;4770.522947705202;4809.206480930678;4810.518948105161;125562.37218813847;127027.36486317695;127042;128130.35934820453;132033.3398333002;66595.00107498391;93960.53019734858;105266;109325;112949.43525282321;95797.52101239588;98341.50829245808;98694;99546;101979;9932.702018939442;10562.31344962575;10630.202734795075;10879.129669626253;10932.508037138188;52816.471835281154;53151.468485314654;54398.184027240415;54544.18183727327;55294.72352638211;70302.29697702957;78311;78638.21361786309;80191;81015.18984810077;13853.584392468241;14023.929880350533;14177.787333190217;14269.001169918007;14293.57119286423;14733.705325893636;14842.40630374773;15604.531864044096;15729;17240.741388878927;14175.432982680577;14210.502632407815;14377.85622143807;14411.711765764836;15308.61728456753;12953.935230324165;13606.86393136056;14043.859561404255;14347.282635868374;15318.846811532188;13634.1137826041;13793.448262069405;13911.791323130363;13942.930285348508;14360.784588231394;11861.406929653655;12034.518619255481;12428.937855310665;12638.494460221857;12800.61598152057;11748.70628234301;11764.882351176379;11956.461959211681;12250.571230006906;12527.56153534622 09:50:15 INFO - PROCESS | 11461 | __end_tp_report 09:50:15 INFO - PROCESS | 11461 | __start_cc_report 09:50:15 INFO - PROCESS | 11461 | _x_x_mozilla_cycle_collect,858 09:50:15 INFO - PROCESS | 11461 | __end_cc_report 09:50:15 INFO - PROCESS | 11461 | __startTimestamp1475772615694__endTimestamp 09:50:15 INFO - PROCESS | 11461 | 09:50:15 INFO - PROCESS | 11461 | ------- Summary: start ------- 09:50:15 INFO - PROCESS | 11461 | Number of tests: 6 09:50:15 INFO - PROCESS | 11461 | 09:50:15 INFO - PROCESS | 11461 | [#0] dojo.html Cycles:165 Average:5679.71 Median:5854.62 stddev:654.48 (11.2%) stddev-sans-first:625.54 09:50:15 INFO - PROCESS | 11461 | Values: 3144.6 3187.5 3192.4 3210.2 3222.7 5634.2 5702.0 5710.0 5711.8 5738.6 5709.7 5747.5 5765.9 5770.2 5773.7 5225.7 5237.7 5259.3 5274.9 5314.5 5169.8 5184.8 5322.9 5332.8 5364.8 5111.9 5125.2 5162.5 5167.5 5210.9 5187.5 5216.7 5260.5 5281.2 5301.5 5087.5 5198.9 5280.1 5316.9 5340.5 5535.0 5573.4 5574.6 5619.8 5650.0 6013.3 6045.7 6063.5 6130.0 6134.8 5830.9 6075.3 6097.9 6101.9 6105.1 5512.2 5572.5 5670.5 5716.9 5794.2 6342.5 6366.8 6368.3 6370.1 6373.5 5899.4 6108.7 6111.0 6115.1 6120.8 6015.0 6034.2 6077.8 6107.3 6143.1 6103.4 6118.7 6131.1 6132.2 6138.2 6125.8 6126.0 6127.5 6128.5 6132.1 4610.2 4617.8 4658.8 4697.2 4699.9 4156.3 4164.5 4165.6 4165.9 4181.9 5846.5 5846.9 5854.1 5854.6 5856.1 6008.1 6059.3 6093.0 6125.1 6217.6 5828.7 5847.5 5848.9 5874.3 5889.7 5341.1 5346.4 5354.4 5354.8 5363.4 6125.6 6158.7 6199.0 6370.1 6402.0 6080.7 6135.8 6149.9 6150.0 6157.3 6342.9 6347.7 6348.4 6352.6 6356.1 5863.3 5963.6 6025.8 6034.7 6047.0 5732.7 6051.2 6134.5 6188.5 6192.4 6003.5 6019.1 6021.8 6022.5 6037.8 5830.0 6013.4 6020.6 6023.4 6028.0 5825.4 5838.9 5852.1 5894.9 5979.4 5982.6 5982.7 5985.8 5991.5 5999.9 5775.0 5844.6 5847.4 5849.8 5851.7 09:50:15 INFO - PROCESS | 11461 | 09:50:15 INFO - PROCESS | 11461 | [#1] ext.html Cycles:180 Average:63751.07 Median:23637.68 stddev:123859.53 (524.0%) stddev-sans-first:124116.22 09:50:15 INFO - PROCESS | 11461 | Values: 596.9 601.4 626.8 636.0 662.2 36226.3 36783.4 36846.4 36881.8 36931.4 46938.3 48366.5 48525.8 52660.0 52684.0 9335.3 9475.8 9491.4 9493.8 9494.0 8617.8 9280.5 9363.5 9388.7 9391.1 8687.9 11165.9 11415.7 11416.0 11792.4 10962.8 11012.2 11035.6 11038.0 11051.5 8532.3 11219.2 11286.9 11304.4 11305.7 33456.2 43017.6 44069.3 44238.8 47857.0 3894.2 4257.8 4465.1 4562.7 4575.6 34323.7 34348.7 34368.7 34550.3 34749.1 4606.0 4638.1 4684.5 4727.6 4763.0 366848.0 367442.0 369441.0 369623.0 369767.0 54831.5 56334.7 63834.7 64249.7 64293.7 67869.3 69872.0 69915.7 69979.0 70281.6 15916.5 15964.7 16082.3 16085.2 16091.7 12578.0 12657.6 12658.6 12667.2 12668.6 16277.4 16914.9 18404.4 18470.8 18480.3 6224.4 6287.8 6442.5 6510.2 6701.7 1025.6 1044.7 1136.9 1143.0 1175.6 33901.8 34755.0 34772.7 34823.0 35013.3 1127.1 1160.9 1166.8 1182.8 1204.9 18085.5 18112.5 18124.5 18148.8 18294.8 550819.0 551650.0 552135.0 554817.0 557282.0 61971.4 62086.4 62207.1 62320.7 62344.0 1374.8 1378.7 1409.5 1411.9 1415.2 32022.5 33065.5 33764.0 34182.5 34486.0 54025.7 59760.4 61630.4 61696.4 67326.7 459279.0 462508.0 463159.0 463662.0 464244.0 27628.0 28581.4 31475.2 31951.5 32015.2 48978.8 52431.7 53836.0 57472.4 57475.7 43413.3 44610.0 45511.7 45933.0 46422.0 44190.6 45214.8 45508.5 46052.5 46597.0 44549.0 44698.0 45039.8 45629.5 45768.5 36958.4 39442.6 39519.8 40486.4 40643.4 29398.3 30132.5 30384.2 30460.0 30601.0 09:50:15 INFO - PROCESS | 11461 | 09:50:15 INFO - PROCESS | 11461 | [#2] jquery.html Cycles:180 Average:29054.87 Median:3274.01 stddev:79808.25 (2437.6%) stddev-sans-first:80003.12 09:50:15 INFO - PROCESS | 11461 | Values: 397.8 408.7 416.5 424.5 435.9 14905.2 16641.0 20296.3 20654.2 20799.8 18030.3 18446.9 18480.8 18838.2 19020.5 5861.4 5868.7 5975.6 6030.5 6088.0 4376.1 5893.7 5961.3 5966.8 5968.0 4545.1 6329.9 6581.7 6606.6 6719.5 6464.9 6605.1 6638.4 6694.7 6703.6 5854.8 5880.8 5896.2 5899.4 5900.6 23873.9 24478.6 24593.1 24857.9 24919.4 3012.0 3255.5 3292.5 3310.9 3402.8 15140.7 15306.7 15308.8 15315.0 15339.5 1925.7 1957.7 1979.7 2039.9 2091.8 61263.4 73566.0 82018.6 82306.0 84668.6 11431.7 11529.2 11647.1 11663.9 11718.4 16130.3 16209.8 16236.7 16293.3 16303.3 8197.0 8214.0 8285.8 8487.8 8519.9 7983.5 7988.6 8099.4 8184.0 8231.5 6944.8 7003.7 7044.2 7125.9 7145.1 1915.8 1923.7 1931.5 1948.9 1978.3 952.3 986.8 1052.2 1066.3 1077.2 20746.8 21193.0 21371.7 21397.5 21425.1 1026.1 1067.7 1068.9 1099.3 1123.7 10986.4 11784.8 11834.9 12292.9 12294.2 468325.0 482750.0 488256.0 489751.0 489908.0 89745.0 98657.5 99332.5 99819.5 100295.5 494.3 508.0 510.8 510.9 511.7 11610.2 11824.8 12184.8 12340.6 12473.8 31187.0 31737.0 41853.6 42167.6 43427.0 55714.0 55889.4 58301.1 59454.4 60372.0 9164.3 9311.3 9318.3 9337.1 9537.3 10556.5 13628.6 13642.1 13819.0 14092.1 11533.8 11830.5 11850.9 12012.3 12247.9 9576.9 10883.4 11867.8 12061.9 12211.5 11369.7 11576.6 11615.1 11648.0 11785.3 8370.2 8844.3 8929.3 9153.8 9302.9 9161.0 11928.8 12066.6 12282.2 12549.7 09:50:15 INFO - PROCESS | 11461 | 09:50:15 INFO - PROCESS | 11461 | [#3] mootools.html Cycles:180 Average:6294.37 Median:4876.71 stddev:3619.45 (74.2%) stddev-sans-first:3610.88 09:50:15 INFO - PROCESS | 11461 | Values: 1395.8 1516.5 1552.2 1712.6 1840.5 8422.8 9189.9 9569.6 9572.2 9916.6 9650.7 9679.4 9709.0 9718.9 9754.7 2612.6 2641.2 2646.3 2646.7 2647.3 2583.0 2659.9 2660.6 2664.4 2665.9 2596.5 2637.6 2673.7 2675.7 2679.3 2549.5 2561.4 2607.8 2610.9 2622.0 2547.5 2553.1 2557.0 2606.3 2608.3 9463.8 9616.6 9674.3 9675.6 9795.3 5562.5 5785.1 5786.1 5787.6 5791.1 3533.8 3549.6 3599.6 3622.7 3625.2 1131.0 1157.1 1193.7 1195.6 1220.4 13379.3 13451.2 13503.5 13527.4 13753.4 4857.5 4895.9 5045.3 5127.2 5127.4 10829.7 10842.0 10843.2 10861.3 10875.5 5597.8 5603.4 5609.1 5618.3 5618.6 4812.8 4825.4 5175.6 5192.4 5212.3 4216.4 4267.5 4299.9 4370.3 4378.5 1293.5 1320.2 1322.4 1336.6 1336.6 989.8 990.2 1007.5 1009.1 1017.4 6036.9 6118.7 6161.1 6438.3 6454.2 496.1 502.6 507.4 514.9 533.1 4229.3 4292.9 4338.9 4417.6 4435.1 11482.4 11942.1 12015.9 12030.6 12045.3 11529.8 11829.4 11981.2 11984.7 12028.0 8905.7 8932.9 9008.3 9018.5 9026.1 6112.4 6181.9 6188.1 6214.2 6257.2 10978.6 11048.3 11100.8 11155.6 11179.5 10939.7 10995.3 11149.7 11328.7 11538.0 7368.2 7647.8 7811.9 7960.9 8077.0 9584.2 9656.0 9659.1 9663.6 9665.9 8410.5 8560.0 8624.2 8633.1 8834.8 8286.4 8325.0 8370.8 8411.3 8624.0 7552.4 7588.7 7683.3 7705.8 7747.6 5689.9 5690.5 5793.3 5846.6 5901.4 5744.9 5770.4 5850.7 5866.3 5950.8 09:50:15 INFO - PROCESS | 11461 | 09:50:15 INFO - PROCESS | 11461 | [#4] prototype.html Cycles:180 Average:4846.33 Median:5337.47 stddev:1409.72 (26.4%) stddev-sans-first:1372.47 09:50:15 INFO - PROCESS | 11461 | Values: 338.8 349.1 354.1 359.2 365.7 5572.3 5577.8 5579.8 5581.3 5607.3 5461.7 5525.8 5551.6 5556.8 5560.1 3844.5 4115.3 4294.8 4327.7 4352.0 4344.5 4346.9 4358.6 4364.8 4388.5 4355.5 4361.3 4366.9 4378.3 4381.2 4162.7 4166.8 4263.7 4310.8 4323.5 3836.5 3841.1 3848.3 3851.6 3854.8 5300.3 5317.3 5334.7 5340.3 5342.0 4822.5 4915.5 4920.8 4929.2 4959.0 5664.0 5668.8 5683.2 5687.0 5709.4 4217.5 4219.1 4236.4 4236.8 4249.6 5673.9 5699.3 5848.9 5896.3 5939.0 4196.5 4336.8 4345.1 4371.9 4376.6 4416.0 4421.1 4424.3 4426.2 4430.2 4824.4 4958.7 4967.6 4968.3 4990.2 5754.6 5786.4 5791.4 5800.5 5805.5 1498.2 1517.8 1525.6 1526.7 1528.6 942.4 1001.0 1002.8 1009.7 1010.9 4855.5 4880.9 4886.4 4911.9 4956.8 5164.7 5176.3 5183.4 5210.7 5260.4 4950.0 4953.6 4964.7 5212.5 5660.7 3156.4 3165.8 3181.0 3183.5 3194.8 6023.4 6195.1 6196.8 6206.8 6207.4 6085.0 6094.9 6096.7 6122.2 6135.8 6154.9 6158.7 6171.8 6180.6 6185.1 5856.6 5878.4 5980.9 5988.2 6011.6 5828.4 5887.8 5900.7 5909.9 5932.0 5647.2 5915.0 6043.3 6060.2 6151.3 4224.7 4296.3 4297.5 4332.3 4342.2 5805.5 5976.1 5983.0 6019.5 6047.8 5865.9 5867.2 5878.4 5889.3 5891.9 5811.0 5824.7 5829.4 5830.7 5863.7 5536.2 5772.9 5792.9 5797.8 5825.7 5889.4 5902.6 5908.9 5909.9 5911.3 5573.8 5752.6 5776.2 5819.2 5827.2 09:50:15 INFO - PROCESS | 11461 | 09:50:15 INFO - PROCESS | 11461 | [#5] yui.html Cycles:180 Average:21919.42 Median:4079.94 stddev:32014.06 (784.7%) stddev-sans-first:32064.51 09:50:15 INFO - PROCESS | 11461 | Values: 778.3 795.7 836.7 856.9 885.0 15832.4 16494.1 16519.8 16553.8 16717.6 15224.6 15546.1 15548.5 15550.4 15563.9 728.4 746.6 843.4 844.6 866.6 881.2 883.9 884.2 884.7 884.8 939.3 939.3 940.5 940.7 940.9 767.2 784.4 786.0 790.4 936.6 659.1 676.0 677.4 681.6 681.7 15182.2 15217.5 15236.1 15251.8 15261.4 13530.8 13774.7 13942.2 14361.9 14601.9 12527.0 12568.0 12574.8 12967.0 13003.3 4386.3 4405.5 4447.5 4556.5 4688.0 65616.0 68978.7 69679.7 83261.6 85613.1 10980.6 11101.2 11204.9 11701.8 11795.7 20859.4 21348.5 21390.0 21423.6 21432.6 8412.5 8524.4 8538.6 8701.7 8732.2 5118.5 5135.4 5239.1 5340.3 5420.2 3893.6 4068.5 4070.1 4075.5 4084.4 700.9 734.6 746.4 747.5 748.1 753.4 765.6 773.0 777.9 801.9 9861.0 10264.7 10277.5 10278.1 10347.2 663.8 687.5 690.8 741.6 773.5 4654.5 4768.4 4770.5 4809.2 4810.5 125562.4 127027.4 127042.0 128130.4 132033.3 66595.0 93960.5 105266.0 109325.0 112949.4 95797.5 98341.5 98694.0 99546.0 101979.0 9932.7 10562.3 10630.2 10879.1 10932.5 52816.5 53151.5 54398.2 54544.2 55294.7 70302.3 78311.0 78638.2 80191.0 81015.2 13853.6 14023.9 14177.8 14269.0 14293.6 14733.7 14842.4 15604.5 15729.0 17240.7 14175.4 14210.5 14377.9 14411.7 15308.6 12953.9 13606.9 14043.9 14347.3 15318.8 13634.1 13793.4 13911.8 13942.9 14360.8 11861.4 12034.5 12428.9 12638.5 12800.6 11748.7 11764.9 11956.5 12250.6 12527.6 09:50:15 INFO - PROCESS | 11461 | -------- Summary: end -------- 09:50:15 INFO - PROCESS | 11461 | 09:50:16 INFO - TEST-INFO | 11461: exit 0 09:50:16 INFO - TEST-OK | dromaeo_css | took 1114449ms 09:50:16 INFO - TEST-START | kraken 09:50:16 INFO - operating with platform_type : linux_ 09:50:16 INFO - Initialising browser for kraken test... 09:50:16 INFO - TEST-INFO | started process 11783 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpphlBjU/profile http://localhost:53740/getInfo.html) 09:50:20 INFO - PROCESS | 11783 | __metrics Screen width/height:1600/1200 09:50:20 INFO - PROCESS | 11783 | colorDepth:24 09:50:20 INFO - PROCESS | 11783 | Browser inner width/height: 1024/697 09:50:20 INFO - PROCESS | 11783 | __metrics 09:50:21 INFO - PROCESS | 11783 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:50:21 INFO - PROCESS | 11783 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:50:21 INFO - PROCESS | 11783 | console.error: PushService: 09:50:21 INFO - PROCESS | 11783 | stateChangeProcessEnqueue: Error transitioning state 09:50:21 INFO - PROCESS | 11783 | UnknownError 09:50:22 INFO - TEST-INFO | 11783: exit 0 09:50:22 INFO - Browser initialized. 09:50:22 INFO - Running cycle 1/1 for kraken test... 09:50:22 INFO - TEST-INFO | started process 11873 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpphlBjU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:50:34 INFO - PROCESS | 11873 | RSS: Main: 144347136 09:50:34 INFO - PROCESS | 11873 | 09:50:36 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53740/tests/kraken/driver.html?testName=audio-beat-detection) 09:50:37 INFO - PROCESS | 11873 | RSS: Main: 185831424 09:50:37 INFO - PROCESS | 11873 | 09:50:40 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53740/tests/kraken/driver.html?testName=audio-dft) 09:50:40 INFO - PROCESS | 11873 | RSS: Main: 175652864 09:50:40 INFO - PROCESS | 11873 | 09:50:43 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53740/tests/kraken/driver.html?testName=audio-fft) 09:50:44 INFO - PROCESS | 11873 | RSS: Main: 267673600 09:50:44 INFO - PROCESS | 11873 | 09:50:46 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53740/tests/kraken/driver.html?testName=audio-oscillator) 09:50:46 INFO - PROCESS | 11873 | RSS: Main: 172711936 09:50:46 INFO - PROCESS | 11873 | 09:50:48 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53740/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:50:49 INFO - PROCESS | 11873 | RSS: Main: 174415872 09:50:49 INFO - PROCESS | 11873 | 09:50:52 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53740/tests/kraken/driver.html?testName=imaging-darkroom) 09:50:52 INFO - PROCESS | 11873 | RSS: Main: 211394560 09:50:52 INFO - PROCESS | 11873 | 09:50:56 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53740/tests/kraken/driver.html?testName=imaging-desaturate) 09:50:56 INFO - PROCESS | 11873 | RSS: Main: 209633280 09:50:56 INFO - PROCESS | 11873 | 09:50:59 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53740/tests/kraken/driver.html?testName=json-parse-financial) 09:51:00 INFO - PROCESS | 11873 | RSS: Main: 208994304 09:51:00 INFO - PROCESS | 11873 | 09:51:01 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53740/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:51:02 INFO - PROCESS | 11873 | RSS: Main: 171364352 09:51:02 INFO - PROCESS | 11873 | 09:51:04 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53740/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:51:04 INFO - PROCESS | 11873 | RSS: Main: 178196480 09:51:04 INFO - PROCESS | 11873 | 09:51:06 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53740/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:51:07 INFO - PROCESS | 11873 | RSS: Main: 187797504 09:51:07 INFO - PROCESS | 11873 | 09:51:09 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53740/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:51:10 INFO - PROCESS | 11873 | RSS: Main: 189792256 09:51:10 INFO - PROCESS | 11873 | 09:51:13 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53740/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:51:13 INFO - PROCESS | 11873 | RSS: Main: 191766528 09:51:13 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | Cycle 1(1): loaded http://localhost:53740/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53740/tests/kraken/driver.html?testName=ai-astar) 09:51:15 INFO - PROCESS | 11873 | RSS: Main: 241942528 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | __start_tp_report 09:51:15 INFO - PROCESS | 11873 | _x_x_mozilla_page_load 09:51:15 INFO - PROCESS | 11873 | _x_x_mozilla_page_load_details 09:51:15 INFO - PROCESS | 11873 | |i|pagename|runs| 09:51:15 INFO - PROCESS | 11873 | |0;ai-astar;107;110;110;110;109;110;110;109;109;110 09:51:15 INFO - PROCESS | 11873 | |1;audio-beat-detection;141;116;117;164;123;119;118;119;150;118 09:51:15 INFO - PROCESS | 11873 | |2;audio-dft;160;161;161;160;160;162;160;160;161;161 09:51:15 INFO - PROCESS | 11873 | |3;audio-fft;76;76;75;109;76;77;76;76;97;76 09:51:15 INFO - PROCESS | 11873 | |4;audio-oscillator;82;80;116;81;81;81;99;81;81;106 09:51:15 INFO - PROCESS | 11873 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;116;116 09:51:15 INFO - PROCESS | 11873 | |6;imaging-darkroom;146;146;147;145;146;145;146;146;147;145 09:51:15 INFO - PROCESS | 11873 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 09:51:15 INFO - PROCESS | 11873 | |8;json-parse-financial;49;49;50;49;50;49;52;49;49;50 09:51:15 INFO - PROCESS | 11873 | |9;json-stringify-tinderbox;58;59;58;59;58;58;57;58;58;59 09:51:15 INFO - PROCESS | 11873 | |10;stanford-crypto-aes;74;74;73;74;73;77;82;72;80;73 09:51:15 INFO - PROCESS | 11873 | |11;stanford-crypto-ccm;123;129;125;128;118;120;129;121;161;143 09:51:15 INFO - PROCESS | 11873 | |12;stanford-crypto-pbkdf2;151;153;151;151;152;152;152;156;152;150 09:51:15 INFO - PROCESS | 11873 | |13;stanford-crypto-sha256-iterative;63;63;63;61;61;61;61;65;60;61 09:51:15 INFO - PROCESS | 11873 | __end_tp_report 09:51:15 INFO - PROCESS | 11873 | __start_cc_report 09:51:15 INFO - PROCESS | 11873 | _x_x_mozilla_cycle_collect,1565 09:51:15 INFO - PROCESS | 11873 | __end_cc_report 09:51:15 INFO - PROCESS | 11873 | __startTimestamp1475772675568__endTimestamp 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | ------- Summary: start ------- 09:51:15 INFO - PROCESS | 11873 | Number of tests: 14 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.00 stddev:0.97 (0.9%) stddev-sans-first:0.50 09:51:15 INFO - PROCESS | 11873 | Values: 107.0 110.0 110.0 110.0 109.0 110.0 110.0 109.0 109.0 110.0 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:121.00 stddev:16.99 (14.0%) stddev-sans-first:17.41 09:51:15 INFO - PROCESS | 11873 | Values: 141.0 116.0 117.0 164.0 123.0 119.0 118.0 119.0 150.0 118.0 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.71 09:51:15 INFO - PROCESS | 11873 | Values: 160.0 161.0 161.0 160.0 160.0 162.0 160.0 160.0 161.0 161.0 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#3] audio-fft Cycles:10 Average:81.40 Median:76.00 stddev:11.74 (15.4%) stddev-sans-first:12.29 09:51:15 INFO - PROCESS | 11873 | Values: 76.0 76.0 75.0 109.0 76.0 77.0 76.0 76.0 97.0 76.0 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#4] audio-oscillator Cycles:10 Average:88.80 Median:81.00 stddev:13.20 (16.3%) stddev-sans-first:13.77 09:51:15 INFO - PROCESS | 11873 | Values: 82.0 80.0 116.0 81.0 81.0 81.0 99.0 81.0 81.0 106.0 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 09:51:15 INFO - PROCESS | 11873 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 09:51:15 INFO - PROCESS | 11873 | Values: 146.0 146.0 147.0 145.0 146.0 145.0 146.0 146.0 147.0 145.0 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 09:51:15 INFO - PROCESS | 11873 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:49.50 stddev:0.97 (2.0%) stddev-sans-first:1.00 09:51:15 INFO - PROCESS | 11873 | Values: 49.0 49.0 50.0 49.0 50.0 49.0 52.0 49.0 49.0 50.0 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#9] json-stringify-tinderbox Cycles:10 Average:58.20 Median:58.00 stddev:0.63 (1.1%) stddev-sans-first:0.67 09:51:15 INFO - PROCESS | 11873 | Values: 58.0 59.0 58.0 59.0 58.0 58.0 57.0 58.0 58.0 59.0 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#10] stanford-crypto-aes Cycles:10 Average:75.20 Median:74.00 stddev:3.36 (4.5%) stddev-sans-first:3.54 09:51:15 INFO - PROCESS | 11873 | Values: 74.0 74.0 73.0 74.0 73.0 77.0 82.0 72.0 80.0 73.0 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#11] stanford-crypto-ccm Cycles:10 Average:129.70 Median:128.50 stddev:13.06 (10.2%) stddev-sans-first:13.62 09:51:15 INFO - PROCESS | 11873 | Values: 123.0 129.0 125.0 128.0 118.0 120.0 129.0 121.0 161.0 143.0 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.00 Median:152.00 stddev:1.63 (1.1%) stddev-sans-first:1.69 09:51:15 INFO - PROCESS | 11873 | Values: 151.0 153.0 151.0 151.0 152.0 152.0 152.0 156.0 152.0 150.0 09:51:15 INFO - PROCESS | 11873 | 09:51:15 INFO - PROCESS | 11873 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:1.52 (2.5%) stddev-sans-first:1.56 09:51:15 INFO - PROCESS | 11873 | Values: 63.0 63.0 63.0 61.0 61.0 61.0 61.0 65.0 60.0 61.0 09:51:15 INFO - PROCESS | 11873 | -------- Summary: end -------- 09:51:15 INFO - PROCESS | 11873 | 09:51:16 INFO - TEST-INFO | 11873: exit 0 09:51:16 INFO - TEST-OK | kraken | took 60175ms 09:51:16 INFO - SUITE-END | took 1174s 09:51:16 INFO - Completed test suite (00:19:34) 09:51:16 INFO - javascript benchmark 09:51:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.5754823098882, 3187.5378070179822, 3192.3774863901535, 3210.2295449092226, 3222.7099561039504, 5634.183043458696, 5701.971490142554, 5710.029295019846, 5711.8000869969555, 5738.569607279455, 5709.71451427428, 5747.453991870766, 5765.855853603662, 5770.249867517227, 5773.682447465388, 5225.6603320784025, 5237.7381130943495, 5259.342582177228, 5274.894502109955, 5314.548263397616, 5169.767360468788, 5184.818531351403, 5322.946770532303, 5332.840014799563, 5364.758585863646, 5111.897762044757, 5125.154349532341, 5162.535371816536, 5167.509086636764, 5210.869728256805, 5187.533122019017, 5216.739163041832, 5260.473952604747, 5281.207818827168, 5301.549368303698, 5087.465816089294, 5198.948010519922, 5280.128778751539, 5316.893662126775, 5340.54605358545, 5535.03136951032, 5573.41479144688, 5574.60977731559, 5619.775208991636, 5649.971750141264, 6013.278406591237, 6045.6674882881425, 6063.514918806485, 6130.049842274455, 6134.815955521297, 5830.941690583082, 6075.331713511554, 6097.908531372033, 6101.938980610182, 6105.114758360058, 5512.173174023888, 5572.470615291581, 5670.546356291528, 5716.914246286309, 5794.188813566104, 6342.524310676626, 6366.77716279928, 6368.267649220306, 6370.0763389308295, 6373.490120790326, 5899.410058994152, 6108.664023478751, 6110.969445152746, 6115.143879856823, 6120.755169793248, 6015.037593984942, 6034.24571928509, 6077.817665469955, 6107.29766076898, 6143.078538219303, 6103.389661033861, 6118.65917226336, 6131.075059010272, 6132.237922446167, 6138.232720909887, 6125.7865398231115, 6125.998614523584, 6127.4974314983165, 6128.506083113493, 6132.141360525245, 4610.170169369546, 4617.792199351037, 4658.790354434059, 4697.177993851063, 4699.882502937454, 4156.25187466259, 4164.479440069991, 4165.604267594574, 4165.91668166635, 4181.916361672749, 5846.503047240947, 5846.883062338729, 5854.063349864001, 5854.619449735754, 5856.0630299151935, 6008.128821320972, 6059.303180134253, 6092.99465588173, 6125.052321061979, 6217.564770466114, 5828.737706803204, 5847.532197424196, 5848.883022339614, 5874.32445268791, 5889.676067816225, 5341.118715411992, 5346.358436987586, 5354.384245811703, 5354.759035843396, 5363.436839131835, 6125.632462052291, 6158.7228574714245, 6198.969005155035, 6370.0763389308295, 6402.03969404593, 6080.726367313393, 6135.821783266876, 6149.907751383644, 6149.969250153899, 6157.26112866441, 6342.936570634234, 6347.650879201688, 6348.396902294275, 6352.555321127476, 6356.141920840627, 5863.296404431325, 5963.642181469126, 6025.78909738157, 6034.668093254738, 6046.969765151146, 5732.742026608729, 6051.183090282755, 6134.478569321657, 6188.504919606331, 6192.442680158807, 6003.519718422603, 6019.067044608093, 6021.7591296347655, 6022.454926061794, 6037.849053773515, 5829.970850145892, 6013.428724271187, 6020.578559500793, 6023.427774361428, 6027.969860150672, 5825.3592104867585, 5838.854028649148, 5852.063669812809, 5894.852628684145, 5979.372165922689, 5982.611130276518, 5982.730777115127, 5985.790497332572, 5991.520678345809, 5999.880002400015, 5775.0, 5844.649321040752, 5847.356790752929, 5849.795257165979, 5851.678157701367], "value": 5634.019812869316, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.8657052163261, 601.3865856826037, 626.8307556959621, 635.9841003974897, 662.1987395251745, 36226.275474490496, 36783.44824827626, 36846.447303290406, 36881.81559092201, 36931.4460283096, 46938.29592556114, 48366.51633483664, 48525.7573712131, 52660.0, 52684.0, 9335.346525743218, 9475.81048379032, 9491.430514169171, 9493.81012379752, 9494.0, 8617.827643447128, 9280.489573073479, 9363.48500832454, 9388.718338449857, 9391.107844754737, 8687.913120868805, 11165.944170279137, 11415.714607134805, 11416.029637480782, 11792.35142067186, 10962.835557466644, 11012.17408694351, 11035.558577656884, 11038.0, 11051.502682379272, 8532.317414606816, 11219.214654974156, 11286.887131128748, 11304.37825919574, 11305.71735706614, 33456.16359591005, 43017.56982430167, 44069.33895991563, 44238.778806105765, 47857.0, 3894.1822217334393, 4257.787110644455, 4465.062336909221, 4562.680612357136, 4575.565321294472, 34323.65676343229, 34348.6565134348, 34368.6563134368, 34550.30899382023, 34749.13127171841, 4605.953940460619, 4638.1187574360765, 4684.531546845288, 4727.572509930273, 4762.952370476286, 366848.0, 367442.0, 369441.0, 369622.9999999973, 369767.0, 54831.451685482636, 56334.7183264081, 63834.68082659557, 64249.67875160594, 64293.678531607045, 67869.32130678729, 69872.0, 69915.65042174756, 69979.0, 70281.64859175672, 15916.522504324657, 15964.68070638604, 16082.276297566637, 16085.230624631282, 16091.678166436606, 12578.0, 12657.620271391874, 12658.620241392773, 12667.176633518977, 12668.556600518938, 16277.430289939795, 16914.91542542304, 18404.447866564024, 18470.81529184718, 18480.260789568536, 6224.439800417984, 6287.842803929939, 6442.452391546677, 6510.21877374718, 6701.731930722716, 1025.6051420203125, 1044.6709286574705, 1136.886311368873, 1142.9885701142882, 1175.6355529785794, 33901.83049084788, 34755.0, 34772.65227347745, 34823.0, 35013.29973400518, 1127.0701671121358, 1160.9419529023514, 1166.7841449331731, 1182.8225766134976, 1204.9216800907914, 18085.547861303574, 18112.45662630123, 18124.45626631203, 18148.818511814978, 18294.817051829577, 550819.0, 551650.0, 552135.0, 554817.0, 557282.0, 61971.38028619746, 62086.37913620896, 62207.06689399663, 62320.68839655773, 62344.0, 1374.8212732344732, 1378.6691194113341, 1409.4925826702379, 1411.9294035298194, 1415.2074838090703, 32022.51966220462, 33065.50401744024, 33764.0, 34182.487262690585, 34486.0, 54025.72987135039, 59760.402395975485, 61630.38369616246, 61696.38303616906, 67326.66336668286, 459279.0, 462508.0, 463159.0, 463662.0, 464244.0, 27628.0, 28581.428371432037, 31475.213119671276, 31951.520727188647, 32015.199620008756, 48978.75510622424, 52431.73784131055, 53836.0, 57472.42527574838, 57475.71262143662, 43413.34879976866, 44610.0, 45511.743094703495, 45933.0, 46422.0, 44190.558094418644, 45214.77392613016, 45508.54491455043, 46052.539474604826, 46597.0, 44549.0, 44698.0, 45039.774801125786, 45629.54370456253, 45768.54231457776, 36958.445623315136, 39442.60557394504, 39519.80240098781, 40486.392704110054, 40643.390349145375, 29398.265043374086, 30132.54801178028, 30384.240393990327, 30460.0, 30601.0], "value": 21497.03733793372, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.7514053716427, 408.6628531461545, 416.4523651398411, 424.49485112715865, 435.9302511598145, 14905.18021508818, 16641.0, 20296.289629862957, 20654.17383304666, 20799.792002079976, 18030.278788848464, 18446.907765461187, 18480.815191848076, 18838.152283147258, 19020.524486887836, 5861.3552509223955, 5868.73590688419, 5975.58170928033, 6030.457258846703, 6087.9695601521935, 4376.146651402978, 5893.705314734246, 5961.254843144607, 5966.820995370146, 5967.970160149215, 4545.136424079431, 6329.873402531946, 6581.7367305307735, 6606.603603783764, 6719.462443004548, 6464.903026454631, 6605.07528945948, 6638.435732962704, 6694.698738556753, 6703.631300278483, 5854.76580936762, 5880.8235752927485, 5896.233489646362, 5899.410058994066, 5900.557458190653, 23873.88063059691, 24478.632820507708, 24593.13924012651, 24857.875710621513, 24919.377015574573, 3011.9548206776917, 3255.544223808669, 3292.4732042873266, 3310.851011704455, 3402.8298585070893, 15140.697186056328, 15306.693866122616, 15308.846911530965, 15315.0, 15339.539813805604, 1925.730397744303, 1957.6574099532525, 1979.6733538966198, 2039.9184032638677, 2091.7908209179113, 61263.38736612666, 73566.0, 82018.58990705009, 82306.0, 84668.57665711633, 11431.714207144722, 11529.244962757428, 11647.12646551512, 11663.941680291713, 11718.355490447937, 16130.274137663833, 16209.83790162107, 16236.67526649484, 16293.266802993894, 16303.347866085223, 8197.0, 8214.0, 8285.83428331442, 8487.830243395098, 8519.872201916976, 7983.481073730189, 7988.6405111768945, 8099.352051835839, 8184.0, 8231.46495477792, 6944.756933507303, 7003.719851205997, 7044.22513523512, 7125.89311160333, 7145.1425828900865, 1915.7605299337583, 1923.6922092465138, 1931.51712071982, 1948.878122770359, 1978.337257018886, 952.2905435450633, 986.7878406142714, 1052.2003277508995, 1066.2962444786403, 1077.21363404715, 20746.79253207479, 21193.0, 21371.67942480864, 21397.46506337354, 21425.142994280053, 1026.0867827633413, 1067.6530127708527, 1068.8770791358938, 1099.312929419113, 1123.685368096934, 10986.400499064255, 11784.76430471403, 11834.940825295818, 12292.938535307267, 12294.20087694297, 468325.0, 482750.0, 488256.0, 489751.0, 489908.0, 89745.0, 98657.50671246598, 99332.50333748285, 99819.50090249503, 100295.49852250692, 494.2758858272576, 507.9796808127634, 510.78802297047054, 510.89526647036524, 511.68275669085403, 11610.187286889837, 11824.763504729684, 12184.7815218481, 12340.568080117153, 12473.750524989267, 31187.0, 31737.0, 41853.58146418619, 42167.57832421637, 43427.0, 55714.0, 55889.44110559005, 58301.125483116935, 59454.405455944885, 60372.0, 9164.312676549152, 9311.301652375963, 9318.301127415336, 9337.066293370608, 9537.332386732862, 10556.472176391304, 13628.591142265748, 13642.124096115936, 13819.0, 14092.084014539023, 11533.82699259495, 11830.46762895689, 11850.881491184979, 12012.279263244232, 12247.938760306142, 9576.904230957602, 10883.401412922365, 11867.76264474723, 12061.939690301493, 12211.450484728031, 11369.658910232707, 11576.594819181288, 11615.128865335302, 11648.0, 11785.292882427082, 8370.204830541088, 8844.336674749291, 8929.285657147282, 9153.816923661623, 9302.860457093013, 9161.0, 11928.761424771283, 12066.638000859988, 12282.201656892274, 12549.686257843627], "value": 9457.51951734992, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1395.7766757318832, 1516.4616561120802, 1552.2083737293979, 1712.6061005968627, 1840.5030641726735, 8422.789430264238, 9189.862152067724, 9569.569369378378, 9572.186364159046, 9916.55375508102, 9650.662226822064, 9679.419234845915, 9708.951455242732, 9718.85421718673, 9754.658586949457, 2612.5950477675897, 2641.1680320699015, 2646.3251870772924, 2646.695630002555, 2647.311698958275, 2582.9870850645816, 2659.9202023939315, 2660.640813490183, 2664.3738721400455, 2665.906693265736, 2596.5196438658813, 2637.617545455908, 2673.70589235184, 2675.665541807274, 2679.303381120913, 2549.490101979602, 2561.449288402993, 2607.8435293882426, 2610.9347266318305, 2621.9737802622017, 2547.4522977559727, 2553.1191738850252, 2557.0283292348972, 2606.2962999990086, 2608.3087981684866, 9463.810723785486, 9616.615335386647, 9674.274429417752, 9675.564599593035, 9795.265355098396, 5562.527185189223, 5785.103308987113, 5786.132080187964, 5787.623804452697, 5791.073428251501, 3533.823308834548, 3549.6095429502734, 3599.586047604507, 3622.6920711739535, 3625.2024554597947, 1131.0, 1157.1205883528578, 1193.7433451807904, 1195.5516681244533, 1220.4263995921992, 13379.331033448289, 13451.192928424325, 13503.459861605525, 13527.391267392892, 13753.449862005507, 4857.489963553776, 4895.926561101586, 5045.318881950963, 5127.154019586801, 5127.435982041972, 10829.67510974672, 10842.0, 10843.240973131962, 10861.294015888941, 10875.45622718861, 5597.832065038056, 5603.439656034446, 5609.102543593006, 5618.269624948731, 5618.606697531215, 4812.759362031885, 4825.372701548777, 5175.560077393464, 5192.376914770176, 5212.270282160501, 4216.409702641571, 4267.487901451846, 4299.892502687458, 4370.322599997005, 4378.474583050054, 1293.4502836294537, 1320.2408615046386, 1322.4049177869997, 1336.5522549945877, 1336.6324260828155, 989.8416253399422, 990.2177279948904, 1007.5063219022773, 1009.0716540782498, 1017.3844823881593, 6036.909446358308, 6118.663473509, 6161.075838624242, 6438.323975982548, 6454.1609590752905, 496.0946273051695, 502.6054547180446, 507.441814004592, 514.9109633125986, 533.0751146760367, 4229.281022226229, 4292.91414171721, 4338.9349159762, 4417.580329868657, 4435.068635586498, 11482.425878706032, 11942.104342174373, 12015.939920300518, 12030.63908082759, 12045.33750643723, 11529.827052594217, 11829.408529573488, 11981.221220620631, 11984.700382490333, 12028.0, 8905.688300909436, 8932.910670893338, 9008.32437567172, 9018.458892466473, 9026.052264512264, 6112.388761123852, 6181.876362472815, 6188.1027251049245, 6214.161088253209, 6257.2491301044165, 10978.615748448767, 11048.337099774039, 11100.777984440105, 11155.55377784912, 11179.546658934287, 10939.671809845717, 10995.285306455055, 11149.721256968316, 11328.716782080184, 11538.0, 7368.226336234618, 7647.808804779703, 7811.882821757792, 7960.880586791087, 8076.959615201887, 9584.185344245818, 9655.951720241354, 9659.082387173208, 9663.613455461702, 9665.9033409665, 8410.495370277802, 8560.0, 8624.223819856243, 8633.136686331316, 8834.823303533765, 8286.4199506034, 8325.0, 8370.790730231793, 8411.3270938326, 8623.95688021581, 7552.39580833543, 7588.696452141852, 7683.270089341504, 7705.845883082419, 7747.612619369122, 5689.8862022758485, 5690.459406356389, 5793.3337666167245, 5846.590738648424, 5901.350851406256, 5744.856378590569, 5770.394108618535, 5850.707464626837, 5866.266716660418, 5950.7917222896995], "value": 4940.8825199512985, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.78995023085685, 349.1132523390588, 354.10588264631804, 359.1559834389187, 365.7147425008494, 5572.331320241572, 5577.804776832807, 5579.804706835262, 5581.320965936353, 5607.327120745516, 5461.722966220266, 5525.778968841251, 5551.611387202887, 5556.777728890849, 5560.110382338827, 3844.4809950656722, 4115.279826030448, 4294.785260736966, 4327.653787696992, 4351.977285337943, 4344.45694288214, 4346.913061738779, 4358.629516491102, 4364.7817609119575, 4388.464037586851, 4355.54266801986, 4361.3457981302745, 4366.868993930188, 4378.343248512717, 4381.167578160155, 4162.687798415116, 4166.791630427172, 4263.722858014219, 4310.80601372939, 4323.459567554056, 3836.5012548368813, 3841.0781412461088, 3848.3073046851837, 3851.6340947609933, 3854.768713877176, 5300.284461597674, 5317.325042611604, 5334.67991920486, 5340.25236466895, 5341.973290133564, 4822.541858523435, 4915.517663579905, 4920.810239443449, 4929.162042452792, 4958.950410495885, 5664.0, 5668.773249070032, 5683.232763576905, 5686.971565142189, 5709.429057094258, 4217.451731274945, 4219.1139860629, 4236.364545318196, 4236.809343579515, 4249.617534421917, 5673.886522269531, 5699.344575373843, 5848.853778655525, 5896.292444906596, 5939.040116761458, 4196.545021162654, 4336.804843782037, 4345.087531618396, 4371.934420983688, 4376.571003093314, 4415.977920110379, 4421.093675796454, 4424.33634954753, 4426.22541055314, 4430.158269928767, 4824.372831531879, 4958.669425370071, 4967.577755890789, 4968.329275547827, 4990.201567749143, 5754.5971781975695, 5786.392428795, 5791.391903850119, 5800.520867178842, 5805.477507024388, 1498.168516473346, 1517.8178618565844, 1525.557588299403, 1526.6565422428414, 1528.6331280492602, 942.3968660057569, 1000.9899901001043, 1002.8495725641212, 1009.7172791618359, 1010.9039641234071, 4855.465898751135, 4880.902381952341, 4886.438059623189, 4911.8948236646465, 4956.801727930914, 5164.715940623302, 5176.301199338116, 5183.377994640592, 5210.6613070150315, 5260.421353651095, 4949.975250123798, 4953.628477864175, 4964.716230533995, 5212.530872221519, 5660.660360378308, 3156.4375634061284, 3165.778395512338, 3180.9522857157162, 3183.4906414973493, 3194.7603929705365, 6023.427774361517, 6195.141457562713, 6196.783112591038, 6206.782762603287, 6207.379262073847, 6085.0, 6094.908576371358, 6096.664683442365, 6122.20411346531, 6135.754569817157, 6154.876902461926, 6158.692065396713, 6171.814845554641, 6180.629162250189, 6185.103160041859, 5856.635982433002, 5878.353381128158, 5980.910286345796, 5988.191594134736, 6011.579189456696, 5828.446297601721, 5887.793927212699, 5900.734466948912, 5909.881802363842, 5931.970340148272, 5647.20939068539, 5915.0, 6043.2748070231855, 6060.15165374565, 6151.323354431099, 4224.690345992752, 4296.291111966616, 4297.462817147856, 4332.3068309070395, 4342.196693611692, 5805.477507024388, 5976.133460648269, 5983.0, 6019.458248757633, 6047.758089676539, 5865.853353666193, 5867.239828051653, 5878.412158784087, 5889.263842019747, 5891.852703682443, 5810.970945145247, 5824.7381466378965, 5829.358770535323, 5830.737616807339, 5863.73613187399, 5536.169574563848, 5772.94227057724, 5792.94207057924, 5797.79707710245, 5825.70871456434, 5889.440503152194, 5902.6458412495385, 5908.881822363443, 5909.852253693692, 5911.261092363455, 5573.832785016456, 5752.568557358131, 5776.162456443876, 5819.243498345188, 5827.213326200908], "value": 4402.585395979116, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.2645400096907, 795.7334293011841, 836.6736972580694, 856.9459564735379, 885.0264708820297, 15832.44586439475, 16494.092824894626, 16519.83480165198, 16553.83446165538, 16717.58206044848, 15224.619384515392, 15546.144962027083, 15548.45580404686, 15550.377984880619, 15563.922180389081, 728.3917928529658, 746.6005686957479, 843.4264700003979, 844.5692696724653, 866.5927014303268, 881.1584936385736, 883.9160279773412, 884.2086332732233, 884.747846863644, 884.8451520983834, 939.2626787971444, 939.2767568971888, 940.5156344482563, 940.6989763275755, 940.9247260219166, 767.17528656694, 784.352908850198, 786.0371045469311, 790.3716545346464, 936.5598168860624, 659.0740010285557, 675.9526833121683, 677.3734295776387, 681.6251061915976, 681.7000519771334, 15182.240887955559, 15217.467388641455, 15236.085834849833, 15251.847481525154, 15261.38954441821, 13530.797038044437, 13774.65563360914, 13942.163470191821, 14361.856381436157, 14601.92699036498, 12527.0, 12568.0, 12574.81137782934, 12967.0, 13003.284819334933, 4386.298192289219, 4405.515393306734, 4447.488538818045, 4556.544345565417, 4688.0, 65616.0, 68978.65510672465, 69679.65160174167, 83261.58369208116, 85613.14386856114, 10980.615678451215, 11101.222914396076, 11204.887951120547, 11701.765964680659, 11795.705107372385, 20859.37421877346, 21348.466288342916, 21390.0, 21423.57152856934, 21432.571348572943, 8412.537310447862, 8524.445911015764, 8538.573071346407, 8701.651933922572, 8732.170443807829, 5118.513741194581, 5135.383753949475, 5239.056969745407, 5340.305760251143, 5420.24116623673, 3893.6301051400073, 4068.4710987571425, 4070.0842310479907, 4075.5109386873764, 4084.36692312692, 700.8773464643768, 734.6400263870665, 746.358132006474, 747.5402627384119, 748.0761259844088, 753.4010461682975, 765.5751058162662, 772.9690812367443, 777.9183185765527, 801.8997625296838, 9861.0, 10264.692059238234, 10277.486125693835, 10278.126359259546, 10347.223958203165, 663.8141320430287, 687.5462194951308, 690.823839920817, 741.5847125609675, 773.4972268025829, 4654.464736555272, 4768.403949506312, 4770.522947705202, 4809.206480930678, 4810.518948105161, 125562.37218813847, 127027.36486317695, 127042.0, 128130.35934820453, 132033.3398333002, 66595.00107498391, 93960.53019734858, 105266.0, 109325.0, 112949.43525282321, 95797.52101239588, 98341.50829245808, 98694.0, 99546.0, 101979.0, 9932.702018939442, 10562.31344962575, 10630.202734795075, 10879.129669626253, 10932.508037138188, 52816.471835281154, 53151.468485314654, 54398.184027240415, 54544.18183727327, 55294.72352638211, 70302.29697702957, 78311.0, 78638.21361786309, 80191.0, 81015.18984810077, 13853.584392468241, 14023.929880350533, 14177.787333190217, 14269.001169918007, 14293.57119286423, 14733.705325893636, 14842.40630374773, 15604.531864044096, 15729.0, 17240.741388878927, 14175.432982680577, 14210.502632407815, 14377.85622143807, 14411.711765764836, 15308.61728456753, 12953.935230324165, 13606.86393136056, 14043.859561404255, 14347.282635868374, 15318.846811532188, 13634.1137826041, 13793.448262069405, 13911.791323130363, 13942.930285348508, 14360.784588231394, 11861.406929653655, 12034.518619255481, 12428.937855310665, 12638.494460221857, 12800.61598152057, 11748.70628234301, 11764.882351176379, 11956.461959211681, 12250.571230006906, 12527.56153534622], "value": 7995.130692127585, "unit": "score"}], "name": "dromaeo_css", "value": 7642.331420979036, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 110.0, 110.0, 110.0, 109.0, 110.0, 110.0, 109.0, 109.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 116.0, 117.0, 164.0, 123.0, 119.0, 118.0, 119.0, 150.0, 118.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 160.0, 160.0, 162.0, 160.0, 160.0, 161.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 109.0, 76.0, 77.0, 76.0, 76.0, 97.0, 76.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 116.0, 81.0, 81.0, 81.0, 99.0, 81.0, 81.0, 106.0], "value": 88.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 145.0, 146.0, 145.0, 146.0, 146.0, 147.0, 145.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 50.0, 49.0, 50.0, 49.0, 52.0, 49.0, 49.0, 50.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 59.0, 58.0, 58.0, 57.0, 58.0, 58.0, 59.0], "value": 58.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 73.0, 74.0, 73.0, 77.0, 82.0, 72.0, 80.0, 73.0], "value": 75.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 129.0, 125.0, 128.0, 118.0, 120.0, 129.0, 121.0, 161.0, 143.0], "value": 129.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 153.0, 151.0, 151.0, 152.0, 152.0, 152.0, 156.0, 152.0, 150.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 61.0, 61.0, 61.0, 61.0, 65.0, 60.0, 61.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1452.9, "alertThreshold": 2.0}]} 09:51:16 INFO - Return code: 0 09:51:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:51:16 INFO - # TBPL SUCCESS # 09:51:16 INFO - Running post-action listener: _resource_record_post_action 09:51:16 INFO - [mozharness: 2016-10-06 16:51:16.897127Z] Finished run-tests step (success) 09:51:16 INFO - Running post-run listener: _resource_record_post_run 09:51:16 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 1998848; Write bytes: 360087552; Read time: 56; Write time: 460968 09:51:16 INFO - TinderboxPrint: CPU usage
13.0% 09:51:16 INFO - TinderboxPrint: I/O read bytes / time
1,998,848 / 56 09:51:16 INFO - TinderboxPrint: I/O write bytes / time
360,087,552 / 460,968 09:51:16 INFO - TinderboxPrint: CPU idle
8,270.5 (87.0%) 09:51:16 INFO - TinderboxPrint: CPU user
1,186.9 (12.5%) 09:51:16 INFO - TinderboxPrint: Swap in / out
0 / 0 09:51:16 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 111423488; Read time: 0; Write time: 168244 09:51:16 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 248233984; Read time: 20; Write time: 292704 09:51:17 INFO - Running post-run listener: _upload_blobber_files 09:51:17 INFO - Blob upload gear active. 09:51:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:51:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:51:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:51:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:51:17 INFO - (blobuploader) - INFO - Open directory for files ... 09:51:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:51:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:51:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:21 INFO - (blobuploader) - INFO - Done attempting. 09:51:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:51:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:51:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:22 INFO - (blobuploader) - INFO - Done attempting. 09:51:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:51:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:51:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:51:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:51:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:51:25 INFO - (blobuploader) - INFO - Done attempting. 09:51:25 INFO - (blobuploader) - INFO - Iteration through files over. 09:51:25 INFO - Return code: 0 09:51:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:51:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:51:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a77d15b5e4c0253d4b37492db073d6d601399d22dee0f49e9733608cc91b4d9e4f33bee6491723cee3920e625eb8feee4e19e7a93281dddd80a293f16dd4a024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82e04b81261f8251f3de33422e01a0ac90ae6b4065ac7fb7d0d6bcb29d73151cdadf43dbdbba514bad2b1321f85cbd97338e5ec04a02f965f16841465f1f5be8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4035e0e14092af35f174017ab2df917c3e53762837f400b0760471e1c989065588c944c289805428389c6cf6e0363107c1704eef524be52040fb781d9d050758"} 09:51:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:51:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:51:25 INFO - Contents: 09:51:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a77d15b5e4c0253d4b37492db073d6d601399d22dee0f49e9733608cc91b4d9e4f33bee6491723cee3920e625eb8feee4e19e7a93281dddd80a293f16dd4a024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82e04b81261f8251f3de33422e01a0ac90ae6b4065ac7fb7d0d6bcb29d73151cdadf43dbdbba514bad2b1321f85cbd97338e5ec04a02f965f16841465f1f5be8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4035e0e14092af35f174017ab2df917c3e53762837f400b0760471e1c989065588c944c289805428389c6cf6e0363107c1704eef524be52040fb781d9d050758"} 09:51:25 INFO - Running post-run listener: copy_logs_to_upload_dir 09:51:25 INFO - Copying logs to upload dir... 09:51:25 INFO - mkdir: /builds/slave/test/build/upload/logs 09:51:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1236.125099 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 36 secs) (at 2016-10-06 09:51:24.957282) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-06 09:51:24.961099) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/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/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a77d15b5e4c0253d4b37492db073d6d601399d22dee0f49e9733608cc91b4d9e4f33bee6491723cee3920e625eb8feee4e19e7a93281dddd80a293f16dd4a024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82e04b81261f8251f3de33422e01a0ac90ae6b4065ac7fb7d0d6bcb29d73151cdadf43dbdbba514bad2b1321f85cbd97338e5ec04a02f965f16841465f1f5be8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4035e0e14092af35f174017ab2df917c3e53762837f400b0760471e1c989065588c944c289805428389c6cf6e0363107c1704eef524be52040fb781d9d050758"} build_url:https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011142 build_url: 'https://queue.taskcluster.net/v1/task/MKakZy6aTEGmvEHpKuzwmw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a77d15b5e4c0253d4b37492db073d6d601399d22dee0f49e9733608cc91b4d9e4f33bee6491723cee3920e625eb8feee4e19e7a93281dddd80a293f16dd4a024", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/82e04b81261f8251f3de33422e01a0ac90ae6b4065ac7fb7d0d6bcb29d73151cdadf43dbdbba514bad2b1321f85cbd97338e5ec04a02f965f16841465f1f5be8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4035e0e14092af35f174017ab2df917c3e53762837f400b0760471e1c989065588c944c289805428389c6cf6e0363107c1704eef524be52040fb781d9d050758"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-06 09:51:24.986904) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 09:51:24.987244) ========= rm -f oauth.txt in dir /builds/slave/test/. (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 SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1475771447.332367-743270250 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004460 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-06 09:51:25.069912) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-06 09:51:25.070265) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-06 09:51:25.070651) ========= ========= Total master_lag: 0.16 =========